[44.512258] Initializing watchdogs
[44.512300]   /dev/watchdog0
[44.518027] [001/130] (960s left) kms_vblank (ts-continuation-modeset-rpm)
[44.756103] Starting subtest: ts-continuation-modeset-rpm
[44.757012] Starting dynamic subtest: pipe-A-DP-4
[45.546055] Dynamic subtest pipe-A-DP-4: SUCCESS (0.789s)
[45.552459] Starting dynamic subtest: pipe-D-DP-4
[46.402556] Dynamic subtest pipe-D-DP-4: SUCCESS (0.852s)
[46.403484] Subtest ts-continuation-modeset-rpm: SUCCESS (1.645s)
[46.579147] [002/130] (958s left) gem_ctx_sseu (invalid-args)
[46.663927] Subtest invalid-args: SKIP (0.000s)
[46.699240] [003/130] (958s left) api_intel_bb (offset-control)
[46.780074] Starting subtest: offset-control
[46.834083] Subtest offset-control: SUCCESS (0.054s)
[46.904302] [004/130] (958s left) gem_ctx_persistence (engines-hostile-preempt)
[46.962502] Starting subtest: engines-hostile-preempt
[46.963383] Starting dynamic subtest: rcs0
[47.034152] Dynamic subtest rcs0: SUCCESS (0.072s)
[47.039507] Starting dynamic subtest: bcs0
[47.091574] Dynamic subtest bcs0: SUCCESS (0.057s)
[47.105652] Starting dynamic subtest: vcs0
[47.162357] Dynamic subtest vcs0: SUCCESS (0.070s)
[47.171457] Starting dynamic subtest: vcs1
[47.211309] Dynamic subtest vcs1: SUCCESS (0.049s)
[47.219691] Starting dynamic subtest: vecs0
[47.251175] Dynamic subtest vecs0: SUCCESS (0.039s)
[47.274422] Starting dynamic subtest: vecs1
[47.318243] Dynamic subtest vecs1: SUCCESS (0.067s)
[47.340652] Starting dynamic subtest: ccs0
[47.363083] Dynamic subtest ccs0: SUCCESS (0.045s)
[47.364596] Subtest engines-hostile-preempt: SUCCESS (0.399s)
[47.508915] [005/130] (957s left) gem_exec_params (no-blt)
[47.606744] Starting subtest: no-blt
[47.609577] Subtest no-blt: SUCCESS (0.003s)
[47.740576] [006/130] (957s left) gem_exec_whisper (basic-queues-forked-all)
[47.813215] Starting subtest: basic-queues-forked-all
[69.698514] Subtest basic-queues-forked-all: SUCCESS (21.867s)
[69.857608] [007/130] (935s left) kms_atomic_interruptible (legacy-pageflip)
[70.038917] Starting subtest: legacy-pageflip
[70.039768] Starting dynamic subtest: pipe-A-DP-4
[76.379693] Dynamic subtest pipe-A-DP-4: SUCCESS (6.337s)
[76.380677] Subtest legacy-pageflip: SUCCESS (6.338s)
[76.571232] [008/130] (928s left) gem_mmap_gtt (basic-write-read)
[76.667248] Subtest basic-write-read: SKIP (0.000s)
[76.696313] [009/130] (928s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[76.755059] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[76.796925] [010/130] (928s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[76.995105] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[76.996754] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[77.070993] [011/130] (928s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[77.280903] Starting subtest: short-busy-flip-before-cursor-toggle
[77.282597] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[77.333539] [012/130] (927s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[77.539956] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[77.541850] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[77.600571] [013/130] (927s left) gem_pread (exhaustion)
[77.663205] Subtest exhaustion: SKIP (0.000s)
[77.696267] [014/130] (927s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[77.898794] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[77.900467] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[77.990345] [015/130] (927s left) kms_rotation_crc (cursor-rotation-180)
[78.204564] Starting subtest: cursor-rotation-180
[79.086099] Subtest cursor-rotation-180: SUCCESS (0.881s)
[79.394112] [016/130] (925s left) perf (short-reads)
[79.776823] Starting subtest: short-reads
[79.795253] Subtest short-reads: SUCCESS (0.019s)
[79.821630] [017/130] (925s left) gem_fenced_exec_thrash (too-many-fences)
[79.923399] Subtest too-many-fences: SKIP (0.000s)
[79.971858] [018/130] (925s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[80.197947] Starting subtest: fbcpsr-1p-rte
[80.199664] Subtest fbcpsr-1p-rte: SKIP (0.000s)
[80.255504] [019/130] (925s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[80.477099] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[80.478906] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[80.544333] [020/130] (924s left) kms_flip (2x-flip-vs-fences)
[80.774639] Starting subtest: 2x-flip-vs-fences
[80.776415] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[80.844495] [021/130] (924s left) kms_psr (fbc-psr-sprite-blt)
[81.080924] Subtest fbc-psr-sprite-blt: SKIP (0.000s)
[81.102075] [022/130] (924s left) kms_feature_discovery (display)
[81.315950] Starting subtest: display
[81.316832] Subtest display: SUCCESS (0.000s)
[81.373726] [023/130] (924s left) perf_pmu (busy-check-all)
[81.451400] Starting subtest: busy-check-all
[81.452329] Starting dynamic subtest: rcs0
[82.046328] Dynamic subtest rcs0: SUCCESS (0.594s)
[82.055457] Starting dynamic subtest: bcs0
[82.596819] Dynamic subtest bcs0: SUCCESS (0.550s)
[82.606313] Starting dynamic subtest: vcs0
[83.148177] Dynamic subtest vcs0: SUCCESS (0.551s)
[83.157602] Starting dynamic subtest: vcs1
[83.699074] Dynamic subtest vcs1: SUCCESS (0.551s)
[83.708410] Starting dynamic subtest: vecs0
[84.249132] Dynamic subtest vecs0: SUCCESS (0.551s)
[84.256109] Starting dynamic subtest: vecs1
[84.798137] Dynamic subtest vecs1: SUCCESS (0.548s)
[84.807706] Starting dynamic subtest: ccs0
[85.349064] Dynamic subtest ccs0: SUCCESS (0.550s)
[85.350945] Subtest busy-check-all: SUCCESS (3.896s)
[85.446224] [024/130] (919s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[85.672294] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[85.674119] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[85.744655] [025/130] (919s left) kms_cursor_legacy (flip-vs-cursor-varying-size)
[85.980422] Starting subtest: flip-vs-cursor-varying-size
[95.657861] Subtest flip-vs-cursor-varying-size: SUCCESS (9.676s)
[95.863341] [026/130] (909s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[96.077861] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[96.441015] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (0.363s)
[96.688554] [027/130] (908s left) gem_ctx_bad_destroy (double-destroy)
[96.812425] Starting subtest: double-destroy
[96.813400] Subtest double-destroy: SUCCESS (0.000s)
[96.875437] [028/130] (908s left) kms_psr2_su (page_flip-p010)
[96.993366] Subtest page_flip-P010: SKIP (0.000s)
[97.030631] [029/130] (908s left) kms_psr (fbc-psr-cursor-plane-move)
[97.238662] Subtest fbc-psr-cursor-plane-move: SKIP (0.000s)
[97.263577] [030/130] (908s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[97.477842] Starting subtest: yf-tiled-64bpp-rotate-0
[97.479586] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[97.556519] [031/130] (908s left) kms_cursor_crc (cursor-sliding-32x10)
[97.853486] Starting subtest: cursor-sliding-32x10
[97.900529] Subtest cursor-sliding-32x10: SKIP (0.045s)
[98.162691] [032/130] (907s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[98.419581] Starting subtest: x-tiled-32bpp-rotate-270
[98.474143] Subtest x-tiled-32bpp-rotate-270: SKIP (0.054s)
[98.640352] [033/130] (907s left) drm_read (short-buffer-nonblock)
[98.938094] Starting subtest: short-buffer-nonblock
[98.938894] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[99.108245] [034/130] (906s left) i915_pm_rps (min-max-config-loaded)
[99.224509] Starting subtest: min-max-config-loaded
[99.226782] Subtest min-max-config-loaded: SKIP (0.001s)
[99.327639] [035/130] (906s left) kms_addfb_basic (addfb25-4-tiled)
[99.548438] Starting subtest: addfb25-4-tiled
[99.549363] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[99.622698] [036/130] (906s left) gem_sync (basic-store-all)
[99.735600] Starting subtest: basic-store-all
[104.663457] Subtest basic-store-all: SUCCESS (4.930s)
[104.854678] [037/130] (900s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[105.074342] Starting subtest: y-tiled-8bpp-rotate-180
[105.076157] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[105.161079] [038/130] (900s left) gem_close_race (basic-process)
[105.310142] Starting subtest: basic-process
[105.352524] Subtest basic-process: SUCCESS (0.042s)
[105.574321] [039/130] (900s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[105.783911] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[105.784842] Starting dynamic subtest: pipe-A-valid-mode
[105.865152] Dynamic subtest pipe-A-valid-mode: SKIP (0.079s)
[105.866907] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.080s)
[106.042691] [040/130] (899s left) kms_plane_scaling (plane-downscale-factor-0-25-with-pixel-format)
[106.290829] Starting subtest: plane-downscale-factor-0-25-with-pixel-format
[106.291608] Starting dynamic subtest: pipe-A
[107.034933] Dynamic subtest pipe-A: SKIP (0.743s)
[107.041650] Starting dynamic subtest: pipe-B
[108.169316] Dynamic subtest pipe-B: SKIP (1.134s)
[108.176072] Starting dynamic subtest: pipe-C
[109.298216] Dynamic subtest pipe-C: SKIP (1.129s)
[109.305172] Starting dynamic subtest: pipe-D
[110.437941] Dynamic subtest pipe-D: SKIP (1.139s)
[110.439380] Subtest plane-downscale-factor-0-25-with-pixel-format: SKIP (4.146s)
[110.524375] [041/130] (895s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[110.735721] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[110.736582] Starting dynamic subtest: pipe-A-DP-4
[110.744782] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[110.751244] Starting dynamic subtest: pipe-B-DP-4
[110.759577] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[110.765885] Starting dynamic subtest: pipe-C-DP-4
[110.773997] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[110.780251] Starting dynamic subtest: pipe-D-DP-4
[110.788806] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[110.790446] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[111.092301] [042/130] (894s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[111.181272] Starting subtest: multi-wait-for-submit-submitted-signaled
[111.182127] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[111.275208] [043/130] (894s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[111.363586] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[111.365421] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[111.524756] [044/130] (894s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[111.611268] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[111.723313] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.107s)
[111.805397] [045/130] (894s left) gem_vm_create (isolation)
[111.901117] Starting subtest: isolation
[111.909132] Subtest isolation: SUCCESS (0.008s)
[112.024977] [046/130] (893s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[112.246194] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (0.000s)
[112.271710] [047/130] (893s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[112.493022] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[112.494643] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[112.742969] [048/130] (893s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[112.874162] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[112.917541] [049/130] (893s left) syncobj_timeline (multi-wait-submitted-signaled)
[113.021209] Starting subtest: multi-wait-submitted-signaled
[113.022350] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[113.158260] [050/130] (892s left) gem_ctx_persistence (processes)
[113.260701] Starting subtest: processes
[115.284327] Subtest processes: SUCCESS (2.023s)
[115.393424] [051/130] (890s left) kms_flip (2x-modeset-vs-vblank-race)
[115.605653] Starting subtest: 2x-modeset-vs-vblank-race
[115.621751] Subtest 2x-modeset-vs-vblank-race: SKIP (0.016s)
[115.691254] [052/130] (890s left) syncobj_timeline (transfer-timeline-point)
[115.794368] Starting subtest: transfer-timeline-point
[115.795359] Subtest transfer-timeline-point: SUCCESS (0.000s)
[115.905249] [053/130] (890s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[116.120510] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[116.121346] Starting dynamic subtest: pipe-A
[116.222033] Dynamic subtest pipe-A: SUCCESS (0.101s)
[116.232140] Starting dynamic subtest: pipe-B
[116.449627] Dynamic subtest pipe-B: SUCCESS (0.227s)
[116.461716] Starting dynamic subtest: pipe-C
[116.676584] Dynamic subtest pipe-C: SUCCESS (0.226s)
[116.689105] Starting dynamic subtest: pipe-D
[116.902805] Dynamic subtest pipe-D: SUCCESS (0.225s)
[116.903839] Subtest planes-downscale-factor-0-5-unity-scaling: SUCCESS (0.783s)
[117.213841] [054/130] (888s left) gem_tiled_wb
[117.360859] [055/130] (888s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[117.583028] Starting subtest: 4-tiled-16bpp-rotate-0
[119.151940] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.568s)
[119.290579] [056/130] (886s left) kms_sequence (get-idle)
[119.513786] Starting subtest: get-idle
[119.515621] Starting dynamic subtest: pipe-A-DP-4
[121.860395] Dynamic subtest pipe-A-DP-4: SUCCESS (2.345s)
[121.867450] Starting dynamic subtest: pipe-B-DP-4
[124.183459] Dynamic subtest pipe-B-DP-4: SUCCESS (2.322s)
[124.193156] Starting dynamic subtest: pipe-C-DP-4
[126.477701] Dynamic subtest pipe-C-DP-4: SUCCESS (2.292s)
[126.490997] Starting dynamic subtest: pipe-D-DP-4
[128.781070] Dynamic subtest pipe-D-DP-4: SUCCESS (2.301s)
[128.781941] Subtest get-idle: SUCCESS (9.266s)
[129.002681] [057/130] (877s left) kms_addfb_basic (unused-offsets)
[129.115275] Starting subtest: unused-offsets
[129.116209] Subtest unused-offsets: SUCCESS (0.000s)
[129.324062] [058/130] (876s left) kms_psr (psr2-sprite-plane-move)
[129.559124] Subtest psr2-sprite-plane-move: SKIP (0.000s)
[129.580196] [059/130] (876s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[129.864948] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[129.982988] [060/130] (876s left) gem_eio (suspend)
[130.315265] Starting subtest: suspend
[143.954144] Subtest suspend: SUCCESS (13.639s)
[147.147521] [061/130] (862s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[147.362520] Starting subtest: nonblocking-crc-frame-sequence
[147.364569] Starting dynamic subtest: pipe-A-DP-4
[148.068982] Dynamic subtest pipe-A-DP-4: SUCCESS (0.705s)
[148.082983] Starting dynamic subtest: pipe-B-DP-4
[148.688020] Dynamic subtest pipe-B-DP-4: SUCCESS (0.617s)
[148.706999] Starting dynamic subtest: pipe-C-DP-4
[149.312455] Dynamic subtest pipe-C-DP-4: SUCCESS (0.621s)
[149.321598] Starting dynamic subtest: pipe-D-DP-4
[149.965141] Dynamic subtest pipe-D-DP-4: SUCCESS (0.651s)
[149.966346] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.603s)
[150.209173] [062/130] (859s left) kms_content_protection (srm)
[150.475911] Starting subtest: srm
[150.500750] Starting dynamic subtest: pipe-A-DP-4
[271.039118] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[271.343880] Closing watchdogs
[271.345048] Initializing watchdogs
[271.345079]   /dev/watchdog0
[271.353998] [063/130] (738s left) gen9_exec_parse (shadow-peek)
[271.441181] Subtest shadow-peek: SKIP (0.000s)
[271.482110] [064/130] (737s left) gem_mmap_gtt (close-race)
[271.567404] Subtest close-race: SKIP (0.000s)
[271.645792] [065/130] (737s left) kms_flip (2x-flip-vs-wf_vblank)
[271.875212] Starting subtest: 2x-flip-vs-wf_vblank
[271.891101] Subtest 2x-flip-vs-wf_vblank: SKIP (0.016s)
[271.953144] [066/130] (737s left) gem_bad_reloc (negative-reloc-lut)
[272.051277] Subtest negative-reloc-lut: SKIP (0.000s)
[272.081973] [067/130] (737s left) gem_exec_reloc (basic-softpin)
[272.179397] Subtest basic-softpin: SKIP (0.000s)
[272.245301] [068/130] (737s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[272.494510] Starting subtest: fbcpsr-rgb101010-draw-render
[272.496280] Subtest fbcpsr-rgb101010-draw-render: SKIP (0.000s)
[272.578116] [069/130] (736s left) gen7_exec_parse (chained-batch)
[272.696441] Subtest chained-batch: SKIP (0.000s)
[272.731747] [070/130] (736s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[272.950114] Starting subtest: blocking-absolute-wf_vblank-interruptible
[272.956444] Starting dynamic subtest: A-DP4
[297.245200] Dynamic subtest A-DP4: SUCCESS (24.288s)
[297.255267] Starting dynamic subtest: B-DP4
[321.192086] Dynamic subtest B-DP4: SUCCESS (23.946s)
[321.200464] Starting dynamic subtest: C-DP4
[345.237140] Dynamic subtest C-DP4: SUCCESS (24.044s)
[345.246327] Starting dynamic subtest: D-DP4
[369.110167] Dynamic subtest D-DP4: SUCCESS (23.872s)
[369.111068] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (96.159s)
[385.785503] [071/130] (623s left) prime_vgem (busy)
[385.949298] Starting subtest: busy
[385.950138] Starting dynamic subtest: rcs0
[385.983910] Dynamic subtest rcs0: SUCCESS (0.034s)
[385.993777] Starting dynamic subtest: bcs0
[386.036355] Dynamic subtest bcs0: SUCCESS (0.052s)
[386.051220] Starting dynamic subtest: vcs0
[386.079569] Dynamic subtest vcs0: SUCCESS (0.042s)
[386.104833] Starting dynamic subtest: vcs1
[386.124158] Dynamic subtest vcs1: SUCCESS (0.043s)
[386.147163] Starting dynamic subtest: vecs0
[386.166838] Dynamic subtest vecs0: SUCCESS (0.042s)
[386.182796] Starting dynamic subtest: vecs1
[386.208706] Dynamic subtest vecs1: SUCCESS (0.041s)
[386.222492] Starting dynamic subtest: ccs0
[386.249624] Dynamic subtest ccs0: SUCCESS (0.040s)
[386.250789] Subtest busy: SUCCESS (0.301s)
[386.397154] [072/130] (623s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[386.617457] Starting subtest: fbcpsr-rgb101010-draw-blt
[386.619253] Subtest fbcpsr-rgb101010-draw-blt: SKIP (0.000s)
[386.713754] [073/130] (622s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[386.958142] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[386.979329] [074/130] (622s left) syncobj_timeline (multi-wait-all-signaled)
[387.067133] Starting subtest: multi-wait-all-signaled
[387.068068] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[387.214437] [075/130] (622s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[387.322247] Starting subtest: etime-multi-wait-all-submitted-signaled
[387.434443] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.108s)
[387.530467] [076/130] (622s left) perf_pmu (init-busy)
[387.610167] Starting subtest: init-busy
[387.611138] Starting dynamic subtest: rcs0
[387.612084] Dynamic subtest rcs0: SUCCESS (0.001s)
[387.620570] Starting dynamic subtest: bcs0
[387.621362] Dynamic subtest bcs0: SUCCESS (0.000s)
[387.629432] Starting dynamic subtest: vcs0
[387.630202] Dynamic subtest vcs0: SUCCESS (0.000s)
[387.638361] Starting dynamic subtest: vcs1
[387.639170] Dynamic subtest vcs1: SUCCESS (0.000s)
[387.647365] Starting dynamic subtest: vecs0
[387.648165] Dynamic subtest vecs0: SUCCESS (0.000s)
[387.656359] Starting dynamic subtest: vecs1
[387.657178] Dynamic subtest vecs1: SUCCESS (0.000s)
[387.665323] Starting dynamic subtest: ccs0
[387.666154] Dynamic subtest ccs0: SUCCESS (0.000s)
[387.667179] Subtest init-busy: SUCCESS (0.006s)
[387.699293] [077/130] (622s left) gem_eio (wait-10ms)
[388.004299] Starting subtest: wait-10ms
[388.588696] Subtest wait-10ms: SUCCESS (0.585s)
[388.785818] [078/130] (620s left) kms_chamelium_color (ctm-green-to-red)
[389.008710] Subtest ctm-green-to-red: SKIP (0.000s)
[389.029112] [079/130] (620s left) gem_tiled_pread_pwrite
[389.167560] [080/130] (620s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[389.540212] Starting subtest: cursor-rapid-movement-256x256
[389.553949] Starting dynamic subtest: pipe-A-DP-4
[389.940804] Dynamic subtest pipe-A-DP-4: SUCCESS (0.387s)
[389.991204] Starting dynamic subtest: pipe-D-DP-4
[390.382452] Dynamic subtest pipe-D-DP-4: SUCCESS (0.398s)
[390.383472] Subtest cursor-rapid-movement-256x256: SUCCESS (0.841s)
[390.713076] [081/130] (619s left) kms_lease (invalid-create-leases)
[390.941768] Starting subtest: invalid-create-leases
[390.947967] Subtest invalid-create-leases: SUCCESS (0.007s)
[391.028724] [082/130] (618s left) kms_invalid_mode (bad-htotal)
[391.264417] Starting subtest: bad-htotal
[391.265328] Starting dynamic subtest: pipe-A-DP-4
[391.348629] Dynamic subtest pipe-A-DP-4: SUCCESS (0.084s)
[391.361371] Starting dynamic subtest: pipe-B-DP-4
[391.362549] Dynamic subtest pipe-B-DP-4: SUCCESS (0.001s)
[391.373570] Starting dynamic subtest: pipe-C-DP-4
[391.374632] Dynamic subtest pipe-C-DP-4: SUCCESS (0.001s)
[391.385631] Starting dynamic subtest: pipe-D-DP-4
[391.386580] Dynamic subtest pipe-D-DP-4: SUCCESS (0.001s)
[391.387575] Subtest bad-htotal: SUCCESS (0.093s)
[391.566361] [083/130] (618s left) kms_psr (fbc-pr-primary-blt)
[391.795039] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[391.825119] [084/130] (618s left) gem_exec_schedule (noreorder-priority)
[392.056160] Starting subtest: noreorder-priority
[392.058129] Starting dynamic subtest: rcs0
[393.187618] Dynamic subtest rcs0: SUCCESS (1.131s)
[393.196351] Starting dynamic subtest: bcs0
[394.327356] Dynamic subtest bcs0: SUCCESS (1.139s)
[394.337428] Starting dynamic subtest: vcs0
[395.451292] Dynamic subtest vcs0: SUCCESS (1.123s)
[395.459908] Starting dynamic subtest: vcs1
[396.580232] Dynamic subtest vcs1: SUCCESS (1.128s)
[396.589177] Starting dynamic subtest: vecs0
[397.699225] Dynamic subtest vecs0: SUCCESS (1.118s)
[397.707760] Starting dynamic subtest: vecs1
[398.844382] Dynamic subtest vecs1: SUCCESS (1.145s)
[398.854730] Starting dynamic subtest: ccs0
[399.944072] Dynamic subtest ccs0: SUCCESS (1.099s)
[399.944908] Subtest noreorder-priority: SUCCESS (7.889s)
[400.202164] [085/130] (609s left) gem_softpin (evict-prime-sanity-check)
[400.290400] Starting subtest: evict-prime-sanity-check
[400.291400] Starting dynamic subtest: rcs0
[401.432179] Dynamic subtest rcs0: SUCCESS (1.142s)
[401.440638] Starting dynamic subtest: bcs0
[402.543174] Dynamic subtest bcs0: SUCCESS (1.111s)
[402.552062] Starting dynamic subtest: vcs0
[403.664368] Dynamic subtest vcs0: SUCCESS (1.121s)
[403.673831] Starting dynamic subtest: vcs1
[404.772307] Dynamic subtest vcs1: SUCCESS (1.107s)
[404.781416] Starting dynamic subtest: vecs0
[405.878399] Dynamic subtest vecs0: SUCCESS (1.106s)
[405.886730] Starting dynamic subtest: vecs1
[406.979208] Dynamic subtest vecs1: SUCCESS (1.100s)
[406.987710] Starting dynamic subtest: ccs0
[408.084220] Dynamic subtest ccs0: SUCCESS (1.105s)
[408.093028] Starting dynamic subtest: all
[409.190163] Dynamic subtest all: SUCCESS (1.106s)
[409.190999] Subtest evict-prime-sanity-check: SUCCESS (8.900s)
[409.248384] [086/130] (600s left) kms_hdr (static-swap)
[409.466364] Starting subtest: static-swap
[412.948525] Starting dynamic subtest: pipe-A-DP-4
[414.184852] Dynamic subtest pipe-A-DP-4: SUCCESS (1.235s)
[414.186351] Subtest static-swap: SUCCESS (4.718s)
[414.508173] [087/130] (595s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-blt)
[414.752931] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-blt
[416.849924] Subtest fbc-1p-offscren-pri-shrfb-draw-blt: SUCCESS (2.098s)
[416.999516] [088/130] (593s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[417.221368] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[418.128790] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.907s)
[418.283789] [089/130] (591s left) perf (gen12-group-concurrent-oa-buffer-read)
[418.463072] Starting subtest: gen12-group-concurrent-oa-buffer-read
[428.501709] Subtest gen12-group-concurrent-oa-buffer-read: SUCCESS (10.038s)
[428.526805] [090/130] (581s left) syncobj_wait (wait-delayed-signal)
[428.611848] Starting subtest: wait-delayed-signal
[428.712927] Subtest wait-delayed-signal: SUCCESS (0.101s)
[428.810247] [091/130] (581s left) dumb_buffer (create-clear)
[428.939243] Starting subtest: create-clear
[463.856341] Subtest create-clear: SUCCESS (34.916s)
[463.928414] [092/130] (546s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[464.151217] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[464.152739] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[464.239999] [093/130] (545s left) kms_pm_rpm (drm-resources-equal)
[466.433360] Starting subtest: drm-resources-equal
[467.960864] Subtest drm-resources-equal: SUCCESS (1.526s)
[468.161384] [094/130] (542s left) i915_pm_rpm (system-suspend)
[470.415391] Starting subtest: system-suspend
[490.219620] Subtest system-suspend: SUCCESS (4.789s)
[492.586253] [095/130] (534s left) i915_query (query-topology-matches-eu-total)
[492.671973] Starting subtest: query-topology-matches-eu-total
[492.672834] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[492.737515] [096/130] (534s left) kms_cursor_crc (cursor-onscreen-32x10)
[493.013230] Starting subtest: cursor-onscreen-32x10
[493.060901] Subtest cursor-onscreen-32x10: SKIP (0.045s)
[493.344325] [097/130] (534s left) gem_exec_endless (dispatch)
[493.453156] Starting subtest: dispatch
[493.454088] Starting dynamic subtest: rcs0
[493.458693] Dynamic subtest rcs0: SUCCESS (0.005s)
[493.469450] Starting dynamic subtest: bcs0
[493.471162] Dynamic subtest bcs0: SUCCESS (0.004s)
[493.482180] Starting dynamic subtest: vcs0
[493.484006] Dynamic subtest vcs0: SUCCESS (0.004s)
[493.494385] Starting dynamic subtest: vcs1
[493.496050] Dynamic subtest vcs1: SUCCESS (0.006s)
[493.505836] Starting dynamic subtest: vecs0
[493.507390] Dynamic subtest vecs0: SUCCESS (0.004s)
[493.516945] Starting dynamic subtest: vecs1
[493.518595] Dynamic subtest vecs1: SUCCESS (0.004s)
[493.528491] Starting dynamic subtest: ccs0
[493.530478] Dynamic subtest ccs0: SUCCESS (0.004s)
[493.531478] Subtest dispatch: SUCCESS (0.036s)
[493.603624] [098/130] (533s left) gem_exec_balancer (parallel-keep-submit-fence)
[493.908651] Starting subtest: parallel-keep-submit-fence
[495.490412] Subtest parallel-keep-submit-fence: SUCCESS (1.582s)
[495.569647] [099/130] (531s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[495.781997] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[495.788143] Starting dynamic subtest: A-DP4
[496.583517] Dynamic subtest A-DP4: SUCCESS (0.793s)
[496.603785] Starting dynamic subtest: B-DP4
[497.388778] Dynamic subtest B-DP4: SUCCESS (0.802s)
[497.412833] Starting dynamic subtest: C-DP4
[498.228388] Dynamic subtest C-DP4: SUCCESS (0.837s)
[498.252202] Starting dynamic subtest: D-DP4
[499.067848] Dynamic subtest D-DP4: SUCCESS (0.839s)
[499.068782] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (3.286s)
[499.355352] [100/130] (528s left) drm_fdinfo (busy)
[499.436200] Starting subtest: busy
[499.437093] Starting dynamic subtest: rcs0
[499.455285] Dynamic subtest rcs0: SKIP (0.018s)
[499.465047] Starting dynamic subtest: bcs0
[499.467029] Dynamic subtest bcs0: SKIP (0.007s)
[499.476484] Starting dynamic subtest: vcs0
[499.478230] Dynamic subtest vcs0: SKIP (0.000s)
[499.487662] Starting dynamic subtest: vcs1
[499.489298] Dynamic subtest vcs1: SKIP (0.000s)
[499.498811] Starting dynamic subtest: vecs0
[499.500663] Dynamic subtest vecs0: SKIP (0.000s)
[499.510108] Starting dynamic subtest: vecs1
[499.512071] Dynamic subtest vecs1: SKIP (0.000s)
[499.521586] Starting dynamic subtest: ccs0
[499.523408] Dynamic subtest ccs0: SKIP (0.000s)
[499.525919] Subtest busy: SKIP (0.030s)
[499.540101] [101/130] (527s left) kms_fence_pin_leak
[499.824337] [102/130] (527s left) kms_big_fb (linear-64bpp-rotate-270)
[500.037271] Starting subtest: linear-64bpp-rotate-270
[500.079516] Subtest linear-64bpp-rotate-270: SKIP (0.042s)
[500.230256] [103/130] (527s left) gem_mmap_offset (isolation)
[500.333086] Starting subtest: isolation
[500.339472] Subtest isolation: SUCCESS (0.005s)
[500.464365] [104/130] (527s left) gem_exec_schedule (reorder-wide)
[500.683554] Subtest reorder-wide: SKIP (0.000s)
[500.870536] [105/130] (526s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[501.131011] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[503.380047] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (2.249s)
[503.498446] [106/130] (524s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[503.712155] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[503.713703] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[503.812434] [107/130] (523s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[503.929126] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[503.957727] [108/130] (523s left) kms_psr (psr-no-drrs)
[504.186535] Subtest psr-no-drrs: SKIP (0.000s)
[504.209408] [109/130] (523s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[504.437646] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[504.439274] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[504.521186] [110/130] (523s left) syncobj_wait (invalid-signal-bad-pad)
[504.631172] Starting subtest: invalid-signal-bad-pad
[504.632093] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[504.702566] [111/130] (523s left) kms_chamelium_hpd (dp-hpd-fast)
[504.989438] Subtest dp-hpd-fast: SKIP (0.000s)
[505.113566] [112/130] (522s left) kms_flip (nonblocking-read)
[505.350515] Starting subtest: nonblocking-read
[505.351442] Subtest nonblocking-read: SUCCESS (0.000s)
[505.448738] [113/130] (522s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[505.710506] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[505.711455] Starting dynamic subtest: pipe-A-DP-4
[505.720209] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[505.730748] Starting dynamic subtest: pipe-B-DP-4
[505.739834] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[505.749928] Starting dynamic subtest: pipe-C-DP-4
[505.758421] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[505.768687] Starting dynamic subtest: pipe-D-DP-4
[505.780589] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[505.782833] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.006s)
[506.137509] [114/130] (521s left) gem_exec_fence (submit)
[506.381657] Starting subtest: submit
[506.384755] Subtest submit: SKIP (0.000s)
[506.959121] [115/130] (520s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[507.187140] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[507.188081] Starting dynamic subtest: pipe-A-DP-4
[507.196972] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[507.207273] Starting dynamic subtest: pipe-B-DP-4
[507.215781] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[507.225902] Starting dynamic subtest: pipe-C-DP-4
[507.234341] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[507.244492] Starting dynamic subtest: pipe-D-DP-4
[507.255229] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[507.257494] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SKIP (0.005s)
[507.643684] [116/130] (520s left) api_intel_allocator (simple-allocator)
[507.742131] Starting subtest: simple-allocator
[507.743132] Starting dynamic subtest: basic
[507.760301] Dynamic subtest basic: SUCCESS (0.018s)
[507.771309] Starting dynamic subtest: parallel-one
[507.952119] Dynamic subtest parallel-one: SUCCESS (0.177s)
[507.962907] Starting dynamic subtest: print
[507.964171] Dynamic subtest print: SUCCESS (0.000s)
[507.974457] Starting dynamic subtest: reuse
[507.975594] Dynamic subtest reuse: SUCCESS (0.000s)
[507.985897] Starting dynamic subtest: reserve
[507.987082] Dynamic subtest reserve: SUCCESS (0.000s)
[507.997127] Starting dynamic subtest: fork-reopen-allocator
[507.998324] Dynamic subtest fork-reopen-allocator: SUCCESS (0.011s)
[507.999524] Subtest simple-allocator: SUCCESS (0.209s)
[508.062412] [117/130] (519s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[508.315053] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[508.316771] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[508.412707] [118/130] (519s left) syncobj_wait (single-wait-all-signaled)
[508.522110] Starting subtest: single-wait-all-signaled
[508.523130] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[508.671054] [119/130] (519s left) gem_pxp (reject-modify-context-protection-off-3)
[511.331210] Subtest reject-modify-context-protection-off-3: SKIP (0.000s)
[511.406388] [120/130] (516s left) gem_exec_whisper (basic-fds-forked-all)
[511.498218] Starting subtest: basic-fds-forked-all
[534.127620] Subtest basic-fds-forked-all: SUCCESS (22.500s)
[534.294004] [121/130] (494s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[534.507562] Starting subtest: crc-primary-basic-yf-tiled-ccs
[534.508568] Starting dynamic subtest: pipe-A-DP-4
[534.517166] Dynamic subtest pipe-A-DP-4: SKIP (0.001s)
[534.527017] Starting dynamic subtest: pipe-B-DP-4
[534.535315] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[534.545053] Starting dynamic subtest: pipe-C-DP-4
[534.552965] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[534.563120] Starting dynamic subtest: pipe-D-DP-4
[534.573869] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[534.575815] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.007s)
[534.957302] [122/130] (493s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[535.055860] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[535.056899] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[535.189267] [123/130] (493s left) gem_exec_fence (syncobj-unused-fence)
[535.734208] Starting subtest: syncobj-unused-fence
[535.755683] Subtest syncobj-unused-fence: SUCCESS (0.022s)
[536.151383] [124/130] (492s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[536.374523] Starting subtest: psr-2p-primscrn-cur-indfb-move
[536.376233] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[536.497199] [125/130] (491s left) gem_create (create-ext-placement-sanity-check)
[536.703721] Starting subtest: create-ext-placement-sanity-check
[536.709855] Subtest create-ext-placement-sanity-check: SUCCESS (0.006s)
[536.830022] [126/130] (491s left) gem_mmap_gtt (basic-small-bo)
[536.934439] Subtest basic-small-bo: SKIP (0.000s)
[536.984584] [127/130] (491s left) kms_pm_backlight (fade-with-dpms)
[537.213017] Subtest fade-with-dpms: SKIP (0.000s)
[537.245409] [128/130] (491s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[537.464847] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[537.466799] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[537.560519] [129/130] (491s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[537.805362] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[537.806358] Starting dynamic subtest: pipe-A-valid-mode
[541.843473] Dynamic subtest pipe-A-valid-mode: SUCCESS (4.036s)
[541.845982] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SUCCESS (4.038s)
[542.091112] [130/130] (486s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[542.348286] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[542.349796] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[542.423885] Closing watchdogs
Done.