[57.328492] Initializing watchdogs
[57.328544]   /dev/watchdog0
[57.352047] [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]
[57.356120] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.358244] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.361379] [001/131] (960s left) fbdev (unaligned-read)
[57.430677] Starting subtest: unaligned-read
[57.445350] Subtest unaligned-read: SUCCESS (0.014s)
[57.520696] [002/131] (960s left) gem_ctx_isolation (dirty-switch)
[57.576946] Starting subtest: dirty-switch
[57.579193] Starting dynamic subtest: rcs0
[57.822654] Dynamic subtest rcs0: SUCCESS (0.244s)
[57.830486] Starting dynamic subtest: bcs0
[58.151656] Dynamic subtest bcs0: SUCCESS (0.327s)
[58.161695] Starting dynamic subtest: vcs0
[58.427883] Dynamic subtest vcs0: SUCCESS (0.275s)
[58.434930] Starting dynamic subtest: vcs1
[60.152943] Dynamic subtest vcs1: SUCCESS (1.716s)
[60.162963] Starting dynamic subtest: vecs0
[62.145561] Dynamic subtest vecs0: SUCCESS (1.986s)
[62.155206] Starting dynamic subtest: ccs0
[64.137436] Dynamic subtest ccs0: SUCCESS (1.987s)
[64.139802] Subtest dirty-switch: SUCCESS (6.537s)
[64.399286] [003/131] (953s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[64.509685] Starting subtest: psr-modesetfrombusy
[70.690241] Subtest psr-modesetfrombusy: SUCCESS (6.176s)
[70.859832] [004/131] (947s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[70.972098] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[70.974037] Starting dynamic subtest: pipe-A-default-mode
[73.691485] Dynamic subtest pipe-A-default-mode: SUCCESS (2.716s)
[73.693489] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.717s)
[74.577331] [005/131] (943s left) gem_exec_params (sol-reset-invalid)
[74.638070] Starting subtest: sol-reset-invalid
[74.640436] Subtest sol-reset-invalid: SUCCESS (0.002s)
[76.028736] [006/131] (942s left) gem_spin_batch (resubmit-new)
[76.098691] Starting subtest: resubmit-new
[76.100620] Starting dynamic subtest: rcs0
[76.105031] Dynamic subtest rcs0: SUCCESS (0.008s)
[76.113083] Starting dynamic subtest: bcs0
[76.115745] Dynamic subtest bcs0: SUCCESS (0.005s)
[76.125924] Starting dynamic subtest: vcs0
[76.128085] Dynamic subtest vcs0: SUCCESS (0.004s)
[76.135842] Starting dynamic subtest: vcs1
[76.138051] Dynamic subtest vcs1: SUCCESS (0.005s)
[76.145099] Starting dynamic subtest: vecs0
[76.147263] Dynamic subtest vecs0: SUCCESS (0.004s)
[76.154553] Starting dynamic subtest: ccs0
[76.156725] Dynamic subtest ccs0: SUCCESS (0.005s)
[76.158884] Subtest resubmit-new: SUCCESS (0.032s)
[77.968169] [007/131] (940s left) gem_exec_schedule (preempt-user)
[78.042338] Starting subtest: preempt-user
[78.044666] Starting dynamic subtest: rcs0
[78.077297] Dynamic subtest rcs0: SUCCESS (0.041s)
[78.088532] Starting dynamic subtest: bcs0
[78.108599] Dynamic subtest bcs0: SUCCESS (0.031s)
[78.118701] Starting dynamic subtest: vcs0
[78.133263] Dynamic subtest vcs0: SUCCESS (0.024s)
[78.143790] Starting dynamic subtest: vcs1
[78.165232] Dynamic subtest vcs1: SUCCESS (0.031s)
[78.172143] Starting dynamic subtest: vecs0
[78.186888] Dynamic subtest vecs0: SUCCESS (0.021s)
[78.193825] Starting dynamic subtest: ccs0
[78.217678] Dynamic subtest ccs0: SUCCESS (0.031s)
[78.220039] Subtest preempt-user: SUCCESS (0.182s)
[80.027645] [008/131] (938s left) sysfs_heartbeat_interval (nopreempt)
[80.091737] Starting subtest: nopreempt
[80.094096] Starting dynamic subtest: vecs0
[89.903875] Dynamic subtest vecs0: SUCCESS (9.807s)
[89.913293] Starting dynamic subtest: ccs0
[94.002900] Dynamic subtest ccs0: SUCCESS (4.100s)
[94.013673] Starting dynamic subtest: vcs0
[103.902664] Dynamic subtest vcs0: SUCCESS (9.895s)
[103.911798] Starting dynamic subtest: bcs0
[107.893306] Dynamic subtest bcs0: SUCCESS (3.992s)
[107.903818] Starting dynamic subtest: rcs0
[112.000518] Dynamic subtest rcs0: SUCCESS (4.105s)
[112.012374] Starting dynamic subtest: vcs1
[121.912857] Dynamic subtest vcs1: SUCCESS (9.910s)
[121.915307] Subtest nopreempt: SUCCESS (41.814s)
[122.004901] [009/131] (896s left) kms_invalid_mode (zero-hdisplay)
[122.106379] Starting subtest: zero-hdisplay
[122.108691] Starting dynamic subtest: pipe-A-eDP-1
[122.388061] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[122.396078] Starting dynamic subtest: pipe-B-eDP-1
[122.398507] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[122.408114] Starting dynamic subtest: pipe-C-eDP-1
[122.410228] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[122.417762] Starting dynamic subtest: pipe-D-eDP-1
[122.419627] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[122.421590] Subtest zero-hdisplay: SUCCESS (0.291s)
[123.200273] [010/131] (895s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[123.314342] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[123.318886] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[123.433209] [011/131] (894s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[123.523742] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[124.381994] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.859s)
[124.610100] [012/131] (893s left) kms_getfb (getfb-handle-zero)
[124.714291] Starting subtest: getfb-handle-zero
[124.716522] Subtest getfb-handle-zero: SUCCESS (0.000s)
[124.850452] [013/131] (893s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[124.922580] Starting subtest: yf-tiled-ccs-to-yf-tiled
[124.926716] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[125.127641] [014/131] (893s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[125.222428] Starting subtest: multiplane-rotation-cropping-bottom
[132.414862] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.193s)
[132.563862] [015/131] (885s left) gem_eio (wait-1us)
[132.776952] Starting subtest: wait-1us
[133.104900] Subtest wait-1us: SUCCESS (0.328s)
[133.276698] [016/131] (885s left) syncobj_wait (multi-wait-for-submit-signaled)
[133.342561] Starting subtest: multi-wait-for-submit-signaled
[133.344577] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[133.473506] [017/131] (885s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[133.567088] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[133.569000] Starting dynamic subtest: pipe-A-default-mode
[133.861603] Dynamic subtest pipe-A-default-mode: SKIP (0.291s)
[133.866395] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.292s)
[134.738866] [018/131] (883s left) kms_plane_alpha_blend (alpha-7efc)
[134.837685] Starting subtest: alpha-7efc
[135.115726] Starting dynamic subtest: pipe-A-eDP-1
[135.714619] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.596s)
[135.744459] Starting dynamic subtest: pipe-D-eDP-1
[137.030894] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.292s)
[137.033648] Subtest alpha-7efc: SUCCESS (2.193s)
[137.892312] [019/131] (880s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[138.007022] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[138.011295] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[138.137452] [020/131] (880s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[138.235769] Starting subtest: fbc-psr2-primary-mmap-cpu
[138.238056] Starting dynamic subtest: eDP-1
[138.242168] Dynamic subtest eDP-1: SKIP (0.000s)
[138.246237] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[138.330308] [021/131] (880s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[138.426042] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[138.436820] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[138.568858] [022/131] (880s left) gem_exec_whisper (basic-normal-all)
[138.633043] Starting subtest: basic-normal-all
[141.129327] Subtest basic-normal-all: SUCCESS (2.494s)
[141.339407] [023/131] (877s left) kms_big_fb (linear-16bpp-rotate-180)
[141.446092] Starting subtest: linear-16bpp-rotate-180
[142.867401] Subtest linear-16bpp-rotate-180: SUCCESS (1.422s)
[143.053496] [024/131] (875s left) kms_frontbuffer_tracking (psr-slowdraw)
[143.156904] Starting subtest: psr-slowdraw
[168.274031] Subtest psr-slowdraw: SUCCESS (25.116s)
[168.419436] [025/131] (850s left) vgem_basic (dmabuf-fence)
[168.516887] Starting subtest: dmabuf-fence
[168.519138] Subtest dmabuf-fence: SUCCESS (0.001s)
[168.594176] [FACT vgem_basic (dmabuf-fence)] new: kernel.kmod_is_loaded.vgem: true
[168.597339] [026/131] (850s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[168.656951] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[168.659452] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[168.800214] [027/131] (850s left) kms_psr (pr-primary-render)
[168.897661] Starting subtest: pr-primary-render
[168.902070] Subtest pr-primary-render: SKIP (0.000s)
[168.992565] [028/131] (849s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[169.087767] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[169.089758] Starting dynamic subtest: pipe-A-eDP-1
[169.109057] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[169.119402] Starting dynamic subtest: pipe-B-eDP-1
[169.137217] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[169.145089] Starting dynamic subtest: pipe-C-eDP-1
[169.163055] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[169.171913] Starting dynamic subtest: pipe-D-eDP-1
[169.190073] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[169.193629] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[171.279672] [029/131] (847s left) core_auth (getclient-master-drop)
[171.318265] Starting subtest: getclient-master-drop
[171.338353] Subtest getclient-master-drop: SUCCESS (0.020s)
[171.473368] [030/131] (847s left) kms_plane_scaling (planes-downscale-factor-0-5)
[171.568748] Starting subtest: planes-downscale-factor-0-5
[171.571020] Starting dynamic subtest: pipe-A
[171.589319] Dynamic subtest pipe-A: SKIP (0.016s)
[171.602502] Starting dynamic subtest: pipe-B
[171.621951] Dynamic subtest pipe-B: SKIP (0.033s)
[171.630871] Starting dynamic subtest: pipe-C
[171.657934] Dynamic subtest pipe-C: SKIP (0.033s)
[171.666195] Starting dynamic subtest: pipe-D
[171.686761] Dynamic subtest pipe-D: SKIP (0.029s)
[171.691220] Subtest planes-downscale-factor-0-5: SKIP (0.114s)
[171.813289] [031/131] (847s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[171.906022] Starting subtest: fb-8bpc-vs-panel-8bpc
[171.908327] Starting dynamic subtest: pipe-A-eDP-1
[173.341084] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.435s)
[173.343556] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.437s)
[174.234406] [032/131] (844s left) gem_partial_pwrite_pread (reads-snoop)
[174.302488] Subtest reads-snoop: SKIP (0.000s)
[174.377664] [033/131] (844s left) gem_eio (in-flight-contexts-immediate)
[174.611179] Starting subtest: in-flight-contexts-immediate
[185.969563] Subtest in-flight-contexts-immediate: SUCCESS (11.357s)
[186.168600] [034/131] (833s left) gen3_render_mixed_blits
[186.304361] [035/131] (832s left) kms_pm_rpm (pm-tiling)
[188.039064] Starting subtest: pm-tiling
[188.043594] Subtest pm-tiling: SKIP (0.000s)
[188.562852] [036/131] (830s left) i915_module_load (reload-no-display)
[188.599035] Starting subtest: reload-no-display
[194.820362] Subtest reload-no-display: SUCCESS (6.221s)
[194.850703] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[194.851396] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[194.854527] [037/131] (824s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[196.472425] Starting subtest: plane-upscale-20x20-with-rotation
[196.474758] Starting dynamic subtest: pipe-A
[196.720311] Dynamic subtest pipe-A: SUCCESS (0.249s)
[196.731728] Starting dynamic subtest: pipe-B
[198.043218] Dynamic subtest pipe-B: SUCCESS (1.321s)
[198.050926] Starting dynamic subtest: pipe-C
[199.377646] Dynamic subtest pipe-C: SUCCESS (1.334s)
[199.385328] Starting dynamic subtest: pipe-D
[200.708168] Dynamic subtest pipe-D: SUCCESS (1.330s)
[200.710496] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.239s)
[201.868523] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[201.869820] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: kernel.kmod_is_loaded.i915: true
[201.873749] [038/131] (817s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[201.988762] Starting subtest: 4-tiled-8bpp-rotate-180
[203.194395] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.206s)
[203.361574] [039/131] (815s left) kms_rotation_crc (primary-rotation-90)
[203.468124] Starting subtest: primary-rotation-90
[203.471436] Subtest primary-rotation-90: SKIP (0.001s)
[203.564302] [040/131] (815s left) kms_cursor_crc (cursor-onscreen-32x10)
[203.737941] Starting subtest: cursor-onscreen-32x10
[203.782297] Subtest cursor-onscreen-32x10: SKIP (0.040s)
[204.078017] [041/131] (815s left) kms_vblank (wait-idle)
[204.183136] Starting subtest: wait-idle
[204.185089] Starting dynamic subtest: pipe-A-eDP-1
[205.943507] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.756s)
[205.957611] Starting dynamic subtest: pipe-D-eDP-1
[208.424370] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.479s)
[208.426804] Subtest wait-idle: SUCCESS (4.241s)
[209.271625] [042/131] (810s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[209.380368] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[209.384330] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[209.493911] [043/131] (810s left) kms_flip (2x-flip-vs-dpms)
[209.601425] Starting subtest: 2x-flip-vs-dpms
[209.610142] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[209.715419] [044/131] (809s left) gem_exec_fence (syncobj-channel)
[210.179755] Starting subtest: syncobj-channel
[211.194702] Subtest syncobj-channel: SUCCESS (1.015s)
[212.065151] [045/131] (807s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[212.169244] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[212.171551] Starting dynamic subtest: pipe-A-eDP-1
[212.180948] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[212.193387] Starting dynamic subtest: pipe-B-eDP-1
[212.202461] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[212.213778] Starting dynamic subtest: pipe-C-eDP-1
[212.222888] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[212.232055] Starting dynamic subtest: pipe-D-eDP-1
[212.240484] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[212.244576] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[214.336869] [046/131] (805s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[214.438291] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[221.700294] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.264s)
[221.847907] [047/131] (797s left) kms_flip (busy-flip)
[221.944186] Starting subtest: busy-flip
[221.946381] Starting dynamic subtest: A-eDP1
[223.660081] Dynamic subtest A-eDP1: SUCCESS (1.717s)
[223.670723] Starting dynamic subtest: B-eDP1
[225.313840] Dynamic subtest B-eDP1: SUCCESS (1.653s)
[225.327109] Starting dynamic subtest: C-eDP1
[226.945789] Dynamic subtest C-eDP1: SUCCESS (1.630s)
[226.957067] Starting dynamic subtest: D-eDP1
[228.574559] Dynamic subtest D-eDP1: SUCCESS (1.628s)
[228.576994] Subtest busy-flip: SUCCESS (6.636s)
[229.856436] [048/131] (789s left) prime_mmap (test_aperture_limit)
[229.928726] Starting subtest: test_aperture_limit
[229.931182] Starting dynamic subtest: test_aperture_limit-smem
[229.941390] Dynamic subtest test_aperture_limit-smem: SKIP (0.011s)
[229.945618] Subtest test_aperture_limit: SKIP (0.012s)
[230.085311] [049/131] (789s left) i915_pm_rpm (sysfs-read)
[232.103565] Starting subtest: sysfs-read
[233.264050] Subtest sysfs-read: SUCCESS (1.159s)
[233.657381] [050/131] (786s left) kms_big_fb (yf-tiled-addfb)
[233.760124] Starting subtest: yf-tiled-addfb
[233.764595] Subtest yf-tiled-addfb: SKIP (0.000s)
[233.881678] [051/131] (785s left) sriov_basic (enable-vfs-autoprobe-on)
[233.941087] Starting subtest: enable-vfs-autoprobe-on
[233.943452] Starting dynamic subtest: numvfs-1
[234.020533] Dynamic subtest numvfs-1: FAIL (0.029s)
[234.028076] Starting dynamic subtest: numvfs-2
[234.101311] Dynamic subtest numvfs-2: FAIL (0.004s)
[234.111232] Starting dynamic subtest: numvfs-3
[234.184550] Dynamic subtest numvfs-3: FAIL (0.004s)
[234.193772] Starting dynamic subtest: numvfs-4
[234.266914] Dynamic subtest numvfs-4: FAIL (0.004s)
[234.276368] Starting dynamic subtest: numvfs-5
[234.349745] Dynamic subtest numvfs-5: FAIL (0.004s)
[234.359683] Starting dynamic subtest: numvfs-6
[234.433889] Dynamic subtest numvfs-6: FAIL (0.003s)
[234.443338] Starting dynamic subtest: numvfs-7
[234.516681] Dynamic subtest numvfs-7: FAIL (0.003s)
[234.525980] Starting dynamic subtest: numvfs-random
[234.591811] Dynamic subtest numvfs-random: FAIL (0.003s)
[234.600716] Starting dynamic subtest: numvfs-all
[234.662549] Dynamic subtest numvfs-all: FAIL (0.509s)
[234.664486] Subtest enable-vfs-autoprobe-on: FAIL (0.572s)
[234.699994] [052/131] (785s left) gem_exec_whisper (basic-queues)
[234.775181] Starting subtest: basic-queues
[240.481833] Subtest basic-queues: SUCCESS (5.703s)
[240.691490] [053/131] (779s left) kms_flip (2x-dpms-vs-vblank-race)
[240.802860] Starting subtest: 2x-dpms-vs-vblank-race
[240.814407] Subtest 2x-dpms-vs-vblank-race: SKIP (0.010s)
[240.916085] [054/131] (779s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[241.007350] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[241.381413] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[244.186276] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.803s)
[244.198036] Starting dynamic subtest: pipe-A-eDP-1-4
[246.362383] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.174s)
[246.373631] Starting dynamic subtest: pipe-A-eDP-1-4
[248.372376] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[248.385547] Starting dynamic subtest: pipe-A-eDP-1-x
[250.573444] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.199s)
[250.942773] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[253.709399] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.774s)
[253.726913] Starting dynamic subtest: pipe-B-eDP-1-4
[255.951527] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.239s)
[255.962661] Starting dynamic subtest: pipe-B-eDP-1-4
[257.961881] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[257.976334] Starting dynamic subtest: pipe-B-eDP-1-x
[260.151943] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.188s)
[260.546677] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[263.348267] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.812s)
[263.360611] Starting dynamic subtest: pipe-C-eDP-1-4
[265.523453] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.174s)
[265.534541] Starting dynamic subtest: pipe-C-eDP-1-4
[267.534029] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[267.548290] Starting dynamic subtest: pipe-C-eDP-1-x
[269.712026] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.176s)
[270.072464] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[272.877350] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.810s)
[272.889138] Starting dynamic subtest: pipe-D-eDP-1-4
[275.063278] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.185s)
[275.074768] Starting dynamic subtest: pipe-D-eDP-1-4
[277.074837] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.010s)
[277.086142] Starting dynamic subtest: pipe-D-eDP-1-x
[279.252821] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.177s)
[279.255637] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.244s)
[280.485907] [055/131] (739s left) gem_mmap_gtt (basic-write-read-distinct)
[280.557055] Subtest basic-write-read-distinct: SKIP (0.000s)
[280.625089] [056/131] (739s left) gem_mmap_offset (oob-read)
[280.681635] Starting subtest: oob-read
[280.683939] Subtest oob-read: SUCCESS (0.000s)
[280.816869] [057/131] (739s left) kms_atomic (atomic-plane-damage)
[280.921168] Starting subtest: atomic-plane-damage
[280.968071] Starting dynamic subtest: pipe-A-eDP-1
[281.517506] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.552s)
[281.519869] Subtest atomic-plane-damage: SUCCESS (0.598s)
[282.432414] [058/131] (737s left) kms_atomic_transition (plane-all-transition-nonblocking)
[282.520656] Starting subtest: plane-all-transition-nonblocking
[282.522971] Starting dynamic subtest: pipe-A-eDP-1
[285.016065] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[285.321510] Starting dynamic subtest: pipe-B-eDP-1
[288.529101] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.217s)
[288.825635] Subtest plane-all-transition-nonblocking: SUCCESS (6.306s)
[289.679255] [059/131] (730s left) gem_exec_parallel (contexts)
[289.749149] Starting subtest: contexts
[289.751118] Starting dynamic subtest: rcs0
[292.712349] Dynamic subtest rcs0: SUCCESS (2.963s)
[292.724450] Starting dynamic subtest: bcs0
[296.177259] Dynamic subtest bcs0: SUCCESS (3.462s)
[296.188630] Starting dynamic subtest: vcs0
[300.329173] Dynamic subtest vcs0: SUCCESS (4.148s)
[300.341601] Starting dynamic subtest: vcs1
[304.394280] Dynamic subtest vcs1: SUCCESS (4.062s)
[304.405202] Starting dynamic subtest: vecs0
[308.428646] Dynamic subtest vecs0: SUCCESS (4.031s)
[308.440049] Starting dynamic subtest: ccs0
[312.051294] Dynamic subtest ccs0: SUCCESS (3.618s)
[312.053940] Subtest contexts: SUCCESS (22.304s)
[312.262071] [060/131] (707s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[312.382974] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[319.135068] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.755s)
[319.282654] [061/131] (700s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[319.390885] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[319.395050] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[319.497270] [062/131] (700s left) kms_atomic_interruptible (legacy-pageflip)
[319.588426] Starting subtest: legacy-pageflip
[319.590716] Starting dynamic subtest: pipe-A-eDP-1
[326.989644] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.401s)
[326.991946] Subtest legacy-pageflip: SUCCESS (7.402s)
[327.843898] [063/131] (692s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[327.964038] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[327.968387] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[328.116306] [064/131] (692s left) kms_psr (psr2-sprite-mmap-cpu)
[328.219009] Starting subtest: psr2-sprite-mmap-cpu
[328.221239] Starting dynamic subtest: eDP-1
[329.907722] Dynamic subtest eDP-1: SUCCESS (1.689s)
[329.910161] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.690s)
[330.805183] [065/131] (689s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[330.913352] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[337.985761] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.075s)
[338.197230] [066/131] (682s left) gem_exec_schedule (fairslice-all)
[338.272965] Starting subtest: fairslice-all
[340.324042] Subtest fairslice-all: SUCCESS (2.054s)
[341.976186] [067/131] (678s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[346.966664] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[346.969029] Starting dynamic subtest: pipe-A-eDP-1
[347.767790] Dynamic subtest pipe-A-eDP-1: SKIP (0.796s)
[347.778916] Starting dynamic subtest: pipe-B-eDP-1
[348.908036] Dynamic subtest pipe-B-eDP-1: SKIP (1.139s)
[348.912332] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.940s)
[350.118131] [068/131] (670s left) api_intel_allocator (two-level-inception)
[350.189974] Starting subtest: two-level-inception
[355.591904] Subtest two-level-inception: SUCCESS (5.401s)
[355.758769] [069/131] (664s left) i915_suspend (debugfs-reader)
[355.821584] Starting subtest: debugfs-reader
[373.739765] Subtest debugfs-reader: SUCCESS (8.091s)
[373.948006] [070/131] (646s left) kms_flip (plain-flip-fb-recreate-interruptible)
[374.069818] Starting subtest: plain-flip-fb-recreate-interruptible
[374.072151] Starting dynamic subtest: A-eDP1
[394.832934] Dynamic subtest A-eDP1: SUCCESS (20.605s)
[394.845980] Starting dynamic subtest: B-eDP1
[403.681338] Dynamic subtest B-eDP1: SUCCESS (8.835s)
[403.695734] Starting dynamic subtest: C-eDP1
[412.490270] Dynamic subtest C-eDP1: SUCCESS (8.804s)
[412.501535] Starting dynamic subtest: D-eDP1
[421.328313] Dynamic subtest D-eDP1: SUCCESS (8.834s)
[421.332625] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (47.088s)
[422.539365] [071/131] (598s left) gem_vm_create (invalid-destroy)
[422.602274] Starting subtest: invalid-destroy
[422.604707] Subtest invalid-destroy: SUCCESS (0.002s)
[422.750465] [072/131] (598s left) gem_ccs (ctrl-surf-copy-new-ctx)
[422.813263] Starting subtest: ctrl-surf-copy-new-ctx
[422.817761] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[422.952237] [073/131] (597s left) syncobj_basic (bad-destroy-pad)
[423.018190] Starting subtest: bad-destroy-pad
[423.020514] Subtest bad-destroy-pad: SUCCESS (0.000s)
[423.154040] [074/131] (597s left) kms_plane_multiple (2x-tiling-y)
[423.255760] Starting subtest: 2x-tiling-y
[423.259691] Subtest 2x-tiling-y: SKIP (0.000s)
[423.349891] [075/131] (597s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[423.442583] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[423.444597] Starting dynamic subtest: pipe-A-eDP-1
[423.463097] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[423.474243] Starting dynamic subtest: pipe-B-eDP-1
[423.491880] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[423.500230] Starting dynamic subtest: pipe-C-eDP-1
[423.517350] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[423.525653] Starting dynamic subtest: pipe-D-eDP-1
[423.543440] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[423.547006] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[425.691584] [076/131] (595s left) kms_pm_rpm (modeset-lpsp)
[427.823380] Starting subtest: modeset-lpsp
[429.295429] Subtest modeset-lpsp: SUCCESS (1.472s)
[429.409160] [077/131] (591s left) kms_setmode (basic)
[429.457334] Starting subtest: basic
[429.775053] Starting dynamic subtest: pipe-A-eDP-1
[431.858789] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.079s)
[432.204226] Starting dynamic subtest: pipe-B-eDP-1
[434.290112] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.089s)
[434.605581] Subtest basic: SUCCESS (5.146s)
[435.463517] [078/131] (585s left) kms_psr (fbc-psr-suspend)
[435.567623] Starting subtest: fbc-psr-suspend
[435.569924] Starting dynamic subtest: eDP-1
[435.573915] Dynamic subtest eDP-1: SKIP (0.000s)
[435.577952] Subtest fbc-psr-suspend: SKIP (0.002s)
[435.666974] [079/131] (585s left) gem_ringfill (engines-basic)
[441.712534] Starting subtest: engines-basic
[441.715059] Starting dynamic subtest: rcs0
[441.800978] Dynamic subtest rcs0: SUCCESS (0.088s)
[441.813537] Starting dynamic subtest: bcs0
[441.847556] Dynamic subtest bcs0: SUCCESS (0.045s)
[441.858656] Starting dynamic subtest: vcs0
[441.891559] Dynamic subtest vcs0: SUCCESS (0.042s)
[441.902957] Starting dynamic subtest: vcs1
[441.934518] Dynamic subtest vcs1: SUCCESS (0.041s)
[441.945133] Starting dynamic subtest: vecs0
[441.980702] Dynamic subtest vecs0: SUCCESS (0.044s)
[441.996493] Starting dynamic subtest: ccs0
[442.025782] Dynamic subtest ccs0: SUCCESS (0.043s)
[442.028192] Subtest engines-basic: SUCCESS (0.316s)
[442.130299] [080/131] (579s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[442.192426] Starting subtest: invalid-bsd2-flag-on-vebox
[442.194725] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[442.337211] [081/131] (578s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[442.449137] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[442.453150] Starting dynamic subtest: pipe-A-eDP-1
[442.474436] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.021s)
[442.484631] Starting dynamic subtest: pipe-B-eDP-1
[442.503915] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.020s)
[442.514043] Starting dynamic subtest: pipe-C-eDP-1
[442.532741] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.019s)
[442.542862] Starting dynamic subtest: pipe-D-eDP-1
[442.561602] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.020s)
[442.563637] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.086s)
[444.755356] [082/131] (576s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[444.862383] Starting subtest: extended-pageflip-modeset-hang-oldfb
[444.864244] Starting dynamic subtest: pipe-A
[452.765395] Dynamic subtest pipe-A: SUCCESS (7.899s)
[452.775414] Starting dynamic subtest: pipe-D
[461.349679] Dynamic subtest pipe-D: SUCCESS (8.579s)
[461.351938] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.483s)
[462.227950] [083/131] (559s left) gem_ppgtt (shrink-vs-evict-any)
[462.299533] Starting subtest: shrink-vs-evict-any
[467.721268] Subtest shrink-vs-evict-any: SUCCESS (5.420s)
[467.822773] [084/131] (553s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[467.891303] Starting subtest: etime-single-wait-for-submit-unsubmitted
[467.992767] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[468.099237] [085/131] (553s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[468.161145] Starting subtest: multi-wait-all-for-submit-signaled
[468.163389] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[468.285201] [086/131] (553s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[468.350963] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[468.457644] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.106s)
[468.561405] [087/131] (552s left) gem_exec_schedule (implicit-write-read)
[468.637065] Starting subtest: implicit-write-read
[468.639262] Starting dynamic subtest: rcs0
[468.819352] Dynamic subtest rcs0: SUCCESS (0.192s)
[468.834093] Starting dynamic subtest: vcs0
[468.956927] Dynamic subtest vcs0: SUCCESS (0.137s)
[468.971627] Starting dynamic subtest: vcs1
[469.061963] Dynamic subtest vcs1: SUCCESS (0.105s)
[469.075343] Starting dynamic subtest: vecs0
[469.178476] Dynamic subtest vecs0: SUCCESS (0.115s)
[469.187839] Starting dynamic subtest: ccs0
[469.304401] Dynamic subtest ccs0: SUCCESS (0.125s)
[469.307258] Subtest implicit-write-read: SUCCESS (0.679s)
[469.901296] [088/131] (551s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[470.019865] Starting subtest: fbcpsr-rgb565-draw-blt
[477.131758] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.119s)
[477.303251] [089/131] (544s left) gem_ctx_persistence (processes)
[477.377295] Starting subtest: processes
[478.795599] Subtest processes: SUCCESS (1.421s)
[478.902345] [090/131] (542s left) kms_cursor_crc (cursor-sliding-32x10)
[479.063114] Starting subtest: cursor-sliding-32x10
[479.107970] Subtest cursor-sliding-32x10: SKIP (0.039s)
[479.435869] [091/131] (542s left) kms_psr (fbc-pr-primary-render)
[479.544608] Starting subtest: fbc-pr-primary-render
[479.549086] Subtest fbc-pr-primary-render: SKIP (0.000s)
[479.644061] [092/131] (542s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[479.713558] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[479.808620] [093/131] (541s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[479.865692] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[479.867734] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[480.008569] [094/131] (541s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[480.115898] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[480.119369] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[480.233109] [095/131] (541s left) gem_exec_async (concurrent-writes)
[480.293469] Starting subtest: concurrent-writes
[480.295828] Starting dynamic subtest: rcs0
[480.308176] Dynamic subtest rcs0: SUCCESS (0.015s)
[480.320710] Starting dynamic subtest: bcs0
[480.322838] Dynamic subtest bcs0: SUCCESS (0.006s)
[480.339424] Starting dynamic subtest: vcs0
[480.341344] Dynamic subtest vcs0: SUCCESS (0.005s)
[480.353735] Starting dynamic subtest: vcs1
[480.355548] Dynamic subtest vcs1: SUCCESS (0.006s)
[480.365763] Starting dynamic subtest: vecs0
[480.367541] Dynamic subtest vecs0: SUCCESS (0.006s)
[480.376556] Starting dynamic subtest: ccs0
[480.378359] Dynamic subtest ccs0: SUCCESS (0.008s)
[480.380194] Subtest concurrent-writes: SUCCESS (0.051s)
[480.614781] [096/131] (541s left) gem_mmap_gtt (basic-small-bo-tiledx)
[480.669192] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[480.735849] [097/131] (541s left) dmabuf_sync_file (import-basic)
[480.778374] Starting subtest: import-basic
[480.780643] Subtest import-basic: SUCCESS (0.001s)
[480.862046] [098/131] (541s left) kms_feature_discovery (display)
[480.953885] Starting subtest: display
[480.955752] Subtest display: SUCCESS (0.000s)
[481.054512] [099/131] (540s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[481.164249] Starting subtest: x-tiled-64bpp-rotate-90
[481.183902] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[481.353964] [100/131] (540s left) perf_pmu (busy-no-semaphores)
[481.393103] Starting subtest: busy-no-semaphores
[481.394728] Starting dynamic subtest: rcs0
[481.960272] Dynamic subtest rcs0: SUCCESS (0.567s)
[481.974478] Starting dynamic subtest: bcs0
[482.510202] Dynamic subtest bcs0: SUCCESS (0.547s)
[482.523061] Starting dynamic subtest: vcs0
[483.060100] Dynamic subtest vcs0: SUCCESS (0.548s)
[483.073443] Starting dynamic subtest: vcs1
[484.269143] Dynamic subtest vcs1: SUCCESS (1.206s)
[484.282697] Starting dynamic subtest: vecs0
[486.257389] Dynamic subtest vecs0: SUCCESS (1.985s)
[486.271783] Starting dynamic subtest: ccs0
[488.247380] Dynamic subtest ccs0: SUCCESS (1.987s)
[488.249976] Subtest busy-no-semaphores: SUCCESS (6.857s)
[488.412040] [101/131] (533s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[488.531297] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[488.535255] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[488.660340] [102/131] (533s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[488.789597] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[488.796427] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.004s)
[488.968748] [103/131] (533s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[489.351363] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[490.122692] [104/131] (532s left) kms_getfb (getfb-reject-nv12)
[490.228581] Starting subtest: getfb-reject-nv12
[490.230959] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[490.364491] [105/131] (531s left) i915_drm_fdinfo (context-close-stress)
[490.405332] Starting subtest: context-close-stress
[501.152248] Subtest context-close-stress: SUCCESS (10.746s)
[501.251935] [106/131] (521s left) perf_pmu (module-unload)
[501.486629] Starting subtest: module-unload
[511.309100] Subtest module-unload: FAIL (9.710s)
[511.343740] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[511.344423] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[511.347779] [107/131] (510s left) kms_chamelium_edid (dp-edid-read)
[513.127784] Subtest dp-edid-read: SKIP (0.000s)
[513.960170] [FACT kms_chamelium_edid (dp-edid-read)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[513.961292] [FACT kms_chamelium_edid (dp-edid-read)] new: kernel.kmod_is_loaded.i915: true
[513.965155] [108/131] (508s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[518.952535] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[518.954813] Starting dynamic subtest: pipe-A-eDP-1
[519.754535] Dynamic subtest pipe-A-eDP-1: SKIP (0.798s)
[519.763564] Starting dynamic subtest: pipe-B-eDP-1
[520.884391] Dynamic subtest pipe-B-eDP-1: SKIP (1.128s)
[520.889324] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.930s)
[522.103324] [109/131] (500s left) kms_vblank (query-busy)
[522.210369] Starting subtest: query-busy
[522.212598] Starting dynamic subtest: pipe-A-eDP-1
[524.083563] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.873s)
[524.098042] Starting dynamic subtest: pipe-D-eDP-1
[526.641313] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.553s)
[526.643249] Subtest query-busy: SUCCESS (4.430s)
[527.460132] [110/131] (495s left) syncobj_timeline (wait-for-submit-complex)
[527.540479] Starting subtest: wait-for-submit-complex
[527.601400] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[527.700355] [111/131] (494s left) perf (invalid-create-userspace-config)
[527.849159] Starting subtest: invalid-create-userspace-config
[527.851467] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[527.888690] [112/131] (494s left) gem_exec_basic (basic)
[527.961252] Starting subtest: basic
[527.963496] Starting dynamic subtest: rcs0-smem
[527.965739] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[527.976492] Starting dynamic subtest: bcs0-smem
[527.978321] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[527.986243] Starting dynamic subtest: vcs0-smem
[527.988087] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[527.995843] Starting dynamic subtest: vcs1-smem
[527.997711] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[528.005468] Starting dynamic subtest: vecs0-smem
[528.007357] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[528.015167] Starting dynamic subtest: ccs0-smem
[528.017048] Dynamic subtest ccs0-smem: SUCCESS (0.001s)
[528.018949] Subtest basic: SUCCESS (0.012s)
[529.771080] [113/131] (492s left) kms_chamelium_color (ctm-0-25)
[529.868836] Subtest ctm-0-25: SKIP (0.000s)
[529.913976] [114/131] (492s left) gem_userptr_blits (process-exit-busy)
[529.988990] Starting subtest: process-exit-busy
[530.002547] Subtest process-exit-busy: SUCCESS (0.013s)
[530.171251] [115/131] (492s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[530.241168] Starting subtest: y-tiled-ccs-to-y-tiled
[530.245302] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[530.445703] [116/131] (492s left) gen7_exec_parse (bitmasks)
[530.496743] Subtest bitmasks: SKIP (0.000s)
[530.562579] [117/131] (492s left) gem_eio (wait-wedge-10ms)
[530.751659] Starting subtest: wait-wedge-10ms
[531.738058] Subtest wait-wedge-10ms: SUCCESS (0.986s)
[531.916096] [118/131] (490s left) kms_content_protection (atomic)
[532.046034] Starting subtest: atomic
[532.050587] Subtest atomic: SKIP (0.000s)
[532.191190] [119/131] (490s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[532.286500] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[532.288770] Starting dynamic subtest: pipe-A-default-mode
[532.581984] Dynamic subtest pipe-A-default-mode: SKIP (0.293s)
[532.586527] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.294s)
[533.398261] [120/131] (489s left) gem_exec_params (invalid-flag)
[533.476491] Starting subtest: invalid-flag
[533.479359] Subtest invalid-flag: SUCCESS (0.000s)
[533.615495] [121/131] (489s left) kms_getfb (getfb-addfb-different-handles)
[533.714387] Starting subtest: getfb-addfb-different-handles
[533.716503] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[533.831086] [122/131] (489s left) gem_exec_flush (basic-uc-ro-default)
[533.891607] Starting subtest: basic-uc-ro-default
[539.342146] Subtest basic-uc-ro-default: SUCCESS (5.450s)
[539.561083] [123/131] (483s left) gem_sync (basic-each)
[539.645304] Starting subtest: basic-each
[542.125757] Subtest basic-each: SUCCESS (2.490s)
[542.339376] [124/131] (480s left) gem_exec_balancer (full)
[542.412789] Starting subtest: full
[547.629682] Subtest full: SUCCESS (5.215s)
[547.975277] [125/131] (475s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[548.101120] Starting subtest: y-tiled-8bpp-rotate-180
[548.105602] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[548.219085] [126/131] (474s left) sw_sync (sync_merge_invalid)
[548.257914] Starting subtest: sync_merge_invalid
[548.279298] Subtest sync_merge_invalid: SUCCESS (0.025s)
[548.363291] [127/131] (474s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[548.463892] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[552.953886] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.493s)
[554.117051] [128/131] (469s left) gem_lmem_swapping (parallel-random-verify-ccs)
[554.165106] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[554.214243] [129/131] (469s left) kms_lease (lease-unleased-connector)
[554.309466] Starting subtest: lease-unleased-connector
[554.311660] Starting dynamic subtest: pipe-A-eDP-1
[554.316467] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[554.330662] Starting dynamic subtest: pipe-B-eDP-1
[554.333080] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[554.342582] Starting dynamic subtest: pipe-C-eDP-1
[554.345024] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[554.353782] Starting dynamic subtest: pipe-D-eDP-1
[554.355619] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[554.357569] Subtest lease-unleased-connector: SUCCESS (0.039s)
[554.473428] [130/131] (468s left) perf (enable-disable)
[554.571368] Starting subtest: enable-disable
[554.573605] Starting dynamic subtest: 0-rcs0
[556.825066] Dynamic subtest 0-rcs0: SUCCESS (2.251s)
[556.835938] Starting dynamic subtest: 1-vcs1
[561.340027] Dynamic subtest 1-vcs1: SUCCESS (4.513s)
[561.342383] Subtest enable-disable: SUCCESS (6.765s)
[561.388790] [131/131] (461s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[561.555396] Starting subtest: cursor-rapid-movement-32x32
[561.603455] Subtest cursor-rapid-movement-32x32: SKIP (0.043s)
[561.932637] 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.