[58.449080] Initializing watchdogs
[58.449129]   /dev/watchdog0
[58.464043] [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]
[58.468461] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.470643] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.472639] [001/132] (960s left) kms_cursor_crc (cursor-random-128x42)
[58.698892] Starting subtest: cursor-random-128x42
[58.746493] Subtest cursor-random-128x42: SKIP (0.046s)
[58.988482] [002/132] (960s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[59.070384] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[65.751640] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (6.683s)
[65.910398] [003/132] (953s left) gem_exec_schedule (preemptive-hang)
[66.095526] Starting subtest: preemptive-hang
[66.097341] Starting dynamic subtest: rcs0
[75.082734] Dynamic subtest rcs0: SUCCESS (8.989s)
[75.090362] Starting dynamic subtest: bcs0
[84.109208] Dynamic subtest bcs0: SUCCESS (9.020s)
[84.118425] Starting dynamic subtest: vcs0
[93.054092] Dynamic subtest vcs0: SUCCESS (8.945s)
[93.070179] Starting dynamic subtest: vcs1
[102.082799] Dynamic subtest vcs1: SUCCESS (9.027s)
[102.091654] Starting dynamic subtest: vecs0
[111.076030] Dynamic subtest vecs0: SUCCESS (8.994s)
[111.084696] Starting dynamic subtest: ccs0
[120.014378] Dynamic subtest ccs0: SUCCESS (8.937s)
[120.015470] Subtest preemptive-hang: SUCCESS (53.916s)
[120.297251] [004/132] (898s left) kms_psr (psr2-primary-mmap-gtt)
[120.399051] Starting subtest: psr2-primary-mmap-gtt
[120.400022] Starting dynamic subtest: eDP-1
[121.851520] Dynamic subtest eDP-1: SKIP (1.449s)
[121.854342] Subtest psr2-primary-mmap-gtt: SKIP (1.450s)
[121.996195] [005/132] (897s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[122.109338] Starting subtest: 2x-long-flip-vs-cursor-legacy
[122.111921] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[122.272013] [006/132] (896s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[122.328695] Starting subtest: etime-multi-wait-all-submitted-signaled
[122.434479] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.103s)
[122.525499] [007/132] (896s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[122.614877] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[129.185829] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.572s)
[129.361142] [008/132] (889s left) perf_pmu (all-busy-check-all)
[129.396380] Starting subtest: all-busy-check-all
[130.901550] Subtest all-busy-check-all: SUCCESS (1.504s)
[131.033013] [009/132] (888s left) gem_exec_balancer (parallel-out-fence)
[131.289955] Starting subtest: parallel-out-fence
[132.308947] Subtest parallel-out-fence: SUCCESS (1.019s)
[132.455978] [010/132] (886s left) gem_bad_reloc (negative-reloc-bltcopy)
[132.510388] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[132.579506] [011/132] (886s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[132.676745] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[132.677678] Starting dynamic subtest: pipe-A-eDP-1
[133.461432] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.781s)
[133.468254] Starting dynamic subtest: pipe-B-eDP-1
[135.007988] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.546s)
[135.014490] Starting dynamic subtest: pipe-C-eDP-1
[136.499392] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.490s)
[136.505977] Starting dynamic subtest: pipe-D-eDP-1
[137.943096] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.443s)
[137.943956] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.263s)
[139.136178] [012/132] (880s left) gem_ctx_freq (sysfs)
[139.202877] Starting subtest: sysfs
[139.204119] Starting dynamic subtest: gt0
[144.740043] Dynamic subtest gt0: SUCCESS (5.537s)
[144.748999] Starting dynamic subtest: gt1
[183.893902] Dynamic subtest gt1: SUCCESS (39.152s)
[183.895351] Subtest sysfs: SUCCESS (44.691s)
[184.028114] [013/132] (835s left) kms_vblank (wait-idle-hang)
[184.120835] Starting subtest: wait-idle-hang
[184.122753] Starting dynamic subtest: pipe-A-eDP-1
[211.669889] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.548s)
[211.678881] Starting dynamic subtest: pipe-D-eDP-1
[226.709602] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.036s)
[226.710566] Subtest wait-idle-hang: SUCCESS (42.588s)
[227.589212] [014/132] (791s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[227.690435] Starting subtest: random-ccs-data-y-tiled-ccs
[227.691447] Starting dynamic subtest: pipe-A-eDP-1
[227.701049] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[227.708115] Starting dynamic subtest: pipe-B-eDP-1
[227.717661] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[227.723866] Starting dynamic subtest: pipe-C-eDP-1
[227.733048] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[227.739168] Starting dynamic subtest: pipe-D-eDP-1
[227.748552] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[227.750222] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.003s)
[229.867917] [015/132] (789s left) syncobj_wait (invalid-signal-zero-handles)
[229.922279] Starting subtest: invalid-signal-zero-handles
[229.923259] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[230.059077] [016/132] (789s left) i915_drm_fdinfo (busy)
[230.087592] Starting subtest: busy
[230.088628] Starting dynamic subtest: rcs0
[230.127273] Dynamic subtest rcs0: SKIP (0.039s)
[230.136316] Starting dynamic subtest: bcs0
[230.137984] Dynamic subtest bcs0: SKIP (0.000s)
[230.144286] Starting dynamic subtest: vcs0
[230.145992] Dynamic subtest vcs0: SKIP (0.001s)
[230.152284] Starting dynamic subtest: vcs1
[230.153907] Dynamic subtest vcs1: SKIP (0.001s)
[230.160058] Starting dynamic subtest: vecs0
[230.161759] Dynamic subtest vecs0: SKIP (0.000s)
[230.168148] Starting dynamic subtest: ccs0
[230.169844] Dynamic subtest ccs0: SKIP (0.000s)
[230.171575] Subtest busy: SKIP (0.045s)
[230.217349] [017/132] (789s left) gem_ctx_param (non-root-set)
[230.272426] Starting subtest: non-root-set
[230.302249] Subtest non-root-set: SUCCESS (0.030s)
[230.408500] [018/132] (789s left) gem_partial_pwrite_pread (reads-display)
[230.452870] Subtest reads-display: SKIP (0.000s)
[230.537776] [019/132] (788s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[230.630626] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[230.642659] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s)
[230.737817] [020/132] (788s left) fbdev (write)
[230.773284] Starting subtest: write
[231.134814] Subtest write: SUCCESS (0.360s)
[231.212266] [021/132] (788s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[231.308888] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[238.341405] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.033s)
[239.487460] [022/132] (780s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[239.579326] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[239.581086] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[239.702285] [023/132] (779s left) gem_exec_reloc (basic-cpu)
[239.760083] Subtest basic-cpu: SKIP (0.000s)
[239.840690] [024/132] (779s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[239.927751] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[239.929594] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[240.060867] [025/132] (779s left) syncobj_wait (invalid-reset-bad-pad)
[240.106476] Starting subtest: invalid-reset-bad-pad
[240.107612] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[240.231690] [026/132] (779s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[240.329440] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[240.330390] Starting dynamic subtest: pipe-A-eDP-1
[240.338888] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[240.348953] Starting dynamic subtest: pipe-B-eDP-1
[240.358055] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[240.366049] Starting dynamic subtest: pipe-C-eDP-1
[240.374259] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[240.380317] Starting dynamic subtest: pipe-D-eDP-1
[240.388648] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[240.390227] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[242.557404] [027/132] (777s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[242.661327] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[243.453426] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.792s)
[243.661840] [028/132] (776s left) kms_lease (lease-invalid-crtc)
[243.753891] Starting subtest: lease-invalid-crtc
[243.754784] Starting dynamic subtest: pipe-A-eDP-1
[243.755654] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[243.762546] Starting dynamic subtest: pipe-B-eDP-1
[243.763395] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[243.769876] Starting dynamic subtest: pipe-C-eDP-1
[243.770708] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[243.777174] Starting dynamic subtest: pipe-D-eDP-1
[243.778014] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[243.778900] Subtest lease-invalid-crtc: SUCCESS (0.003s)
[243.844641] [029/132] (775s left) gem_create (create-ext-placement-all)
[244.006429] Starting subtest: create-ext-placement-all
[244.007652] Subtest create-ext-placement-all: SUCCESS (0.000s)
[244.109721] [030/132] (775s left) kms_properties (invalid-properties-legacy)
[244.190324] Starting subtest: invalid-properties-legacy
[244.298066] Subtest invalid-properties-legacy: SUCCESS (0.106s)
[244.386370] [031/132] (775s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[244.473031] Starting subtest: primary-yf-tiled-reflect-x-270
[244.474753] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[244.563947] [032/132] (775s left) gem_pread (snoop)
[244.620424] Subtest snoop: SKIP (0.000s)
[244.686011] [033/132] (775s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[244.782501] Starting subtest: y-tiled-16bpp-rotate-180
[244.784297] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[244.889537] [034/132] (775s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[244.950836] Starting subtest: x-tiled-to-vebox-yf-tiled
[244.952947] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[245.147482] [035/132] (774s left) gem_set_tiling_vs_gtt
[245.258717] [036/132] (774s left) kms_psr (fbc-psr-cursor-blt)
[245.342239] Starting subtest: fbc-psr-cursor-blt
[245.343259] Starting dynamic subtest: eDP-1
[245.345064] Dynamic subtest eDP-1: SKIP (0.000s)
[245.346881] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[245.430875] [037/132] (774s left) perf_pmu (gt-awake)
[245.464604] Starting subtest: gt-awake
[253.911796] Subtest gt-awake: SUCCESS (8.445s)
[254.034638] [038/132] (766s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[254.152411] Starting subtest: flip-vs-cursor-atomic
[261.211321] Subtest flip-vs-cursor-atomic: SUCCESS (7.059s)
[262.093615] [039/132] (758s left) kms_big_fb (x-tiled-addfb-size-overflow)
[262.201351] Starting subtest: x-tiled-addfb-size-overflow
[262.202327] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[262.307264] [040/132] (757s left) gem_eio (wait-10ms)
[262.499385] Starting subtest: wait-10ms
[262.783654] Subtest wait-10ms: SUCCESS (0.285s)
[262.955753] [041/132] (757s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[263.056402] Starting subtest: 4-tiled-16bpp-rotate-0
[264.278386] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.222s)
[264.420126] [042/132] (755s left) syncobj_timeline (reset-multiple-signaled)
[264.481587] Starting subtest: reset-multiple-signaled
[264.482737] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[264.593345] [043/132] (755s left) i915_pm_rc6_residency (rc6-fence)
[264.644742] Starting subtest: rc6-fence
[264.686860] Starting dynamic subtest: gt0
[277.012594] Dynamic subtest gt0: SUCCESS (12.325s)
[277.022818] Starting dynamic subtest: gt1
[292.885885] Dynamic subtest gt1: SUCCESS (15.866s)
[292.887327] Subtest rc6-fence: SUCCESS (28.237s)
[292.978857] [044/132] (727s left) gem_exec_fence (invalid-fence-array)
[293.440689] Starting subtest: invalid-fence-array
[293.443327] Subtest invalid-fence-array: SUCCESS (0.004s)
[295.012277] [FACT gem_exec_fence (invalid-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[295.014239] [045/132] (725s left) perf (polling-parameterized)
[295.156823] Starting subtest: polling-parameterized
[315.184347] Subtest polling-parameterized: SUCCESS (20.022s)
[315.223283] [046/132] (705s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[315.323918] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[322.049486] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.725s)
[322.204593] [047/132] (698s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[322.307867] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[322.977441] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.670s)
[323.181228] [048/132] (697s left) prime_mmap_kms (buffer-sharing)
[323.272412] Starting subtest: buffer-sharing
[323.715650] Subtest buffer-sharing: SUCCESS (0.444s)
[324.561987] [049/132] (695s left) gem_mmap_wc (bad-size)
[324.614978] Subtest bad-size: SKIP (0.000s)
[324.684513] [050/132] (695s left) kms_flip (dpms-vs-vblank-race-interruptible)
[324.780275] Starting subtest: dpms-vs-vblank-race-interruptible
[324.782584] Starting dynamic subtest: A-eDP1
[329.728800] Dynamic subtest A-eDP1: SUCCESS (4.944s)
[329.736127] Starting dynamic subtest: B-eDP1
[333.268095] Dynamic subtest B-eDP1: SUCCESS (3.539s)
[333.275349] Starting dynamic subtest: C-eDP1
[337.877131] Dynamic subtest C-eDP1: SUCCESS (4.607s)
[337.884706] Starting dynamic subtest: D-eDP1
[342.481557] Dynamic subtest D-eDP1: SUCCESS (4.604s)
[342.483936] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (17.702s)
[343.396158] [051/132] (677s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[343.512911] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[344.396801] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.881s)
[344.595348] [052/132] (675s left) i915_query (engine-info)
[344.644440] Starting subtest: engine-info
[344.648394] Subtest engine-info: SUCCESS (0.005s)
[344.796426] [053/132] (675s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[344.898465] Starting subtest: plane-all-modeset-transition-internal-panels
[344.899434] Starting dynamic subtest: pipe-A-eDP-1
[346.215084] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.313s)
[346.500995] Starting dynamic subtest: pipe-B-eDP-1
[347.548905] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.054s)
[347.814817] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.914s)
[348.682353] [054/132] (671s left) gem_reg_read (timestamp-monotonic)
[348.742046] Starting subtest: timestamp-monotonic
[353.134580] Subtest timestamp-monotonic: SUCCESS (4.391s)
[353.224263] [055/132] (667s left) sw_sync (alloc_fence)
[353.256775] Starting subtest: alloc_fence
[353.257775] Subtest alloc_fence: SUCCESS (0.000s)
[353.334450] [056/132] (667s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[353.426383] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[353.427335] Starting dynamic subtest: pipe-A-eDP-1
[353.449485] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[353.457772] Starting dynamic subtest: pipe-B-eDP-1
[353.481151] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[353.488557] Starting dynamic subtest: pipe-C-eDP-1
[353.511580] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[353.518756] Starting dynamic subtest: pipe-D-eDP-1
[353.541356] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[353.543060] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[355.657581] [057/132] (665s left) gem_mmap_gtt (basic-small-bo)
[355.707558] Subtest basic-small-bo: SKIP (0.000s)
[355.766697] [058/132] (664s left) template (b)
[355.823414] Starting subtest: B
[355.824370] Subtest B: SUCCESS (0.000s)
[355.941031] [059/132] (664s left) kms_pm_backlight (fade)
[356.035547] Starting subtest: fade
[356.086997] Starting dynamic subtest: eDP-1
[358.751541] Dynamic subtest eDP-1: SUCCESS (2.666s)
[358.752492] Subtest fade: SUCCESS (2.718s)
[359.580781] [060/132] (661s left) gem_exec_reloc (basic-wc-read)
[359.642083] Subtest basic-wc-read: SKIP (0.000s)
[359.716209] [061/132] (661s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[359.804316] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[366.260250] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.454s)
[366.417194] [062/132] (654s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[366.511233] Starting subtest: plane-ctm3x4-lut1d
[366.525718] Starting dynamic subtest: pipe-A-eDP-1
[366.983898] Dynamic subtest pipe-A-eDP-1: FAIL (0.314s)
[367.863977] Starting dynamic subtest: pipe-B-eDP-1
[368.358973] Dynamic subtest pipe-B-eDP-1: FAIL (0.313s)
[369.295842] Starting dynamic subtest: pipe-C-eDP-1
[369.769137] Dynamic subtest pipe-C-eDP-1: FAIL (0.269s)
[370.629544] Starting dynamic subtest: pipe-D-eDP-1
[371.093795] Dynamic subtest pipe-D-eDP-1: FAIL (0.246s)
[371.157053] Subtest plane-ctm3x4-lut1d: FAIL (4.646s)
[372.021390] [063/132] (648s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[372.105334] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[372.106228] Starting dynamic subtest: pipe-A-default-mode
[374.826529] Dynamic subtest pipe-A-default-mode: SUCCESS (2.721s)
[374.827457] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.723s)
[375.714968] [064/132] (645s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[375.823793] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[375.825445] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[375.961571] [065/132] (645s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[378.077559] Starting subtest: dpms-mode-unset-lpsp
[382.102042] Subtest dpms-mode-unset-lpsp: SUCCESS (4.024s)
[382.504123] [066/132] (638s left) gem_lmem_swapping (verify)
[382.542583] Subtest verify: SKIP (0.000s)
[382.578480] [067/132] (638s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[382.629467] Starting subtest: etime-single-wait-for-submit-submitted
[382.738378] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.106s)
[382.832462] [068/132] (638s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[387.828997] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[387.829878] Starting dynamic subtest: pipe-A-eDP-1
[388.636348] Dynamic subtest pipe-A-eDP-1: SKIP (0.806s)
[388.646901] Starting dynamic subtest: pipe-B-eDP-1
[389.765861] Dynamic subtest pipe-B-eDP-1: SKIP (1.127s)
[389.767691] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.938s)
[390.982819] [069/132] (630s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[391.063094] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[391.063944] Starting dynamic subtest: pipe-A-eDP-1
[391.071199] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[391.078721] Starting dynamic subtest: pipe-B-eDP-1
[391.085817] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[391.093723] Starting dynamic subtest: pipe-C-eDP-1
[391.102436] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[391.113500] Starting dynamic subtest: pipe-D-eDP-1
[391.121054] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[391.121928] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[393.256518] [070/132] (627s left) api_intel_bb (render-ccs)
[393.315451] Starting subtest: render-ccs
[393.677889] Subtest render-ccs: SUCCESS (0.359s)
[393.791348] [071/132] (627s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[398.737090] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[398.738094] Starting dynamic subtest: pipe-A-eDP-1
[415.906053] Dynamic subtest pipe-A-eDP-1: SUCCESS (17.166s)
[415.913920] Starting dynamic subtest: pipe-B-eDP-1
[433.037371] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.129s)
[433.038340] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (34.300s)
[433.858889] [072/132] (587s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[433.963460] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[438.482467] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.519s)
[439.659709] [073/132] (581s left) kms_selftest (drm_dp_mst_helper)
[439.690622] Starting subtest: drm_dp_mst_helper
[439.714506] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[439.837127] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.123s)
[439.844424] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[439.978899] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.140s)
[439.990413] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[440.129987] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.152s)
[440.130920] Subtest drm_dp_mst_helper: SUCCESS (0.440s)
[440.336423] [074/132] (580s left) kms_psr (fbc-pr-no-drrs)
[440.433848] Starting subtest: fbc-pr-no-drrs
[440.435707] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[440.533385] [075/132] (580s left) gem_exec_balancer (fairslice)
[440.589635] Starting subtest: fairslice
[443.469970] Subtest fairslice: SUCCESS (2.878s)
[443.826662] [076/132] (577s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[443.894899] Subtest tiled-to-tiled: SKIP (0.000s)
[443.968448] [077/132] (577s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[444.074262] Starting subtest: plane-all-modeset-transition-fencing
[444.076041] Subtest plane-all-modeset-transition-fencing: SKIP (0.000s)
[444.168954] [078/132] (577s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[444.223920] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[444.225047] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[444.360881] [079/132] (577s left) kms_vblank (query-busy-hang)
[444.452577] Starting subtest: query-busy-hang
[444.453476] Starting dynamic subtest: pipe-A-eDP-1
[470.787926] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.333s)
[470.797586] Starting dynamic subtest: pipe-D-eDP-1
[495.641052] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.850s)
[495.642063] Subtest query-busy-hang: SUCCESS (51.188s)
[496.492347] [080/132] (524s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[496.593221] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[500.977824] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.384s)
[502.177614] [081/132] (519s left) syncobj_timeline (wait-all-delayed-signal)
[502.234554] Starting subtest: wait-all-delayed-signal
[502.338669] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[502.430338] [082/132] (519s left) kms_flip (flip-vs-absolute-wf_vblank)
[502.533128] Starting subtest: flip-vs-absolute-wf_vblank
[502.535049] Starting dynamic subtest: A-eDP1
[511.509074] Dynamic subtest A-eDP1: SUCCESS (8.974s)
[511.519466] Starting dynamic subtest: B-eDP1
[520.445979] Dynamic subtest B-eDP1: SUCCESS (8.935s)
[520.458853] Starting dynamic subtest: C-eDP1
[529.362311] Dynamic subtest C-eDP1: SUCCESS (8.914s)
[529.376269] Starting dynamic subtest: D-eDP1
[538.294931] Dynamic subtest D-eDP1: SUCCESS (8.931s)
[538.298771] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.766s)
[539.527403] [083/132] (482s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[539.633967] Starting subtest: short-flip-before-cursor-atomic-transitions
[540.594320] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.962s)
[541.470225] [084/132] (480s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[541.529861] Starting subtest: yf-tiled-to-vebox-yf-tiled
[541.531767] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[541.736788] [085/132] (479s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[541.837679] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[541.839590] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[541.978447] [086/132] (479s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[542.075073] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[542.075876] Starting dynamic subtest: pipe-A
[542.416117] Dynamic subtest pipe-A: SUCCESS (0.341s)
[542.424589] Starting dynamic subtest: pipe-B
[543.843754] Dynamic subtest pipe-B: SUCCESS (1.426s)
[543.851561] Starting dynamic subtest: pipe-C
[545.298655] Dynamic subtest pipe-C: SUCCESS (1.453s)
[545.307662] Starting dynamic subtest: pipe-D
[546.762032] Dynamic subtest pipe-D: SUCCESS (1.463s)
[546.762969] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.688s)
[547.928413] [087/132] (473s left) api_intel_allocator (open-vm)
[547.993771] Starting subtest: open-vm
[547.999478] Subtest open-vm: SUCCESS (0.006s)
[548.140142] [088/132] (473s left) gem_exec_whisper (basic-contexts-priority-all)
[548.216714] Starting subtest: basic-contexts-priority-all
[571.255536] Subtest basic-contexts-priority-all: SUCCESS (23.035s)
[571.453369] [089/132] (450s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[571.578427] Starting subtest: cursor-tearing-framebuffer-change
[571.579372] Starting dynamic subtest: pipe-A-eDP-1
[572.145887] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.566s)
[572.153609] Starting dynamic subtest: pipe-D-eDP-1
[573.417235] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.269s)
[573.418130] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.838s)
[574.262813] [090/132] (447s left) kms_psr (psr2-sprite-mmap-cpu)
[574.357404] Starting subtest: psr2-sprite-mmap-cpu
[574.358415] Starting dynamic subtest: eDP-1
[576.040692] Dynamic subtest eDP-1: SUCCESS (1.683s)
[576.041701] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.684s)
[576.933894] [091/132] (444s left) gem_tiled_wc
[577.041397] [092/132] (444s left) vgem_basic (second-client)
[577.080524] Starting subtest: second-client
[577.100804] Subtest second-client: SUCCESS (0.021s)
[577.175470] [093/132] (444s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[577.266316] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[581.241801] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.976s)
[582.457685] [094/132] (439s left) prime_vgem (shrink)
[582.539359] Starting subtest: shrink
[582.572123] Subtest shrink: SUCCESS (0.033s)
[582.715338] [095/132] (439s left) sysfs_preempt_timeout (invalid)
[582.772169] Starting subtest: invalid
[582.773095] Starting dynamic subtest: vecs0
[582.773983] Dynamic subtest vecs0: SUCCESS (0.000s)
[582.782816] Starting dynamic subtest: ccs0
[582.783701] Dynamic subtest ccs0: SUCCESS (0.000s)
[582.791332] Starting dynamic subtest: vcs0
[582.792111] Dynamic subtest vcs0: SUCCESS (0.000s)
[582.799673] Starting dynamic subtest: bcs0
[582.800497] Dynamic subtest bcs0: SUCCESS (0.000s)
[582.807739] Starting dynamic subtest: rcs0
[582.808631] Dynamic subtest rcs0: SUCCESS (0.000s)
[582.815985] Starting dynamic subtest: vcs1
[582.816833] Dynamic subtest vcs1: SUCCESS (0.000s)
[582.817695] Subtest invalid: SUCCESS (0.006s)
[582.884822] [096/132] (439s left) gem_ctx_persistence (heartbeat-stop)
[582.943279] Starting subtest: heartbeat-stop
[582.945073] Subtest heartbeat-stop: SKIP (0.000s)
[583.059387] [097/132] (439s left) kms_joiner (basic-force-big-joiner)
[583.138392] Starting subtest: basic-force-big-joiner
[583.139459] Starting dynamic subtest: single
[586.474533] Dynamic subtest single: SUCCESS (3.338s)
[586.475606] Subtest basic-force-big-joiner: SUCCESS (3.339s)
[587.679762] [098/132] (434s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[587.732269] Starting subtest: multi-wait-available-submitted-signaled
[587.733357] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[587.860326] [099/132] (434s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[587.975120] Starting subtest: cursorA-vs-flipB-legacy
[587.977045] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[588.163996] [100/132] (434s left) gem_userptr_blits (probe)
[588.231224] Starting subtest: probe
[588.309878] Subtest probe: SUCCESS (0.078s)
[588.473463] [101/132] (433s left) kms_properties (colorop-properties-atomic)
[588.567070] Starting subtest: colorop-properties-atomic
[588.567893] Starting dynamic subtest: pipe-A-eDP-1
[588.915796] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.348s)
[588.924972] Starting dynamic subtest: pipe-B-eDP-1
[589.944878] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.027s)
[589.952918] Starting dynamic subtest: pipe-C-eDP-1
[590.988870] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.042s)
[590.996731] Starting dynamic subtest: pipe-D-eDP-1
[592.029581] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.040s)
[592.030501] Subtest colorop-properties-atomic: SUCCESS (3.463s)
[592.852240] [102/132] (429s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[592.949641] Starting subtest: addfb25-y-tiled-legacy
[592.950480] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[593.080794] [103/132] (429s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[593.157908] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[593.163304] Starting dynamic subtest: pipe-A-default-mode
[593.449227] Dynamic subtest pipe-A-default-mode: SKIP (0.290s)
[593.450834] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.292s)
[594.268880] [104/132] (428s left) kms_plane_multiple (tiling-y)
[594.368543] Starting subtest: tiling-y
[594.370317] Subtest tiling-y: SKIP (0.000s)
[594.458175] [105/132] (427s left) gem_softpin (evict-prime)
[594.510083] Starting subtest: evict-prime
[594.511116] Starting dynamic subtest: rcs0
[595.683320] Dynamic subtest rcs0: SUCCESS (1.172s)
[595.695425] Starting dynamic subtest: bcs0
[596.835892] Dynamic subtest bcs0: SUCCESS (1.149s)
[596.848105] Starting dynamic subtest: vcs0
[598.904239] Dynamic subtest vcs0: SUCCESS (2.064s)
[598.914563] Starting dynamic subtest: vcs1
[600.885386] Dynamic subtest vcs1: SUCCESS (1.977s)
[600.895867] Starting dynamic subtest: vecs0
[602.936701] Dynamic subtest vecs0: SUCCESS (2.050s)
[602.949684] Starting dynamic subtest: ccs0
[604.100345] Dynamic subtest ccs0: SUCCESS (1.161s)
[604.116735] Starting dynamic subtest: all
[605.873092] Dynamic subtest all: SUCCESS (1.768s)
[605.874532] Subtest evict-prime: SUCCESS (11.361s)
[605.972797] [106/132] (416s left) gem_mmap_offset (close-race)
[606.032247] Starting subtest: close-race
[626.137676] Subtest close-race: SUCCESS (20.106s)
[626.294765] [107/132] (396s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[626.393128] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[626.394992] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[626.524104] [108/132] (395s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[626.617980] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[633.521770] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (6.903s)
[633.685836] [109/132] (388s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[633.787666] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[641.282251] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.495s)
[641.425227] [110/132] (381s left) kms_chamelium_frames (dp-frame-dump)
[641.811616] Subtest dp-frame-dump: SKIP (0.000s)
[642.585766] [111/132] (379s left) kms_content_protection (dp-mst-lic-type-0)
[642.729682] Starting subtest: dp-mst-lic-type-0
[642.731369] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[642.896724] [112/132] (379s left) gen9_exec_parse (bb-chained)
[642.951779] Subtest bb-chained: SKIP (0.000s)
[643.015210] [113/132] (379s left) gem_lmem_swapping (heavy-verify-random)
[643.050694] Subtest heavy-verify-random: SKIP (0.000s)
[643.092753] [114/132] (379s left) kms_pipe_crc_basic (suspend-read-crc)
[643.183320] Starting subtest: suspend-read-crc
[643.184243] Starting dynamic subtest: pipe-A-eDP-1
[660.987432] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.053s)
[661.000457] Starting dynamic subtest: pipe-B-eDP-1
[679.990187] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.462s)
[679.999589] Starting dynamic subtest: pipe-C-eDP-1
[698.940998] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.462s)
[698.951092] Starting dynamic subtest: pipe-D-eDP-1
[717.935333] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.430s)
[717.936174] Subtest suspend-read-crc: SUCCESS (13.414s)
[730.818458] [115/132] (303s left) gem_exec_schedule (submit-late-slice)
[730.892328] Starting subtest: submit-late-slice
[730.893411] Starting dynamic subtest: rcs0
[730.947845] Dynamic subtest rcs0: SUCCESS (0.054s)
[730.959689] Starting dynamic subtest: bcs0
[730.992792] Dynamic subtest bcs0: SUCCESS (0.045s)
[731.003119] Starting dynamic subtest: vcs0
[731.043945] Dynamic subtest vcs0: SUCCESS (0.050s)
[731.055167] Starting dynamic subtest: vcs1
[731.096367] Dynamic subtest vcs1: SUCCESS (0.052s)
[731.105699] Starting dynamic subtest: vecs0
[731.139307] Dynamic subtest vecs0: SUCCESS (0.042s)
[731.151825] Starting dynamic subtest: ccs0
[731.185221] Dynamic subtest ccs0: SUCCESS (0.044s)
[731.186787] Subtest submit-late-slice: SUCCESS (0.292s)
[732.408039] [116/132] (302s left) kms_cursor_crc (cursor-sliding-256x256)
[732.614343] Starting subtest: cursor-sliding-256x256
[732.617905] Starting dynamic subtest: pipe-A-eDP-1
[735.824766] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.198s)
[735.856701] Starting dynamic subtest: pipe-D-eDP-1
[739.698979] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.846s)
[739.699870] Subtest cursor-sliding-256x256: SUCCESS (7.067s)
[740.580102] [117/132] (294s left) kms_dp_aux_dev
[740.953151] [118/132] (293s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[741.002917] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[741.058042] [119/132] (293s left) kms_feature_discovery (display-1x)
[741.154432] Starting subtest: display-1x
[741.155403] Subtest display-1x: SUCCESS (0.000s)
[741.246751] [120/132] (293s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[741.345043] Starting subtest: fbc-psr-sprite-mmap-cpu
[741.345893] Starting dynamic subtest: eDP-1
[741.347517] Dynamic subtest eDP-1: SKIP (0.000s)
[741.349043] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[741.440531] [121/132] (293s left) gem_exec_params (secure-non-root)
[741.493724] Starting subtest: secure-non-root
[741.495659] Subtest secure-non-root: SKIP (0.000s)
[741.620498] [122/132] (293s left) gem_exec_alignment (many)
[741.686005] Starting subtest: many
[766.049515] Subtest many: SUCCESS (24.346s)
[768.167067] [123/132] (266s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[768.588602] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[769.372911] [124/132] (265s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[769.472989] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[769.474826] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[769.610944] [125/132] (265s left) gem_mmap (basic-small-bo)
[769.658905] Subtest basic-small-bo: SKIP (0.000s)
[769.722332] [126/132] (265s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[769.831083] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[769.832992] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[769.962216] [127/132] (265s left) dumb_buffer (invalid-bpp)
[770.013692] Starting subtest: invalid-bpp
[770.014686] Subtest invalid-bpp: SUCCESS (0.000s)
[770.144062] [128/132] (265s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[770.247403] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[770.249080] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[770.373011] [129/132] (264s left) i915_getparams_basic (basic-eu-total)
[770.435999] Starting subtest: basic-eu-total
[770.437652] Subtest basic-eu-total: SUCCESS (0.000s)
[770.542000] [130/132] (264s left) gem_exec_parallel (userptr)
[770.603547] Starting subtest: userptr
[770.604478] Starting dynamic subtest: rcs0
[772.061317] Dynamic subtest rcs0: SUCCESS (1.457s)
[772.074175] Starting dynamic subtest: bcs0
[773.558529] Dynamic subtest bcs0: SUCCESS (1.495s)
[773.572421] Starting dynamic subtest: vcs0
[775.225981] Dynamic subtest vcs0: SUCCESS (1.664s)
[775.236337] Starting dynamic subtest: vcs1
[777.264473] Dynamic subtest vcs1: SUCCESS (2.039s)
[777.277048] Starting dynamic subtest: vecs0
[779.185076] Dynamic subtest vecs0: SUCCESS (1.917s)
[779.195152] Starting dynamic subtest: ccs0
[780.715321] Dynamic subtest ccs0: SUCCESS (1.529s)
[780.716770] Subtest userptr: SUCCESS (10.113s)
[780.918384] [131/132] (254s left) kms_flip (2x-plain-flip-ts-check)
[781.015455] Starting subtest: 2x-plain-flip-ts-check
[781.027581] Subtest 2x-plain-flip-ts-check: SKIP (0.011s)
[781.127712] [132/132] (254s left) kms_busy (extended-modeset-hang-oldfb)
[781.227730] Starting subtest: extended-modeset-hang-oldfb
[781.228692] Starting dynamic subtest: pipe-A
[793.223970] Dynamic subtest pipe-A: SUCCESS (11.993s)
[793.235203] Starting dynamic subtest: pipe-D
[805.695338] Dynamic subtest pipe-D: SUCCESS (12.466s)
[805.696261] Subtest extended-modeset-hang-oldfb: SUCCESS (24.463s)
[806.513091] 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.