[41.962586] Initializing watchdogs
[41.962645]   /dev/watchdog0
[41.971380] [001/130] (960s left) i915_module_load (reload)
[42.032345] Starting subtest: reload
[44.773694] Subtest reload: SUCCESS (2.740s)
[44.796254] [002/130] (957s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[45.093516] Subtest psr2-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[45.111036] [003/130] (957s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[45.333012] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[45.334465] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[45.431610] [004/130] (957s left) kms_prop_blob (basic)
[45.518583] Starting subtest: basic
[45.519480] Subtest basic: SUCCESS (0.000s)
[45.582448] [005/130] (956s left) gem_pwrite_snooped
[45.719010] [006/130] (956s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[45.966909] Starting subtest: primary-yf-tiled-reflect-x-90
[45.968437] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[46.026468] [007/130] (956s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[46.257967] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[46.259452] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[46.338971] [008/130] (956s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[46.585982] Starting subtest: fbcpsr-rgb565-draw-blt
[46.587648] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[46.682188] [009/130] (955s left) gem_exec_params (larger-than-life-batch)
[46.800811] Starting subtest: larger-than-life-batch
[54.457030] Subtest larger-than-life-batch: SUCCESS (7.827s)
[54.844947] [010/130] (947s left) kms_psr (psr-primary-page-flip)
[55.131542] Subtest psr-primary-page-flip: SKIP (0.000s)
[55.184544] [011/130] (947s left) gem_lmem_swapping (parallel-random-verify)
[58.618843] Starting subtest: parallel-random-verify
[58.619840] Starting dynamic subtest: lmem0
[115.291147] Dynamic subtest lmem0: SUCCESS (56.717s)
[115.292649] Subtest parallel-random-verify: SUCCESS (56.718s)
[117.071476] [012/130] (885s left) kms_flip (dpms-vs-vblank-race)
[121.379882] Starting subtest: dpms-vs-vblank-race
[121.383888] Starting dynamic subtest: A-DP4
[124.712604] Dynamic subtest A-DP4: SUCCESS (3.328s)
[124.723196] Starting dynamic subtest: B-DP4
[127.884671] Dynamic subtest B-DP4: SUCCESS (3.171s)
[127.894326] Starting dynamic subtest: C-DP4
[131.059498] Dynamic subtest C-DP4: SUCCESS (3.175s)
[131.068242] Starting dynamic subtest: D-DP4
[134.202630] Dynamic subtest D-DP4: SUCCESS (3.143s)
[134.203447] Subtest dpms-vs-vblank-race: SUCCESS (12.824s)
[134.425137] [013/130] (867s left) kms_psr (fbc-psr2-cursor-plane-move)
[134.676605] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[134.710245] [014/130] (867s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-pwrite)
[134.938298] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-pwrite
[134.939888] Subtest fbcpsr-1p-offscren-pri-indfb-draw-pwrite: SKIP (0.000s)
[135.020622] [015/130] (867s left) gem_create (create-ext-placement-all)
[135.217593] Starting subtest: create-ext-placement-all
[135.218443] Subtest create-ext-placement-all: SUCCESS (0.000s)
[135.344949] [016/130] (867s left) kms_big_fb (x-tiled-addfb-size-overflow)
[135.594328] Starting subtest: x-tiled-addfb-size-overflow
[135.595245] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.001s)
[135.701950] [017/130] (866s left) gem_basic (create-fd-close)
[135.797934] Starting subtest: create-fd-close
[135.799557] Subtest create-fd-close: SUCCESS (0.000s)
[135.928491] [018/130] (866s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[136.014960] Starting subtest: y-tiled-ccs-to-x-tiled
[136.016569] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[136.235778] [019/130] (866s left) drm_fdinfo (most-busy-idle-check-all)
[136.319124] Starting subtest: most-busy-idle-check-all
[136.320010] Starting dynamic subtest: rcs0
[136.345411] Dynamic subtest rcs0: SKIP (0.025s)
[136.352847] Starting dynamic subtest: bcs0
[136.354429] Dynamic subtest bcs0: SKIP (0.000s)
[136.361519] Starting dynamic subtest: vcs0
[136.363142] Dynamic subtest vcs0: SKIP (0.000s)
[136.370273] Starting dynamic subtest: vcs1
[136.371874] Dynamic subtest vcs1: SKIP (0.000s)
[136.378964] Starting dynamic subtest: vecs0
[136.380490] Dynamic subtest vecs0: SKIP (0.001s)
[136.387645] Starting dynamic subtest: vecs1
[136.389179] Dynamic subtest vecs1: SKIP (0.000s)
[136.396220] Starting dynamic subtest: ccs0
[136.397750] Dynamic subtest ccs0: SKIP (0.000s)
[136.399301] Subtest most-busy-idle-check-all: SKIP (0.031s)
[136.413898] [020/130] (866s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[136.706887] Starting subtest: basic-busy-flip-before-cursor-atomic
[136.708428] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[136.765768] [021/130] (865s left) gem_ctx_shared (q-smoketest-all)
[136.858261] Starting subtest: Q-smoketest-all
[169.159212] Subtest Q-smoketest-all: SUCCESS (32.295s)
[169.357325] [022/130] (833s left) gem_ctx_persistence (legacy-engines-cleanup)
[169.447503] Starting subtest: legacy-engines-cleanup
[169.448340] Starting dynamic subtest: render
[169.535430] Dynamic subtest render: SUCCESS (0.087s)
[169.544013] Starting dynamic subtest: bsd1
[169.584627] Dynamic subtest bsd1: SUCCESS (0.048s)
[169.618834] Starting dynamic subtest: bsd2
[169.635382] Dynamic subtest bsd2: SUCCESS (0.049s)
[169.653380] Starting dynamic subtest: blt
[169.656315] Dynamic subtest blt: SUCCESS (0.019s)
[169.669740] Starting dynamic subtest: vebox
[169.719473] Dynamic subtest vebox: SUCCESS (0.062s)
[169.720580] Subtest legacy-engines-cleanup: SUCCESS (0.272s)
[169.857881] [023/130] (832s left) kms_big_fb (linear-16bpp-rotate-180)
[170.114852] Starting subtest: linear-16bpp-rotate-180
[171.870255] Subtest linear-16bpp-rotate-180: SUCCESS (1.755s)
[172.021571] [024/130] (830s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[172.260163] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[173.470361] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.209s)
[173.663387] [025/130] (829s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[173.892968] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[173.893732] Starting dynamic subtest: pipe-A
[176.118909] Dynamic subtest pipe-A: SUCCESS (2.227s)
[176.128640] Starting dynamic subtest: pipe-B
[178.377883] Dynamic subtest pipe-B: SUCCESS (2.258s)
[178.386060] Starting dynamic subtest: pipe-C
[178.686728] Dynamic subtest pipe-C: SUCCESS (0.308s)
[178.702848] Starting dynamic subtest: pipe-D
[179.011855] Dynamic subtest pipe-D: SUCCESS (0.324s)
[179.013242] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.120s)
[179.308411] [026/130] (823s left) gem_exec_await (wide-contexts)
[179.630717] Starting subtest: wide-contexts
[201.604254] Subtest wide-contexts: SUCCESS (21.973s)
[201.807108] [027/130] (801s left) kms_cursor_crc (cursor-size-hints)
[202.109127] Starting subtest: cursor-size-hints
[202.111651] Starting dynamic subtest: pipe-A-DP-4
[202.638418] Dynamic subtest pipe-A-DP-4: SUCCESS (0.528s)
[202.649673] Starting dynamic subtest: pipe-D-DP-4
[203.125177] Dynamic subtest pipe-D-DP-4: SUCCESS (0.484s)
[203.126146] Subtest cursor-size-hints: SUCCESS (1.016s)
[203.585963] [028/130] (799s left) gem_exec_whisper (basic-contexts)
[203.696885] Starting subtest: basic-contexts
[213.535911] Subtest basic-contexts: SUCCESS (9.838s)
[213.747533] [029/130] (789s left) kms_chamelium_audio (hdmi-audio)
[214.087394] Subtest hdmi-audio: SKIP (0.000s)
[214.205399] [030/130] (788s left) gem_exec_fence (syncobj-timeline-unused-fence)
[214.873518] Starting subtest: syncobj-timeline-unused-fence
[214.898489] Subtest syncobj-timeline-unused-fence: SUCCESS (0.025s)
[215.176557] [031/130] (787s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[215.254148] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[215.255027] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[215.360153] [032/130] (787s left) prime_self_import (basic-with_one_bo_two_files)
[215.425365] Starting subtest: basic-with_one_bo_two_files
[215.440171] Subtest basic-with_one_bo_two_files: SUCCESS (0.015s)
[215.567978] [033/130] (787s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[215.819378] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[215.820987] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[215.916630] [034/130] (787s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[218.554303] Subtest verify-pxp-stale-buf-optout-execution: SKIP (0.000s)
[218.638305] [035/130] (784s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[218.864509] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[218.866222] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[218.964386] [036/130] (784s left) kms_cursor_crc (cursor-random-256x256)
[219.385940] Starting subtest: cursor-random-256x256
[219.390585] Starting dynamic subtest: pipe-A-DP-4
[223.598356] Dynamic subtest pipe-A-DP-4: SUCCESS (4.207s)
[223.628274] Starting dynamic subtest: pipe-D-DP-4
[228.132689] Dynamic subtest pipe-D-DP-4: SUCCESS (4.511s)
[228.133595] Subtest cursor-random-256x256: SUCCESS (8.746s)
[228.454120] [037/130] (774s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[228.709772] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[228.711331] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[228.800287] [038/130] (774s left) kms_draw_crc (fill-fb)
[229.053559] Starting subtest: fill-fb
[229.575572] Subtest fill-fb: SUCCESS (0.521s)
[229.694537] [039/130] (773s left) gem_eio (suspend)
[230.015958] Starting subtest: suspend
[243.820667] Subtest suspend: SUCCESS (13.805s)
[247.040335] [040/130] (759s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[247.277734] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[247.278544] Starting dynamic subtest: pipe-A-DP-4
[247.285746] Dynamic subtest pipe-A-DP-4: SUCCESS (0.001s)
[247.295354] Starting dynamic subtest: pipe-B-DP-4
[247.302460] Dynamic subtest pipe-B-DP-4: SUCCESS (0.000s)
[247.312013] Starting dynamic subtest: pipe-C-DP-4
[247.318947] Dynamic subtest pipe-C-DP-4: SUCCESS (0.000s)
[247.328099] Starting dynamic subtest: pipe-D-DP-4
[247.335021] Dynamic subtest pipe-D-DP-4: SUCCESS (0.000s)
[247.335834] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SUCCESS (0.008s)
[247.676352] [041/130] (758s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[247.933537] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[247.935185] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[248.054474] [042/130] (758s left) gem_spin_batch (resubmit-new)
[248.161921] Starting subtest: resubmit-new
[248.162838] Starting dynamic subtest: rcs0
[248.192345] Dynamic subtest rcs0: SUCCESS (0.030s)
[248.202157] Starting dynamic subtest: bcs0
[248.203072] Dynamic subtest bcs0: SUCCESS (0.003s)
[248.212815] Starting dynamic subtest: vcs0
[248.213632] Dynamic subtest vcs0: SUCCESS (0.004s)
[248.223140] Starting dynamic subtest: vcs1
[248.223944] Dynamic subtest vcs1: SUCCESS (0.006s)
[248.233159] Starting dynamic subtest: vecs0
[248.233968] Dynamic subtest vecs0: SUCCESS (0.003s)
[248.242985] Starting dynamic subtest: vecs1
[248.243807] Dynamic subtest vecs1: SUCCESS (0.003s)
[248.252587] Starting dynamic subtest: ccs0
[248.253399] Dynamic subtest ccs0: SUCCESS (0.004s)
[248.254200] Subtest resubmit-new: SUCCESS (0.060s)
[248.504780] [043/130] (757s left) kms_hdr (bpc-switch-suspend)
[248.734190] Starting subtest: bpc-switch-suspend
[251.945211] Starting dynamic subtest: pipe-A-DP-4
[272.660758] Dynamic subtest pipe-A-DP-4: SUCCESS (5.019s)
[272.661727] Subtest bpc-switch-suspend: SUCCESS (8.232s)
[275.934018] [044/130] (749s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[276.168803] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[276.170463] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[276.280363] [045/130] (748s left) sysfs_heartbeat_interval (idempotent)
[276.371848] Starting subtest: idempotent
[276.372710] Starting dynamic subtest: vecs0
[276.374932] Dynamic subtest vecs0: SUCCESS (0.001s)
[276.385200] Starting dynamic subtest: ccs0
[276.386118] Dynamic subtest ccs0: SUCCESS (0.001s)
[276.396172] Starting dynamic subtest: vcs0
[276.397065] Dynamic subtest vcs0: SUCCESS (0.001s)
[276.406938] Starting dynamic subtest: bcs0
[276.407737] Dynamic subtest bcs0: SUCCESS (0.001s)
[276.417613] Starting dynamic subtest: vecs1
[276.418407] Dynamic subtest vecs1: SUCCESS (0.001s)
[276.428809] Starting dynamic subtest: rcs0
[276.429612] Dynamic subtest rcs0: SUCCESS (0.001s)
[276.439112] Starting dynamic subtest: vcs1
[276.439938] Dynamic subtest vcs1: SUCCESS (0.001s)
[276.440713] Subtest idempotent: SUCCESS (0.013s)
[276.455050] [046/130] (748s left) kms_color (ctm-green-to-red)
[276.685091] Starting subtest: ctm-green-to-red
[276.685866] Starting dynamic subtest: pipe-A-DP-4
[277.306076] Dynamic subtest pipe-A-DP-4: SUCCESS (0.619s)
[277.328324] Starting dynamic subtest: pipe-B-DP-4
[277.982513] Dynamic subtest pipe-B-DP-4: SUCCESS (0.674s)
[278.000045] Starting dynamic subtest: pipe-C-DP-4
[278.623274] Dynamic subtest pipe-C-DP-4: SUCCESS (0.640s)
[278.639260] Starting dynamic subtest: pipe-D-DP-4
[279.268130] Dynamic subtest pipe-D-DP-4: SUCCESS (0.642s)
[279.269244] Subtest ctm-green-to-red: SUCCESS (2.582s)
[279.498477] [047/130] (745s left) gem_exec_schedule (semaphore-user)
[279.611608] Starting subtest: semaphore-user
[279.689712] Subtest semaphore-user: SUCCESS (0.077s)
[280.079714] [048/130] (745s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[280.317118] Starting subtest: psr-1p-primscrn-cur-indfb-move
[280.318711] Subtest psr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[280.421780] [049/130] (744s left) syncobj_timeline (multi-wait-available-signaled)
[280.517002] Starting subtest: multi-wait-available-signaled
[280.517875] Subtest multi-wait-available-signaled: SUCCESS (0.001s)
[280.646297] [050/130] (744s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[280.891408] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[280.893057] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[280.981813] [051/130] (744s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[281.229766] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[281.249650] [052/130] (744s left) gem_exec_flush (basic-uc-set-default)
[281.336959] Subtest basic-uc-set-default: SKIP (0.000s)
[281.403275] [053/130] (744s left) kms_lease (lease-get)
[281.636132] Starting subtest: lease-get
[281.636950] Starting dynamic subtest: pipe-A-DP-4
[281.639193] Dynamic subtest pipe-A-DP-4: SUCCESS (0.002s)
[281.651263] Starting dynamic subtest: pipe-B-DP-4
[281.652096] Dynamic subtest pipe-B-DP-4: SUCCESS (0.001s)
[281.662133] Starting dynamic subtest: pipe-C-DP-4
[281.663073] Dynamic subtest pipe-C-DP-4: SUCCESS (0.001s)
[281.673842] Starting dynamic subtest: pipe-D-DP-4
[281.674639] Dynamic subtest pipe-D-DP-4: SUCCESS (0.001s)
[281.675462] Subtest lease-get: SUCCESS (0.013s)
[281.755694] [054/130] (743s left) kms_addfb_basic (unused-pitches)
[281.846430] Starting subtest: unused-pitches
[281.847313] Subtest unused-pitches: SUCCESS (0.000s)
[282.104440] [055/130] (743s left) gem_workarounds (reset-fd)
[282.216798] Starting subtest: reset-fd
[282.439621] Subtest reset-fd: SUCCESS (0.223s)
[282.594479] [056/130] (742s left) kms_content_protection (mei-interface)
[282.875807] Starting subtest: mei-interface
[283.475575] Subtest mei-interface: SKIP (0.598s)
[283.833588] [057/130] (741s left) kms_big_fb (4-tiled-addfb-size-overflow)
[284.097803] Starting subtest: 4-tiled-addfb-size-overflow
[284.100630] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.001s)
[284.195156] [058/130] (741s left) gem_mmap_gtt (flink-race)
[284.295160] Subtest flink-race: SKIP (0.000s)
[284.345663] [059/130] (741s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[284.656506] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[284.785568] [060/130] (740s left) gen7_exec_parse (batch-without-end)
[284.881096] Subtest batch-without-end: SKIP (0.000s)
[284.924547] [061/130] (740s left) kms_psr (pr-cursor-render)
[285.155703] Subtest pr-cursor-render: SKIP (0.000s)
[285.189478] [062/130] (740s left) kms_psr (psr2-primary-render)
[285.421319] Subtest psr2-primary-render: SKIP (0.000s)
[285.456071] [063/130] (740s left) gem_exec_schedule (preempt-engines)
[285.546504] Starting subtest: preempt-engines
[285.547406] Starting dynamic subtest: rcs0
[287.808921] Dynamic subtest rcs0: SUCCESS (2.261s)
[287.821387] Starting dynamic subtest: bcs0
[290.024833] Dynamic subtest bcs0: SUCCESS (2.225s)
[290.037708] Starting dynamic subtest: vcs0
[292.316617] Dynamic subtest vcs0: SUCCESS (2.299s)
[292.329706] Starting dynamic subtest: vcs1
[294.610195] Dynamic subtest vcs1: SUCCESS (2.298s)
[294.622556] Starting dynamic subtest: vecs0
[296.902811] Dynamic subtest vecs0: SUCCESS (2.296s)
[296.921479] Starting dynamic subtest: vecs1
[299.206134] Dynamic subtest vecs1: SUCCESS (2.304s)
[299.218713] Starting dynamic subtest: ccs0
[301.390577] Dynamic subtest ccs0: SUCCESS (2.185s)
[301.392017] Subtest preempt-engines: SUCCESS (15.892s)
[301.718072] [064/130] (724s left) gem_exec_reloc (basic-wc-read)
[301.801190] Subtest basic-wc-read: SKIP (0.000s)
[301.867725] [065/130] (723s left) gem_workarounds (reset)
[301.974808] Starting subtest: reset
[302.190102] Subtest reset: SUCCESS (0.216s)
[302.311908] [066/130] (723s left) gem_set_tiling_vs_pwrite
[302.425731] [067/130] (723s left) kms_plane (plane-panning-top-left)
[302.661438] Starting subtest: plane-panning-top-left
[302.662548] Starting dynamic subtest: pipe-A
[303.411659] Dynamic subtest pipe-A: SUCCESS (0.750s)
[303.426922] Starting dynamic subtest: pipe-B
[304.070815] Dynamic subtest pipe-B: SUCCESS (0.658s)
[304.071656] Subtest plane-panning-top-left: SUCCESS (1.412s)
[304.311360] [068/130] (721s left) kms_dp_linktrain_fallback (dp-fallback)
[304.551576] Starting subtest: dp-fallback
[312.075591] Subtest dp-fallback: SUCCESS (7.531s)
[312.501839] [069/130] (713s left) perf (mi-rpc)
[312.868056] Starting subtest: mi-rpc
[312.869641] Subtest mi-rpc: SKIP (0.000s)
[312.900829] [070/130] (712s left) syncobj_timeline (signal)
[313.054058] Starting subtest: signal
[313.054936] Subtest signal: SUCCESS (0.000s)
[313.159705] [071/130] (712s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[313.432384] Starting subtest: compare-crc-sanitycheck-nv12
[313.433252] Starting dynamic subtest: pipe-A-DP-4
[314.123149] Dynamic subtest pipe-A-DP-4: SUCCESS (0.690s)
[314.137542] Starting dynamic subtest: pipe-B-DP-4
[314.789204] Dynamic subtest pipe-B-DP-4: SUCCESS (0.664s)
[314.802042] Starting dynamic subtest: pipe-C-DP-4
[315.415798] Dynamic subtest pipe-C-DP-4: SUCCESS (0.626s)
[315.428697] Starting dynamic subtest: pipe-D-DP-4
[316.045030] Dynamic subtest pipe-D-DP-4: SUCCESS (0.627s)
[316.045845] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.615s)
[316.287522] [072/130] (709s left) gem_exec_balancer (parallel-keep-in-fence)
[316.656352] Starting subtest: parallel-keep-in-fence
[318.644373] Subtest parallel-keep-in-fence: SUCCESS (1.989s)
[318.746100] [073/130] (707s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[319.097869] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[319.224547] [074/130] (706s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[319.366027] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[319.472955] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.105s)
[319.564588] [075/130] (706s left) kms_plane_scaling (planes-upscale-20x20)
[319.853395] Starting subtest: planes-upscale-20x20
[319.854159] Starting dynamic subtest: pipe-A
[319.964057] Dynamic subtest pipe-A: SUCCESS (0.111s)
[319.989155] Starting dynamic subtest: pipe-B
[320.188889] Dynamic subtest pipe-B: SUCCESS (0.223s)
[320.209443] Starting dynamic subtest: pipe-C
[320.413876] Dynamic subtest pipe-C: SUCCESS (0.223s)
[320.434425] Starting dynamic subtest: pipe-D
[320.639685] Dynamic subtest pipe-D: SUCCESS (0.224s)
[320.640517] Subtest planes-upscale-20x20: SUCCESS (0.788s)
[320.887788] [076/130] (705s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[321.180392] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[321.182636] Starting dynamic subtest: pipe-A-DP-4
[324.789884] Dynamic subtest pipe-A-DP-4: SUCCESS (3.600s)
[324.802959] Starting dynamic subtest: pipe-B-DP-4
[325.360295] Dynamic subtest pipe-B-DP-4: SUCCESS (0.561s)
[325.387114] Starting dynamic subtest: pipe-C-DP-4
[325.950336] Dynamic subtest pipe-C-DP-4: SUCCESS (0.584s)
[325.977017] Starting dynamic subtest: pipe-D-DP-4
[326.547633] Dynamic subtest pipe-D-DP-4: SUCCESS (0.590s)
[326.549412] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SUCCESS (5.355s)
[326.830911] [077/130] (699s left) gem_softpin (allocator-basic)
[326.992098] Starting subtest: allocator-basic
[327.179093] Subtest allocator-basic: SUCCESS (0.177s)
[327.334532] [078/130] (698s left) gem_mmap_gtt (coherency)
[327.464207] Subtest coherency: SKIP (0.000s)
[327.516475] [079/130] (698s left) syncobj_eventfd (binary-wait-signaled)
[327.653057] Starting subtest: binary-wait-signaled
[327.653893] Subtest binary-wait-signaled: SUCCESS (0.000s)
[327.745206] [080/130] (698s left) syncobj_timeline (etime-single-wait-all-submitted)
[327.865102] Starting subtest: etime-single-wait-all-submitted
[327.966865] Subtest etime-single-wait-all-submitted: SUCCESS (0.102s)
[328.085999] [081/130] (698s left) kms_flip (basic-flip-vs-modeset)
[328.352717] Starting subtest: basic-flip-vs-modeset
[328.357260] Starting dynamic subtest: A-DP4
[329.234479] Dynamic subtest A-DP4: SUCCESS (0.875s)
[329.261231] Starting dynamic subtest: B-DP4
[330.087240] Dynamic subtest B-DP4: SUCCESS (0.852s)
[330.102271] Starting dynamic subtest: C-DP4
[330.926598] Dynamic subtest C-DP4: SUCCESS (0.837s)
[330.947455] Starting dynamic subtest: D-DP4
[331.776713] Dynamic subtest D-DP4: SUCCESS (0.849s)
[331.777733] Subtest basic-flip-vs-modeset: SUCCESS (3.428s)
[332.036081] [082/130] (694s left) kms_atomic_interruptible (universal-setplane-cursor)
[332.316255] Starting subtest: universal-setplane-cursor
[332.318456] Starting dynamic subtest: pipe-A-DP-4
[338.617053] Dynamic subtest pipe-A-DP-4: SUCCESS (6.301s)
[338.618518] Subtest universal-setplane-cursor: SUCCESS (6.305s)
[338.824162] [083/130] (687s left) kms_dirtyfb (drrs-dirtyfb-ioctl)
[339.124772] Starting subtest: drrs-dirtyfb-ioctl
[339.126475] Subtest drrs-dirtyfb-ioctl: SKIP (0.001s)
[339.268154] [084/130] (687s left) prime_busy (after)
[339.414180] Starting subtest: after
[339.420135] Starting dynamic subtest: rcs0
[339.478311] Dynamic subtest rcs0: SUCCESS (0.058s)
[339.490459] Starting dynamic subtest: bcs0
[339.538695] Dynamic subtest bcs0: SUCCESS (0.059s)
[339.558588] Starting dynamic subtest: vcs0
[339.580680] Dynamic subtest vcs0: SUCCESS (0.040s)
[339.608935] Starting dynamic subtest: vcs1
[339.622427] Dynamic subtest vcs1: SUCCESS (0.040s)
[339.641133] Starting dynamic subtest: vecs0
[339.664640] Dynamic subtest vecs0: SUCCESS (0.041s)
[339.680111] Starting dynamic subtest: vecs1
[339.705744] Dynamic subtest vecs1: SUCCESS (0.040s)
[339.722631] Starting dynamic subtest: ccs0
[339.745236] Dynamic subtest ccs0: SUCCESS (0.038s)
[339.854618] Subtest after: SUCCESS (0.440s)
[339.991646] [085/130] (686s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[340.297964] Starting subtest: psr-shrfb-scaledprimary
[340.299570] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[340.403946] [086/130] (686s left) kms_flip (blocking-absolute-wf_vblank)
[340.712095] Starting subtest: blocking-absolute-wf_vblank
[340.716495] Starting dynamic subtest: A-DP4
[348.728084] Dynamic subtest A-DP4: SUCCESS (8.015s)
[348.741898] Starting dynamic subtest: B-DP4
[356.648522] Dynamic subtest B-DP4: SUCCESS (7.921s)
[356.663063] Starting dynamic subtest: C-DP4
[364.589802] Dynamic subtest C-DP4: SUCCESS (7.941s)
[364.602449] Starting dynamic subtest: D-DP4
[372.516483] Dynamic subtest D-DP4: SUCCESS (7.929s)
[372.518730] Subtest blocking-absolute-wf_vblank: SUCCESS (31.823s)
[372.839433] [087/130] (653s left) perf_pmu (all-busy-idle-check-all)
[372.957887] Starting subtest: all-busy-idle-check-all
[373.649277] Subtest all-busy-idle-check-all: SUCCESS (0.692s)
[373.766538] [088/130] (652s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[374.053745] Starting subtest: y-tiled-32bpp-rotate-0
[374.055456] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[374.135126] [089/130] (652s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[374.427482] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[374.428342] Starting dynamic subtest: pipe-A-DP-4
[374.436376] Dynamic subtest pipe-A-DP-4: SKIP (0.001s)
[374.448215] Starting dynamic subtest: pipe-B-DP-4
[374.456165] Dynamic subtest pipe-B-DP-4: SKIP (0.001s)
[374.467738] Starting dynamic subtest: pipe-C-DP-4
[374.475378] Dynamic subtest pipe-C-DP-4: SKIP (0.001s)
[374.486301] Starting dynamic subtest: pipe-D-DP-4
[374.494172] Dynamic subtest pipe-D-DP-4: SKIP (0.001s)
[374.495737] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.010s)
[374.862122] [090/130] (651s left) kms_vblank (wait-forked-busy-hang)
[375.146402] Starting subtest: wait-forked-busy-hang
[375.148613] Starting dynamic subtest: pipe-A-DP-4
[401.307741] Dynamic subtest pipe-A-DP-4: SUCCESS (26.133s)
[401.322631] Starting dynamic subtest: pipe-D-DP-4
[426.250720] Dynamic subtest pipe-D-DP-4: SUCCESS (24.928s)
[426.251568] Subtest wait-forked-busy-hang: SUCCESS (51.068s)
[426.474807] [091/130] (600s left) kms_atomic (plane-cursor-legacy)
[426.760522] Starting subtest: plane-cursor-legacy
[426.761405] Starting dynamic subtest: pipe-A-DP-4
[427.274580] Dynamic subtest pipe-A-DP-4: SUCCESS (0.513s)
[427.275440] Subtest plane-cursor-legacy: SUCCESS (0.515s)
[427.514506] [092/130] (599s left) gem_exec_fence (invalid-fence-array)
[428.132837] Starting subtest: invalid-fence-array
[428.135195] Subtest invalid-fence-array: SUCCESS (0.002s)
[428.529917] [093/130] (598s left) kms_universal_plane (universal-plane-functional)
[428.823068] Starting subtest: universal-plane-functional
[428.823995] Starting dynamic subtest: pipe-A-DP-4
[431.221868] Dynamic subtest pipe-A-DP-4: SUCCESS (2.398s)
[431.235222] Starting dynamic subtest: pipe-B-DP-4
[433.454792] Dynamic subtest pipe-B-DP-4: SUCCESS (2.231s)
[433.468474] Starting dynamic subtest: pipe-C-DP-4
[435.676999] Dynamic subtest pipe-C-DP-4: SUCCESS (2.220s)
[435.690244] Starting dynamic subtest: pipe-D-DP-4
[437.891425] Dynamic subtest pipe-D-DP-4: SUCCESS (2.213s)
[437.892341] Subtest universal-plane-functional: SUCCESS (9.070s)
[438.137375] [094/130] (588s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[440.792207] Starting subtest: gem-execbuf-stress-pc8
[440.875533] Subtest gem-execbuf-stress-pc8: SKIP (0.085s)
[441.216637] [095/130] (585s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[441.494796] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[441.495658] Starting dynamic subtest: pipe-A-valid-mode
[444.100059] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.603s)
[444.102017] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SUCCESS (2.606s)
[444.352495] [096/130] (582s left) kms_addfb_basic (bad-pitch-1024)
[444.480512] Starting subtest: bad-pitch-1024
[444.481345] Subtest bad-pitch-1024: SUCCESS (0.000s)
[444.708957] [097/130] (582s left) debugfs_test (basic-hwmon)
[444.844649] Starting subtest: basic-hwmon
[444.857224] Subtest basic-hwmon: SUCCESS (0.011s)
[445.071868] [098/130] (581s left) gem_caching (reads)
[445.210172] Starting subtest: reads
[447.878178] Subtest reads: SUCCESS (2.667s)
[448.040249] [099/130] (578s left) prime_mmap (test_correct_cpu_write)
[448.167595] Starting subtest: test_correct_cpu_write
[448.168458] Starting dynamic subtest: test_correct_cpu_write-smem
[448.169442] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[448.181829] Starting dynamic subtest: test_correct_cpu_write-lmem0
[448.182624] Dynamic subtest test_correct_cpu_write-lmem0: SUCCESS (0.000s)
[448.183419] Subtest test_correct_cpu_write: SUCCESS (0.003s)
[448.305671] [100/130] (578s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[448.597231] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[448.599034] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[448.704857] [101/130] (578s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[449.043729] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[449.045750] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[449.147668] [102/130] (578s left) gem_mmap_offset (pf-nonblock)
[449.302715] Starting subtest: pf-nonblock
[449.339726] Subtest pf-nonblock: SUCCESS (0.037s)
[449.476458] [103/130] (577s left) kms_pm_rpm (cursor)
[452.212344] Starting subtest: cursor
[456.888305] Subtest cursor: SUCCESS (4.675s)
[457.300942] [104/130] (569s left) perf_pmu (enable-race)
[457.412886] Starting subtest: enable-race
[457.413732] Starting dynamic subtest: rcs0
[470.199656] Dynamic subtest rcs0: SUCCESS (12.785s)
[470.212373] Starting dynamic subtest: bcs0
[482.958650] Dynamic subtest bcs0: SUCCESS (12.757s)
[482.971274] Starting dynamic subtest: vcs0
[495.713794] Dynamic subtest vcs0: SUCCESS (12.754s)
[495.726289] Starting dynamic subtest: vcs1
[508.467590] Dynamic subtest vcs1: SUCCESS (12.753s)
[508.481294] Starting dynamic subtest: vecs0
[521.221699] Dynamic subtest vecs0: SUCCESS (12.753s)
[521.234167] Starting dynamic subtest: vecs1
[533.976597] Dynamic subtest vecs1: SUCCESS (12.754s)
[533.988836] Starting dynamic subtest: ccs0
[546.729555] Dynamic subtest ccs0: SUCCESS (12.753s)
[546.730446] Subtest enable-race: SUCCESS (89.317s)
[546.870084] [105/130] (480s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[547.177081] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[547.178862] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[547.318681] [106/130] (479s left) api_intel_bb (blit-reloc-purge-cache)
[547.448469] Starting subtest: blit-reloc-purge-cache
[547.450168] Subtest blit-reloc-purge-cache: SKIP (0.000s)
[547.573905] [107/130] (479s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[547.866351] Starting subtest: basic-flip-before-cursor-legacy
[548.889937] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.023s)
[549.180814] [108/130] (478s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[549.462895] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[549.465787] Starting dynamic subtest: pipe-A-DP-4
[549.475353] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[549.489322] Starting dynamic subtest: pipe-B-DP-4
[549.497264] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[549.509217] Starting dynamic subtest: pipe-C-DP-4
[549.516953] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[549.528817] Starting dynamic subtest: pipe-D-DP-4
[549.536783] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[549.538391] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.011s)
[549.864633] [109/130] (477s left) gen9_exec_parse (bb-chained)
[549.999834] Subtest bb-chained: SKIP (0.000s)
[550.045174] [110/130] (477s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[550.314123] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[550.315983] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[550.410662] [111/130] (477s left) gem_userptr_blits (coherency-unsync)
[550.555032] Subtest coherency-unsync: SKIP (0.000s)
[550.719547] [112/130] (476s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[550.993362] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[551.029043] [113/130] (476s left) kms_atomic_transition (plane-all-transition-nonblocking)
[551.303998] Starting subtest: plane-all-transition-nonblocking
[551.304813] Starting dynamic subtest: pipe-A-DP-4
[555.030418] Dynamic subtest pipe-A-DP-4: SUCCESS (3.725s)
[555.145546] Starting dynamic subtest: pipe-B-DP-4
[558.905853] Dynamic subtest pipe-B-DP-4: SUCCESS (3.798s)
[558.959424] Subtest plane-all-transition-nonblocking: SUCCESS (7.656s)
[559.183282] [114/130] (468s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[559.514220] Starting subtest: yf-tiled-64bpp-rotate-180
[559.516231] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[559.615186] [115/130] (468s left) i915_query (query-regions-unallocated)
[559.752598] Starting subtest: query-regions-unallocated
[560.572747] Subtest query-regions-unallocated: SUCCESS (0.821s)
[560.659786] [116/130] (467s left) syncobj_basic (bad-flags-fd-to-handle)
[560.786398] Starting subtest: bad-flags-fd-to-handle
[560.787353] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[560.872259] [117/130] (466s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[561.153065] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[561.154042] Starting dynamic subtest: pipe-A-valid-mode
[561.242987] Dynamic subtest pipe-A-valid-mode: SKIP (0.087s)
[561.244698] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.090s)
[561.434326] [118/130] (466s left) gem_exec_nop (basic-sequential)
[561.568072] Starting subtest: basic-sequential
[566.032702] Subtest basic-sequential: SUCCESS (4.463s)
[566.272222] [119/130] (461s left) gem_bad_reloc (negative-reloc-lut)
[566.396705] Subtest negative-reloc-lut: SKIP (0.000s)
[566.470777] [120/130] (461s left) perf (gen12-group-exclusive-stream-sample-oa)
[566.758279] Starting subtest: gen12-group-exclusive-stream-sample-oa
[566.783133] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.024s)
[566.811357] [121/130] (461s left) dmabuf_sync_file (import-basic)
[566.925537] Starting subtest: import-basic
[566.927640] Subtest import-basic: SUCCESS (0.002s)
[566.978404] [122/130] (460s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[567.253693] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[567.255311] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[567.366052] [123/130] (460s left) gem_ctx_param (vm)
[567.506729] Starting subtest: vm
[567.576152] Subtest vm: SUCCESS (0.069s)
[567.717662] [124/130] (460s left) gem_exec_fence (basic-busy-all)
[567.848594] Starting subtest: basic-busy-all
[567.969704] Subtest basic-busy-all: SUCCESS (0.120s)
[568.753612] [125/130] (459s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[568.889043] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[568.889890] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[569.034963] [126/130] (458s left) kms_psr (fbc-psr-dpms)
[569.314263] Subtest fbc-psr-dpms: SKIP (0.000s)
[569.341708] [127/130] (458s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[569.621396] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[569.623053] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[569.731800] [128/130] (458s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[570.014633] Starting subtest: short-flip-before-cursor-atomic-transitions
[571.051939] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (1.037s)
[571.300009] [129/130] (456s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[571.584655] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[571.586452] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[571.721237] [130/130] (456s left) gem_pxp (create-regular-buffer)
[574.381255] Subtest create-regular-buffer: SKIP (0.000s)
[574.461470] Closing watchdogs
Done.