[57.611671] Initializing watchdogs
[57.611718]   /dev/watchdog0
[57.626237] [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.630973] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.633186] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.636662] [001/131] (960s left) fbdev (unaligned-read)
[57.702890] Starting subtest: unaligned-read
[57.720340] Subtest unaligned-read: SUCCESS (0.017s)
[57.817474] [002/131] (960s left) gem_ctx_isolation (dirty-switch)
[57.876603] Starting subtest: dirty-switch
[57.879046] Starting dynamic subtest: rcs0
[58.135866] Dynamic subtest rcs0: SUCCESS (0.259s)
[58.146894] Starting dynamic subtest: bcs0
[58.406564] Dynamic subtest bcs0: SUCCESS (0.271s)
[58.414520] Starting dynamic subtest: vcs0
[58.648288] Dynamic subtest vcs0: SUCCESS (0.242s)
[58.657393] Starting dynamic subtest: vcs1
[60.125564] Dynamic subtest vcs1: SUCCESS (1.479s)
[60.133671] Starting dynamic subtest: vecs0
[62.140724] Dynamic subtest vecs0: SUCCESS (2.017s)
[62.150615] Starting dynamic subtest: ccs0
[64.129441] Dynamic subtest ccs0: SUCCESS (1.990s)
[64.131850] Subtest dirty-switch: SUCCESS (6.260s)
[64.395958] [003/131] (953s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[64.497416] Starting subtest: psr-modesetfrombusy
[70.724078] Subtest psr-modesetfrombusy: SUCCESS (6.234s)
[70.903474] [004/131] (947s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[71.001367] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[71.003272] Starting dynamic subtest: pipe-A-default-mode
[73.731874] Dynamic subtest pipe-A-default-mode: SUCCESS (2.727s)
[73.734333] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.730s)
[74.618349] [005/131] (943s left) gem_exec_params (sol-reset-invalid)
[74.683907] Starting subtest: sol-reset-invalid
[74.686350] Subtest sol-reset-invalid: SUCCESS (0.002s)
[76.049544] [006/131] (942s left) gem_spin_batch (resubmit-new)
[76.107964] Starting subtest: resubmit-new
[76.110448] Starting dynamic subtest: rcs0
[76.116664] Dynamic subtest rcs0: SUCCESS (0.009s)
[76.126889] Starting dynamic subtest: bcs0
[76.129205] Dynamic subtest bcs0: SUCCESS (0.005s)
[76.137162] Starting dynamic subtest: vcs0
[76.139353] Dynamic subtest vcs0: SUCCESS (0.005s)
[76.147434] Starting dynamic subtest: vcs1
[76.149692] Dynamic subtest vcs1: SUCCESS (0.005s)
[76.156962] Starting dynamic subtest: vecs0
[76.159143] Dynamic subtest vecs0: SUCCESS (0.004s)
[76.166570] Starting dynamic subtest: ccs0
[76.168738] Dynamic subtest ccs0: SUCCESS (0.005s)
[76.170929] Subtest resubmit-new: SUCCESS (0.033s)
[77.980355] [007/131] (940s left) gem_exec_schedule (preempt-user)
[78.068961] Starting subtest: preempt-user
[78.071062] Starting dynamic subtest: rcs0
[78.095048] Dynamic subtest rcs0: SUCCESS (0.033s)
[78.105566] Starting dynamic subtest: bcs0
[78.126221] Dynamic subtest bcs0: SUCCESS (0.031s)
[78.134765] Starting dynamic subtest: vcs0
[78.155912] Dynamic subtest vcs0: SUCCESS (0.030s)
[78.164067] Starting dynamic subtest: vcs1
[78.178040] Dynamic subtest vcs1: SUCCESS (0.022s)
[78.185836] Starting dynamic subtest: vecs0
[78.202115] Dynamic subtest vecs0: SUCCESS (0.024s)
[78.210021] Starting dynamic subtest: ccs0
[78.234361] Dynamic subtest ccs0: SUCCESS (0.032s)
[78.236709] Subtest preempt-user: SUCCESS (0.174s)
[80.013412] [008/131] (938s left) sysfs_heartbeat_interval (nopreempt)
[80.085014] Starting subtest: nopreempt
[80.087429] Starting dynamic subtest: vecs0
[89.906622] Dynamic subtest vecs0: SUCCESS (9.818s)
[89.916992] Starting dynamic subtest: ccs0
[93.936405] Dynamic subtest ccs0: SUCCESS (4.029s)
[93.952319] Starting dynamic subtest: vcs0
[103.911272] Dynamic subtest vcs0: SUCCESS (9.975s)
[103.921615] Starting dynamic subtest: bcs0
[107.995467] Dynamic subtest bcs0: SUCCESS (4.080s)
[108.004304] Starting dynamic subtest: rcs0
[112.053550] Dynamic subtest rcs0: SUCCESS (4.059s)
[112.064559] Starting dynamic subtest: vcs1
[121.959716] Dynamic subtest vcs1: SUCCESS (9.905s)
[121.962816] Subtest nopreempt: SUCCESS (41.874s)
[122.057188] [009/131] (896s left) kms_invalid_mode (zero-hdisplay)
[122.149489] Starting subtest: zero-hdisplay
[122.151747] Starting dynamic subtest: pipe-A-eDP-1
[122.445678] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.295s)
[122.455386] Starting dynamic subtest: pipe-B-eDP-1
[122.457398] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[122.464775] Starting dynamic subtest: pipe-C-eDP-1
[122.467085] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[122.475153] Starting dynamic subtest: pipe-D-eDP-1
[122.477578] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[122.480032] Subtest zero-hdisplay: SUCCESS (0.300s)
[123.347741] [010/131] (895s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[123.457502] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[123.461703] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[123.594750] [011/131] (894s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[123.703380] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[124.549500] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.848s)
[124.754648] [012/131] (893s left) kms_getfb (getfb-handle-zero)
[124.842240] Starting subtest: getfb-handle-zero
[124.844545] Subtest getfb-handle-zero: SUCCESS (0.000s)
[124.963445] [013/131] (893s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[125.032622] Starting subtest: yf-tiled-ccs-to-yf-tiled
[125.036930] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[125.239190] [014/131] (893s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[125.337375] Starting subtest: multiplane-rotation-cropping-bottom
[132.616125] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.282s)
[132.776023] [015/131] (885s left) gem_eio (wait-1us)
[133.006636] Starting subtest: wait-1us
[133.348863] Subtest wait-1us: SUCCESS (0.343s)
[133.516681] [016/131] (885s left) syncobj_wait (multi-wait-for-submit-signaled)
[133.578097] Starting subtest: multi-wait-for-submit-signaled
[133.580044] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[133.693475] [017/131] (885s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[133.776051] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[133.778068] Starting dynamic subtest: pipe-A-default-mode
[134.066955] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[134.071688] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.289s)
[134.888361] [018/131] (883s left) kms_plane_alpha_blend (alpha-7efc)
[134.991177] Starting subtest: alpha-7efc
[135.275355] Starting dynamic subtest: pipe-A-eDP-1
[135.879044] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.601s)
[135.914785] Starting dynamic subtest: pipe-D-eDP-1
[137.192638] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.288s)
[137.195038] Subtest alpha-7efc: SUCCESS (2.201s)
[138.053210] [019/131] (880s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[138.168713] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[138.172888] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[138.301435] [020/131] (880s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[138.398034] Starting subtest: fbc-psr2-primary-mmap-cpu
[138.399953] Starting dynamic subtest: eDP-1
[138.404114] Dynamic subtest eDP-1: SKIP (0.000s)
[138.408258] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[138.488923] [021/131] (880s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[138.588210] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[138.599555] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[138.701627] [022/131] (880s left) gem_exec_whisper (basic-normal-all)
[138.766530] Starting subtest: basic-normal-all
[141.179514] Subtest basic-normal-all: SUCCESS (2.410s)
[141.386076] [023/131] (877s left) kms_big_fb (linear-16bpp-rotate-180)
[141.502642] Starting subtest: linear-16bpp-rotate-180
[142.836981] Subtest linear-16bpp-rotate-180: SUCCESS (1.334s)
[142.991723] [024/131] (876s left) kms_frontbuffer_tracking (psr-slowdraw)
[143.100444] Starting subtest: psr-slowdraw
[168.347438] Subtest psr-slowdraw: SUCCESS (25.247s)
[168.537547] [025/131] (850s left) vgem_basic (dmabuf-fence)
[168.646195] Starting subtest: dmabuf-fence
[168.648405] Subtest dmabuf-fence: SUCCESS (0.001s)
[168.723201] [FACT vgem_basic (dmabuf-fence)] new: kernel.kmod_is_loaded.vgem: true
[168.726363] [026/131] (850s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[168.789705] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[168.792222] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[168.906747] [027/131] (850s left) kms_psr (pr-primary-render)
[169.001169] Starting subtest: pr-primary-render
[169.005599] Subtest pr-primary-render: SKIP (0.000s)
[169.097939] [028/131] (850s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[169.190518] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[169.192421] Starting dynamic subtest: pipe-A-eDP-1
[169.210896] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[169.221986] Starting dynamic subtest: pipe-B-eDP-1
[169.239236] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[169.246977] Starting dynamic subtest: pipe-C-eDP-1
[169.263888] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[169.271853] Starting dynamic subtest: pipe-D-eDP-1
[169.289453] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[169.292790] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[171.519981] [029/131] (847s left) core_auth (getclient-master-drop)
[171.554280] Starting subtest: getclient-master-drop
[171.578025] Subtest getclient-master-drop: SUCCESS (0.022s)
[171.715214] [030/131] (847s left) kms_plane_scaling (planes-downscale-factor-0-5)
[171.806061] Starting subtest: planes-downscale-factor-0-5
[171.808178] Starting dynamic subtest: pipe-A
[171.827243] Dynamic subtest pipe-A: SKIP (0.019s)
[171.835828] Starting dynamic subtest: pipe-B
[171.866860] Dynamic subtest pipe-B: SKIP (0.037s)
[171.875655] Starting dynamic subtest: pipe-C
[171.903251] Dynamic subtest pipe-C: SKIP (0.037s)
[171.912037] Starting dynamic subtest: pipe-D
[171.936259] Dynamic subtest pipe-D: SKIP (0.031s)
[171.939894] Subtest planes-downscale-factor-0-5: SKIP (0.127s)
[172.066785] [031/131] (847s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[172.159074] Starting subtest: fb-8bpc-vs-panel-8bpc
[172.161548] Starting dynamic subtest: pipe-A-eDP-1
[173.602757] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.445s)
[173.605112] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.446s)
[174.488928] [032/131] (844s left) gem_partial_pwrite_pread (reads-snoop)
[174.558868] Subtest reads-snoop: SKIP (0.000s)
[174.645391] [033/131] (844s left) gem_eio (in-flight-contexts-immediate)
[174.887882] Starting subtest: in-flight-contexts-immediate
[188.899167] Subtest in-flight-contexts-immediate: SUCCESS (14.011s)
[189.082857] [034/131] (830s left) gen3_render_mixed_blits
[189.213934] [035/131] (830s left) kms_pm_rpm (pm-tiling)
[191.136858] Starting subtest: pm-tiling
[191.141826] Subtest pm-tiling: SKIP (0.000s)
[191.544133] [036/131] (827s left) i915_module_load (reload-no-display)
[191.584578] Starting subtest: reload-no-display
[196.246090] Subtest reload-no-display: SUCCESS (4.633s)
[196.296192] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[196.298065] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[196.303838] [037/131] (823s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[197.906448] Starting subtest: plane-upscale-20x20-with-rotation
[197.908588] Starting dynamic subtest: pipe-A
[198.148573] Dynamic subtest pipe-A: SUCCESS (0.244s)
[198.156522] Starting dynamic subtest: pipe-B
[199.476754] Dynamic subtest pipe-B: SUCCESS (1.328s)
[199.484576] Starting dynamic subtest: pipe-C
[200.807303] Dynamic subtest pipe-C: SUCCESS (1.330s)
[200.816050] Starting dynamic subtest: pipe-D
[202.125300] Dynamic subtest pipe-D: SUCCESS (1.316s)
[202.127710] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.222s)
[203.276015] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[203.277291] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: kernel.kmod_is_loaded.i915: true
[203.281069] [038/131] (816s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[203.392953] Starting subtest: 4-tiled-8bpp-rotate-180
[204.586149] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.195s)
[204.748016] [039/131] (814s left) kms_rotation_crc (primary-rotation-90)
[204.846904] Starting subtest: primary-rotation-90
[204.850673] Subtest primary-rotation-90: SKIP (0.001s)
[204.947745] [040/131] (814s left) kms_cursor_crc (cursor-onscreen-32x10)
[205.120346] Starting subtest: cursor-onscreen-32x10
[205.169258] Subtest cursor-onscreen-32x10: SKIP (0.044s)
[205.469710] [041/131] (814s left) kms_vblank (wait-idle)
[205.566418] Starting subtest: wait-idle
[205.568338] Starting dynamic subtest: pipe-A-eDP-1
[207.322456] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.754s)
[207.336315] Starting dynamic subtest: pipe-D-eDP-1
[209.736082] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.413s)
[209.738479] Subtest wait-idle: SUCCESS (4.172s)
[210.576119] [042/131] (809s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[210.683642] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[210.687665] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[210.804225] [043/131] (809s left) kms_flip (2x-flip-vs-dpms)
[210.903516] Starting subtest: 2x-flip-vs-dpms
[210.912447] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[211.011503] [044/131] (808s left) gem_exec_fence (syncobj-channel)
[211.452070] Starting subtest: syncobj-channel
[212.469714] Subtest syncobj-channel: SUCCESS (1.015s)
[212.978107] [045/131] (806s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[213.085258] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[213.087484] Starting dynamic subtest: pipe-A-eDP-1
[213.096551] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[213.106215] Starting dynamic subtest: pipe-B-eDP-1
[213.115431] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[213.123952] Starting dynamic subtest: pipe-C-eDP-1
[213.133288] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[213.142772] Starting dynamic subtest: pipe-D-eDP-1
[213.151565] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[213.155983] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.007s)
[215.417379] [046/131] (804s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[215.526607] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[222.880353] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.356s)
[223.054064] [047/131] (796s left) kms_flip (busy-flip)
[223.158148] Starting subtest: busy-flip
[223.160394] Starting dynamic subtest: A-eDP1
[224.829053] Dynamic subtest A-eDP1: SUCCESS (1.670s)
[224.839538] Starting dynamic subtest: B-eDP1
[226.479337] Dynamic subtest B-eDP1: SUCCESS (1.649s)
[226.490067] Starting dynamic subtest: C-eDP1
[228.126066] Dynamic subtest C-eDP1: SUCCESS (1.646s)
[228.135411] Starting dynamic subtest: D-eDP1
[229.767677] Dynamic subtest D-eDP1: SUCCESS (1.640s)
[229.769889] Subtest busy-flip: SUCCESS (6.613s)
[230.951696] [048/131] (789s left) prime_mmap (test_aperture_limit)
[231.024946] Starting subtest: test_aperture_limit
[231.026932] Starting dynamic subtest: test_aperture_limit-smem
[231.033918] Dynamic subtest test_aperture_limit-smem: SKIP (0.008s)
[231.038276] Subtest test_aperture_limit: SKIP (0.009s)
[231.162384] [049/131] (788s left) i915_pm_rpm (sysfs-read)
[233.097544] Starting subtest: sysfs-read
[234.254965] Subtest sysfs-read: SUCCESS (1.155s)
[234.650807] [050/131] (785s left) kms_big_fb (yf-tiled-addfb)
[234.755247] Starting subtest: yf-tiled-addfb
[234.759730] Subtest yf-tiled-addfb: SKIP (0.000s)
[234.886853] [051/131] (785s left) sriov_basic (enable-vfs-autoprobe-on)
[234.954020] Starting subtest: enable-vfs-autoprobe-on
[234.955932] Starting dynamic subtest: numvfs-1
[235.034246] Dynamic subtest numvfs-1: FAIL (0.029s)
[235.042376] Starting dynamic subtest: numvfs-2
[235.113364] Dynamic subtest numvfs-2: FAIL (0.004s)
[235.122050] Starting dynamic subtest: numvfs-3
[235.191428] Dynamic subtest numvfs-3: FAIL (0.003s)
[235.199679] Starting dynamic subtest: numvfs-4
[235.268951] Dynamic subtest numvfs-4: FAIL (0.003s)
[235.277247] Starting dynamic subtest: numvfs-5
[235.346295] Dynamic subtest numvfs-5: FAIL (0.004s)
[235.355289] Starting dynamic subtest: numvfs-6
[235.424551] Dynamic subtest numvfs-6: FAIL (0.004s)
[235.432829] Starting dynamic subtest: numvfs-7
[235.503147] Dynamic subtest numvfs-7: FAIL (0.008s)
[235.511197] Starting dynamic subtest: numvfs-random
[235.580440] Dynamic subtest numvfs-random: FAIL (0.004s)
[235.588349] Starting dynamic subtest: numvfs-all
[235.647712] Dynamic subtest numvfs-all: FAIL (0.004s)
[235.649431] Subtest enable-vfs-autoprobe-on: FAIL (0.547s)
[235.689654] [052/131] (784s left) gem_exec_whisper (basic-queues)
[235.747677] Starting subtest: basic-queues
[241.448834] Subtest basic-queues: SUCCESS (5.698s)
[241.657173] [053/131] (778s left) kms_flip (2x-dpms-vs-vblank-race)
[241.764329] Starting subtest: 2x-dpms-vs-vblank-race
[241.773775] Subtest 2x-dpms-vs-vblank-race: SKIP (0.011s)
[241.878523] [054/131] (778s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[241.980983] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[242.356190] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[245.096235] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.738s)
[245.105220] Starting dynamic subtest: pipe-A-eDP-1-4
[247.304934] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.207s)
[247.316842] Starting dynamic subtest: pipe-A-eDP-1-4
[249.315062] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[249.326225] Starting dynamic subtest: pipe-A-eDP-1-x
[251.493631] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.178s)
[251.862619] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[254.624105] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.768s)
[254.634643] Starting dynamic subtest: pipe-B-eDP-1-4
[256.799384] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.174s)
[256.810822] Starting dynamic subtest: pipe-B-eDP-1-4
[258.809364] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[258.820838] Starting dynamic subtest: pipe-B-eDP-1-x
[260.999539] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.189s)
[261.375063] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[264.153482] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.785s)
[264.165326] Starting dynamic subtest: pipe-C-eDP-1-4
[266.317628] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.163s)
[266.328841] Starting dynamic subtest: pipe-C-eDP-1-4
[268.327693] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.010s)
[268.338537] Starting dynamic subtest: pipe-C-eDP-1-x
[270.506539] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[270.874833] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[273.686886] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.817s)
[273.698103] Starting dynamic subtest: pipe-D-eDP-1-4
[275.860951] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.173s)
[275.869753] Starting dynamic subtest: pipe-D-eDP-1-4
[277.871238] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[277.880529] Starting dynamic subtest: pipe-D-eDP-1-x
[280.051089] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.177s)
[280.053971] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.070s)
[281.304930] [055/131] (739s left) gem_mmap_gtt (basic-write-read-distinct)
[281.368130] Subtest basic-write-read-distinct: SKIP (0.000s)
[281.447431] [056/131] (738s left) gem_mmap_offset (oob-read)
[281.501938] Starting subtest: oob-read
[281.504077] Subtest oob-read: SUCCESS (0.000s)
[281.637310] [057/131] (738s left) kms_atomic (atomic-plane-damage)
[281.732907] Starting subtest: atomic-plane-damage
[281.783485] Starting dynamic subtest: pipe-A-eDP-1
[282.355773] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.574s)
[282.358250] Subtest atomic-plane-damage: SUCCESS (0.625s)
[283.248454] [058/131] (737s left) kms_atomic_transition (plane-all-transition-nonblocking)
[283.355453] Starting subtest: plane-all-transition-nonblocking
[283.357696] Starting dynamic subtest: pipe-A-eDP-1
[285.851334] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.498s)
[286.161027] Starting dynamic subtest: pipe-B-eDP-1
[289.357091] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.207s)
[289.657687] Subtest plane-all-transition-nonblocking: SUCCESS (6.301s)
[290.504775] [059/131] (729s left) gem_exec_parallel (contexts)
[290.568471] Starting subtest: contexts
[290.570644] Starting dynamic subtest: rcs0
[293.543083] Dynamic subtest rcs0: SUCCESS (2.973s)
[293.555208] Starting dynamic subtest: bcs0
[297.003085] Dynamic subtest bcs0: SUCCESS (3.457s)
[297.014301] Starting dynamic subtest: vcs0
[301.297049] Dynamic subtest vcs0: SUCCESS (4.286s)
[301.306505] Starting dynamic subtest: vcs1
[305.352642] Dynamic subtest vcs1: SUCCESS (4.058s)
[305.364466] Starting dynamic subtest: vecs0
[309.430839] Dynamic subtest vecs0: SUCCESS (4.075s)
[309.441768] Starting dynamic subtest: ccs0
[313.183343] Dynamic subtest ccs0: SUCCESS (3.751s)
[313.185649] Subtest contexts: SUCCESS (22.614s)
[313.402753] [060/131] (707s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[313.514574] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[320.302792] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.791s)
[320.465497] [061/131] (700s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[320.580060] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[320.583952] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[320.695641] [062/131] (699s left) kms_atomic_interruptible (legacy-pageflip)
[320.795077] Starting subtest: legacy-pageflip
[320.797273] Starting dynamic subtest: pipe-A-eDP-1
[328.241680] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.444s)
[328.244192] Subtest legacy-pageflip: SUCCESS (7.445s)
[329.104030] [063/131] (691s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[329.208499] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[329.212653] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[329.330023] [064/131] (691s left) kms_psr (psr2-sprite-mmap-cpu)
[329.436289] Starting subtest: psr2-sprite-mmap-cpu
[329.438411] Starting dynamic subtest: eDP-1
[331.162399] Dynamic subtest eDP-1: SUCCESS (1.726s)
[331.164836] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.727s)
[332.061806] [065/131] (688s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[332.161810] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[339.261426] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.102s)
[339.454960] [066/131] (681s left) gem_exec_schedule (fairslice-all)
[339.536899] Starting subtest: fairslice-all
[341.583242] Subtest fairslice-all: SUCCESS (2.053s)
[342.993866] [067/131] (677s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[347.987235] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[347.989507] Starting dynamic subtest: pipe-A-eDP-1
[348.797333] Dynamic subtest pipe-A-eDP-1: SKIP (0.808s)
[348.810413] Starting dynamic subtest: pipe-B-eDP-1
[349.924513] Dynamic subtest pipe-B-eDP-1: SKIP (1.120s)
[349.929189] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.936s)
[351.243259] [068/131] (669s left) api_intel_allocator (two-level-inception)
[351.309475] Starting subtest: two-level-inception
[356.706680] Subtest two-level-inception: SUCCESS (5.397s)
[356.863960] [069/131] (664s left) i915_suspend (debugfs-reader)
[356.930057] Starting subtest: debugfs-reader
[374.839887] Subtest debugfs-reader: SUCCESS (8.094s)
[375.050823] [070/131] (646s left) kms_flip (plain-flip-fb-recreate-interruptible)
[375.159377] Starting subtest: plain-flip-fb-recreate-interruptible
[375.161707] Starting dynamic subtest: A-eDP1
[384.007928] Dynamic subtest A-eDP1: SUCCESS (8.879s)
[384.018424] Starting dynamic subtest: B-eDP1
[392.805526] Dynamic subtest B-eDP1: SUCCESS (8.808s)
[392.816767] Starting dynamic subtest: C-eDP1
[401.614848] Dynamic subtest C-eDP1: SUCCESS (8.814s)
[401.625534] Starting dynamic subtest: D-eDP1
[410.435071] Dynamic subtest D-eDP1: SUCCESS (8.820s)
[410.439723] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.331s)
[411.657741] [071/131] (609s left) gem_vm_create (invalid-destroy)
[411.717776] Starting subtest: invalid-destroy
[411.719807] Subtest invalid-destroy: SUCCESS (0.002s)
[411.890804] [072/131] (609s left) gem_ccs (ctrl-surf-copy-new-ctx)
[411.949007] Starting subtest: ctrl-surf-copy-new-ctx
[411.953368] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[412.097240] [073/131] (609s left) syncobj_basic (bad-destroy-pad)
[412.152680] Starting subtest: bad-destroy-pad
[412.154886] Subtest bad-destroy-pad: SUCCESS (0.000s)
[412.262783] [074/131] (609s left) kms_plane_multiple (2x-tiling-y)
[412.383543] Starting subtest: 2x-tiling-y
[412.387860] Subtest 2x-tiling-y: SKIP (0.000s)
[412.485919] [075/131] (608s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[412.586020] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[412.587998] Starting dynamic subtest: pipe-A-eDP-1
[412.607574] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[412.619342] Starting dynamic subtest: pipe-B-eDP-1
[412.636841] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[412.646317] Starting dynamic subtest: pipe-C-eDP-1
[412.664052] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[412.673623] Starting dynamic subtest: pipe-D-eDP-1
[412.692868] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[412.696600] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[414.899925] [076/131] (606s left) kms_pm_rpm (modeset-lpsp)
[416.940867] Starting subtest: modeset-lpsp
[418.389964] Subtest modeset-lpsp: SUCCESS (1.453s)
[418.506512] [077/131] (602s left) kms_setmode (basic)
[418.569011] Starting subtest: basic
[418.892092] Starting dynamic subtest: pipe-A-eDP-1
[420.969418] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.072s)
[421.321603] Starting dynamic subtest: pipe-B-eDP-1
[423.405080] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.089s)
[423.726245] Subtest basic: SUCCESS (5.156s)
[424.577905] [078/131] (596s left) kms_psr (fbc-psr-suspend)
[424.675838] Starting subtest: fbc-psr-suspend
[424.678140] Starting dynamic subtest: eDP-1
[424.682725] Dynamic subtest eDP-1: SKIP (0.000s)
[424.686766] Subtest fbc-psr-suspend: SKIP (0.003s)
[424.783207] [079/131] (596s left) gem_ringfill (engines-basic)
[430.709665] Starting subtest: engines-basic
[430.712207] Starting dynamic subtest: rcs0
[430.789375] Dynamic subtest rcs0: SUCCESS (0.080s)
[430.801961] Starting dynamic subtest: bcs0
[430.834121] Dynamic subtest bcs0: SUCCESS (0.043s)
[430.846431] Starting dynamic subtest: vcs0
[430.877159] Dynamic subtest vcs0: SUCCESS (0.041s)
[430.888005] Starting dynamic subtest: vcs1
[430.920451] Dynamic subtest vcs1: SUCCESS (0.041s)
[430.931595] Starting dynamic subtest: vecs0
[430.964445] Dynamic subtest vecs0: SUCCESS (0.042s)
[430.975598] Starting dynamic subtest: ccs0
[431.010133] Dynamic subtest ccs0: SUCCESS (0.044s)
[431.012577] Subtest engines-basic: SUCCESS (0.303s)
[431.115751] [080/131] (590s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[431.177684] Starting subtest: invalid-bsd2-flag-on-vebox
[431.179624] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[431.319313] [081/131] (590s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[431.418507] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[431.420794] Starting dynamic subtest: pipe-A-eDP-1
[431.440123] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.020s)
[431.450046] Starting dynamic subtest: pipe-B-eDP-1
[431.469589] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.020s)
[431.479399] Starting dynamic subtest: pipe-C-eDP-1
[431.500145] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.019s)
[431.510660] Starting dynamic subtest: pipe-D-eDP-1
[431.531385] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.021s)
[431.533684] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.086s)
[433.849363] [082/131] (587s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[433.953620] Starting subtest: extended-pageflip-modeset-hang-oldfb
[433.955869] Starting dynamic subtest: pipe-A
[441.854979] Dynamic subtest pipe-A: SUCCESS (7.905s)
[441.865191] Starting dynamic subtest: pipe-D
[450.425583] Dynamic subtest pipe-D: SUCCESS (8.569s)
[450.427887] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.478s)
[451.303953] [083/131] (570s left) gem_ppgtt (shrink-vs-evict-any)
[451.379878] Starting subtest: shrink-vs-evict-any
[456.717881] Subtest shrink-vs-evict-any: SUCCESS (5.338s)
[456.842610] [084/131] (564s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[456.904835] Starting subtest: etime-single-wait-for-submit-unsubmitted
[457.005855] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.102s)
[457.112510] [085/131] (564s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[457.177430] Starting subtest: multi-wait-all-for-submit-signaled
[457.179629] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[457.321089] [086/131] (564s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[457.382002] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[457.485133] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.104s)
[457.593999] [087/131] (564s left) gem_exec_schedule (implicit-write-read)
[457.673522] Starting subtest: implicit-write-read
[457.675450] Starting dynamic subtest: rcs0
[457.861424] Dynamic subtest rcs0: SUCCESS (0.194s)
[457.875235] Starting dynamic subtest: vcs0
[457.984228] Dynamic subtest vcs0: SUCCESS (0.124s)
[457.997134] Starting dynamic subtest: vcs1
[458.089200] Dynamic subtest vcs1: SUCCESS (0.104s)
[458.101894] Starting dynamic subtest: vecs0
[458.188826] Dynamic subtest vecs0: SUCCESS (0.099s)
[458.201785] Starting dynamic subtest: ccs0
[458.312312] Dynamic subtest ccs0: SUCCESS (0.123s)
[458.314951] Subtest implicit-write-read: SUCCESS (0.648s)
[459.847453] [088/131] (562s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[459.959554] Starting subtest: fbcpsr-rgb565-draw-blt
[467.028193] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.073s)
[467.206275] [089/131] (554s left) gem_ctx_persistence (processes)
[467.280813] Starting subtest: processes
[468.676963] Subtest processes: SUCCESS (1.399s)
[468.780983] [090/131] (553s left) kms_cursor_crc (cursor-sliding-32x10)
[468.939958] Starting subtest: cursor-sliding-32x10
[468.984386] Subtest cursor-sliding-32x10: SKIP (0.044s)
[469.294364] [091/131] (552s left) kms_psr (fbc-pr-primary-render)
[469.403971] Starting subtest: fbc-pr-primary-render
[469.408354] Subtest fbc-pr-primary-render: SKIP (0.000s)
[469.501944] [092/131] (552s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[469.561682] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[469.640650] [093/131] (552s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[469.694439] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[469.696156] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[469.829742] [094/131] (552s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[469.926709] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[469.930275] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[470.064962] [095/131] (552s left) gem_exec_async (concurrent-writes)
[470.136225] Starting subtest: concurrent-writes
[470.137903] Starting dynamic subtest: rcs0
[470.148237] Dynamic subtest rcs0: SUCCESS (0.015s)
[470.159102] Starting dynamic subtest: bcs0
[470.161050] Dynamic subtest bcs0: SUCCESS (0.006s)
[470.171103] Starting dynamic subtest: vcs0
[470.172959] Dynamic subtest vcs0: SUCCESS (0.004s)
[470.182908] Starting dynamic subtest: vcs1
[470.184604] Dynamic subtest vcs1: SUCCESS (0.006s)
[470.195593] Starting dynamic subtest: vecs0
[470.197234] Dynamic subtest vecs0: SUCCESS (0.006s)
[470.206630] Starting dynamic subtest: ccs0
[470.208205] Dynamic subtest ccs0: SUCCESS (0.005s)
[470.209896] Subtest concurrent-writes: SUCCESS (0.046s)
[470.439324] [096/131] (551s left) gem_mmap_gtt (basic-small-bo-tiledx)
[470.496983] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[470.567171] [097/131] (551s left) dmabuf_sync_file (import-basic)
[470.609246] Starting subtest: import-basic
[470.611061] Subtest import-basic: SUCCESS (0.002s)
[470.692198] [098/131] (551s left) kms_feature_discovery (display)
[470.781850] Starting subtest: display
[470.783611] Subtest display: SUCCESS (0.000s)
[470.880971] [099/131] (551s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[470.976617] Starting subtest: x-tiled-64bpp-rotate-90
[470.997972] Subtest x-tiled-64bpp-rotate-90: SKIP (0.020s)
[471.161414] [100/131] (551s left) perf_pmu (busy-no-semaphores)
[471.206884] Starting subtest: busy-no-semaphores
[471.208909] Starting dynamic subtest: rcs0
[471.773219] Dynamic subtest rcs0: SUCCESS (0.564s)
[471.784681] Starting dynamic subtest: bcs0
[472.321850] Dynamic subtest bcs0: SUCCESS (0.545s)
[472.335119] Starting dynamic subtest: vcs0
[472.872325] Dynamic subtest vcs0: SUCCESS (0.547s)
[472.885216] Starting dynamic subtest: vcs1
[474.229428] Dynamic subtest vcs1: SUCCESS (1.353s)
[474.241230] Starting dynamic subtest: vecs0
[476.213464] Dynamic subtest vecs0: SUCCESS (1.980s)
[476.225634] Starting dynamic subtest: ccs0
[478.262343] Dynamic subtest ccs0: SUCCESS (2.045s)
[478.265473] Subtest busy-no-semaphores: SUCCESS (7.051s)
[478.430432] [101/131] (544s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[478.540843] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[478.545072] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[478.661799] [102/131] (543s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[478.778687] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[478.783040] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[478.950303] [103/131] (543s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[479.338565] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[480.116183] [104/131] (542s left) kms_getfb (getfb-reject-nv12)
[480.224416] Starting subtest: getfb-reject-nv12
[480.226548] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[480.359247] [105/131] (542s left) i915_drm_fdinfo (context-close-stress)
[480.401191] Starting subtest: context-close-stress
[491.149318] Subtest context-close-stress: SUCCESS (10.744s)
[491.249118] [106/131] (531s left) perf_pmu (module-unload)
[491.509149] Starting subtest: module-unload
[496.879109] Subtest module-unload: FAIL (5.259s)
[496.911104] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[496.911846] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[496.914925] [107/131] (525s left) kms_chamelium_edid (dp-edid-read)
[498.732335] Subtest dp-edid-read: SKIP (0.000s)
[499.567340] [FACT kms_chamelium_edid (dp-edid-read)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[499.569095] [FACT kms_chamelium_edid (dp-edid-read)] new: kernel.kmod_is_loaded.i915: true
[499.573118] [108/131] (523s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[504.555894] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[504.557816] Starting dynamic subtest: pipe-A-eDP-1
[505.366762] Dynamic subtest pipe-A-eDP-1: SKIP (0.808s)
[505.377093] Starting dynamic subtest: pipe-B-eDP-1
[506.503606] Dynamic subtest pipe-B-eDP-1: SKIP (1.135s)
[506.508151] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.947s)
[507.712176] [109/131] (515s left) kms_vblank (query-busy)
[507.809681] Starting subtest: query-busy
[507.811864] Starting dynamic subtest: pipe-A-eDP-1
[509.690430] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.879s)
[509.704648] Starting dynamic subtest: pipe-D-eDP-1
[512.247458] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.554s)
[512.249687] Subtest query-busy: SUCCESS (4.437s)
[513.070456] [110/131] (509s left) syncobj_timeline (wait-for-submit-complex)
[513.136023] Starting subtest: wait-for-submit-complex
[513.196348] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[513.296081] [111/131] (509s left) perf (invalid-create-userspace-config)
[513.431493] Starting subtest: invalid-create-userspace-config
[513.433817] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[513.473939] [112/131] (509s left) gem_exec_basic (basic)
[513.536345] Starting subtest: basic
[513.538660] Starting dynamic subtest: rcs0-smem
[513.540973] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[513.551382] Starting dynamic subtest: bcs0-smem
[513.553259] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[513.562034] Starting dynamic subtest: vcs0-smem
[513.563910] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[513.572470] Starting dynamic subtest: vcs1-smem
[513.574321] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[513.582743] Starting dynamic subtest: vecs0-smem
[513.584614] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[513.593132] Starting dynamic subtest: ccs0-smem
[513.595007] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[513.596886] Subtest basic: SUCCESS (0.014s)
[514.808789] [113/131] (508s left) kms_chamelium_color (ctm-0-25)
[514.922961] Subtest ctm-0-25: SKIP (0.000s)
[514.965445] [114/131] (508s left) gem_userptr_blits (process-exit-busy)
[515.033747] Starting subtest: process-exit-busy
[515.049316] Subtest process-exit-busy: SUCCESS (0.016s)
[515.226003] [115/131] (507s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[515.290232] Starting subtest: y-tiled-ccs-to-y-tiled
[515.294819] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[515.496789] [116/131] (507s left) gen7_exec_parse (bitmasks)
[515.550841] Subtest bitmasks: SKIP (0.000s)
[515.622733] [117/131] (507s left) gem_eio (wait-wedge-10ms)
[515.812855] Starting subtest: wait-wedge-10ms
[517.808874] Subtest wait-wedge-10ms: SUCCESS (1.996s)
[518.001214] [118/131] (505s left) kms_content_protection (atomic)
[518.142417] Starting subtest: atomic
[518.146915] Subtest atomic: SKIP (0.000s)
[518.304403] [119/131] (504s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[518.406565] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[518.408881] Starting dynamic subtest: pipe-A-default-mode
[518.696348] Dynamic subtest pipe-A-default-mode: SKIP (0.289s)
[518.700777] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.291s)
[519.513603] [120/131] (503s left) gem_exec_params (invalid-flag)
[519.564864] Starting subtest: invalid-flag
[519.567289] Subtest invalid-flag: SUCCESS (0.000s)
[519.709268] [121/131] (503s left) kms_getfb (getfb-addfb-different-handles)
[519.800682] Starting subtest: getfb-addfb-different-handles
[519.802825] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[519.931556] [122/131] (503s left) gem_exec_flush (basic-uc-ro-default)
[519.988846] Starting subtest: basic-uc-ro-default
[525.438187] Subtest basic-uc-ro-default: SUCCESS (5.451s)
[525.654186] [123/131] (497s left) gem_sync (basic-each)
[525.735411] Starting subtest: basic-each
[528.330569] Subtest basic-each: SUCCESS (2.607s)
[528.541288] [124/131] (494s left) gem_exec_balancer (full)
[528.614341] Starting subtest: full
[533.632993] Subtest full: SUCCESS (5.021s)
[533.934894] [125/131] (489s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[534.046659] Starting subtest: y-tiled-8bpp-rotate-180
[534.050901] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[534.188588] [126/131] (489s left) sw_sync (sync_merge_invalid)
[534.220963] Starting subtest: sync_merge_invalid
[534.248827] Subtest sync_merge_invalid: SUCCESS (0.029s)
[534.345563] [127/131] (489s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[534.449333] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[538.929427] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.486s)
[540.113293] [128/131] (483s left) gem_lmem_swapping (parallel-random-verify-ccs)
[540.154505] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[540.202332] [129/131] (483s left) kms_lease (lease-unleased-connector)
[540.303469] Starting subtest: lease-unleased-connector
[540.305699] Starting dynamic subtest: pipe-A-eDP-1
[540.310964] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.008s)
[540.323133] Starting dynamic subtest: pipe-B-eDP-1
[540.325299] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.008s)
[540.335493] Starting dynamic subtest: pipe-C-eDP-1
[540.337658] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.008s)
[540.347322] Starting dynamic subtest: pipe-D-eDP-1
[540.349457] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[540.351530] Subtest lease-unleased-connector: SUCCESS (0.037s)
[540.478589] [130/131] (483s left) perf (enable-disable)
[540.588227] Starting subtest: enable-disable
[540.590644] Starting dynamic subtest: 0-rcs0
[542.868052] Dynamic subtest 0-rcs0: SUCCESS (2.279s)
[542.879151] Starting dynamic subtest: 1-vcs1
[547.353716] Dynamic subtest 1-vcs1: SUCCESS (4.485s)
[547.356227] Subtest enable-disable: SUCCESS (6.766s)
[547.403716] [131/131] (476s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[547.563074] Starting subtest: cursor-rapid-movement-32x32
[547.610270] Subtest cursor-rapid-movement-32x32: SKIP (0.042s)
[547.906779] 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.