[58.117112] Initializing watchdogs
[58.117158]   /dev/watchdog0
[58.134246] [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.138760] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.141288] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.144283] [001/131] (960s left) fbdev (unaligned-read)
[58.209664] Starting subtest: unaligned-read
[58.225577] Subtest unaligned-read: SUCCESS (0.016s)
[58.302428] [002/131] (960s left) gem_ctx_isolation (dirty-switch)
[58.367655] Starting subtest: dirty-switch
[58.369854] Starting dynamic subtest: rcs0
[58.622823] Dynamic subtest rcs0: SUCCESS (0.257s)
[58.631911] Starting dynamic subtest: bcs0
[58.905034] Dynamic subtest bcs0: SUCCESS (0.284s)
[58.914980] Starting dynamic subtest: vcs0
[59.162255] Dynamic subtest vcs0: SUCCESS (0.259s)
[59.171616] Starting dynamic subtest: vcs1
[61.100436] Dynamic subtest vcs1: SUCCESS (1.950s)
[61.109269] Starting dynamic subtest: vecs0
[63.164574] Dynamic subtest vecs0: SUCCESS (2.073s)
[63.174088] Starting dynamic subtest: ccs0
[65.134671] Dynamic subtest ccs0: SUCCESS (1.977s)
[65.137142] Subtest dirty-switch: SUCCESS (6.802s)
[65.416113] [003/131] (953s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[65.522349] Starting subtest: psr-modesetfrombusy
[71.722562] Subtest psr-modesetfrombusy: SUCCESS (6.218s)
[71.892336] [004/131] (946s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[71.987496] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[71.989464] Starting dynamic subtest: pipe-A-default-mode
[74.765816] Dynamic subtest pipe-A-default-mode: SUCCESS (2.781s)
[74.768267] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.782s)
[75.635516] [005/131] (943s left) gem_exec_params (sol-reset-invalid)
[75.701359] Starting subtest: sol-reset-invalid
[75.705967] Subtest sol-reset-invalid: SUCCESS (0.004s)
[76.997154] [006/131] (941s left) gem_spin_batch (resubmit-new)
[77.064263] Starting subtest: resubmit-new
[77.066681] Starting dynamic subtest: rcs0
[77.072439] Dynamic subtest rcs0: SUCCESS (0.008s)
[77.082225] Starting dynamic subtest: bcs0
[77.084078] Dynamic subtest bcs0: SUCCESS (0.004s)
[77.091604] Starting dynamic subtest: vcs0
[77.094010] Dynamic subtest vcs0: SUCCESS (0.004s)
[77.101140] Starting dynamic subtest: vcs1
[77.103283] Dynamic subtest vcs1: SUCCESS (0.004s)
[77.110551] Starting dynamic subtest: vecs0
[77.112741] Dynamic subtest vecs0: SUCCESS (0.004s)
[77.120053] Starting dynamic subtest: ccs0
[77.122209] Dynamic subtest ccs0: SUCCESS (0.004s)
[77.124689] Subtest resubmit-new: SUCCESS (0.028s)
[78.993740] [007/131] (939s left) gem_exec_schedule (preempt-user)
[79.071973] Starting subtest: preempt-user
[79.074259] Starting dynamic subtest: rcs0
[79.109111] Dynamic subtest rcs0: SUCCESS (0.044s)
[79.117229] Starting dynamic subtest: bcs0
[79.138428] Dynamic subtest bcs0: SUCCESS (0.029s)
[79.148633] Starting dynamic subtest: vcs0
[79.160409] Dynamic subtest vcs0: SUCCESS (0.022s)
[79.167665] Starting dynamic subtest: vcs1
[79.183291] Dynamic subtest vcs1: SUCCESS (0.023s)
[79.190971] Starting dynamic subtest: vecs0
[79.207435] Dynamic subtest vecs0: SUCCESS (0.024s)
[79.214836] Starting dynamic subtest: ccs0
[79.234890] Dynamic subtest ccs0: SUCCESS (0.027s)
[79.237303] Subtest preempt-user: SUCCESS (0.170s)
[81.043524] [008/131] (937s left) sysfs_heartbeat_interval (nopreempt)
[81.120922] Starting subtest: nopreempt
[81.123307] Starting dynamic subtest: vecs0
[90.906732] Dynamic subtest vecs0: SUCCESS (9.783s)
[90.918053] Starting dynamic subtest: ccs0
[94.956583] Dynamic subtest ccs0: SUCCESS (4.049s)
[94.967148] Starting dynamic subtest: vcs0
[104.917730] Dynamic subtest vcs0: SUCCESS (9.959s)
[104.927459] Starting dynamic subtest: bcs0
[108.899231] Dynamic subtest bcs0: SUCCESS (3.980s)
[108.909764] Starting dynamic subtest: rcs0
[112.999273] Dynamic subtest rcs0: SUCCESS (4.100s)
[113.009873] Starting dynamic subtest: vcs1
[122.910412] Dynamic subtest vcs1: SUCCESS (9.910s)
[122.913499] Subtest nopreempt: SUCCESS (41.787s)
[123.011216] [009/131] (895s left) kms_invalid_mode (zero-hdisplay)
[123.107946] Starting subtest: zero-hdisplay
[123.110264] Starting dynamic subtest: pipe-A-eDP-1
[123.396271] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[123.405182] Starting dynamic subtest: pipe-B-eDP-1
[123.407116] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[123.414320] Starting dynamic subtest: pipe-C-eDP-1
[123.416214] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[123.423967] Starting dynamic subtest: pipe-D-eDP-1
[123.426249] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[123.428414] Subtest zero-hdisplay: SUCCESS (0.295s)
[124.271322] [010/131] (894s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[124.376838] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[124.381510] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[124.509800] [011/131] (894s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[124.632846] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[125.501391] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.870s)
[125.797085] [012/131] (893s left) kms_getfb (getfb-handle-zero)
[125.898448] Starting subtest: getfb-handle-zero
[125.900699] Subtest getfb-handle-zero: SUCCESS (0.000s)
[126.021651] [013/131] (893s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[126.089302] Starting subtest: yf-tiled-ccs-to-yf-tiled
[126.093935] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[126.290251] [014/131] (892s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[126.378945] Starting subtest: multiplane-rotation-cropping-bottom
[133.548216] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.171s)
[133.715661] [015/131] (885s left) gem_eio (wait-1us)
[133.952705] Starting subtest: wait-1us
[134.311354] Subtest wait-1us: SUCCESS (0.359s)
[134.516794] [016/131] (884s left) syncobj_wait (multi-wait-for-submit-signaled)
[134.561346] Starting subtest: multi-wait-for-submit-signaled
[134.563426] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[134.690243] [017/131] (884s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[134.787471] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[134.789511] Starting dynamic subtest: pipe-A-default-mode
[135.076514] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[135.081163] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.287s)
[135.897122] [018/131] (883s left) kms_plane_alpha_blend (alpha-7efc)
[135.996195] Starting subtest: alpha-7efc
[136.319579] Starting dynamic subtest: pipe-A-eDP-1
[136.923511] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.603s)
[136.969022] Starting dynamic subtest: pipe-D-eDP-1
[138.208846] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.246s)
[138.211136] Subtest alpha-7efc: SUCCESS (2.215s)
[139.077541] [019/131] (880s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[139.189434] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[139.193727] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[139.321442] [020/131] (880s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[139.417783] Starting subtest: fbc-psr2-primary-mmap-cpu
[139.420095] Starting dynamic subtest: eDP-1
[139.424340] Dynamic subtest eDP-1: SKIP (0.000s)
[139.428632] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[139.514743] [021/131] (879s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[139.597926] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[139.610156] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[139.756177] [022/131] (879s left) gem_exec_whisper (basic-normal-all)
[139.821260] Starting subtest: basic-normal-all
[142.280342] Subtest basic-normal-all: SUCCESS (2.459s)
[142.486322] [023/131] (877s left) kms_big_fb (linear-16bpp-rotate-180)
[142.603468] Starting subtest: linear-16bpp-rotate-180
[143.961263] Subtest linear-16bpp-rotate-180: SUCCESS (1.357s)
[144.129261] [024/131] (875s left) kms_frontbuffer_tracking (psr-slowdraw)
[144.237143] Starting subtest: psr-slowdraw
[169.507975] Subtest psr-slowdraw: SUCCESS (25.270s)
[169.682148] [025/131] (849s left) vgem_basic (dmabuf-fence)
[169.787693] Starting subtest: dmabuf-fence
[169.790053] Subtest dmabuf-fence: SUCCESS (0.001s)
[169.864255] [FACT vgem_basic (dmabuf-fence)] new: kernel.kmod_is_loaded.vgem: true
[169.868218] [026/131] (849s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[169.935338] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[169.937740] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[170.068056] [027/131] (849s left) kms_psr (pr-primary-render)
[170.164865] Starting subtest: pr-primary-render
[170.168851] Subtest pr-primary-render: SKIP (0.000s)
[170.260618] [028/131] (849s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[170.365935] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[170.367807] Starting dynamic subtest: pipe-A-eDP-1
[170.386307] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[170.395689] Starting dynamic subtest: pipe-B-eDP-1
[170.413211] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[170.420623] Starting dynamic subtest: pipe-C-eDP-1
[170.438607] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[170.446165] Starting dynamic subtest: pipe-D-eDP-1
[170.464095] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[170.467653] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[172.572488] [029/131] (847s left) core_auth (getclient-master-drop)
[172.612293] Starting subtest: getclient-master-drop
[172.638479] Subtest getclient-master-drop: SUCCESS (0.026s)
[172.775262] [030/131] (847s left) kms_plane_scaling (planes-downscale-factor-0-5)
[172.874317] Starting subtest: planes-downscale-factor-0-5
[172.876589] Starting dynamic subtest: pipe-A
[172.897549] Dynamic subtest pipe-A: SKIP (0.019s)
[172.907105] Starting dynamic subtest: pipe-B
[172.931863] Dynamic subtest pipe-B: SKIP (0.036s)
[172.940702] Starting dynamic subtest: pipe-C
[172.967984] Dynamic subtest pipe-C: SKIP (0.035s)
[172.976673] Starting dynamic subtest: pipe-D
[173.000863] Dynamic subtest pipe-D: SKIP (0.032s)
[173.004428] Subtest planes-downscale-factor-0-5: SKIP (0.125s)
[173.120151] [031/131] (846s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[173.219975] Starting subtest: fb-8bpc-vs-panel-8bpc
[173.222284] Starting dynamic subtest: pipe-A-eDP-1
[174.639076] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.416s)
[174.640917] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.418s)
[175.533670] [032/131] (844s left) gem_partial_pwrite_pread (reads-snoop)
[175.594459] Subtest reads-snoop: SKIP (0.000s)
[175.670786] [033/131] (844s left) gem_eio (in-flight-contexts-immediate)
[175.914854] Starting subtest: in-flight-contexts-immediate
[189.934611] Subtest in-flight-contexts-immediate: SUCCESS (14.019s)
[190.141805] [034/131] (829s left) gen3_render_mixed_blits
[190.276850] [035/131] (829s left) kms_pm_rpm (pm-tiling)
[192.018354] Starting subtest: pm-tiling
[192.023142] Subtest pm-tiling: SKIP (0.000s)
[192.570857] [036/131] (827s left) i915_module_load (reload-no-display)
[192.610937] Starting subtest: reload-no-display
[198.891324] Subtest reload-no-display: SUCCESS (6.278s)
[198.922504] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[198.923250] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[198.925959] [037/131] (821s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[200.550946] Starting subtest: plane-upscale-20x20-with-rotation
[200.553184] Starting dynamic subtest: pipe-A
[200.793506] Dynamic subtest pipe-A: SUCCESS (0.245s)
[200.801442] Starting dynamic subtest: pipe-B
[202.134429] Dynamic subtest pipe-B: SUCCESS (1.340s)
[202.142402] Starting dynamic subtest: pipe-C
[203.448224] Dynamic subtest pipe-C: SUCCESS (1.311s)
[203.460966] Starting dynamic subtest: pipe-D
[204.769411] Dynamic subtest pipe-D: SUCCESS (1.319s)
[204.771667] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.222s)
[205.925266] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[205.926718] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: kernel.kmod_is_loaded.i915: true
[205.930140] [038/131] (814s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[206.060740] Starting subtest: 4-tiled-8bpp-rotate-180
[207.252720] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.190s)
[207.416058] [039/131] (812s left) kms_rotation_crc (primary-rotation-90)
[207.517176] Starting subtest: primary-rotation-90
[207.520642] Subtest primary-rotation-90: SKIP (0.001s)
[207.611401] [040/131] (812s left) kms_cursor_crc (cursor-onscreen-32x10)
[207.761628] Starting subtest: cursor-onscreen-32x10
[207.807557] Subtest cursor-onscreen-32x10: SKIP (0.042s)
[208.108326] [041/131] (812s left) kms_vblank (wait-idle)
[208.204878] Starting subtest: wait-idle
[208.206718] Starting dynamic subtest: pipe-A-eDP-1
[209.964875] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.758s)
[209.978236] Starting dynamic subtest: pipe-D-eDP-1
[212.404732] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.438s)
[212.407022] Subtest wait-idle: SUCCESS (4.200s)
[213.256823] [042/131] (806s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[213.366048] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[213.370699] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[213.522226] [043/131] (806s left) kms_flip (2x-flip-vs-dpms)
[213.617094] Starting subtest: 2x-flip-vs-dpms
[213.628760] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[213.734485] [044/131] (806s left) gem_exec_fence (syncobj-channel)
[214.159219] Starting subtest: syncobj-channel
[215.177753] Subtest syncobj-channel: SUCCESS (1.015s)
[216.026036] [045/131] (804s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[216.133072] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[216.135390] Starting dynamic subtest: pipe-A-eDP-1
[216.144327] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[216.157037] Starting dynamic subtest: pipe-B-eDP-1
[216.166777] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[216.175690] Starting dynamic subtest: pipe-C-eDP-1
[216.184083] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[216.191873] Starting dynamic subtest: pipe-D-eDP-1
[216.200182] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[216.204175] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[218.423730] [046/131] (802s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[218.528509] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[225.892548] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.366s)
[226.075921] [047/131] (794s left) kms_flip (busy-flip)
[226.168976] Starting subtest: busy-flip
[226.171298] Starting dynamic subtest: A-eDP1
[227.834778] Dynamic subtest A-eDP1: SUCCESS (1.667s)
[227.846576] Starting dynamic subtest: B-eDP1
[229.575751] Dynamic subtest B-eDP1: SUCCESS (1.739s)
[229.588600] Starting dynamic subtest: C-eDP1
[231.293915] Dynamic subtest C-eDP1: SUCCESS (1.716s)
[231.305872] Starting dynamic subtest: D-eDP1
[232.926161] Dynamic subtest D-eDP1: SUCCESS (1.629s)
[232.932829] Subtest busy-flip: SUCCESS (6.767s)
[234.103917] [048/131] (786s left) prime_mmap (test_aperture_limit)
[234.167626] Starting subtest: test_aperture_limit
[234.169638] Starting dynamic subtest: test_aperture_limit-smem
[234.177073] Dynamic subtest test_aperture_limit-smem: SKIP (0.008s)
[234.181293] Subtest test_aperture_limit: SKIP (0.009s)
[234.310765] [049/131] (786s left) i915_pm_rpm (sysfs-read)
[236.137630] Starting subtest: sysfs-read
[237.290063] Subtest sysfs-read: SUCCESS (1.155s)
[237.692621] [050/131] (782s left) kms_big_fb (yf-tiled-addfb)
[237.796412] Starting subtest: yf-tiled-addfb
[237.800769] Subtest yf-tiled-addfb: SKIP (0.000s)
[237.914469] [051/131] (782s left) sriov_basic (enable-vfs-autoprobe-on)
[237.971838] Starting subtest: enable-vfs-autoprobe-on
[237.973712] Starting dynamic subtest: numvfs-1
[238.050489] Dynamic subtest numvfs-1: FAIL (0.028s)
[238.058499] Starting dynamic subtest: numvfs-2
[238.130596] Dynamic subtest numvfs-2: FAIL (0.004s)
[238.140448] Starting dynamic subtest: numvfs-3
[238.212662] Dynamic subtest numvfs-3: FAIL (0.003s)
[238.222181] Starting dynamic subtest: numvfs-4
[238.293968] Dynamic subtest numvfs-4: FAIL (0.004s)
[238.304235] Starting dynamic subtest: numvfs-5
[238.380100] Dynamic subtest numvfs-5: FAIL (0.004s)
[238.389290] Starting dynamic subtest: numvfs-6
[238.461581] Dynamic subtest numvfs-6: FAIL (0.004s)
[238.472086] Starting dynamic subtest: numvfs-7
[238.544740] Dynamic subtest numvfs-7: FAIL (0.004s)
[238.553087] Starting dynamic subtest: numvfs-random
[238.619853] Dynamic subtest numvfs-random: FAIL (0.004s)
[238.627323] Starting dynamic subtest: numvfs-all
[238.686203] Dynamic subtest numvfs-all: FAIL (0.506s)
[238.688065] Subtest enable-vfs-autoprobe-on: FAIL (0.567s)
[238.723694] [052/131] (781s left) gem_exec_whisper (basic-queues)
[238.790585] Starting subtest: basic-queues
[244.584820] Subtest basic-queues: SUCCESS (5.790s)
[244.788127] [053/131] (775s left) kms_flip (2x-dpms-vs-vblank-race)
[244.878798] Starting subtest: 2x-dpms-vs-vblank-race
[244.887664] Subtest 2x-dpms-vs-vblank-race: SKIP (0.010s)
[244.991805] [054/131] (775s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[245.092804] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[245.476877] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[248.228787] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.750s)
[248.240220] Starting dynamic subtest: pipe-A-eDP-1-4
[250.404859] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.174s)
[250.416331] Starting dynamic subtest: pipe-A-eDP-1-4
[252.414468] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[252.425771] Starting dynamic subtest: pipe-A-eDP-1-x
[254.604409] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.189s)
[254.969522] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[257.755217] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.790s)
[257.766730] Starting dynamic subtest: pipe-B-eDP-1-4
[259.941535] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.186s)
[259.952700] Starting dynamic subtest: pipe-B-eDP-1-4
[261.951984] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[261.963817] Starting dynamic subtest: pipe-B-eDP-1-x
[264.141285] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.189s)
[264.510571] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[267.278755] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.777s)
[267.293631] Starting dynamic subtest: pipe-C-eDP-1-4
[269.476776] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.196s)
[269.488627] Starting dynamic subtest: pipe-C-eDP-1-4
[271.487070] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.008s)
[271.496859] Starting dynamic subtest: pipe-C-eDP-1-x
[273.665704] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[274.032529] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[276.807309] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.780s)
[276.817795] Starting dynamic subtest: pipe-D-eDP-1-4
[278.993497] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.185s)
[279.004696] Starting dynamic subtest: pipe-D-eDP-1-4
[281.003430] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[281.014806] Starting dynamic subtest: pipe-D-eDP-1-x
[283.182128] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[283.184479] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.089s)
[284.415189] [055/131] (736s left) gem_mmap_gtt (basic-write-read-distinct)
[284.465276] Subtest basic-write-read-distinct: SKIP (0.000s)
[284.521615] [056/131] (736s left) gem_mmap_offset (oob-read)
[284.573594] Starting subtest: oob-read
[284.575602] Subtest oob-read: SUCCESS (0.000s)
[284.711109] [057/131] (736s left) kms_atomic (atomic-plane-damage)
[284.812386] Starting subtest: atomic-plane-damage
[284.860832] Starting dynamic subtest: pipe-A-eDP-1
[285.391207] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.529s)
[285.393086] Subtest atomic-plane-damage: SUCCESS (0.578s)
[286.240449] [058/131] (734s left) kms_atomic_transition (plane-all-transition-nonblocking)
[286.335049] Starting subtest: plane-all-transition-nonblocking
[286.337328] Starting dynamic subtest: pipe-A-eDP-1
[288.828585] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[289.146079] Starting dynamic subtest: pipe-B-eDP-1
[292.361041] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.225s)
[292.662780] Subtest plane-all-transition-nonblocking: SUCCESS (6.327s)
[293.508087] [059/131] (727s left) gem_exec_parallel (contexts)
[293.587322] Starting subtest: contexts
[293.589224] Starting dynamic subtest: rcs0
[296.387365] Dynamic subtest rcs0: SUCCESS (2.801s)
[296.402625] Starting dynamic subtest: bcs0
[299.878840] Dynamic subtest bcs0: SUCCESS (3.488s)
[299.889285] Starting dynamic subtest: vcs0
[304.281029] Dynamic subtest vcs0: SUCCESS (4.398s)
[304.292779] Starting dynamic subtest: vcs1
[308.360491] Dynamic subtest vcs1: SUCCESS (4.076s)
[308.370291] Starting dynamic subtest: vecs0
[312.299098] Dynamic subtest vecs0: SUCCESS (3.935s)
[312.310547] Starting dynamic subtest: ccs0
[315.842427] Dynamic subtest ccs0: SUCCESS (3.539s)
[315.845168] Subtest contexts: SUCCESS (22.256s)
[316.054865] [060/131] (704s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[316.173196] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[323.005231] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.833s)
[323.178152] [061/131] (697s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[323.307205] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[323.311171] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[323.411813] [062/131] (697s left) kms_atomic_interruptible (legacy-pageflip)
[323.508346] Starting subtest: legacy-pageflip
[323.510679] Starting dynamic subtest: pipe-A-eDP-1
[330.923933] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.414s)
[330.926214] Subtest legacy-pageflip: SUCCESS (7.415s)
[331.776030] [063/131] (689s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[331.883732] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[331.888241] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[332.019499] [064/131] (689s left) kms_psr (psr2-sprite-mmap-cpu)
[332.124080] Starting subtest: psr2-sprite-mmap-cpu
[332.126354] Starting dynamic subtest: eDP-1
[333.865985] Dynamic subtest eDP-1: SUCCESS (1.741s)
[333.868377] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.742s)
[334.752912] [065/131] (686s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[334.862521] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[341.943360] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.083s)
[342.134944] [066/131] (679s left) gem_exec_schedule (fairslice-all)
[342.209074] Starting subtest: fairslice-all
[344.266297] Subtest fairslice-all: SUCCESS (2.055s)
[346.017283] [067/131] (675s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[351.049699] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[351.052086] Starting dynamic subtest: pipe-A-eDP-1
[351.823837] Dynamic subtest pipe-A-eDP-1: SKIP (0.769s)
[351.833244] Starting dynamic subtest: pipe-B-eDP-1
[352.954508] Dynamic subtest pipe-B-eDP-1: SKIP (1.129s)
[352.959092] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.903s)
[354.161354] [068/131] (667s left) api_intel_allocator (two-level-inception)
[354.226785] Starting subtest: two-level-inception
[359.623720] Subtest two-level-inception: SUCCESS (5.398s)
[359.778349] [069/131] (661s left) i915_suspend (debugfs-reader)
[359.847671] Starting subtest: debugfs-reader
[378.058656] Subtest debugfs-reader: SUCCESS (8.760s)
[378.229914] [070/131] (643s left) kms_flip (plain-flip-fb-recreate-interruptible)
[378.332191] Starting subtest: plain-flip-fb-recreate-interruptible
[378.334836] Starting dynamic subtest: A-eDP1
[398.925629] Dynamic subtest A-eDP1: SUCCESS (20.853s)
[398.938439] Starting dynamic subtest: B-eDP1
[407.824967] Dynamic subtest B-eDP1: SUCCESS (8.912s)
[407.838787] Starting dynamic subtest: C-eDP1
[416.629365] Dynamic subtest C-eDP1: SUCCESS (8.798s)
[416.638767] Starting dynamic subtest: D-eDP1
[425.458107] Dynamic subtest D-eDP1: SUCCESS (8.827s)
[425.463417] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (47.402s)
[426.659911] [071/131] (594s left) gem_vm_create (invalid-destroy)
[426.725287] Starting subtest: invalid-destroy
[426.728546] Subtest invalid-destroy: SUCCESS (0.002s)
[426.867860] [072/131] (594s left) gem_ccs (ctrl-surf-copy-new-ctx)
[426.947297] Starting subtest: ctrl-surf-copy-new-ctx
[426.953436] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[427.087605] [073/131] (594s left) syncobj_basic (bad-destroy-pad)
[427.147744] Starting subtest: bad-destroy-pad
[427.150432] Subtest bad-destroy-pad: SUCCESS (0.000s)
[427.261554] [074/131] (594s left) kms_plane_multiple (2x-tiling-y)
[427.359235] Starting subtest: 2x-tiling-y
[427.365268] Subtest 2x-tiling-y: SKIP (0.000s)
[427.458368] [075/131] (594s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[427.558163] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[427.560911] Starting dynamic subtest: pipe-A-eDP-1
[427.586576] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[427.597129] Starting dynamic subtest: pipe-B-eDP-1
[427.623129] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[427.633197] Starting dynamic subtest: pipe-C-eDP-1
[427.657908] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[427.667382] Starting dynamic subtest: pipe-D-eDP-1
[427.693277] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[427.698078] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[429.732074] [076/131] (592s left) kms_pm_rpm (modeset-lpsp)
[431.560972] Starting subtest: modeset-lpsp
[433.022888] Subtest modeset-lpsp: SUCCESS (1.463s)
[433.123770] [077/131] (588s left) kms_setmode (basic)
[433.192817] Starting subtest: basic
[433.514374] Starting dynamic subtest: pipe-A-eDP-1
[435.586324] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.068s)
[435.926813] Starting dynamic subtest: pipe-B-eDP-1
[438.012098] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.090s)
[438.329264] Subtest basic: SUCCESS (5.137s)
[439.187219] [078/131] (582s left) kms_psr (fbc-psr-suspend)
[439.277159] Starting subtest: fbc-psr-suspend
[439.279792] Starting dynamic subtest: eDP-1
[439.284684] Dynamic subtest eDP-1: SKIP (0.000s)
[439.289279] Subtest fbc-psr-suspend: SKIP (0.001s)
[439.373687] [079/131] (582s left) gem_ringfill (engines-basic)
[445.401938] Starting subtest: engines-basic
[445.404862] Starting dynamic subtest: rcs0
[445.466046] Dynamic subtest rcs0: SUCCESS (0.065s)
[445.478077] Starting dynamic subtest: bcs0
[445.513039] Dynamic subtest bcs0: SUCCESS (0.045s)
[445.523631] Starting dynamic subtest: vcs0
[445.555982] Dynamic subtest vcs0: SUCCESS (0.041s)
[445.566360] Starting dynamic subtest: vcs1
[445.600983] Dynamic subtest vcs1: SUCCESS (0.043s)
[445.611296] Starting dynamic subtest: vecs0
[445.645172] Dynamic subtest vecs0: SUCCESS (0.043s)
[445.656361] Starting dynamic subtest: ccs0
[445.690969] Dynamic subtest ccs0: SUCCESS (0.044s)
[445.693528] Subtest engines-basic: SUCCESS (0.291s)
[445.795212] [080/131] (576s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[445.857613] Starting subtest: invalid-bsd2-flag-on-vebox
[445.860376] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[445.978938] [081/131] (576s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[446.084059] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[446.086064] Starting dynamic subtest: pipe-A-eDP-1
[446.109082] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.021s)
[446.120081] Starting dynamic subtest: pipe-B-eDP-1
[446.143270] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.020s)
[446.153287] Starting dynamic subtest: pipe-C-eDP-1
[446.176536] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.020s)
[446.186153] Starting dynamic subtest: pipe-D-eDP-1
[446.205914] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.019s)
[446.208016] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.085s)
[448.365888] [082/131] (573s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[448.472055] Starting subtest: extended-pageflip-modeset-hang-oldfb
[448.474119] Starting dynamic subtest: pipe-A
[456.367251] Dynamic subtest pipe-A: SUCCESS (7.898s)
[456.377443] Starting dynamic subtest: pipe-D
[464.954251] Dynamic subtest pipe-D: SUCCESS (8.587s)
[464.956936] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.489s)
[465.820718] [083/131] (556s left) gem_ppgtt (shrink-vs-evict-any)
[465.882247] Starting subtest: shrink-vs-evict-any
[471.351939] Subtest shrink-vs-evict-any: SUCCESS (5.470s)
[471.474847] [084/131] (550s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[471.546226] Starting subtest: etime-single-wait-for-submit-unsubmitted
[471.648933] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[471.759012] [085/131] (550s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[471.813805] Starting subtest: multi-wait-all-for-submit-signaled
[471.816401] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[471.951892] [086/131] (550s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[472.008207] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[472.114384] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.106s)
[472.224317] [087/131] (550s left) gem_exec_schedule (implicit-write-read)
[472.305141] Starting subtest: implicit-write-read
[472.307749] Starting dynamic subtest: rcs0
[472.511988] Dynamic subtest rcs0: SUCCESS (0.211s)
[472.526788] Starting dynamic subtest: vcs0
[472.642620] Dynamic subtest vcs0: SUCCESS (0.131s)
[472.655621] Starting dynamic subtest: vcs1
[472.750545] Dynamic subtest vcs1: SUCCESS (0.107s)
[472.763457] Starting dynamic subtest: vecs0
[472.858562] Dynamic subtest vecs0: SUCCESS (0.107s)
[472.872038] Starting dynamic subtest: ccs0
[472.992126] Dynamic subtest ccs0: SUCCESS (0.133s)
[472.995190] Subtest implicit-write-read: SUCCESS (0.693s)
[474.531133] [088/131] (547s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[474.649647] Starting subtest: fbcpsr-rgb565-draw-blt
[481.605313] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (6.956s)
[481.776952] [089/131] (540s left) gem_ctx_persistence (processes)
[481.846240] Starting subtest: processes
[483.358695] Subtest processes: SUCCESS (1.512s)
[483.461025] [090/131] (538s left) kms_cursor_crc (cursor-sliding-32x10)
[483.614356] Starting subtest: cursor-sliding-32x10
[483.658372] Subtest cursor-sliding-32x10: SKIP (0.041s)
[483.958924] [091/131] (538s left) kms_psr (fbc-pr-primary-render)
[484.062289] Starting subtest: fbc-pr-primary-render
[484.067204] Subtest fbc-pr-primary-render: SKIP (0.000s)
[484.161151] [092/131] (538s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[484.232374] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[484.314433] [093/131] (538s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[484.373593] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[484.375854] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[484.507731] [094/131] (538s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[484.610526] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[484.615710] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[484.719779] [095/131] (537s left) gem_exec_async (concurrent-writes)
[484.780979] Starting subtest: concurrent-writes
[484.783114] Starting dynamic subtest: rcs0
[484.796765] Dynamic subtest rcs0: SUCCESS (0.015s)
[484.809251] Starting dynamic subtest: bcs0
[484.811957] Dynamic subtest bcs0: SUCCESS (0.005s)
[484.822013] Starting dynamic subtest: vcs0
[484.824214] Dynamic subtest vcs0: SUCCESS (0.005s)
[484.834233] Starting dynamic subtest: vcs1
[484.836375] Dynamic subtest vcs1: SUCCESS (0.005s)
[484.845885] Starting dynamic subtest: vecs0
[484.847839] Dynamic subtest vecs0: SUCCESS (0.005s)
[484.857014] Starting dynamic subtest: ccs0
[484.859161] Dynamic subtest ccs0: SUCCESS (0.005s)
[484.861149] Subtest concurrent-writes: SUCCESS (0.045s)
[485.091025] [096/131] (537s left) gem_mmap_gtt (basic-small-bo-tiledx)
[485.161120] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[485.233734] [097/131] (537s left) dmabuf_sync_file (import-basic)
[485.277087] Starting subtest: import-basic
[485.279225] Subtest import-basic: SUCCESS (0.001s)
[485.356601] [098/131] (537s left) kms_feature_discovery (display)
[485.450670] Starting subtest: display
[485.452706] Subtest display: SUCCESS (0.000s)
[485.548013] [099/131] (537s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[485.654425] Starting subtest: x-tiled-64bpp-rotate-90
[485.675078] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[485.836518] [100/131] (537s left) perf_pmu (busy-no-semaphores)
[485.879699] Starting subtest: busy-no-semaphores
[485.881875] Starting dynamic subtest: rcs0
[486.455672] Dynamic subtest rcs0: SUCCESS (0.574s)
[486.469048] Starting dynamic subtest: bcs0
[487.005443] Dynamic subtest bcs0: SUCCESS (0.547s)
[487.018527] Starting dynamic subtest: vcs0
[487.557017] Dynamic subtest vcs0: SUCCESS (0.548s)
[487.570828] Starting dynamic subtest: vcs1
[488.921533] Dynamic subtest vcs1: SUCCESS (1.361s)
[488.936089] Starting dynamic subtest: vecs0
[490.906581] Dynamic subtest vecs0: SUCCESS (1.982s)
[490.920791] Starting dynamic subtest: ccs0
[492.891225] Dynamic subtest ccs0: SUCCESS (1.981s)
[492.894358] Subtest busy-no-semaphores: SUCCESS (7.009s)
[493.078760] [101/131] (529s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[493.191129] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[493.196041] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[493.329038] [102/131] (529s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[493.452375] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[493.456941] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[493.622198] [103/131] (529s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[494.011089] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[494.817745] [104/131] (528s left) kms_getfb (getfb-reject-nv12)
[494.911677] Starting subtest: getfb-reject-nv12
[494.914349] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[495.025200] [105/131] (528s left) i915_drm_fdinfo (context-close-stress)
[495.072849] Starting subtest: context-close-stress
[505.818627] Subtest context-close-stress: SUCCESS (10.746s)
[505.923198] [106/131] (517s left) perf_pmu (module-unload)
[506.147778] Starting subtest: module-unload
[512.748246] Subtest module-unload: FAIL (6.461s)
[512.782905] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[512.783655] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[512.786988] [107/131] (510s left) kms_chamelium_edid (dp-edid-read)
[515.858196] Subtest dp-edid-read: SKIP (0.000s)
[516.710129] [FACT kms_chamelium_edid (dp-edid-read)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[516.711490] [FACT kms_chamelium_edid (dp-edid-read)] new: kernel.kmod_is_loaded.i915: true
[516.715888] [108/131] (506s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[521.787558] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[521.789909] Starting dynamic subtest: pipe-A-eDP-1
[522.594942] Dynamic subtest pipe-A-eDP-1: SKIP (0.804s)
[522.604271] Starting dynamic subtest: pipe-B-eDP-1
[523.724865] Dynamic subtest pipe-B-eDP-1: SKIP (1.129s)
[523.730403] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.938s)
[524.946403] [109/131] (498s left) kms_vblank (query-busy)
[525.039516] Starting subtest: query-busy
[525.042126] Starting dynamic subtest: pipe-A-eDP-1
[526.928091] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.890s)
[526.944094] Starting dynamic subtest: pipe-D-eDP-1
[529.485506] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.557s)
[529.488285] Subtest query-busy: SUCCESS (4.450s)
[530.317278] [110/131] (492s left) syncobj_timeline (wait-for-submit-complex)
[530.379946] Starting subtest: wait-for-submit-complex
[530.439163] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[530.542684] [111/131] (492s left) perf (invalid-create-userspace-config)
[530.690233] Starting subtest: invalid-create-userspace-config
[530.692960] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[530.734574] [112/131] (492s left) gem_exec_basic (basic)
[530.794238] Starting subtest: basic
[530.797130] Starting dynamic subtest: rcs0-smem
[530.799909] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[530.810394] Starting dynamic subtest: bcs0-smem
[530.813218] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[530.822190] Starting dynamic subtest: vcs0-smem
[530.824840] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[530.833498] Starting dynamic subtest: vcs1-smem
[530.835670] Dynamic subtest vcs1-smem: SUCCESS (0.002s)
[530.844199] Starting dynamic subtest: vecs0-smem
[530.846560] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[530.855088] Starting dynamic subtest: ccs0-smem
[530.857370] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[530.859695] Subtest basic: SUCCESS (0.015s)
[532.435073] [113/131] (490s left) kms_chamelium_color (ctm-0-25)
[532.544320] Subtest ctm-0-25: SKIP (0.000s)
[532.590022] [114/131] (490s left) gem_userptr_blits (process-exit-busy)
[532.671637] Starting subtest: process-exit-busy
[532.685488] Subtest process-exit-busy: SUCCESS (0.015s)
[532.832740] [115/131] (490s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[532.908078] Starting subtest: y-tiled-ccs-to-y-tiled
[532.913145] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[533.114344] [116/131] (490s left) gen7_exec_parse (bitmasks)
[533.183452] Subtest bitmasks: SKIP (0.000s)
[533.239327] [117/131] (490s left) gem_eio (wait-wedge-10ms)
[533.452628] Starting subtest: wait-wedge-10ms
[535.383745] Subtest wait-wedge-10ms: SUCCESS (1.930s)
[535.525765] [118/131] (488s left) kms_content_protection (atomic)
[535.673357] Starting subtest: atomic
[535.679006] Subtest atomic: SKIP (0.000s)
[535.833193] [119/131] (487s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[535.935265] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[535.937795] Starting dynamic subtest: pipe-A-default-mode
[536.247084] Dynamic subtest pipe-A-default-mode: SKIP (0.308s)
[536.252194] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.313s)
[537.074271] [120/131] (486s left) gem_exec_params (invalid-flag)
[537.146064] Starting subtest: invalid-flag
[537.149045] Subtest invalid-flag: SUCCESS (0.000s)
[537.278845] [121/131] (486s left) kms_getfb (getfb-addfb-different-handles)
[537.376997] Starting subtest: getfb-addfb-different-handles
[537.379452] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[537.516123] [122/131] (486s left) gem_exec_flush (basic-uc-ro-default)
[537.586020] Starting subtest: basic-uc-ro-default
[543.045040] Subtest basic-uc-ro-default: SUCCESS (5.462s)
[543.258899] [123/131] (480s left) gem_sync (basic-each)
[543.343129] Starting subtest: basic-each
[545.634652] Subtest basic-each: SUCCESS (2.306s)
[545.859862] [124/131] (478s left) gem_exec_balancer (full)
[545.923719] Starting subtest: full
[550.230089] Subtest full: SUCCESS (4.307s)
[550.596256] [125/131] (473s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[550.707939] Starting subtest: y-tiled-8bpp-rotate-180
[550.712408] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[550.840349] [126/131] (473s left) sw_sync (sync_merge_invalid)
[550.880226] Starting subtest: sync_merge_invalid
[550.901820] Subtest sync_merge_invalid: SUCCESS (0.025s)
[550.983597] [127/131] (473s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[551.092153] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[555.510309] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.427s)
[556.676757] [128/131] (467s left) gem_lmem_swapping (parallel-random-verify-ccs)
[556.722821] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[556.763385] [129/131] (467s left) kms_lease (lease-unleased-connector)
[556.858158] Starting subtest: lease-unleased-connector
[556.860548] Starting dynamic subtest: pipe-A-eDP-1
[556.866319] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[556.876097] Starting dynamic subtest: pipe-B-eDP-1
[556.878696] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[556.888897] Starting dynamic subtest: pipe-C-eDP-1
[556.891416] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[556.901130] Starting dynamic subtest: pipe-D-eDP-1
[556.903881] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[556.906383] Subtest lease-unleased-connector: SUCCESS (0.034s)
[557.028195] [130/131] (467s left) perf (enable-disable)
[557.135896] Starting subtest: enable-disable
[557.138722] Starting dynamic subtest: 0-rcs0
[559.404970] Dynamic subtest 0-rcs0: SUCCESS (2.268s)
[559.416221] Starting dynamic subtest: 1-vcs0
[563.897872] Dynamic subtest 1-vcs0: SUCCESS (4.493s)
[563.900249] Subtest enable-disable: SUCCESS (6.763s)
[563.959030] [131/131] (460s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[564.109947] Starting subtest: cursor-rapid-movement-32x32
[564.157972] Subtest cursor-rapid-movement-32x32: SKIP (0.041s)
[564.487739] 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.