[66.082339] Initializing watchdogs
[66.082408]   /dev/watchdog0
[66.097954] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[66.104179] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[66.106632] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[66.108523] [001/133] (960s left) kms_chamelium_color (ctm-green-to-red)
[66.199549] Subtest ctm-green-to-red: SKIP (0.000s)
[66.245332] [002/133] (960s left) gem_exec_schedule (out-order)
[66.294340] Starting subtest: out-order
[66.295104] Starting dynamic subtest: rcs0
[66.359516] Dynamic subtest rcs0: SUCCESS (0.065s)
[66.369717] Starting dynamic subtest: bcs0
[66.429194] Dynamic subtest bcs0: SUCCESS (0.069s)
[66.437847] Starting dynamic subtest: vcs0
[66.494607] Dynamic subtest vcs0: SUCCESS (0.065s)
[66.508316] Starting dynamic subtest: vcs1
[66.565384] Dynamic subtest vcs1: SUCCESS (0.070s)
[66.575707] Starting dynamic subtest: vecs0
[66.630402] Dynamic subtest vecs0: SUCCESS (0.065s)
[66.631467] Subtest out-order: SUCCESS (0.336s)
[66.922504] [003/133] (959s left) kms_flip (flip-vs-suspend-interruptible)
[67.250614] Starting subtest: flip-vs-suspend-interruptible
[67.252594] Starting dynamic subtest: A-HDMI-A1
[99.544038] Dynamic subtest A-HDMI-A1: SUCCESS (2.790s)
[99.550517] Starting dynamic subtest: B-HDMI-A1
[147.532526] Dynamic subtest B-HDMI-A1: SUCCESS (3.627s)
[147.538738] Starting dynamic subtest: C-HDMI-A1
[195.529718] Dynamic subtest C-HDMI-A1: SUCCESS (3.641s)
[195.538063] Starting dynamic subtest: D-HDMI-A1
[243.509383] Dynamic subtest D-HDMI-A1: SUCCESS (3.593s)
[243.511292] Subtest flip-vs-suspend-interruptible: SUCCESS (13.657s)
[252.732828] [004/133] (783s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[252.832860] Starting subtest: 4-tiled-16bpp-rotate-90
[252.834181] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.000s)
[252.904707] [005/133] (782s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[252.993050] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[254.090708] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (1.102s)
[254.222031] [006/133] (781s left) tools_test (tools_test)
[254.261315] Starting subtest: tools_test
[254.331484] Subtest tools_test: SUCCESS (0.075s)
[254.382214] [007/133] (781s left) kms_psr (psr2-primary-page-flip)
[254.468848] Subtest psr2-primary-page-flip: SKIP (0.000s)
[254.504522] [008/133] (781s left) gem_busy (parallel)
[254.682015] Starting subtest: parallel
[254.683018] Starting dynamic subtest: rcs0
[254.718848] Dynamic subtest rcs0: SUCCESS (0.045s)
[254.728590] Starting dynamic subtest: bcs0
[254.743975] Dynamic subtest bcs0: SUCCESS (0.024s)
[254.755079] Starting dynamic subtest: vcs0
[254.770012] Dynamic subtest vcs0: SUCCESS (0.025s)
[254.781758] Starting dynamic subtest: vcs1
[254.803167] Dynamic subtest vcs1: SUCCESS (0.031s)
[254.817264] Starting dynamic subtest: vecs0
[254.834334] Dynamic subtest vecs0: SUCCESS (0.031s)
[254.835513] Subtest parallel: SUCCESS (0.162s)
[255.072398] [009/133] (780s left) gem_busy (close-race)
[255.114134] Starting subtest: close-race
[277.134529] Subtest close-race: SUCCESS (22.051s)
[277.494918] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true
[277.498081] [010/133] (758s left) kms_selftest (drm_plane_helper)
[277.586255] Starting subtest: drm_plane_helper
[277.590560] Starting dynamic subtest: drm_test_check_plane_state
[277.665340] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.075s)
[277.676957] Starting dynamic subtest: drm_test_check_invalid_plane_state
[277.735186] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.067s)
[277.736335] Subtest drm_plane_helper: SUCCESS (0.148s)
[277.871222] [011/133] (758s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[277.963965] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[277.964643] Starting dynamic subtest: pipe-A-HDMI-A-1
[277.981411] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[277.988961] Starting dynamic subtest: pipe-B-HDMI-A-1
[278.006421] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[278.013924] Starting dynamic subtest: pipe-C-HDMI-A-1
[278.031285] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[278.039272] Starting dynamic subtest: pipe-D-HDMI-A-1
[278.056586] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.002s)
[278.057981] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.012s)
[278.205135] [012/133] (757s left) sw_sync (sync_multi_consumer_producer)
[278.241448] Starting subtest: sync_multi_consumer_producer
[278.605767] Subtest sync_multi_consumer_producer: SUCCESS (0.356s)
[278.666249] [013/133] (757s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[278.705121] Starting subtest: yf-tiled-to-vebox-yf-tiled
[278.705841] Starting dynamic subtest: smem
[278.777776] Dynamic subtest smem: SUCCESS (0.073s)
[278.778550] Subtest yf-tiled-to-vebox-yf-tiled: SUCCESS (0.073s)
[278.962637] [014/133] (757s left) gem_ppgtt (blt-vs-render-ctx0)
[279.001007] Starting subtest: blt-vs-render-ctx0
[311.439902] Subtest blt-vs-render-ctx0: SUCCESS (32.438s)
[311.556840] [015/133] (724s left) gem_softpin (allocator-nopin)
[311.639547] Starting subtest: allocator-nopin
[311.719920] Subtest allocator-nopin: SUCCESS (0.080s)
[311.833189] [016/133] (724s left) kms_psr (psr-no-drrs)
[311.923056] Subtest psr-no-drrs: SKIP (0.000s)
[311.966674] [017/133] (724s left) gem_fence_thrash (bo-write-verify-threaded-none)
[311.998745] Starting subtest: bo-write-verify-threaded-none
[316.167559] Subtest bo-write-verify-threaded-none: SUCCESS (4.137s)
[316.248292] [018/133] (720s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[316.288438] Starting subtest: multi-wait-for-submit-submitted-signaled
[316.289109] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[316.356870] [019/133] (720s left) kms_pipe_stress (stress-xrgb8888-yftiled)
[316.447125] Starting subtest: stress-xrgb8888-yftiled
[316.448577] Subtest stress-xrgb8888-yftiled: SKIP (0.000s)
[316.491284] [020/133] (719s left) kms_vblank (ts-continuation-modeset)
[316.582163] Starting subtest: ts-continuation-modeset
[316.582848] Starting dynamic subtest: pipe-A-HDMI-A-1
[316.885486] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.302s)
[316.897762] Starting dynamic subtest: pipe-D-HDMI-A-1
[317.180998] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.292s)
[317.181753] Subtest ts-continuation-modeset: SUCCESS (0.599s)
[317.302631] [021/133] (719s left) kms_joiner (basic-force-ultra-joiner)
[317.392350] Starting subtest: basic-force-ultra-joiner
[317.393685] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[317.451301] [022/133] (719s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[317.539087] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[318.882426] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (1.343s)
[319.001943] [023/133] (717s left) kms_cursor_crc (cursor-sliding-128x128)
[319.097022] Starting subtest: cursor-sliding-128x128
[319.102454] Starting dynamic subtest: pipe-A-HDMI-A-1
[320.971792] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.870s)
[321.005331] Starting dynamic subtest: pipe-D-HDMI-A-1
[322.804329] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.807s)
[322.805190] Subtest cursor-sliding-128x128: SUCCESS (3.709s)
[322.950776] [024/133] (713s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[323.053134] Starting subtest: 2x-absolute-wf_vblank-interruptible
[323.066931] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.013s)
[323.132071] [025/133] (713s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[323.216357] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[323.217919] Starting dynamic subtest: pipe-A-HDMI-A-1
[323.224360] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[323.231552] Starting dynamic subtest: pipe-B-HDMI-A-1
[323.238001] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[323.245049] Starting dynamic subtest: pipe-C-HDMI-A-1
[323.251473] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[323.258414] Starting dynamic subtest: pipe-D-HDMI-A-1
[323.265029] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[323.266356] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.005s)
[323.447623] [026/133] (713s left) gem_eio (wait-wedge-1us)
[323.497829] Starting subtest: wait-wedge-1us
[323.752067] Subtest wait-wedge-1us: SUCCESS (0.254s)
[323.864775] [027/133] (712s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[323.902710] Starting subtest: untiled-to-tiled
[324.030439] Subtest untiled-to-tiled: SUCCESS (0.122s)
[324.121614] [028/133] (712s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[324.230073] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[324.231517] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[324.313733] [029/133] (712s left) kms_chamelium_hpd (common-hpd-after-suspend)
[324.456003] Subtest common-hpd-after-suspend: SKIP (0.000s)
[324.553200] [030/133] (712s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[324.647412] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[324.648800] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[324.724421] [031/133] (712s left) kms_async_flips (test-time-stamp)
[324.809371] Starting subtest: test-time-stamp
[324.939865] Starting dynamic subtest: pipe-A-HDMI-A-1
[325.047272] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.107s)
[325.174260] Starting dynamic subtest: pipe-B-HDMI-A-1
[325.253104] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.085s)
[325.368683] Starting dynamic subtest: pipe-C-HDMI-A-1
[325.453799] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.093s)
[325.586297] Starting dynamic subtest: pipe-D-HDMI-A-1
[325.662451] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.082s)
[325.663213] Subtest test-time-stamp: SUCCESS (0.854s)
[325.870339] [032/133] (711s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[325.969293] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[325.970691] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[326.058295] [033/133] (710s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[326.099815] Starting subtest: basic-batch-kernel-default-cmd
[326.101244] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[326.279885] [034/133] (710s left) sw_sync (sync_busy)
[326.312560] Starting subtest: sync_busy
[326.314529] Subtest sync_busy: SUCCESS (0.003s)
[326.383761] [035/133] (710s left) kms_getfb (getfb2-into-addfb2)
[326.468830] Starting subtest: getfb2-into-addfb2
[326.469579] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[326.560067] [036/133] (710s left) gem_create (create-invalid-size)
[326.593234] Starting subtest: create-invalid-size
[326.593933] Subtest create-invalid-size: SUCCESS (0.000s)
[326.781222] [037/133] (710s left) kms_color (degamma)
[326.865760] Starting subtest: degamma
[326.866496] Starting dynamic subtest: pipe-A-HDMI-A-1
[327.190132] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.324s)
[327.200060] Starting dynamic subtest: pipe-B-HDMI-A-1
[327.504324] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.314s)
[327.514901] Starting dynamic subtest: pipe-C-HDMI-A-1
[327.807086] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.302s)
[327.815414] Starting dynamic subtest: pipe-D-HDMI-A-1
[328.121590] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.309s)
[328.122441] Subtest degamma: SUCCESS (1.252s)
[328.256598] [038/133] (708s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[328.292009] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[328.332795] [039/133] (708s left) kms_draw_crc (draw-method-mmap-wc)
[328.419771] Starting subtest: draw-method-mmap-wc
[328.420486] Starting dynamic subtest: xrgb8888-untiled
[328.569084] Dynamic subtest xrgb8888-untiled: SUCCESS (0.149s)
[328.585838] Starting dynamic subtest: rgb565-untiled
[328.727476] Dynamic subtest rgb565-untiled: SUCCESS (0.157s)
[328.744746] Starting dynamic subtest: xrgb2101010-untiled
[328.868955] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.140s)
[328.885242] Starting dynamic subtest: xbgr16161616f-untiled
[329.010584] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.140s)
[329.026892] Starting dynamic subtest: xrgb8888-xtiled
[329.102402] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.090s)
[329.120218] Starting dynamic subtest: rgb565-xtiled
[329.193958] Dynamic subtest rgb565-xtiled: SUCCESS (0.089s)
[329.211486] Starting dynamic subtest: xrgb2101010-xtiled
[329.277280] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.082s)
[329.295618] Starting dynamic subtest: xbgr16161616f-xtiled
[329.360508] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.081s)
[329.377554] Starting dynamic subtest: xrgb8888-ytiled
[329.452259] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.090s)
[329.471595] Starting dynamic subtest: rgb565-ytiled
[329.544087] Dynamic subtest rgb565-ytiled: SUCCESS (0.089s)
[329.561718] Starting dynamic subtest: xrgb2101010-ytiled
[329.627166] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.081s)
[329.639925] Starting dynamic subtest: xbgr16161616f-ytiled
[329.701994] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.073s)
[329.704993] Subtest draw-method-mmap-wc: SUCCESS (1.284s)
[329.798011] [040/133] (707s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[329.835771] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[329.874972] [041/133] (707s left) prime_mmap (test_userptr)
[329.908226] Starting subtest: test_userptr
[329.908933] Starting dynamic subtest: test_userptr-smem
[329.909642] Dynamic subtest test_userptr-smem: SUCCESS (0.000s)
[329.910347] Subtest test_userptr: SUCCESS (0.001s)
[330.001844] [042/133] (707s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[330.093268] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[330.094632] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[330.181481] [043/133] (707s left) kms_plane (pixel-format-linear-modifier)
[330.266485] Starting subtest: pixel-format-linear-modifier
[330.285020] Starting dynamic subtest: pipe-A-plane-0
[332.784472] Dynamic subtest pipe-A-plane-0: SUCCESS (2.500s)
[332.802161] Starting dynamic subtest: pipe-A-plane-3
[334.991679] Dynamic subtest pipe-A-plane-3: SUCCESS (2.206s)
[335.007157] Starting dynamic subtest: pipe-A-plane-5
[336.183408] Dynamic subtest pipe-A-plane-5: SUCCESS (1.191s)
[336.198211] Starting dynamic subtest: pipe-A-plane-7
[336.291721] Dynamic subtest pipe-A-plane-7: SUCCESS (0.106s)
[336.446571] Starting dynamic subtest: pipe-B-plane-0
[338.905547] Dynamic subtest pipe-B-plane-0: SUCCESS (2.467s)
[338.921094] Starting dynamic subtest: pipe-B-plane-3
[341.121863] Dynamic subtest pipe-B-plane-3: SUCCESS (2.215s)
[341.137585] Starting dynamic subtest: pipe-B-plane-5
[342.337802] Dynamic subtest pipe-B-plane-5: SUCCESS (1.215s)
[342.358116] Starting dynamic subtest: pipe-B-plane-7
[342.454441] Dynamic subtest pipe-B-plane-7: SUCCESS (0.113s)
[342.527958] Subtest pixel-format-linear-modifier: SUCCESS (12.266s)
[342.676299] [044/133] (694s left) i915_suspend (forcewake)
[342.713362] Starting subtest: forcewake
[375.506189] Subtest forcewake: SUCCESS (2.420s)
[384.614512] [045/133] (661s left) kms_psr (pr-basic)
[384.729554] Subtest pr-basic: SKIP (0.000s)
[384.775263] [046/133] (661s left) kms_addfb_basic (small-bo)
[384.861487] Starting subtest: small-bo
[384.862179] Subtest small-bo: SUCCESS (0.000s)
[384.961631] [047/133] (661s left) prime_busy (after-wait)
[384.997693] Starting subtest: after-wait
[385.002410] Starting dynamic subtest: rcs0
[385.030072] Dynamic subtest rcs0: SUCCESS (0.027s)
[385.042758] Starting dynamic subtest: bcs0
[385.045332] Dynamic subtest bcs0: SUCCESS (0.014s)
[385.054503] Starting dynamic subtest: vcs0
[385.063638] Dynamic subtest vcs0: SUCCESS (0.017s)
[385.072507] Starting dynamic subtest: vcs1
[385.079073] Dynamic subtest vcs1: SUCCESS (0.014s)
[385.087757] Starting dynamic subtest: vecs0
[385.093907] Dynamic subtest vecs0: SUCCESS (0.014s)
[385.204458] Subtest after-wait: SUCCESS (0.206s)
[385.272821] [048/133] (661s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[385.357489] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[385.358162] Starting dynamic subtest: pipe-A-valid-mode
[386.213706] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.860s)
[386.214410] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SUCCESS (0.861s)
[386.382666] [049/133] (660s left) kms_psr2_su (page_flip-xrgb8888)
[386.421189] Subtest page_flip-XRGB8888: SKIP (0.000s)
[386.468808] [050/133] (660s left) gem_render_copy_redux (normal)
[386.507441] Starting subtest: normal
[386.837352] Subtest normal: SUCCESS (0.324s)
[386.931910] [051/133] (659s left) gem_exec_params (invalid-flag)
[386.968273] Starting subtest: invalid-flag
[386.968970] Subtest invalid-flag: SUCCESS (0.000s)
[387.061077] [052/133] (659s left) i915_module_load (resize-bar)
[387.093763] Starting subtest: resize-bar
[387.098341] Subtest resize-bar: SKIP (0.005s)
[387.136766] [053/133] (659s left) dumb_buffer (map-invalid-size)
[387.169504] Starting subtest: map-invalid-size
[387.170224] Subtest map-invalid-size: SUCCESS (0.000s)
[387.260195] [054/133] (659s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[387.346068] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.000s)
[387.387498] [055/133] (659s left) kms_chamelium_edid (dp-edid-resolution-list)
[387.523359] Subtest dp-edid-resolution-list: SKIP (0.000s)
[387.617430] [056/133] (659s left) gem_mmap_wc (write-prefaulted)
[387.664381] Starting subtest: write-prefaulted
[387.699599] Subtest write-prefaulted: SUCCESS (0.036s)
[387.784309] [057/133] (659s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[387.869579] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[387.870299] Starting dynamic subtest: pipe-A-HDMI-A-1
[387.877219] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[387.887444] Starting dynamic subtest: pipe-B-HDMI-A-1
[387.894449] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[387.904953] Starting dynamic subtest: pipe-C-HDMI-A-1
[387.911830] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[387.921963] Starting dynamic subtest: pipe-D-HDMI-A-1
[387.928724] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[387.930052] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.010s)
[388.113526] [058/133] (658s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[388.204515] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[389.821429] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (1.623s)
[389.958765] [059/133] (657s left) vgem_basic (bad-pad)
[389.995548] Starting subtest: bad-pad
[389.996265] Subtest bad-pad: SUCCESS (0.000s)
[390.049132] [060/133] (657s left) kms_pm_rpm (cursor-dpms)
[391.446539] Starting subtest: cursor-dpms
[403.469227] Subtest cursor-dpms: SUCCESS (12.043s)
[403.668093] [061/133] (643s left) gem_ccs (large-ctrl-surf-copy)
[403.707633] Starting subtest: large-ctrl-surf-copy
[403.708992] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[403.766158] [062/133] (643s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[403.855554] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[403.856909] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[403.951831] [063/133] (643s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[404.039148] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (0.000s)
[404.086379] [064/133] (643s left) syncobj_timeline (host-signal-ordered)
[404.120971] Starting subtest: host-signal-ordered
[404.121711] Subtest host-signal-ordered: SUCCESS (0.001s)
[404.225487] [065/133] (643s left) gem_exec_store (basic)
[404.268342] Starting subtest: basic
[404.335180] Subtest basic: SUCCESS (0.067s)
[404.528502] [066/133] (642s left) gem_ctx_param (get-priority-new-ctx)
[404.562547] Starting subtest: get-priority-new-ctx
[404.563249] Subtest get-priority-new-ctx: SUCCESS (0.000s)
[404.625892] [067/133] (642s left) gem_ctx_shared (exec-single-timeline)
[404.666881] Starting subtest: exec-single-timeline
[404.667597] Starting dynamic subtest: rcs0
[405.687016] Dynamic subtest rcs0: SUCCESS (1.012s)
[405.696022] Starting dynamic subtest: bcs0
[406.710526] Dynamic subtest bcs0: SUCCESS (1.022s)
[406.719742] Starting dynamic subtest: vcs0
[407.734775] Dynamic subtest vcs0: SUCCESS (1.023s)
[407.743069] Starting dynamic subtest: vcs1
[408.758574] Dynamic subtest vcs1: SUCCESS (1.023s)
[408.767479] Starting dynamic subtest: vecs0
[409.782840] Dynamic subtest vecs0: SUCCESS (1.022s)
[409.783925] Subtest exec-single-timeline: SUCCESS (5.112s)
[409.977516] [068/133] (637s left) kms_content_protection (suspend-resume)
[410.069191] Starting subtest: suspend-resume
[410.082601] Subtest suspend-resume: SKIP (0.012s)
[410.172166] [069/133] (637s left) drm_read (empty-block)
[410.276730] Starting subtest: empty-block
[411.284843] Subtest empty-block: SUCCESS (1.001s)
[411.383978] [070/133] (636s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[411.471293] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[411.518017] [071/133] (636s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[411.553687] Starting subtest: cpuset-medium-copy-XY
[421.781320] Subtest cpuset-medium-copy-XY: SUCCESS (10.229s)
[421.861052] [072/133] (625s left) gem_exec_schedule (u-submit-early-slice)
[421.953679] Starting subtest: u-submit-early-slice
[421.954406] Starting dynamic subtest: rcs0
[421.984599] Dynamic subtest rcs0: SUCCESS (0.030s)
[421.994085] Starting dynamic subtest: bcs0
[422.012494] Dynamic subtest bcs0: SUCCESS (0.027s)
[422.021414] Starting dynamic subtest: vcs0
[422.041657] Dynamic subtest vcs0: SUCCESS (0.028s)
[422.052070] Starting dynamic subtest: vcs1
[422.068369] Dynamic subtest vcs1: SUCCESS (0.026s)
[422.077966] Starting dynamic subtest: vecs0
[422.095778] Dynamic subtest vecs0: SUCCESS (0.026s)
[422.096507] Subtest u-submit-early-slice: SUCCESS (0.143s)
[422.399507] [073/133] (625s left) gem_mmap_wc (write-read)
[422.434437] Starting subtest: write-read
[422.436449] Subtest write-read: SUCCESS (0.003s)
[422.507905] [074/133] (625s left) gem_exec_fence (syncobj-invalid-flags)
[422.856584] Starting subtest: syncobj-invalid-flags
[422.857264] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[423.150318] [075/133] (624s left) gem_userptr_blits (relocations)
[423.189052] Starting subtest: relocations
[423.189852] Subtest relocations: SUCCESS (0.002s)
[423.316093] [076/133] (624s left) perf_pmu (busy-start)
[423.353354] Starting subtest: busy-start
[423.354038] Starting dynamic subtest: rcs0
[425.898301] Dynamic subtest rcs0: SUCCESS (2.518s)
[425.909356] Starting dynamic subtest: bcs0
[428.419532] Dynamic subtest bcs0: SUCCESS (2.519s)
[428.430270] Starting dynamic subtest: vcs0
[430.940567] Dynamic subtest vcs0: SUCCESS (2.518s)
[430.951449] Starting dynamic subtest: vcs1
[433.436060] Dynamic subtest vcs1: SUCCESS (2.518s)
[433.449107] Starting dynamic subtest: vecs0
[435.981798] Dynamic subtest vecs0: SUCCESS (2.519s)
[435.982785] Subtest busy-start: SUCCESS (12.603s)
[436.030036] [077/133] (611s left) i915_drm_fdinfo (memory-info-idle)
[436.068470] Starting subtest: memory-info-idle
[436.069174] Starting dynamic subtest: smem0
[436.103746] Dynamic subtest smem0: SUCCESS (0.036s)
[436.104484] Subtest memory-info-idle: SUCCESS (0.037s)
[436.199091] [078/133] (611s left) gem_exec_balancer (hog)
[436.240336] Starting subtest: hog
[437.607716] Subtest hog: SUCCESS (1.359s)
[437.788971] [079/133] (610s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[437.893155] Starting subtest: y-tiled-64bpp-rotate-0
[439.380390] Subtest y-tiled-64bpp-rotate-0: SUCCESS (1.487s)
[439.508899] [080/133] (608s left) gem_exec_basic (basic)
[439.546851] Starting subtest: basic
[439.547558] Starting dynamic subtest: rcs0-smem
[439.549521] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[439.560352] Starting dynamic subtest: bcs0-smem
[439.561043] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[439.568800] Starting dynamic subtest: vcs0-smem
[439.569493] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[439.577045] Starting dynamic subtest: vcs1-smem
[439.577729] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[439.585084] Starting dynamic subtest: vecs0-smem
[439.585760] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[439.586440] Subtest basic: SUCCESS (0.011s)
[439.728936] [081/133] (608s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[439.763828] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[439.872015] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.106s)
[439.945324] [082/133] (608s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[439.979989] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[439.980680] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[440.043830] [083/133] (608s left) gem_ctx_persistence (file)
[440.081931] Starting subtest: file
[440.160081] Subtest file: SUCCESS (0.078s)
[440.262659] [084/133] (607s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[440.356461] Starting subtest: cursorB-vs-flipB-legacy
[440.358855] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[440.416667] [085/133] (607s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[440.501556] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[440.502297] Starting dynamic subtest: pipe-A
[441.745996] Dynamic subtest pipe-A: SUCCESS (1.245s)
[441.763855] Starting dynamic subtest: pipe-B
[443.013009] Dynamic subtest pipe-B: SUCCESS (1.266s)
[443.023917] Starting dynamic subtest: pipe-C
[443.213837] Dynamic subtest pipe-C: SUCCESS (0.200s)
[443.232658] Starting dynamic subtest: pipe-D
[443.423773] Dynamic subtest pipe-D: SUCCESS (0.209s)
[443.424473] Subtest plane-downscale-factor-0-5-with-pixel-format: SUCCESS (2.924s)
[443.604686] [086/133] (604s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[443.698535] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[443.699946] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[443.787914] [087/133] (604s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[443.822081] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[443.926384] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[444.004610] [088/133] (604s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[444.088700] Starting subtest: modeset-atomic-cursor-hotspot
[444.090083] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[444.153349] [089/133] (604s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[444.238673] Starting subtest: basic-busy-flip-before-cursor-legacy
[444.240058] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[444.301943] [090/133] (604s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[444.391403] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[444.392771] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[444.476833] [091/133] (603s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[444.571601] Starting subtest: x-tiled-8bpp-rotate-270
[444.647390] Subtest x-tiled-8bpp-rotate-270: SKIP (0.075s)
[444.736300] [092/133] (603s left) syncobj_wait (wait-for-submit-snapshot)
[444.770490] Starting subtest: wait-for-submit-snapshot
[444.853432] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[444.931008] [093/133] (603s left) gem_mmap_gtt (basic-wc)
[444.965574] Starting subtest: basic-wc
[445.602981] Subtest basic-wc: SUCCESS (0.630s)
[445.662444] [094/133] (602s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[445.754926] Starting subtest: 2x-flip-vs-rmfb-interruptible
[445.764518] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.009s)
[445.828762] [095/133] (602s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[445.917401] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[445.918747] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[446.013510] [096/133] (602s left) gen9_exec_parse (bb-oversize)
[446.050329] Subtest bb-oversize: SKIP (0.000s)
[446.098207] [097/133] (602s left) gem_mmap_offset (bad-extensions)
[446.131311] Starting subtest: bad-extensions
[446.131981] Subtest bad-extensions: SUCCESS (0.000s)
[446.219503] [098/133] (602s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[446.307377] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[446.308723] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[446.404257] [099/133] (602s left) kms_cursor_crc (cursor-offscreen-256x85)
[446.503802] Starting subtest: cursor-offscreen-256x85
[446.511725] Starting dynamic subtest: pipe-A-HDMI-A-1
[447.759575] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.261s)
[447.806107] Starting dynamic subtest: pipe-D-HDMI-A-1
[449.026417] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.227s)
[449.027157] Subtest cursor-offscreen-256x85: SUCCESS (2.533s)
[449.173983] [100/133] (599s left) i915_pm_rpm (gem-idle)
[450.569531] Starting subtest: gem-idle
[456.811942] Subtest gem-idle: SUCCESS (6.245s)
[456.985502] [101/133] (591s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[457.023175] Starting subtest: single-wait-all-for-submit-signaled
[457.023912] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[457.081566] [102/133] (591s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[457.169680] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[457.171046] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[457.278211] [103/133] (591s left) gem_pxp (verify-pxp-stale-ctx-execution)
[457.382056] Starting subtest: verify-pxp-stale-ctx-execution
[458.447103] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.064s)
[458.552566] [104/133] (590s left) gem_wait (write-wait)
[458.598184] Starting subtest: write-wait
[458.598912] Starting dynamic subtest: all-engines
[459.631808] Dynamic subtest all-engines: SUCCESS (1.033s)
[459.644371] Starting dynamic subtest: rcs0
[460.669154] Dynamic subtest rcs0: SUCCESS (1.035s)
[460.687865] Starting dynamic subtest: bcs0
[461.703789] Dynamic subtest bcs0: SUCCESS (1.032s)
[461.715858] Starting dynamic subtest: vcs0
[462.736803] Dynamic subtest vcs0: SUCCESS (1.029s)
[462.746931] Starting dynamic subtest: vcs1
[463.771207] Dynamic subtest vcs1: SUCCESS (1.034s)
[463.784543] Starting dynamic subtest: vecs0
[464.803979] Dynamic subtest vecs0: SUCCESS (1.030s)
[464.807300] Subtest write-wait: SUCCESS (6.209s)
[465.000640] [105/133] (583s left) gem_exec_gttfill (engines)
[465.043122] Starting subtest: engines
[465.043852] Starting dynamic subtest: rcs0
[488.390689] Dynamic subtest rcs0: SUCCESS (23.350s)
[488.400690] Starting dynamic subtest: bcs0
[511.545038] Dynamic subtest bcs0: SUCCESS (23.150s)
[511.558762] Starting dynamic subtest: vcs0
[534.750460] Dynamic subtest vcs0: SUCCESS (23.204s)
[534.760753] Starting dynamic subtest: vcs1
[558.076178] Dynamic subtest vcs1: SUCCESS (23.327s)
[558.087959] Starting dynamic subtest: vecs0
[581.078001] Dynamic subtest vecs0: SUCCESS (23.004s)
[581.078714] Subtest engines: SUCCESS (116.042s)
[581.260476] [106/133] (467s left) perf_pmu (busy-double-start)
[581.349717] Starting subtest: busy-double-start
[581.350588] Starting dynamic subtest: rcs0
[584.900292] Dynamic subtest rcs0: SUCCESS (3.550s)
[584.912351] Starting dynamic subtest: bcs0
[588.447224] Dynamic subtest bcs0: SUCCESS (3.545s)
[588.459259] Starting dynamic subtest: vcs0
[591.999208] Dynamic subtest vcs0: SUCCESS (3.550s)
[592.011902] Starting dynamic subtest: vcs1
[595.542319] Dynamic subtest vcs1: SUCCESS (3.541s)
[595.554650] Starting dynamic subtest: vecs0
[599.090265] Dynamic subtest vecs0: SUCCESS (3.546s)
[599.091393] Subtest busy-double-start: SUCCESS (17.745s)
[599.162050] [107/133] (449s left) syncobj_timeline (multi-wait-all-signaled)
[599.205424] Starting subtest: multi-wait-all-signaled
[599.206126] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[599.269860] [108/133] (449s left) api_intel_bb (add-remove-objects)
[599.303361] Starting subtest: add-remove-objects
[599.308275] Subtest add-remove-objects: SUCCESS (0.005s)
[599.418894] [109/133] (449s left) kms_pm_rpm (pm-caching)
[600.840924] Starting subtest: pm-caching
[606.905414] Subtest pm-caching: SUCCESS (6.066s)
[607.084707] [110/133] (442s left) kms_flip (bo-too-big-interruptible)
[607.175110] Starting subtest: bo-too-big-interruptible
[607.177979] Starting dynamic subtest: A-HDMI-A1
[607.490872] Dynamic subtest A-HDMI-A1: SUCCESS (0.313s)
[607.504608] Starting dynamic subtest: B-HDMI-A1
[607.780429] Dynamic subtest B-HDMI-A1: SUCCESS (0.284s)
[607.788807] Starting dynamic subtest: C-HDMI-A1
[608.071313] Dynamic subtest C-HDMI-A1: SUCCESS (0.291s)
[608.081178] Starting dynamic subtest: D-HDMI-A1
[608.365332] Dynamic subtest D-HDMI-A1: SUCCESS (0.290s)
[608.369228] Subtest bo-too-big-interruptible: SUCCESS (1.194s)
[608.538244] [111/133] (440s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs)
[608.627540] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs
[608.628874] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s)
[608.780222] [112/133] (440s left) kms_cursor_edge_walk (64x64-right-edge)
[608.865573] Starting subtest: 64x64-right-edge
[608.867336] Starting dynamic subtest: pipe-A-HDMI-A-1
[610.617400] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.751s)
[610.628144] Starting dynamic subtest: pipe-D-HDMI-A-1
[612.310250] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.691s)
[612.310949] Subtest 64x64-right-edge: SUCCESS (3.445s)
[612.415387] [113/133] (436s left) syncobj_timeline (invalid-signal-illegal-handle)
[612.450186] Starting subtest: invalid-signal-illegal-handle
[612.450902] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[612.518894] [114/133] (436s left) kms_pm_backlight (bad-brightness)
[612.607912] Subtest bad-brightness: SKIP (0.000s)
[612.652498] [115/133] (436s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[612.741300] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[612.742694] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[612.834579] [116/133] (436s left) i915_query (query-topology-matches-eu-total)
[612.867769] Starting subtest: query-topology-matches-eu-total
[612.868490] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[612.941467] [117/133] (436s left) gem_eio (in-flight-suspend)
[612.982790] Starting subtest: in-flight-suspend
[650.558297] Subtest in-flight-suspend: SUCCESS (7.613s)
[659.725189] [118/133] (398s left) kms_lease (lease-revoke)
[659.826360] Starting subtest: lease-revoke
[659.828146] Starting dynamic subtest: pipe-A-HDMI-A-1
[659.862205] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.034s)
[659.872013] Starting dynamic subtest: pipe-B-HDMI-A-1
[659.896623] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.034s)
[659.906464] Starting dynamic subtest: pipe-C-HDMI-A-1
[659.931768] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.033s)
[659.942452] Starting dynamic subtest: pipe-D-HDMI-A-1
[659.966933] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.034s)
[659.967633] Subtest lease-revoke: SUCCESS (0.142s)
[660.068450] [119/133] (398s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[660.156971] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[660.158418] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[660.254899] [120/133] (398s left) kms_addfb_basic (bad-pitch-256)
[660.288945] Starting subtest: bad-pitch-256
[660.289643] Subtest bad-pitch-256: SUCCESS (0.000s)
[660.427578] [121/133] (398s left) api_intel_bb (full-batch)
[660.461362] Starting subtest: full-batch
[660.463353] Subtest full-batch: SUCCESS (0.003s)
[660.591451] [122/133] (398s left) kms_psr (fbc-pr-sprite-plane-onoff)
[660.679612] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[660.722459] [123/133] (398s left) gem_ctx_isolation (preservation)
[660.764694] Starting subtest: preservation
[660.765457] Starting dynamic subtest: rcs0
[660.894073] Dynamic subtest rcs0: SUCCESS (0.121s)
[660.904027] Starting dynamic subtest: bcs0
[661.063032] Dynamic subtest bcs0: SUCCESS (0.169s)
[661.071431] Starting dynamic subtest: vcs0
[661.218452] Dynamic subtest vcs0: SUCCESS (0.155s)
[661.226948] Starting dynamic subtest: vcs1
[661.378843] Dynamic subtest vcs1: SUCCESS (0.160s)
[661.387532] Starting dynamic subtest: vecs0
[661.538661] Dynamic subtest vecs0: SUCCESS (0.159s)
[661.539408] Subtest preservation: SUCCESS (0.769s)
[661.735099] [124/133] (397s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[661.831112] Starting subtest: yf-tiled-16bpp-rotate-180
[661.832543] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[661.919592] [125/133] (396s left) kms_async_flips (async-flip-dpms)
[662.005443] Starting subtest: async-flip-dpms
[662.146994] Starting dynamic subtest: pipe-A-HDMI-A-1
[664.599319] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.459s)
[664.766370] Starting dynamic subtest: pipe-B-HDMI-A-1
[667.167281] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.416s)
[667.334404] Starting dynamic subtest: pipe-C-HDMI-A-1
[669.836373] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.513s)
[669.994911] Starting dynamic subtest: pipe-D-HDMI-A-1
[672.378626] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (2.396s)
[672.379958] Subtest async-flip-dpms: SUCCESS (10.399s)
[672.636925] [126/133] (386s left) kms_busy (basic-hang)
[672.730241] Starting subtest: basic-hang
[672.731238] Starting dynamic subtest: flip-pipe-A
[673.076444] Dynamic subtest flip-pipe-A: SUCCESS (0.346s)
[673.086242] Starting dynamic subtest: modeset-pipe-A
[673.674935] Dynamic subtest modeset-pipe-A: SUCCESS (0.597s)
[673.687832] Starting dynamic subtest: flip-pipe-D
[673.952701] Dynamic subtest flip-pipe-D: SUCCESS (0.274s)
[673.964028] Starting dynamic subtest: modeset-pipe-D
[674.514631] Dynamic subtest modeset-pipe-D: SUCCESS (0.562s)
[674.515353] Subtest basic-hang: SUCCESS (1.787s)
[674.648980] [127/133] (384s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[674.743276] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[674.744679] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[674.842184] [128/133] (384s left) kms_big_fb (linear-8bpp-rotate-90)
[674.938560] Starting subtest: linear-8bpp-rotate-90
[675.034304] Subtest linear-8bpp-rotate-90: SKIP (0.095s)
[675.146261] [129/133] (383s left) kms_plane_multiple (2x-tiling-y)
[675.233913] Starting subtest: 2x-tiling-y
[675.235370] Subtest 2x-tiling-y: SKIP (0.000s)
[675.297829] [130/133] (383s left) kms_getfb (getfb-repeated-different-handles)
[675.382804] Starting subtest: getfb-repeated-different-handles
[675.383570] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[675.482080] [131/133] (383s left) syncobj_wait (multi-wait-all-submitted)
[675.516551] Starting subtest: multi-wait-all-submitted
[675.625044] Subtest multi-wait-all-submitted: SUCCESS (0.108s)
[675.701155] [132/133] (383s left) kms_properties (crtc-properties-legacy)
[675.786414] Starting subtest: crtc-properties-legacy
[675.787170] Starting dynamic subtest: pipe-A-HDMI-A-1
[675.944932] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.158s)
[675.954799] Starting dynamic subtest: pipe-B-HDMI-A-1
[676.135007] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.184s)
[676.145374] Starting dynamic subtest: pipe-C-HDMI-A-1
[676.320415] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.186s)
[676.331496] Starting dynamic subtest: pipe-D-HDMI-A-1
[676.536641] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.215s)
[676.537572] Subtest crtc-properties-legacy: SUCCESS (0.752s)
[676.657415] [133/133] (382s left) gem_exec_reloc (basic-gtt-cpu-active)
[676.708818] Starting subtest: basic-gtt-cpu-active
[676.830521] Subtest basic-gtt-cpu-active: SUCCESS (0.121s)
[676.955641] Closing watchdogs
results: parsing output: 0/ for test: kms_chamelium_color
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: kms_flip
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: tools_test
results: parsing output: 6/ for test: kms_psr
results: parsing output: 7/ for test: gem_busy
results: parsing output: 8/ for test: gem_busy
results: parsing output: 9/ for test: kms_selftest
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: sw_sync
results: parsing output: 12/ for test: gem_render_copy
results: parsing output: 13/ for test: gem_ppgtt
results: parsing output: 14/ for test: gem_softpin
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: gem_fence_thrash
results: parsing output: 17/ for test: syncobj_wait
results: parsing output: 18/ for test: kms_pipe_stress
results: parsing output: 19/ for test: kms_vblank
results: parsing output: 20/ for test: kms_joiner
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_cursor_crc
results: parsing output: 23/ for test: kms_flip
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: gem_eio
results: parsing output: 26/ for test: gem_set_tiling_vs_blt
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_chamelium_hpd
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_async_flips
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_exec_flush
results: parsing output: 33/ for test: sw_sync
results: parsing output: 34/ for test: kms_getfb
results: parsing output: 35/ for test: gem_create
results: parsing output: 36/ for test: kms_color
results: parsing output: 37/ for test: kms_pipe_b_c_ivb
results: parsing output: 38/ for test: kms_draw_crc
results: parsing output: 39/ for test: gem_lmem_swapping
results: parsing output: 40/ for test: prime_mmap
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_plane
results: parsing output: 43/ for test: i915_suspend
results: parsing output: 44/ for test: kms_psr
results: parsing output: 45/ for test: kms_addfb_basic
results: parsing output: 46/ for test: prime_busy
results: parsing output: 47/ for test: kms_flip_scaled_crc
results: parsing output: 48/ for test: kms_psr2_su
results: parsing output: 49/ for test: gem_render_copy_redux
results: parsing output: 50/ for test: gem_exec_params
results: parsing output: 51/ for test: i915_module_load
results: parsing output: 52/ for test: dumb_buffer
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: kms_chamelium_edid
results: parsing output: 55/ for test: gem_mmap_wc
results: parsing output: 56/ for test: kms_ccs
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: vgem_basic
results: parsing output: 59/ for test: kms_pm_rpm
results: parsing output: 60/ for test: gem_ccs
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: gem_exec_store
results: parsing output: 65/ for test: gem_ctx_param
results: parsing output: 66/ for test: gem_ctx_shared
results: parsing output: 67/ for test: kms_content_protection
results: parsing output: 68/ for test: drm_read
results: parsing output: 69/ for test: kms_psr2_sf
results: parsing output: 70/ for test: gem_mmap_gtt
results: parsing output: 71/ for test: gem_exec_schedule
results: parsing output: 72/ for test: gem_mmap_wc
results: parsing output: 73/ for test: gem_exec_fence
results: parsing output: 74/ for test: gem_userptr_blits
results: parsing output: 75/ for test: perf_pmu
results: parsing output: 76/ for test: i915_drm_fdinfo
results: parsing output: 77/ for test: gem_exec_balancer
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: gem_exec_basic
results: parsing output: 80/ for test: syncobj_wait
results: parsing output: 81/ for test: syncobj_wait
results: parsing output: 82/ for test: gem_ctx_persistence
results: parsing output: 83/ for test: kms_cursor_legacy
results: parsing output: 84/ for test: kms_plane_scaling
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: kms_cursor_legacy
results: parsing output: 88/ for test: kms_cursor_legacy
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: gem_mmap_gtt
results: parsing output: 93/ for test: kms_flip
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gen9_exec_parse
results: parsing output: 96/ for test: gem_mmap_offset
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_cursor_crc
results: parsing output: 99/ for test: i915_pm_rpm
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: gem_pxp
results: parsing output: 103/ for test: gem_wait
results: parsing output: 104/ for test: gem_exec_gttfill
results: parsing output: 105/ for test: perf_pmu
results: parsing output: 106/ for test: syncobj_timeline
results: parsing output: 107/ for test: api_intel_bb
results: parsing output: 108/ for test: kms_pm_rpm
results: parsing output: 109/ for test: kms_flip
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: kms_cursor_edge_walk
results: parsing output: 112/ for test: syncobj_timeline
results: parsing output: 113/ for test: kms_pm_backlight
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: i915_query
results: parsing output: 116/ for test: gem_eio
results: parsing output: 117/ for test: kms_lease
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_addfb_basic
results: parsing output: 120/ for test: api_intel_bb
results: parsing output: 121/ for test: kms_psr
results: parsing output: 122/ for test: gem_ctx_isolation
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: kms_async_flips
results: parsing output: 125/ for test: kms_busy
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_big_fb
results: parsing output: 128/ for test: kms_plane_multiple
results: parsing output: 129/ for test: kms_getfb
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: kms_properties
results: parsing output: 132/ for test: gem_exec_reloc
Done.