[59.299062] Initializing watchdogs
[59.299108]   /dev/watchdog0
[59.313447] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[59.317549] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.319504] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.322702] [001/156] (960s left) gen7_exec_parse (oacontrol-tracking)
[59.406663] Subtest oacontrol-tracking: SKIP (0.000s)
[59.473820] [002/156] (960s left) gem_eio (in-flight-10ms)
[59.611410] Starting subtest: in-flight-10ms
[66.851480] Subtest in-flight-10ms: SUCCESS (7.294s)
[67.014856] [FACT gem_eio (in-flight-10ms)] new: kernel.kmod_is_loaded.vgem: true
[67.017892] [003/156] (952s left) kms_content_protection (dp-mst-lic-type-1)
[67.145384] Starting subtest: dp-mst-lic-type-1
[67.149563] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[67.265121] [004/156] (952s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[67.354326] Starting subtest: 4-tiled-32bpp-rotate-90
[67.388182] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.033s)
[67.495950] [005/156] (952s left) syncobj_eventfd (invalid-bad-pad)
[67.552326] Starting subtest: invalid-bad-pad
[67.554044] Subtest invalid-bad-pad: SUCCESS (0.000s)
[67.652762] [006/156] (952s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[67.725327] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[67.727217] Starting dynamic subtest: pipe-A-eDP-1
[68.003110] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.253s)
[68.012962] Starting dynamic subtest: pipe-B-eDP-1
[69.502966] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.498s)
[69.511851] Starting dynamic subtest: pipe-C-eDP-1
[70.710062] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.209s)
[70.718351] Starting dynamic subtest: pipe-D-eDP-1
[71.926694] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.222s)
[71.928994] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.186s)
[73.069378] [007/156] (947s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[73.217138] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[73.220938] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[73.295877] [008/156] (946s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[73.373742] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[73.376067] Starting dynamic subtest: pipe-A-default-mode
[76.089130] Dynamic subtest pipe-A-default-mode: SUCCESS (2.719s)
[76.091540] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.720s)
[76.911477] [009/156] (943s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[76.972304] Starting subtest: yf-tiled-to-vebox-yf-tiled
[76.976958] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[77.156844] [010/156] (943s left) gem_ctx_param (invalid-ctx-get)
[77.206670] Starting subtest: invalid-ctx-get
[77.209254] Subtest invalid-ctx-get: SUCCESS (0.000s)
[77.285780] [011/156] (942s left) kms_psr (pr-suspend)
[77.358301] Starting subtest: pr-suspend
[77.362577] Subtest pr-suspend: SKIP (0.000s)
[77.426694] [012/156] (942s left) gem_spin_batch (spin-all)
[77.490670] Starting subtest: spin-all
[77.503963] Subtest spin-all: SUCCESS (0.012s)
[79.266561] [013/156] (941s left) kms_setmode (basic)
[79.312529] Starting subtest: basic
[79.659663] Starting dynamic subtest: pipe-A-eDP-1
[83.067563] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.406s)
[83.411644] Starting dynamic subtest: pipe-B-eDP-1
[85.545938] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.137s)
[85.854065] Subtest basic: SUCCESS (6.543s)
[86.692661] [014/156] (933s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[86.774511] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[86.778737] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[86.870176] [015/156] (933s left) gem_pread (display)
[86.918114] Subtest display: SKIP (0.000s)
[86.975963] [016/156] (933s left) gem_pxp (verify-pxp-stale-buf-execution)
[87.089034] Starting subtest: verify-pxp-stale-buf-execution
[88.150138] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.064s)
[88.286032] [017/156] (932s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[88.358757] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[88.361102] Starting dynamic subtest: pipe-A-eDP-1
[120.709806] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.089s)
[120.720200] Starting dynamic subtest: pipe-B-eDP-1
[153.640585] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.795s)
[153.651145] Starting dynamic subtest: pipe-C-eDP-1
[186.596029] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.794s)
[186.606460] Starting dynamic subtest: pipe-D-eDP-1
[219.574498] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.735s)
[219.576970] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (14.417s)
[220.752409] [018/156] (799s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[220.844808] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[220.850754] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[220.933692] [019/156] (799s left) gem_mmap_gtt (basic-read-write)
[220.991842] Subtest basic-read-write: SKIP (0.000s)
[221.050403] [020/156] (799s left) gem_lmem_swapping (parallel-random)
[221.093751] Subtest parallel-random: SKIP (0.000s)
[221.135991] [021/156] (799s left) gem_eio (in-flight-internal-1us)
[221.285776] Starting subtest: in-flight-internal-1us
[222.297409] Subtest in-flight-internal-1us: SUCCESS (1.023s)
[222.417342] [022/156] (798s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[222.494476] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[222.499449] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[222.596190] [023/156] (798s left) kms_getfb (getfb-handle-protection)
[222.677640] Starting subtest: getfb-handle-protection
[222.703252] Subtest getfb-handle-protection: SUCCESS (0.026s)
[222.812183] [024/156] (797s left) kms_frontbuffer_tracking (psrhdr-indfb-scaledprimary)
[222.896710] Starting subtest: psrhdr-indfb-scaledprimary
[222.902370] Subtest psrhdr-indfb-scaledprimary: SKIP (0.000s)
[222.999542] [025/156] (797s left) kms_pm_rpm (universal-planes-dpms)
[224.500521] Starting subtest: universal-planes-dpms
[224.502942] Starting dynamic subtest: plane-35
[232.817843] Dynamic subtest plane-35: SUCCESS (8.365s)
[232.830227] Starting dynamic subtest: plane-65
[241.155856] Dynamic subtest plane-65: SUCCESS (8.354s)
[241.169233] Starting dynamic subtest: plane-95
[249.471459] Dynamic subtest plane-95: SUCCESS (8.322s)
[249.488184] Starting dynamic subtest: plane-125
[257.780235] Dynamic subtest plane-125: SUCCESS (8.337s)
[257.790796] Starting dynamic subtest: plane-135
[266.129473] Dynamic subtest plane-135: SUCCESS (8.362s)
[266.143524] Starting dynamic subtest: plane-145
[274.409420] Dynamic subtest plane-145: SUCCESS (8.285s)
[274.412269] Subtest universal-planes-dpms: SUCCESS (50.038s)
[274.781900] [026/156] (746s left) kms_cursor_crc (cursor-random-256x256)
[275.015643] Starting subtest: cursor-random-256x256
[275.018090] Starting dynamic subtest: pipe-A-eDP-1
[278.379181] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.365s)
[278.410107] Starting dynamic subtest: pipe-D-eDP-1
[282.431493] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.031s)
[282.434101] Subtest cursor-random-256x256: SUCCESS (7.421s)
[283.241015] [027/156] (737s left) gem_ctx_persistence (file)
[283.292846] Starting subtest: file
[283.397229] Subtest file: SUCCESS (0.105s)
[283.520413] [028/156] (737s left) syncobj_timeline (etime-multi-wait-submitted)
[283.579851] Starting subtest: etime-multi-wait-submitted
[283.687796] Subtest etime-multi-wait-submitted: SUCCESS (0.107s)
[283.778640] [029/156] (737s left) kms_async_flips (crc-atomic)
[283.852564] Starting subtest: crc-atomic
[284.228273] Starting dynamic subtest: pipe-A-eDP-1
[287.162460] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.931s)
[287.536944] Starting dynamic subtest: pipe-B-eDP-1
[290.483314] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.954s)
[290.861607] Starting dynamic subtest: pipe-C-eDP-1
[293.795653] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.947s)
[294.161948] Starting dynamic subtest: pipe-D-eDP-1
[297.023084] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.872s)
[297.025708] Subtest crc-atomic: SUCCESS (13.163s)
[298.186097] [030/156] (722s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[298.275119] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[298.279775] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[298.351360] [031/156] (722s left) kms_addfb_basic (invalid-get-prop)
[298.402077] Starting subtest: invalid-get-prop
[298.404750] Subtest invalid-get-prop: SUCCESS (0.000s)
[298.519695] [032/156] (722s left) perf (sysctl-defaults)
[298.590717] Starting subtest: sysctl-defaults
[298.593188] Subtest sysctl-defaults: SUCCESS (0.000s)
[298.694204] [033/156] (722s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-blt)
[298.772628] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-blt
[298.777825] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[298.860777] [034/156] (722s left) kms_dsc (dsc-with-bpc)
[298.935730] Starting subtest: dsc-with-bpc
[298.972731] Subtest dsc-with-bpc: SKIP (0.001s)
[299.044206] [035/156] (722s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-blt)
[299.120585] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-blt
[299.126001] Subtest hdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[299.240425] [036/156] (722s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[299.311696] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[299.313647] Starting dynamic subtest: pipe-A-eDP-1
[300.634361] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.322s)
[300.918974] Starting dynamic subtest: pipe-B-eDP-1
[301.996410] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.086s)
[302.280703] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.970s)
[303.105482] [037/156] (718s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-blt)
[303.213681] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-blt
[303.218542] Subtest psrhdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[303.302524] [038/156] (718s left) perf (invalid-open-flags)
[303.393162] Starting subtest: invalid-open-flags
[303.395933] Subtest invalid-open-flags: SUCCESS (0.000s)
[303.436408] [039/156] (717s left) kms_sequence (queue-busy)
[303.507637] Starting subtest: queue-busy
[303.510268] Starting dynamic subtest: pipe-A-eDP-1
[305.452930] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.945s)
[305.463351] Starting dynamic subtest: pipe-B-eDP-1
[308.042757] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.587s)
[308.053127] Starting dynamic subtest: pipe-C-eDP-1
[310.643004] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.602s)
[310.654539] Starting dynamic subtest: pipe-D-eDP-1
[313.276950] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.630s)
[313.279810] Subtest queue-busy: SUCCESS (9.770s)
[314.160741] [040/156] (707s left) gem_exec_fence (syncobj-export)
[314.587685] Starting subtest: syncobj-export
[314.593023] Subtest syncobj-export: SUCCESS (0.005s)
[316.332597] [041/156] (705s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[316.413841] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[316.416368] Starting dynamic subtest: pipe-A
[316.436383] Dynamic subtest pipe-A: SKIP (0.018s)
[316.445935] Starting dynamic subtest: pipe-B
[316.470506] Dynamic subtest pipe-B: SKIP (0.032s)
[316.480407] Starting dynamic subtest: pipe-C
[316.500831] Dynamic subtest pipe-C: SKIP (0.032s)
[316.510217] Starting dynamic subtest: pipe-D
[316.540751] Dynamic subtest pipe-D: SKIP (0.037s)
[316.545834] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.122s)
[316.630298] [042/156] (704s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[316.707077] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[316.711737] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[316.800433] [043/156] (704s left) kms_addfb_basic (small-bo)
[316.885760] Starting subtest: small-bo
[316.888751] Subtest small-bo: SUCCESS (0.000s)
[316.999975] [044/156] (704s left) perf_pmu (event-wait)
[317.039955] Starting subtest: event-wait
[317.042886] Starting dynamic subtest: rcs0
[317.048598] Dynamic subtest rcs0: SKIP (0.000s)
[317.053981] Subtest event-wait: SKIP (0.001s)
[317.170641] [045/156] (704s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[317.263376] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[317.268350] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[317.365787] [046/156] (704s left) gem_exec_schedule (u-semaphore-user)
[317.437569] Starting subtest: u-semaphore-user
[317.489343] Subtest u-semaphore-user: SUCCESS (0.060s)
[319.321021] [047/156] (702s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[319.405664] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[319.410786] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[319.511199] [048/156] (702s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[319.598760] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-pwrite
[319.603852] Subtest hdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[319.692627] [049/156] (702s left) kms_vblank (ts-continuation-modeset)
[319.767802] Starting subtest: ts-continuation-modeset
[319.770207] Starting dynamic subtest: pipe-A-eDP-1
[321.214701] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.447s)
[321.229855] Starting dynamic subtest: pipe-D-eDP-1
[323.380374] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.161s)
[323.382982] Subtest ts-continuation-modeset: SUCCESS (3.612s)
[324.196963] [050/156] (697s left) kms_psr (psr-primary-blt)
[324.272134] Starting subtest: psr-primary-blt
[324.274544] Starting dynamic subtest: eDP-1
[325.991835] Dynamic subtest eDP-1: SUCCESS (1.718s)
[325.994352] Subtest psr-primary-blt: SUCCESS (1.719s)
[326.838407] [051/156] (695s left) i915_pm_freq_mult (media-freq)
[326.898770] Starting subtest: media-freq
[326.901716] Starting dynamic subtest: gt0
[326.907463] Dynamic subtest gt0: SKIP (0.000s)
[326.918515] Starting dynamic subtest: gt1
[326.923623] Dynamic subtest gt1: SKIP (0.000s)
[326.928603] Subtest media-freq: SKIP (0.001s)
[326.991975] [052/156] (694s left) kms_hdmi_inject (inject-audio)
[327.053424] Subtest inject-audio: SKIP (0.000s)
[327.115744] [053/156] (694s left) kms_cursor_crc (cursor-sliding-max-size)
[327.414550] Starting subtest: cursor-sliding-max-size
[327.422477] Subtest cursor-sliding-max-size: SKIP (0.000s)
[327.536795] [054/156] (694s left) gem_exec_schedule (preempt-hang)
[327.702683] Starting subtest: preempt-hang
[327.705522] Starting dynamic subtest: rcs0
[327.941271] Dynamic subtest rcs0: SUCCESS (0.236s)
[327.952958] Starting dynamic subtest: bcs0
[328.178233] Dynamic subtest bcs0: SUCCESS (0.236s)
[328.188963] Starting dynamic subtest: vcs0
[328.401657] Dynamic subtest vcs0: SUCCESS (0.223s)
[328.412726] Starting dynamic subtest: vcs1
[328.625037] Dynamic subtest vcs1: SUCCESS (0.222s)
[328.635985] Starting dynamic subtest: vecs0
[328.846069] Dynamic subtest vecs0: SUCCESS (0.221s)
[328.855751] Starting dynamic subtest: ccs0
[329.072990] Dynamic subtest ccs0: SUCCESS (0.227s)
[329.075306] Subtest preempt-hang: SUCCESS (1.370s)
[329.349237] [055/156] (692s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[329.435704] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[336.042753] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.615s)
[336.155650] [056/156] (685s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[336.235787] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[340.380520] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.151s)
[341.574164] [057/156] (680s left) prime_mmap (test_reprime)
[341.629685] Starting subtest: test_reprime
[341.632077] Starting dynamic subtest: test_reprime-smem
[341.660269] Dynamic subtest test_reprime-smem: SUCCESS (0.032s)
[341.662961] Subtest test_reprime: SUCCESS (0.033s)
[341.762581] [058/156] (680s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[341.836786] Starting subtest: fbc-pr-cursor-mmap-cpu
[341.842636] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[341.915635] [059/156] (680s left) api_intel_bb (lot-of-buffers)
[341.967411] Starting subtest: lot-of-buffers
[342.944745] Subtest lot-of-buffers: SUCCESS (0.980s)
[343.380366] [060/156] (678s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[343.753788] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[344.575557] [061/156] (677s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[344.657681] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[351.480938] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.829s)
[351.615859] [062/156] (670s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[351.702298] Starting subtest: x-tiled-8bpp-rotate-90
[351.727758] Subtest x-tiled-8bpp-rotate-90: SKIP (0.024s)
[351.852251] [063/156] (670s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[351.940962] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[351.946467] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[352.033101] [064/156] (670s left) syncobj_timeline (reset-unsignaled)
[352.094751] Starting subtest: reset-unsignaled
[352.097731] Subtest reset-unsignaled: SUCCESS (0.000s)
[352.180885] [065/156] (670s left) kms_flip (2x-flip-vs-panning-vs-hang)
[352.250273] Starting subtest: 2x-flip-vs-panning-vs-hang
[352.254941] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.001s)
[352.352370] [066/156] (670s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-render)
[352.440588] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-render
[352.444418] Subtest fbchdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[352.534154] [067/156] (670s left) kms_color (ctm-red-to-blue)
[352.603455] Starting subtest: ctm-red-to-blue
[352.605732] Starting dynamic subtest: pipe-A-eDP-1
[353.316804] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.710s)
[353.328105] Starting dynamic subtest: pipe-B-eDP-1
[354.711851] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.396s)
[354.722413] Starting dynamic subtest: pipe-C-eDP-1
[356.082990] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.369s)
[356.092820] Starting dynamic subtest: pipe-D-eDP-1
[357.415704] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[357.418267] Subtest ctm-red-to-blue: SUCCESS (4.810s)
[358.221993] [068/156] (664s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-blt)
[358.303744] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-blt
[358.308928] Subtest fbchdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[358.403188] [069/156] (664s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-pwrite)
[358.487395] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-pwrite
[358.492730] Subtest psrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[358.588235] [070/156] (664s left) kms_psr (fbc-psr-primary-page-flip)
[358.666935] Starting subtest: fbc-psr-primary-page-flip
[358.669945] Starting dynamic subtest: eDP-1
[358.674956] Dynamic subtest eDP-1: SKIP (0.000s)
[358.679945] Subtest fbc-psr-primary-page-flip: SKIP (0.001s)
[358.738470] [071/156] (664s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[358.820997] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[365.435248] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.622s)
[365.575036] [072/156] (657s left) syncobj_basic (test-valid-cycle)
[365.634974] Starting subtest: test-valid-cycle
[365.637858] Subtest test-valid-cycle: SUCCESS (0.000s)
[365.728193] [073/156] (657s left) kms_pwrite_crc (basic)
[365.800813] Starting subtest: basic
[366.483031] Subtest basic: SUCCESS (0.680s)
[367.361185] [074/156] (655s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[367.444135] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[367.446941] Starting dynamic subtest: pipe-A
[367.467281] Dynamic subtest pipe-A: SKIP (0.016s)
[367.479957] Starting dynamic subtest: pipe-B
[367.496619] Dynamic subtest pipe-B: SKIP (0.029s)
[367.506883] Starting dynamic subtest: pipe-C
[367.527750] Dynamic subtest pipe-C: SKIP (0.030s)
[367.537797] Starting dynamic subtest: pipe-D
[367.559369] Dynamic subtest pipe-D: SKIP (0.029s)
[367.564446] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.108s)
[367.640730] [075/156] (655s left) kms_flip (2x-modeset-vs-vblank-race)
[367.713602] Starting subtest: 2x-modeset-vs-vblank-race
[367.720655] Subtest 2x-modeset-vs-vblank-race: SKIP (0.008s)
[367.800152] [076/156] (655s left) syncobj_timeline (wait-all-interrupted)
[367.858970] Starting subtest: wait-all-interrupted
[367.958465] Subtest wait-all-interrupted: SUCCESS (0.101s)
[368.062187] [077/156] (654s left) gem_readwrite (new-obj)
[368.118698] Subtest new-obj: SKIP (0.000s)
[368.176208] [078/156] (654s left) syncobj_timeline (multi-wait-available-signaled)
[368.223086] Starting subtest: multi-wait-available-signaled
[368.225699] Subtest multi-wait-available-signaled: SUCCESS (0.000s)
[368.318276] [079/156] (654s left) kms_frontbuffer_tracking (psrhdr-1p-pri-indfb-multidraw)
[368.398587] Starting subtest: psrhdr-1p-pri-indfb-multidraw
[368.402706] Subtest psrhdr-1p-pri-indfb-multidraw: SKIP (0.000s)
[368.505447] [080/156] (654s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[368.583460] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[368.588103] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[368.678374] [081/156] (654s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-blt)
[368.750485] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-blt
[368.754743] Subtest hdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[368.848114] [082/156] (654s left) kms_chamelium_color (ctm-negative)
[368.917794] Subtest ctm-negative: SKIP (0.000s)
[368.963681] [083/156] (654s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-render)
[369.055555] Starting subtest: fbchdr-rgb101010-draw-render
[369.059601] Subtest fbchdr-rgb101010-draw-render: SKIP (0.000s)
[369.150470] [084/156] (654s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[369.250818] Starting subtest: basic-busy-flip-before-cursor-atomic
[369.255270] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[369.393881] [085/156] (653s left) syncobj_wait (invalid-wait-bad-flags)
[369.442794] Starting subtest: invalid-wait-bad-flags
[369.445408] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[369.539654] [086/156] (653s left) gem_exec_parallel (engines)
[369.590544] Starting subtest: engines
[369.592577] Starting dynamic subtest: basic
[371.298359] Dynamic subtest basic: SUCCESS (1.707s)
[371.311520] Starting dynamic subtest: contexts
[375.730827] Dynamic subtest contexts: SUCCESS (4.429s)
[375.743516] Starting dynamic subtest: fds
[381.299583] Dynamic subtest fds: SUCCESS (5.566s)
[381.313006] Starting dynamic subtest: userptr
[383.282952] Dynamic subtest userptr: SUCCESS (1.981s)
[383.285980] Subtest engines: SUCCESS (13.690s)
[383.479287] [087/156] (639s left) kms_flip (nonblocking-read)
[383.564488] Starting subtest: nonblocking-read
[383.566820] Subtest nonblocking-read: SUCCESS (0.000s)
[383.669015] [088/156] (639s left) kms_pipe_stress (stress-xrgb8888-4tiled)
[383.745758] Starting subtest: stress-xrgb8888-4tiled
[389.584357] Subtest stress-xrgb8888-4tiled: SUCCESS (5.842s)
[389.639296] [089/156] (633s left) gem_exec_capture (capture-invisible)
[389.694981] Starting subtest: capture-invisible
[389.697240] Starting dynamic subtest: smem0
[389.701699] Dynamic subtest smem0: SKIP (0.000s)
[389.705834] Subtest capture-invisible: SKIP (0.001s)
[389.799628] [090/156] (633s left) gem_ctx_shared (disjoint-timelines)
[389.866632] Starting subtest: disjoint-timelines
[389.901248] Subtest disjoint-timelines: SUCCESS (0.034s)
[390.127229] [091/156] (633s left) kms_flip (wf_vblank-ts-check-interruptible)
[390.206827] Starting subtest: wf_vblank-ts-check-interruptible
[390.209400] Starting dynamic subtest: A-eDP1
[399.108761] Dynamic subtest A-eDP1: SUCCESS (8.902s)
[399.119121] Starting dynamic subtest: B-eDP1
[408.029432] Dynamic subtest B-eDP1: SUCCESS (8.921s)
[408.040506] Starting dynamic subtest: C-eDP1
[416.948299] Dynamic subtest C-eDP1: SUCCESS (8.919s)
[416.961805] Starting dynamic subtest: D-eDP1
[425.871103] Dynamic subtest D-eDP1: SUCCESS (8.922s)
[425.873528] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.671s)
[427.104446] [092/156] (596s left) gem_mmap_wc (write-prefaulted)
[427.157727] Subtest write-prefaulted: SKIP (0.000s)
[427.219450] [093/156] (596s left) kms_pm_rpm (basic-rte)
[427.255060] Starting subtest: basic-rte
[430.905750] Subtest basic-rte: SUCCESS (3.651s)
[432.395622] [094/156] (591s left) kms_flip (basic-flip-vs-modeset)
[432.465882] Starting subtest: basic-flip-vs-modeset
[432.468573] Starting dynamic subtest: A-eDP1
[435.019522] Dynamic subtest A-eDP1: SUCCESS (2.555s)
[435.029937] Starting dynamic subtest: B-eDP1
[437.191703] Dynamic subtest B-eDP1: SUCCESS (2.169s)
[437.203049] Starting dynamic subtest: C-eDP1
[439.365655] Dynamic subtest C-eDP1: SUCCESS (2.177s)
[439.376963] Starting dynamic subtest: D-eDP1
[441.547876] Dynamic subtest D-eDP1: SUCCESS (2.178s)
[441.550747] Subtest basic-flip-vs-modeset: SUCCESS (9.089s)
[442.351803] [095/156] (581s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[442.430550] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[449.878958] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.456s)
[449.994500] [096/156] (573s left) kms_flip (nonexisting-fb-interruptible)
[450.066341] Starting subtest: nonexisting-fb-interruptible
[450.068658] Starting dynamic subtest: A-eDP1
[451.256656] Dynamic subtest A-eDP1: SUCCESS (1.189s)
[451.268631] Starting dynamic subtest: B-eDP1
[452.396597] Dynamic subtest B-eDP1: SUCCESS (1.140s)
[452.411136] Starting dynamic subtest: C-eDP1
[453.521853] Dynamic subtest C-eDP1: SUCCESS (1.124s)
[453.531976] Starting dynamic subtest: D-eDP1
[454.662232] Dynamic subtest D-eDP1: SUCCESS (1.139s)
[454.667670] Subtest nonexisting-fb-interruptible: SUCCESS (4.600s)
[455.839339] [097/156] (568s left) kms_lease (simple-lease)
[455.921732] Starting subtest: simple-lease
[455.924313] Starting dynamic subtest: pipe-A-eDP-1
[456.419838] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.499s)
[456.437946] Starting dynamic subtest: pipe-B-eDP-1
[457.878555] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.457s)
[457.890253] Starting dynamic subtest: pipe-C-eDP-1
[459.392341] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.511s)
[459.402801] Starting dynamic subtest: pipe-D-eDP-1
[460.798767] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.405s)
[460.801369] Subtest simple-lease: SUCCESS (4.878s)
[461.613608] [098/156] (562s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[461.698801] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[469.039812] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.349s)
[469.166118] [099/156] (554s left) kms_async_flips (test-cursor)
[469.245270] Starting subtest: test-cursor
[469.617681] Starting dynamic subtest: pipe-A-eDP-1
[470.387880] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.772s)
[470.785844] Starting dynamic subtest: pipe-B-eDP-1
[471.529413] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.752s)
[471.908387] Starting dynamic subtest: pipe-C-eDP-1
[472.660590] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.761s)
[473.034318] Starting dynamic subtest: pipe-D-eDP-1
[473.789899] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.767s)
[473.792290] Subtest test-cursor: SUCCESS (4.547s)
[474.963827] [100/156] (549s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[475.046690] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc
[475.051748] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[475.149033] [101/156] (548s left) gem_exec_flush (basic-uc-pro-default)
[475.204565] Starting subtest: basic-uc-pro-default
[480.667778] Subtest basic-uc-pro-default: SUCCESS (5.463s)
[480.873039] [102/156] (543s left) gem_fence_thrash (bo-write-verify-none)
[480.905842] Starting subtest: bo-write-verify-none
[480.926732] Subtest bo-write-verify-none: SKIP (0.016s)
[480.985700] [103/156] (543s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[481.045707] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[481.109328] [104/156] (543s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[481.189714] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[481.194834] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[481.282188] [105/156] (542s left) kms_big_fb (linear-8bpp-rotate-180)
[481.365806] Starting subtest: linear-8bpp-rotate-180
[482.624637] Subtest linear-8bpp-rotate-180: SUCCESS (1.258s)
[482.746141] [106/156] (541s left) kms_frontbuffer_tracking (fbcpsrhdr-modesetfrombusy)
[482.831718] Starting subtest: fbcpsrhdr-modesetfrombusy
[482.835739] Subtest fbcpsrhdr-modesetfrombusy: SKIP (0.000s)
[482.924304] [107/156] (541s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-shrfb-msflip-blt)
[483.005638] Starting subtest: fbchdr-1p-primscrn-shrfb-msflip-blt
[483.009899] Subtest fbchdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[483.103530] [108/156] (541s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[483.176999] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[483.179562] Starting dynamic subtest: pipe-A-eDP-1
[483.201519] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[483.211994] Starting dynamic subtest: pipe-B-eDP-1
[483.232727] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[483.242231] Starting dynamic subtest: pipe-C-eDP-1
[483.263194] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[483.272251] Starting dynamic subtest: pipe-D-eDP-1
[483.293944] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[483.298596] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[485.440707] [109/156] (538s left) kms_flip (basic-plain-flip)
[485.519872] Starting subtest: basic-plain-flip
[485.522303] Starting dynamic subtest: A-eDP1
[487.249267] Dynamic subtest A-eDP1: SUCCESS (1.730s)
[487.261151] Starting dynamic subtest: B-eDP1
[488.982562] Dynamic subtest B-eDP1: SUCCESS (1.732s)
[488.994224] Starting dynamic subtest: C-eDP1
[490.629962] Dynamic subtest C-eDP1: SUCCESS (1.646s)
[490.641821] Starting dynamic subtest: D-eDP1
[492.271124] Dynamic subtest D-eDP1: SUCCESS (1.638s)
[492.276407] Subtest basic-plain-flip: SUCCESS (6.756s)
[493.440270] [110/156] (530s left) gem_mmap_gtt (basic-wc)
[493.501632] Subtest basic-wc: SKIP (0.000s)
[493.565321] [111/156] (530s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[493.642690] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[493.645535] Starting dynamic subtest: pipe-A-eDP-1
[493.788957] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.134s)
[493.801838] Starting dynamic subtest: pipe-B-eDP-1
[493.826735] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[493.837591] Starting dynamic subtest: pipe-C-eDP-1
[493.861739] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[493.871802] Starting dynamic subtest: pipe-D-eDP-1
[493.896187] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[493.898751] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.142s)
[496.104984] [112/156] (528s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt)
[496.196737] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt
[496.202551] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[496.296626] [113/156] (528s left) perf_pmu (busy-idle)
[496.337977] Starting subtest: busy-idle
[496.340894] Starting dynamic subtest: rcs0
[497.025747] Dynamic subtest rcs0: SUCCESS (0.688s)
[497.039960] Starting dynamic subtest: bcs0
[497.701617] Dynamic subtest bcs0: SUCCESS (0.673s)
[497.715998] Starting dynamic subtest: vcs0
[499.251466] Dynamic subtest vcs0: SUCCESS (1.546s)
[499.268777] Starting dynamic subtest: vcs1
[501.299318] Dynamic subtest vcs1: SUCCESS (2.044s)
[501.312936] Starting dynamic subtest: vecs0
[503.283500] Dynamic subtest vecs0: SUCCESS (1.981s)
[503.297825] Starting dynamic subtest: ccs0
[503.951481] Dynamic subtest ccs0: SUCCESS (0.666s)
[503.954251] Subtest busy-idle: SUCCESS (7.614s)
[504.093056] [114/156] (520s left) kms_busy (extended-modeset-hang-newfb)
[504.177855] Starting subtest: extended-modeset-hang-newfb
[504.180639] Starting dynamic subtest: pipe-A
[538.192980] Dynamic subtest pipe-A: SUCCESS (34.010s)
[538.204571] Starting dynamic subtest: pipe-D
[561.212888] Dynamic subtest pipe-D: SUCCESS (23.016s)
[561.215618] Subtest extended-modeset-hang-newfb: SUCCESS (57.031s)
[562.109060] [115/156] (462s left) gem_wait (await)
[562.177672] Starting subtest: await
[562.180704] Starting dynamic subtest: all-engines
[563.283146] Dynamic subtest all-engines: SUCCESS (1.106s)
[563.299115] Starting dynamic subtest: rcs0
[564.349928] Dynamic subtest rcs0: SUCCESS (1.063s)
[564.366959] Starting dynamic subtest: bcs0
[565.412370] Dynamic subtest bcs0: SUCCESS (1.061s)
[565.429287] Starting dynamic subtest: vcs0
[566.471133] Dynamic subtest vcs0: SUCCESS (1.057s)
[566.488604] Starting dynamic subtest: vcs1
[567.530719] Dynamic subtest vcs1: SUCCESS (1.057s)
[567.548997] Starting dynamic subtest: vecs0
[568.589955] Dynamic subtest vecs0: SUCCESS (1.057s)
[568.608643] Starting dynamic subtest: ccs0
[569.652803] Dynamic subtest ccs0: SUCCESS (1.060s)
[569.655747] Subtest await: SUCCESS (7.477s)
[569.852734] [116/156] (454s left) kms_plane_multiple (tiling-4)
[569.931046] Starting subtest: tiling-4
[569.934620] Starting dynamic subtest: pipe-A-eDP-1
[573.678771] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.747s)
[573.690214] Starting dynamic subtest: pipe-B-eDP-1
[578.240724] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.560s)
[578.253428] Starting dynamic subtest: pipe-C-eDP-1
[582.702940] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.462s)
[582.714426] Starting dynamic subtest: pipe-D-eDP-1
[587.139522] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.438s)
[587.141939] Subtest tiling-4: SUCCESS (17.212s)
[587.954730] [117/156] (436s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-wc)
[588.041976] Starting subtest: hdr-rgb565-draw-mmap-wc
[588.047633] Subtest hdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[588.134339] [118/156] (436s left) gen9_exec_parse (unaligned-jump)
[588.192475] Subtest unaligned-jump: SKIP (0.000s)
[588.271372] [119/156] (436s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[588.344412] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[588.346746] Starting dynamic subtest: pipe-A-eDP-1
[588.418807] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[588.430005] Starting dynamic subtest: pipe-B-eDP-1
[589.535523] Dynamic subtest pipe-B-eDP-1: SKIP (1.113s)
[589.540864] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.192s)
[590.721194] [120/156] (434s left) gem_exec_balancer (bonded-false-hang)
[590.976861] Starting subtest: bonded-false-hang
[590.982883] Subtest bonded-false-hang: SKIP (0.000s)
[591.062268] [121/156] (433s left) gem_exec_reloc (basic-write-cpu-active)
[591.130789] Subtest basic-write-cpu-active: SKIP (0.000s)
[591.188976] [122/156] (433s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[591.290883] Starting subtest: cursorB-vs-flipA-atomic
[591.296103] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[591.437223] [123/156] (433s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[591.519632] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[591.524386] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[591.621441] [124/156] (433s left) gem_exec_store (pages)
[591.679407] Starting subtest: pages
[591.681991] Starting dynamic subtest: rcs0
[591.776911] Dynamic subtest rcs0: SUCCESS (0.098s)
[591.787929] Starting dynamic subtest: bcs0
[591.849352] Dynamic subtest bcs0: SUCCESS (0.070s)
[591.861134] Starting dynamic subtest: vcs0
[591.922091] Dynamic subtest vcs0: SUCCESS (0.072s)
[591.932647] Starting dynamic subtest: vcs1
[591.999484] Dynamic subtest vcs1: SUCCESS (0.077s)
[592.009631] Starting dynamic subtest: vecs0
[592.067782] Dynamic subtest vecs0: SUCCESS (0.067s)
[592.079770] Starting dynamic subtest: ccs0
[592.140759] Dynamic subtest ccs0: SUCCESS (0.072s)
[592.143361] Subtest pages: SUCCESS (0.463s)
[592.331445] [125/156] (432s left) prime_vgem (fence-read-hang)
[592.402845] Subtest fence-read-hang: SKIP (0.000s)
[592.505649] [126/156] (432s left) kms_invalid_mode (bad-htotal)
[592.578653] Starting subtest: bad-htotal
[592.580968] Starting dynamic subtest: pipe-A-eDP-1
[592.859587] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[592.870977] Starting dynamic subtest: pipe-B-eDP-1
[592.873776] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[592.884724] Starting dynamic subtest: pipe-C-eDP-1
[592.887138] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[592.901373] Starting dynamic subtest: pipe-D-eDP-1
[592.903890] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[592.906702] Subtest bad-htotal: SUCCESS (0.289s)
[593.756231] [127/156] (431s left) perf (short-reads)
[593.828950] Starting subtest: short-reads
[593.843776] Subtest short-reads: SUCCESS (0.015s)
[593.887855] [128/156] (431s left) kms_psr (fbc-psr2-primary-blt)
[593.975422] Starting subtest: fbc-psr2-primary-blt
[593.977943] Starting dynamic subtest: eDP-1
[593.982645] Dynamic subtest eDP-1: SKIP (0.000s)
[593.987705] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[594.052531] [129/156] (431s left) i915_query (query-regions-garbage-items)
[594.105664] Starting subtest: query-regions-garbage-items
[594.108187] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[594.232822] [130/156] (431s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[594.312770] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc
[594.317811] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[594.400323] [131/156] (430s left) gem_busy (extended)
[594.579798] Starting subtest: extended
[594.582802] Starting dynamic subtest: rcs0
[594.664435] Dynamic subtest rcs0: SUCCESS (0.088s)
[594.681102] Starting dynamic subtest: bcs0
[594.709578] Dynamic subtest bcs0: SUCCESS (0.043s)
[594.721683] Starting dynamic subtest: vcs0
[596.275249] Dynamic subtest vcs0: SUCCESS (1.563s)
[596.289218] Starting dynamic subtest: vcs1
[598.259489] Dynamic subtest vcs1: SUCCESS (1.981s)
[598.272510] Starting dynamic subtest: vecs0
[600.263457] Dynamic subtest vecs0: SUCCESS (1.983s)
[600.274687] Starting dynamic subtest: ccs0
[600.289972] Dynamic subtest ccs0: SUCCESS (0.043s)
[600.292861] Subtest extended: SUCCESS (5.715s)
[600.516708] [132/156] (424s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render)
[600.608249] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render
[600.612847] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[600.702194] [133/156] (424s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-render)
[600.780873] Starting subtest: fbcpsrhdr-rgb565-draw-render
[600.785397] Subtest fbcpsrhdr-rgb565-draw-render: SKIP (0.000s)
[600.873579] [134/156] (424s left) device_reset (cold-reset-bound)
[600.923775] Starting subtest: cold-reset-bound
[600.929768] Subtest cold-reset-bound: SKIP (0.000s)
[600.995988] [135/156] (424s left) gem_mmap_offset (perf)
[601.051746] Starting subtest: perf
[601.054648] Starting dynamic subtest: smem0
[601.409974] Dynamic subtest smem0: SUCCESS (0.358s)
[601.412614] Subtest perf: SUCCESS (0.359s)
[601.520520] [136/156] (424s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[601.596748] Starting subtest: y-tiled-8bpp-rotate-270
[601.601070] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[601.682304] [137/156] (423s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[601.747871] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[601.753355] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[601.822201] [138/156] (423s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[602.197561] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[602.983518] [139/156] (422s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[603.060793] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[603.065721] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[603.160849] [140/156] (422s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[603.240390] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[603.245407] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[603.334943] [141/156] (422s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-render)
[603.426930] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-render
[603.432344] Subtest hdr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[603.539470] [142/156] (422s left) gem_exec_reloc (basic-wc)
[603.596853] Subtest basic-wc: SKIP (0.000s)
[603.679333] [143/156] (422s left) i915_pm_rps (min-max-config-idle)
[603.736950] Starting subtest: min-max-config-idle
[603.742645] Subtest min-max-config-idle: SKIP (0.001s)
[603.854594] [144/156] (422s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[603.930116] Starting subtest: fbcpsr-rgb565-draw-blt
[611.038462] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.115s)
[611.164301] [145/156] (414s left) kms_cursor_legacy (single-bo)
[611.261640] Starting subtest: single-bo
[611.263732] Starting dynamic subtest: pipe-A
[616.643890] Dynamic subtest pipe-A: SUCCESS (5.380s)
[616.656877] Starting dynamic subtest: pipe-B
[622.024962] Dynamic subtest pipe-B: SUCCESS (5.379s)
[622.037917] Starting dynamic subtest: pipe-C
[627.405130] Dynamic subtest pipe-C: SUCCESS (5.378s)
[627.417888] Starting dynamic subtest: pipe-D
[632.786556] Dynamic subtest pipe-D: SUCCESS (5.379s)
[632.798931] Starting dynamic subtest: all-pipes
[638.173879] Dynamic subtest all-pipes: SUCCESS (5.386s)
[638.176625] Subtest single-bo: SUCCESS (26.915s)
[638.319739] [146/156] (387s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-move)
[638.409624] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-move
[638.414893] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[638.487292] [147/156] (387s left) kms_cursor_crc (cursor-offscreen-128x42)
[638.689949] Starting subtest: cursor-offscreen-128x42
[638.737550] Subtest cursor-offscreen-128x42: SKIP (0.042s)
[638.935023] [148/156] (387s left) gem_render_copy (linear)
[638.994613] Starting subtest: linear
[638.997193] Starting dynamic subtest: smem
[639.035703] Dynamic subtest smem: SUCCESS (0.040s)
[639.038427] Subtest linear: SUCCESS (0.041s)
[639.216755] [149/156] (386s left) api_intel_allocator (reopen-fork)
[639.269605] Starting subtest: reopen-fork
[642.539771] Subtest reopen-fork: SUCCESS (3.271s)
[642.616468] [150/156] (383s left) gem_ctx_persistence (legacy-engines-hostile)
[642.670953] Starting subtest: legacy-engines-hostile
[642.673849] Starting dynamic subtest: render
[642.809371] Dynamic subtest render: SUCCESS (0.138s)
[642.824422] Starting dynamic subtest: bsd1
[642.906170] Dynamic subtest bsd1: SUCCESS (0.094s)
[642.919315] Starting dynamic subtest: bsd2
[643.000220] Dynamic subtest bsd2: SUCCESS (0.092s)
[643.013084] Starting dynamic subtest: blt
[643.112412] Dynamic subtest blt: SUCCESS (0.109s)
[643.124977] Starting dynamic subtest: vebox
[643.217124] Dynamic subtest vebox: SUCCESS (0.102s)
[643.219898] Subtest legacy-engines-hostile: SUCCESS (0.548s)
[643.330793] [151/156] (382s left) sysfs_timeslice_duration (timeout)
[643.384481] Starting subtest: timeout
[643.387258] Starting dynamic subtest: vecs0
[653.293960] Dynamic subtest vecs0: SUCCESS (9.906s)
[653.307955] Starting dynamic subtest: ccs0
[654.584757] Dynamic subtest ccs0: SUCCESS (1.292s)
[654.595502] Starting dynamic subtest: vcs0
[664.239038] Dynamic subtest vcs0: SUCCESS (9.651s)
[664.252628] Starting dynamic subtest: bcs0
[664.859489] Dynamic subtest bcs0: SUCCESS (0.600s)
[664.879880] Starting dynamic subtest: rcs0
[666.110544] Dynamic subtest rcs0: SUCCESS (1.269s)
[666.121284] Starting dynamic subtest: vcs1
[675.264641] Dynamic subtest vcs1: SUCCESS (9.137s)
[675.267532] Subtest timeout: SUCCESS (31.868s)
[675.330575] [152/156] (350s left) gem_cs_tlb (engines)
[675.391523] Starting subtest: engines
[675.393937] Starting dynamic subtest: rcs0
[677.548954] Dynamic subtest rcs0: SUCCESS (2.154s)
[677.559148] Starting dynamic subtest: bcs0
[679.702493] Dynamic subtest bcs0: SUCCESS (2.152s)
[679.714639] Starting dynamic subtest: vcs0
[681.857593] Dynamic subtest vcs0: SUCCESS (2.153s)
[681.869265] Starting dynamic subtest: vcs1
[684.009190] Dynamic subtest vcs1: SUCCESS (2.151s)
[684.021861] Starting dynamic subtest: vecs0
[686.161977] Dynamic subtest vecs0: SUCCESS (2.152s)
[686.173274] Starting dynamic subtest: ccs0
[688.316100] Dynamic subtest ccs0: SUCCESS (2.154s)
[688.318801] Subtest engines: SUCCESS (12.924s)
[688.429083] [153/156] (337s left) kms_flip (2x-flip-vs-suspend)
[688.510893] Starting subtest: 2x-flip-vs-suspend
[688.515788] Subtest 2x-flip-vs-suspend: SKIP (0.002s)
[688.614498] [154/156] (337s left) kms_rotation_crc (sprite-rotation-90)
[688.692443] Starting subtest: sprite-rotation-90
[688.697644] Subtest sprite-rotation-90: SKIP (0.001s)
[688.775654] [155/156] (337s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[688.823945] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[688.927869] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.103s)
[689.006101] [156/156] (337s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[689.077818] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[689.757088] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.681s)
[689.903937] Closing watchdogs
results: parsing output: 0/ for test: gen7_exec_parse
results: parsing output: 1/ for test: gem_eio
results: parsing output: 2/ for test: kms_content_protection
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: syncobj_eventfd
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: kms_psr2_sf
results: parsing output: 7/ for test: kms_flip_scaled_crc
results: parsing output: 8/ for test: gem_render_copy
results: parsing output: 9/ for test: gem_ctx_param
results: parsing output: 10/ for test: kms_psr
results: parsing output: 11/ for test: gem_spin_batch
results: parsing output: 12/ for test: kms_setmode
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: gem_pread
results: parsing output: 15/ for test: gem_pxp
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_mmap_gtt
results: parsing output: 19/ for test: gem_lmem_swapping
results: parsing output: 20/ for test: gem_eio
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_getfb
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_pm_rpm
results: parsing output: 25/ for test: kms_cursor_crc
results: parsing output: 26/ for test: gem_ctx_persistence
results: parsing output: 27/ for test: syncobj_timeline
results: parsing output: 28/ for test: kms_async_flips
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_addfb_basic
results: parsing output: 31/ for test: perf
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_dsc
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_atomic_transition
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: perf
results: parsing output: 38/ for test: kms_sequence
results: parsing output: 39/ for test: gem_exec_fence
results: parsing output: 40/ for test: kms_plane_scaling
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_addfb_basic
results: parsing output: 43/ for test: perf_pmu
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_exec_schedule
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_vblank
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: i915_pm_freq_mult
results: parsing output: 51/ for test: kms_hdmi_inject
results: parsing output: 52/ for test: kms_cursor_crc
results: parsing output: 53/ for test: gem_exec_schedule
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: prime_mmap
results: parsing output: 57/ for test: kms_psr
results: parsing output: 58/ for test: api_intel_bb
results: parsing output: 59/ for test: kms_chamelium_frames
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: kms_flip
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_color
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: syncobj_basic
results: parsing output: 72/ for test: kms_pwrite_crc
results: parsing output: 73/ for test: kms_plane_scaling
results: parsing output: 74/ for test: kms_flip
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: gem_readwrite
results: parsing output: 77/ for test: syncobj_timeline
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_chamelium_color
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_cursor_legacy
results: parsing output: 84/ for test: syncobj_wait
results: parsing output: 85/ for test: gem_exec_parallel
results: parsing output: 86/ for test: kms_flip
results: parsing output: 87/ for test: kms_pipe_stress
results: parsing output: 88/ for test: gem_exec_capture
results: parsing output: 89/ for test: gem_ctx_shared
results: parsing output: 90/ for test: kms_flip
results: parsing output: 91/ for test: gem_mmap_wc
results: parsing output: 92/ for test: kms_pm_rpm
results: parsing output: 93/ for test: kms_flip
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_flip
results: parsing output: 96/ for test: kms_lease
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_async_flips
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_exec_flush
results: parsing output: 101/ for test: gem_fence_thrash
results: parsing output: 102/ for test: gem_mmap_gtt
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: kms_flip
results: parsing output: 109/ for test: gem_mmap_gtt
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: perf_pmu
results: parsing output: 113/ for test: kms_busy
results: parsing output: 114/ for test: gem_wait
results: parsing output: 115/ for test: kms_plane_multiple
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: gen9_exec_parse
results: parsing output: 118/ for test: kms_psr2_sf
results: parsing output: 119/ for test: gem_exec_balancer
results: parsing output: 120/ for test: gem_exec_reloc
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_exec_store
results: parsing output: 124/ for test: prime_vgem
results: parsing output: 125/ for test: kms_invalid_mode
results: parsing output: 126/ for test: perf
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: i915_query
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_busy
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: device_reset
results: parsing output: 134/ for test: gem_mmap_offset
results: parsing output: 135/ for test: kms_big_fb
results: parsing output: 136/ for test: kms_plane
results: parsing output: 137/ for test: kms_chamelium_hpd
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: gem_exec_reloc
results: parsing output: 142/ for test: i915_pm_rps
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: kms_cursor_legacy
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_cursor_crc
results: parsing output: 147/ for test: gem_render_copy
results: parsing output: 148/ for test: api_intel_allocator
results: parsing output: 149/ for test: gem_ctx_persistence
results: parsing output: 150/ for test: sysfs_timeslice_duration
results: parsing output: 151/ for test: gem_cs_tlb
results: parsing output: 152/ for test: kms_flip
results: parsing output: 153/ for test: kms_rotation_crc
results: parsing output: 154/ for test: syncobj_timeline
results: parsing output: 155/ for test: kms_big_fb
Done.