[60.765962] Initializing watchdogs
[60.766026]   /dev/watchdog0
[60.781188] [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]
[60.785763] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.788543] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.792078] [001/132] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[60.930466] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[68.704942] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.789s)
[68.841987] [002/132] (952s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[68.958225] Starting subtest: cursor-vs-flip-varying-size
[100.362883] Subtest cursor-vs-flip-varying-size: SUCCESS (31.409s)
[101.189154] [003/132] (920s left) gem_ctx_param (basic)
[101.246644] Starting subtest: basic
[101.249213] Subtest basic: SUCCESS (0.001s)
[101.343832] [004/132] (920s left) gem_exec_balancer (parallel-contexts)
[101.610741] Starting subtest: parallel-contexts
[104.335510] Subtest parallel-contexts: SUCCESS (2.724s)
[105.085458] [005/132] (916s left) gem_wait (invalid-flags)
[105.147400] Starting subtest: invalid-flags
[105.149836] Subtest invalid-flags: SUCCESS (0.000s)
[105.374327] [006/132] (916s left) gem_ringfill (legacy-basic)
[110.937194] Starting subtest: legacy-basic
[110.939799] Starting dynamic subtest: default
[110.986266] Dynamic subtest default: SUCCESS (0.050s)
[110.995226] Starting dynamic subtest: render
[111.030047] Dynamic subtest render: SUCCESS (0.042s)
[111.040708] Starting dynamic subtest: bsd
[111.072714] Dynamic subtest bsd: SUCCESS (0.041s)
[111.085932] Starting dynamic subtest: bsd1
[111.116760] Dynamic subtest bsd1: SUCCESS (0.042s)
[111.126524] Starting dynamic subtest: bsd2
[111.156246] Dynamic subtest bsd2: SUCCESS (0.039s)
[111.165866] Starting dynamic subtest: blt
[111.201108] Dynamic subtest blt: SUCCESS (0.044s)
[111.211052] Starting dynamic subtest: vebox
[111.244041] Dynamic subtest vebox: SUCCESS (0.042s)
[111.246825] Subtest legacy-basic: SUCCESS (0.310s)
[111.323056] [FACT gem_ringfill (legacy-basic)] new: kernel.kmod_is_loaded.vgem: true
[111.326193] [007/132] (910s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[111.426208] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[111.430433] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[111.495160] [008/132] (910s left) gem_exec_params (batch-first)
[111.549663] Starting subtest: batch-first
[111.555218] Subtest batch-first: SUCCESS (0.006s)
[111.661917] [009/132] (909s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[111.723416] Starting subtest: map-fixed-invalidate-overlap-busy
[111.727510] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[111.845338] [010/132] (909s left) gem_ctx_persistence (file)
[111.908073] Starting subtest: file
[112.022550] Subtest file: SUCCESS (0.115s)
[112.155386] [011/132] (909s left) kms_async_flips (crc)
[112.242522] Starting subtest: crc
[112.628326] Starting dynamic subtest: pipe-A-eDP-1
[115.583721] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.956s)
[115.960868] Starting dynamic subtest: pipe-B-eDP-1
[118.915082] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.964s)
[119.300627] Starting dynamic subtest: pipe-C-eDP-1
[122.210771] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.924s)
[122.590377] Starting dynamic subtest: pipe-D-eDP-1
[125.460736] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.877s)
[125.463089] Subtest crc: SUCCESS (13.217s)
[126.633680] [012/132] (895s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[126.750604] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[127.552666] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.803s)
[127.714204] [013/132] (894s left) kms_cursor_crc (cursor-random-256x85)
[127.976823] Starting subtest: cursor-random-256x85
[128.028707] Subtest cursor-random-256x85: SKIP (0.047s)
[128.210417] [014/132] (893s left) gem_partial_pwrite_pread (writes-after-reads)
[128.268178] Subtest writes-after-reads: SKIP (0.000s)
[128.346028] [015/132] (893s left) kms_psr (psr-sprite-mmap-gtt)
[128.444733] Starting subtest: psr-sprite-mmap-gtt
[128.446971] Starting dynamic subtest: eDP-1
[129.941154] Dynamic subtest eDP-1: SKIP (1.493s)
[129.946043] Subtest psr-sprite-mmap-gtt: SKIP (1.494s)
[130.089224] [016/132] (891s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[130.173178] Starting subtest: fbc-pr-sprite-mmap-cpu
[130.177622] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[130.246221] [017/132] (891s left) api_intel_bb (full-batch)
[130.303807] Starting subtest: full-batch
[130.308324] Subtest full-batch: SUCCESS (0.005s)
[130.395441] [018/132] (891s left) kms_busy (extended-pageflip-hang-newfb)
[130.491128] Starting subtest: extended-pageflip-hang-newfb
[130.493445] Starting dynamic subtest: pipe-A
[156.717298] Dynamic subtest pipe-A: SUCCESS (26.226s)
[156.725837] Starting dynamic subtest: pipe-D
[181.687119] Dynamic subtest pipe-D: SUCCESS (24.966s)
[181.689528] Subtest extended-pageflip-hang-newfb: SUCCESS (51.196s)
[182.549883] [019/132] (839s left) gem_mmap_wc (write-cpu-read-wc)
[182.613857] Subtest write-cpu-read-wc: SKIP (0.000s)
[182.677644] [020/132] (839s left) gem_userptr_blits (stress-purge)
[182.735999] Starting subtest: stress-purge
[188.128972] Subtest stress-purge: SUCCESS (5.395s)
[188.247175] [021/132] (833s left) gem_eio (in-flight-suspend)
[188.489319] Starting subtest: in-flight-suspend
[232.740533] Subtest in-flight-suspend: SUCCESS (14.767s)
[232.867507] [022/132] (789s left) sw_sync (sync_multi_consumer)
[232.919439] Starting subtest: sync_multi_consumer
[233.382022] Subtest sync_multi_consumer: SUCCESS (0.462s)
[233.444733] [023/132] (788s left) kms_lease (lease-unleased-crtc)
[233.534362] Starting subtest: lease-unleased-crtc
[233.536760] Starting dynamic subtest: pipe-A-eDP-1
[233.824883] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.295s)
[233.835806] Starting dynamic subtest: pipe-B-eDP-1
[234.578792] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.761s)
[234.591283] Starting dynamic subtest: pipe-C-eDP-1
[234.593364] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[234.602661] Starting dynamic subtest: pipe-D-eDP-1
[234.604771] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.005s)
[234.606854] Subtest lease-unleased-crtc: SUCCESS (1.069s)
[234.935809] [024/132] (787s left) kms_pipe_crc_basic (suspend-read-crc)
[235.026621] Starting subtest: suspend-read-crc
[235.028968] Starting dynamic subtest: pipe-A-eDP-1
[253.068857] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.535s)
[253.088518] Starting dynamic subtest: pipe-B-eDP-1
[271.108190] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.270s)
[271.118858] Starting dynamic subtest: pipe-C-eDP-1
[289.044462] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.122s)
[289.056699] Starting dynamic subtest: pipe-D-eDP-1
[307.037341] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.050s)
[307.039729] Subtest suspend-read-crc: SUCCESS (11.982s)
[307.865739] [025/132] (714s left) gem_exec_gttfill (engines)
[307.929491] Starting subtest: engines
[307.932163] Starting dynamic subtest: rcs0
[330.573697] Dynamic subtest rcs0: SUCCESS (22.673s)
[330.586615] Starting dynamic subtest: bcs0
[353.811217] Dynamic subtest bcs0: SUCCESS (23.313s)
[353.822249] Starting dynamic subtest: vcs0
[377.094987] Dynamic subtest vcs0: SUCCESS (23.289s)
[377.104952] Starting dynamic subtest: vcs1
[400.398731] Dynamic subtest vcs1: SUCCESS (23.298s)
[400.411312] Starting dynamic subtest: vecs0
[423.679304] Dynamic subtest vecs0: SUCCESS (23.283s)
[423.691472] Starting dynamic subtest: ccs0
[447.019752] Dynamic subtest ccs0: SUCCESS (23.336s)
[447.022819] Subtest engines: SUCCESS (139.201s)
[447.212627] [026/132] (575s left) sysfs_heartbeat_interval (invalid)
[447.326231] Starting subtest: invalid
[447.329248] Starting dynamic subtest: vecs0
[447.331719] Dynamic subtest vecs0: SUCCESS (0.000s)
[447.346223] Starting dynamic subtest: ccs0
[447.348542] Dynamic subtest ccs0: SUCCESS (0.000s)
[447.363773] Starting dynamic subtest: vcs0
[447.366253] Dynamic subtest vcs0: SUCCESS (0.000s)
[447.376430] Starting dynamic subtest: bcs0
[447.379008] Dynamic subtest bcs0: SUCCESS (0.000s)
[447.388455] Starting dynamic subtest: rcs0
[447.390938] Dynamic subtest rcs0: SUCCESS (0.000s)
[447.400882] Starting dynamic subtest: vcs1
[447.403217] Dynamic subtest vcs1: SUCCESS (0.000s)
[447.405484] Subtest invalid: SUCCESS (0.004s)
[447.445638] [027/132] (574s left) prime_mmap (test_userptr)
[447.497062] Starting subtest: test_userptr
[447.499268] Starting dynamic subtest: test_userptr-smem
[447.501644] Dynamic subtest test_userptr-smem: SUCCESS (0.000s)
[447.503736] Subtest test_userptr: SUCCESS (0.001s)
[447.592824] [028/132] (574s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[447.708413] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[447.737288] Starting dynamic subtest: pipe-A-plane-0
[448.038578] Dynamic subtest pipe-A-plane-0: SUCCESS (0.300s)
[448.048372] Starting dynamic subtest: pipe-A-plane-3
[448.271262] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[449.424363] Starting dynamic subtest: pipe-B-plane-0
[449.727065] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[449.738135] Starting dynamic subtest: pipe-B-plane-3
[449.985161] Dynamic subtest pipe-B-plane-3: SUCCESS (0.254s)
[450.294473] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.580s)
[451.098738] [029/132] (571s left) kms_psr (pr-primary-render)
[451.194033] Starting subtest: pr-primary-render
[451.199219] Subtest pr-primary-render: SKIP (0.000s)
[451.279634] [030/132] (571s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[451.383972] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[452.253440] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.870s)
[452.433075] [031/132] (570s left) prime_busy (before-wait)
[452.503269] Starting subtest: before-wait
[452.506268] Starting dynamic subtest: rcs0
[452.548122] Dynamic subtest rcs0: SUCCESS (0.042s)
[452.559710] Starting dynamic subtest: bcs0
[452.600166] Dynamic subtest bcs0: SUCCESS (0.051s)
[452.612403] Starting dynamic subtest: vcs0
[452.643155] Dynamic subtest vcs0: SUCCESS (0.042s)
[452.654490] Starting dynamic subtest: vcs1
[452.684028] Dynamic subtest vcs1: SUCCESS (0.040s)
[452.694516] Starting dynamic subtest: vecs0
[452.725114] Dynamic subtest vecs0: SUCCESS (0.041s)
[452.735427] Starting dynamic subtest: ccs0
[452.769216] Dynamic subtest ccs0: SUCCESS (0.043s)
[452.878049] Subtest before-wait: SUCCESS (0.374s)
[452.959267] [032/132] (569s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[453.054327] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[460.347117] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.297s)
[460.460852] [033/132] (562s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[461.052216] Starting subtest: syncobj-timeline-invalid-flags
[461.054635] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[462.565280] [034/132] (560s left) kms_setmode (invalid-clone-exclusive-crtc)
[462.622938] Starting subtest: invalid-clone-exclusive-crtc
[462.631231] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[462.735515] [035/132] (560s left) kms_selftest (drm_cmdline_parser)
[462.790745] Starting subtest: drm_cmdline_parser
[462.802835] Starting dynamic subtest: drm_test_cmdline_force_d_only
[462.913447] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.108s)
[462.924465] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[462.995429] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.082s)
[463.007057] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[463.150273] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.125s)
[463.163443] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[463.223045] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.102s)
[463.234204] Starting dynamic subtest: drm_test_cmdline_force_e_only
[463.315079] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.091s)
[463.326702] Starting dynamic subtest: drm_test_cmdline_res
[463.392258] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.076s)
[463.403718] Starting dynamic subtest: drm_test_cmdline_res_vesa
[463.478419] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.085s)
[463.489726] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[463.565490] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.087s)
[463.575361] Starting dynamic subtest: drm_test_cmdline_res_rblank
[463.649298] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.083s)
[463.661242] Starting dynamic subtest: drm_test_cmdline_res_bpp
[463.735590] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.083s)
[463.745430] Starting dynamic subtest: drm_test_cmdline_res_refresh
[463.823439] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.088s)
[463.835007] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[463.904179] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.080s)
[463.913994] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[463.994301] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.087s)
[464.004445] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[464.075072] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.082s)
[464.085274] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[464.158305] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.080s)
[464.168223] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[464.235913] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.078s)
[464.245605] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[464.322979] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.086s)
[464.334421] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[464.413813] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.088s)
[464.423126] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[464.498166] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.085s)
[464.509596] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[464.580789] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.081s)
[464.590232] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[464.668180] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.088s)
[464.677785] Starting dynamic subtest: drm_test_cmdline_name
[464.751812] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.083s)
[464.761873] Starting dynamic subtest: drm_test_cmdline_name_bpp
[464.830290] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.077s)
[464.840154] Starting dynamic subtest: drm_test_cmdline_name_option
[464.912969] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.081s)
[464.923221] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[465.002192] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.089s)
[465.012197] Starting dynamic subtest: drm_test_cmdline_rotate_0
[465.089591] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.086s)
[465.099999] Starting dynamic subtest: drm_test_cmdline_rotate_90
[465.175655] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.086s)
[465.185730] Starting dynamic subtest: drm_test_cmdline_rotate_180
[465.265963] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.090s)
[465.275929] Starting dynamic subtest: drm_test_cmdline_rotate_270
[465.349206] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.079s)
[465.359177] Starting dynamic subtest: drm_test_cmdline_hmirror
[465.443873] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.094s)
[465.453456] Starting dynamic subtest: drm_test_cmdline_vmirror
[465.529798] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.087s)
[465.540038] Starting dynamic subtest: drm_test_cmdline_margin_options
[465.617860] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.084s)
[465.627332] Starting dynamic subtest: drm_test_cmdline_multiple_options
[465.697212] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.082s)
[465.706876] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[465.786822] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.088s)
[465.796942] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[465.870089] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.083s)
[465.879775] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[465.957709] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.086s)
[465.966943] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[466.037621] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.080s)
[466.048428] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[466.122146] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.082s)
[466.132370] Starting dynamic subtest: drm_test_cmdline_invalid
[466.224680] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.100s)
[466.234213] Starting dynamic subtest: drm_test_cmdline_tv_options
[466.325951] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.102s)
[466.328078] Subtest drm_cmdline_parser: SUCCESS (3.536s)
[466.474532] [036/132] (556s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[466.572207] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[466.576265] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[466.645976] [037/132] (556s left) syncobj_timeline (device-submit-unordered)
[466.691578] Starting subtest: device-submit-unordered
[466.693689] Subtest device-submit-unordered: SUCCESS (0.001s)
[466.792538] [038/132] (556s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[466.903963] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[466.908121] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[467.055610] [039/132] (555s left) gem_ctx_param (invalid-param-get)
[467.103907] Starting subtest: invalid-param-get
[467.106174] Subtest invalid-param-get: SUCCESS (0.001s)
[467.189594] [040/132] (555s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling)
[467.305232] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling
[467.307739] Starting dynamic subtest: pipe-A-default-mode
[470.094141] Dynamic subtest pipe-A-default-mode: SUCCESS (2.790s)
[470.096827] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (2.792s)
[470.933480] [041/132] (552s left) gem_exec_schedule (preemptive-hang)
[471.123835] Starting subtest: preemptive-hang
[471.126348] Starting dynamic subtest: rcs0
[480.697425] Dynamic subtest rcs0: SUCCESS (9.570s)
[480.707818] Starting dynamic subtest: bcs0
[489.591233] Dynamic subtest bcs0: SUCCESS (8.895s)
[489.603903] Starting dynamic subtest: vcs0
[498.615922] Dynamic subtest vcs0: SUCCESS (9.026s)
[498.627665] Starting dynamic subtest: vcs1
[507.642131] Dynamic subtest vcs1: SUCCESS (9.025s)
[507.656347] Starting dynamic subtest: vecs0
[516.596442] Dynamic subtest vecs0: SUCCESS (8.955s)
[516.606479] Starting dynamic subtest: ccs0
[525.625754] Dynamic subtest ccs0: SUCCESS (9.026s)
[525.628447] Subtest preemptive-hang: SUCCESS (54.506s)
[525.868117] [042/132] (497s left) kms_psr (psr2-cursor-plane-move)
[525.977229] Starting subtest: psr2-cursor-plane-move
[525.980094] Starting dynamic subtest: eDP-1
[527.686154] Dynamic subtest eDP-1: SUCCESS (1.709s)
[527.689053] Subtest psr2-cursor-plane-move: SUCCESS (1.710s)
[528.487671] [043/132] (494s left) kms_content_protection (legacy-hdcp14)
[528.634444] Starting subtest: legacy-hdcp14
[528.639960] Subtest legacy-hdcp14: SKIP (0.000s)
[528.746308] [044/132] (494s left) kms_atomic_transition (modeset-transition-fencing)
[528.837406] Starting subtest: modeset-transition-fencing
[528.840229] Starting dynamic subtest: 1x-outputs
[539.595723] Dynamic subtest 1x-outputs: SUCCESS (10.761s)
[539.598436] Subtest modeset-transition-fencing: SUCCESS (10.763s)
[540.463767] [045/132] (482s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[540.518392] Starting subtest: single-wait-for-submit-available-signaled
[540.521025] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[540.603916] [046/132] (482s left) perf_pmu (most-busy-idle-check-all)
[540.641885] Starting subtest: most-busy-idle-check-all
[540.644032] Starting dynamic subtest: rcs0
[542.476884] Dynamic subtest rcs0: SUCCESS (1.835s)
[542.490493] Starting dynamic subtest: bcs0
[544.463100] Dynamic subtest bcs0: SUCCESS (1.983s)
[544.476991] Starting dynamic subtest: vcs0
[546.510264] Dynamic subtest vcs0: SUCCESS (2.045s)
[546.524568] Starting dynamic subtest: vcs1
[548.495233] Dynamic subtest vcs1: SUCCESS (1.982s)
[548.509702] Starting dynamic subtest: vecs0
[550.477994] Dynamic subtest vecs0: SUCCESS (1.981s)
[550.492537] Starting dynamic subtest: ccs0
[552.526800] Dynamic subtest ccs0: SUCCESS (2.046s)
[552.529462] Subtest most-busy-idle-check-all: SUCCESS (11.888s)
[552.637981] [047/132] (470s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[552.728269] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[552.733666] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[552.830488] [048/132] (470s left) kms_cursor_crc (cursor-tearing-position-change)
[552.936304] Starting subtest: cursor-tearing-position-change
[552.939200] Starting dynamic subtest: pipe-A-eDP-1
[553.529417] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.591s)
[553.540555] Starting dynamic subtest: pipe-D-eDP-1
[554.795574] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.267s)
[554.798182] Subtest cursor-tearing-position-change: SUCCESS (1.860s)
[555.634203] [049/132] (467s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[555.734308] Starting subtest: x-tiled-32bpp-rotate-0
[557.169234] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.435s)
[557.312892] [050/132] (466s left) gem_exec_reloc (basic-wc-read)
[557.369324] Subtest basic-wc-read: SKIP (0.000s)
[557.440695] [051/132] (465s left) gem_ctx_engines (invalid-engines)
[557.497958] Starting subtest: invalid-engines
[557.508808] Subtest invalid-engines: SUCCESS (0.014s)
[557.740789] [052/132] (465s left) kms_properties (plane-properties-atomic)
[557.833428] Starting subtest: plane-properties-atomic
[557.836265] Starting dynamic subtest: pipe-A-eDP-1
[558.380739] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.546s)
[558.397372] Starting dynamic subtest: pipe-B-eDP-1
[559.695243] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.311s)
[559.706718] Starting dynamic subtest: pipe-C-eDP-1
[560.924613] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.231s)
[560.935981] Starting dynamic subtest: pipe-D-eDP-1
[562.163475] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s)
[562.166067] Subtest plane-properties-atomic: SUCCESS (4.329s)
[562.959687] [053/132] (460s left) api_intel_bb (bb-with-allocator)
[563.027307] Starting subtest: bb-with-allocator
[563.029946] Subtest bb-with-allocator: SUCCESS (0.006s)
[563.135957] [054/132] (460s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[563.240073] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[563.245002] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[563.343152] [055/132] (460s left) sw_sync (alloc_timeline)
[563.378358] Starting subtest: alloc_timeline
[563.381212] Subtest alloc_timeline: SUCCESS (0.000s)
[563.442418] [056/132] (460s left) vgem_basic (dmabuf-fence-before)
[563.490784] Starting subtest: dmabuf-fence-before
[563.493031] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[563.552122] [057/132] (460s left) gem_ctx_sseu (invalid-args)
[563.618052] Subtest invalid-args: SKIP (0.000s)
[563.692561] [058/132] (460s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[563.792886] Starting subtest: 4-tiled-32bpp-rotate-180
[565.161988] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.369s)
[565.308794] [059/132] (458s left) syncobj_timeline (invalid-signal-bad-pad)
[565.368613] Starting subtest: invalid-signal-bad-pad
[565.371195] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[565.474372] [060/132] (458s left) kms_joiner (basic-max-non-joiner)
[565.560560] Starting subtest: basic-max-non-joiner
[565.564177] Subtest basic-max-non-joiner: SKIP (0.000s)
[565.630404] [061/132] (458s left) i915_module_load (resize-bar)
[565.663634] Starting subtest: resize-bar
[565.670171] Subtest resize-bar: SKIP (0.006s)
[565.718613] [062/132] (458s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping)
[565.803192] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping
[565.807139] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[565.903813] [063/132] (458s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[566.004205] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[566.008770] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[566.095954] [064/132] (457s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[566.187977] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[566.190023] Starting dynamic subtest: pipe-A-eDP-1
[566.208038] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[566.221171] Starting dynamic subtest: pipe-B-eDP-1
[566.242180] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[566.251811] Starting dynamic subtest: pipe-C-eDP-1
[566.272169] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[566.281677] Starting dynamic subtest: pipe-D-eDP-1
[566.303662] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[566.308169] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[568.443888] [065/132] (455s left) kms_cursor_crc (cursor-onscreen-32x32)
[568.598008] Starting subtest: cursor-onscreen-32x32
[568.646331] Subtest cursor-onscreen-32x32: SKIP (0.042s)
[568.917417] [066/132] (455s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[569.025423] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[569.028175] Starting dynamic subtest: pipe-A
[569.360662] Dynamic subtest pipe-A: SUCCESS (0.338s)
[569.377220] Starting dynamic subtest: pipe-B
[570.731682] Dynamic subtest pipe-B: SUCCESS (1.370s)
[570.743933] Starting dynamic subtest: pipe-C
[572.166091] Dynamic subtest pipe-C: SUCCESS (1.433s)
[572.176066] Starting dynamic subtest: pipe-D
[573.591567] Dynamic subtest pipe-D: SUCCESS (1.424s)
[573.594341] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.569s)
[574.764788] [067/132] (449s left) gem_media_fill (media-fill)
[574.837266] Subtest media-fill: SKIP (0.000s)
[574.944750] [068/132] (449s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[575.040056] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[575.044957] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[576.198365] [069/132] (448s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[576.302682] Starting subtest: yf-tiled-64bpp-rotate-90
[576.307915] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[576.393776] [070/132] (447s left) kms_color (invalid-degamma-lut-sizes)
[576.495370] Starting subtest: invalid-degamma-lut-sizes
[576.498055] Starting dynamic subtest: pipe-A
[576.787145] Dynamic subtest pipe-A: SUCCESS (0.292s)
[576.799277] Starting dynamic subtest: pipe-B
[576.802278] Dynamic subtest pipe-B: SUCCESS (0.000s)
[576.813187] Starting dynamic subtest: pipe-C
[576.816705] Dynamic subtest pipe-C: SUCCESS (0.000s)
[576.827950] Starting dynamic subtest: pipe-D
[576.830939] Dynamic subtest pipe-D: SUCCESS (0.000s)
[576.833972] Subtest invalid-degamma-lut-sizes: SUCCESS (0.295s)
[577.629166] [071/132] (446s left) syncobj_basic (create-signaled)
[577.697468] Starting subtest: create-signaled
[577.700120] Subtest create-signaled: SUCCESS (0.000s)
[577.818414] [072/132] (446s left) gem_exec_reloc (basic-cpu)
[577.876267] Subtest basic-cpu: SKIP (0.000s)
[577.948584] [073/132] (446s left) kms_psr (fbc-psr-sprite-plane-move)
[578.044241] Starting subtest: fbc-psr-sprite-plane-move
[578.047457] Starting dynamic subtest: eDP-1
[578.052608] Dynamic subtest eDP-1: SKIP (0.000s)
[578.057849] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[578.121845] [074/132] (446s left) kms_plane (planar-pixel-format-settings)
[578.216817] Starting subtest: planar-pixel-format-settings
[578.283281] Subtest planar-pixel-format-settings: SUCCESS (0.068s)
[578.389644] [075/132] (446s left) gem_softpin (evict-snoop-interruptible)
[578.450767] Starting subtest: evict-snoop-interruptible
[578.456330] Subtest evict-snoop-interruptible: SKIP (0.000s)
[578.570746] [076/132] (445s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[578.673448] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[578.675791] Starting dynamic subtest: pipe-A-eDP-1
[578.698928] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[578.709898] Starting dynamic subtest: pipe-B-eDP-1
[578.734905] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[578.744910] Starting dynamic subtest: pipe-C-eDP-1
[578.770252] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[578.780233] Starting dynamic subtest: pipe-D-eDP-1
[578.806495] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[578.811775] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.009s)
[581.017791] [077/132] (443s left) drm_mm (drm_mm)
[581.063924] Starting subtest: drm_mm
[581.092372] Starting dynamic subtest: drm_test_mm_init
[581.189567] Dynamic subtest drm_test_mm_init: SUCCESS (0.097s)
[581.201943] Starting dynamic subtest: drm_test_mm_debug
[581.283321] Dynamic subtest drm_test_mm_debug: SUCCESS (0.093s)
[581.295445] Starting dynamic subtest: drm_test_mm_align32
[581.366337] Dynamic subtest drm_test_mm_align32: SUCCESS (0.082s)
[581.379590] Starting dynamic subtest: drm_test_mm_align64
[581.458247] Dynamic subtest drm_test_mm_align64: SUCCESS (0.090s)
[581.468959] Starting dynamic subtest: drm_test_mm_lowest
[581.540076] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.082s)
[581.551013] Starting dynamic subtest: drm_test_mm_highest
[581.616024] Dynamic subtest drm_test_mm_highest: SUCCESS (0.076s)
[581.618443] Subtest drm_mm: SUCCESS (0.560s)
[581.780184] [078/132] (442s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[581.886169] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[581.891093] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[581.986535] [079/132] (442s left) gem_userptr_blits (unsync-unmap-after-close)
[582.050021] Subtest unsync-unmap-after-close: SKIP (0.000s)
[582.165950] [080/132] (442s left) sw_sync (sync_busy_fork)
[582.199826] Starting subtest: sync_busy_fork
[583.211255] Subtest sync_busy_fork: SUCCESS (1.010s)
[583.279165] [081/132] (441s left) kms_prop_blob (basic)
[583.339334] Starting subtest: basic
[583.341965] Subtest basic: SUCCESS (0.000s)
[583.446478] [082/132] (441s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[583.547263] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[583.552519] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[583.652130] [083/132] (441s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[583.753822] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[583.756314] Starting dynamic subtest: pipe-A-eDP-1
[583.781094] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[583.792589] Starting dynamic subtest: pipe-B-eDP-1
[583.817524] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[583.827237] Starting dynamic subtest: pipe-C-eDP-1
[583.852220] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[583.862203] Starting dynamic subtest: pipe-D-eDP-1
[583.888228] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[583.893206] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[585.972512] [084/132] (438s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[586.072258] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[586.077351] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[586.168491] [085/132] (438s left) gem_set_tiling_vs_pwrite
[586.307861] [086/132] (438s left) gem_ctx_isolation (dirty-create)
[586.376687] Starting subtest: dirty-create
[586.379482] Starting dynamic subtest: rcs0
[586.560158] Dynamic subtest rcs0: SUCCESS (0.183s)
[586.570911] Starting dynamic subtest: bcs0
[586.828363] Dynamic subtest bcs0: SUCCESS (0.267s)
[586.839455] Starting dynamic subtest: vcs0
[587.049122] Dynamic subtest vcs0: SUCCESS (0.219s)
[587.061242] Starting dynamic subtest: vcs1
[588.699912] Dynamic subtest vcs1: SUCCESS (1.648s)
[588.711581] Starting dynamic subtest: vecs0
[590.711223] Dynamic subtest vecs0: SUCCESS (2.011s)
[590.722235] Starting dynamic subtest: ccs0
[592.720659] Dynamic subtest ccs0: SUCCESS (2.008s)
[592.723370] Subtest dirty-create: SUCCESS (6.343s)
[592.991019] [087/132] (432s left) kms_draw_crc (draw-method-render)
[593.082870] Starting subtest: draw-method-render
[593.085124] Starting dynamic subtest: xrgb8888-untiled
[593.326438] Dynamic subtest xrgb8888-untiled: SUCCESS (0.245s)
[593.339703] Starting dynamic subtest: rgb565-untiled
[593.561218] Dynamic subtest rgb565-untiled: SUCCESS (0.231s)
[593.573442] Starting dynamic subtest: xrgb2101010-untiled
[593.793066] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.232s)
[593.803558] Starting dynamic subtest: xbgr16161616f-untiled
[594.103974] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.310s)
[594.119017] Starting dynamic subtest: xrgb8888-xtiled
[594.292686] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.188s)
[594.302868] Starting dynamic subtest: rgb565-xtiled
[594.415664] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[594.426089] Starting dynamic subtest: xrgb2101010-xtiled
[594.539091] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[594.549536] Starting dynamic subtest: xbgr16161616f-xtiled
[594.681910] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.142s)
[594.694851] Starting dynamic subtest: xrgb8888-4tiled
[594.804971] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.120s)
[594.814939] Starting dynamic subtest: rgb565-4tiled
[594.938043] Dynamic subtest rgb565-4tiled: SUCCESS (0.131s)
[594.948412] Starting dynamic subtest: xrgb2101010-4tiled
[595.059778] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[595.070294] Starting dynamic subtest: xbgr16161616f-4tiled
[595.215143] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.155s)
[595.217134] Subtest draw-method-render: SUCCESS (2.135s)
[595.324081] [088/132] (429s left) kms_chamelium_frames (dp-crc-fast)
[595.720269] Subtest dp-crc-fast: SKIP (0.000s)
[596.498267] [089/132] (428s left) syncobj_wait (wait-all-complex)
[596.558338] Starting subtest: wait-all-complex
[596.775625] Subtest wait-all-complex: SUCCESS (0.217s)
[596.866775] [090/132] (428s left) i915_query (query-topology-coherent-slice-mask)
[596.926831] Starting subtest: query-topology-coherent-slice-mask
[596.932029] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[597.011245] [091/132] (428s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[597.112353] Starting subtest: y-tiled-64bpp-rotate-0
[597.118222] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[597.219051] [092/132] (428s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[597.267711] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[597.368123] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.101s)
[597.447037] [093/132] (427s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[597.537985] Starting subtest: primary-yf-tiled-reflect-x-180
[597.598862] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.060s)
[597.703798] [094/132] (427s left) gem_exec_schedule (u-submit-golden-slice)
[597.785352] Starting subtest: u-submit-golden-slice
[597.788198] Starting dynamic subtest: rcs0
[597.821066] Dynamic subtest rcs0: SUCCESS (0.042s)
[597.832119] Starting dynamic subtest: bcs0
[597.849612] Dynamic subtest bcs0: SUCCESS (0.028s)
[597.860299] Starting dynamic subtest: vcs0
[597.888044] Dynamic subtest vcs0: SUCCESS (0.037s)
[597.899854] Starting dynamic subtest: vcs1
[597.930360] Dynamic subtest vcs1: SUCCESS (0.041s)
[597.941309] Starting dynamic subtest: vecs0
[597.968132] Dynamic subtest vecs0: SUCCESS (0.037s)
[597.979858] Starting dynamic subtest: ccs0
[598.011407] Dynamic subtest ccs0: SUCCESS (0.042s)
[598.013910] Subtest u-submit-golden-slice: SUCCESS (0.234s)
[599.609889] [095/132] (425s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[599.703064] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[599.708249] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[599.837138] [096/132] (425s left) sw_sync (alloc_fence_invalid_timeline)
[599.874552] Starting subtest: alloc_fence_invalid_timeline
[599.877128] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[599.927568] [097/132] (425s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[600.021885] Starting subtest: pr-overlay-plane-update-continuous-sf
[600.026285] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[600.092938] [098/132] (425s left) gem_pxp (reject-modify-context-protection-on)
[600.219423] Starting subtest: reject-modify-context-protection-on
[600.222222] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[600.325830] [099/132] (425s left) fbdev (info)
[600.364656] Starting subtest: info
[600.367250] Subtest info: SUCCESS (0.000s)
[600.444465] [100/132] (425s left) kms_psr (fbc-pr-cursor-render)
[600.529716] Starting subtest: fbc-pr-cursor-render
[600.534461] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[600.606272] [101/132] (425s left) kms_display_modes (extended-mode-basic)
[600.710460] Starting subtest: extended-mode-basic
[600.714955] Subtest extended-mode-basic: SKIP (0.000s)
[600.760464] [102/132] (424s left) gem_exec_balancer (busy)
[600.824611] Starting subtest: busy
[602.507828] Subtest busy: SUCCESS (1.681s)
[602.831427] [103/132] (422s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[602.929262] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[602.934273] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[603.039453] [104/132] (422s left) i915_drm_fdinfo (all-busy-idle-check-all)
[603.085570] Starting subtest: all-busy-idle-check-all
[603.091268] Subtest all-busy-idle-check-all: SKIP (0.004s)
[603.169593] [105/132] (422s left) kms_vblank (ts-continuation-modeset-rpm)
[603.285039] Starting subtest: ts-continuation-modeset-rpm
[603.287467] Starting dynamic subtest: pipe-A-eDP-1
[605.809222] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.525s)
[605.825721] Starting dynamic subtest: pipe-D-eDP-1
[608.049800] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.234s)
[608.052504] Subtest ts-continuation-modeset-rpm: SUCCESS (4.763s)
[608.931826] [106/132] (416s left) kms_fbcon_fbt (fbc)
[608.997408] Starting subtest: fbc
[615.369953] Subtest fbc: SUCCESS (6.375s)
[616.469112] [107/132] (409s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[616.568169] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[623.921120] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.357s)
[624.058872] [108/132] (401s left) i915_pm_rps (engine-order)
[624.120706] Starting subtest: engine-order
[628.949454] Subtest engine-order: SUCCESS (4.827s)
[629.073587] [109/132] (396s left) gem_softpin (allocator-nopin-reserve)
[629.128436] Starting subtest: allocator-nopin-reserve
[629.233760] Subtest allocator-nopin-reserve: SUCCESS (0.104s)
[629.404780] [110/132] (396s left) gem_mmap_offset (ptrace)
[629.452561] Starting subtest: ptrace
[629.458294] Starting dynamic subtest: smem0-wb
[629.473420] Dynamic subtest smem0-wb: SUCCESS (0.015s)
[629.487424] Starting dynamic subtest: smem0-wc
[629.493432] Dynamic subtest smem0-wc: SUCCESS (0.018s)
[629.504428] Starting dynamic subtest: smem0-uc
[629.513285] Dynamic subtest smem0-uc: SUCCESS (0.021s)
[629.516220] Subtest ptrace: SUCCESS (0.062s)
[629.603837] [111/132] (396s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[629.999838] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[630.801629] [112/132] (395s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[630.905351] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[630.910266] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[630.991187] [113/132] (395s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[631.091142] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[637.835220] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.747s)
[637.940781] [114/132] (388s left) perf (polling)
[638.082137] Starting subtest: polling
[638.084783] Starting dynamic subtest: 0-rcs0
[648.106296] Dynamic subtest 0-rcs0: SUCCESS (10.023s)
[648.123173] Starting dynamic subtest: 1-vcs0
[658.137887] Dynamic subtest 1-vcs0: SUCCESS (10.022s)
[658.142917] Subtest polling: SUCCESS (20.049s)
[658.189743] [115/132] (368s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[658.286288] Starting subtest: fbcpsr-tiling-y
[658.291592] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[658.385927] [116/132] (367s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[658.478672] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[658.483858] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[658.577828] [117/132] (367s left) gem_mmap_gtt (hang-user)
[658.634896] Subtest hang-user: SKIP (0.000s)
[658.708598] [118/132] (367s left) i915_pm_rps (thresholds-idle-park)
[658.771151] Starting subtest: thresholds-idle-park
[658.776414] Subtest thresholds-idle-park: SKIP (0.001s)
[658.894526] [119/132] (367s left) gem_sync (basic-each)
[658.973637] Starting subtest: basic-each
[661.584338] Subtest basic-each: SUCCESS (2.621s)
[661.783235] [120/132] (364s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[661.845440] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[661.848105] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[661.958526] [121/132] (364s left) kms_pm_rpm (dpms-non-lpsp)
[663.700055] Starting subtest: dpms-non-lpsp
[663.705722] Subtest dpms-non-lpsp: SKIP (0.001s)
[664.204571] [122/132] (362s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[664.291005] Starting subtest: random-ccs-data-yf-tiled-ccs
[664.293436] Starting dynamic subtest: pipe-A-eDP-1
[664.319074] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[664.330524] Starting dynamic subtest: pipe-B-eDP-1
[664.358425] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[664.369202] Starting dynamic subtest: pipe-C-eDP-1
[664.397297] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[664.407556] Starting dynamic subtest: pipe-D-eDP-1
[664.435564] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[664.440258] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[666.576589] [123/132] (360s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[666.679445] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[674.064672] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.390s)
[674.191961] [124/132] (352s left) gem_exec_schedule (timeslicing)
[674.269293] Starting subtest: timeslicing
[674.272214] Starting dynamic subtest: rcs0
[674.279020] Dynamic subtest rcs0: SUCCESS (0.021s)
[674.290459] Starting dynamic subtest: bcs0
[674.299313] Dynamic subtest bcs0: SUCCESS (0.020s)
[674.310711] Starting dynamic subtest: vcs0
[674.319159] Dynamic subtest vcs0: SUCCESS (0.019s)
[674.330123] Starting dynamic subtest: vcs1
[674.341020] Dynamic subtest vcs1: SUCCESS (0.021s)
[674.352093] Starting dynamic subtest: vecs0
[674.361414] Dynamic subtest vecs0: SUCCESS (0.020s)
[674.372422] Starting dynamic subtest: ccs0
[674.382423] Dynamic subtest ccs0: SUCCESS (0.020s)
[674.385316] Subtest timeslicing: SUCCESS (0.127s)
[675.576502] [125/132] (351s left) gen9_exec_parse (bb-chained)
[675.631030] Subtest bb-chained: SKIP (0.000s)
[675.686898] [126/132] (351s left) kms_getfb (getfb-handle-protection)
[675.785150] Starting subtest: getfb-handle-protection
[675.801415] Subtest getfb-handle-protection: SUCCESS (0.018s)
[675.920789] [127/132] (350s left) gem_userptr_blits (coherency-sync)
[675.993786] Starting subtest: coherency-sync
[676.213153] Subtest coherency-sync: SKIP (0.173s)
[676.298747] [128/132] (350s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[676.440289] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[676.444464] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[676.525110] [129/132] (350s left) perf (gen12-oa-tlb-invalidate)
[676.653714] Starting subtest: gen12-oa-tlb-invalidate
[676.656342] Starting dynamic subtest: 0-rcs0
[686.685787] Dynamic subtest 0-rcs0: SUCCESS (10.032s)
[686.698078] Starting dynamic subtest: 1-vcs1
[696.739038] Dynamic subtest 1-vcs1: SUCCESS (10.052s)
[696.741473] Subtest gen12-oa-tlb-invalidate: SUCCESS (20.086s)
[696.791338] [130/132] (330s left) gem_exec_reloc (basic-wc-cpu)
[696.859299] Subtest basic-wc-cpu: SKIP (0.000s)
[696.929249] [131/132] (330s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[697.030002] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[703.368415] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.343s)
[704.530540] [132/132] (322s left) gem_lmem_swapping (parallel-random-engines)
[704.578203] Subtest parallel-random-engines: SKIP (0.000s)
[704.631919] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_cursor_legacy
results: parsing output: 2/ for test: gem_ctx_param
results: parsing output: 3/ for test: gem_exec_balancer
results: parsing output: 4/ for test: gem_wait
results: parsing output: 5/ for test: gem_ringfill
results: parsing output: 6/ for test: kms_psr2_sf
results: parsing output: 7/ for test: gem_exec_params
results: parsing output: 8/ for test: gem_userptr_blits
results: parsing output: 9/ for test: gem_ctx_persistence
results: parsing output: 10/ for test: kms_async_flips
results: parsing output: 11/ for test: kms_big_fb
results: parsing output: 12/ for test: kms_cursor_crc
results: parsing output: 13/ for test: gem_partial_pwrite_pread
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: api_intel_bb
results: parsing output: 17/ for test: kms_busy
results: parsing output: 18/ for test: gem_mmap_wc
results: parsing output: 19/ for test: gem_userptr_blits
results: parsing output: 20/ for test: gem_eio
results: parsing output: 21/ for test: sw_sync
results: parsing output: 22/ for test: kms_lease
results: parsing output: 23/ for test: kms_pipe_crc_basic
results: parsing output: 24/ for test: gem_exec_gttfill
results: parsing output: 25/ for test: sysfs_heartbeat_interval
results: parsing output: 26/ for test: prime_mmap
results: parsing output: 27/ for test: kms_plane
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: prime_busy
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_exec_fence
results: parsing output: 33/ for test: kms_setmode
results: parsing output: 34/ for test: kms_selftest
results: parsing output: 35/ for test: kms_flip_scaled_crc
results: parsing output: 36/ for test: syncobj_timeline
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: gem_ctx_param
results: parsing output: 39/ for test: kms_flip_scaled_crc
results: parsing output: 40/ for test: gem_exec_schedule
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: kms_content_protection
results: parsing output: 43/ for test: kms_atomic_transition
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_cursor_crc
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: gem_exec_reloc
results: parsing output: 50/ for test: gem_ctx_engines
results: parsing output: 51/ for test: kms_properties
results: parsing output: 52/ for test: api_intel_bb
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: sw_sync
results: parsing output: 55/ for test: vgem_basic
results: parsing output: 56/ for test: gem_ctx_sseu
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: syncobj_timeline
results: parsing output: 59/ for test: kms_joiner
results: parsing output: 60/ for test: i915_module_load
results: parsing output: 61/ for test: kms_plane
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_ccs
results: parsing output: 64/ for test: kms_cursor_crc
results: parsing output: 65/ for test: kms_plane_scaling
results: parsing output: 66/ for test: gem_media_fill
results: parsing output: 67/ for test: kms_ccs
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: kms_color
results: parsing output: 70/ for test: syncobj_basic
results: parsing output: 71/ for test: gem_exec_reloc
results: parsing output: 72/ for test: kms_psr
results: parsing output: 73/ for test: kms_plane
results: parsing output: 74/ for test: gem_softpin
results: parsing output: 75/ for test: kms_ccs
results: parsing output: 76/ for test: drm_mm
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_userptr_blits
results: parsing output: 79/ for test: sw_sync
results: parsing output: 80/ for test: kms_prop_blob
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_ccs
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 85/ for test: gem_ctx_isolation
results: parsing output: 86/ for test: kms_draw_crc
results: parsing output: 87/ for test: kms_chamelium_frames
results: parsing output: 88/ for test: syncobj_wait
results: parsing output: 89/ for test: i915_query
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: syncobj_timeline
results: parsing output: 92/ for test: kms_rotation_crc
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: sw_sync
results: parsing output: 96/ for test: kms_psr2_sf
results: parsing output: 97/ for test: gem_pxp
results: parsing output: 98/ for test: fbdev
results: parsing output: 99/ for test: kms_psr
results: parsing output: 100/ for test: kms_display_modes
results: parsing output: 101/ for test: gem_exec_balancer
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: i915_drm_fdinfo
results: parsing output: 104/ for test: kms_vblank
results: parsing output: 105/ for test: kms_fbcon_fbt
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: i915_pm_rps
results: parsing output: 108/ for test: gem_softpin
results: parsing output: 109/ for test: gem_mmap_offset
results: parsing output: 110/ for test: kms_chamelium_hpd
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: perf
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: gem_mmap_gtt
results: parsing output: 117/ for test: i915_pm_rps
results: parsing output: 118/ for test: gem_sync
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: kms_pm_rpm
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_exec_schedule
results: parsing output: 124/ for test: gen9_exec_parse
results: parsing output: 125/ for test: kms_getfb
results: parsing output: 126/ for test: gem_userptr_blits
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: perf
results: parsing output: 129/ for test: gem_exec_reloc
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_lmem_swapping
Done.