[58.508854] Initializing watchdogs
[58.508900]   /dev/watchdog0
[58.522899] [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]
[58.527428] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.529545] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.532744] [001/131] (960s left) fbdev (unaligned-read)
[58.599481] Starting subtest: unaligned-read
[58.613723] Subtest unaligned-read: SUCCESS (0.014s)
[58.691224] [002/131] (960s left) gem_ctx_isolation (dirty-switch)
[58.743546] Starting subtest: dirty-switch
[58.745821] Starting dynamic subtest: rcs0
[58.996106] Dynamic subtest rcs0: SUCCESS (0.252s)
[59.006068] Starting dynamic subtest: bcs0
[59.240992] Dynamic subtest bcs0: SUCCESS (0.245s)
[59.251109] Starting dynamic subtest: vcs0
[59.480630] Dynamic subtest vcs0: SUCCESS (0.240s)
[59.490284] Starting dynamic subtest: vcs1
[61.104929] Dynamic subtest vcs1: SUCCESS (1.625s)
[61.113448] Starting dynamic subtest: vecs0
[63.123642] Dynamic subtest vecs0: SUCCESS (2.020s)
[63.133545] Starting dynamic subtest: ccs0
[65.159753] Dynamic subtest ccs0: SUCCESS (2.038s)
[65.162260] Subtest dirty-switch: SUCCESS (6.424s)
[65.425328] [003/131] (953s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[65.529693] Starting subtest: psr-modesetfrombusy
[71.653165] Subtest psr-modesetfrombusy: SUCCESS (6.131s)
[71.814014] [004/131] (947s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[71.915281] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[71.917273] Starting dynamic subtest: pipe-A-default-mode
[74.659606] Dynamic subtest pipe-A-default-mode: SUCCESS (2.744s)
[74.661621] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.745s)
[75.542104] [005/131] (943s left) gem_exec_params (sol-reset-invalid)
[75.603996] Starting subtest: sol-reset-invalid
[75.606382] Subtest sol-reset-invalid: SUCCESS (0.002s)
[77.001085] [006/131] (942s left) gem_spin_batch (resubmit-new)
[77.062092] Starting subtest: resubmit-new
[77.064456] Starting dynamic subtest: rcs0
[77.070461] Dynamic subtest rcs0: SUCCESS (0.008s)
[77.079205] Starting dynamic subtest: bcs0
[77.081517] Dynamic subtest bcs0: SUCCESS (0.004s)
[77.089011] Starting dynamic subtest: vcs0
[77.091339] Dynamic subtest vcs0: SUCCESS (0.004s)
[77.098689] Starting dynamic subtest: vcs1
[77.100594] Dynamic subtest vcs1: SUCCESS (0.004s)
[77.107150] Starting dynamic subtest: vecs0
[77.109031] Dynamic subtest vecs0: SUCCESS (0.004s)
[77.115698] Starting dynamic subtest: ccs0
[77.117598] Dynamic subtest ccs0: SUCCESS (0.005s)
[77.119473] Subtest resubmit-new: SUCCESS (0.029s)
[78.922834] [007/131] (940s left) gem_exec_schedule (preempt-user)
[78.998980] Starting subtest: preempt-user
[79.000850] Starting dynamic subtest: rcs0
[79.032212] Dynamic subtest rcs0: SUCCESS (0.035s)
[79.039538] Starting dynamic subtest: bcs0
[79.069450] Dynamic subtest bcs0: SUCCESS (0.037s)
[79.076964] Starting dynamic subtest: vcs0
[79.096625] Dynamic subtest vcs0: SUCCESS (0.027s)
[79.103764] Starting dynamic subtest: vcs1
[79.122037] Dynamic subtest vcs1: SUCCESS (0.025s)
[79.129328] Starting dynamic subtest: vecs0
[79.145905] Dynamic subtest vecs0: SUCCESS (0.024s)
[79.152835] Starting dynamic subtest: ccs0
[79.174647] Dynamic subtest ccs0: SUCCESS (0.029s)
[79.177016] Subtest preempt-user: SUCCESS (0.178s)
[81.042914] [008/131] (938s left) sysfs_heartbeat_interval (nopreempt)
[81.106687] Starting subtest: nopreempt
[81.108816] Starting dynamic subtest: vecs0
[90.900745] Dynamic subtest vecs0: SUCCESS (9.795s)
[90.911328] Starting dynamic subtest: ccs0
[94.920816] Dynamic subtest ccs0: SUCCESS (4.021s)
[94.930541] Starting dynamic subtest: vcs0
[104.928578] Dynamic subtest vcs0: SUCCESS (10.009s)
[104.939291] Starting dynamic subtest: bcs0
[108.936726] Dynamic subtest bcs0: SUCCESS (4.006s)
[108.947352] Starting dynamic subtest: rcs0
[113.053297] Dynamic subtest rcs0: SUCCESS (4.116s)
[113.063718] Starting dynamic subtest: vcs1
[122.922218] Dynamic subtest vcs1: SUCCESS (9.864s)
[122.924823] Subtest nopreempt: SUCCESS (41.818s)
[123.018194] [009/131] (896s left) kms_invalid_mode (zero-hdisplay)
[123.132215] Starting subtest: zero-hdisplay
[123.134573] Starting dynamic subtest: pipe-A-eDP-1
[123.414324] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[123.423709] Starting dynamic subtest: pipe-B-eDP-1
[123.425850] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[123.433253] Starting dynamic subtest: pipe-C-eDP-1
[123.435492] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[123.442596] Starting dynamic subtest: pipe-D-eDP-1
[123.444532] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[123.446446] Subtest zero-hdisplay: SUCCESS (0.288s)
[124.253782] [010/131] (895s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[124.370866] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[124.375068] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[124.509993] [011/131] (894s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[124.620901] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[125.478426] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.859s)
[125.706929] [012/131] (893s left) kms_getfb (getfb-handle-zero)
[125.808760] Starting subtest: getfb-handle-zero
[125.810973] Subtest getfb-handle-zero: SUCCESS (0.000s)
[125.925557] [013/131] (893s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[125.996785] Starting subtest: yf-tiled-ccs-to-yf-tiled
[126.000968] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[126.195176] [014/131] (893s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[126.302194] Starting subtest: multiplane-rotation-cropping-bottom
[133.543386] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.242s)
[133.686954] [015/131] (885s left) gem_eio (wait-1us)
[133.912328] Starting subtest: wait-1us
[134.246454] Subtest wait-1us: SUCCESS (0.334s)
[134.435890] [016/131] (885s left) syncobj_wait (multi-wait-for-submit-signaled)
[134.494308] Starting subtest: multi-wait-for-submit-signaled
[134.496220] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[134.612608] [017/131] (884s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[134.706881] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[134.708825] Starting dynamic subtest: pipe-A-default-mode
[135.011045] Dynamic subtest pipe-A-default-mode: SKIP (0.299s)
[135.015629] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.300s)
[135.894453] [018/131] (883s left) kms_plane_alpha_blend (alpha-7efc)
[136.004143] Starting subtest: alpha-7efc
[136.279708] Starting dynamic subtest: pipe-A-eDP-1
[136.885945] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.603s)
[136.916478] Starting dynamic subtest: pipe-D-eDP-1
[138.195418] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.287s)
[138.197809] Subtest alpha-7efc: SUCCESS (2.192s)
[139.059245] [019/131] (880s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[139.160074] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[139.164437] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[139.288399] [020/131] (880s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[139.389581] Starting subtest: fbc-psr2-primary-mmap-cpu
[139.391736] Starting dynamic subtest: eDP-1
[139.395692] Dynamic subtest eDP-1: SKIP (0.000s)
[139.399691] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[139.484143] [021/131] (880s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[139.590019] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[139.598538] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[139.730294] [022/131] (880s left) gem_exec_whisper (basic-normal-all)
[139.796348] Starting subtest: basic-normal-all
[142.267196] Subtest basic-normal-all: SUCCESS (2.469s)
[142.468339] [023/131] (877s left) kms_big_fb (linear-16bpp-rotate-180)
[142.564948] Starting subtest: linear-16bpp-rotate-180
[144.042099] Subtest linear-16bpp-rotate-180: SUCCESS (1.479s)
[144.208177] [024/131] (875s left) kms_frontbuffer_tracking (psr-slowdraw)
[144.315614] Starting subtest: psr-slowdraw
[169.460099] Subtest psr-slowdraw: SUCCESS (25.144s)
[169.607329] [025/131] (850s left) vgem_basic (dmabuf-fence)
[169.703244] Starting subtest: dmabuf-fence
[169.705518] Subtest dmabuf-fence: SUCCESS (0.001s)
[169.787442] [FACT vgem_basic (dmabuf-fence)] new: kernel.kmod_is_loaded.vgem: true
[169.790818] [026/131] (850s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[169.845509] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[169.847833] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[169.970126] [027/131] (850s left) kms_psr (pr-primary-render)
[170.067100] Starting subtest: pr-primary-render
[170.071211] Subtest pr-primary-render: SKIP (0.000s)
[170.164426] [028/131] (849s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[170.256046] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[170.257888] Starting dynamic subtest: pipe-A-eDP-1
[170.276309] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[170.286292] Starting dynamic subtest: pipe-B-eDP-1
[170.302863] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[170.310797] Starting dynamic subtest: pipe-C-eDP-1
[170.327849] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[170.336328] Starting dynamic subtest: pipe-D-eDP-1
[170.355033] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[170.358760] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[172.439458] [029/131] (847s left) core_auth (getclient-master-drop)
[172.471059] Starting subtest: getclient-master-drop
[172.494393] Subtest getclient-master-drop: SUCCESS (0.023s)
[172.612350] [030/131] (847s left) kms_plane_scaling (planes-downscale-factor-0-5)
[172.706876] Starting subtest: planes-downscale-factor-0-5
[172.709162] Starting dynamic subtest: pipe-A
[172.729479] Dynamic subtest pipe-A: SKIP (0.018s)
[172.740188] Starting dynamic subtest: pipe-B
[172.782930] Dynamic subtest pipe-B: SKIP (0.054s)
[172.791674] Starting dynamic subtest: pipe-C
[172.819704] Dynamic subtest pipe-C: SKIP (0.035s)
[172.828058] Starting dynamic subtest: pipe-D
[172.850327] Dynamic subtest pipe-D: SKIP (0.031s)
[172.854621] Subtest planes-downscale-factor-0-5: SKIP (0.141s)
[172.973384] [031/131] (847s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[173.073834] Starting subtest: fb-8bpc-vs-panel-8bpc
[173.076036] Starting dynamic subtest: pipe-A-eDP-1
[174.534271] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.459s)
[174.536499] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.461s)
[175.409341] [032/131] (844s left) gem_partial_pwrite_pread (reads-snoop)
[175.474589] Subtest reads-snoop: SKIP (0.000s)
[175.560493] [033/131] (844s left) gem_eio (in-flight-contexts-immediate)
[175.811279] Starting subtest: in-flight-contexts-immediate
[189.937425] Subtest in-flight-contexts-immediate: SUCCESS (14.125s)
[190.147610] [034/131] (830s left) gen3_render_mixed_blits
[190.272195] [035/131] (830s left) kms_pm_rpm (pm-tiling)
[192.099327] Starting subtest: pm-tiling
[192.104382] Subtest pm-tiling: SKIP (0.000s)
[192.531510] [036/131] (827s left) i915_module_load (reload-no-display)
[192.574186] Starting subtest: reload-no-display
[198.705200] Subtest reload-no-display: SUCCESS (6.131s)
[198.736734] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[198.737478] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[198.740882] [037/131] (821s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[200.299039] Starting subtest: plane-upscale-20x20-with-rotation
[200.301451] Starting dynamic subtest: pipe-A
[200.538367] Dynamic subtest pipe-A: SUCCESS (0.242s)
[200.546165] Starting dynamic subtest: pipe-B
[201.903715] Dynamic subtest pipe-B: SUCCESS (1.363s)
[201.912400] Starting dynamic subtest: pipe-C
[203.231819] Dynamic subtest pipe-C: SUCCESS (1.327s)
[203.242846] Starting dynamic subtest: pipe-D
[204.558421] Dynamic subtest pipe-D: SUCCESS (1.326s)
[204.560555] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.263s)
[205.715217] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[205.716540] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: kernel.kmod_is_loaded.i915: true
[205.720536] [038/131] (814s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[205.825058] Starting subtest: 4-tiled-8bpp-rotate-180
[206.951587] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.128s)
[207.111254] [039/131] (813s left) kms_rotation_crc (primary-rotation-90)
[207.211214] Starting subtest: primary-rotation-90
[207.214872] Subtest primary-rotation-90: SKIP (0.001s)
[207.307837] [040/131] (813s left) kms_cursor_crc (cursor-onscreen-32x10)
[207.459938] Starting subtest: cursor-onscreen-32x10
[207.505053] Subtest cursor-onscreen-32x10: SKIP (0.041s)
[207.784153] [041/131] (812s left) kms_vblank (wait-idle)
[207.870282] Starting subtest: wait-idle
[207.871847] Starting dynamic subtest: pipe-A-eDP-1
[209.637365] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.766s)
[209.649905] Starting dynamic subtest: pipe-D-eDP-1
[212.079708] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.441s)
[212.082072] Subtest wait-idle: SUCCESS (4.211s)
[212.934230] [042/131] (807s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[213.036659] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[213.041129] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[213.180090] [043/131] (807s left) kms_flip (2x-flip-vs-dpms)
[213.272255] Starting subtest: 2x-flip-vs-dpms
[213.293906] Subtest 2x-flip-vs-dpms: SKIP (0.022s)
[213.401789] [044/131] (807s left) gem_exec_fence (syncobj-channel)
[213.817742] Starting subtest: syncobj-channel
[214.838720] Subtest syncobj-channel: SUCCESS (1.017s)
[215.152864] [045/131] (805s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[215.257071] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[215.259642] Starting dynamic subtest: pipe-A-eDP-1
[215.268976] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[215.279704] Starting dynamic subtest: pipe-B-eDP-1
[215.288744] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[215.298091] Starting dynamic subtest: pipe-C-eDP-1
[215.306152] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[215.313796] Starting dynamic subtest: pipe-D-eDP-1
[215.322062] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[215.326071] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[217.601065] [046/131] (803s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[217.696014] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[225.050628] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.359s)
[225.219717] [047/131] (795s left) kms_flip (busy-flip)
[225.321939] Starting subtest: busy-flip
[225.324122] Starting dynamic subtest: A-eDP1
[227.008021] Dynamic subtest A-eDP1: SUCCESS (1.685s)
[227.018419] Starting dynamic subtest: B-eDP1
[228.639528] Dynamic subtest B-eDP1: SUCCESS (1.630s)
[228.650086] Starting dynamic subtest: C-eDP1
[230.268571] Dynamic subtest C-eDP1: SUCCESS (1.627s)
[230.278447] Starting dynamic subtest: D-eDP1
[231.902079] Dynamic subtest D-eDP1: SUCCESS (1.633s)
[231.904381] Subtest busy-flip: SUCCESS (6.584s)
[233.099218] [048/131] (787s left) prime_mmap (test_aperture_limit)
[233.156259] Starting subtest: test_aperture_limit
[233.158625] Starting dynamic subtest: test_aperture_limit-smem
[233.166242] Dynamic subtest test_aperture_limit-smem: SKIP (0.008s)
[233.170272] Subtest test_aperture_limit: SKIP (0.009s)
[233.317283] [049/131] (787s left) i915_pm_rpm (sysfs-read)
[235.049329] Starting subtest: sysfs-read
[236.206609] Subtest sysfs-read: SUCCESS (1.155s)
[236.577427] [050/131] (784s left) kms_big_fb (yf-tiled-addfb)
[236.677272] Starting subtest: yf-tiled-addfb
[236.681554] Subtest yf-tiled-addfb: SKIP (0.000s)
[236.818756] [051/131] (784s left) sriov_basic (enable-vfs-autoprobe-on)
[236.879073] Starting subtest: enable-vfs-autoprobe-on
[236.881352] Starting dynamic subtest: numvfs-1
[236.959630] Dynamic subtest numvfs-1: FAIL (0.028s)
[236.967218] Starting dynamic subtest: numvfs-2
[237.038235] Dynamic subtest numvfs-2: FAIL (0.003s)
[237.048050] Starting dynamic subtest: numvfs-3
[237.120281] Dynamic subtest numvfs-3: FAIL (0.003s)
[237.130098] Starting dynamic subtest: numvfs-4
[237.201587] Dynamic subtest numvfs-4: FAIL (0.003s)
[237.210826] Starting dynamic subtest: numvfs-5
[237.288158] Dynamic subtest numvfs-5: FAIL (0.003s)
[237.297364] Starting dynamic subtest: numvfs-6
[237.369677] Dynamic subtest numvfs-6: FAIL (0.003s)
[237.379782] Starting dynamic subtest: numvfs-7
[237.451591] Dynamic subtest numvfs-7: FAIL (0.003s)
[237.459801] Starting dynamic subtest: numvfs-random
[237.523707] Dynamic subtest numvfs-random: FAIL (0.004s)
[237.530971] Starting dynamic subtest: numvfs-all
[237.590261] Dynamic subtest numvfs-all: FAIL (0.504s)
[237.591959] Subtest enable-vfs-autoprobe-on: FAIL (0.564s)
[237.627141] [052/131] (783s left) gem_exec_whisper (basic-queues)
[237.687957] Starting subtest: basic-queues
[244.852537] Subtest basic-queues: SUCCESS (7.159s)
[245.054283] [053/131] (776s left) kms_flip (2x-dpms-vs-vblank-race)
[245.152875] Starting subtest: 2x-dpms-vs-vblank-race
[245.163712] Subtest 2x-dpms-vs-vblank-race: SKIP (0.010s)
[245.265500] [054/131] (775s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[245.369094] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[245.739320] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[248.531837] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.791s)
[248.545322] Starting dynamic subtest: pipe-A-eDP-1-4
[250.707014] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.173s)
[250.721667] Starting dynamic subtest: pipe-A-eDP-1-4
[252.717310] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[252.730955] Starting dynamic subtest: pipe-A-eDP-1-x
[254.908022] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.189s)
[255.270223] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[258.057735] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.792s)
[258.069349] Starting dynamic subtest: pipe-B-eDP-1-4
[260.243644] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.185s)
[260.252547] Starting dynamic subtest: pipe-B-eDP-1-4
[262.253895] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.008s)
[262.265220] Starting dynamic subtest: pipe-B-eDP-1-x
[264.455879] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.201s)
[264.819681] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[267.585463] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.772s)
[267.597753] Starting dynamic subtest: pipe-C-eDP-1-4
[269.760712] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.174s)
[269.772024] Starting dynamic subtest: pipe-C-eDP-1-4
[271.770304] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[271.782559] Starting dynamic subtest: pipe-C-eDP-1-x
[273.949218] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.178s)
[274.309417] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[277.111816] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.807s)
[277.123307] Starting dynamic subtest: pipe-D-eDP-1-4
[279.286899] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[279.295569] Starting dynamic subtest: pipe-D-eDP-1-4
[281.297108] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.010s)
[281.308974] Starting dynamic subtest: pipe-D-eDP-1-x
[283.477398] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[283.480207] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.106s)
[284.727272] [055/131] (736s left) gem_mmap_gtt (basic-write-read-distinct)
[284.790778] Subtest basic-write-read-distinct: SKIP (0.000s)
[284.856388] [056/131] (736s left) gem_mmap_offset (oob-read)
[284.915662] Starting subtest: oob-read
[284.918037] Subtest oob-read: SUCCESS (0.000s)
[285.062242] [057/131] (736s left) kms_atomic (atomic-plane-damage)
[285.151076] Starting subtest: atomic-plane-damage
[285.199319] Starting dynamic subtest: pipe-A-eDP-1
[285.718142] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.518s)
[285.720384] Subtest atomic-plane-damage: SUCCESS (0.567s)
[286.546698] [058/131] (734s left) kms_atomic_transition (plane-all-transition-nonblocking)
[286.643947] Starting subtest: plane-all-transition-nonblocking
[286.646287] Starting dynamic subtest: pipe-A-eDP-1
[289.138070] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.494s)
[289.442864] Starting dynamic subtest: pipe-B-eDP-1
[292.658368] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.225s)
[292.960025] Subtest plane-all-transition-nonblocking: SUCCESS (6.316s)
[293.792479] [059/131] (727s left) gem_exec_parallel (contexts)
[293.866633] Starting subtest: contexts
[293.868746] Starting dynamic subtest: rcs0
[296.886663] Dynamic subtest rcs0: SUCCESS (3.018s)
[296.894977] Starting dynamic subtest: bcs0
[300.432038] Dynamic subtest bcs0: SUCCESS (3.544s)
[300.442868] Starting dynamic subtest: vcs0
[304.374671] Dynamic subtest vcs0: SUCCESS (3.942s)
[304.384473] Starting dynamic subtest: vcs1
[308.353757] Dynamic subtest vcs1: SUCCESS (3.978s)
[308.363347] Starting dynamic subtest: vecs0
[312.416837] Dynamic subtest vecs0: SUCCESS (4.060s)
[312.426604] Starting dynamic subtest: ccs0
[315.862853] Dynamic subtest ccs0: SUCCESS (3.445s)
[315.865709] Subtest contexts: SUCCESS (21.996s)
[316.075612] [060/131] (705s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[316.193898] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[322.906114] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.715s)
[323.097205] [061/131] (698s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[323.210258] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[323.214441] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[323.330208] [062/131] (698s left) kms_atomic_interruptible (legacy-pageflip)
[323.425579] Starting subtest: legacy-pageflip
[323.427704] Starting dynamic subtest: pipe-A-eDP-1
[330.830097] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.404s)
[330.832351] Subtest legacy-pageflip: SUCCESS (7.405s)
[331.677521] [063/131] (689s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[331.775571] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[331.780091] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[331.900981] [064/131] (689s left) kms_psr (psr2-sprite-mmap-cpu)
[331.999086] Starting subtest: psr2-sprite-mmap-cpu
[332.001394] Starting dynamic subtest: eDP-1
[333.742192] Dynamic subtest eDP-1: SUCCESS (1.743s)
[333.744586] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.744s)
[334.635648] [065/131] (686s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[334.738292] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[341.864768] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.128s)
[342.034654] [066/131] (679s left) gem_exec_schedule (fairslice-all)
[342.108620] Starting subtest: fairslice-all
[344.160911] Subtest fairslice-all: SUCCESS (2.060s)
[346.005441] [067/131] (675s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[351.005724] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[351.007596] Starting dynamic subtest: pipe-A-eDP-1
[351.809211] Dynamic subtest pipe-A-eDP-1: SKIP (0.801s)
[351.820926] Starting dynamic subtest: pipe-B-eDP-1
[352.931795] Dynamic subtest pipe-B-eDP-1: SKIP (1.120s)
[352.936469] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.925s)
[354.147325] [068/131] (667s left) api_intel_allocator (two-level-inception)
[354.198738] Starting subtest: two-level-inception
[359.597504] Subtest two-level-inception: SUCCESS (5.399s)
[359.757489] [069/131] (661s left) i915_suspend (debugfs-reader)
[359.817185] Starting subtest: debugfs-reader
[378.028613] Subtest debugfs-reader: SUCCESS (7.817s)
[378.115548] [070/131] (643s left) kms_flip (plain-flip-fb-recreate-interruptible)
[378.243159] Starting subtest: plain-flip-fb-recreate-interruptible
[378.245467] Starting dynamic subtest: A-eDP1
[387.070379] Dynamic subtest A-eDP1: SUCCESS (8.854s)
[387.080770] Starting dynamic subtest: B-eDP1
[395.875991] Dynamic subtest B-eDP1: SUCCESS (8.815s)
[395.894188] Starting dynamic subtest: C-eDP1
[404.691470] Dynamic subtest C-eDP1: SUCCESS (8.819s)
[404.702019] Starting dynamic subtest: D-eDP1
[413.509008] Dynamic subtest D-eDP1: SUCCESS (8.817s)
[413.513306] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.316s)
[414.717255] [071/131] (607s left) gem_vm_create (invalid-destroy)
[414.784680] Starting subtest: invalid-destroy
[414.787056] Subtest invalid-destroy: SUCCESS (0.002s)
[414.934621] [072/131] (606s left) gem_ccs (ctrl-surf-copy-new-ctx)
[415.004435] Starting subtest: ctrl-surf-copy-new-ctx
[415.008829] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[415.159322] [073/131] (606s left) syncobj_basic (bad-destroy-pad)
[415.229917] Starting subtest: bad-destroy-pad
[415.232246] Subtest bad-destroy-pad: SUCCESS (0.000s)
[415.370533] [074/131] (606s left) kms_plane_multiple (2x-tiling-y)
[415.478113] Starting subtest: 2x-tiling-y
[415.482337] Subtest 2x-tiling-y: SKIP (0.000s)
[415.569014] [075/131] (606s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[415.664258] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[415.666232] Starting dynamic subtest: pipe-A-eDP-1
[415.684495] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[415.695622] Starting dynamic subtest: pipe-B-eDP-1
[415.712772] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[415.721944] Starting dynamic subtest: pipe-C-eDP-1
[415.739090] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[415.748054] Starting dynamic subtest: pipe-D-eDP-1
[415.765931] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[415.769244] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[417.987781] [076/131] (604s left) kms_pm_rpm (modeset-lpsp)
[419.527916] Starting subtest: modeset-lpsp
[421.003368] Subtest modeset-lpsp: SUCCESS (1.479s)
[421.126786] [077/131] (600s left) kms_setmode (basic)
[421.180048] Starting subtest: basic
[421.503457] Starting dynamic subtest: pipe-A-eDP-1
[423.571192] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.063s)
[423.910925] Starting dynamic subtest: pipe-B-eDP-1
[425.994209] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.088s)
[426.302395] Subtest basic: SUCCESS (5.121s)
[427.163915] [078/131] (594s left) kms_psr (fbc-psr-suspend)
[427.269822] Starting subtest: fbc-psr-suspend
[427.272159] Starting dynamic subtest: eDP-1
[427.276548] Dynamic subtest eDP-1: SKIP (0.000s)
[427.280934] Subtest fbc-psr-suspend: SKIP (0.001s)
[427.367606] [079/131] (594s left) gem_ringfill (engines-basic)
[433.335608] Starting subtest: engines-basic
[433.338210] Starting dynamic subtest: rcs0
[433.403383] Dynamic subtest rcs0: SUCCESS (0.068s)
[433.415952] Starting dynamic subtest: bcs0
[433.448572] Dynamic subtest bcs0: SUCCESS (0.044s)
[433.459291] Starting dynamic subtest: vcs0
[433.491445] Dynamic subtest vcs0: SUCCESS (0.041s)
[433.501921] Starting dynamic subtest: vcs1
[433.534452] Dynamic subtest vcs1: SUCCESS (0.041s)
[433.545266] Starting dynamic subtest: vecs0
[433.577550] Dynamic subtest vecs0: SUCCESS (0.041s)
[433.587870] Starting dynamic subtest: ccs0
[433.624058] Dynamic subtest ccs0: SUCCESS (0.044s)
[433.626779] Subtest engines-basic: SUCCESS (0.290s)
[433.725984] [080/131] (588s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[433.790787] Starting subtest: invalid-bsd2-flag-on-vebox
[433.793241] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[433.932309] [081/131] (588s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[434.040506] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[434.042393] Starting dynamic subtest: pipe-A-eDP-1
[434.064144] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.020s)
[434.083162] Starting dynamic subtest: pipe-B-eDP-1
[434.103569] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.019s)
[434.115833] Starting dynamic subtest: pipe-C-eDP-1
[434.146680] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.020s)
[434.157498] Starting dynamic subtest: pipe-D-eDP-1
[434.175783] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.020s)
[434.177949] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.085s)
[436.440840] [082/131] (585s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[436.545041] Starting subtest: extended-pageflip-modeset-hang-oldfb
[436.547167] Starting dynamic subtest: pipe-A
[444.451774] Dynamic subtest pipe-A: SUCCESS (7.909s)
[444.461554] Starting dynamic subtest: pipe-D
[453.018526] Dynamic subtest pipe-D: SUCCESS (8.568s)
[453.020857] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.482s)
[453.883294] [083/131] (568s left) gem_ppgtt (shrink-vs-evict-any)
[453.949157] Starting subtest: shrink-vs-evict-any
[459.283775] Subtest shrink-vs-evict-any: SUCCESS (5.334s)
[459.385299] [084/131] (562s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[459.458903] Starting subtest: etime-single-wait-for-submit-unsubmitted
[459.567703] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.108s)
[459.674266] [085/131] (562s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[459.731174] Starting subtest: multi-wait-all-for-submit-signaled
[459.733464] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[459.861514] [086/131] (562s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[459.915901] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[460.025240] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.109s)
[460.129044] [087/131] (562s left) gem_exec_schedule (implicit-write-read)
[460.199277] Starting subtest: implicit-write-read
[460.201492] Starting dynamic subtest: rcs0
[460.378352] Dynamic subtest rcs0: SUCCESS (0.187s)
[460.391427] Starting dynamic subtest: vcs0
[460.487013] Dynamic subtest vcs0: SUCCESS (0.108s)
[460.499455] Starting dynamic subtest: vcs1
[460.595833] Dynamic subtest vcs1: SUCCESS (0.108s)
[460.606709] Starting dynamic subtest: vecs0
[460.701939] Dynamic subtest vecs0: SUCCESS (0.104s)
[460.714245] Starting dynamic subtest: ccs0
[460.819083] Dynamic subtest ccs0: SUCCESS (0.117s)
[460.821820] Subtest implicit-write-read: SUCCESS (0.629s)
[461.447522] [088/131] (561s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[461.554654] Starting subtest: fbcpsr-rgb565-draw-blt
[468.566301] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.016s)
[468.743852] [089/131] (553s left) gem_ctx_persistence (processes)
[468.801025] Starting subtest: processes
[470.344191] Subtest processes: SUCCESS (1.543s)
[470.447396] [090/131] (552s left) kms_cursor_crc (cursor-sliding-32x10)
[470.607132] Starting subtest: cursor-sliding-32x10
[470.650820] Subtest cursor-sliding-32x10: SKIP (0.040s)
[470.952487] [091/131] (551s left) kms_psr (fbc-pr-primary-render)
[471.057548] Starting subtest: fbc-pr-primary-render
[471.061608] Subtest fbc-pr-primary-render: SKIP (0.000s)
[471.154690] [092/131] (551s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[471.217165] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[471.314634] [093/131] (551s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[471.372489] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[471.374661] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[471.499191] [094/131] (551s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[471.603882] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[471.607343] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[471.749056] [095/131] (551s left) gem_exec_async (concurrent-writes)
[471.818221] Starting subtest: concurrent-writes
[471.820199] Starting dynamic subtest: rcs0
[471.833727] Dynamic subtest rcs0: SUCCESS (0.015s)
[471.846470] Starting dynamic subtest: bcs0
[471.848349] Dynamic subtest bcs0: SUCCESS (0.005s)
[471.857847] Starting dynamic subtest: vcs0
[471.859734] Dynamic subtest vcs0: SUCCESS (0.005s)
[471.868944] Starting dynamic subtest: vcs1
[471.870596] Dynamic subtest vcs1: SUCCESS (0.005s)
[471.879705] Starting dynamic subtest: vecs0
[471.881310] Dynamic subtest vecs0: SUCCESS (0.006s)
[471.890440] Starting dynamic subtest: ccs0
[471.892075] Dynamic subtest ccs0: SUCCESS (0.006s)
[471.893697] Subtest concurrent-writes: SUCCESS (0.046s)
[472.142548] [096/131] (550s left) gem_mmap_gtt (basic-small-bo-tiledx)
[472.208467] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[472.277173] [097/131] (550s left) dmabuf_sync_file (import-basic)
[472.319509] Starting subtest: import-basic
[472.321463] Subtest import-basic: SUCCESS (0.001s)
[472.403249] [098/131] (550s left) kms_feature_discovery (display)
[472.499711] Starting subtest: display
[472.501524] Subtest display: SUCCESS (0.000s)
[472.599181] [099/131] (550s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[472.702849] Starting subtest: x-tiled-64bpp-rotate-90
[472.722487] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[472.883158] [100/131] (550s left) perf_pmu (busy-no-semaphores)
[472.924910] Starting subtest: busy-no-semaphores
[472.926943] Starting dynamic subtest: rcs0
[473.502844] Dynamic subtest rcs0: SUCCESS (0.575s)
[473.516082] Starting dynamic subtest: bcs0
[474.052508] Dynamic subtest bcs0: SUCCESS (0.547s)
[474.065812] Starting dynamic subtest: vcs0
[474.603745] Dynamic subtest vcs0: SUCCESS (0.548s)
[474.617754] Starting dynamic subtest: vcs1
[475.831468] Dynamic subtest vcs1: SUCCESS (1.223s)
[475.848391] Starting dynamic subtest: vecs0
[477.815827] Dynamic subtest vecs0: SUCCESS (1.980s)
[477.829284] Starting dynamic subtest: ccs0
[479.800625] Dynamic subtest ccs0: SUCCESS (1.982s)
[479.803515] Subtest busy-no-semaphores: SUCCESS (6.872s)
[479.956501] [101/131] (543s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[480.071663] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[480.075956] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[480.208674] [102/131] (542s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[480.329540] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[480.333979] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[480.501707] [103/131] (542s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[480.869729] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[481.687547] [104/131] (541s left) kms_getfb (getfb-reject-nv12)
[481.782287] Starting subtest: getfb-reject-nv12
[481.784647] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[481.921416] [105/131] (541s left) i915_drm_fdinfo (context-close-stress)
[481.964593] Starting subtest: context-close-stress
[492.713215] Subtest context-close-stress: SUCCESS (10.745s)
[492.813545] [106/131] (530s left) perf_pmu (module-unload)
[493.039660] Starting subtest: module-unload
[501.870799] Subtest module-unload: FAIL (8.723s)
[501.902211] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[501.902903] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[501.905817] [107/131] (521s left) kms_chamelium_edid (dp-edid-read)
[503.658783] Subtest dp-edid-read: SKIP (0.000s)
[504.495533] [FACT kms_chamelium_edid (dp-edid-read)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[504.496719] [FACT kms_chamelium_edid (dp-edid-read)] new: kernel.kmod_is_loaded.i915: true
[504.500328] [108/131] (518s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[509.500266] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[509.502512] Starting dynamic subtest: pipe-A-eDP-1
[510.305596] Dynamic subtest pipe-A-eDP-1: SKIP (0.802s)
[510.315358] Starting dynamic subtest: pipe-B-eDP-1
[511.437791] Dynamic subtest pipe-B-eDP-1: SKIP (1.131s)
[511.442387] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.937s)
[512.658502] [109/131] (510s left) kms_vblank (query-busy)
[512.759457] Starting subtest: query-busy
[512.761306] Starting dynamic subtest: pipe-A-eDP-1
[514.634450] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.873s)
[514.649485] Starting dynamic subtest: pipe-D-eDP-1
[517.190345] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.556s)
[517.192678] Subtest query-busy: SUCCESS (4.433s)
[518.016776] [110/131] (505s left) syncobj_timeline (wait-for-submit-complex)
[518.083064] Starting subtest: wait-for-submit-complex
[518.143554] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[518.263131] [111/131] (505s left) perf (invalid-create-userspace-config)
[518.415959] Starting subtest: invalid-create-userspace-config
[518.418330] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[518.461914] [112/131] (505s left) gem_exec_basic (basic)
[518.519395] Starting subtest: basic
[518.522194] Starting dynamic subtest: rcs0-smem
[518.524684] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[518.542676] Starting dynamic subtest: bcs0-smem
[518.545017] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[518.555918] Starting dynamic subtest: vcs0-smem
[518.558125] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[518.566608] Starting dynamic subtest: vcs1-smem
[518.568739] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[518.576920] Starting dynamic subtest: vecs0-smem
[518.579046] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[518.587358] Starting dynamic subtest: ccs0-smem
[518.589534] Dynamic subtest ccs0-smem: SUCCESS (0.001s)
[518.591720] Subtest basic: SUCCESS (0.016s)
[520.372711] [113/131] (503s left) kms_chamelium_color (ctm-0-25)
[520.491037] Subtest ctm-0-25: SKIP (0.000s)
[520.535027] [114/131] (503s left) gem_userptr_blits (process-exit-busy)
[520.607602] Starting subtest: process-exit-busy
[520.618351] Subtest process-exit-busy: SUCCESS (0.014s)
[520.780392] [115/131] (502s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[520.850267] Starting subtest: y-tiled-ccs-to-y-tiled
[520.854783] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[521.056385] [116/131] (502s left) gen7_exec_parse (bitmasks)
[521.118565] Subtest bitmasks: SKIP (0.000s)
[521.185473] [117/131] (502s left) gem_eio (wait-wedge-10ms)
[521.373035] Starting subtest: wait-wedge-10ms
[523.370681] Subtest wait-wedge-10ms: SUCCESS (1.997s)
[523.533647] [118/131] (500s left) kms_content_protection (atomic)
[523.671585] Starting subtest: atomic
[523.676231] Subtest atomic: SKIP (0.000s)
[523.844647] [119/131] (499s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[523.949027] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[523.951158] Starting dynamic subtest: pipe-A-default-mode
[524.261251] Dynamic subtest pipe-A-default-mode: SKIP (0.310s)
[524.265675] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.312s)
[525.158548] [120/131] (498s left) gem_exec_params (invalid-flag)
[525.216862] Starting subtest: invalid-flag
[525.219215] Subtest invalid-flag: SUCCESS (0.000s)
[525.346099] [121/131] (498s left) kms_getfb (getfb-addfb-different-handles)
[525.443004] Starting subtest: getfb-addfb-different-handles
[525.445136] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[525.578868] [122/131] (498s left) gem_exec_flush (basic-uc-ro-default)
[525.626450] Starting subtest: basic-uc-ro-default
[531.083836] Subtest basic-uc-ro-default: SUCCESS (5.459s)
[531.299468] [123/131] (492s left) gem_sync (basic-each)
[531.377018] Starting subtest: basic-each
[533.921430] Subtest basic-each: SUCCESS (2.555s)
[534.129285] [124/131] (489s left) gem_exec_balancer (full)
[534.195307] Starting subtest: full
[539.176730] Subtest full: SUCCESS (4.982s)
[539.528382] [125/131] (484s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[539.642819] Starting subtest: y-tiled-8bpp-rotate-180
[539.647212] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[539.754257] [126/131] (484s left) sw_sync (sync_merge_invalid)
[539.788532] Starting subtest: sync_merge_invalid
[539.811089] Subtest sync_merge_invalid: SUCCESS (0.024s)
[539.886016] [127/131] (484s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[539.976792] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[544.329333] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.357s)
[545.565943] [128/131] (478s left) gem_lmem_swapping (parallel-random-verify-ccs)
[545.614126] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[545.661953] [129/131] (478s left) kms_lease (lease-unleased-connector)
[545.750389] Starting subtest: lease-unleased-connector
[545.752736] Starting dynamic subtest: pipe-A-eDP-1
[545.757644] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[545.767898] Starting dynamic subtest: pipe-B-eDP-1
[545.770059] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[545.780585] Starting dynamic subtest: pipe-C-eDP-1
[545.782716] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[545.792040] Starting dynamic subtest: pipe-D-eDP-1
[545.794121] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[545.796224] Subtest lease-unleased-connector: SUCCESS (0.034s)
[545.922901] [130/131] (478s left) perf (enable-disable)
[546.018355] Starting subtest: enable-disable
[546.020779] Starting dynamic subtest: 0-rcs0
[548.259184] Dynamic subtest 0-rcs0: SUCCESS (2.239s)
[548.270541] Starting dynamic subtest: 1-vcs0
[552.695037] Dynamic subtest 1-vcs0: SUCCESS (4.436s)
[552.697334] Subtest enable-disable: SUCCESS (6.677s)
[552.742009] [131/131] (471s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[552.901971] Starting subtest: cursor-rapid-movement-32x32
[552.949456] Subtest cursor-rapid-movement-32x32: SKIP (0.040s)
[553.272596] Closing watchdogs
results: parsing output: 0/ for test: fbdev
results: parsing output: 1/ for test: gem_ctx_isolation
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_flip_scaled_crc
results: parsing output: 4/ for test: gem_exec_params
results: parsing output: 5/ for test: gem_spin_batch
results: parsing output: 6/ for test: gem_exec_schedule
results: parsing output: 7/ for test: sysfs_heartbeat_interval
results: parsing output: 8/ for test: kms_invalid_mode
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_getfb
results: parsing output: 12/ for test: gem_render_copy
results: parsing output: 13/ for test: kms_rotation_crc
results: parsing output: 14/ for test: gem_eio
results: parsing output: 15/ for test: syncobj_wait
results: parsing output: 16/ for test: kms_flip_scaled_crc
results: parsing output: 17/ for test: kms_plane_alpha_blend
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: kms_flip
results: parsing output: 21/ for test: gem_exec_whisper
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: vgem_basic
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: core_auth
results: parsing output: 29/ for test: kms_plane_scaling
results: parsing output: 30/ for test: kms_dither
results: parsing output: 31/ for test: gem_partial_pwrite_pread
results: parsing output: 32/ for test: gem_eio
results: parsing output: 33/ for test: gen3_render_mixed_blits
results: parsing output: 34/ for test: kms_pm_rpm
results: parsing output: 35/ for test: i915_module_load
results: parsing output: 36/ for test: kms_plane_scaling
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_rotation_crc
results: parsing output: 39/ for test: kms_cursor_crc
results: parsing output: 40/ for test: kms_vblank
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_flip
results: parsing output: 43/ for test: gem_exec_fence
results: parsing output: 44/ for test: kms_ccs
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_flip
results: parsing output: 47/ for test: prime_mmap
results: parsing output: 48/ for test: i915_pm_rpm
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: sriov_basic
results: parsing output: 51/ for test: gem_exec_whisper
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_async_flips
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: gem_mmap_offset
results: parsing output: 56/ for test: kms_atomic
results: parsing output: 57/ for test: kms_atomic_transition
results: parsing output: 58/ for test: gem_exec_parallel
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_atomic_interruptible
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gem_exec_schedule
results: parsing output: 66/ for test: kms_psr2_sf
results: parsing output: 67/ for test: api_intel_allocator
results: parsing output: 68/ for test: i915_suspend
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: gem_vm_create
results: parsing output: 71/ for test: gem_ccs
results: parsing output: 72/ for test: syncobj_basic
results: parsing output: 73/ for test: kms_plane_multiple
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: kms_pm_rpm
results: parsing output: 76/ for test: kms_setmode
results: parsing output: 77/ for test: kms_psr
results: parsing output: 78/ for test: gem_ringfill
results: parsing output: 79/ for test: gem_exec_params
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: kms_busy
results: parsing output: 82/ for test: gem_ppgtt
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: syncobj_timeline
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: gem_exec_schedule
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_ctx_persistence
results: parsing output: 89/ for test: kms_cursor_crc
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: gem_exec_reloc
results: parsing output: 92/ for test: syncobj_wait
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_exec_async
results: parsing output: 95/ for test: gem_mmap_gtt
results: parsing output: 96/ for test: dmabuf_sync_file
results: parsing output: 97/ for test: kms_feature_discovery
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: perf_pmu
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_cursor_legacy
results: parsing output: 102/ for test: kms_chamelium_hpd
results: parsing output: 103/ for test: kms_getfb
results: parsing output: 104/ for test: i915_drm_fdinfo
results: parsing output: 105/ for test: perf_pmu
results: parsing output: 106/ for test: kms_chamelium_edid
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: kms_vblank
results: parsing output: 109/ for test: syncobj_timeline
results: parsing output: 110/ for test: perf
results: parsing output: 111/ for test: gem_exec_basic
results: parsing output: 112/ for test: kms_chamelium_color
results: parsing output: 113/ for test: gem_userptr_blits
results: parsing output: 114/ for test: gem_render_copy
results: parsing output: 115/ for test: gen7_exec_parse
results: parsing output: 116/ for test: gem_eio
results: parsing output: 117/ for test: kms_content_protection
results: parsing output: 118/ for test: kms_flip_scaled_crc
results: parsing output: 119/ for test: gem_exec_params
results: parsing output: 120/ for test: kms_getfb
results: parsing output: 121/ for test: gem_exec_flush
results: parsing output: 122/ for test: gem_sync
results: parsing output: 123/ for test: gem_exec_balancer
results: parsing output: 124/ for test: kms_big_fb
results: parsing output: 125/ for test: sw_sync
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: gem_lmem_swapping
results: parsing output: 128/ for test: kms_lease
results: parsing output: 129/ for test: perf
results: parsing output: 130/ for test: kms_cursor_crc
Done.