[60.483211] Initializing watchdogs
[60.483260]   /dev/watchdog0
[60.497417] [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]
[60.501776] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.503960] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.507306] [001/131] (960s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[60.623347] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[60.625738] Starting dynamic subtest: pipe-A-eDP-1
[61.077374] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.453s)
[61.086246] Starting dynamic subtest: pipe-B-eDP-1
[61.103710] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[61.110070] Starting dynamic subtest: pipe-C-eDP-1
[61.126944] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[61.133051] Starting dynamic subtest: pipe-D-eDP-1
[61.149190] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[61.150954] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.466s)
[63.397602] [002/131] (957s left) i915_query (query-regions-sanity-check)
[63.455789] Starting subtest: query-regions-sanity-check
[63.466760] Subtest query-regions-sanity-check: SUCCESS (0.013s)
[63.617597] [003/131] (957s left) gem_exec_whisper (basic-fds)
[63.682316] Starting subtest: basic-fds
[70.900139] Subtest basic-fds: SUCCESS (7.214s)
[71.165531] [004/131] (950s left) gem_exec_schedule (u-semaphore-codependency)
[71.340910] Starting subtest: u-semaphore-codependency
[71.364620] Subtest u-semaphore-codependency: SUCCESS (0.027s)
[71.826683] [005/131] (949s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[71.934057] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[71.936317] Starting dynamic subtest: pipe-A-eDP-1
[73.209207] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.276s)
[73.218705] Starting dynamic subtest: pipe-B-eDP-1
[75.536202] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.326s)
[75.543861] Starting dynamic subtest: pipe-C-eDP-1
[77.862494] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.326s)
[77.871810] Starting dynamic subtest: pipe-D-eDP-1
[80.190292] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.325s)
[80.192478] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (8.256s)
[81.421552] [006/131] (939s left) kms_pm_rpm (cursor)
[83.042324] Starting subtest: cursor
[83.047317] Subtest cursor: SKIP (0.000s)
[83.657169] [007/131] (937s left) kms_chamelium_edid (vga-edid-read)
[84.048675] Subtest vga-edid-read: SKIP (0.000s)
[84.842615] [008/131] (936s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[84.894859] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[84.896822] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[85.007359] [009/131] (936s left) kms_getfb (getfb-handle-closed)
[85.096066] Starting subtest: getfb-handle-closed
[85.098402] Subtest getfb-handle-closed: SUCCESS (0.000s)
[85.225193] [010/131] (936s left) gem_userptr_blits (map-fixed-invalidate)
[85.301221] Starting subtest: map-fixed-invalidate
[85.305130] Subtest map-fixed-invalidate: SKIP (0.000s)
[85.459998] [011/131] (935s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[85.548732] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[85.560075] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[85.689590] [012/131] (935s left) kms_universal_plane (cursor-fb-leak)
[85.781659] Starting subtest: cursor-fb-leak
[85.783953] Starting dynamic subtest: pipe-A-eDP-1
[86.097827] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.317s)
[86.108787] Starting dynamic subtest: pipe-B-eDP-1
[87.467373] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.368s)
[87.475246] Starting dynamic subtest: pipe-C-eDP-1
[88.782116] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.313s)
[88.791853] Starting dynamic subtest: pipe-D-eDP-1
[90.133270] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.352s)
[90.135544] Subtest cursor-fb-leak: SUCCESS (4.354s)
[91.325964] [013/131] (930s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf)
[96.405934] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf
[96.408312] Starting dynamic subtest: pipe-A-eDP-1
[107.831596] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.425s)
[107.842295] Starting dynamic subtest: pipe-B-eDP-1
[119.275117] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.443s)
[119.277599] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.871s)
[120.135123] [014/131] (901s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[120.240706] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[121.229114] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.989s)
[121.370152] [015/131] (900s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[126.418977] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[126.423497] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.003s)
[127.275825] [016/131] (894s left) kms_fb_coherency (memset-crc)
[127.373753] Starting subtest: memset-crc
[127.375973] Starting dynamic subtest: mmap-offset-wc
[127.759280] Dynamic subtest mmap-offset-wc: SUCCESS (0.387s)
[128.059289] Starting dynamic subtest: mmap-offset-uc
[134.197479] Dynamic subtest mmap-offset-uc: SUCCESS (6.147s)
[134.488922] Subtest memset-crc: SUCCESS (7.115s)
[135.349467] [017/131] (886s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[135.413831] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[135.520723] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.107s)
[135.620134] [018/131] (886s left) gem_watchdog (default-physical)
[135.687020] Starting subtest: default-physical
[137.442399] Subtest default-physical: SUCCESS (1.768s)
[137.555868] [019/131] (884s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[137.648049] Starting subtest: flip-primary-invalidate-overlay
[149.215935] Subtest flip-primary-invalidate-overlay: SUCCESS (11.570s)
[149.409714] [020/131] (872s left) kms_flip (busy-flip)
[149.509352] Starting subtest: busy-flip
[149.511446] Starting dynamic subtest: A-eDP1
[151.238058] Dynamic subtest A-eDP1: SUCCESS (1.727s)
[151.246442] Starting dynamic subtest: B-eDP1
[152.890828] Dynamic subtest B-eDP1: SUCCESS (1.652s)
[152.900361] Starting dynamic subtest: C-eDP1
[154.519183] Dynamic subtest C-eDP1: SUCCESS (1.627s)
[154.529349] Starting dynamic subtest: D-eDP1
[156.161981] Dynamic subtest D-eDP1: SUCCESS (1.642s)
[156.167542] Subtest busy-flip: SUCCESS (6.655s)
[157.335162] [021/131] (864s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[157.432141] Starting subtest: fbc-psr2-cursor-mmap-cpu
[157.434248] Starting dynamic subtest: eDP-1
[157.438480] Dynamic subtest eDP-1: SKIP (0.000s)
[157.442668] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[157.513987] [022/131] (864s left) gem_flink_basic (bad-flink)
[157.571227] Starting subtest: bad-flink
[157.573480] Subtest bad-flink: SUCCESS (0.000s)
[157.684791] [023/131] (864s left) gem_mmap (bad-object)
[157.742858] Subtest bad-object: SKIP (0.000s)
[157.800147] [024/131] (864s left) kms_psr (psr2-sprite-plane-move)
[157.899087] Starting subtest: psr2-sprite-plane-move
[157.901385] Starting dynamic subtest: eDP-1
[159.645671] Dynamic subtest eDP-1: SUCCESS (1.748s)
[159.647990] Subtest psr2-sprite-plane-move: SUCCESS (1.748s)
[160.500987] [025/131] (861s left) gen9_exec_parse (bb-chained)
[160.566073] Subtest bb-chained: SKIP (0.000s)
[160.628441] [026/131] (861s left) gem_partial_pwrite_pread (write-display)
[160.694641] Subtest write-display: SKIP (0.000s)
[160.787070] [027/131] (861s left) kms_plane_scaling (planes-downscale-factor-0-75)
[160.883835] Starting subtest: planes-downscale-factor-0-75
[160.886101] Starting dynamic subtest: pipe-A
[160.906272] Dynamic subtest pipe-A: SKIP (0.018s)
[160.920329] Starting dynamic subtest: pipe-B
[160.941251] Dynamic subtest pipe-B: SKIP (0.036s)
[160.949587] Starting dynamic subtest: pipe-C
[160.972050] Dynamic subtest pipe-C: SKIP (0.032s)
[160.979620] Starting dynamic subtest: pipe-D
[161.004684] Dynamic subtest pipe-D: SKIP (0.031s)
[161.008555] Subtest planes-downscale-factor-0-75: SKIP (0.117s)
[161.099846] [028/131] (860s left) gem_exec_schedule (preempt-engines)
[161.164558] Starting subtest: preempt-engines
[161.166772] Starting dynamic subtest: rcs0
[163.414189] Dynamic subtest rcs0: SUCCESS (2.253s)
[163.428402] Starting dynamic subtest: bcs0
[165.728460] Dynamic subtest bcs0: SUCCESS (2.311s)
[165.741571] Starting dynamic subtest: vcs0
[168.003616] Dynamic subtest vcs0: SUCCESS (2.275s)
[168.015375] Starting dynamic subtest: vcs1
[170.247483] Dynamic subtest vcs1: SUCCESS (2.242s)
[170.256333] Starting dynamic subtest: vecs0
[172.493696] Dynamic subtest vecs0: SUCCESS (2.245s)
[172.506603] Starting dynamic subtest: ccs0
[174.787948] Dynamic subtest ccs0: SUCCESS (2.294s)
[174.789988] Subtest preempt-engines: SUCCESS (13.625s)
[175.094020] [029/131] (847s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[175.197931] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[175.200262] Starting dynamic subtest: pipe-A-eDP-1
[175.266748] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.070s)
[175.560124] Starting dynamic subtest: pipe-B-eDP-1
[176.393698] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.834s)
[176.688655] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.490s)
[177.558681] [030/131] (844s left) kms_plane_cursor (viewport)
[177.651049] Starting subtest: viewport
[177.655380] Starting dynamic subtest: pipe-A-eDP-1-size-64
[180.403985] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.752s)
[180.462567] Starting dynamic subtest: pipe-A-eDP-1-size-128
[183.180609] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.726s)
[183.241117] Starting dynamic subtest: pipe-A-eDP-1-size-256
[185.996525] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.760s)
[186.342931] Starting dynamic subtest: pipe-B-eDP-1-size-64
[189.834363] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.502s)
[189.893473] Starting dynamic subtest: pipe-B-eDP-1-size-128
[192.690828] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.804s)
[192.750806] Starting dynamic subtest: pipe-B-eDP-1-size-256
[195.525427] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.782s)
[195.872303] Starting dynamic subtest: pipe-C-eDP-1-size-64
[198.955964] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.091s)
[198.990998] Starting dynamic subtest: pipe-C-eDP-1-size-128
[201.398899] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.413s)
[201.439133] Starting dynamic subtest: pipe-C-eDP-1-size-256
[203.789083] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.357s)
[204.115115] Starting dynamic subtest: pipe-D-eDP-1-size-64
[207.205155] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.099s)
[207.247679] Starting dynamic subtest: pipe-D-eDP-1-size-128
[209.627073] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.391s)
[209.664303] Starting dynamic subtest: pipe-D-eDP-1-size-256
[212.015171] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.358s)
[212.337958] Subtest viewport: SUCCESS (34.686s)
[213.186415] [031/131] (808s left) syncobj_timeline (single-wait-available-submitted)
[213.245402] Starting subtest: single-wait-available-submitted
[213.247577] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[213.369853] [032/131] (808s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[213.495382] Starting subtest: basic-busy-flip-before-cursor-atomic
[213.499740] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[213.660759] [033/131] (808s left) kms_vblank (wait-forked-busy-hang)
[213.748051] Starting subtest: wait-forked-busy-hang
[213.749961] Starting dynamic subtest: pipe-A-eDP-1
[240.663512] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.913s)
[240.677212] Starting dynamic subtest: pipe-D-eDP-1
[255.710829] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.043s)
[255.713065] Subtest wait-forked-busy-hang: SUCCESS (41.962s)
[256.566135] [034/131] (765s left) kms_frontbuffer_tracking (basic)
[256.658825] Starting subtest: basic
[259.820618] Subtest basic: SUCCESS (3.165s)
[259.967119] [035/131] (762s left) api_intel_bb (object-reloc-keep-cache)
[260.028271] Starting subtest: object-reloc-keep-cache
[260.035865] Subtest object-reloc-keep-cache: SKIP (0.003s)
[260.165572] [036/131] (762s left) kms_content_protection (mei-interface)
[260.307366] Starting subtest: mei-interface
[260.311284] Subtest mei-interface: SKIP (0.001s)
[260.472953] [037/131] (761s left) kms_psr (fbc-psr-primary-page-flip)
[260.557124] Starting subtest: fbc-psr-primary-page-flip
[260.558934] Starting dynamic subtest: eDP-1
[260.562365] Dynamic subtest eDP-1: SKIP (0.000s)
[260.565787] Subtest fbc-psr-primary-page-flip: SKIP (0.001s)
[260.654656] [038/131] (761s left) kms_lease (atomic-implicit-crtc)
[260.741080] Starting subtest: atomic-implicit-crtc
[260.743155] Starting dynamic subtest: pipe-A-eDP-1
[260.753291] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[260.762832] Starting dynamic subtest: pipe-B-eDP-1
[260.772530] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.008s)
[260.779610] Starting dynamic subtest: pipe-C-eDP-1
[260.788194] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.008s)
[260.795803] Starting dynamic subtest: pipe-D-eDP-1
[260.804354] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.008s)
[260.805887] Subtest atomic-implicit-crtc: SUCCESS (0.032s)
[260.893410] [039/131] (761s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[261.000042] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[261.003623] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[261.117015] [040/131] (761s left) kms_cursor_crc (cursor-alpha-transparent)
[261.219279] Starting subtest: cursor-alpha-transparent
[261.220846] Starting dynamic subtest: pipe-A-eDP-1
[261.840259] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.619s)
[261.848450] Starting dynamic subtest: pipe-D-eDP-1
[263.147045] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.306s)
[263.149321] Subtest cursor-alpha-transparent: SUCCESS (1.927s)
[263.953540] [041/131] (758s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[264.042128] Starting subtest: plane-upscale-20x20-with-pixel-format
[264.044637] Starting dynamic subtest: pipe-A
[265.091742] Dynamic subtest pipe-A: SUCCESS (1.050s)
[265.099501] Starting dynamic subtest: pipe-B
[267.252228] Dynamic subtest pipe-B: SUCCESS (2.160s)
[267.260997] Starting dynamic subtest: pipe-C
[268.461448] Dynamic subtest pipe-C: SUCCESS (1.208s)
[268.471685] Starting dynamic subtest: pipe-D
[269.598773] Dynamic subtest pipe-D: SUCCESS (1.137s)
[269.601067] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.557s)
[270.817110] [042/131] (751s left) gem_exec_params (invalid-bsd2-flag-on-render)
[270.888500] Starting subtest: invalid-bsd2-flag-on-render
[270.890785] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[271.028430] [043/131] (751s left) gem_ctx_shared (exec-shared-gtt)
[271.090030] Starting subtest: exec-shared-gtt
[271.092444] Starting dynamic subtest: rcs0
[271.097596] Dynamic subtest rcs0: SUCCESS (0.007s)
[271.107346] Starting dynamic subtest: bcs0
[271.109202] Dynamic subtest bcs0: SUCCESS (0.005s)
[271.117646] Starting dynamic subtest: vcs0
[271.119800] Dynamic subtest vcs0: SUCCESS (0.007s)
[271.127922] Starting dynamic subtest: vcs1
[271.130042] Dynamic subtest vcs1: SUCCESS (0.007s)
[271.137602] Starting dynamic subtest: vecs0
[271.139498] Dynamic subtest vecs0: SUCCESS (0.006s)
[271.146791] Starting dynamic subtest: ccs0
[271.148590] Dynamic subtest ccs0: SUCCESS (0.005s)
[271.150479] Subtest exec-shared-gtt: SUCCESS (0.040s)
[271.328229] [044/131] (751s left) prime_self_import (export-vs-gem_close-race)
[271.356986] Starting subtest: export-vs-gem_close-race
[276.464269] Subtest export-vs-gem_close-race: SUCCESS (5.105s)
[276.559701] [045/131] (746s left) kms_cursor_crc (cursor-random-512x170)
[276.869604] Starting subtest: cursor-random-512x170
[276.873538] Subtest cursor-random-512x170: SKIP (0.001s)
[277.048494] [046/131] (745s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[277.102475] Starting subtest: x-tiled-to-vebox-yf-tiled
[277.106949] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[277.290181] [047/131] (745s left) kms_async_flips (test-cursor-atomic)
[277.379164] Starting subtest: test-cursor-atomic
[277.383226] Subtest test-cursor-atomic: SKIP (0.000s)
[278.641078] [048/131] (744s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[278.707313] Starting subtest: rel-constants-invalid-rel-gen5
[278.709774] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[278.843431] [049/131] (744s left) perf (blocking)
[278.980722] Starting subtest: blocking
[278.982873] Starting dynamic subtest: 0-rcs0
[289.007804] Dynamic subtest 0-rcs0: SUCCESS (10.026s)
[289.016075] Starting dynamic subtest: 1-vcs0
[299.029125] Dynamic subtest 1-vcs0: SUCCESS (10.020s)
[299.031978] Subtest blocking: SUCCESS (20.048s)
[299.075800] [050/131] (723s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[299.173679] Starting subtest: psr-2p-pri-indfb-multidraw
[299.178057] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[299.259962] [051/131] (723s left) kms_cursor_legacy (single-bo)
[299.384141] Starting subtest: single-bo
[299.386337] Starting dynamic subtest: pipe-A
[304.766753] Dynamic subtest pipe-A: SUCCESS (5.380s)
[304.775984] Starting dynamic subtest: pipe-B
[310.150763] Dynamic subtest pipe-B: SUCCESS (5.380s)
[310.161146] Starting dynamic subtest: pipe-C
[315.531543] Dynamic subtest pipe-C: SUCCESS (5.379s)
[315.541816] Starting dynamic subtest: pipe-D
[320.914948] Dynamic subtest pipe-D: SUCCESS (5.379s)
[320.924671] Starting dynamic subtest: all-pipes
[326.303446] Dynamic subtest all-pipes: SUCCESS (5.387s)
[326.305940] Subtest single-bo: SUCCESS (26.916s)
[326.456164] [052/131] (696s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[326.561425] Starting subtest: 2x-plain-flip-ts-check-interruptible
[326.573485] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.011s)
[326.693411] [053/131] (696s left) gem_mmap_wc (read-write-distinct)
[326.752517] Subtest read-write-distinct: SKIP (0.000s)
[326.818339] [054/131] (696s left) gem_exec_whisper (basic-queues-priority)
[326.886753] Starting subtest: basic-queues-priority
[349.998109] Subtest basic-queues-priority: SUCCESS (23.105s)
[350.200475] [055/131] (672s left) gem_ccs (suspend-resume)
[350.265801] Starting subtest: suspend-resume
[350.270370] Subtest suspend-resume: SKIP (0.000s)
[350.409850] [056/131] (672s left) i915_drm_fdinfo (idle)
[350.445105] Starting subtest: idle
[350.447053] Starting dynamic subtest: rcs0
[351.032770] Dynamic subtest rcs0: SUCCESS (0.588s)
[351.043827] Starting dynamic subtest: bcs0
[351.539605] Dynamic subtest bcs0: SUCCESS (0.503s)
[351.548268] Starting dynamic subtest: vcs0
[352.044899] Dynamic subtest vcs0: SUCCESS (0.503s)
[352.055713] Starting dynamic subtest: vcs1
[352.550245] Dynamic subtest vcs1: SUCCESS (0.503s)
[352.560061] Starting dynamic subtest: vecs0
[353.055409] Dynamic subtest vecs0: SUCCESS (0.503s)
[353.064664] Starting dynamic subtest: ccs0
[353.561603] Dynamic subtest ccs0: SUCCESS (0.503s)
[353.564283] Subtest idle: SUCCESS (3.117s)
[353.664564] [057/131] (669s left) gem_exec_balancer (fairslice)
[353.726216] Starting subtest: fairslice
[356.619387] Subtest fairslice: SUCCESS (2.891s)
[356.975360] [058/131] (666s left) kms_joiner (basic-force-ultra-joiner)
[357.083076] Starting subtest: basic-force-ultra-joiner
[357.087676] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[357.160437] [059/131] (666s left) i915_pm_freq_api (freq-basic-api)
[357.234617] Starting subtest: freq-basic-api
[357.237046] Starting dynamic subtest: gt0
[357.239404] Dynamic subtest gt0: SUCCESS (0.003s)
[357.249802] Starting dynamic subtest: gt1
[357.252079] Dynamic subtest gt1: SUCCESS (0.002s)
[357.254288] Subtest freq-basic-api: SUCCESS (0.006s)
[357.334860] [060/131] (665s left) gem_caching (reads)
[357.406417] Subtest reads: SKIP (0.000s)
[357.488580] [061/131] (665s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[357.551840] Starting subtest: yf-tiled-ccs-to-y-tiled
[357.556441] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[357.745416] [062/131] (665s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[357.839167] Starting subtest: bad-aux-stride-y-tiled-ccs
[357.841434] Starting dynamic subtest: pipe-A-eDP-1
[357.863754] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[357.873449] Starting dynamic subtest: pipe-B-eDP-1
[357.895068] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[357.902999] Starting dynamic subtest: pipe-C-eDP-1
[357.924393] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[357.931838] Starting dynamic subtest: pipe-D-eDP-1
[357.952698] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[357.956807] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[360.089398] [063/131] (663s left) gem_tiled_fence_blits (basic)
[360.151120] Subtest basic: SKIP (0.000s)
[360.216013] [064/131] (663s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[360.311208] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[360.315135] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[360.438537] [065/131] (663s left) gem_mmap (basic-small-bo)
[360.497465] Subtest basic-small-bo: SKIP (0.000s)
[360.555259] [066/131] (663s left) prime_mmap_kms (buffer-sharing)
[360.644784] Starting subtest: buffer-sharing
[361.090469] Subtest buffer-sharing: SUCCESS (0.446s)
[361.948919] [067/131] (661s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[362.041407] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[368.923446] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.884s)
[369.104016] [068/131] (654s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[369.206996] Starting subtest: fbcpsr-modesetfrombusy
[375.320029] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.117s)
[375.488953] [069/131] (648s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[375.593331] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[375.597133] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[375.713157] [070/131] (648s left) dmabuf (all-tests)
[375.744806] Starting subtest: all-tests
[375.746503] Starting dynamic subtest: sanitycheck
[375.905848] Dynamic subtest sanitycheck: SUCCESS (0.161s)
[375.922669] Starting dynamic subtest: dma_fence
[376.182327] Dynamic subtest dma_fence: SUCCESS (0.274s)
[376.193249] Starting dynamic subtest: dma_fence_chain
[384.658156] Dynamic subtest dma_fence_chain: SUCCESS (8.473s)
[384.669407] Starting dynamic subtest: dma_fence_unwrap
[384.783393] Dynamic subtest dma_fence_unwrap: SUCCESS (0.123s)
[384.794240] Starting dynamic subtest: dma_resv
[384.908522] Dynamic subtest dma_resv: SUCCESS (0.123s)
[384.910982] Subtest all-tests: SUCCESS (9.165s)
[384.983131] [071/131] (638s left) gem_exec_schedule (fairslice-all)
[385.050072] Starting subtest: fairslice-all
[387.124173] Subtest fairslice-all: SUCCESS (2.077s)
[389.009904] [072/131] (634s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[389.065766] Starting subtest: multi-wait-available-unsubmitted-submitted
[389.068169] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[389.195295] [073/131] (634s left) gem_exec_fence (syncobj-repeat)
[389.652008] Starting subtest: syncobj-repeat
[389.862071] Subtest syncobj-repeat: SUCCESS (0.186s)
[391.071619] [FACT gem_exec_fence (syncobj-repeat)] new: kernel.kmod_is_loaded.vgem: true
[391.074734] [074/131] (632s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[391.175345] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[397.937646] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.765s)
[398.109531] [075/131] (625s left) gem_exec_reloc (basic-gtt-cpu)
[398.174663] Subtest basic-gtt-cpu: SKIP (0.000s)
[398.253990] [076/131] (625s left) kms_color (ctm-0-50)
[398.341672] Starting subtest: ctm-0-50
[398.343893] Starting dynamic subtest: pipe-A-eDP-1
[401.772442] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.430s)
[401.785703] Starting dynamic subtest: pipe-B-eDP-1
[405.957582] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.185s)
[405.966518] Starting dynamic subtest: pipe-C-eDP-1
[410.007364] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.051s)
[410.015931] Starting dynamic subtest: pipe-D-eDP-1
[414.004600] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.995s)
[414.007116] Subtest ctm-0-50: SUCCESS (15.664s)
[414.857437] [077/131] (609s left) kms_cdclk (mode-transition-all-outputs)
[414.965574] Starting subtest: mode-transition-all-outputs
[415.251615] Subtest mode-transition-all-outputs: SKIP (0.285s)
[416.100411] [078/131] (607s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[416.207336] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[423.184894] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (6.982s)
[423.345538] [079/131] (600s left) prime_busy (after)
[423.402779] Starting subtest: after
[423.407641] Starting dynamic subtest: rcs0
[423.459245] Dynamic subtest rcs0: SUCCESS (0.053s)
[423.468090] Starting dynamic subtest: bcs0
[423.526871] Dynamic subtest bcs0: SUCCESS (0.066s)
[423.535429] Starting dynamic subtest: vcs0
[423.566590] Dynamic subtest vcs0: SUCCESS (0.040s)
[423.574931] Starting dynamic subtest: vcs1
[423.606811] Dynamic subtest vcs1: SUCCESS (0.038s)
[423.616988] Starting dynamic subtest: vecs0
[423.646854] Dynamic subtest vecs0: SUCCESS (0.041s)
[423.656621] Starting dynamic subtest: ccs0
[423.688042] Dynamic subtest ccs0: SUCCESS (0.041s)
[423.797756] Subtest after: SUCCESS (0.392s)
[423.897437] [080/131] (600s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt)
[423.983514] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt
[423.987890] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[424.117952] [081/131] (600s left) kms_cursor_crc (cursor-suspend)
[424.251578] Starting subtest: cursor-suspend
[424.253720] Starting dynamic subtest: pipe-A-eDP-1
[458.053516] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.973s)
[458.065994] Starting dynamic subtest: pipe-D-eDP-1
[493.020274] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.433s)
[493.022468] Subtest cursor-suspend: SUCCESS (8.408s)
[503.876401] [082/131] (530s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[503.935522] Starting subtest: etime-multi-wait-all-submitted-signaled
[504.039866] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.104s)
[504.144312] [083/131] (530s left) kms_pipe_crc_basic (nonblocking-crc)
[504.229885] Starting subtest: nonblocking-crc
[504.232249] Starting dynamic subtest: pipe-A-eDP-1
[504.970099] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.740s)
[504.980545] Starting dynamic subtest: pipe-B-eDP-1
[506.335871] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.362s)
[506.346687] Starting dynamic subtest: pipe-C-eDP-1
[507.741195] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.402s)
[507.751819] Starting dynamic subtest: pipe-D-eDP-1
[509.073898] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[509.076208] Subtest nonblocking-crc: SUCCESS (4.841s)
[509.886450] [084/131] (524s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[510.002756] Starting subtest: yf-tiled-32bpp-rotate-180
[510.006885] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[510.154408] [085/131] (524s left) gem_exec_params (invalid-bsd1-flag-on-render)
[510.210986] Starting subtest: invalid-bsd1-flag-on-render
[510.213487] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[510.348297] [086/131] (524s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[510.444431] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[510.446684] Starting dynamic subtest: pipe-A-eDP-1
[510.466904] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[510.477995] Starting dynamic subtest: pipe-B-eDP-1
[510.497289] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[510.506409] Starting dynamic subtest: pipe-C-eDP-1
[510.525525] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[510.536123] Starting dynamic subtest: pipe-D-eDP-1
[510.555437] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[510.557669] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.007s)
[512.701427] [087/131] (521s left) gem_userptr_blits (unsync-overlap)
[512.769374] Subtest unsync-overlap: SKIP (0.000s)
[512.944187] [088/131] (521s left) prime_self_import (basic-with_one_bo_two_files)
[512.975550] Starting subtest: basic-with_one_bo_two_files
[513.006325] Subtest basic-with_one_bo_two_files: SUCCESS (0.029s)
[513.194176] [089/131] (521s left) i915_pm_rpm (gem-idle)
[515.420680] Starting subtest: gem-idle
[521.640067] Subtest gem-idle: SUCCESS (6.216s)
[521.995046] [090/131] (512s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[523.232973] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[523.235196] Starting dynamic subtest: pipe-A-eDP-1
[523.243965] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[523.253536] Starting dynamic subtest: pipe-B-eDP-1
[523.262287] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[523.271643] Starting dynamic subtest: pipe-C-eDP-1
[523.279823] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[523.288725] Starting dynamic subtest: pipe-D-eDP-1
[523.296878] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[523.300829] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.004s)
[525.461905] [091/131] (509s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[525.561653] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[525.563835] Starting dynamic subtest: pipe-A-default-mode
[526.700183] Dynamic subtest pipe-A-default-mode: SKIP (1.136s)
[526.704357] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.137s)
[526.914177] [092/131] (507s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[527.033609] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[527.038093] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[527.149536] [093/131] (507s left) sw_sync (alloc_merge_fence)
[527.180011] Starting subtest: alloc_merge_fence
[527.182321] Subtest alloc_merge_fence: SUCCESS (0.000s)
[527.246992] [094/131] (507s left) kms_psr (psr2-primary-blt)
[527.350231] Starting subtest: psr2-primary-blt
[527.352479] Starting dynamic subtest: eDP-1
[529.073070] Dynamic subtest eDP-1: SUCCESS (1.724s)
[529.075383] Subtest psr2-primary-blt: SUCCESS (1.724s)
[529.957426] [095/131] (504s left) gem_eio (reset-stress)
[530.199974] Starting subtest: reset-stress
[573.818881] Subtest reset-stress: SUCCESS (43.611s)
[575.319986] [096/131] (459s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[575.425844] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[575.430009] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[575.552171] [097/131] (459s left) kms_getfb (getfb-reject-nv12)
[575.646041] Starting subtest: getfb-reject-nv12
[575.648348] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[575.783853] [098/131] (459s left) kms_pm_rpm (universal-planes)
[577.305884] Starting subtest: universal-planes
[577.308747] Starting dynamic subtest: plane-33
[585.600675] Dynamic subtest plane-33: SUCCESS (8.292s)
[585.612656] Starting dynamic subtest: plane-43
[593.879824] Dynamic subtest plane-43: SUCCESS (8.277s)
[593.891688] Starting dynamic subtest: plane-53
[602.161692] Dynamic subtest plane-53: SUCCESS (8.280s)
[602.174027] Starting dynamic subtest: plane-63
[610.444276] Dynamic subtest plane-63: SUCCESS (8.279s)
[610.457093] Starting dynamic subtest: plane-73
[618.758705] Dynamic subtest plane-73: SUCCESS (8.312s)
[618.771303] Starting dynamic subtest: plane-83
[627.241365] Dynamic subtest plane-83: SUCCESS (8.481s)
[627.243969] Subtest universal-planes: SUCCESS (49.936s)
[627.634883] [099/131] (407s left) perf (blocking-parameterized)
[627.721745] Starting subtest: blocking-parameterized
[647.738834] Subtest blocking-parameterized: SUCCESS (20.013s)
[647.787868] [100/131] (387s left) syncobj_timeline (wait-zero-handles)
[647.852256] Starting subtest: wait-zero-handles
[647.854594] Subtest wait-zero-handles: SUCCESS (0.000s)
[647.982747] [101/131] (387s left) kms_async_flips (alternate-sync-async-flip)
[648.079893] Starting subtest: alternate-sync-async-flip
[648.450781] Starting dynamic subtest: pipe-A-eDP-1
[651.196354] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.745s)
[651.567076] Starting dynamic subtest: pipe-B-eDP-1
[654.331500] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.775s)
[654.723396] Starting dynamic subtest: pipe-C-eDP-1
[657.471374] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.758s)
[657.854665] Starting dynamic subtest: pipe-D-eDP-1
[660.604936] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.759s)
[660.606840] Subtest alternate-sync-async-flip: SUCCESS (12.527s)
[661.783987] [102/131] (373s left) gem_exec_suspend (basic-s3-devices)
[661.846739] Starting subtest: basic-S3-devices
[661.849105] Starting dynamic subtest: smem
[672.284005] Dynamic subtest smem: SUCCESS (10.434s)
[672.286599] Subtest basic-S3-devices: SUCCESS (10.435s)
[678.496880] [103/131] (362s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[678.556736] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[678.612222] [104/131] (362s left) kms_big_fb (y-tiled-addfb)
[678.716150] Starting subtest: y-tiled-addfb
[678.720441] Subtest y-tiled-addfb: SKIP (0.000s)
[678.839145] [105/131] (362s left) syncobj_timeline (multi-wait-all-available-submitted)
[678.885886] Starting subtest: multi-wait-all-available-submitted
[678.888186] Subtest multi-wait-all-available-submitted: SUCCESS (0.000s)
[679.013877] [106/131] (362s left) gem_softpin (safe-alignment)
[679.062493] Starting subtest: safe-alignment
[679.083169] Subtest safe-alignment: SUCCESS (0.021s)
[680.334234] [107/131] (360s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[680.432580] Starting subtest: psr-shrfb-scaledprimary
[686.178319] Subtest psr-shrfb-scaledprimary: SUCCESS (5.749s)
[686.347912] [108/131] (355s left) kms_flip (2x-flip-vs-panning-vs-hang)
[686.446034] Starting subtest: 2x-flip-vs-panning-vs-hang
[686.457261] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.011s)
[686.588958] [109/131] (354s left) perf_pmu (busy-no-semaphores)
[686.627219] Starting subtest: busy-no-semaphores
[686.629326] Starting dynamic subtest: rcs0
[687.193872] Dynamic subtest rcs0: SUCCESS (0.566s)
[687.206794] Starting dynamic subtest: bcs0
[687.743797] Dynamic subtest bcs0: SUCCESS (0.547s)
[687.755979] Starting dynamic subtest: vcs0
[688.295119] Dynamic subtest vcs0: SUCCESS (0.548s)
[688.305579] Starting dynamic subtest: vcs1
[689.757125] Dynamic subtest vcs1: SUCCESS (1.459s)
[689.769632] Starting dynamic subtest: vecs0
[691.804954] Dynamic subtest vecs0: SUCCESS (2.046s)
[691.817579] Starting dynamic subtest: ccs0
[693.790641] Dynamic subtest ccs0: SUCCESS (1.983s)
[693.793586] Subtest busy-no-semaphores: SUCCESS (7.164s)
[693.936537] [110/131] (347s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[694.047482] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[694.051638] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[694.182264] [111/131] (347s left) gem_userptr_blits (unsync-unmap)
[694.253252] Subtest unsync-unmap: SKIP (0.000s)
[694.384396] [112/131] (347s left) sw_sync (sync_busy)
[694.417798] Starting subtest: sync_busy
[694.422169] Subtest sync_busy: SUCCESS (0.004s)
[694.503404] [113/131] (347s left) gem_ctx_exec (basic-norecovery)
[694.562219] Starting subtest: basic-norecovery
[694.867371] Subtest basic-norecovery: SUCCESS (0.304s)
[695.016977] [114/131] (346s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[695.120392] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[695.123973] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[695.230033] [115/131] (346s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[695.329843] Starting subtest: x-tiled-64bpp-rotate-90
[695.349900] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[695.486391] [116/131] (346s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[695.581744] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[695.585286] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[695.720411] [117/131] (346s left) api_intel_allocator (default-alignment)
[695.773378] Starting subtest: default-alignment
[695.774987] Subtest default-alignment: SUCCESS (0.003s)
[695.933945] [118/131] (345s left) gem_pwrite (basic-self)
[695.980547] Subtest basic-self: SKIP (0.000s)
[696.040637] [119/131] (345s left) gem_busy (parallel)
[696.217971] Starting subtest: parallel
[696.220318] Starting dynamic subtest: rcs0
[698.267153] Dynamic subtest rcs0: SUCCESS (2.047s)
[698.280600] Starting dynamic subtest: bcs0
[700.250953] Dynamic subtest bcs0: SUCCESS (1.982s)
[700.263419] Starting dynamic subtest: vcs0
[702.299003] Dynamic subtest vcs0: SUCCESS (2.046s)
[702.311550] Starting dynamic subtest: vcs1
[704.284373] Dynamic subtest vcs1: SUCCESS (1.982s)
[704.296702] Starting dynamic subtest: vecs0
[706.268746] Dynamic subtest vecs0: SUCCESS (1.981s)
[706.281643] Starting dynamic subtest: ccs0
[708.252553] Dynamic subtest ccs0: SUCCESS (1.982s)
[708.255883] Subtest parallel: SUCCESS (12.033s)
[708.493208] [120/131] (333s left) kms_big_fb (linear-16bpp-rotate-0)
[708.601144] Starting subtest: linear-16bpp-rotate-0
[710.033437] Subtest linear-16bpp-rotate-0: SUCCESS (1.433s)
[710.169893] [121/131] (331s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[710.258270] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[710.260216] Starting dynamic subtest: pipe-A-default-mode
[713.015861] Dynamic subtest pipe-A-default-mode: SUCCESS (2.757s)
[713.018137] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.758s)
[713.854637] [122/131] (328s left) gem_exec_flush (basic-uc-set-default)
[713.916516] Starting subtest: basic-uc-set-default
[719.383593] Subtest basic-uc-set-default: SUCCESS (5.467s)
[719.584474] [123/131] (322s left) gem_ctx_persistence (process)
[719.639055] Starting subtest: process
[719.832784] Subtest process: SUCCESS (0.193s)
[719.925705] [124/131] (322s left) gem_exec_balancer (persistence)
[720.182070] Starting subtest: persistence
[721.080483] Subtest persistence: SUCCESS (0.897s)
[721.216146] [125/131] (320s left) syncobj_wait (invalid-wait-illegal-handle)
[721.274464] Starting subtest: invalid-wait-illegal-handle
[721.276945] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[721.409568] [126/131] (320s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[721.525139] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[721.529356] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[721.636039] [127/131] (320s left) gem_mmap_offset (clear)
[721.688601] Starting subtest: clear
[721.691014] Starting dynamic subtest: smem0
[745.561855] Dynamic subtest smem0: SUCCESS (23.868s)
[745.564101] Subtest clear: SUCCESS (23.869s)
[745.735297] [128/131] (296s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[745.897373] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[752.849298] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (6.954s)
[752.979011] [129/131] (289s left) kms_flip (2x-flip-vs-expired-vblank)
[753.090102] Starting subtest: 2x-flip-vs-expired-vblank
[753.101227] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s)
[753.209088] [130/131] (289s left) gem_exec_params (batch-first)
[753.271984] Starting subtest: batch-first
[753.278551] Subtest batch-first: SUCCESS (0.007s)
[753.423029] [131/131] (288s left) kms_psr2_su (page_flip-p010)
[753.487028] Subtest page_flip-P010: SKIP (0.000s)
[753.554321] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: i915_query
results: parsing output: 2/ for test: gem_exec_whisper
results: parsing output: 3/ for test: gem_exec_schedule
results: parsing output: 4/ for test: kms_ccs
results: parsing output: 5/ for test: kms_pm_rpm
results: parsing output: 6/ for test: kms_chamelium_edid
results: parsing output: 7/ for test: syncobj_wait
results: parsing output: 8/ for test: kms_getfb
results: parsing output: 9/ for test: gem_userptr_blits
results: parsing output: 10/ for test: kms_flip
results: parsing output: 11/ for test: kms_universal_plane
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: kms_psr2_sf
results: parsing output: 15/ for test: kms_fb_coherency
results: parsing output: 16/ for test: syncobj_timeline
results: parsing output: 17/ for test: gem_watchdog
results: parsing output: 18/ for test: kms_psr_stress_test
results: parsing output: 19/ for test: kms_flip
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: gem_flink_basic
results: parsing output: 22/ for test: gem_mmap
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: gen9_exec_parse
results: parsing output: 25/ for test: gem_partial_pwrite_pread
results: parsing output: 26/ for test: kms_plane_scaling
results: parsing output: 27/ for test: gem_exec_schedule
results: parsing output: 28/ for test: kms_atomic_transition
results: parsing output: 29/ for test: kms_plane_cursor
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: kms_cursor_legacy
results: parsing output: 32/ for test: kms_vblank
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: api_intel_bb
results: parsing output: 35/ for test: kms_content_protection
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: kms_lease
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_cursor_crc
results: parsing output: 40/ for test: kms_plane_scaling
results: parsing output: 41/ for test: gem_exec_params
results: parsing output: 42/ for test: gem_ctx_shared
results: parsing output: 43/ for test: prime_self_import
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: gem_render_copy
results: parsing output: 46/ for test: kms_async_flips
results: parsing output: 47/ for test: gem_exec_params
results: parsing output: 48/ for test: perf
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_cursor_legacy
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: gem_mmap_wc
results: parsing output: 53/ for test: gem_exec_whisper
results: parsing output: 54/ for test: gem_ccs
results: parsing output: 55/ for test: i915_drm_fdinfo
results: parsing output: 56/ for test: gem_exec_balancer
results: parsing output: 57/ for test: kms_joiner
results: parsing output: 58/ for test: i915_pm_freq_api
results: parsing output: 59/ for test: gem_caching
results: parsing output: 60/ for test: gem_render_copy
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: gem_tiled_fence_blits
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_mmap
results: parsing output: 65/ for test: prime_mmap_kms
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: dmabuf
results: parsing output: 70/ for test: gem_exec_schedule
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: gem_exec_fence
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: kms_color
results: parsing output: 76/ for test: kms_cdclk
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: prime_busy
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_cursor_crc
results: parsing output: 81/ for test: syncobj_timeline
results: parsing output: 82/ for test: kms_pipe_crc_basic
results: parsing output: 83/ for test: kms_big_fb
results: parsing output: 84/ for test: gem_exec_params
results: parsing output: 85/ for test: kms_ccs
results: parsing output: 86/ for test: gem_userptr_blits
results: parsing output: 87/ for test: prime_self_import
results: parsing output: 88/ for test: i915_pm_rpm
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: kms_flip_scaled_crc
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: sw_sync
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: gem_eio
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_getfb
results: parsing output: 97/ for test: kms_pm_rpm
results: parsing output: 98/ for test: perf
results: parsing output: 99/ for test: syncobj_timeline
results: parsing output: 100/ for test: kms_async_flips
results: parsing output: 101/ for test: gem_exec_suspend
results: parsing output: 102/ for test: gem_mmap_gtt
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: gem_softpin
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: perf_pmu
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_userptr_blits
results: parsing output: 111/ for test: sw_sync
results: parsing output: 112/ for test: gem_ctx_exec
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: api_intel_allocator
results: parsing output: 117/ for test: gem_pwrite
results: parsing output: 118/ for test: gem_busy
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_flip_scaled_crc
results: parsing output: 121/ for test: gem_exec_flush
results: parsing output: 122/ for test: gem_ctx_persistence
results: parsing output: 123/ for test: gem_exec_balancer
results: parsing output: 124/ for test: syncobj_wait
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_mmap_offset
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_flip
results: parsing output: 129/ for test: gem_exec_params
results: parsing output: 130/ for test: kms_psr2_su
Done.