[62.271543] Initializing watchdogs
[62.271586]   /dev/watchdog0
[62.285131] [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]
[62.289196] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.291542] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.294989] [001/130] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[62.434605] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[62.438570] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[62.567296] [002/130] (960s left) gem_exec_schedule (out-order)
[62.635786] Starting subtest: out-order
[62.637946] Starting dynamic subtest: rcs0
[62.736728] Dynamic subtest rcs0: SUCCESS (0.102s)
[62.744806] Starting dynamic subtest: vcs0
[62.817444] Dynamic subtest vcs0: SUCCESS (0.080s)
[62.827568] Starting dynamic subtest: vcs1
[62.891749] Dynamic subtest vcs1: SUCCESS (0.074s)
[62.903088] Starting dynamic subtest: vecs0
[62.963748] Dynamic subtest vecs0: SUCCESS (0.072s)
[62.974086] Starting dynamic subtest: ccs0
[63.048570] Dynamic subtest ccs0: SUCCESS (0.084s)
[63.051035] Subtest out-order: SUCCESS (0.414s)
[64.004747] [003/130] (958s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[64.096844] Starting subtest: fbc-psr2-cursor-mmap-gtt
[64.099128] Starting dynamic subtest: eDP-1
[64.103351] Dynamic subtest eDP-1: SKIP (0.000s)
[64.107641] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[64.191169] [004/130] (958s left) kms_pipe_crc_basic (suspend-read-crc)
[64.279611] Starting subtest: suspend-read-crc
[64.282034] Starting dynamic subtest: pipe-A-eDP-1
[81.840149] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.814s)
[81.849721] Starting dynamic subtest: pipe-B-eDP-1
[99.853155] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.168s)
[99.863230] Starting dynamic subtest: pipe-C-eDP-1
[117.793734] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.035s)
[117.807831] Starting dynamic subtest: pipe-D-eDP-1
[135.777276] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.031s)
[135.779451] Subtest suspend-read-crc: SUCCESS (16.051s)
[136.635455] [005/130] (886s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[136.726898] Starting subtest: fbcpsr-2p-rte
[136.730883] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[136.848456] [006/130] (886s left) kms_hdr (static-toggle)
[136.930007] Starting subtest: static-toggle
[136.936614] Subtest static-toggle: SKIP (0.000s)
[137.026932] [007/130] (885s left) gem_userptr_blits (sync-unmap-after-close)
[137.082449] Starting subtest: sync-unmap-after-close
[137.095079] Subtest sync-unmap-after-close: SUCCESS (0.013s)
[137.253378] [008/130] (885s left) kms_selftest (drm_cmdline_parser)
[137.294130] Starting subtest: drm_cmdline_parser
[137.307785] Starting dynamic subtest: drm_test_cmdline_force_d_only
[137.434122] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.126s)
[137.443614] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[137.556367] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.122s)
[137.564824] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[137.669894] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.114s)
[137.679023] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[137.775885] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.107s)
[137.785148] Starting dynamic subtest: drm_test_cmdline_force_e_only
[137.900214] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.124s)
[137.909827] Starting dynamic subtest: drm_test_cmdline_res
[138.042360] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.143s)
[138.051277] Starting dynamic subtest: drm_test_cmdline_res_vesa
[138.174948] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.132s)
[138.184719] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[138.308923] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.134s)
[138.318737] Starting dynamic subtest: drm_test_cmdline_res_rblank
[138.420103] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.112s)
[138.429169] Starting dynamic subtest: drm_test_cmdline_res_bpp
[138.564947] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.144s)
[138.573995] Starting dynamic subtest: drm_test_cmdline_res_refresh
[138.691787] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.127s)
[138.699400] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[138.778139] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.087s)
[138.787252] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[138.875017] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.097s)
[138.884082] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[138.985994] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.112s)
[138.995733] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[139.099057] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.113s)
[139.108435] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[139.214081] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.115s)
[139.221231] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[139.323748] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.111s)
[139.330810] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[139.440131] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.115s)
[139.448285] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[139.544411] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.106s)
[139.551462] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[139.667892] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.122s)
[139.675468] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[139.771633] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.105s)
[139.778759] Starting dynamic subtest: drm_test_cmdline_name
[139.887556] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.115s)
[139.894749] Starting dynamic subtest: drm_test_cmdline_name_bpp
[140.011934] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.124s)
[140.019457] Starting dynamic subtest: drm_test_cmdline_name_option
[140.130950] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.119s)
[140.139086] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[140.233672] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.105s)
[140.241166] Starting dynamic subtest: drm_test_cmdline_rotate_0
[140.343262] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.109s)
[140.351108] Starting dynamic subtest: drm_test_cmdline_rotate_90
[140.447604] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.105s)
[140.454747] Starting dynamic subtest: drm_test_cmdline_rotate_180
[140.568987] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.120s)
[140.576607] Starting dynamic subtest: drm_test_cmdline_rotate_270
[140.684607] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.116s)
[140.691751] Starting dynamic subtest: drm_test_cmdline_hmirror
[140.808154] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.124s)
[140.816164] Starting dynamic subtest: drm_test_cmdline_vmirror
[140.923260] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.114s)
[140.932114] Starting dynamic subtest: drm_test_cmdline_margin_options
[141.043518] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.121s)
[141.050653] Starting dynamic subtest: drm_test_cmdline_multiple_options
[141.154927] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.111s)
[141.162500] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[141.291573] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.139s)
[141.299017] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[141.404645] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.111s)
[141.414288] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[141.520191] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.115s)
[141.529773] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[141.638123] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.118s)
[141.645761] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[141.774474] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.137s)
[141.781791] Starting dynamic subtest: drm_test_cmdline_invalid
[141.935606] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.163s)
[141.942951] Starting dynamic subtest: drm_test_cmdline_tv_options
[142.100892] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.164s)
[142.102886] Subtest drm_cmdline_parser: SUCCESS (4.823s)
[142.311856] [009/130] (880s left) gem_exec_gttfill (engines)
[142.383957] Starting subtest: engines
[142.386299] Starting dynamic subtest: rcs0
[165.800766] Dynamic subtest rcs0: SUCCESS (23.441s)
[165.811583] Starting dynamic subtest: bcs0
[189.160318] Dynamic subtest bcs0: SUCCESS (23.354s)
[189.169890] Starting dynamic subtest: vcs0
[212.477052] Dynamic subtest vcs0: SUCCESS (23.321s)
[212.487786] Starting dynamic subtest: vcs1
[235.760574] Dynamic subtest vcs1: SUCCESS (23.286s)
[235.771900] Starting dynamic subtest: vecs0
[259.083523] Dynamic subtest vecs0: SUCCESS (23.315s)
[259.095610] Starting dynamic subtest: ccs0
[282.352142] Dynamic subtest ccs0: SUCCESS (23.269s)
[282.355201] Subtest engines: SUCCESS (139.994s)
[282.563281] [010/130] (740s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[282.710626] Starting subtest: fbc-rgb101010-draw-blt
[287.280084] Subtest fbc-rgb101010-draw-blt: SUCCESS (4.573s)
[288.488877] [011/130] (734s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[288.586737] Starting subtest: yf-tiled-8bpp-rotate-270
[288.591274] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[288.710397] [012/130] (734s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[288.813873] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[288.818323] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[288.944258] [013/130] (734s left) i915_getparams_basic (basic-eu-total)
[289.001688] Starting subtest: basic-eu-total
[289.006224] Subtest basic-eu-total: SUCCESS (0.000s)
[289.123045] [014/130] (734s left) prime_vgem (fence-wait)
[289.279425] Starting subtest: fence-wait
[289.281769] Starting dynamic subtest: rcs0
[290.333661] Dynamic subtest rcs0: SUCCESS (1.054s)
[290.344603] Starting dynamic subtest: bcs0
[291.407572] Dynamic subtest bcs0: SUCCESS (1.074s)
[291.419526] Starting dynamic subtest: vcs0
[292.455996] Dynamic subtest vcs0: SUCCESS (1.046s)
[292.467786] Starting dynamic subtest: vcs1
[293.499456] Dynamic subtest vcs1: SUCCESS (1.042s)
[293.510794] Starting dynamic subtest: vecs0
[294.541539] Dynamic subtest vecs0: SUCCESS (1.041s)
[294.553518] Starting dynamic subtest: ccs0
[295.586561] Dynamic subtest ccs0: SUCCESS (1.043s)
[295.589557] Subtest fence-wait: SUCCESS (6.308s)
[295.760618] [FACT prime_vgem (fence-wait)] new: kernel.kmod_is_loaded.vgem: true
[295.764952] [015/130] (727s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[295.868032] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[295.871980] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[295.994183] [016/130] (727s left) gem_ctx_shared (exec-shared-gtt)
[296.065756] Starting subtest: exec-shared-gtt
[296.068419] Starting dynamic subtest: rcs0
[296.076752] Dynamic subtest rcs0: SUCCESS (0.010s)
[296.088982] Starting dynamic subtest: bcs0
[296.091611] Dynamic subtest bcs0: SUCCESS (0.006s)
[296.100846] Starting dynamic subtest: vcs0
[296.103174] Dynamic subtest vcs0: SUCCESS (0.008s)
[296.112403] Starting dynamic subtest: vcs1
[296.114325] Dynamic subtest vcs1: SUCCESS (0.006s)
[296.122322] Starting dynamic subtest: vecs0
[296.124194] Dynamic subtest vecs0: SUCCESS (0.005s)
[296.132441] Starting dynamic subtest: ccs0
[296.134360] Dynamic subtest ccs0: SUCCESS (0.005s)
[296.136279] Subtest exec-shared-gtt: SUCCESS (0.042s)
[296.318868] [017/130] (727s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[296.444899] Starting subtest: modeset-atomic-cursor-hotspot
[296.449555] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[296.610891] [018/130] (726s left) kms_plane_cursor (viewport)
[296.705796] Starting subtest: viewport
[296.709309] Starting dynamic subtest: pipe-A-eDP-1-size-64
[299.160989] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.456s)
[299.220995] Starting dynamic subtest: pipe-A-eDP-1-size-128
[301.695190] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.482s)
[301.754783] Starting dynamic subtest: pipe-A-eDP-1-size-256
[304.240535] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.494s)
[304.608494] Starting dynamic subtest: pipe-B-eDP-1-size-64
[307.733771] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.144s)
[307.794616] Starting dynamic subtest: pipe-B-eDP-1-size-128
[310.256613] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.471s)
[310.319392] Starting dynamic subtest: pipe-B-eDP-1-size-256
[312.756091] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.448s)
[313.108152] Starting dynamic subtest: pipe-C-eDP-1-size-64
[315.980746] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.884s)
[316.018677] Starting dynamic subtest: pipe-C-eDP-1-size-128
[318.146937] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.135s)
[318.185780] Starting dynamic subtest: pipe-C-eDP-1-size-256
[320.236243] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.058s)
[320.565029] Starting dynamic subtest: pipe-D-eDP-1-size-64
[323.288949] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.737s)
[323.328122] Starting dynamic subtest: pipe-D-eDP-1-size-128
[325.467587] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.146s)
[325.504675] Starting dynamic subtest: pipe-D-eDP-1-size-256
[327.589660] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.093s)
[327.911131] Subtest viewport: SUCCESS (31.213s)
[328.808858] [019/130] (694s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[328.910062] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[328.914368] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[329.042864] [020/130] (694s left) kms_psr2_su (page_flip-xrgb8888)
[329.111067] Subtest page_flip-XRGB8888: SKIP (0.000s)
[329.184529] [021/130] (694s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[329.292542] Starting subtest: 4-tiled-32bpp-rotate-90
[329.312955] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.020s)
[329.483970] [022/130] (694s left) kms_vblank (ts-continuation-dpms-rpm)
[329.581786] Starting subtest: ts-continuation-dpms-rpm
[329.583588] Starting dynamic subtest: pipe-A-eDP-1
[332.073522] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.491s)
[332.087389] Starting dynamic subtest: pipe-D-eDP-1
[334.326868] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.250s)
[334.328795] Subtest ts-continuation-dpms-rpm: SUCCESS (4.745s)
[335.233186] [023/130] (688s left) template (a)
[335.290196] Starting subtest: A
[335.292387] Subtest A: SUCCESS (0.000s)
[335.406966] [024/130] (688s left) i915_pm_rc6_residency (rc6-accuracy)
[335.467571] Starting subtest: rc6-accuracy
[335.469942] Starting dynamic subtest: gt0
[338.643041] Dynamic subtest gt0: SUCCESS (3.171s)
[338.654938] Starting dynamic subtest: gt1
[341.814726] Dynamic subtest gt1: SUCCESS (3.169s)
[341.817444] Subtest rc6-accuracy: SUCCESS (6.348s)
[341.916891] [025/130] (681s left) gem_exec_balancer (parallel)
[342.173562] Starting subtest: parallel
[343.071902] Subtest parallel: SUCCESS (0.898s)
[343.455083] [026/130] (680s left) kms_content_protection (atomic)
[343.660351] Starting subtest: atomic
[343.665015] Subtest atomic: SKIP (0.000s)
[343.830839] [027/130] (680s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[343.955423] Starting subtest: cursor-vs-flip-atomic
[373.170526] Subtest cursor-vs-flip-atomic: SUCCESS (29.214s)
[374.066762] [028/130] (649s left) perf_pmu (enable-race)
[374.114367] Starting subtest: enable-race
[374.116752] Starting dynamic subtest: rcs0
[386.880853] Dynamic subtest rcs0: SUCCESS (12.763s)
[386.890412] Starting dynamic subtest: bcs0
[399.621925] Dynamic subtest bcs0: SUCCESS (12.737s)
[399.631228] Starting dynamic subtest: vcs0
[415.390067] Dynamic subtest vcs0: SUCCESS (15.764s)
[415.401932] Starting dynamic subtest: vcs1
[431.388028] Dynamic subtest vcs1: SUCCESS (15.995s)
[431.399640] Starting dynamic subtest: vecs0
[447.388171] Dynamic subtest vecs0: SUCCESS (15.998s)
[447.400294] Starting dynamic subtest: ccs0
[460.136456] Dynamic subtest ccs0: SUCCESS (12.748s)
[460.139107] Subtest enable-race: SUCCESS (86.018s)
[460.267770] [029/130] (563s left) vgem_basic (create)
[460.308047] Starting subtest: create
[460.310366] Subtest create: SUCCESS (0.000s)
[460.389242] [030/130] (563s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[460.490796] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[460.493065] Starting dynamic subtest: pipe-A-eDP-1
[460.552045] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[460.566266] Starting dynamic subtest: pipe-B-eDP-1
[460.625937] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[460.637344] Starting dynamic subtest: pipe-C-eDP-1
[460.695974] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[460.706156] Starting dynamic subtest: pipe-D-eDP-1
[460.764007] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[460.768447] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.009s)
[462.739917] [031/130] (561s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt)
[462.848235] Starting subtest: psr-rgb101010-draw-mmap-gtt
[462.852372] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[462.984411] [032/130] (561s left) i915_query (query-regions-unallocated)
[463.048364] Starting subtest: query-regions-unallocated
[463.050613] Subtest query-regions-unallocated: SUCCESS (0.000s)
[463.172140] [033/130] (560s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[463.469496] Starting subtest: cursor-rapid-movement-max-size
[463.475878] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[463.635595] [034/130] (560s left) api_intel_bb (intel-bb-blit-x)
[463.695611] Starting subtest: intel-bb-blit-x
[467.466354] Subtest intel-bb-blit-x: SUCCESS (3.771s)
[467.626216] [035/130] (556s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[467.734435] Starting subtest: bo-too-small-due-to-tiling
[467.738782] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[467.844178] [036/130] (556s left) kms_hdr (bpc-switch-dpms)
[467.936861] Starting subtest: bpc-switch-dpms
[468.989885] Starting dynamic subtest: pipe-A-eDP-1
[473.521075] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.531s)
[473.523467] Subtest bpc-switch-dpms: SUCCESS (5.587s)
[473.661466] [037/130] (550s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[473.759159] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[473.770635] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.010s)
[473.913038] [038/130] (550s left) syncobj_wait (invalid-signal-illegal-handle)
[473.978512] Starting subtest: invalid-signal-illegal-handle
[473.980895] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[474.098448] [039/130] (550s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[474.166861] Starting subtest: legacy-engines-hostile-preempt
[474.169148] Starting dynamic subtest: render
[474.223183] Dynamic subtest render: SUCCESS (0.056s)
[474.233895] Starting dynamic subtest: bsd1
[475.429478] Dynamic subtest bsd1: SUCCESS (1.203s)
[475.441344] Starting dynamic subtest: bsd2
[477.417067] Dynamic subtest bsd2: SUCCESS (1.985s)
[477.428526] Starting dynamic subtest: blt
[477.502533] Dynamic subtest blt: SUCCESS (0.084s)
[477.516866] Starting dynamic subtest: vebox
[479.373091] Dynamic subtest vebox: SUCCESS (1.866s)
[479.376381] Subtest legacy-engines-hostile-preempt: SUCCESS (5.206s)
[479.542229] [040/130] (544s left) kms_pm_rpm (pc8-residency)
[481.579229] Starting subtest: pc8-residency
[481.584152] Subtest pc8-residency: SKIP (0.000s)
[481.967839] [041/130] (542s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[482.071205] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[482.075299] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[482.177532] [042/130] (542s left) kms_sequence (get-busy)
[482.278026] Starting subtest: get-busy
[482.280364] Starting dynamic subtest: pipe-A-eDP-1
[484.213795] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.937s)
[484.223249] Starting dynamic subtest: pipe-B-eDP-1
[486.841363] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.625s)
[486.851371] Starting dynamic subtest: pipe-C-eDP-1
[489.460939] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.621s)
[489.470651] Starting dynamic subtest: pipe-D-eDP-1
[492.007464] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.544s)
[492.009732] Subtest get-busy: SUCCESS (9.733s)
[492.826047] [043/130] (531s left) gen9_exec_parse (batch-without-end)
[492.892621] Subtest batch-without-end: SKIP (0.000s)
[492.972619] [044/130] (531s left) gem_exec_whisper (basic-queues-forked-all)
[493.035273] Starting subtest: basic-queues-forked-all
[512.582530] Subtest basic-queues-forked-all: SUCCESS (19.546s)
[512.794917] [045/130] (511s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[512.891581] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[512.893913] Starting dynamic subtest: pipe-A
[513.228228] Dynamic subtest pipe-A: SUCCESS (0.338s)
[513.238206] Starting dynamic subtest: pipe-B
[514.588857] Dynamic subtest pipe-B: SUCCESS (1.359s)
[514.600468] Starting dynamic subtest: pipe-C
[516.020683] Dynamic subtest pipe-C: SUCCESS (1.431s)
[516.030875] Starting dynamic subtest: pipe-D
[517.438519] Dynamic subtest pipe-D: SUCCESS (1.416s)
[517.440897] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.550s)
[518.651049] [046/130] (505s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[518.752494] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[518.754971] Starting dynamic subtest: pipe-A-eDP-1
[518.777339] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[518.787828] Starting dynamic subtest: pipe-B-eDP-1
[518.807916] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[518.817418] Starting dynamic subtest: pipe-C-eDP-1
[518.838225] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[518.847959] Starting dynamic subtest: pipe-D-eDP-1
[518.870097] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[518.874477] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[520.915149] [047/130] (503s left) kms_psr (pr-cursor-plane-onoff)
[521.012678] Starting subtest: pr-cursor-plane-onoff
[521.016967] Subtest pr-cursor-plane-onoff: SKIP (0.000s)
[521.112315] [048/130] (503s left) kms_cursor_crc (cursor-alpha-transparent)
[521.215089] Starting subtest: cursor-alpha-transparent
[521.217330] Starting dynamic subtest: pipe-A-eDP-1
[521.869608] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.654s)
[521.879055] Starting dynamic subtest: pipe-D-eDP-1
[523.182586] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.312s)
[523.184840] Subtest cursor-alpha-transparent: SUCCESS (1.969s)
[524.076987] [049/130] (500s left) gem_exec_suspend (basic-s3)
[524.140487] Starting subtest: basic-S3
[524.142849] Starting dynamic subtest: smem
[543.145924] Dynamic subtest smem: SUCCESS (6.259s)
[543.149013] Subtest basic-S3: SUCCESS (6.261s)
[543.353701] [050/130] (481s left) kms_properties (colorop-properties-atomic)
[543.459741] Starting subtest: colorop-properties-atomic
[543.461834] Starting dynamic subtest: pipe-A-eDP-1
[543.816645] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.356s)
[543.826305] Starting dynamic subtest: pipe-B-eDP-1
[544.852538] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.041s)
[544.863855] Starting dynamic subtest: pipe-C-eDP-1
[545.892111] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.042s)
[545.902301] Starting dynamic subtest: pipe-D-eDP-1
[546.917279] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.026s)
[546.919182] Subtest colorop-properties-atomic: SUCCESS (3.470s)
[547.738004] [051/130] (477s left) gem_ctx_isolation (clean)
[547.810090] Starting subtest: clean
[547.812376] Starting dynamic subtest: rcs0
[547.846228] Dynamic subtest rcs0: SUCCESS (0.039s)
[547.858581] Starting dynamic subtest: bcs0
[547.935775] Dynamic subtest bcs0: SUCCESS (0.089s)
[547.947715] Starting dynamic subtest: vcs0
[548.003365] Dynamic subtest vcs0: SUCCESS (0.067s)
[548.014959] Starting dynamic subtest: vcs1
[549.166751] Dynamic subtest vcs1: SUCCESS (1.166s)
[549.176428] Starting dynamic subtest: vecs0
[551.153072] Dynamic subtest vecs0: SUCCESS (1.990s)
[551.162659] Starting dynamic subtest: ccs0
[553.222724] Dynamic subtest ccs0: SUCCESS (2.073s)
[553.225096] Subtest clean: SUCCESS (5.429s)
[553.494100] [052/130] (471s left) kms_flip (plain-flip-ts-check)
[553.599118] Starting subtest: plain-flip-ts-check
[553.601420] Starting dynamic subtest: A-eDP1
[562.482195] Dynamic subtest A-eDP1: SUCCESS (8.892s)
[562.493496] Starting dynamic subtest: B-eDP1
[571.309449] Dynamic subtest B-eDP1: SUCCESS (8.832s)
[571.328099] Starting dynamic subtest: C-eDP1
[580.126425] Dynamic subtest C-eDP1: SUCCESS (8.814s)
[580.137492] Starting dynamic subtest: D-eDP1
[588.958300] Dynamic subtest D-eDP1: SUCCESS (8.828s)
[588.960691] Subtest plain-flip-ts-check: SUCCESS (35.376s)
[590.175284] [053/130] (434s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[590.289951] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[590.293907] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[590.413232] [054/130] (434s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[590.523086] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[590.527254] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[590.650212] [055/130] (434s left) gem_wait (write-wait)
[590.707367] Starting subtest: write-wait
[590.709619] Starting dynamic subtest: all-engines
[591.814329] Dynamic subtest all-engines: SUCCESS (1.106s)
[591.830998] Starting dynamic subtest: rcs0
[592.876785] Dynamic subtest rcs0: SUCCESS (1.059s)
[592.895592] Starting dynamic subtest: bcs0
[593.937138] Dynamic subtest bcs0: SUCCESS (1.058s)
[593.955287] Starting dynamic subtest: vcs0
[594.991770] Dynamic subtest vcs0: SUCCESS (1.053s)
[595.009304] Starting dynamic subtest: vcs1
[596.043840] Dynamic subtest vcs1: SUCCESS (1.050s)
[596.061193] Starting dynamic subtest: vecs0
[597.097123] Dynamic subtest vecs0: SUCCESS (1.051s)
[597.114193] Starting dynamic subtest: ccs0
[598.155151] Dynamic subtest ccs0: SUCCESS (1.056s)
[598.157839] Subtest write-wait: SUCCESS (7.449s)
[598.385392] [056/130] (426s left) perf (short-reads)
[598.543496] Starting subtest: short-reads
[598.557310] Subtest short-reads: SUCCESS (0.015s)
[598.596828] [057/130] (426s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[598.693610] Starting subtest: addfb25-y-tiled-small-legacy
[598.697738] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[598.855814] [058/130] (426s left) syncobj_timeline (wait-all-for-submit-complex)
[598.923508] Starting subtest: wait-all-for-submit-complex
[599.138062] Subtest wait-all-for-submit-complex: SUCCESS (0.214s)
[599.236898] [059/130] (425s left) gem_exec_capture (userptr)
[599.309301] Starting subtest: userptr
[600.126828] Subtest userptr: SUCCESS (0.814s)
[600.285182] [060/130] (424s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[600.350418] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[600.451304] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.103s)
[600.544983] [061/130] (424s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[600.650462] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[600.652581] Starting dynamic subtest: pipe-A-default-mode
[600.939476] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[600.943851] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.288s)
[601.794052] [062/130] (423s left) drm_read (empty-nonblock)
[601.941657] Starting subtest: empty-nonblock
[601.943971] Subtest empty-nonblock: SUCCESS (0.000s)
[602.121896] [063/130] (423s left) kms_big_fb (y-tiled-addfb-size-overflow)
[602.218234] Starting subtest: y-tiled-addfb-size-overflow
[602.222411] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[602.340832] [064/130] (423s left) api_intel_allocator (reopen-fork)
[602.405132] Starting subtest: reopen-fork
[605.684728] Subtest reopen-fork: SUCCESS (3.281s)
[605.785024] [065/130] (419s left) gem_exec_store (pages)
[605.856879] Starting subtest: pages
[605.859178] Starting dynamic subtest: rcs0
[605.982561] Dynamic subtest rcs0: SUCCESS (0.126s)
[605.994765] Starting dynamic subtest: bcs0
[606.090364] Dynamic subtest bcs0: SUCCESS (0.107s)
[606.102881] Starting dynamic subtest: vcs0
[606.174216] Dynamic subtest vcs0: SUCCESS (0.081s)
[606.186330] Starting dynamic subtest: vcs1
[606.252659] Dynamic subtest vcs1: SUCCESS (0.078s)
[606.264727] Starting dynamic subtest: vecs0
[606.328563] Dynamic subtest vecs0: SUCCESS (0.075s)
[606.338079] Starting dynamic subtest: ccs0
[606.435759] Dynamic subtest ccs0: SUCCESS (0.105s)
[606.438477] Subtest pages: SUCCESS (0.581s)
[606.651938] [066/130] (418s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[606.705842] Starting subtest: multi-wait-all-for-submit-signaled
[606.708224] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[606.842766] [067/130] (418s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[606.943322] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[613.581156] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.639s)
[613.734598] [068/130] (411s left) kms_addfb_basic (unused-offsets)
[613.803335] Starting subtest: unused-offsets
[613.805272] Subtest unused-offsets: SUCCESS (0.000s)
[614.009041] [069/130] (411s left) syncobj_timeline (single-wait-all-available-signaled)
[614.081658] Starting subtest: single-wait-all-available-signaled
[614.083626] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[614.221003] [070/130] (411s left) gem_eio (in-flight-internal-10ms)
[614.427507] Starting subtest: in-flight-internal-10ms
[616.230996] Subtest in-flight-internal-10ms: SUCCESS (1.803s)
[616.406532] [071/130] (409s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[616.521005] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[617.496914] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (0.977s)
[617.668450] [072/130] (408s left) syncobj_basic (bad-destroy-pad)
[617.726647] Starting subtest: bad-destroy-pad
[617.728549] Subtest bad-destroy-pad: SUCCESS (0.000s)
[617.847175] [073/130] (407s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[617.937832] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[617.939713] Starting dynamic subtest: pipe-A-default-mode
[620.695766] Dynamic subtest pipe-A-default-mode: SUCCESS (2.758s)
[620.698153] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.759s)
[621.562623] [074/130] (404s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[621.672191] Starting subtest: psr-2p-primscrn-cur-indfb-move
[621.676548] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[621.802400] [075/130] (404s left) kms_psr (psr2-sprite-mmap-cpu)
[621.891499] Starting subtest: psr2-sprite-mmap-cpu
[621.893833] Starting dynamic subtest: eDP-1
[623.598686] Dynamic subtest eDP-1: SUCCESS (1.708s)
[623.600958] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.709s)
[624.485903] [076/130] (401s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[624.598845] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[628.990456] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.397s)
[630.180568] [077/130] (395s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[635.261203] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[635.265673] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[636.115735] [078/130] (389s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[636.225434] Starting subtest: x-tiled-8bpp-rotate-0
[637.438085] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.213s)
[637.584794] [079/130] (388s left) perf (gen12-mi-rpc)
[637.693658] Starting subtest: gen12-mi-rpc
[637.695754] Starting dynamic subtest: rcs0
[637.711156] Dynamic subtest rcs0: SUCCESS (0.016s)
[637.713138] Subtest gen12-mi-rpc: SUCCESS (0.017s)
[637.756239] [080/130] (388s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[637.895809] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[637.897675] Starting dynamic subtest: pipe-A-eDP-1
[637.916441] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[637.927780] Starting dynamic subtest: pipe-B-eDP-1
[637.945849] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[637.955154] Starting dynamic subtest: pipe-C-eDP-1
[637.972569] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[637.981752] Starting dynamic subtest: pipe-D-eDP-1
[637.999432] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[638.002993] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[640.088036] [081/130] (386s left) kms_joiner (invalid-modeset-big-joiner)
[640.192022] Starting subtest: invalid-modeset-big-joiner
[640.196267] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[640.279079] [082/130] (385s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[640.374937] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[640.377304] Starting dynamic subtest: pipe-A-default-mode
[643.105414] Dynamic subtest pipe-A-default-mode: SUCCESS (2.728s)
[643.107299] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.730s)
[643.992765] [083/130] (382s left) gem_exec_fence (invalid-timeline-fence-array)
[644.544790] Starting subtest: invalid-timeline-fence-array
[644.551118] Subtest invalid-timeline-fence-array: SUCCESS (0.006s)
[646.321438] [084/130] (380s left) kms_chamelium_color (ctm-negative)
[646.425649] Subtest ctm-negative: SKIP (0.000s)
[646.470953] [085/130] (379s left) gem_exec_flush (basic-wb-prw-default)
[646.526566] Starting subtest: basic-wb-prw-default
[651.986687] Subtest basic-wb-prw-default: SUCCESS (5.458s)
[652.200830] [086/130] (374s left) gem_exec_reloc (basic-softpin)
[652.267373] Subtest basic-softpin: SKIP (0.000s)
[652.361918] [087/130] (374s left) gem_mmap_wc (bad-object)
[652.420496] Subtest bad-object: SKIP (0.000s)
[652.487431] [088/130] (374s left) gem_mmap_gtt (pf-nonblock)
[652.538205] Subtest pf-nonblock: SKIP (0.000s)
[652.603492] [089/130] (373s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[652.976410] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[653.772718] [090/130] (372s left) gem_eio (wait-wedge-immediate)
[653.996339] Starting subtest: wait-wedge-immediate
[655.210531] Subtest wait-wedge-immediate: SUCCESS (1.212s)
[655.391300] [091/130] (371s left) prime_mmap (test_forked_cpu_write)
[655.446555] Starting subtest: test_forked_cpu_write
[655.448568] Starting dynamic subtest: test_forked_cpu_write-smem
[655.452248] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.009s)
[655.454536] Subtest test_forked_cpu_write: SUCCESS (0.009s)
[655.578996] [092/130] (371s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[655.683244] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[655.687730] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[655.833084] [093/130] (370s left) kms_hdmi_inject (inject-4k)
[656.193931] Starting subtest: inject-4k
[656.501035] Subtest inject-4k: SUCCESS (0.310s)
[657.095601] [094/130] (369s left) perf_pmu (frequency)
[657.135315] Starting subtest: frequency
[657.137619] Starting dynamic subtest: gt0
[659.318374] Dynamic subtest gt0: SUCCESS (2.174s)
[659.330542] Starting dynamic subtest: idle-gt0
[659.823480] Dynamic subtest idle-gt0: SUCCESS (0.504s)
[659.836828] Starting dynamic subtest: gt1
[664.195654] Dynamic subtest gt1: SUCCESS (4.367s)
[664.207554] Starting dynamic subtest: idle-gt1
[664.701319] Dynamic subtest idle-gt1: SUCCESS (0.505s)
[664.704241] Subtest frequency: SUCCESS (7.568s)
[664.826149] [095/130] (361s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[664.927643] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[664.929942] Starting dynamic subtest: pipe-A
[665.153961] Dynamic subtest pipe-A: SUCCESS (0.229s)
[665.167301] Starting dynamic subtest: pipe-B
[666.490369] Dynamic subtest pipe-B: SUCCESS (1.335s)
[666.505848] Starting dynamic subtest: pipe-C
[667.776352] Dynamic subtest pipe-C: SUCCESS (1.285s)
[667.786579] Starting dynamic subtest: pipe-D
[669.091797] Dynamic subtest pipe-D: SUCCESS (1.315s)
[669.094079] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.167s)
[670.255668] [096/130] (356s left) gem_mmap_gtt (basic-write-read)
[670.316761] Subtest basic-write-read: SKIP (0.000s)
[670.391983] [097/130] (356s left) gem_softpin (evict-snoop)
[670.456448] Starting subtest: evict-snoop
[670.460820] Subtest evict-snoop: SKIP (0.000s)
[670.611537] [098/130] (356s left) gem_exec_schedule (preempt-user)
[670.687351] Starting subtest: preempt-user
[670.689329] Starting dynamic subtest: rcs0
[670.709511] Dynamic subtest rcs0: SUCCESS (0.029s)
[670.723701] Starting dynamic subtest: bcs0
[670.728717] Dynamic subtest bcs0: SUCCESS (0.019s)
[670.739309] Starting dynamic subtest: vcs0
[670.747274] Dynamic subtest vcs0: SUCCESS (0.018s)
[670.758044] Starting dynamic subtest: vcs1
[670.768768] Dynamic subtest vcs1: SUCCESS (0.021s)
[670.779131] Starting dynamic subtest: vecs0
[670.787828] Dynamic subtest vecs0: SUCCESS (0.018s)
[670.798698] Starting dynamic subtest: ccs0
[670.810441] Dynamic subtest ccs0: SUCCESS (0.022s)
[670.812689] Subtest preempt-user: SUCCESS (0.131s)
[672.233765] [099/130] (354s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[672.351924] Starting subtest: cursor-vs-flip-legacy
[701.601271] Subtest cursor-vs-flip-legacy: SUCCESS (29.251s)
[702.442035] [100/130] (324s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[702.533215] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[702.537730] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[702.655203] [101/130] (324s left) gem_create (create-clear)
[702.714175] Starting subtest: create-clear
[702.716616] Starting dynamic subtest: smem0
[735.378333] Dynamic subtest smem0: SUCCESS (32.668s)
[735.380875] Subtest create-clear: SUCCESS (32.670s)
[735.593870] [102/130] (291s left) kms_flip (absolute-wf_vblank)
[735.697604] Starting subtest: absolute-wf_vblank
[735.699963] Starting dynamic subtest: A-eDP1
[744.437414] Dynamic subtest A-eDP1: SUCCESS (8.741s)
[744.450330] Starting dynamic subtest: B-eDP1
[753.137026] Dynamic subtest B-eDP1: SUCCESS (8.698s)
[753.150126] Starting dynamic subtest: C-eDP1
[761.816734] Dynamic subtest C-eDP1: SUCCESS (8.680s)
[761.829370] Starting dynamic subtest: D-eDP1
[770.513880] Dynamic subtest D-eDP1: SUCCESS (8.695s)
[770.519162] Subtest absolute-wf_vblank: SUCCESS (34.827s)
[771.731677] [103/130] (255s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[771.836922] Starting subtest: plane-use-after-nonblocking-unbind
[771.839264] Starting dynamic subtest: pipe-A-eDP-1
[771.899191] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[772.200919] Starting dynamic subtest: pipe-B-eDP-1
[772.977959] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.785s)
[773.265059] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.429s)
[774.126082] [104/130] (253s left) gem_workarounds (suspend-resume-fd)
[774.206524] Starting subtest: suspend-resume-fd
[789.949129] Subtest suspend-resume-fd: SUCCESS (1.206s)
[790.098806] [105/130] (237s left) kms_psr (fbc-psr-primary-mmap-cpu)
[790.204088] Starting subtest: fbc-psr-primary-mmap-cpu
[790.206470] Starting dynamic subtest: eDP-1
[790.211032] Dynamic subtest eDP-1: SKIP (0.000s)
[790.215990] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s)
[790.309841] [106/130] (236s left) gem_exec_capture (capture-recoverable)
[790.365800] Starting subtest: capture-recoverable
[790.371565] Subtest capture-recoverable: SUCCESS (0.003s)
[790.514492] [107/130] (236s left) gem_mmap_gtt (big-bo-tiledy)
[790.570235] Subtest big-bo-tiledY: SKIP (0.000s)
[790.631946] [108/130] (236s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[790.721584] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[790.723861] Starting dynamic subtest: pipe-A-eDP-1
[790.732955] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[790.743311] Starting dynamic subtest: pipe-B-eDP-1
[790.752598] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[790.762250] Starting dynamic subtest: pipe-C-eDP-1
[790.770674] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[790.780226] Starting dynamic subtest: pipe-D-eDP-1
[790.788933] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[790.793304] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[792.944274] [109/130] (234s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[793.005132] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[793.007141] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[793.144783] [110/130] (234s left) kms_big_fb (linear-8bpp-rotate-90)
[793.254823] Starting subtest: linear-8bpp-rotate-90
[793.274959] Subtest linear-8bpp-rotate-90: SKIP (0.019s)
[793.435231] [111/130] (234s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[793.506007] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[793.572723] [112/130] (233s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[793.709881] Starting subtest: basic-flip-before-cursor-legacy
[794.633946] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.922s)
[795.448981] [113/130] (232s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[795.549167] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[802.444882] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (6.892s)
[802.616634] [114/130] (224s left) gem_render_copy (yf-tiled)
[802.672214] Starting subtest: yf-tiled
[802.676700] Subtest yf-tiled: SKIP (0.000s)
[802.878889] [115/130] (224s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[802.937824] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[803.015620] [116/130] (224s left) gem_softpin (safe-alignment)
[803.086104] Starting subtest: safe-alignment
[803.108922] Subtest safe-alignment: SUCCESS (0.022s)
[804.720182] [117/130] (223s left) kms_dp_link_training (uhbr-sst)
[804.815815] Starting subtest: uhbr-sst
[804.824342] Subtest uhbr-sst: SKIP (0.000s)
[804.913623] [118/130] (222s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[804.973453] Starting subtest: y-tiled-to-vebox-yf-tiled
[804.977977] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[805.186712] [119/130] (222s left) perf (polling)
[805.287214] Starting subtest: polling
[805.289736] Starting dynamic subtest: 0-rcs0
[815.308139] Dynamic subtest 0-rcs0: SUCCESS (10.016s)
[815.318836] Starting dynamic subtest: 1-vecs0
[825.330762] Dynamic subtest 1-vecs0: SUCCESS (10.018s)
[825.333513] Subtest polling: SUCCESS (20.037s)
[825.380163] [120/130] (202s left) syncobj_wait (wait-all-complex)
[825.444093] Starting subtest: wait-all-complex
[825.658312] Subtest wait-all-complex: SUCCESS (0.212s)
[825.764789] [121/130] (202s left) dmabuf (all-tests)
[825.808460] Starting subtest: all-tests
[825.810691] Starting dynamic subtest: sanitycheck
[825.957848] Dynamic subtest sanitycheck: SUCCESS (0.150s)
[825.972161] Starting dynamic subtest: dma_fence
[826.244084] Dynamic subtest dma_fence: SUCCESS (0.283s)
[826.256676] Starting dynamic subtest: dma_fence_chain
[834.381347] Dynamic subtest dma_fence_chain: SUCCESS (8.131s)
[834.394426] Starting dynamic subtest: dma_fence_unwrap
[834.512867] Dynamic subtest dma_fence_unwrap: SUCCESS (0.130s)
[834.525425] Starting dynamic subtest: dma_resv
[834.646517] Dynamic subtest dma_resv: SUCCESS (0.132s)
[834.649273] Subtest all-tests: SUCCESS (8.838s)
[834.737782] [122/130] (193s left) dumb_buffer (map-invalid-size)
[834.800088] Starting subtest: map-invalid-size
[834.802379] Subtest map-invalid-size: SUCCESS (0.000s)
[834.940342] [123/130] (193s left) kms_chamelium_hpd (vga-hpd)
[835.351353] Subtest vga-hpd: SKIP (0.000s)
[836.137248] [124/130] (191s left) kms_busy (basic)
[836.250429] Starting subtest: basic
[836.253393] Starting dynamic subtest: flip
[836.835546] Dynamic subtest flip: SUCCESS (0.587s)
[836.847135] Starting dynamic subtest: modeset
[840.175165] Dynamic subtest modeset: SUCCESS (3.338s)
[840.177674] Subtest basic: SUCCESS (3.927s)
[841.051959] [125/130] (187s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[841.163904] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[848.575445] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.412s)
[848.735194] [126/130] (179s left) i915_drm_fdinfo (busy)
[848.771574] Starting subtest: busy
[848.773938] Starting dynamic subtest: rcs0
[848.793239] Dynamic subtest rcs0: SKIP (0.020s)
[848.804588] Starting dynamic subtest: bcs0
[848.811962] Dynamic subtest bcs0: SKIP (0.016s)
[848.824999] Starting dynamic subtest: vcs0
[848.829670] Dynamic subtest vcs0: SKIP (0.000s)
[848.839712] Starting dynamic subtest: vcs1
[848.843746] Dynamic subtest vcs1: SKIP (0.000s)
[848.853595] Starting dynamic subtest: vecs0
[848.857707] Dynamic subtest vecs0: SKIP (0.000s)
[848.867384] Starting dynamic subtest: ccs0
[848.871531] Dynamic subtest ccs0: SKIP (0.000s)
[848.875565] Subtest busy: SKIP (0.041s)
[848.912396] [127/130] (179s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[848.959716] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[848.961709] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[849.075808] [128/130] (179s left) kms_pm_lpsp (screens-disabled)
[850.181256] Starting subtest: screens-disabled
[850.185932] Subtest screens-disabled: SKIP (0.000s)
[850.277847] [129/130] (177s left) kms_async_flips (invalid-async-flip-atomic)
[850.375482] Starting subtest: invalid-async-flip-atomic
[850.751646] Starting dynamic subtest: pipe-A-eDP-1
[851.542487] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.791s)
[851.910022] Starting dynamic subtest: pipe-B-eDP-1
[852.729872] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.829s)
[853.085766] Starting dynamic subtest: pipe-C-eDP-1
[853.916637] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.838s)
[854.279069] Starting dynamic subtest: pipe-D-eDP-1
[855.109734] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.841s)
[855.112080] Subtest invalid-async-flip-atomic: SUCCESS (4.736s)
[856.275802] [130/130] (171s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[856.525619] Starting subtest: cursor-rapid-movement-256x256
[856.530479] Starting dynamic subtest: pipe-A-eDP-1
[857.069208] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.539s)
[857.098234] Starting dynamic subtest: pipe-D-eDP-1
[858.312582] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.222s)
[858.315157] Subtest cursor-rapid-movement-256x256: SUCCESS (1.785s)
[859.158388] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: kms_psr
results: parsing output: 3/ for test: kms_pipe_crc_basic
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_hdr
results: parsing output: 6/ for test: gem_userptr_blits
results: parsing output: 7/ for test: kms_selftest
results: parsing output: 8/ for test: gem_exec_gttfill
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: i915_getparams_basic
results: parsing output: 13/ for test: prime_vgem
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_ctx_shared
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: kms_plane_cursor
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_psr2_su
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: kms_vblank
results: parsing output: 22/ for test: template
results: parsing output: 23/ for test: i915_pm_rc6_residency
results: parsing output: 24/ for test: gem_exec_balancer
results: parsing output: 25/ for test: kms_content_protection
results: parsing output: 26/ for test: kms_cursor_legacy
results: parsing output: 27/ for test: perf_pmu
results: parsing output: 28/ for test: vgem_basic
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: i915_query
results: parsing output: 32/ for test: kms_cursor_crc
results: parsing output: 33/ for test: api_intel_bb
results: parsing output: 34/ for test: kms_addfb_basic
results: parsing output: 35/ for test: kms_hdr
results: parsing output: 36/ for test: kms_flip
results: parsing output: 37/ for test: syncobj_wait
results: parsing output: 38/ for test: gem_ctx_persistence
results: parsing output: 39/ for test: kms_pm_rpm
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_sequence
results: parsing output: 42/ for test: gen9_exec_parse
results: parsing output: 43/ for test: gem_exec_whisper
results: parsing output: 44/ for test: kms_plane_scaling
results: parsing output: 45/ for test: kms_ccs
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_cursor_crc
results: parsing output: 48/ for test: gem_exec_suspend
results: parsing output: 49/ for test: kms_properties
results: parsing output: 50/ for test: gem_ctx_isolation
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_wait
results: parsing output: 55/ for test: perf
results: parsing output: 56/ for test: kms_addfb_basic
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: gem_exec_capture
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: kms_flip_scaled_crc
results: parsing output: 61/ for test: drm_read
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: api_intel_allocator
results: parsing output: 64/ for test: gem_exec_store
results: parsing output: 65/ for test: syncobj_wait
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_addfb_basic
results: parsing output: 68/ for test: syncobj_timeline
results: parsing output: 69/ for test: gem_eio
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: syncobj_basic
results: parsing output: 72/ for test: kms_flip_scaled_crc
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_psr2_sf
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: perf
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: kms_joiner
results: parsing output: 81/ for test: kms_flip_scaled_crc
results: parsing output: 82/ for test: gem_exec_fence
results: parsing output: 83/ for test: kms_chamelium_color
results: parsing output: 84/ for test: gem_exec_flush
results: parsing output: 85/ for test: gem_exec_reloc
results: parsing output: 86/ for test: gem_mmap_wc
results: parsing output: 87/ for test: gem_mmap_gtt
results: parsing output: 88/ for test: kms_chamelium_hpd
results: parsing output: 89/ for test: gem_eio
results: parsing output: 90/ for test: prime_mmap
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_hdmi_inject
results: parsing output: 93/ for test: perf_pmu
results: parsing output: 94/ for test: kms_plane_scaling
results: parsing output: 95/ for test: gem_mmap_gtt
results: parsing output: 96/ for test: gem_softpin
results: parsing output: 97/ for test: gem_exec_schedule
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_create
results: parsing output: 101/ for test: kms_flip
results: parsing output: 102/ for test: kms_atomic_transition
results: parsing output: 103/ for test: gem_workarounds
results: parsing output: 104/ for test: kms_psr
results: parsing output: 105/ for test: gem_exec_capture
results: parsing output: 106/ for test: gem_mmap_gtt
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: kms_pipe_b_c_ivb
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_render_copy
results: parsing output: 114/ for test: gem_mmap_gtt
results: parsing output: 115/ for test: gem_softpin
results: parsing output: 116/ for test: kms_dp_link_training
results: parsing output: 117/ for test: gem_render_copy
results: parsing output: 118/ for test: perf
results: parsing output: 119/ for test: syncobj_wait
results: parsing output: 120/ for test: dmabuf
results: parsing output: 121/ for test: dumb_buffer
results: parsing output: 122/ for test: kms_chamelium_hpd
results: parsing output: 123/ for test: kms_busy
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: i915_drm_fdinfo
results: parsing output: 126/ for test: syncobj_timeline
results: parsing output: 127/ for test: kms_pm_lpsp
results: parsing output: 128/ for test: kms_async_flips
results: parsing output: 129/ for test: kms_cursor_crc
Done.