[57.476114] Initializing watchdogs
[57.476159]   /dev/watchdog0
[57.489566] [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.494228] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.496353] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.499743] [001/132] (960s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[57.636412] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[64.668017] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (7.034s)
[64.777129] [002/132] (953s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[64.835811] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[64.941843] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.105s)
[65.023446] [003/132] (953s left) syncobj_wait (invalid-reset-zero-handles)
[65.076951] Starting subtest: invalid-reset-zero-handles
[65.079042] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[65.160295] [004/132] (952s left) gem_render_copy (linear-to-vebox-yf-tiled)
[65.220288] Starting subtest: linear-to-vebox-yf-tiled
[65.224499] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[65.405254] [005/132] (952s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[65.496923] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[72.852952] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.359s)
[72.983821] [006/132] (945s left) gem_exec_params (sol-reset-invalid)
[73.046066] Starting subtest: sol-reset-invalid
[73.048493] Subtest sol-reset-invalid: SUCCESS (0.002s)
[74.946235] [007/132] (943s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[75.061682] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[75.066234] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[75.179228] [008/132] (943s left) gem_exec_params (rsvd2-dirt)
[75.249117] Starting subtest: rsvd2-dirt
[75.253678] Subtest rsvd2-dirt: SKIP (0.000s)
[75.345181] [009/132] (942s left) kms_async_flips (async-flip-dpms)
[75.439868] Starting subtest: async-flip-dpms
[75.827643] Starting dynamic subtest: pipe-A-eDP-1
[79.775728] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.948s)
[80.166855] Starting dynamic subtest: pipe-B-eDP-1
[83.029775] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.870s)
[83.422927] Starting dynamic subtest: pipe-C-eDP-1
[86.197714] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.782s)
[86.579616] Starting dynamic subtest: pipe-D-eDP-1
[89.365068] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.792s)
[89.367265] Subtest async-flip-dpms: SUCCESS (13.926s)
[90.536942] [010/132] (927s left) sysfs_preempt_timeout (invalid)
[90.595131] Starting subtest: invalid
[90.597580] Starting dynamic subtest: vecs0
[90.599980] Dynamic subtest vecs0: SUCCESS (0.000s)
[90.608391] Starting dynamic subtest: ccs0
[90.610454] Dynamic subtest ccs0: SUCCESS (0.000s)
[90.618728] Starting dynamic subtest: vcs0
[90.621015] Dynamic subtest vcs0: SUCCESS (0.000s)
[90.628061] Starting dynamic subtest: bcs0
[90.630180] Dynamic subtest bcs0: SUCCESS (0.000s)
[90.637095] Starting dynamic subtest: rcs0
[90.639190] Dynamic subtest rcs0: SUCCESS (0.000s)
[90.646320] Starting dynamic subtest: vcs1
[90.648219] Dynamic subtest vcs1: SUCCESS (0.000s)
[90.650105] Subtest invalid: SUCCESS (0.003s)
[90.702460] [011/132] (927s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[90.808414] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[90.810608] Starting dynamic subtest: pipe-A-eDP-1
[90.904019] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[90.913531] Starting dynamic subtest: pipe-B-eDP-1
[92.029618] Dynamic subtest pipe-B-eDP-1: SKIP (1.124s)
[92.033882] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.225s)
[93.213371] [012/132] (925s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[93.310904] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[93.315189] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[93.377085] [013/132] (925s left) kms_plane_alpha_blend (alpha-transparent-fb)
[93.460345] Starting subtest: alpha-transparent-fb
[93.750961] Starting dynamic subtest: pipe-A-eDP-1
[94.375795] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.623s)
[94.385079] Starting dynamic subtest: pipe-D-eDP-1
[95.784415] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.408s)
[95.786729] Subtest alpha-transparent-fb: SUCCESS (2.322s)
[96.596580] [014/132] (921s left) i915_pm_rps (waitboost)
[96.656713] Starting subtest: waitboost
[102.993942] Subtest waitboost: SUCCESS (6.335s)
[103.066607] [015/132] (915s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[103.175678] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[103.179978] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[103.270632] [016/132] (915s left) kms_prime (basic-crc-vgem)
[103.401464] Starting subtest: basic-crc-vgem
[103.403756] Starting dynamic subtest: second-to-first
[104.385220] Dynamic subtest second-to-first: SUCCESS (0.986s)
[104.387329] Subtest basic-crc-vgem: SUCCESS (0.986s)
[104.474653] [FACT kms_prime (basic-crc-vgem)] new: kernel.kmod_is_loaded.vgem: true
[104.477642] [017/132] (914s left) gem_exec_reloc (basic-write-gtt-noreloc)
[104.581896] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[104.645158] [018/132] (914s left) syncobj_timeline (single-wait-signaled)
[104.689158] Starting subtest: single-wait-signaled
[104.691050] Subtest single-wait-signaled: SUCCESS (0.000s)
[104.788695] [019/132] (913s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[104.889331] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[104.891309] Starting dynamic subtest: pipe-A-eDP-1
[104.939982] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[104.947843] Starting dynamic subtest: pipe-B-eDP-1
[104.994912] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[105.002820] Starting dynamic subtest: pipe-C-eDP-1
[105.051611] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[105.060386] Starting dynamic subtest: pipe-D-eDP-1
[105.107154] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[105.110893] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.007s)
[107.072770] [020/132] (911s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[107.160483] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[107.164571] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[107.261350] [021/132] (911s left) gem_exec_parallel (engines)
[107.333276] Starting subtest: engines
[107.335707] Starting dynamic subtest: basic
[108.910952] Dynamic subtest basic: SUCCESS (1.577s)
[108.919188] Starting dynamic subtest: contexts
[113.241796] Dynamic subtest contexts: SUCCESS (4.328s)
[113.252699] Starting dynamic subtest: fds
[117.013061] Dynamic subtest fds: SUCCESS (3.770s)
[117.022345] Starting dynamic subtest: userptr
[118.872266] Dynamic subtest userptr: SUCCESS (1.857s)
[118.874938] Subtest engines: SUCCESS (11.537s)
[119.061066] [022/132] (899s left) gem_exec_balancer (invalid-balancer)
[119.119059] Starting subtest: invalid-balancer
[120.016625] Subtest invalid-balancer: SUCCESS (0.898s)
[120.301604] [023/132] (898s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[120.411591] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[120.413883] Starting dynamic subtest: pipe-A-eDP-1
[120.435339] Dynamic subtest pipe-A-eDP-1: SKIP (0.002s)
[120.443322] Starting dynamic subtest: pipe-B-eDP-1
[120.464944] Dynamic subtest pipe-B-eDP-1: SKIP (0.002s)
[120.474100] Starting dynamic subtest: pipe-C-eDP-1
[120.494813] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[120.501716] Starting dynamic subtest: pipe-D-eDP-1
[120.523596] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[120.527791] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.014s)
[122.731563] [024/132] (896s left) gem_exec_create (forked)
[122.795733] Starting subtest: forked
[122.797958] Starting dynamic subtest: smem
[142.989952] Dynamic subtest smem: SUCCESS (20.194s)
[142.992281] Subtest forked: SUCCESS (20.196s)
[143.173317] [025/132] (875s left) tools_test (sysfs_l3_parity)
[143.223256] Starting subtest: sysfs_l3_parity
[143.278060] Subtest sysfs_l3_parity: SKIP (0.063s)
[143.345904] [026/132] (875s left) kms_plane_cursor (viewport)
[143.450794] Starting subtest: viewport
[143.455111] Starting dynamic subtest: pipe-A-eDP-1-size-64
[145.913464] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.463s)
[145.972080] Starting dynamic subtest: pipe-A-eDP-1-size-128
[148.581759] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.615s)
[148.641963] Starting dynamic subtest: pipe-A-eDP-1-size-256
[151.171567] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.538s)
[151.517614] Starting dynamic subtest: pipe-B-eDP-1-size-64
[154.742261] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.234s)
[154.803614] Starting dynamic subtest: pipe-B-eDP-1-size-128
[157.276467] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.482s)
[157.335896] Starting dynamic subtest: pipe-B-eDP-1-size-256
[159.877490] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.549s)
[160.230557] Starting dynamic subtest: pipe-C-eDP-1-size-64
[163.155945] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.933s)
[163.191889] Starting dynamic subtest: pipe-C-eDP-1-size-128
[165.390912] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.205s)
[165.429765] Starting dynamic subtest: pipe-C-eDP-1-size-256
[167.545506] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.125s)
[167.875828] Starting dynamic subtest: pipe-D-eDP-1-size-64
[170.768450] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.902s)
[170.803607] Starting dynamic subtest: pipe-D-eDP-1-size-128
[172.912353] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.114s)
[172.950595] Starting dynamic subtest: pipe-D-eDP-1-size-256
[175.047333] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.103s)
[175.366479] Subtest viewport: SUCCESS (31.913s)
[176.157898] [027/132] (842s left) gem_exec_reloc (basic-write-gtt)
[176.235853] Subtest basic-write-gtt: SKIP (0.000s)
[176.307191] [028/132] (842s left) api_intel_bb (blit-reloc-keep-cache)
[176.360226] Starting subtest: blit-reloc-keep-cache
[176.364577] Subtest blit-reloc-keep-cache: SKIP (0.001s)
[176.462095] [029/132] (842s left) kms_chamelium_audio (dp-audio)
[176.832523] Subtest dp-audio: SKIP (0.000s)
[177.659249] [030/132] (841s left) kms_selftest (drm_cmdline_parser)
[177.694930] Starting subtest: drm_cmdline_parser
[177.771421] Starting dynamic subtest: drm_test_cmdline_force_d_only
[177.874283] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.103s)
[177.881905] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[177.979147] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.104s)
[177.986631] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[178.070466] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.089s)
[178.077859] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[178.165417] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.096s)
[178.172555] Starting dynamic subtest: drm_test_cmdline_force_e_only
[178.267061] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.100s)
[178.276046] Starting dynamic subtest: drm_test_cmdline_res
[178.372466] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.106s)
[178.382173] Starting dynamic subtest: drm_test_cmdline_res_vesa
[178.480109] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.106s)
[178.487372] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[178.575309] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.094s)
[178.584307] Starting dynamic subtest: drm_test_cmdline_res_rblank
[178.655255] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.079s)
[178.664059] Starting dynamic subtest: drm_test_cmdline_res_bpp
[178.752915] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.096s)
[178.761602] Starting dynamic subtest: drm_test_cmdline_res_refresh
[178.846953] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.093s)
[178.855433] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[178.941551] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.093s)
[178.948601] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[179.035522] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.094s)
[179.042695] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[179.123281] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.087s)
[179.130281] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[179.207605] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.083s)
[179.216054] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[179.309704] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.101s)
[179.318308] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[179.415439] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.102s)
[179.424446] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[179.506512] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.090s)
[179.515299] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[179.612327] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.105s)
[179.620328] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[179.712556] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.100s)
[179.720701] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[179.810554] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.097s)
[179.818783] Starting dynamic subtest: drm_test_cmdline_name
[179.921365] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.110s)
[179.929532] Starting dynamic subtest: drm_test_cmdline_name_bpp
[180.036450] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.112s)
[180.045581] Starting dynamic subtest: drm_test_cmdline_name_option
[180.132462] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.096s)
[180.141300] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[180.226256] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.094s)
[180.235055] Starting dynamic subtest: drm_test_cmdline_rotate_0
[180.312164] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.086s)
[180.319545] Starting dynamic subtest: drm_test_cmdline_rotate_90
[180.413461] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.100s)
[180.422422] Starting dynamic subtest: drm_test_cmdline_rotate_180
[180.513152] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.099s)
[180.521675] Starting dynamic subtest: drm_test_cmdline_rotate_270
[180.602036] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.088s)
[180.610881] Starting dynamic subtest: drm_test_cmdline_hmirror
[180.704380] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.102s)
[180.712080] Starting dynamic subtest: drm_test_cmdline_vmirror
[180.793168] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.089s)
[180.800872] Starting dynamic subtest: drm_test_cmdline_margin_options
[180.892266] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.100s)
[180.899715] Starting dynamic subtest: drm_test_cmdline_multiple_options
[180.979301] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.084s)
[180.986804] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[181.073797] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.094s)
[181.080956] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[181.159935] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.085s)
[181.167079] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[181.260685] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.101s)
[181.267766] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[181.352386] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.090s)
[181.360754] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[181.453577] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.100s)
[181.462102] Starting dynamic subtest: drm_test_cmdline_invalid
[181.569009] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.116s)
[181.576891] Starting dynamic subtest: drm_test_cmdline_tv_options
[181.666080] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.094s)
[181.667735] Subtest drm_cmdline_parser: SUCCESS (3.974s)
[181.823268] [031/132] (837s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[181.942885] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[181.946490] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[182.038012] [032/132] (837s left) kms_flip (flip-vs-rmfb-interruptible)
[182.122922] Starting subtest: flip-vs-rmfb-interruptible
[182.125049] Starting dynamic subtest: A-eDP1
[190.762349] Dynamic subtest A-eDP1: SUCCESS (8.638s)
[190.773193] Starting dynamic subtest: B-eDP1
[199.391214] Dynamic subtest B-eDP1: SUCCESS (8.625s)
[199.402749] Starting dynamic subtest: C-eDP1
[208.016968] Dynamic subtest C-eDP1: SUCCESS (8.623s)
[208.028049] Starting dynamic subtest: D-eDP1
[216.648956] Dynamic subtest D-eDP1: SUCCESS (8.629s)
[216.653934] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.531s)
[217.835614] [033/132] (801s left) kms_plane_scaling (invalid-parameters)
[217.928161] Starting subtest: invalid-parameters
[217.930473] Starting dynamic subtest: less-than-1-height-src
[217.944227] Dynamic subtest less-than-1-height-src: SUCCESS (0.016s)
[217.952603] Starting dynamic subtest: less-than-1-width-src
[217.958049] Dynamic subtest less-than-1-width-src: SUCCESS (0.013s)
[217.960474] Subtest invalid-parameters: SUCCESS (0.029s)
[218.068881] [034/132] (801s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[218.130372] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[218.135039] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[218.311903] [035/132] (801s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[218.409584] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[218.414783] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.000s)
[218.485196] [036/132] (800s left) gem_create (create-massive)
[218.538930] Starting subtest: create-massive
[218.541384] Subtest create-massive: SUCCESS (0.000s)
[218.735572] [037/132] (800s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[218.857325] Starting subtest: cursorA-vs-flipA-legacy
[230.021503] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.162s)
[230.863190] [038/132] (788s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[230.977357] Starting subtest: bo-too-small-due-to-tiling
[230.981321] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[231.078449] [039/132] (788s left) gem_exec_whisper (basic-fds-all)
[231.141348] Starting subtest: basic-fds-all
[242.380672] Subtest basic-fds-all: SUCCESS (11.235s)
[242.563307] [040/132] (776s left) kms_cursor_crc (cursor-onscreen-256x85)
[242.824870] Starting subtest: cursor-onscreen-256x85
[242.870677] Subtest cursor-onscreen-256x85: SKIP (0.042s)
[243.047935] [041/132] (776s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[243.132108] Starting subtest: primary-y-tiled-reflect-x-180
[243.203548] Subtest primary-y-tiled-reflect-x-180: SKIP (0.071s)
[243.307099] [042/132] (776s left) kms_content_protection (mei-interface)
[243.451161] Starting subtest: mei-interface
[243.455915] Subtest mei-interface: SKIP (0.001s)
[243.578073] [043/132] (776s left) gen9_exec_parse (shadow-peek)
[243.639298] Subtest shadow-peek: SKIP (0.000s)
[243.712825] [044/132] (775s left) gem_flink_race (flink_close)
[243.743072] Starting subtest: flink_close
[248.833314] Subtest flink_close: SUCCESS (5.089s)
[248.910983] [045/132] (770s left) kms_rotation_crc (multiplane-rotation)
[249.041162] Starting subtest: multiplane-rotation
[266.510673] Subtest multiplane-rotation: SUCCESS (17.471s)
[266.629239] [046/132] (753s left) gem_exec_params (invalid-ring)
[266.688206] Starting subtest: invalid-ring
[266.689762] Subtest invalid-ring: SUCCESS (0.000s)
[266.806230] [047/132] (753s left) gem_exec_schedule (smoketest)
[266.991900] Starting subtest: smoketest
[266.994234] Starting dynamic subtest: rcs0
[272.537819] Dynamic subtest rcs0: SUCCESS (5.496s)
[272.548373] Starting dynamic subtest: bcs0
[278.061381] Dynamic subtest bcs0: SUCCESS (5.522s)
[278.071224] Starting dynamic subtest: vcs0
[283.559811] Dynamic subtest vcs0: SUCCESS (5.497s)
[283.572629] Starting dynamic subtest: vcs1
[289.064480] Dynamic subtest vcs1: SUCCESS (5.498s)
[289.079883] Starting dynamic subtest: vecs0
[294.560584] Dynamic subtest vecs0: SUCCESS (5.494s)
[294.581146] Starting dynamic subtest: ccs0
[300.061898] Dynamic subtest ccs0: SUCCESS (5.503s)
[300.064271] Subtest smoketest: SUCCESS (33.017s)
[300.194936] [048/132] (719s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[300.284976] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[300.287248] Starting dynamic subtest: pipe-A
[300.306904] Dynamic subtest pipe-A: SKIP (0.017s)
[300.315446] Starting dynamic subtest: pipe-B
[300.336502] Dynamic subtest pipe-B: SKIP (0.030s)
[300.345131] Starting dynamic subtest: pipe-C
[300.368738] Dynamic subtest pipe-C: SKIP (0.030s)
[300.377236] Starting dynamic subtest: pipe-D
[300.399561] Dynamic subtest pipe-D: SKIP (0.030s)
[300.403748] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.109s)
[300.499853] [049/132] (719s left) gem_exec_schedule (implicit-write-read)
[300.567730] Starting subtest: implicit-write-read
[300.569613] Starting dynamic subtest: rcs0
[300.672067] Dynamic subtest rcs0: SUCCESS (0.114s)
[300.682213] Starting dynamic subtest: vcs0
[300.765051] Dynamic subtest vcs0: SUCCESS (0.092s)
[300.773817] Starting dynamic subtest: vcs1
[300.846743] Dynamic subtest vcs1: SUCCESS (0.079s)
[300.857837] Starting dynamic subtest: vecs0
[300.925801] Dynamic subtest vecs0: SUCCESS (0.079s)
[300.935575] Starting dynamic subtest: ccs0
[301.012456] Dynamic subtest ccs0: SUCCESS (0.086s)
[301.014772] Subtest implicit-write-read: SUCCESS (0.455s)
[301.995062] [050/132] (717s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[302.093380] Starting subtest: yf-tiled-64bpp-rotate-270
[302.097560] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[302.183731] [051/132] (717s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[302.253156] Starting subtest: multi-wait-available-submitted-signaled
[302.255761] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[302.345847] [052/132] (717s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[302.436091] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[302.440129] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[302.507704] [053/132] (717s left) kms_big_fb (x-tiled-addfb-size-offset-overflow)
[302.606863] Starting subtest: x-tiled-addfb-size-offset-overflow
[302.609267] Subtest x-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[302.714396] [054/132] (717s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[302.814492] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[302.818601] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[302.922857] [055/132] (717s left) gem_pxp (create-regular-buffer)
[303.051308] Starting subtest: create-regular-buffer
[303.053501] Subtest create-regular-buffer: SUCCESS (0.000s)
[303.149869] [056/132] (717s left) kms_psr (fbc-psr2-cursor-plane-move)
[303.254353] Starting subtest: fbc-psr2-cursor-plane-move
[303.256305] Starting dynamic subtest: eDP-1
[303.259855] Dynamic subtest eDP-1: SKIP (0.000s)
[303.263511] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[303.325745] [057/132] (716s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[303.415092] Starting subtest: plane-ctm3x4-lut1d
[303.594943] Starting dynamic subtest: pipe-A-plane-0
[303.880649] Dynamic subtest pipe-A-plane-0: FAIL (0.152s)
[303.891285] Starting dynamic subtest: pipe-A-plane-1
[304.084611] Dynamic subtest pipe-A-plane-1: FAIL (0.166s)
[304.096766] Starting dynamic subtest: pipe-A-plane-2
[304.295329] Dynamic subtest pipe-A-plane-2: FAIL (0.179s)
[305.345391] Starting dynamic subtest: pipe-B-plane-0
[305.756603] Dynamic subtest pipe-B-plane-0: FAIL (0.141s)
[305.767411] Starting dynamic subtest: pipe-B-plane-1
[306.077278] Dynamic subtest pipe-B-plane-1: FAIL (0.166s)
[306.086627] Starting dynamic subtest: pipe-B-plane-2
[306.390677] Dynamic subtest pipe-B-plane-2: FAIL (0.166s)
[306.976184] Starting dynamic subtest: pipe-C-plane-0
[307.466706] Dynamic subtest pipe-C-plane-0: FAIL (0.129s)
[307.476910] Starting dynamic subtest: pipe-C-plane-1
[307.876551] Dynamic subtest pipe-C-plane-1: FAIL (0.157s)
[307.884083] Starting dynamic subtest: pipe-C-plane-2
[308.245808] Dynamic subtest pipe-C-plane-2: FAIL (0.165s)
[309.117610] Starting dynamic subtest: pipe-D-plane-0
[309.691208] Dynamic subtest pipe-D-plane-0: FAIL (0.128s)
[309.706067] Starting dynamic subtest: pipe-D-plane-1
[310.167744] Dynamic subtest pipe-D-plane-1: FAIL (0.606s)
[310.177502] Starting dynamic subtest: pipe-D-plane-2
[310.615877] Dynamic subtest pipe-D-plane-2: FAIL (0.549s)
[310.698242] Subtest plane-ctm3x4-lut1d: FAIL (7.283s)
[311.490010] [058/132] (708s left) perf_pmu (busy-start)
[311.531182] Starting subtest: busy-start
[311.533526] Starting dynamic subtest: rcs0
[314.083447] Dynamic subtest rcs0: SUCCESS (2.551s)
[314.095021] Starting dynamic subtest: bcs0
[316.632344] Dynamic subtest bcs0: SUCCESS (2.546s)
[316.645640] Starting dynamic subtest: vcs0
[319.895736] Dynamic subtest vcs0: SUCCESS (3.258s)
[319.907191] Starting dynamic subtest: vcs1
[323.927094] Dynamic subtest vcs1: SUCCESS (4.028s)
[323.938839] Starting dynamic subtest: vecs0
[327.895253] Dynamic subtest vecs0: SUCCESS (3.965s)
[327.906729] Starting dynamic subtest: ccs0
[330.444602] Dynamic subtest ccs0: SUCCESS (2.548s)
[330.447271] Subtest busy-start: SUCCESS (18.914s)
[330.573971] [059/132] (689s left) i915_hangman (hangcheck-unterminated)
[330.644551] Starting subtest: hangcheck-unterminated
[341.878509] Subtest hangcheck-unterminated: SUCCESS (11.231s)
[341.992118] [060/132] (678s left) gen7_exec_parse (batch-without-end)
[342.070358] Subtest batch-without-end: SKIP (0.000s)
[342.153391] [061/132] (678s left) gem_mmap_offset (bad-object)
[342.204164] Starting subtest: bad-object
[342.206458] Subtest bad-object: SUCCESS (0.001s)
[342.310541] [062/132] (678s left) tools_test (tools_test)
[342.358932] Starting subtest: tools_test
[342.406314] Subtest tools_test: SUCCESS (0.062s)
[342.472972] [063/132] (678s left) kms_invalid_mode (int-max-clock)
[342.566642] Starting subtest: int-max-clock
[342.568885] Starting dynamic subtest: pipe-A-eDP-1
[342.855532] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[342.864993] Starting dynamic subtest: pipe-B-eDP-1
[342.866966] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[342.875590] Starting dynamic subtest: pipe-C-eDP-1
[342.877995] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[342.886424] Starting dynamic subtest: pipe-D-eDP-1
[342.888392] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[342.890389] Subtest int-max-clock: SUCCESS (0.297s)
[343.721926] [064/132] (676s left) gem_close (many-handles-one-vma)
[343.781351] Starting subtest: many-handles-one-vma
[343.805526] Subtest many-handles-one-vma: SUCCESS (0.024s)
[343.919290] [065/132] (676s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[344.016481] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[344.020700] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[344.083357] [066/132] (676s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[344.182148] Starting subtest: fbc-rgb565-draw-render
[348.362583] Subtest fbc-rgb565-draw-render: SUCCESS (4.186s)
[349.510034] [067/132] (671s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[349.603456] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[349.607539] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[349.700872] [068/132] (670s left) kms_atomic_interruptible (universal-setplane-cursor)
[349.800089] Starting subtest: universal-setplane-cursor
[349.802261] Starting dynamic subtest: pipe-A-eDP-1
[357.222439] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.421s)
[357.224685] Subtest universal-setplane-cursor: SUCCESS (7.423s)
[358.061996] [069/132] (662s left) gem_ctx_persistence (engines-mixed)
[358.121132] Starting subtest: engines-mixed
[358.123050] Starting dynamic subtest: rcs0
[358.190655] Dynamic subtest rcs0: SUCCESS (0.072s)
[358.204068] Starting dynamic subtest: bcs0
[358.243522] Dynamic subtest bcs0: SUCCESS (0.051s)
[358.260082] Starting dynamic subtest: vcs0
[358.291676] Dynamic subtest vcs0: SUCCESS (0.047s)
[358.304719] Starting dynamic subtest: vcs1
[358.338652] Dynamic subtest vcs1: SUCCESS (0.044s)
[358.349641] Starting dynamic subtest: vecs0
[358.383752] Dynamic subtest vecs0: SUCCESS (0.043s)
[358.397687] Starting dynamic subtest: ccs0
[358.431517] Dynamic subtest ccs0: SUCCESS (0.046s)
[358.433962] Subtest engines-mixed: SUCCESS (0.314s)
[358.513230] [070/132] (662s left) i915_query (query-topology-matches-eu-total)
[358.566100] Starting subtest: query-topology-matches-eu-total
[358.568475] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[358.673672] [071/132] (662s left) gem_ctx_shared (q-in-order)
[358.741391] Starting subtest: Q-in-order
[358.743758] Starting dynamic subtest: rcs0
[358.852807] Dynamic subtest rcs0: SUCCESS (0.114s)
[358.864694] Starting dynamic subtest: vcs0
[358.945450] Dynamic subtest vcs0: SUCCESS (0.092s)
[358.962260] Starting dynamic subtest: vcs1
[359.032912] Dynamic subtest vcs1: SUCCESS (0.086s)
[359.048192] Starting dynamic subtest: vecs0
[359.112080] Dynamic subtest vecs0: SUCCESS (0.079s)
[359.128558] Starting dynamic subtest: ccs0
[359.211829] Dynamic subtest ccs0: SUCCESS (0.099s)
[359.214332] Subtest Q-in-order: SUCCESS (0.475s)
[361.001425] [072/132] (659s left) prime_mmap_coherency (ioctl-errors)
[361.073054] Starting subtest: ioctl-errors
[377.167696] Subtest ioctl-errors: SUCCESS (16.094s)
[377.319370] [073/132] (643s left) kms_flip (basic-flip-vs-modeset)
[377.453586] Starting subtest: basic-flip-vs-modeset
[377.455499] Starting dynamic subtest: A-eDP1
[380.063895] Dynamic subtest A-eDP1: SUCCESS (2.608s)
[380.074798] Starting dynamic subtest: B-eDP1
[382.215457] Dynamic subtest B-eDP1: SUCCESS (2.152s)
[382.226693] Starting dynamic subtest: C-eDP1
[384.385584] Dynamic subtest C-eDP1: SUCCESS (2.167s)
[384.395971] Starting dynamic subtest: D-eDP1
[386.557923] Dynamic subtest D-eDP1: SUCCESS (2.171s)
[386.559882] Subtest basic-flip-vs-modeset: SUCCESS (9.106s)
[387.350803] [074/132] (633s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[387.465450] Starting subtest: x-tiled-16bpp-rotate-180
[388.857071] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.391s)
[388.967241] [075/132] (632s left) kms_psr (psr2-primary-page-flip)
[389.074173] Starting subtest: psr2-primary-page-flip
[389.076166] Starting dynamic subtest: eDP-1
[390.843302] Dynamic subtest eDP-1: SUCCESS (1.769s)
[390.845569] Subtest psr2-primary-page-flip: SUCCESS (1.770s)
[391.733423] [076/132] (629s left) gem_exec_reloc (basic-write-read-noreloc)
[391.793140] Subtest basic-write-read-noreloc: SKIP (0.000s)
[391.870789] [077/132] (629s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[391.974191] Starting subtest: fbcpsr-2p-rte
[391.978500] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[392.079143] [078/132] (629s left) gem_vm_create (isolation)
[392.137779] Starting subtest: isolation
[392.146617] Subtest isolation: SUCCESS (0.009s)
[392.288664] [079/132] (628s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[392.348592] Starting subtest: multi-wait-all-for-submit-available-submitted
[392.351065] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[392.463127] [080/132] (628s left) kms_addfb_basic (small-bo)
[392.559257] Starting subtest: small-bo
[392.561164] Subtest small-bo: SUCCESS (0.000s)
[392.684429] [081/132] (628s left) kms_draw_crc (draw-method-mmap-gtt)
[392.777743] Starting subtest: draw-method-mmap-gtt
[392.781271] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[392.874508] [082/132] (628s left) gem_exec_capture (capture-invisible)
[392.938749] Starting subtest: capture-invisible
[392.940643] Starting dynamic subtest: smem0
[392.944331] Dynamic subtest smem0: SKIP (0.000s)
[392.948214] Subtest capture-invisible: SKIP (0.001s)
[393.055035] [083/132] (628s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[393.147776] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[393.149650] Starting dynamic subtest: pipe-A-eDP-1
[399.003608] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.856s)
[399.014328] Starting dynamic subtest: pipe-B-eDP-1
[405.674754] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.672s)
[405.677092] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.531s)
[406.538528] [084/132] (614s left) gem_ctx_persistence (heartbeat-hostile)
[406.608287] Starting subtest: heartbeat-hostile
[406.612589] Subtest heartbeat-hostile: SKIP (0.000s)
[406.706425] [085/132] (614s left) perf (i915-ref-count)
[406.773774] Starting subtest: i915-ref-count
[406.845679] Subtest i915-ref-count: SUCCESS (0.070s)
[406.902622] [086/132] (614s left) template (a)
[406.963076] Starting subtest: A
[406.965043] Subtest A: SUCCESS (0.000s)
[407.070585] [087/132] (614s left) gem_render_copy_redux (flink)
[407.129759] Starting subtest: flink
[407.942130] Subtest flink: SUCCESS (0.813s)
[408.075452] [088/132] (613s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[408.193557] Starting subtest: cursorA-vs-flipB-atomic
[408.197516] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[408.352195] [089/132] (613s left) core_auth (getclient-simple)
[408.389758] Starting subtest: getclient-simple
[408.400075] Subtest getclient-simple: SUCCESS (0.014s)
[408.502130] [090/132] (613s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[408.633111] Starting subtest: short-busy-flip-before-cursor-toggle
[408.636963] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[408.778116] [091/132] (612s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[408.886909] Starting subtest: 4-tiled-32bpp-rotate-0
[410.278186] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.389s)
[410.427936] [092/132] (611s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[410.517648] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[410.521710] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[410.607884] [093/132] (611s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[410.701786] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[415.095705] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.395s)
[416.251637] [094/132] (605s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[416.345716] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[416.350227] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[416.436976] [095/132] (605s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[416.525643] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[416.529853] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[416.614090] [096/132] (605s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[416.707161] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[416.711288] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[416.820638] [097/132] (605s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[416.908993] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[416.913028] Starting dynamic subtest: pipe-A-default-mode
[419.676950] Dynamic subtest pipe-A-default-mode: SUCCESS (2.766s)
[419.679367] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.768s)
[420.508880] [098/132] (601s left) kms_lease (cursor-implicit-plane)
[420.602353] Starting subtest: cursor-implicit-plane
[420.604366] Starting dynamic subtest: pipe-A-eDP-1
[421.059662] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.458s)
[421.070131] Starting dynamic subtest: pipe-B-eDP-1
[422.153855] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.092s)
[422.164264] Starting dynamic subtest: pipe-C-eDP-1
[423.271727] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.116s)
[423.283836] Starting dynamic subtest: pipe-D-eDP-1
[424.396175] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.124s)
[424.398454] Subtest cursor-implicit-plane: SUCCESS (3.795s)
[425.249254] [099/132] (596s left) kms_vrr (flip-suspend)
[425.360059] Starting subtest: flip-suspend
[425.366297] Subtest flip-suspend: SKIP (0.000s)
[425.434118] [100/132] (596s left) kms_async_flips (test-time-stamp)
[425.526954] Starting subtest: test-time-stamp
[425.915508] Starting dynamic subtest: pipe-A-eDP-1
[426.670616] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.755s)
[427.048943] Starting dynamic subtest: pipe-B-eDP-1
[427.799397] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.762s)
[428.183454] Starting dynamic subtest: pipe-C-eDP-1
[428.934909] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.757s)
[429.326197] Starting dynamic subtest: pipe-D-eDP-1
[430.086987] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.767s)
[430.089448] Subtest test-time-stamp: SUCCESS (4.561s)
[431.266193] [101/132] (590s left) gem_userptr_blits (process-exit-busy)
[431.346821] Starting subtest: process-exit-busy
[431.361035] Subtest process-exit-busy: SUCCESS (0.016s)
[431.469508] [102/132] (590s left) kms_cursor_crc (cursor-random-128x128)
[431.669920] Starting subtest: cursor-random-128x128
[431.674689] Starting dynamic subtest: pipe-A-eDP-1
[435.035661] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.360s)
[435.068062] Starting dynamic subtest: pipe-D-eDP-1
[439.032902] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.972s)
[439.035079] Subtest cursor-random-128x128: SUCCESS (7.362s)
[439.910489] [103/132] (582s left) gem_exec_reloc (basic-gtt-read-noreloc)
[439.962538] Subtest basic-gtt-read-noreloc: SKIP (0.000s)
[440.038328] [104/132] (582s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[440.138256] Starting subtest: fbcpsr-1p-rte
[447.135390] Subtest fbcpsr-1p-rte: SUCCESS (7.000s)
[447.290296] [105/132] (574s left) gem_lmem_swapping (smem-oom)
[447.335505] Subtest smem-oom: SKIP (0.000s)
[447.384529] [106/132] (574s left) kms_big_fb (linear-64bpp-rotate-270)
[447.478406] Starting subtest: linear-64bpp-rotate-270
[447.497211] Subtest linear-64bpp-rotate-270: SKIP (0.018s)
[447.613080] [107/132] (574s left) core_setmaster (master-drop-set-shared-fd)
[447.695081] Starting subtest: master-drop-set-shared-fd
[447.705794] Subtest master-drop-set-shared-fd: SUCCESS (0.011s)
[447.764444] [108/132] (574s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[447.831854] Starting subtest: stress-mm-invalidate-close-overlap
[449.981213] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[450.099964] [109/132] (572s left) kms_vblank (ts-continuation-modeset)
[450.211778] Starting subtest: ts-continuation-modeset
[450.213610] Starting dynamic subtest: pipe-A-eDP-1
[451.704022] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.493s)
[451.719874] Starting dynamic subtest: pipe-D-eDP-1
[453.863311] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.153s)
[453.865213] Subtest ts-continuation-modeset: SUCCESS (3.651s)
[454.664382] [110/132] (567s left) fbdev (unaligned-write)
[454.705475] Starting subtest: unaligned-write
[454.804244] Subtest unaligned-write: SUCCESS (0.099s)
[454.881915] [111/132] (567s left) gem_ctx_param (root-set)
[454.933380] Starting subtest: root-set
[454.935786] Subtest root-set: SUCCESS (0.001s)
[455.040032] [112/132] (567s left) gem_mmap_wc (close)
[455.104960] Subtest close: SKIP (0.000s)
[455.162424] [113/132] (567s left) kms_pm_rpm (dpms-lpsp)
[457.092236] Starting subtest: dpms-lpsp
[457.399053] Subtest dpms-lpsp: SUCCESS (0.310s)
[457.489867] [114/132] (565s left) syncobj_wait (wait-any-snapshot)
[457.544219] Starting subtest: wait-any-snapshot
[457.623619] Subtest wait-any-snapshot: SUCCESS (0.081s)
[457.713388] [115/132] (564s left) prime_busy (hang)
[457.767157] Starting subtest: hang
[457.769483] Starting dynamic subtest: rcs0
[483.439678] Dynamic subtest rcs0: SUCCESS (25.668s)
[483.448436] Starting dynamic subtest: bcs0
[490.594531] Dynamic subtest bcs0: SUCCESS (7.156s)
[490.604045] Starting dynamic subtest: vcs0
[497.571998] Dynamic subtest vcs0: SUCCESS (6.975s)
[497.594064] Starting dynamic subtest: vcs1
[504.545063] Dynamic subtest vcs1: SUCCESS (6.969s)
[504.555686] Starting dynamic subtest: vecs0
[511.583858] Dynamic subtest vecs0: SUCCESS (7.041s)
[511.593517] Starting dynamic subtest: ccs0
[525.425923] Dynamic subtest ccs0: SUCCESS (13.839s)
[525.429500] Subtest hang: SUCCESS (67.657s)
[525.543442] [116/132] (497s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[525.633058] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[525.635336] Starting dynamic subtest: pipe-A-eDP-1
[525.658483] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[525.670859] Starting dynamic subtest: pipe-B-eDP-1
[525.692377] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[525.701261] Starting dynamic subtest: pipe-C-eDP-1
[525.722330] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[525.731334] Starting dynamic subtest: pipe-D-eDP-1
[525.753116] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[525.757376] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[527.910356] [117/132] (494s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[528.307885] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[529.126147] [118/132] (493s left) gem_caching (writes)
[529.177743] Subtest writes: SKIP (0.000s)
[529.246429] [119/132] (493s left) kms_chamelium_edid (dp-edid-resolution-list)
[529.642234] Subtest dp-edid-resolution-list: SKIP (0.000s)
[530.460994] [120/132] (492s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[530.546275] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[530.548384] Starting dynamic subtest: pipe-A-eDP-1
[530.567820] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[530.578738] Starting dynamic subtest: pipe-B-eDP-1
[530.598173] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[530.607205] Starting dynamic subtest: pipe-C-eDP-1
[530.626252] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[530.635055] Starting dynamic subtest: pipe-D-eDP-1
[530.654231] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[530.656395] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[532.840086] [121/132] (490s left) kms_flip (blocking-wf_vblank)
[532.958977] Starting subtest: blocking-wf_vblank
[532.961160] Starting dynamic subtest: A-eDP1
[541.898107] Dynamic subtest A-eDP1: SUCCESS (8.940s)
[541.907643] Starting dynamic subtest: B-eDP1
[550.837812] Dynamic subtest B-eDP1: SUCCESS (8.938s)
[550.850206] Starting dynamic subtest: C-eDP1
[559.763948] Dynamic subtest C-eDP1: SUCCESS (8.922s)
[559.773710] Starting dynamic subtest: D-eDP1
[568.699906] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[568.704706] Subtest blocking-wf_vblank: SUCCESS (35.746s)
[569.927273] [122/132] (453s left) gem_softpin (evict-prime-sanity-check)
[569.985346] Starting subtest: evict-prime-sanity-check
[569.987691] Starting dynamic subtest: rcs0
[571.130941] Dynamic subtest rcs0: SUCCESS (1.145s)
[571.143515] Starting dynamic subtest: bcs0
[572.247946] Dynamic subtest bcs0: SUCCESS (1.116s)
[572.259583] Starting dynamic subtest: vcs0
[573.899854] Dynamic subtest vcs0: SUCCESS (1.649s)
[573.912955] Starting dynamic subtest: vcs1
[575.887027] Dynamic subtest vcs1: SUCCESS (1.985s)
[575.899667] Starting dynamic subtest: vecs0
[577.872739] Dynamic subtest vecs0: SUCCESS (1.983s)
[577.885090] Starting dynamic subtest: ccs0
[578.989290] Dynamic subtest ccs0: SUCCESS (1.115s)
[578.999619] Starting dynamic subtest: all
[580.105458] Dynamic subtest all: SUCCESS (1.115s)
[580.107729] Subtest evict-prime-sanity-check: SUCCESS (10.120s)
[580.183113] [123/132] (442s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[580.294324] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[580.296733] Starting dynamic subtest: pipe-A-eDP-1
[596.743063] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.327s)
[596.753486] Starting dynamic subtest: pipe-B-eDP-1
[613.688231] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.422s)
[613.700217] Starting dynamic subtest: pipe-C-eDP-1
[630.673002] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.353s)
[630.686636] Starting dynamic subtest: pipe-D-eDP-1
[647.683822] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.380s)
[647.685822] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (9.488s)
[648.902777] [124/132] (374s left) kms_psr (pr-cursor-blt)
[649.015360] Starting subtest: pr-cursor-blt
[649.019826] Subtest pr-cursor-blt: SKIP (0.000s)
[649.091987] [125/132] (374s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[649.695701] Starting subtest: syncobj-backward-timeline-chain-engines
[649.797339] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.103s)
[650.907122] [126/132] (372s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[651.001775] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[651.005806] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[651.103295] [127/132] (372s left) gem_exec_whisper (basic-queues-priority)
[651.159876] Starting subtest: basic-queues-priority
[675.726900] Subtest basic-queues-priority: SUCCESS (24.543s)
[675.916273] [128/132] (347s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[676.011988] Starting subtest: fbc-psr2-sprite-plane-onoff
[676.014228] Starting dynamic subtest: eDP-1
[676.018247] Dynamic subtest eDP-1: SKIP (0.000s)
[676.022219] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[676.085917] [129/132] (347s left) sw_sync (alloc_merge_fence)
[676.127602] Starting subtest: alloc_merge_fence
[676.130011] Subtest alloc_merge_fence: SUCCESS (0.000s)
[676.187529] [130/132] (347s left) kms_cursor_crc (cursor-sliding-32x10)
[676.352562] Starting subtest: cursor-sliding-32x10
[676.400858] Subtest cursor-sliding-32x10: SKIP (0.044s)
[676.682753] [131/132] (346s left) i915_pm_rpm (gem-execbuf-stress)
[678.913514] Starting subtest: gem-execbuf-stress
[678.916098] Starting dynamic subtest: smem0
[691.625599] Dynamic subtest smem0: SUCCESS (12.709s)
[691.639975] Starting dynamic subtest: extra-wait-smem0
[753.320539] Dynamic subtest extra-wait-smem0: SUCCESS (61.675s)
[753.323760] Subtest gem-execbuf-stress: SUCCESS (74.388s)
[753.695262] [132/132] (269s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[753.760199] Starting subtest: etime-multi-wait-for-submit-submitted
[753.868300] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.107s)
[753.942841] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: syncobj_timeline
results: parsing output: 2/ for test: syncobj_wait
results: parsing output: 3/ for test: gem_render_copy
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_exec_params
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_exec_params
results: parsing output: 8/ for test: kms_async_flips
results: parsing output: 9/ for test: sysfs_preempt_timeout
results: parsing output: 10/ for test: kms_psr2_sf
results: parsing output: 11/ for test: kms_flip_scaled_crc
results: parsing output: 12/ for test: kms_plane_alpha_blend
results: parsing output: 13/ for test: i915_pm_rps
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: kms_prime
results: parsing output: 16/ for test: gem_exec_reloc
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_ccs
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: gem_exec_parallel
results: parsing output: 21/ for test: gem_exec_balancer
results: parsing output: 22/ for test: kms_ccs
results: parsing output: 23/ for test: gem_exec_create
results: parsing output: 24/ for test: tools_test
results: parsing output: 25/ for test: kms_plane_cursor
results: parsing output: 26/ for test: gem_exec_reloc
results: parsing output: 27/ for test: api_intel_bb
results: parsing output: 28/ for test: kms_chamelium_audio
results: parsing output: 29/ for test: kms_selftest
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_flip
results: parsing output: 32/ for test: kms_plane_scaling
results: parsing output: 33/ for test: gem_render_copy
results: parsing output: 34/ for test: kms_flip_scaled_crc
results: parsing output: 35/ for test: gem_create
results: parsing output: 36/ for test: kms_cursor_legacy
results: parsing output: 37/ for test: kms_addfb_basic
results: parsing output: 38/ for test: gem_exec_whisper
results: parsing output: 39/ for test: kms_cursor_crc
results: parsing output: 40/ for test: kms_rotation_crc
results: parsing output: 41/ for test: kms_content_protection
results: parsing output: 42/ for test: gen9_exec_parse
results: parsing output: 43/ for test: gem_flink_race
results: parsing output: 44/ for test: kms_rotation_crc
results: parsing output: 45/ for test: gem_exec_params
results: parsing output: 46/ for test: gem_exec_schedule
results: parsing output: 47/ for test: kms_plane_scaling
results: parsing output: 48/ for test: gem_exec_schedule
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: kms_plane
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_pxp
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: kms_color_pipeline
results: parsing output: 57/ for test: perf_pmu
results: parsing output: 58/ for test: i915_hangman
results: parsing output: 59/ for test: gen7_exec_parse
results: parsing output: 60/ for test: gem_mmap_offset
results: parsing output: 61/ for test: tools_test
results: parsing output: 62/ for test: kms_invalid_mode
results: parsing output: 63/ for test: gem_close
results: parsing output: 64/ for test: kms_psr2_sf
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_atomic_interruptible
results: parsing output: 68/ for test: gem_ctx_persistence
results: parsing output: 69/ for test: i915_query
results: parsing output: 70/ for test: gem_ctx_shared
results: parsing output: 71/ for test: prime_mmap_coherency
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_vm_create
results: parsing output: 78/ for test: syncobj_timeline
results: parsing output: 79/ for test: kms_addfb_basic
results: parsing output: 80/ for test: kms_draw_crc
results: parsing output: 81/ for test: gem_exec_capture
results: parsing output: 82/ for test: kms_psr2_sf
results: parsing output: 83/ for test: gem_ctx_persistence
results: parsing output: 84/ for test: perf
results: parsing output: 85/ for test: template
results: parsing output: 86/ for test: gem_render_copy_redux
results: parsing output: 87/ for test: kms_cursor_legacy
results: parsing output: 88/ for test: core_auth
results: parsing output: 89/ for test: kms_cursor_legacy
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: kms_lease
results: parsing output: 98/ for test: kms_vrr
results: parsing output: 99/ for test: kms_async_flips
results: parsing output: 100/ for test: gem_userptr_blits
results: parsing output: 101/ for test: kms_cursor_crc
results: parsing output: 102/ for test: gem_exec_reloc
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_lmem_swapping
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: core_setmaster
results: parsing output: 107/ for test: gem_userptr_blits
results: parsing output: 108/ for test: kms_vblank
results: parsing output: 109/ for test: fbdev
results: parsing output: 110/ for test: gem_ctx_param
results: parsing output: 111/ for test: gem_mmap_wc
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: syncobj_wait
results: parsing output: 114/ for test: prime_busy
results: parsing output: 115/ for test: kms_ccs
results: parsing output: 116/ for test: kms_chamelium_hpd
results: parsing output: 117/ for test: gem_caching
results: parsing output: 118/ for test: kms_chamelium_edid
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: gem_softpin
results: parsing output: 122/ for test: kms_ccs
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: gem_exec_fence
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_exec_whisper
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: sw_sync
results: parsing output: 129/ for test: kms_cursor_crc
results: parsing output: 130/ for test: i915_pm_rpm
results: parsing output: 131/ for test: syncobj_timeline
Done.