[58.991442] Initializing watchdogs
[58.991511]   /dev/watchdog0
[59.005362] [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]
[59.009812] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.011911] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.015460] [001/131] (960s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[59.148949] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[59.153671] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.002s)
[59.275867] [002/131] (960s left) gem_exec_fence (submit3)
[59.462047] Starting subtest: submit3
[59.466515] Subtest submit3: SKIP (0.000s)
[61.024557] [FACT gem_exec_fence (submit3)] new: kernel.kmod_is_loaded.vgem: true
[61.027696] [003/131] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[61.123406] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[61.127655] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[61.264642] [004/131] (958s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[61.378077] Starting subtest: fbc-rgb101010-draw-mmap-wc
[66.167612] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.808s)
[67.411251] [005/131] (952s left) gem_exec_balancer (noheartbeat)
[67.807704] Starting subtest: noheartbeat
[67.812654] Subtest noheartbeat: SKIP (0.000s)
[68.070908] [006/131] (951s left) kms_chamelium_color (ctm-blue-to-red)
[68.157354] Subtest ctm-blue-to-red: SKIP (0.000s)
[68.203575] [007/131] (951s left) gem_workarounds (suspend-resume)
[68.263116] Starting subtest: suspend-resume
[84.169803] Subtest suspend-resume: SUCCESS (4.764s)
[84.316526] [008/131] (935s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[84.428023] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[84.430380] Starting dynamic subtest: pipe-A
[84.495391] Dynamic subtest pipe-A: SUCCESS (0.069s)
[84.505728] Starting dynamic subtest: pipe-B
[85.594361] Dynamic subtest pipe-B: SUCCESS (1.129s)
[85.604433] Starting dynamic subtest: pipe-C
[86.690136] Dynamic subtest pipe-C: SUCCESS (1.124s)
[86.697905] Starting dynamic subtest: pipe-D
[87.796870] Dynamic subtest pipe-D: SUCCESS (1.131s)
[87.799079] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.454s)
[88.958143] [009/131] (930s left) gem_pxp (create-regular-buffer)
[89.076849] Starting subtest: create-regular-buffer
[89.079251] Subtest create-regular-buffer: SUCCESS (0.000s)
[89.204161] [010/131] (930s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[89.262854] Starting subtest: rel-constants-invalid-rel-gen5
[89.265145] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[89.400523] [011/131] (930s left) gem_exec_nop (basic-series)
[89.469627] Starting subtest: basic-series
[105.057994] Subtest basic-series: SUCCESS (15.708s)
[105.329146] [012/131] (914s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[105.440202] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[105.444291] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[105.565789] [013/131] (914s left) kms_psr (fbc-psr-primary-page-flip)
[105.669205] Starting subtest: fbc-psr-primary-page-flip
[105.671212] Starting dynamic subtest: eDP-1
[105.675470] Dynamic subtest eDP-1: SKIP (0.000s)
[105.679711] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[105.763209] [014/131] (914s left) i915_pm_rps (engine-order)
[105.830703] Starting subtest: engine-order
[110.590316] Subtest engine-order: SUCCESS (4.767s)
[110.743455] [015/131] (909s left) gem_softpin (evict-active)
[110.818065] Starting subtest: evict-active
[110.824558] Subtest evict-active: SUCCESS (0.009s)
[110.987252] [016/131] (909s left) syncobj_timeline (wait-delayed-signal)
[111.053998] Starting subtest: wait-delayed-signal
[111.158572] Subtest wait-delayed-signal: SUCCESS (0.102s)
[111.267386] [017/131] (908s left) kms_panel_fitting (atomic-fastset)
[111.366420] Starting subtest: atomic-fastset
[111.368820] Starting dynamic subtest: pipe-A-eDP-1
[111.528813] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.164s)
[111.540270] Starting dynamic subtest: pipe-B-eDP-1
[112.760431] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.233s)
[112.770087] Starting dynamic subtest: pipe-C-eDP-1
[113.953204] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.192s)
[113.963590] Starting dynamic subtest: pipe-D-eDP-1
[115.185655] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.232s)
[115.187943] Subtest atomic-fastset: SUCCESS (3.823s)
[116.462239] [018/131] (903s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[116.558503] Starting subtest: x-tiled-16bpp-rotate-270
[116.578338] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[116.719172] [019/131] (903s left) api_intel_bb (render-ccs)
[116.776994] Starting subtest: render-ccs
[117.170943] Subtest render-ccs: SUCCESS (0.393s)
[117.333165] [020/131] (902s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[117.433968] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[117.436224] Starting dynamic subtest: pipe-A-eDP-1
[117.458804] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[117.469821] Starting dynamic subtest: pipe-B-eDP-1
[117.493136] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[117.502946] Starting dynamic subtest: pipe-C-eDP-1
[117.524812] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[117.533195] Starting dynamic subtest: pipe-D-eDP-1
[117.555528] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[117.559595] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[119.630407] [021/131] (900s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[119.691286] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[119.801984] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.109s)
[119.896454] [022/131] (900s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[120.002280] Starting subtest: psr-2p-primscrn-cur-indfb-move
[120.006438] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[120.131342] [023/131] (900s left) gem_lmem_swapping (heavy-multi)
[120.176937] Subtest heavy-multi: SKIP (0.000s)
[120.215719] [024/131] (900s left) kms_async_flips (test-cursor)
[120.320428] Starting subtest: test-cursor
[120.324924] Subtest test-cursor: SKIP (0.000s)
[121.561443] [025/131] (898s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[126.602305] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[126.604736] Starting dynamic subtest: pipe-A-eDP-1
[127.403692] Dynamic subtest pipe-A-eDP-1: SKIP (0.797s)
[127.414002] Starting dynamic subtest: pipe-B-eDP-1
[128.527569] Dynamic subtest pipe-B-eDP-1: SKIP (1.123s)
[128.531790] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.924s)
[129.753173] [026/131] (890s left) kms_plane_multiple (2x-tiling-4)
[129.851702] Starting subtest: 2x-tiling-4
[129.855600] Subtest 2x-tiling-4: SKIP (0.000s)
[129.950466] [027/131] (890s left) gem_ctx_param (invalid-param-set)
[130.001998] Starting subtest: invalid-param-set
[130.004411] Subtest invalid-param-set: SUCCESS (0.000s)
[130.098907] [028/131] (890s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[130.186012] Starting subtest: bad-pixel-format-y-tiled-ccs
[130.188615] Starting dynamic subtest: pipe-A-eDP-1
[130.211590] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[130.222712] Starting dynamic subtest: pipe-B-eDP-1
[130.243773] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[130.252827] Starting dynamic subtest: pipe-C-eDP-1
[130.275274] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[130.283883] Starting dynamic subtest: pipe-D-eDP-1
[130.305737] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[130.310138] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.003s)
[132.506651] [029/131] (888s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[132.611945] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[132.614199] Starting dynamic subtest: pipe-A
[132.631390] Dynamic subtest pipe-A: SKIP (0.016s)
[132.641403] Starting dynamic subtest: pipe-B
[132.659718] Dynamic subtest pipe-B: SKIP (0.029s)
[132.668306] Starting dynamic subtest: pipe-C
[132.689978] Dynamic subtest pipe-C: SKIP (0.029s)
[132.698590] Starting dynamic subtest: pipe-D
[132.717829] Dynamic subtest pipe-D: SKIP (0.029s)
[132.721553] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.103s)
[132.835294] [030/131] (887s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[132.890520] Starting subtest: multi-wait-all-available-submitted-signaled
[132.892807] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[133.029563] [031/131] (887s left) perf (missing-sample-flags)
[133.175214] Starting subtest: missing-sample-flags
[133.177376] Subtest missing-sample-flags: SUCCESS (0.000s)
[133.215258] [032/131] (887s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[133.314396] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[139.355933] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.045s)
[140.515889] [033/131] (880s left) kms_pm_rpm (system-suspend-idle)
[142.176684] Starting subtest: system-suspend-idle
[165.798773] Subtest system-suspend-idle: SUCCESS (7.853s)
[166.205283] [034/131] (854s left) kms_vblank (wait-forked)
[166.313598] Starting subtest: wait-forked
[166.315962] Starting dynamic subtest: pipe-A-eDP-1
[168.091515] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.799s)
[168.105656] Starting dynamic subtest: pipe-D-eDP-1
[170.603519] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.533s)
[170.605840] Subtest wait-forked: SUCCESS (4.336s)
[171.486309] [035/131] (849s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[171.595075] Starting subtest: y-tiled-32bpp-rotate-0
[171.598994] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[171.752250] [036/131] (849s left) kms_pipe_crc_basic (read-crc)
[171.854141] Starting subtest: read-crc
[171.856336] Starting dynamic subtest: pipe-A-eDP-1
[172.551611] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.702s)
[172.561802] Starting dynamic subtest: pipe-B-eDP-1
[173.912942] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.368s)
[173.922712] Starting dynamic subtest: pipe-C-eDP-1
[175.200691] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.294s)
[175.211101] Starting dynamic subtest: pipe-D-eDP-1
[176.525998] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.330s)
[176.528563] Subtest read-crc: SUCCESS (4.699s)
[177.379664] [037/131] (843s left) kms_cursor_legacy (single-move)
[177.498767] Starting subtest: single-move
[177.500984] Starting dynamic subtest: pipe-A
[182.917580] Dynamic subtest pipe-A: SUCCESS (5.378s)
[182.928621] Starting dynamic subtest: pipe-B
[188.289838] Dynamic subtest pipe-B: SUCCESS (5.381s)
[188.300948] Starting dynamic subtest: pipe-C
[193.667344] Dynamic subtest pipe-C: SUCCESS (5.380s)
[193.678993] Starting dynamic subtest: pipe-D
[199.052322] Dynamic subtest pipe-D: SUCCESS (5.380s)
[199.063530] Starting dynamic subtest: all-pipes
[204.390850] Dynamic subtest all-pipes: SUCCESS (5.387s)
[204.393301] Subtest single-move: SUCCESS (26.914s)
[204.554665] [038/131] (816s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[209.613785] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[209.618315] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[210.480694] [039/131] (810s left) gem_unref_active_buffers
[210.708654] [040/131] (810s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[210.813384] Starting subtest: fbc-psr2-primary-mmap-gtt
[210.815645] Starting dynamic subtest: eDP-1
[210.819656] Dynamic subtest eDP-1: SKIP (0.000s)
[210.823674] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[210.905877] [041/131] (810s left) syncobj_wait (invalid-signal-zero-handles)
[210.962293] Starting subtest: invalid-signal-zero-handles
[210.964551] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[211.097593] [042/131] (810s left) gen9_exec_parse (bb-oversize)
[211.158713] Subtest bb-oversize: SKIP (0.000s)
[211.237332] [043/131] (810s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[211.301966] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[211.409019] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.107s)
[211.510941] [044/131] (809s left) gem_exec_reloc (basic-write-cpu-noreloc)
[211.577958] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[211.661734] [045/131] (809s left) gem_mmap_offset (bad-flags)
[211.719602] Starting subtest: bad-flags
[211.721886] Subtest bad-flags: SUCCESS (0.000s)
[211.865370] [046/131] (809s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[211.990930] Starting subtest: cursor-vs-flip-legacy
[241.204760] Subtest cursor-vs-flip-legacy: SUCCESS (29.214s)
[242.112579] [047/131] (779s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[242.217959] Starting subtest: sprite-rotation-90-pos-100-0
[242.222109] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[242.313715] [048/131] (779s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[242.414539] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[243.390624] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (0.978s)
[243.574528] [049/131] (777s left) gem_create (busy-create)
[243.627596] Starting subtest: busy-create
[243.629839] Starting dynamic subtest: smem0
[276.992521] Dynamic subtest smem0: SUCCESS (33.368s)
[276.995302] Subtest busy-create: SUCCESS (33.369s)
[277.207694] [050/131] (744s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[277.309075] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[281.450253] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.145s)
[282.606568] [051/131] (738s left) perf (invalid-open-flags)
[282.729916] Starting subtest: invalid-open-flags
[282.732264] Subtest invalid-open-flags: SUCCESS (0.000s)
[282.772797] [052/131] (738s left) perf (oa-formats)
[282.868890] Starting subtest: oa-formats
[282.871162] Starting dynamic subtest: 0-rcs0
[282.882554] Dynamic subtest 0-rcs0: SUCCESS (0.014s)
[282.892452] Starting dynamic subtest: 1-vcs0
[282.903741] Dynamic subtest 1-vcs0: SUCCESS (0.021s)
[282.905990] Subtest oa-formats: SUCCESS (0.037s)
[282.945863] [053/131] (738s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[283.046656] Starting subtest: 4-tiled-8bpp-rotate-270
[283.067428] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.020s)
[283.215444] [054/131] (738s left) kms_cursor_crc (cursor-onscreen-64x21)
[283.397573] Starting subtest: cursor-onscreen-64x21
[283.444603] Subtest cursor-onscreen-64x21: SKIP (0.042s)
[283.697394] [055/131] (738s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[283.789573] Starting subtest: primary-4-tiled-reflect-x-180
[290.916105] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.130s)
[292.160020] [056/131] (729s left) kms_cursor_crc (cursor-onscreen-256x256)
[292.400259] Starting subtest: cursor-onscreen-256x256
[292.402553] Starting dynamic subtest: pipe-A-eDP-1
[294.499846] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.096s)
[294.525959] Starting dynamic subtest: pipe-D-eDP-1
[297.307836] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.787s)
[297.310143] Subtest cursor-onscreen-256x256: SUCCESS (4.908s)
[298.212909] [057/131] (723s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[298.314687] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[305.620292] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.310s)
[305.799724] [058/131] (716s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[305.894781] Starting subtest: async-flip-with-page-flip-events-tiled
[306.264013] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[309.076909] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.811s)
[309.088217] Starting dynamic subtest: pipe-A-eDP-1-4
[311.302481] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.173s)
[311.314659] Starting dynamic subtest: pipe-A-eDP-1-4
[313.258694] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[313.272563] Starting dynamic subtest: pipe-A-eDP-1-x
[315.439401] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.177s)
[315.801207] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[318.598288] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.802s)
[318.607404] Starting dynamic subtest: pipe-B-eDP-1-4
[320.772248] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.174s)
[320.781551] Starting dynamic subtest: pipe-B-eDP-1-4
[322.780121] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[322.794528] Starting dynamic subtest: pipe-B-eDP-1-x
[325.010197] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.178s)
[325.326485] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[328.124514] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.808s)
[328.134059] Starting dynamic subtest: pipe-C-eDP-1-4
[330.287244] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.162s)
[330.296758] Starting dynamic subtest: pipe-C-eDP-1-4
[332.295673] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[332.311346] Starting dynamic subtest: pipe-C-eDP-1-x
[334.474033] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[334.834180] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[337.564509] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.738s)
[337.576480] Starting dynamic subtest: pipe-D-eDP-1-4
[339.741023] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[339.751936] Starting dynamic subtest: pipe-D-eDP-1-4
[341.748382] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[341.760270] Starting dynamic subtest: pipe-D-eDP-1-x
[343.928169] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[343.930910] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.044s)
[345.165827] [059/131] (676s left) perf (polling)
[345.275189] Starting subtest: polling
[345.277441] Starting dynamic subtest: 0-rcs0
[355.347645] Dynamic subtest 0-rcs0: SUCCESS (10.026s)
[355.358618] Starting dynamic subtest: 1-vcs1
[365.374422] Dynamic subtest 1-vcs1: SUCCESS (10.020s)
[365.377107] Subtest polling: SUCCESS (20.049s)
[365.415774] [060/131] (656s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[365.534807] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[365.539106] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[365.642586] [061/131] (656s left) syncobj_timeline (reset-signaled)
[365.709350] Starting subtest: reset-signaled
[365.711444] Subtest reset-signaled: SUCCESS (0.000s)
[365.843523] [062/131] (656s left) kms_rmfb (rmfb-ioctl)
[365.935736] Starting subtest: rmfb-ioctl
[365.938016] Starting dynamic subtest: pipe-A-eDP-1
[366.039438] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[366.051150] Starting dynamic subtest: pipe-B-eDP-1
[367.204773] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.163s)
[367.216780] Starting dynamic subtest: pipe-C-eDP-1
[368.333583] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.127s)
[368.352699] Starting dynamic subtest: pipe-D-eDP-1
[369.477513] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.140s)
[369.479868] Subtest rmfb-ioctl: SUCCESS (3.544s)
[370.722058] [063/131] (651s left) gem_userptr_blits (create-destroy-sync)
[370.795771] Starting subtest: create-destroy-sync
[375.860065] Subtest create-destroy-sync: SUCCESS (5.013s)
[375.970897] [064/131] (646s left) gem_exec_reloc (basic-gtt-cpu-active)
[376.035267] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[376.116931] [065/131] (646s left) api_intel_allocator (execbuf-with-allocator)
[376.179960] Starting subtest: execbuf-with-allocator
[376.187306] Subtest execbuf-with-allocator: SUCCESS (0.010s)
[376.357341] [066/131] (645s left) kms_lease (cursor-implicit-plane)
[376.462950] Starting subtest: cursor-implicit-plane
[376.465288] Starting dynamic subtest: pipe-A-eDP-1
[376.924692] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.459s)
[376.934190] Starting dynamic subtest: pipe-B-eDP-1
[378.008859] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.083s)
[378.018744] Starting dynamic subtest: pipe-C-eDP-1
[379.128376] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.118s)
[379.139058] Starting dynamic subtest: pipe-D-eDP-1
[380.264183] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.132s)
[380.266300] Subtest cursor-implicit-plane: SUCCESS (3.800s)
[381.146896] [067/131] (641s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[381.272646] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[381.277047] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[381.441933] [068/131] (640s left) syncobj_wait (invalid-signal-illegal-handle)
[381.497084] Starting subtest: invalid-signal-illegal-handle
[381.499431] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[381.625130] [069/131] (640s left) kms_psr (psr-primary-blt)
[381.713108] Starting subtest: psr-primary-blt
[381.715211] Starting dynamic subtest: eDP-1
[383.414387] Dynamic subtest eDP-1: SUCCESS (1.700s)
[383.416592] Subtest psr-primary-blt: SUCCESS (1.701s)
[384.307484] [070/131] (638s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[384.400048] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[384.402155] Starting dynamic subtest: pipe-A-eDP-1
[384.458976] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[384.470038] Starting dynamic subtest: pipe-B-eDP-1
[384.524985] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[384.535344] Starting dynamic subtest: pipe-C-eDP-1
[384.592021] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[384.601500] Starting dynamic subtest: pipe-D-eDP-1
[384.655126] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[384.659411] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.008s)
[386.562842] [071/131] (635s left) kms_psr (pr-primary-blt)
[386.670672] Starting subtest: pr-primary-blt
[386.675306] Subtest pr-primary-blt: SKIP (0.000s)
[386.767607] [072/131] (635s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[386.879944] Starting subtest: psr-2p-primscrn-spr-indfb-move
[386.883979] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[387.023029] [073/131] (635s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[387.128361] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[387.130560] Starting dynamic subtest: pipe-A-default-mode
[388.305290] Dynamic subtest pipe-A-default-mode: SKIP (1.173s)
[388.309260] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.174s)
[388.513879] [074/131] (634s left) gem_exec_schedule (u-semaphore-noskip)
[388.610965] Starting subtest: u-semaphore-noskip
[389.008213] Subtest u-semaphore-noskip: SUCCESS (0.403s)
[389.403898] [075/131] (633s left) i915_drm_fdinfo (memory-info-idle)
[389.443621] Starting subtest: memory-info-idle
[389.445790] Starting dynamic subtest: smem0
[389.495196] Dynamic subtest smem0: SUCCESS (0.052s)
[389.497667] Subtest memory-info-idle: SUCCESS (0.053s)
[389.593403] [076/131] (633s left) i915_selftest (live)
[393.155542] Starting subtest: live
[393.157806] Starting dynamic subtest: sanitycheck
[397.594046] Dynamic subtest sanitycheck: SUCCESS (4.437s)
[397.603970] Starting dynamic subtest: uncore
[402.031250] Dynamic subtest uncore: SUCCESS (4.434s)
[402.040921] Starting dynamic subtest: workarounds
[420.064730] Dynamic subtest workarounds: FAIL (17.917s)
[420.073089] Starting dynamic subtest: gt_engines
[423.131815] Dynamic subtest gt_engines: SUCCESS (3.179s)
[423.142119] Starting dynamic subtest: gt_timelines
[439.524109] Dynamic subtest gt_timelines: SUCCESS (16.390s)
[439.533972] Starting dynamic subtest: gt_contexts
[444.028783] Dynamic subtest gt_contexts: SUCCESS (4.503s)
[444.045592] Starting dynamic subtest: gt_lrc
[448.483823] Dynamic subtest gt_lrc: SUCCESS (4.453s)
[448.493823] Starting dynamic subtest: gt_mocs
[451.626286] Dynamic subtest gt_mocs: SUCCESS (3.141s)
[451.642027] Starting dynamic subtest: gt_pm
[458.465457] Dynamic subtest gt_pm: SUCCESS (6.837s)
[458.475405] Starting dynamic subtest: gt_heartbeat
[462.889140] Dynamic subtest gt_heartbeat: SUCCESS (4.422s)
[462.899481] Starting dynamic subtest: gt_tlb
[472.888842] Dynamic subtest gt_tlb: SUCCESS (9.998s)
[472.898921] Starting dynamic subtest: requests
[486.285122] Dynamic subtest requests: SUCCESS (13.396s)
[486.295519] Starting dynamic subtest: migrate
[494.407950] Dynamic subtest migrate: SUCCESS (8.122s)
[494.418216] Starting dynamic subtest: active
[498.852498] Dynamic subtest active: SUCCESS (4.443s)
[498.862557] Starting dynamic subtest: objects
[503.675538] Dynamic subtest objects: SUCCESS (4.822s)
[503.685505] Starting dynamic subtest: mman
[508.744030] Dynamic subtest mman: SUCCESS (5.067s)
[508.754358] Starting dynamic subtest: dmabuf
[513.193025] Dynamic subtest dmabuf: SUCCESS (4.448s)
[513.202443] Starting dynamic subtest: vma
[517.632700] Dynamic subtest vma: SUCCESS (4.440s)
[517.643033] Starting dynamic subtest: coherency
[522.774906] Dynamic subtest coherency: SUCCESS (5.137s)
[522.785081] Starting dynamic subtest: gtt
[534.882818] Dynamic subtest gtt: SUCCESS (12.109s)
[534.892389] Starting dynamic subtest: gem
[540.791750] Dynamic subtest gem: SUCCESS (5.911s)
[540.801884] Starting dynamic subtest: evict
[546.298910] Dynamic subtest evict: SUCCESS (5.506s)
[546.309245] Starting dynamic subtest: hugepages
[557.927669] Dynamic subtest hugepages: SUCCESS (11.629s)
[557.937276] Starting dynamic subtest: gem_contexts
[585.831278] Dynamic subtest gem_contexts: SUCCESS (27.908s)
[585.841352] Starting dynamic subtest: client
[588.821019] Dynamic subtest client: SUCCESS (2.989s)
[588.836942] Starting dynamic subtest: gem_migrate
[593.275275] Dynamic subtest gem_migrate: SUCCESS (4.453s)
[593.285359] Starting dynamic subtest: reset
[596.180760] Dynamic subtest reset: SUCCESS (2.904s)
[596.189751] Starting dynamic subtest: memory_region
[598.960007] Dynamic subtest memory_region: SUCCESS (2.778s)
[598.970064] Starting dynamic subtest: hangcheck
[613.222883] Dynamic subtest hangcheck: SUCCESS (14.260s)
[613.232773] Starting dynamic subtest: execlists
[615.994098] Dynamic subtest execlists: SUCCESS (2.765s)
[616.004318] Starting dynamic subtest: ring_submission
[620.473753] Dynamic subtest ring_submission: SUCCESS (4.473s)
[620.484086] Starting dynamic subtest: perf
[624.921234] Dynamic subtest perf: SUCCESS (4.440s)
[624.932070] Starting dynamic subtest: slpc
[639.649994] Dynamic subtest slpc: SUCCESS (14.717s)
[639.660694] Starting dynamic subtest: guc
[643.729996] Dynamic subtest guc: SUCCESS (4.076s)
[643.740448] Starting dynamic subtest: guc_multi_lrc
[648.230733] Dynamic subtest guc_multi_lrc: SUCCESS (4.497s)
[648.240989] Starting dynamic subtest: guc_hang
[653.236035] Dynamic subtest guc_hang: SUCCESS (5.000s)
[653.245814] Starting dynamic subtest: late_gt_pm
[657.763731] Dynamic subtest late_gt_pm: SUCCESS (4.524s)
[657.766542] Subtest live: FAIL (264.602s)
[657.794521] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[657.795198] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[657.798762] [077/131] (364s left) gem_userptr_blits (nohangcheck)
[659.325684] Starting subtest: nohangcheck
[659.558397] Subtest nohangcheck: SUCCESS (0.234s)
[659.635262] [FACT gem_userptr_blits (nohangcheck)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[659.636436] [FACT gem_userptr_blits (nohangcheck)] new: kernel.kmod_is_loaded.i915: true
[659.640105] [078/131] (363s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[659.750576] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[659.752905] Starting dynamic subtest: pipe-A-eDP-1
[660.627643] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.871s)
[660.636444] Starting dynamic subtest: pipe-B-eDP-1
[662.228356] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.597s)
[662.239446] Starting dynamic subtest: pipe-C-eDP-1
[663.697284] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.467s)
[663.707563] Starting dynamic subtest: pipe-D-eDP-1
[665.216432] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.518s)
[665.218710] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.458s)
[666.661480] [079/131] (356s left) gem_softpin (allocator-nopin)
[666.738111] Starting subtest: allocator-nopin
[666.878285] Subtest allocator-nopin: SUCCESS (0.140s)
[667.060535] [080/131] (355s left) kms_draw_crc (draw-method-pwrite)
[667.164514] Starting subtest: draw-method-pwrite
[667.166722] Starting dynamic subtest: xrgb8888-untiled
[667.727077] Dynamic subtest xrgb8888-untiled: SUCCESS (0.562s)
[667.736182] Starting dynamic subtest: rgb565-untiled
[668.148189] Dynamic subtest rgb565-untiled: SUCCESS (0.421s)
[668.160155] Starting dynamic subtest: xrgb2101010-untiled
[668.669662] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.521s)
[668.678991] Starting dynamic subtest: xbgr16161616f-untiled
[669.436952] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.766s)
[669.446517] Starting dynamic subtest: xrgb8888-xtiled
[671.182037] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.744s)
[671.193507] Starting dynamic subtest: rgb565-xtiled
[672.227670] Dynamic subtest rgb565-xtiled: SUCCESS (1.044s)
[672.242420] Starting dynamic subtest: xrgb2101010-xtiled
[673.927006] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.698s)
[673.937128] Starting dynamic subtest: xbgr16161616f-xtiled
[677.039185] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.110s)
[677.048416] Starting dynamic subtest: xrgb8888-4tiled
[678.083780] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.044s)
[678.092954] Starting dynamic subtest: rgb565-4tiled
[678.618002] Dynamic subtest rgb565-4tiled: SUCCESS (0.533s)
[678.627774] Starting dynamic subtest: xrgb2101010-4tiled
[679.650527] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.032s)
[679.659969] Starting dynamic subtest: xbgr16161616f-4tiled
[682.508002] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.855s)
[682.510989] Subtest draw-method-pwrite: SUCCESS (15.342s)
[682.651610] [081/131] (340s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[682.753356] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[682.757578] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[682.893435] [082/131] (339s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[682.991728] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[682.995776] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[683.129288] [083/131] (339s left) syncobj_timeline (host-signal-points)
[683.190518] Starting subtest: host-signal-points
[683.195515] Subtest host-signal-points: SUCCESS (0.004s)
[683.317894] [084/131] (339s left) gem_mmap_gtt (basic-small-bo)
[683.377831] Subtest basic-small-bo: SKIP (0.000s)
[683.453451] [085/131] (339s left) perf_pmu (semaphore-wait-idle)
[683.500840] Starting subtest: semaphore-wait-idle
[683.502772] Starting dynamic subtest: rcs0
[684.017738] Dynamic subtest rcs0: SUCCESS (0.513s)
[684.030048] Starting dynamic subtest: bcs0
[684.530103] Dynamic subtest bcs0: SUCCESS (0.510s)
[684.541414] Starting dynamic subtest: vcs0
[685.044584] Dynamic subtest vcs0: SUCCESS (0.511s)
[685.055303] Starting dynamic subtest: vcs1
[685.556357] Dynamic subtest vcs1: SUCCESS (0.511s)
[685.569262] Starting dynamic subtest: vecs0
[686.073539] Dynamic subtest vecs0: SUCCESS (0.513s)
[686.086321] Starting dynamic subtest: ccs0
[686.589608] Dynamic subtest ccs0: SUCCESS (0.513s)
[686.592380] Subtest semaphore-wait-idle: SUCCESS (3.087s)
[688.014615] [086/131] (335s left) kms_addfb_basic (no-handle)
[688.074923] Starting subtest: no-handle
[688.077351] Subtest no-handle: SUCCESS (0.000s)
[688.280934] [087/131] (334s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[688.381918] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[695.386254] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.006s)
[695.524264] [088/131] (327s left) gem_render_copy (yf-tiled-ccs-to-linear)
[695.594806] Starting subtest: yf-tiled-ccs-to-linear
[695.599345] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[695.804969] [089/131] (327s left) kms_flip (wf_vblank-ts-check)
[695.892770] Starting subtest: wf_vblank-ts-check
[695.895129] Starting dynamic subtest: A-eDP1
[704.885395] Dynamic subtest A-eDP1: SUCCESS (8.956s)
[704.896222] Starting dynamic subtest: B-eDP1
[713.812251] Dynamic subtest B-eDP1: SUCCESS (8.924s)
[713.823220] Starting dynamic subtest: C-eDP1
[722.698225] Dynamic subtest C-eDP1: SUCCESS (8.916s)
[722.709585] Starting dynamic subtest: D-eDP1
[731.633642] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[731.636145] Subtest wf_vblank-ts-check: SUCCESS (35.738s)
[732.882640] [090/131] (290s left) gem_exec_schedule (deep)
[733.065956] Starting subtest: deep
[733.068352] Starting dynamic subtest: rcs0
[737.731318] Dynamic subtest rcs0: SKIP (4.661s)
[737.743295] Starting dynamic subtest: vcs0
[747.915162] Dynamic subtest vcs0: SUCCESS (10.185s)
[747.927990] Starting dynamic subtest: vcs1
[759.112849] Dynamic subtest vcs1: SUCCESS (11.196s)
[759.126707] Starting dynamic subtest: vecs0
[769.813412] Dynamic subtest vecs0: SUCCESS (10.700s)
[769.826190] Starting dynamic subtest: ccs0
[782.010303] Dynamic subtest ccs0: SUCCESS (12.195s)
[782.013995] Subtest deep: SUCCESS (48.942s)
[783.589666] [091/131] (239s left) kms_sequence (queue-idle)
[783.698446] Starting subtest: queue-idle
[783.700366] Starting dynamic subtest: pipe-A-eDP-1
[785.508030] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.809s)
[785.517649] Starting dynamic subtest: pipe-B-eDP-1
[788.015730] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.507s)
[788.025394] Starting dynamic subtest: pipe-C-eDP-1
[790.464925] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.448s)
[790.475503] Starting dynamic subtest: pipe-D-eDP-1
[792.892324] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.425s)
[792.894699] Subtest queue-idle: SUCCESS (9.194s)
[793.731315] [092/131] (229s left) gem_gtt_cpu_tlb
[793.880280] [093/131] (229s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[793.981945] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[800.527581] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.548s)
[800.709776] [094/131] (222s left) gem_eio (create-ext)
[800.937916] Starting subtest: create-ext
[801.263897] Subtest create-ext: SUCCESS (0.325s)
[801.438492] [095/131] (222s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[801.501325] Subtest writes-after-reads-uncached: SKIP (0.000s)
[801.590042] [096/131] (221s left) sysfs_preempt_timeout (idempotent)
[801.655707] Starting subtest: idempotent
[801.657668] Starting dynamic subtest: vecs0
[801.659672] Dynamic subtest vecs0: SUCCESS (0.000s)
[801.671510] Starting dynamic subtest: ccs0
[801.673095] Dynamic subtest ccs0: SUCCESS (0.000s)
[801.682083] Starting dynamic subtest: vcs0
[801.683669] Dynamic subtest vcs0: SUCCESS (0.000s)
[801.692771] Starting dynamic subtest: bcs0
[801.694393] Dynamic subtest bcs0: SUCCESS (0.000s)
[801.703521] Starting dynamic subtest: rcs0
[801.705020] Dynamic subtest rcs0: SUCCESS (0.000s)
[801.714589] Starting dynamic subtest: vcs1
[801.716350] Dynamic subtest vcs1: SUCCESS (0.000s)
[801.717933] Subtest idempotent: SUCCESS (0.006s)
[801.801114] [097/131] (221s left) gem_busy (semaphore)
[801.993877] Subtest semaphore: SKIP (0.000s)
[802.248516] [098/131] (221s left) sriov_basic (enable-vfs-bind-unbind-each)
[802.313082] Starting subtest: enable-vfs-bind-unbind-each
[802.315362] Starting dynamic subtest: numvfs-1
[802.386881] Dynamic subtest numvfs-1: FAIL (0.005s)
[802.397519] Starting dynamic subtest: numvfs-2
[802.466691] Dynamic subtest numvfs-2: FAIL (0.003s)
[802.476597] Starting dynamic subtest: numvfs-3
[802.545420] Dynamic subtest numvfs-3: FAIL (0.003s)
[802.555035] Starting dynamic subtest: numvfs-4
[802.622908] Dynamic subtest numvfs-4: FAIL (0.003s)
[802.633409] Starting dynamic subtest: numvfs-5
[802.702690] Dynamic subtest numvfs-5: FAIL (0.003s)
[802.712183] Starting dynamic subtest: numvfs-6
[802.781980] Dynamic subtest numvfs-6: FAIL (0.004s)
[802.793041] Starting dynamic subtest: numvfs-7
[802.864824] Dynamic subtest numvfs-7: FAIL (0.003s)
[802.875073] Starting dynamic subtest: numvfs-random
[802.947134] Dynamic subtest numvfs-random: FAIL (0.003s)
[802.949151] Subtest enable-vfs-bind-unbind-each: FAIL (0.033s)
[802.992870] [099/131] (220s left) kms_pm_lpsp (screens-disabled)
[804.096854] Starting subtest: screens-disabled
[804.100995] Subtest screens-disabled: SKIP (0.000s)
[804.194134] [100/131] (219s left) gem_exec_capture (capture-invisible)
[804.252263] Starting subtest: capture-invisible
[804.254393] Starting dynamic subtest: smem0
[804.258614] Dynamic subtest smem0: SKIP (0.000s)
[804.262796] Subtest capture-invisible: SKIP (0.001s)
[804.370681] [101/131] (219s left) gem_lmem_swapping (verify-ccs)
[804.412314] Subtest verify-ccs: SKIP (0.000s)
[804.461042] [102/131] (219s left) kms_joiner (basic-force-ultra-joiner)
[804.562944] Starting subtest: basic-force-ultra-joiner
[804.567127] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[804.652389] [103/131] (219s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[804.749323] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[811.267582] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.520s)
[811.405154] [104/131] (212s left) kms_color (legacy-gamma)
[811.508562] Starting subtest: legacy-gamma
[811.510438] Starting dynamic subtest: pipe-A-eDP-1
[812.229252] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.721s)
[812.239751] Starting dynamic subtest: pipe-B-eDP-1
[813.620958] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.391s)
[813.631507] Starting dynamic subtest: pipe-C-eDP-1
[814.926889] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.307s)
[814.938018] Starting dynamic subtest: pipe-D-eDP-1
[816.259583] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[816.261943] Subtest legacy-gamma: SUCCESS (4.753s)
[817.102824] [105/131] (206s left) gem_exec_schedule (wide)
[817.292490] Starting subtest: wide
[817.295281] Starting dynamic subtest: rcs0
[827.257417] Dynamic subtest rcs0: SUCCESS (9.915s)
[827.268827] Starting dynamic subtest: vcs0
[837.974115] Dynamic subtest vcs0: SUCCESS (10.767s)
[837.987101] Starting dynamic subtest: vcs1
[850.358659] Dynamic subtest vcs1: SUCCESS (12.385s)
[850.369101] Starting dynamic subtest: vecs0
[860.366507] Dynamic subtest vecs0: SUCCESS (10.007s)
[860.379839] Starting dynamic subtest: ccs0
[874.977341] Dynamic subtest ccs0: SUCCESS (14.557s)
[874.979748] Subtest wide: SUCCESS (57.637s)
[876.775371] [106/131] (147s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[876.888504] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[876.890736] Starting dynamic subtest: pipe-A-default-mode
[877.175071] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[877.179625] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.285s)
[878.011866] [107/131] (145s left) kms_big_fb (linear-64bpp-rotate-180)
[878.128667] Starting subtest: linear-64bpp-rotate-180
[879.686938] Subtest linear-64bpp-rotate-180: SUCCESS (1.559s)
[879.889849] [108/131] (144s left) gem_ppgtt (blt-vs-render-ctxn)
[879.951318] Starting subtest: blt-vs-render-ctxN
[913.065912] Subtest blt-vs-render-ctxN: SUCCESS (33.108s)
[913.232104] [109/131] (110s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[913.381602] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[913.385669] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[913.495534] [110/131] (110s left) kms_chamelium_frames (dp-frame-dump)
[913.896919] Subtest dp-frame-dump: SKIP (0.000s)
[914.706639] [111/131] (109s left) kms_psr2_su (page_flip-nv12)
[914.772655] Subtest page_flip-NV12: SKIP (0.000s)
[914.849303] [112/131] (109s left) gem_tiling_max_stride
[914.999840] [113/131] (109s left) perf_pmu (busy-idle)
[915.038556] Starting subtest: busy-idle
[915.041143] Starting dynamic subtest: rcs0
[915.769951] Dynamic subtest rcs0: SUCCESS (0.731s)
[915.782409] Starting dynamic subtest: bcs0
[916.438022] Dynamic subtest bcs0: SUCCESS (0.666s)
[916.451379] Starting dynamic subtest: vcs0
[917.933172] Dynamic subtest vcs0: SUCCESS (1.491s)
[917.947128] Starting dynamic subtest: vcs1
[919.917725] Dynamic subtest vcs1: SUCCESS (1.980s)
[919.931304] Starting dynamic subtest: vecs0
[921.965085] Dynamic subtest vecs0: SUCCESS (2.044s)
[921.978318] Starting dynamic subtest: ccs0
[922.640634] Dynamic subtest ccs0: SUCCESS (0.674s)
[922.643100] Subtest busy-idle: SUCCESS (7.603s)
[922.784215] [114/131] (101s left) dmabuf_sync_file (export-before-signal)
[922.834112] Starting subtest: export-before-signal
[922.836043] Subtest export-before-signal: SUCCESS (0.001s)
[922.919676] [115/131] (101s left) gem_exec_reloc (basic-cpu-read)
[922.983042] Subtest basic-cpu-read: SKIP (0.000s)
[923.062513] [116/131] (101s left) kms_hdr (bpc-switch)
[923.161544] Starting subtest: bpc-switch
[924.193792] Starting dynamic subtest: pipe-A-eDP-1
[927.691761] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.497s)
[927.694105] Subtest bpc-switch: SUCCESS (4.532s)
[927.840131] [117/131] ( 96s left) perf (i915-ref-count)
[927.961031] Starting subtest: i915-ref-count
[928.031122] Subtest i915-ref-count: SUCCESS (0.068s)
[928.089778] [118/131] ( 96s left) gem_ctx_isolation (nonpriv)
[928.173525] Starting subtest: nonpriv
[928.175736] Starting dynamic subtest: rcs0
[928.418455] Dynamic subtest rcs0: SUCCESS (0.245s)
[928.429009] Starting dynamic subtest: bcs0
[928.680125] Dynamic subtest bcs0: SUCCESS (0.261s)
[928.690504] Starting dynamic subtest: vcs0
[928.971583] Dynamic subtest vcs0: SUCCESS (0.290s)
[928.981699] Starting dynamic subtest: vcs1
[930.232342] Dynamic subtest vcs1: SUCCESS (1.259s)
[930.245831] Starting dynamic subtest: vecs0
[932.222637] Dynamic subtest vecs0: SUCCESS (1.989s)
[932.233133] Starting dynamic subtest: ccs0
[934.216655] Dynamic subtest ccs0: SUCCESS (1.993s)
[934.218899] Subtest nonpriv: SUCCESS (6.043s)
[934.499908] [119/131] ( 89s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[934.571511] Starting subtest: y-tiled-to-vebox-x-tiled
[934.575752] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[934.779574] [120/131] ( 89s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[934.878165] Starting subtest: plane-use-after-nonblocking-unbind
[934.880427] Starting dynamic subtest: pipe-A-eDP-1
[934.936595] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.060s)
[935.254706] Starting dynamic subtest: pipe-B-eDP-1
[936.060056] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.817s)
[936.345544] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.467s)
[937.240763] [121/131] ( 87s left) i915_hangman (engine-error-state-capture)
[937.324310] Starting subtest: engine-error-state-capture
[937.326747] Starting dynamic subtest: rcs0
[940.939024] Dynamic subtest rcs0: SUCCESS (3.615s)
[940.953298] Starting dynamic subtest: bcs0
[946.892248] Dynamic subtest bcs0: SUCCESS (5.950s)
[946.906101] Starting dynamic subtest: vcs0
[951.946421] Dynamic subtest vcs0: SUCCESS (5.052s)
[951.959057] Starting dynamic subtest: vcs1
[957.835429] Dynamic subtest vcs1: SUCCESS (5.885s)
[957.848647] Starting dynamic subtest: vecs0
[962.826790] Dynamic subtest vecs0: SUCCESS (4.989s)
[962.839576] Starting dynamic subtest: ccs0
[967.883562] Dynamic subtest ccs0: SUCCESS (5.053s)
[967.886385] Subtest engine-error-state-capture: SUCCESS (30.559s)
[968.043178] [122/131] ( 56s left) gem_exec_schedule (pi-shared-iova)
[968.238924] Starting subtest: pi-shared-iova
[968.242200] Starting dynamic subtest: rcs0
[968.358224] Dynamic subtest rcs0: SUCCESS (0.120s)
[968.372963] Starting dynamic subtest: vcs0
[968.455139] Dynamic subtest vcs0: SUCCESS (0.098s)
[968.468069] Starting dynamic subtest: vcs1
[968.544050] Dynamic subtest vcs1: SUCCESS (0.087s)
[968.557057] Starting dynamic subtest: vecs0
[968.631854] Dynamic subtest vecs0: SUCCESS (0.088s)
[968.649706] Starting dynamic subtest: ccs0
[968.784859] Dynamic subtest ccs0: SUCCESS (0.151s)
[968.787364] Subtest pi-shared-iova: SUCCESS (0.548s)
[970.108235] [123/131] ( 54s left) syncobj_wait (invalid-reset-illegal-handle)
[970.157995] Starting subtest: invalid-reset-illegal-handle
[970.160319] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[970.294897] [124/131] ( 54s left) syncobj_wait (single-wait-all-for-submit-signaled)
[970.358115] Starting subtest: single-wait-all-for-submit-signaled
[970.360486] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[970.501834] [125/131] ( 54s left) kms_cursor_crc (cursor-sliding-512x170)
[970.787237] Starting subtest: cursor-sliding-512x170
[970.791675] Subtest cursor-sliding-512x170: SKIP (0.001s)
[970.968711] [126/131] ( 53s left) kms_chamelium_hpd (hdmi-hpd-fast)
[971.355521] Subtest hdmi-hpd-fast: SKIP (0.000s)
[972.123242] [127/131] ( 52s left) gem_ctx_persistence (hostile)
[972.191211] Starting subtest: hostile
[982.350990] Subtest hostile: SUCCESS (10.156s)
[982.518511] [128/131] ( 42s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[982.631453] Starting subtest: fbc-rgb565-draw-blt
[986.799550] Subtest fbc-rgb565-draw-blt: SUCCESS (4.171s)
[987.962972] [129/131] ( 36s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[988.068842] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[997.354426] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.287s)
[997.541732] [130/131] ( 27s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[997.634249] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[997.638637] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[997.764558] [131/131] ( 27s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[997.854767] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[997.859380] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[997.958425] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: gem_exec_fence
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_exec_balancer
results: parsing output: 5/ for test: kms_chamelium_color
results: parsing output: 6/ for test: gem_workarounds
results: parsing output: 7/ for test: kms_plane_scaling
results: parsing output: 8/ for test: gem_pxp
results: parsing output: 9/ for test: gem_exec_params
results: parsing output: 10/ for test: gem_exec_nop
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: i915_pm_rps
results: parsing output: 14/ for test: gem_softpin
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: kms_panel_fitting
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: api_intel_bb
results: parsing output: 19/ for test: kms_ccs
results: parsing output: 20/ for test: syncobj_wait
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_lmem_swapping
results: parsing output: 23/ for test: kms_async_flips
results: parsing output: 24/ for test: kms_psr2_sf
results: parsing output: 25/ for test: kms_plane_multiple
results: parsing output: 26/ for test: gem_ctx_param
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_plane_scaling
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: perf
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_pm_rpm
results: parsing output: 33/ for test: kms_vblank
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: kms_pipe_crc_basic
results: parsing output: 36/ for test: kms_cursor_legacy
results: parsing output: 37/ for test: kms_psr2_sf
results: parsing output: 38/ for test: gem_unref_active_buffers
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: syncobj_wait
results: parsing output: 41/ for test: gen9_exec_parse
results: parsing output: 42/ for test: syncobj_timeline
results: parsing output: 43/ for test: gem_exec_reloc
results: parsing output: 44/ for test: gem_mmap_offset
results: parsing output: 45/ for test: kms_cursor_legacy
results: parsing output: 46/ for test: kms_rotation_crc
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: gem_create
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: perf
results: parsing output: 51/ for test: perf
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_cursor_crc
results: parsing output: 54/ for test: kms_rotation_crc
results: parsing output: 55/ for test: kms_cursor_crc
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_async_flips
results: parsing output: 58/ for test: perf
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_rmfb
results: parsing output: 62/ for test: gem_userptr_blits
results: parsing output: 63/ for test: gem_exec_reloc
results: parsing output: 64/ for test: api_intel_allocator
results: parsing output: 65/ for test: kms_lease
results: parsing output: 66/ for test: kms_cursor_legacy
results: parsing output: 67/ for test: syncobj_wait
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_flip_scaled_crc
results: parsing output: 73/ for test: gem_exec_schedule
results: parsing output: 74/ for test: i915_drm_fdinfo
results: parsing output: 75/ for test: i915_selftest
results: parsing output: 76/ for test: gem_userptr_blits
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: gem_softpin
results: parsing output: 79/ for test: kms_draw_crc
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: gem_mmap_gtt
results: parsing output: 84/ for test: perf_pmu
results: parsing output: 85/ for test: kms_addfb_basic
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_render_copy
results: parsing output: 88/ for test: kms_flip
results: parsing output: 89/ for test: gem_exec_schedule
results: parsing output: 90/ for test: kms_sequence
results: parsing output: 91/ for test: gem_gtt_cpu_tlb
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_eio
results: parsing output: 94/ for test: gem_partial_pwrite_pread
results: parsing output: 95/ for test: sysfs_preempt_timeout
results: parsing output: 96/ for test: gem_busy
results: parsing output: 97/ for test: sriov_basic
results: parsing output: 98/ for test: kms_pm_lpsp
results: parsing output: 99/ for test: gem_exec_capture
results: parsing output: 100/ for test: gem_lmem_swapping
results: parsing output: 101/ for test: kms_joiner
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_color
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: kms_flip_scaled_crc
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: gem_ppgtt
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_chamelium_frames
results: parsing output: 110/ for test: kms_psr2_su
results: parsing output: 111/ for test: gem_tiling_max_stride
results: parsing output: 112/ for test: perf_pmu
results: parsing output: 113/ for test: dmabuf_sync_file
results: parsing output: 114/ for test: gem_exec_reloc
results: parsing output: 115/ for test: kms_hdr
results: parsing output: 116/ for test: perf
results: parsing output: 117/ for test: gem_ctx_isolation
results: parsing output: 118/ for test: gem_render_copy
results: parsing output: 119/ for test: kms_atomic_transition
results: parsing output: 120/ for test: i915_hangman
results: parsing output: 121/ for test: gem_exec_schedule
results: parsing output: 122/ for test: syncobj_wait
results: parsing output: 123/ for test: syncobj_wait
results: parsing output: 124/ for test: kms_cursor_crc
results: parsing output: 125/ for test: kms_chamelium_hpd
results: parsing output: 126/ for test: gem_ctx_persistence
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_frontbuffer_tracking
Done.