[59.357236] Initializing watchdogs
[59.357379]   /dev/watchdog0
[59.373229] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.377747] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.380163] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.383675] [001/132] (960s left) kms_cursor_crc (cursor-random-128x42)
[59.650917] Starting subtest: cursor-random-128x42
[59.702146] Subtest cursor-random-128x42: SKIP (0.049s)
[59.945286] [002/132] (959s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[60.052829] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[66.841192] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (6.769s)
[67.128195] [003/132] (952s left) gem_exec_schedule (preemptive-hang)
[67.362840] Starting subtest: preemptive-hang
[67.365351] Starting dynamic subtest: rcs0
[77.060582] Dynamic subtest rcs0: SUCCESS (9.691s)
[77.071305] Starting dynamic subtest: bcs0
[86.017309] Dynamic subtest bcs0: SUCCESS (8.951s)
[86.027697] Starting dynamic subtest: vcs0
[95.040425] Dynamic subtest vcs0: SUCCESS (9.018s)
[95.050593] Starting dynamic subtest: vcs1
[104.067099] Dynamic subtest vcs1: SUCCESS (9.025s)
[104.077159] Starting dynamic subtest: vecs0
[113.019647] Dynamic subtest vecs0: SUCCESS (8.950s)
[113.029256] Starting dynamic subtest: ccs0
[122.046644] Dynamic subtest ccs0: SUCCESS (9.026s)
[122.048786] Subtest preemptive-hang: SUCCESS (54.662s)
[122.325812] [004/132] (897s left) kms_psr (psr2-primary-mmap-gtt)
[122.421489] Starting subtest: psr2-primary-mmap-gtt
[122.423825] Starting dynamic subtest: eDP-1
[123.871997] Dynamic subtest eDP-1: SKIP (1.445s)
[123.877081] Subtest psr2-primary-mmap-gtt: SKIP (1.446s)
[124.042352] [005/132] (896s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[124.169134] Starting subtest: 2x-long-flip-vs-cursor-legacy
[124.173746] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[124.336980] [006/132] (895s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[124.393943] Starting subtest: etime-multi-wait-all-submitted-signaled
[124.499791] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.105s)
[124.593545] [007/132] (895s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[124.687276] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[131.655094] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.969s)
[131.836192] [008/132] (888s left) perf_pmu (all-busy-check-all)
[131.891748] Starting subtest: all-busy-check-all
[133.913721] Subtest all-busy-check-all: SUCCESS (2.020s)
[134.056747] [009/132] (886s left) gem_exec_balancer (parallel-out-fence)
[134.319866] Starting subtest: parallel-out-fence
[135.386804] Subtest parallel-out-fence: SUCCESS (1.066s)
[135.537243] [010/132] (884s left) gem_bad_reloc (negative-reloc-bltcopy)
[135.602694] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[135.686274] [011/132] (884s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[135.776884] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[135.779295] Starting dynamic subtest: pipe-A-eDP-1
[136.632612] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.850s)
[136.644681] Starting dynamic subtest: pipe-B-eDP-1
[138.166393] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.529s)
[138.176260] Starting dynamic subtest: pipe-C-eDP-1
[139.745957] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.578s)
[139.755714] Starting dynamic subtest: pipe-D-eDP-1
[141.220697] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.475s)
[141.222958] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.435s)
[142.409254] [012/132] (877s left) gem_ctx_freq (sysfs)
[142.477657] Starting subtest: sysfs
[142.480060] Starting dynamic subtest: gt0
[147.998181] Dynamic subtest gt0: SUCCESS (5.520s)
[148.008191] Starting dynamic subtest: gt1
[189.911324] Dynamic subtest gt1: SUCCESS (41.911s)
[189.913989] Subtest sysfs: SUCCESS (47.433s)
[190.072716] [013/132] (830s left) kms_vblank (wait-idle-hang)
[190.174952] Starting subtest: wait-idle-hang
[190.177258] Starting dynamic subtest: pipe-A-eDP-1
[216.674481] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.500s)
[216.690232] Starting dynamic subtest: pipe-D-eDP-1
[241.688297] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.009s)
[241.690674] Subtest wait-idle-hang: SUCCESS (51.512s)
[242.555022] [014/132] (777s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[242.671161] Starting subtest: random-ccs-data-y-tiled-ccs
[242.673853] Starting dynamic subtest: pipe-A-eDP-1
[242.700920] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[242.712592] Starting dynamic subtest: pipe-B-eDP-1
[242.737305] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[242.747436] Starting dynamic subtest: pipe-C-eDP-1
[242.772070] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[242.781611] Starting dynamic subtest: pipe-D-eDP-1
[242.805238] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[242.809579] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.003s)
[244.919297] [015/132] (775s left) syncobj_wait (invalid-signal-zero-handles)
[244.987365] Starting subtest: invalid-signal-zero-handles
[244.989435] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[245.126559] [016/132] (775s left) i915_drm_fdinfo (busy)
[245.164059] Starting subtest: busy
[245.165982] Starting dynamic subtest: rcs0
[245.183944] Dynamic subtest rcs0: SKIP (0.020s)
[245.193620] Starting dynamic subtest: bcs0
[245.200514] Dynamic subtest bcs0: SKIP (0.017s)
[245.208628] Starting dynamic subtest: vcs0
[245.212759] Dynamic subtest vcs0: SKIP (0.000s)
[245.220644] Starting dynamic subtest: vcs1
[245.224599] Dynamic subtest vcs1: SKIP (0.000s)
[245.232489] Starting dynamic subtest: vecs0
[245.236753] Dynamic subtest vecs0: SKIP (0.000s)
[245.244633] Starting dynamic subtest: ccs0
[245.248820] Dynamic subtest ccs0: SKIP (0.000s)
[245.253090] Subtest busy: SKIP (0.040s)
[245.291928] [017/132] (775s left) gem_ctx_param (non-root-set)
[245.360758] Starting subtest: non-root-set
[245.384918] Subtest non-root-set: SUCCESS (0.024s)
[245.501863] [018/132] (775s left) gem_partial_pwrite_pread (reads-display)
[245.553452] Subtest reads-display: SKIP (0.000s)
[245.618173] [019/132] (774s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[245.718164] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[245.730637] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s)
[245.869901] [020/132] (774s left) fbdev (write)
[245.907265] Starting subtest: write
[246.269432] Subtest write: SUCCESS (0.360s)
[246.353740] [021/132] (774s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[246.456061] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[253.608229] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.156s)
[254.814274] [022/132] (765s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[254.921718] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[254.925997] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[255.062417] [023/132] (765s left) gem_exec_reloc (basic-cpu)
[255.123264] Subtest basic-cpu: SKIP (0.000s)
[255.194400] [024/132] (765s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[255.294910] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[255.299447] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[255.426384] [025/132] (765s left) syncobj_wait (invalid-reset-bad-pad)
[255.484065] Starting subtest: invalid-reset-bad-pad
[255.486401] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[255.622005] [026/132] (765s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[255.703962] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[255.706224] Starting dynamic subtest: pipe-A-eDP-1
[255.732520] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[255.742079] Starting dynamic subtest: pipe-B-eDP-1
[255.761034] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[255.769355] Starting dynamic subtest: pipe-C-eDP-1
[255.787039] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[255.795119] Starting dynamic subtest: pipe-D-eDP-1
[255.813834] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[255.817358] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.013s)
[258.051082] [027/132] (762s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[258.157317] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[258.982177] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.826s)
[259.160321] [028/132] (761s left) kms_lease (lease-invalid-crtc)
[259.253967] Starting subtest: lease-invalid-crtc
[259.256294] Starting dynamic subtest: pipe-A-eDP-1
[259.258423] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[259.266997] Starting dynamic subtest: pipe-B-eDP-1
[259.269256] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[259.277439] Starting dynamic subtest: pipe-C-eDP-1
[259.279774] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[259.287976] Starting dynamic subtest: pipe-D-eDP-1
[259.290253] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[259.292416] Subtest lease-invalid-crtc: SUCCESS (0.003s)
[259.349051] [029/132] (761s left) gem_create (create-ext-placement-all)
[259.512667] Starting subtest: create-ext-placement-all
[259.515231] Subtest create-ext-placement-all: SUCCESS (0.000s)
[259.659444] [030/132] (761s left) kms_properties (invalid-properties-legacy)
[259.752196] Starting subtest: invalid-properties-legacy
[259.859463] Subtest invalid-properties-legacy: SUCCESS (0.108s)
[259.952810] [031/132] (761s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[260.058621] Starting subtest: primary-yf-tiled-reflect-x-270
[260.063019] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[260.153065] [032/132] (760s left) gem_pread (snoop)
[260.215600] Subtest snoop: SKIP (0.000s)
[260.284638] [033/132] (760s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[260.388891] Starting subtest: y-tiled-16bpp-rotate-180
[260.393290] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[260.521066] [034/132] (760s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[260.572988] Starting subtest: x-tiled-to-vebox-yf-tiled
[260.577453] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[260.775218] [035/132] (760s left) gem_set_tiling_vs_gtt
[260.908808] [036/132] (760s left) kms_psr (fbc-psr-cursor-blt)
[261.000172] Starting subtest: fbc-psr-cursor-blt
[261.002079] Starting dynamic subtest: eDP-1
[261.006180] Dynamic subtest eDP-1: SKIP (0.000s)
[261.010270] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[261.092562] [037/132] (760s left) perf_pmu (gt-awake)
[261.129625] Starting subtest: gt-awake
[269.913999] Subtest gt-awake: SUCCESS (8.782s)
[270.055307] [038/132] (751s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[270.182034] Starting subtest: flip-vs-cursor-atomic
[277.355345] Subtest flip-vs-cursor-atomic: SUCCESS (7.173s)
[278.185128] [039/132] (743s left) kms_big_fb (x-tiled-addfb-size-overflow)
[278.282172] Starting subtest: x-tiled-addfb-size-overflow
[278.284366] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[278.418615] [040/132] (743s left) gem_eio (wait-10ms)
[278.626383] Starting subtest: wait-10ms
[278.912231] Subtest wait-10ms: SUCCESS (0.288s)
[279.089989] [041/132] (742s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[279.195955] Starting subtest: 4-tiled-16bpp-rotate-0
[280.462245] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.268s)
[280.623136] [042/132] (740s left) syncobj_timeline (reset-multiple-signaled)
[280.690322] Starting subtest: reset-multiple-signaled
[280.692217] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[280.823098] [043/132] (740s left) i915_pm_rc6_residency (rc6-fence)
[280.880527] Starting subtest: rc6-fence
[280.917566] Starting dynamic subtest: gt0
[293.239724] Dynamic subtest gt0: SUCCESS (12.321s)
[293.251693] Starting dynamic subtest: gt1
[308.887179] Dynamic subtest gt1: SUCCESS (15.644s)
[308.889878] Subtest rc6-fence: SUCCESS (28.006s)
[308.985680] [044/132] (712s left) gem_exec_fence (invalid-fence-array)
[309.449082] Starting subtest: invalid-fence-array
[309.454497] Subtest invalid-fence-array: SUCCESS (0.005s)
[311.063928] [FACT gem_exec_fence (invalid-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[311.066876] [045/132] (710s left) perf (polling-parameterized)
[311.211757] Starting subtest: polling-parameterized
[331.247406] Subtest polling-parameterized: SUCCESS (20.034s)
[331.296916] [046/132] (690s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[331.411275] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[338.288302] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.879s)
[338.448384] [047/132] (683s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[338.548269] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[339.227724] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.681s)
[339.413344] [048/132] (682s left) prime_mmap_kms (buffer-sharing)
[339.512239] Starting subtest: buffer-sharing
[339.973872] Subtest buffer-sharing: SUCCESS (0.462s)
[340.853276] [049/132] (680s left) gem_mmap_wc (bad-size)
[340.921155] Subtest bad-size: SKIP (0.000s)
[340.981540] [050/132] (680s left) kms_flip (dpms-vs-vblank-race-interruptible)
[341.075285] Starting subtest: dpms-vs-vblank-race-interruptible
[341.077508] Starting dynamic subtest: A-eDP1
[346.023325] Dynamic subtest A-eDP1: SUCCESS (4.945s)
[346.033492] Starting dynamic subtest: B-eDP1
[350.655432] Dynamic subtest B-eDP1: SUCCESS (4.630s)
[350.665763] Starting dynamic subtest: C-eDP1
[355.259168] Dynamic subtest C-eDP1: SUCCESS (4.603s)
[355.269367] Starting dynamic subtest: D-eDP1
[359.883535] Dynamic subtest D-eDP1: SUCCESS (4.623s)
[359.885873] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (18.808s)
[360.779591] [051/132] (661s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[360.906487] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[361.730531] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.826s)
[361.944352] [052/132] (659s left) i915_query (engine-info)
[361.994332] Starting subtest: engine-info
[361.996692] Subtest engine-info: SUCCESS (0.005s)
[362.155996] [053/132] (659s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[362.254930] Starting subtest: plane-all-modeset-transition-internal-panels
[362.256776] Starting dynamic subtest: pipe-A-eDP-1
[363.596667] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.340s)
[363.874803] Starting dynamic subtest: pipe-B-eDP-1
[364.918350] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.051s)
[365.185997] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.931s)
[366.059188] [054/132] (655s left) gem_reg_read (timestamp-monotonic)
[366.127413] Starting subtest: timestamp-monotonic
[370.562951] Subtest timestamp-monotonic: SUCCESS (4.422s)
[370.650133] [055/132] (651s left) sw_sync (alloc_fence)
[370.690283] Starting subtest: alloc_fence
[370.692140] Subtest alloc_fence: SUCCESS (0.000s)
[370.780325] [056/132] (651s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[370.899084] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[370.901374] Starting dynamic subtest: pipe-A-eDP-1
[370.960138] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[370.971147] Starting dynamic subtest: pipe-B-eDP-1
[371.029072] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[371.040070] Starting dynamic subtest: pipe-C-eDP-1
[371.096821] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[371.107549] Starting dynamic subtest: pipe-D-eDP-1
[371.166099] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[371.170337] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[373.128114] [057/132] (649s left) gem_mmap_gtt (basic-small-bo)
[373.199770] Subtest basic-small-bo: SKIP (0.000s)
[373.271292] [058/132] (648s left) template (b)
[373.337332] Starting subtest: B
[373.339310] Subtest B: SUCCESS (0.000s)
[373.466161] [059/132] (648s left) kms_pm_backlight (fade)
[373.560028] Starting subtest: fade
[373.619052] Starting dynamic subtest: eDP-1
[376.299308] Dynamic subtest eDP-1: SUCCESS (2.678s)
[376.301603] Subtest fade: SUCCESS (2.739s)
[377.161173] [060/132] (645s left) gem_exec_reloc (basic-wc-read)
[377.224715] Subtest basic-wc-read: SKIP (0.000s)
[377.295016] [061/132] (645s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[377.398249] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[384.069601] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.672s)
[384.219093] [062/132] (638s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[384.325973] Starting subtest: plane-ctm3x4-lut1d
[384.338842] Starting dynamic subtest: pipe-A-eDP-1
[385.064729] Dynamic subtest pipe-A-eDP-1: FAIL (0.334s)
[385.725409] Starting dynamic subtest: pipe-B-eDP-1
[386.562042] Dynamic subtest pipe-B-eDP-1: FAIL (0.313s)
[387.159441] Starting dynamic subtest: pipe-C-eDP-1
[387.975567] Dynamic subtest pipe-C-eDP-1: FAIL (0.246s)
[388.496068] Starting dynamic subtest: pipe-D-eDP-1
[389.329308] Dynamic subtest pipe-D-eDP-1: FAIL (0.258s)
[389.331645] Subtest plane-ctm3x4-lut1d: FAIL (4.718s)
[389.889184] [063/132] (632s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[389.988938] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[389.991149] Starting dynamic subtest: pipe-A-default-mode
[392.732160] Dynamic subtest pipe-A-default-mode: SUCCESS (2.743s)
[392.734457] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.745s)
[393.594856] [064/132] (628s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[393.703063] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[393.707264] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[393.840756] [065/132] (628s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[396.065152] Starting subtest: dpms-mode-unset-lpsp
[400.137689] Subtest dpms-mode-unset-lpsp: SUCCESS (4.071s)
[400.537236] [066/132] (621s left) gem_lmem_swapping (verify)
[400.578294] Subtest verify: SKIP (0.000s)
[400.620814] [067/132] (621s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[400.679470] Starting subtest: etime-single-wait-for-submit-submitted
[400.788229] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.108s)
[400.888159] [068/132] (621s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[405.868662] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[405.870893] Starting dynamic subtest: pipe-A-eDP-1
[406.673063] Dynamic subtest pipe-A-eDP-1: SKIP (0.802s)
[406.683225] Starting dynamic subtest: pipe-B-eDP-1
[407.798579] Dynamic subtest pipe-B-eDP-1: SKIP (1.122s)
[407.803296] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.929s)
[408.998946] [069/132] (613s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[409.096051] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[409.098343] Starting dynamic subtest: pipe-A-eDP-1
[409.119048] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[409.129391] Starting dynamic subtest: pipe-B-eDP-1
[409.149033] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[409.158077] Starting dynamic subtest: pipe-C-eDP-1
[409.177752] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[409.186920] Starting dynamic subtest: pipe-D-eDP-1
[409.206752] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[409.208989] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[411.371104] [070/132] (611s left) api_intel_bb (render-ccs)
[411.430243] Starting subtest: render-ccs
[411.789811] Subtest render-ccs: SUCCESS (0.360s)
[411.909679] [071/132] (610s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[416.902758] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[416.905172] Starting dynamic subtest: pipe-A-eDP-1
[434.084324] Dynamic subtest pipe-A-eDP-1: SUCCESS (17.178s)
[434.094069] Starting dynamic subtest: pipe-B-eDP-1
[451.230616] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.143s)
[451.233047] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (34.326s)
[452.053078] [072/132] (570s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[452.159354] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[456.594063] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.438s)
[457.745446] [073/132] (565s left) kms_selftest (drm_dp_mst_helper)
[457.784070] Starting subtest: drm_dp_mst_helper
[457.809773] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[457.938444] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.128s)
[457.952640] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[458.079549] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.142s)
[458.089499] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[458.192067] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.111s)
[458.194238] Subtest drm_dp_mst_helper: SUCCESS (0.408s)
[458.424272] [074/132] (564s left) kms_psr (fbc-pr-no-drrs)
[458.532969] Starting subtest: fbc-pr-no-drrs
[458.537318] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[458.629159] [075/132] (564s left) gem_exec_balancer (fairslice)
[458.695418] Starting subtest: fairslice
[461.598230] Subtest fairslice: SUCCESS (2.901s)
[461.949455] [076/132] (561s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[461.997503] Subtest tiled-to-tiled: SKIP (0.000s)
[462.074430] [077/132] (560s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[462.181778] Starting subtest: plane-all-modeset-transition-fencing
[462.186121] Subtest plane-all-modeset-transition-fencing: SKIP (0.000s)
[462.278952] [078/132] (560s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[462.337036] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[462.339292] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[462.482252] [079/132] (560s left) kms_vblank (query-busy-hang)
[462.584800] Starting subtest: query-busy-hang
[462.586633] Starting dynamic subtest: pipe-A-eDP-1
[489.694431] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.106s)
[489.709033] Starting dynamic subtest: pipe-D-eDP-1
[504.679345] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.982s)
[504.681562] Subtest query-busy-hang: SUCCESS (42.093s)
[505.496701] [080/132] (517s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[505.612633] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[509.989564] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.380s)
[511.208467] [081/132] (511s left) syncobj_timeline (wait-all-delayed-signal)
[511.264609] Starting subtest: wait-all-delayed-signal
[511.367473] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[511.472465] [082/132] (511s left) kms_flip (flip-vs-absolute-wf_vblank)
[511.570645] Starting subtest: flip-vs-absolute-wf_vblank
[511.572606] Starting dynamic subtest: A-eDP1
[520.502325] Dynamic subtest A-eDP1: SUCCESS (8.934s)
[520.514510] Starting dynamic subtest: B-eDP1
[529.430860] Dynamic subtest B-eDP1: SUCCESS (8.925s)
[529.442327] Starting dynamic subtest: C-eDP1
[538.346351] Dynamic subtest C-eDP1: SUCCESS (8.914s)
[538.357196] Starting dynamic subtest: D-eDP1
[547.266506] Dynamic subtest D-eDP1: SUCCESS (8.918s)
[547.268850] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.701s)
[548.510070] [083/132] (474s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[548.640907] Starting subtest: short-flip-before-cursor-atomic-transitions
[549.584416] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.945s)
[550.439947] [084/132] (472s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[550.504760] Starting subtest: yf-tiled-to-vebox-yf-tiled
[550.508707] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[550.709763] [085/132] (472s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[550.819678] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[550.824307] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[550.931894] [086/132] (472s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[551.025222] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[551.027520] Starting dynamic subtest: pipe-A
[551.387373] Dynamic subtest pipe-A: SUCCESS (0.364s)
[551.405027] Starting dynamic subtest: pipe-B
[552.866726] Dynamic subtest pipe-B: SUCCESS (1.478s)
[552.876868] Starting dynamic subtest: pipe-C
[554.325485] Dynamic subtest pipe-C: SUCCESS (1.458s)
[554.335625] Starting dynamic subtest: pipe-D
[555.822446] Dynamic subtest pipe-D: SUCCESS (1.496s)
[555.824700] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.800s)
[556.987128] [087/132] (466s left) api_intel_allocator (open-vm)
[557.043590] Starting subtest: open-vm
[557.045830] Subtest open-vm: SUCCESS (0.003s)
[557.202639] [088/132] (466s left) gem_exec_whisper (basic-contexts-priority-all)
[557.270749] Starting subtest: basic-contexts-priority-all
[581.685527] Subtest basic-contexts-priority-all: SUCCESS (24.410s)
[581.897283] [089/132] (441s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[582.012286] Starting subtest: cursor-tearing-framebuffer-change
[582.014186] Starting dynamic subtest: pipe-A-eDP-1
[582.587511] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.576s)
[582.597632] Starting dynamic subtest: pipe-D-eDP-1
[583.791894] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.204s)
[583.794737] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.782s)
[584.651799] [090/132] (438s left) kms_psr (psr2-sprite-mmap-cpu)
[584.738089] Starting subtest: psr2-sprite-mmap-cpu
[584.740255] Starting dynamic subtest: eDP-1
[586.424271] Dynamic subtest eDP-1: SUCCESS (1.686s)
[586.426418] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.688s)
[587.324241] [091/132] (436s left) gem_tiled_wc
[587.461262] [092/132] (436s left) vgem_basic (second-client)
[587.508100] Starting subtest: second-client
[587.530058] Subtest second-client: SUCCESS (0.022s)
[587.615935] [093/132] (436s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[587.715758] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[591.728658] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (4.017s)
[592.932347] [094/132] (430s left) prime_vgem (shrink)
[592.993533] Starting subtest: shrink
[593.034022] Subtest shrink: SUCCESS (0.038s)
[593.181922] [095/132] (430s left) sysfs_preempt_timeout (invalid)
[593.240776] Starting subtest: invalid
[593.243189] Starting dynamic subtest: vecs0
[593.245573] Dynamic subtest vecs0: SUCCESS (0.000s)
[593.256411] Starting dynamic subtest: ccs0
[593.258352] Dynamic subtest ccs0: SUCCESS (0.000s)
[593.267523] Starting dynamic subtest: vcs0
[593.269476] Dynamic subtest vcs0: SUCCESS (0.000s)
[593.278600] Starting dynamic subtest: bcs0
[593.280472] Dynamic subtest bcs0: SUCCESS (0.000s)
[593.289513] Starting dynamic subtest: rcs0
[593.291424] Dynamic subtest rcs0: SUCCESS (0.000s)
[593.300330] Starting dynamic subtest: vcs1
[593.302267] Dynamic subtest vcs1: SUCCESS (0.000s)
[593.304137] Subtest invalid: SUCCESS (0.009s)
[593.399028] [096/132] (430s left) gem_ctx_persistence (heartbeat-stop)
[593.465479] Starting subtest: heartbeat-stop
[593.470020] Subtest heartbeat-stop: SKIP (0.000s)
[593.606131] [097/132] (430s left) kms_joiner (basic-force-big-joiner)
[593.710753] Starting subtest: basic-force-big-joiner
[593.712992] Starting dynamic subtest: single
[597.030046] Dynamic subtest single: SUCCESS (3.328s)
[597.032247] Subtest basic-force-big-joiner: SUCCESS (3.328s)
[598.232688] [098/132] (425s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[598.296290] Starting subtest: multi-wait-available-submitted-signaled
[598.298803] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[598.413298] [099/132] (425s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[598.536384] Starting subtest: cursorA-vs-flipB-legacy
[598.540991] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[598.713826] [100/132] (425s left) gem_userptr_blits (probe)
[598.787974] Starting subtest: probe
[598.863831] Subtest probe: SUCCESS (0.075s)
[599.000099] [101/132] (425s left) kms_properties (colorop-properties-atomic)
[599.098588] Starting subtest: colorop-properties-atomic
[599.100797] Starting dynamic subtest: pipe-A-eDP-1
[599.441148] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.340s)
[599.457039] Starting dynamic subtest: pipe-B-eDP-1
[600.471694] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.029s)
[600.484333] Starting dynamic subtest: pipe-C-eDP-1
[601.502027] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.031s)
[601.515404] Starting dynamic subtest: pipe-D-eDP-1
[602.533251] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.030s)
[602.535718] Subtest colorop-properties-atomic: SUCCESS (3.437s)
[603.364174] [102/132] (420s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[603.462330] Starting subtest: addfb25-y-tiled-legacy
[603.464586] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[603.599426] [103/132] (420s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[603.689136] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[603.691325] Starting dynamic subtest: pipe-A-default-mode
[603.976323] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[603.980702] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.287s)
[604.794890] [104/132] (419s left) kms_plane_multiple (tiling-y)
[604.889408] Starting subtest: tiling-y
[604.893648] Subtest tiling-y: SKIP (0.000s)
[604.985191] [105/132] (419s left) gem_softpin (evict-prime)
[605.041605] Starting subtest: evict-prime
[605.043763] Starting dynamic subtest: rcs0
[606.235898] Dynamic subtest rcs0: SUCCESS (1.194s)
[606.249149] Starting dynamic subtest: bcs0
[607.386898] Dynamic subtest bcs0: SUCCESS (1.149s)
[607.400573] Starting dynamic subtest: vcs0
[608.887031] Dynamic subtest vcs0: SUCCESS (1.496s)
[608.899708] Starting dynamic subtest: vcs1
[610.929039] Dynamic subtest vcs1: SUCCESS (2.038s)
[610.941327] Starting dynamic subtest: vecs0
[612.926477] Dynamic subtest vecs0: SUCCESS (1.996s)
[612.939164] Starting dynamic subtest: ccs0
[614.111283] Dynamic subtest ccs0: SUCCESS (1.183s)
[614.124064] Starting dynamic subtest: all
[615.925227] Dynamic subtest all: SUCCESS (1.811s)
[615.927901] Subtest evict-prime: SUCCESS (10.883s)
[616.036254] [106/132] (408s left) gem_mmap_offset (close-race)
[616.109542] Starting subtest: close-race
[636.219374] Subtest close-race: SUCCESS (20.107s)
[636.393097] [107/132] (387s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[636.511309] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[636.515585] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[636.629485] [108/132] (387s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[636.732699] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[643.786550] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.056s)
[643.959099] [109/132] (380s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[644.051533] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[651.726663] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.675s)
[651.889193] [110/132] (372s left) kms_chamelium_frames (dp-frame-dump)
[652.280478] Subtest dp-frame-dump: SKIP (0.000s)
[653.119053] [111/132] (371s left) kms_content_protection (dp-mst-lic-type-0)
[653.259585] Starting subtest: dp-mst-lic-type-0
[653.264020] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[653.421030] [112/132] (371s left) gen9_exec_parse (bb-chained)
[653.482263] Subtest bb-chained: SKIP (0.000s)
[653.544724] [113/132] (370s left) gem_lmem_swapping (heavy-verify-random)
[653.587198] Subtest heavy-verify-random: SKIP (0.000s)
[653.630800] [114/132] (370s left) kms_pipe_crc_basic (suspend-read-crc)
[653.728081] Starting subtest: suspend-read-crc
[653.730301] Starting dynamic subtest: pipe-A-eDP-1
[671.007993] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.629s)
[671.020195] Starting dynamic subtest: pipe-B-eDP-1
[689.055277] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.210s)
[689.065949] Starting dynamic subtest: pipe-C-eDP-1
[707.013103] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.007s)
[707.025819] Starting dynamic subtest: pipe-D-eDP-1
[725.015765] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.089s)
[725.018241] Subtest suspend-read-crc: SUCCESS (11.942s)
[725.894156] [115/132] (298s left) gem_exec_schedule (submit-late-slice)
[725.978776] Starting subtest: submit-late-slice
[725.980745] Starting dynamic subtest: rcs0
[726.010555] Dynamic subtest rcs0: SUCCESS (0.039s)
[726.021473] Starting dynamic subtest: bcs0
[726.051880] Dynamic subtest bcs0: SUCCESS (0.040s)
[726.063309] Starting dynamic subtest: vcs0
[726.082574] Dynamic subtest vcs0: SUCCESS (0.029s)
[726.093241] Starting dynamic subtest: vcs1
[726.111798] Dynamic subtest vcs1: SUCCESS (0.028s)
[726.122690] Starting dynamic subtest: vecs0
[726.145288] Dynamic subtest vecs0: SUCCESS (0.032s)
[726.156735] Starting dynamic subtest: ccs0
[726.175916] Dynamic subtest ccs0: SUCCESS (0.029s)
[726.178105] Subtest submit-late-slice: SUCCESS (0.202s)
[728.144133] [116/132] (296s left) kms_cursor_crc (cursor-sliding-256x256)
[728.396125] Starting subtest: cursor-sliding-256x256
[728.401383] Starting dynamic subtest: pipe-A-eDP-1
[731.738368] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.301s)
[731.776209] Starting dynamic subtest: pipe-D-eDP-1
[735.636165] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.845s)
[735.638583] Subtest cursor-sliding-256x256: SUCCESS (7.174s)
[736.472081] [117/132] (288s left) kms_dp_aux_dev
[736.840353] [118/132] (287s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[736.900245] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[736.968864] [119/132] (287s left) kms_feature_discovery (display-1x)
[737.064574] Starting subtest: display-1x
[737.066446] Subtest display-1x: SUCCESS (0.000s)
[737.167282] [120/132] (287s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[737.267346] Starting subtest: fbc-psr-sprite-mmap-cpu
[737.269696] Starting dynamic subtest: eDP-1
[737.274071] Dynamic subtest eDP-1: SKIP (0.000s)
[737.278460] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[737.367431] [121/132] (287s left) gem_exec_params (secure-non-root)
[737.436504] Starting subtest: secure-non-root
[737.441316] Subtest secure-non-root: SKIP (0.000s)
[737.581650] [122/132] (287s left) gem_exec_alignment (many)
[737.649909] Starting subtest: many
[762.019609] Subtest many: SUCCESS (24.338s)
[763.063859] [123/132] (261s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[763.502410] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[764.278171] [124/132] (260s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[764.398921] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[764.403219] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[764.541322] [125/132] (260s left) gem_mmap (basic-small-bo)
[764.611987] Subtest basic-small-bo: SKIP (0.000s)
[764.686550] [126/132] (260s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[764.789141] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[764.793052] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[764.900448] [127/132] (260s left) dumb_buffer (invalid-bpp)
[764.960948] Starting subtest: invalid-bpp
[764.962889] Subtest invalid-bpp: SUCCESS (0.000s)
[765.095878] [128/132] (260s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[765.198811] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[765.203114] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[765.350366] [129/132] (259s left) i915_getparams_basic (basic-eu-total)
[765.410752] Starting subtest: basic-eu-total
[765.414402] Subtest basic-eu-total: SUCCESS (0.000s)
[765.534619] [130/132] (259s left) gem_exec_parallel (userptr)
[765.607444] Starting subtest: userptr
[765.609473] Starting dynamic subtest: rcs0
[767.077999] Dynamic subtest rcs0: SUCCESS (1.470s)
[767.091777] Starting dynamic subtest: bcs0
[768.614210] Dynamic subtest bcs0: SUCCESS (1.533s)
[768.628618] Starting dynamic subtest: vcs0
[770.043315] Dynamic subtest vcs0: SUCCESS (1.427s)
[770.055157] Starting dynamic subtest: vcs1
[771.983378] Dynamic subtest vcs1: SUCCESS (1.937s)
[771.997372] Starting dynamic subtest: vecs0
[774.031242] Dynamic subtest vecs0: SUCCESS (2.045s)
[774.049005] Starting dynamic subtest: ccs0
[775.564540] Dynamic subtest ccs0: SUCCESS (1.532s)
[775.567254] Subtest userptr: SUCCESS (9.956s)
[775.777098] [131/132] (249s left) kms_flip (2x-plain-flip-ts-check)
[775.881349] Starting subtest: 2x-plain-flip-ts-check
[775.890172] Subtest 2x-plain-flip-ts-check: SKIP (0.011s)
[776.021947] [132/132] (249s left) kms_busy (extended-modeset-hang-oldfb)
[776.122783] Starting subtest: extended-modeset-hang-oldfb
[776.124772] Starting dynamic subtest: pipe-A
[787.665493] Dynamic subtest pipe-A: SUCCESS (11.538s)
[787.677563] Starting dynamic subtest: pipe-D
[799.939473] Dynamic subtest pipe-D: SUCCESS (12.271s)
[799.941676] Subtest extended-modeset-hang-oldfb: SUCCESS (23.814s)
[800.847223] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_exec_schedule
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: perf_pmu
results: parsing output: 8/ for test: gem_exec_balancer
results: parsing output: 9/ for test: gem_bad_reloc
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: gem_ctx_freq
results: parsing output: 12/ for test: kms_vblank
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: syncobj_wait
results: parsing output: 15/ for test: i915_drm_fdinfo
results: parsing output: 16/ for test: gem_ctx_param
results: parsing output: 17/ for test: gem_partial_pwrite_pread
results: parsing output: 18/ for test: kms_flip
results: parsing output: 19/ for test: fbdev
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_exec_reloc
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: syncobj_wait
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: kms_lease
results: parsing output: 28/ for test: gem_create
results: parsing output: 29/ for test: kms_properties
results: parsing output: 30/ for test: kms_rotation_crc
results: parsing output: 31/ for test: gem_pread
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: gem_render_copy
results: parsing output: 34/ for test: gem_set_tiling_vs_gtt
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: perf_pmu
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: gem_eio
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: i915_pm_rc6_residency
results: parsing output: 43/ for test: gem_exec_fence
results: parsing output: 44/ for test: perf
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: prime_mmap_kms
results: parsing output: 48/ for test: gem_mmap_wc
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: i915_query
results: parsing output: 52/ for test: kms_atomic_transition
results: parsing output: 53/ for test: gem_reg_read
results: parsing output: 54/ for test: sw_sync
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: gem_mmap_gtt
results: parsing output: 57/ for test: template
results: parsing output: 58/ for test: kms_pm_backlight
results: parsing output: 59/ for test: gem_exec_reloc
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_color_pipeline
results: parsing output: 62/ for test: kms_flip_scaled_crc
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_pm_rpm
results: parsing output: 65/ for test: gem_lmem_swapping
results: parsing output: 66/ for test: syncobj_timeline
results: parsing output: 67/ for test: kms_psr2_sf
results: parsing output: 68/ for test: kms_ccs
results: parsing output: 69/ for test: api_intel_bb
results: parsing output: 70/ for test: kms_psr2_sf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_selftest
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: gem_exec_balancer
results: parsing output: 75/ for test: gem_set_tiling_vs_blt
results: parsing output: 76/ for test: kms_atomic_transition
results: parsing output: 77/ for test: syncobj_wait
results: parsing output: 78/ for test: kms_vblank
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: kms_flip
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: gem_render_copy
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_plane_scaling
results: parsing output: 86/ for test: api_intel_allocator
results: parsing output: 87/ for test: gem_exec_whisper
results: parsing output: 88/ for test: kms_cursor_crc
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: gem_tiled_wc
results: parsing output: 91/ for test: vgem_basic
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: prime_vgem
results: parsing output: 94/ for test: sysfs_preempt_timeout
results: parsing output: 95/ for test: gem_ctx_persistence
results: parsing output: 96/ for test: kms_joiner
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: gem_userptr_blits
results: parsing output: 100/ for test: kms_properties
results: parsing output: 101/ for test: kms_addfb_basic
results: parsing output: 102/ for test: kms_flip_scaled_crc
results: parsing output: 103/ for test: kms_plane_multiple
results: parsing output: 104/ for test: gem_softpin
results: parsing output: 105/ for test: gem_mmap_offset
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_chamelium_frames
results: parsing output: 110/ for test: kms_content_protection
results: parsing output: 111/ for test: gen9_exec_parse
results: parsing output: 112/ for test: gem_lmem_swapping
results: parsing output: 113/ for test: kms_pipe_crc_basic
results: parsing output: 114/ for test: gem_exec_schedule
results: parsing output: 115/ for test: kms_cursor_crc
results: parsing output: 116/ for test: kms_dp_aux_dev
results: parsing output: 117/ for test: gem_mmap_wc
results: parsing output: 118/ for test: kms_feature_discovery
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: gem_exec_params
results: parsing output: 121/ for test: gem_exec_alignment
results: parsing output: 122/ for test: kms_chamelium_hpd
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_mmap
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: dumb_buffer
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: i915_getparams_basic
results: parsing output: 129/ for test: gem_exec_parallel
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: kms_busy
Done.