[60.860517] Initializing watchdogs
[60.860565]   /dev/watchdog0
[60.875410] [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.879896] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.882334] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.886662] [001/133] (960s left) kms_cursor_crc (cursor-onscreen-32x10)
[61.064587] Starting subtest: cursor-onscreen-32x10
[61.118240] Subtest cursor-onscreen-32x10: SKIP (0.048s)
[61.374018] [002/133] (960s left) perf_pmu (gt-awake)
[61.419676] Starting subtest: gt-awake
[69.913847] Subtest gt-awake: SUCCESS (8.494s)
[70.012545] [003/133] (951s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[70.129284] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[77.621780] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.498s)
[77.746455] [004/133] (943s left) perf (create-destroy-userspace-config)
[77.876093] Starting subtest: create-destroy-userspace-config
[77.895050] Subtest create-destroy-userspace-config: SUCCESS (0.019s)
[77.932592] [005/133] (943s left) gem_fenced_exec_thrash (no-spare-fences)
[77.987069] Subtest no-spare-fences: SKIP (0.000s)
[78.055289] [006/133] (943s left) gem_ctx_persistence (legacy-engines-persistence)
[78.100772] Starting subtest: legacy-engines-persistence
[78.103161] Starting dynamic subtest: render
[78.940966] Dynamic subtest render: SUCCESS (0.839s)
[78.951945] Starting dynamic subtest: bsd1
[79.697853] Dynamic subtest bsd1: SUCCESS (0.755s)
[79.708860] Starting dynamic subtest: bsd2
[80.474056] Dynamic subtest bsd2: SUCCESS (0.774s)
[80.484889] Starting dynamic subtest: blt
[81.242836] Dynamic subtest blt: SUCCESS (0.768s)
[81.253465] Starting dynamic subtest: vebox
[82.001357] Dynamic subtest vebox: SUCCESS (0.757s)
[82.004096] Subtest legacy-engines-persistence: SUCCESS (3.900s)
[82.087462] [007/133] (939s left) kms_prime (basic-modeset-hybrid)
[82.186118] Subtest basic-modeset-hybrid: SKIP (0.000s)
[82.298426] [FACT kms_prime (basic-modeset-hybrid)] new: kernel.kmod_is_loaded.vgem: true
[82.301778] [008/133] (939s left) kms_psr (fbc-psr-dpms)
[82.401018] Starting subtest: fbc-psr-dpms
[82.402970] Starting dynamic subtest: eDP-1
[82.407201] Dynamic subtest eDP-1: SKIP (0.000s)
[82.411303] Subtest fbc-psr-dpms: SKIP (0.000s)
[82.471957] [009/133] (939s left) gem_exec_schedule (preempt-contexts)
[82.548291] Starting subtest: preempt-contexts
[82.550228] Starting dynamic subtest: rcs0
[82.601095] Dynamic subtest rcs0: SUCCESS (0.060s)
[82.610855] Starting dynamic subtest: bcs0
[82.655530] Dynamic subtest bcs0: SUCCESS (0.054s)
[82.665128] Starting dynamic subtest: vcs0
[82.713916] Dynamic subtest vcs0: SUCCESS (0.058s)
[82.726617] Starting dynamic subtest: vcs1
[82.763609] Dynamic subtest vcs1: SUCCESS (0.049s)
[82.772676] Starting dynamic subtest: vecs0
[82.812978] Dynamic subtest vecs0: SUCCESS (0.049s)
[82.821510] Starting dynamic subtest: ccs0
[82.867785] Dynamic subtest ccs0: SUCCESS (0.055s)
[82.870080] Subtest preempt-contexts: SUCCESS (0.327s)
[84.045716] [010/133] (937s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[84.115732] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[84.118092] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.001s)
[84.238071] [011/133] (937s left) gem_ctx_shared (detached-shared-gtt)
[84.296418] Starting subtest: detached-shared-gtt
[86.448063] Subtest detached-shared-gtt: SUCCESS (2.152s)
[86.778380] [012/133] (935s left) gem_wait (write-busy)
[86.834655] Starting subtest: write-busy
[86.837150] Starting dynamic subtest: all-engines
[87.466626] Dynamic subtest all-engines: SUCCESS (0.633s)
[87.482793] Starting dynamic subtest: rcs0
[88.023111] Dynamic subtest rcs0: SUCCESS (0.556s)
[88.038986] Starting dynamic subtest: bcs0
[88.573650] Dynamic subtest bcs0: SUCCESS (0.550s)
[88.587996] Starting dynamic subtest: vcs0
[89.879816] Dynamic subtest vcs0: SUCCESS (1.304s)
[89.894767] Starting dynamic subtest: vcs1
[91.926981] Dynamic subtest vcs1: SUCCESS (2.046s)
[91.941891] Starting dynamic subtest: vecs0
[93.912653] Dynamic subtest vecs0: SUCCESS (1.984s)
[93.927056] Starting dynamic subtest: ccs0
[94.462738] Dynamic subtest ccs0: SUCCESS (0.551s)
[94.465593] Subtest write-busy: SUCCESS (7.629s)
[94.661055] [013/133] (927s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[94.777994] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[94.782616] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[94.893334] [014/133] (927s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[94.984086] Starting subtest: psr-rgb565-draw-mmap-cpu
[101.757327] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.774s)
[101.879422] [015/133] (920s left) gem_exec_store (pages)
[101.930837] Starting subtest: pages
[101.933105] Starting dynamic subtest: rcs0
[102.010780] Dynamic subtest rcs0: SUCCESS (0.079s)
[102.021906] Starting dynamic subtest: bcs0
[102.080710] Dynamic subtest bcs0: SUCCESS (0.069s)
[102.091361] Starting dynamic subtest: vcs0
[102.157731] Dynamic subtest vcs0: SUCCESS (0.077s)
[102.168186] Starting dynamic subtest: vcs1
[102.224099] Dynamic subtest vcs1: SUCCESS (0.066s)
[102.235603] Starting dynamic subtest: vecs0
[102.291081] Dynamic subtest vecs0: SUCCESS (0.067s)
[102.301239] Starting dynamic subtest: ccs0
[102.354784] Dynamic subtest ccs0: SUCCESS (0.064s)
[102.357156] Subtest pages: SUCCESS (0.424s)
[102.536420] [016/133] (919s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[102.647550] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[102.649880] Starting dynamic subtest: pipe-A-eDP-1
[102.718494] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[102.731668] Starting dynamic subtest: pipe-B-eDP-1
[103.863277] Dynamic subtest pipe-B-eDP-1: SKIP (1.142s)
[103.867836] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.223s)
[105.040739] [017/133] (917s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[105.138368] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[105.140668] Starting dynamic subtest: pipe-A-eDP-1
[105.161181] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.023s)
[105.169597] Starting dynamic subtest: pipe-B-eDP-1
[105.189685] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.025s)
[105.200156] Starting dynamic subtest: pipe-C-eDP-1
[105.220628] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.023s)
[105.231209] Starting dynamic subtest: pipe-D-eDP-1
[105.251282] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.024s)
[105.253504] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.098s)
[107.422650] [018/133] (914s left) sysfs_timeslice_duration (timeout)
[107.476629] Starting subtest: timeout
[107.478972] Starting dynamic subtest: vecs0
[116.882891] Dynamic subtest vecs0: SUCCESS (9.403s)
[116.909054] Starting dynamic subtest: ccs0
[118.066849] Dynamic subtest ccs0: SUCCESS (1.183s)
[118.079829] Starting dynamic subtest: vcs0
[127.892364] Dynamic subtest vcs0: SUCCESS (9.823s)
[127.904993] Starting dynamic subtest: bcs0
[128.473026] Dynamic subtest bcs0: SUCCESS (0.579s)
[128.483875] Starting dynamic subtest: rcs0
[129.689594] Dynamic subtest rcs0: SUCCESS (1.216s)
[129.701073] Starting dynamic subtest: vcs1
[138.905299] Dynamic subtest vcs1: SUCCESS (9.214s)
[138.907857] Subtest timeout: SUCCESS (31.426s)
[138.987767] [019/133] (883s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[139.047067] Starting subtest: multi-wait-all-for-submit-signaled
[139.049476] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[139.156768] [020/133] (883s left) gem_lmem_swapping (heavy-verify-random-ccs)
[139.207329] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[139.253254] [021/133] (882s left) kms_cdclk (plane-scaling)
[139.360730] Starting subtest: plane-scaling
[139.363073] Starting dynamic subtest: pipe-A-eDP-1
[139.660180] Dynamic subtest pipe-A-eDP-1: SKIP (0.296s)
[139.669391] Starting dynamic subtest: pipe-B-eDP-1
[139.674013] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[139.682106] Starting dynamic subtest: pipe-C-eDP-1
[139.686693] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[139.694726] Starting dynamic subtest: pipe-D-eDP-1
[139.699155] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[139.703173] Subtest plane-scaling: SKIP (0.302s)
[140.477793] [022/133] (881s left) syncobj_wait (multi-wait-signaled)
[140.544840] Starting subtest: multi-wait-signaled
[140.547264] Subtest multi-wait-signaled: SUCCESS (0.000s)
[140.642828] [023/133] (881s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[140.736087] Starting subtest: 4-tiled-8bpp-rotate-180
[141.993820] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.259s)
[142.130357] [024/133] (880s left) gem_exec_params (cliprects_ptr-dirt)
[142.186764] Starting subtest: cliprects_ptr-dirt
[142.188820] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[142.284753] [025/133] (880s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[142.398254] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[142.402119] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[142.503115] [026/133] (879s left) i915_query (query-topology-matches-eu-total)
[142.566300] Starting subtest: query-topology-matches-eu-total
[142.568178] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[142.671221] [027/133] (879s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[143.049211] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[143.866212] [028/133] (878s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[143.972799] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[147.585507] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.616s)
[148.798113] [029/133] (873s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[148.898636] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[148.902701] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[148.980515] [030/133] (873s left) kms_pm_rpm (basic-pci-d3-state)
[151.125515] Starting subtest: basic-pci-d3-state
[152.610327] Subtest basic-pci-d3-state: SUCCESS (1.487s)
[152.698220] [031/133] (869s left) kms_content_protection (atomic-dpms-hdcp14)
[152.836947] Starting subtest: atomic-dpms-hdcp14
[152.841607] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[152.952670] [032/133] (869s left) syncobj_basic (bad-destroy-pad)
[153.008753] Starting subtest: bad-destroy-pad
[153.010866] Subtest bad-destroy-pad: SUCCESS (0.000s)
[153.102126] [033/133] (869s left) prime_self_import (export-vs-gem_close-race)
[153.139139] Starting subtest: export-vs-gem_close-race
[158.219030] Subtest export-vs-gem_close-race: SUCCESS (5.079s)
[158.294957] [034/133] (864s left) kms_psr (psr2-suspend)
[158.412802] Starting subtest: psr2-suspend
[158.415174] Starting dynamic subtest: eDP-1
[176.334293] Dynamic subtest eDP-1: SUCCESS (3.752s)
[176.336519] Subtest psr2-suspend: SUCCESS (3.753s)
[177.229096] [035/133] (845s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[177.326439] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[177.328622] Starting dynamic subtest: pipe-A
[177.555728] Dynamic subtest pipe-A: SUCCESS (0.230s)
[177.568752] Starting dynamic subtest: pipe-B
[178.901316] Dynamic subtest pipe-B: SUCCESS (1.345s)
[178.910647] Starting dynamic subtest: pipe-C
[180.244318] Dynamic subtest pipe-C: SUCCESS (1.341s)
[180.254832] Starting dynamic subtest: pipe-D
[181.593715] Dynamic subtest pipe-D: SUCCESS (1.349s)
[181.595947] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.267s)
[182.756368] [036/133] (840s left) gem_ccs (ctrl-surf-copy)
[182.814069] Starting subtest: ctrl-surf-copy
[182.818602] Subtest ctrl-surf-copy: SKIP (0.000s)
[182.910915] [037/133] (839s left) prime_mmap (test_errors)
[182.955653] Starting subtest: test_errors
[182.957845] Starting dynamic subtest: test_errors-smem
[183.130446] Dynamic subtest test_errors-smem: SUCCESS (0.173s)
[183.132845] Subtest test_errors: SUCCESS (0.174s)
[183.214799] [038/133] (839s left) gen7_exec_parse (cmd-crossing-page)
[183.277243] Subtest cmd-crossing-page: SKIP (0.000s)
[183.336191] [039/133] (839s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[183.429098] Starting subtest: bad-pixel-format-y-tiled-ccs
[183.431374] Starting dynamic subtest: pipe-A-eDP-1
[183.452810] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[183.463594] Starting dynamic subtest: pipe-B-eDP-1
[183.484507] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[183.493105] Starting dynamic subtest: pipe-C-eDP-1
[183.513374] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[183.521655] Starting dynamic subtest: pipe-D-eDP-1
[183.543724] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[183.548092] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[185.632705] [040/133] (837s left) kms_plane_scaling (invalid-parameters)
[185.720702] Starting subtest: invalid-parameters
[185.722942] Starting dynamic subtest: less-than-1-height-src
[185.733171] Dynamic subtest less-than-1-height-src: SUCCESS (0.014s)
[185.742677] Starting dynamic subtest: less-than-1-width-src
[185.747150] Dynamic subtest less-than-1-width-src: SUCCESS (0.013s)
[185.749282] Subtest invalid-parameters: SUCCESS (0.028s)
[185.851507] [041/133] (837s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[186.149685] Starting subtest: cursor-rapid-movement-512x512
[186.154199] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[186.290496] [042/133] (836s left) syncobj_eventfd (timeline-wait-before-signal)
[186.345311] Starting subtest: timeline-wait-before-signal
[186.377054] Subtest timeline-wait-before-signal: SUCCESS (0.031s)
[186.454918] [043/133] (836s left) gem_render_copy (yf-tiled)
[186.507269] Starting subtest: yf-tiled
[186.511720] Subtest yf-tiled: SKIP (0.000s)
[186.701311] [044/133] (836s left) gem_exec_fence (keep-in-fence)
[186.873900] Starting subtest: keep-in-fence
[186.876247] Starting dynamic subtest: rcs0
[186.928074] Dynamic subtest rcs0: SUCCESS (0.054s)
[186.937518] Starting dynamic subtest: bcs0
[186.980082] Dynamic subtest bcs0: SUCCESS (0.051s)
[186.990576] Starting dynamic subtest: vcs0
[188.081909] Dynamic subtest vcs0: SUCCESS (1.098s)
[188.093606] Starting dynamic subtest: vcs1
[190.065188] Dynamic subtest vcs1: SUCCESS (1.982s)
[190.076765] Starting dynamic subtest: vecs0
[192.049051] Dynamic subtest vecs0: SUCCESS (1.982s)
[192.058168] Starting dynamic subtest: ccs0
[192.287746] Dynamic subtest ccs0: SUCCESS (0.238s)
[192.290443] Subtest keep-in-fence: SUCCESS (5.413s)
[194.189088] [045/133] (828s left) core_getversion (basic)
[194.240760] Starting subtest: basic
[194.245453] Subtest basic: SUCCESS (0.000s)
[194.308073] [046/133] (828s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[194.423315] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[194.427922] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[194.577247] [047/133] (828s left) kms_color (invalid-degamma-lut-sizes)
[194.670169] Starting subtest: invalid-degamma-lut-sizes
[194.672487] Starting dynamic subtest: pipe-A
[194.959539] Dynamic subtest pipe-A: SUCCESS (0.290s)
[194.969767] Starting dynamic subtest: pipe-B
[194.972069] Dynamic subtest pipe-B: SUCCESS (0.000s)
[194.984256] Starting dynamic subtest: pipe-C
[194.986512] Dynamic subtest pipe-C: SUCCESS (0.000s)
[194.996050] Starting dynamic subtest: pipe-D
[194.998137] Dynamic subtest pipe-D: SUCCESS (0.000s)
[195.000057] Subtest invalid-degamma-lut-sizes: SUCCESS (0.293s)
[195.771686] [048/133] (827s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[195.863048] Starting subtest: blocking-absolute-wf_vblank-interruptible
[195.865257] Starting dynamic subtest: A-eDP1
[204.536754] Dynamic subtest A-eDP1: SUCCESS (8.671s)
[204.548716] Starting dynamic subtest: B-eDP1
[213.261695] Dynamic subtest B-eDP1: SUCCESS (8.723s)
[213.272425] Starting dynamic subtest: C-eDP1
[221.980538] Dynamic subtest C-eDP1: SUCCESS (8.718s)
[221.990022] Starting dynamic subtest: D-eDP1
[230.711200] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[230.713949] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.847s)
[231.927407] [049/133] (791s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[232.036244] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[232.038583] Starting dynamic subtest: pipe-A-eDP-1
[232.064382] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[232.080424] Starting dynamic subtest: pipe-B-eDP-1
[232.104674] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[232.115016] Starting dynamic subtest: pipe-C-eDP-1
[232.138660] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[232.147266] Starting dynamic subtest: pipe-D-eDP-1
[232.171297] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[232.175634] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[234.214639] [050/133] (789s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[234.313540] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[234.315968] Starting dynamic subtest: pipe-A-default-mode
[237.039185] Dynamic subtest pipe-A-default-mode: SUCCESS (2.724s)
[237.041192] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.726s)
[237.907991] [051/133] (785s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[237.997947] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[238.000154] Starting dynamic subtest: pipe-A-eDP-1
[238.022760] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[238.036579] Starting dynamic subtest: pipe-B-eDP-1
[238.058172] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[238.067294] Starting dynamic subtest: pipe-C-eDP-1
[238.088788] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[238.097479] Starting dynamic subtest: pipe-D-eDP-1
[238.119633] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[238.123662] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[240.281259] [052/133] (783s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[240.390969] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[241.458573] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.068s)
[241.556589] [053/133] (781s left) kms_vblank (query-forked-busy-hang)
[241.648350] Starting subtest: query-forked-busy-hang
[241.650171] Starting dynamic subtest: pipe-A-eDP-1
[268.867957] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.215s)
[268.882913] Starting dynamic subtest: pipe-D-eDP-1
[283.904289] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.035s)
[283.906169] Subtest query-forked-busy-hang: SUCCESS (42.255s)
[284.731264] [054/133] (738s left) kms_sequence (get-idle)
[284.828901] Starting subtest: get-idle
[284.831065] Starting dynamic subtest: pipe-A-eDP-1
[286.647312] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.818s)
[286.660624] Starting dynamic subtest: pipe-B-eDP-1
[289.109944] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.462s)
[289.120160] Starting dynamic subtest: pipe-C-eDP-1
[291.536089] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.425s)
[291.545912] Starting dynamic subtest: pipe-D-eDP-1
[293.965295] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.425s)
[293.967299] Subtest get-idle: SUCCESS (9.136s)
[294.777493] [055/133] (728s left) kms_invalid_mode (bad-hsync-end)
[294.880264] Starting subtest: bad-hsync-end
[294.882554] Starting dynamic subtest: pipe-A-eDP-1
[295.177813] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.296s)
[295.187394] Starting dynamic subtest: pipe-B-eDP-1
[295.189319] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[295.197920] Starting dynamic subtest: pipe-C-eDP-1
[295.200253] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[295.209299] Starting dynamic subtest: pipe-D-eDP-1
[295.211687] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[295.214004] Subtest bad-hsync-end: SUCCESS (0.302s)
[296.008165] [056/133] (727s left) kms_addfb_basic (basic)
[296.073189] Starting subtest: basic
[296.075431] Subtest basic: SUCCESS (0.000s)
[296.225519] [057/133] (727s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[296.335772] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[296.340006] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[296.432921] [058/133] (727s left) kms_flip (absolute-wf_vblank)
[296.525413] Starting subtest: absolute-wf_vblank
[296.527772] Starting dynamic subtest: A-eDP1
[305.275490] Dynamic subtest A-eDP1: SUCCESS (8.752s)
[305.285415] Starting dynamic subtest: B-eDP1
[314.001453] Dynamic subtest B-eDP1: SUCCESS (8.725s)
[314.014605] Starting dynamic subtest: C-eDP1
[322.721381] Dynamic subtest C-eDP1: SUCCESS (8.716s)
[322.730995] Starting dynamic subtest: D-eDP1
[331.451104] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[331.453344] Subtest absolute-wf_vblank: SUCCESS (34.933s)
[332.693018] [059/133] (691s left) sw_sync (sync_multi_producer_single_consumer)
[332.728454] Starting subtest: sync_multi_producer_single_consumer
[333.802540] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[333.876066] [060/133] (689s left) kms_atomic (test-only)
[333.967814] Starting subtest: test-only
[334.014199] Starting dynamic subtest: pipe-A-eDP-1
[336.558520] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.544s)
[336.560948] Subtest test-only: SUCCESS (2.593s)
[337.361611] [061/133] (686s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[337.468943] Starting subtest: y-tiled-16bpp-rotate-180
[337.473255] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[337.557808] [062/133] (686s left) gem_flink_basic (double-flink)
[337.617987] Starting subtest: double-flink
[337.620175] Subtest double-flink: SUCCESS (0.000s)
[337.731914] [063/133] (686s left) i915_getparams_basic (basic-subslice-total)
[337.786830] Starting subtest: basic-subslice-total
[337.791118] Subtest basic-subslice-total: SUCCESS (0.000s)
[337.876027] [064/133] (686s left) gem_spin_batch (spin-each)
[337.927780] Starting subtest: spin-each
[341.203077] Subtest spin-each: SUCCESS (3.266s)
[343.106048] [065/133] (680s left) gem_workarounds (suspend-resume-fd)
[343.172579] Starting subtest: suspend-resume-fd
[358.898542] Subtest suspend-resume-fd: SUCCESS (1.167s)
[360.070061] [066/133] (665s left) kms_big_fb (linear-32bpp-rotate-90)
[360.191800] Starting subtest: linear-32bpp-rotate-90
[360.217989] Subtest linear-32bpp-rotate-90: SKIP (0.021s)
[360.352417] [067/133] (664s left) kms_rotation_crc (exhaust-fences)
[360.465094] Starting subtest: exhaust-fences
[360.469510] Subtest exhaust-fences: SKIP (0.000s)
[360.539698] [068/133] (664s left) kms_psr (fbc-pr-sprite-plane-move)
[360.633562] Starting subtest: fbc-pr-sprite-plane-move
[360.637975] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[360.709274] [069/133] (664s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[360.807739] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[368.198035] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.388s)
[368.347000] [070/133] (656s left) gem_basic (create-close)
[368.398250] Starting subtest: create-close
[368.402302] Subtest create-close: SUCCESS (0.000s)
[368.492977] [071/133] (656s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[368.586331] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[372.105684] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.521s)
[373.248613] [072/133] (652s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[373.342803] Starting subtest: yf-tiled-64bpp-rotate-270
[373.347329] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[373.435379] [073/133] (651s left) kms_hdmi_inject (inject-4k)
[373.778308] Starting subtest: inject-4k
[374.093839] Subtest inject-4k: SUCCESS (0.317s)
[374.611500] [074/133] (650s left) i915_drm_fdinfo (memory-info-resident)
[374.655645] Starting subtest: memory-info-resident
[374.657662] Starting dynamic subtest: smem0
[377.347357] Dynamic subtest smem0: SUCCESS (2.693s)
[377.349790] Subtest memory-info-resident: SUCCESS (2.694s)
[377.439886] [075/133] (648s left) gem_exec_reloc (basic-gtt-read)
[377.490443] Subtest basic-gtt-read: SKIP (0.000s)
[377.568472] [076/133] (647s left) gem_ctx_persistence (engines-persistence)
[377.635429] Starting subtest: engines-persistence
[377.637447] Starting dynamic subtest: rcs0
[378.463091] Dynamic subtest rcs0: SUCCESS (0.826s)
[378.476224] Starting dynamic subtest: bcs0
[379.222252] Dynamic subtest bcs0: SUCCESS (0.757s)
[379.234687] Starting dynamic subtest: vcs0
[379.964146] Dynamic subtest vcs0: SUCCESS (0.739s)
[379.975271] Starting dynamic subtest: vcs1
[380.746926] Dynamic subtest vcs1: SUCCESS (0.780s)
[380.760351] Starting dynamic subtest: vecs0
[381.504106] Dynamic subtest vecs0: SUCCESS (0.754s)
[381.515469] Starting dynamic subtest: ccs0
[382.295229] Dynamic subtest ccs0: SUCCESS (0.788s)
[382.297841] Subtest engines-persistence: SUCCESS (4.659s)
[382.385590] [077/133] (643s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[382.507357] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[382.511784] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[382.619340] [078/133] (642s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[382.724139] Starting subtest: primary-4-tiled-reflect-x-180
[389.881124] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.158s)
[391.070973] [079/133] (634s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[391.124863] Starting subtest: y-tiled-ccs-to-x-tiled
[391.129417] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[391.312547] [080/133] (634s left) perf_pmu (busy)
[391.357638] Starting subtest: busy
[391.359821] Starting dynamic subtest: rcs0
[391.937906] Dynamic subtest rcs0: SUCCESS (0.581s)
[391.950034] Starting dynamic subtest: bcs0
[392.498831] Dynamic subtest bcs0: SUCCESS (0.559s)
[392.509941] Starting dynamic subtest: vcs0
[393.617513] Dynamic subtest vcs0: SUCCESS (1.116s)
[393.630193] Starting dynamic subtest: vcs1
[395.665995] Dynamic subtest vcs1: SUCCESS (2.045s)
[395.678142] Starting dynamic subtest: vecs0
[397.650604] Dynamic subtest vecs0: SUCCESS (1.981s)
[397.663064] Starting dynamic subtest: ccs0
[398.213234] Dynamic subtest ccs0: SUCCESS (0.561s)
[398.215715] Subtest busy: SUCCESS (6.859s)
[398.318696] [081/133] (627s left) api_intel_bb (blit-reloc-purge-cache)
[398.374083] Starting subtest: blit-reloc-purge-cache
[398.378548] Subtest blit-reloc-purge-cache: SKIP (0.001s)
[398.483643] [082/133] (627s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[398.582149] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[398.586209] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[398.683709] [083/133] (627s left) gem_exec_params (invalid-fence-in-submit)
[398.744625] Starting subtest: invalid-fence-in-submit
[398.752493] Subtest invalid-fence-in-submit: SUCCESS (0.006s)
[398.888513] [084/133] (626s left) kms_frontbuffer_tracking (psr-1p-rte)
[398.988864] Starting subtest: psr-1p-rte
[404.280403] Subtest psr-1p-rte: SUCCESS (5.296s)
[404.403361] [085/133] (621s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier)
[404.498412] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier
[404.525153] Starting dynamic subtest: pipe-A-plane-0
[404.992928] Dynamic subtest pipe-A-plane-0: SUCCESS (0.466s)
[405.004897] Starting dynamic subtest: pipe-A-plane-3
[405.247401] Dynamic subtest pipe-A-plane-3: SUCCESS (0.255s)
[405.256496] Starting dynamic subtest: pipe-A-plane-5
[405.260243] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[406.328733] Starting dynamic subtest: pipe-B-plane-0
[406.784737] Dynamic subtest pipe-B-plane-0: SUCCESS (0.466s)
[406.795434] Starting dynamic subtest: pipe-B-plane-3
[407.050995] Dynamic subtest pipe-B-plane-3: SUCCESS (0.266s)
[407.060365] Starting dynamic subtest: pipe-B-plane-5
[407.064818] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[407.362394] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.862s)
[408.167825] [086/133] (617s left) kms_psr (fbc-psr-suspend)
[408.265396] Starting subtest: fbc-psr-suspend
[408.267270] Starting dynamic subtest: eDP-1
[408.271272] Dynamic subtest eDP-1: SKIP (0.000s)
[408.275271] Subtest fbc-psr-suspend: SKIP (0.001s)
[408.350263] [087/133] (617s left) kms_plane_multiple (2x-tiling-none)
[408.449532] Starting subtest: 2x-tiling-none
[408.453988] Subtest 2x-tiling-none: SKIP (0.000s)
[408.522424] [088/133] (617s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[408.630883] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[408.634993] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[408.733770] [089/133] (617s left) gem_render_copy (linear)
[408.797482] Starting subtest: linear
[408.799946] Starting dynamic subtest: smem
[408.843621] Dynamic subtest smem: SUCCESS (0.046s)
[408.845871] Subtest linear: SUCCESS (0.047s)
[409.031321] [090/133] (617s left) gem_exec_schedule (u-independent)
[409.111893] Starting subtest: u-independent
[409.114308] Starting dynamic subtest: rcs0
[410.643446] Dynamic subtest rcs0: SUCCESS (1.540s)
[410.655782] Starting dynamic subtest: vcs0
[412.640226] Dynamic subtest vcs0: SUCCESS (1.994s)
[412.652920] Starting dynamic subtest: vcs1
[414.620502] Dynamic subtest vcs1: SUCCESS (1.978s)
[414.632912] Starting dynamic subtest: vecs0
[416.669936] Dynamic subtest vecs0: SUCCESS (2.047s)
[416.681285] Starting dynamic subtest: ccs0
[418.641774] Dynamic subtest ccs0: SUCCESS (1.969s)
[418.644747] Subtest u-independent: SUCCESS (9.540s)
[418.943980] [091/133] (607s left) gem_mmap_offset (close-race)
[419.005388] Starting subtest: close-race
[439.120180] Subtest close-race: SUCCESS (20.113s)
[439.241808] [092/133] (586s left) gem_ringfill (engines-basic)
[444.628927] Starting subtest: engines-basic
[444.631463] Starting dynamic subtest: rcs0
[444.677167] Dynamic subtest rcs0: SUCCESS (0.049s)
[444.687828] Starting dynamic subtest: bcs0
[444.723251] Dynamic subtest bcs0: SUCCESS (0.044s)
[444.734821] Starting dynamic subtest: vcs0
[444.766461] Dynamic subtest vcs0: SUCCESS (0.041s)
[444.777114] Starting dynamic subtest: vcs1
[444.810586] Dynamic subtest vcs1: SUCCESS (0.042s)
[444.822897] Starting dynamic subtest: vecs0
[444.856001] Dynamic subtest vecs0: SUCCESS (0.043s)
[444.871209] Starting dynamic subtest: ccs0
[444.900187] Dynamic subtest ccs0: SUCCESS (0.044s)
[444.902732] Subtest engines-basic: SUCCESS (0.273s)
[444.986661] [093/133] (581s left) kms_rotation_crc (primary-rotation-180)
[445.087267] Starting subtest: primary-rotation-180
[452.070901] Subtest primary-rotation-180: SUCCESS (6.985s)
[453.263149] [094/133] (573s left) prime_busy (after-wait)
[453.321733] Starting subtest: after-wait
[453.327881] Starting dynamic subtest: rcs0
[453.353855] Dynamic subtest rcs0: SUCCESS (0.026s)
[453.365706] Starting dynamic subtest: bcs0
[453.396982] Dynamic subtest bcs0: SUCCESS (0.042s)
[453.408088] Starting dynamic subtest: vcs0
[453.437406] Dynamic subtest vcs0: SUCCESS (0.040s)
[453.448390] Starting dynamic subtest: vcs1
[453.477609] Dynamic subtest vcs1: SUCCESS (0.039s)
[453.487039] Starting dynamic subtest: vecs0
[453.517884] Dynamic subtest vecs0: SUCCESS (0.040s)
[453.527677] Starting dynamic subtest: ccs0
[453.559029] Dynamic subtest ccs0: SUCCESS (0.040s)
[453.667999] Subtest after-wait: SUCCESS (0.344s)
[453.745733] [095/133] (572s left) kms_psr (fbc-pr-cursor-blt)
[453.842373] Starting subtest: fbc-pr-cursor-blt
[453.846798] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[453.926201] [096/133] (572s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[454.025231] Starting subtest: basic-modeset-with-all-modifiers-formats
[454.397666] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[455.211937] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.815s)
[455.221903] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[455.233910] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[455.243373] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[455.256143] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.021s)
[455.265731] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[455.411944] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.155s)
[455.423752] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[455.434318] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[455.448931] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[455.456306] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.021s)
[455.465597] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[455.478572] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[455.488079] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[455.500628] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.021s)
[455.510519] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[455.522978] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.021s)
[455.532258] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[455.545001] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[455.556830] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[455.567816] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.020s)
[455.577234] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[455.589598] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.021s)
[455.598982] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[455.756381] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.166s)
[455.766089] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[455.778446] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.021s)
[455.787758] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[455.800494] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.021s)
[455.809970] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[455.822997] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[455.832289] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[455.845359] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.021s)
[455.854668] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[455.867589] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.021s)
[455.876980] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[455.889602] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.021s)
[455.898479] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[455.911471] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[455.920320] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[455.933727] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.021s)
[455.943553] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[456.100888] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.166s)
[456.110540] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[456.123044] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.021s)
[456.132063] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[456.145058] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.021s)
[456.154109] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[456.167542] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[456.176558] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[456.189668] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.021s)
[456.198722] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[456.211769] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[456.221448] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[456.233812] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.021s)
[456.244411] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[456.256347] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[456.266101] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[456.278698] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.021s)
[456.662360] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.638s)
[457.474287] [097/133] (568s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[457.583357] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[464.496777] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.915s)
[464.612098] [098/133] (561s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[464.666812] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[464.726859] [099/133] (561s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[464.823343] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[464.827833] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[464.929682] [100/133] (561s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[465.026993] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[472.226220] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.202s)
[472.328648] [101/133] (554s left) kms_cursor_edge_walk (64x64-right-edge)
[472.432411] Starting subtest: 64x64-right-edge
[472.434815] Starting dynamic subtest: pipe-A-eDP-1
[475.917348] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.483s)
[475.927439] Starting dynamic subtest: pipe-D-eDP-1
[479.074058] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.154s)
[479.076479] Subtest 64x64-right-edge: SUCCESS (6.641s)
[479.864343] [102/133] (546s left) gem_pxp (create-regular-buffer)
[479.996682] Starting subtest: create-regular-buffer
[479.999280] Subtest create-regular-buffer: SUCCESS (0.000s)
[480.105310] [103/133] (546s left) i915_pm_rps (thresholds-idle-park)
[480.166481] Starting subtest: thresholds-idle-park
[480.170593] Subtest thresholds-idle-park: SKIP (0.000s)
[480.267338] [104/133] (546s left) gem_exec_params (rsvd2-dirt)
[480.339719] Starting subtest: rsvd2-dirt
[480.344383] Subtest rsvd2-dirt: SKIP (0.000s)
[480.447548] [105/133] (546s left) i915_pm_rpm (reg-read-ioctl)
[481.973718] Starting subtest: reg-read-ioctl
[484.268516] Subtest reg-read-ioctl: SUCCESS (2.298s)
[484.650023] [106/133] (542s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[485.028496] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[485.838669] [107/133] (541s left) kms_draw_crc (draw-method-pwrite)
[485.931606] Starting subtest: draw-method-pwrite
[485.933588] Starting dynamic subtest: xrgb8888-untiled
[486.520319] Dynamic subtest xrgb8888-untiled: SUCCESS (0.589s)
[486.531013] Starting dynamic subtest: rgb565-untiled
[486.985721] Dynamic subtest rgb565-untiled: SUCCESS (0.466s)
[486.996577] Starting dynamic subtest: xrgb2101010-untiled
[487.541002] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.553s)
[487.550881] Starting dynamic subtest: xbgr16161616f-untiled
[488.329802] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.788s)
[488.339922] Starting dynamic subtest: xrgb8888-xtiled
[490.118771] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.787s)
[490.131591] Starting dynamic subtest: rgb565-xtiled
[491.218840] Dynamic subtest rgb565-xtiled: SUCCESS (1.097s)
[491.229400] Starting dynamic subtest: xrgb2101010-xtiled
[493.041033] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.821s)
[493.060624] Starting dynamic subtest: xbgr16161616f-xtiled
[496.386055] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.343s)
[496.395902] Starting dynamic subtest: xrgb8888-4tiled
[497.497829] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.110s)
[497.507568] Starting dynamic subtest: rgb565-4tiled
[498.074754] Dynamic subtest rgb565-4tiled: SUCCESS (0.575s)
[498.084798] Starting dynamic subtest: xrgb2101010-4tiled
[499.197110] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.121s)
[499.207394] Starting dynamic subtest: xbgr16161616f-4tiled
[502.242287] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.042s)
[502.244700] Subtest draw-method-pwrite: SUCCESS (16.312s)
[502.363661] [108/133] (524s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[502.469549] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[502.471772] Starting dynamic subtest: pipe-A-eDP-1
[502.556212] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[502.568086] Starting dynamic subtest: pipe-B-eDP-1
[503.699464] Dynamic subtest pipe-B-eDP-1: SKIP (1.142s)
[503.704163] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.234s)
[504.883118] [109/133] (522s left) perf (blocking)
[504.984941] Starting subtest: blocking
[504.987447] Starting dynamic subtest: 0-rcs0
[515.018507] Dynamic subtest 0-rcs0: SUCCESS (10.031s)
[515.028726] Starting dynamic subtest: 1-vcs1
[525.039865] Dynamic subtest 1-vcs1: SUCCESS (10.018s)
[525.042254] Subtest blocking: SUCCESS (20.052s)
[525.091585] [110/133] (501s left) kms_atomic_transition (plane-all-modeset-transition)
[525.184856] Starting subtest: plane-all-modeset-transition
[525.189076] Subtest plane-all-modeset-transition: SKIP (0.000s)
[525.258487] [111/133] (501s left) gem_mmap (big-bo)
[525.315368] Subtest big-bo: SKIP (0.000s)
[525.381068] [112/133] (501s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[525.480472] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[525.484666] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[525.566027] [113/133] (501s left) kms_busy (basic)
[525.659166] Starting subtest: basic
[525.661468] Starting dynamic subtest: flip
[526.235503] Dynamic subtest flip: SUCCESS (0.578s)
[526.246374] Starting dynamic subtest: modeset
[529.890810] Dynamic subtest modeset: SUCCESS (3.652s)
[529.893225] Subtest basic: SUCCESS (4.232s)
[530.758294] [114/133] (496s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[530.810108] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[530.917705] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.107s)
[531.011956] [115/133] (496s left) kms_lease (cursor-implicit-plane)
[531.110258] Starting subtest: cursor-implicit-plane
[531.112450] Starting dynamic subtest: pipe-A-eDP-1
[531.570379] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.461s)
[531.580895] Starting dynamic subtest: pipe-B-eDP-1
[532.660189] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.086s)
[532.670365] Starting dynamic subtest: pipe-C-eDP-1
[533.729480] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.067s)
[533.739252] Starting dynamic subtest: pipe-D-eDP-1
[534.831925] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.100s)
[534.833808] Subtest cursor-implicit-plane: SUCCESS (3.721s)
[535.660636] [116/133] (491s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[535.771853] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[536.939580] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.168s)
[537.076121] [117/133] (490s left) api_intel_bb (misplaced-blitter)
[537.135585] Starting subtest: misplaced-blitter
[537.140188] Subtest misplaced-blitter: SUCCESS (0.006s)
[537.258002] [118/133] (490s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[537.346836] Starting subtest: compare-crc-sanitycheck-xr24
[537.348710] Starting dynamic subtest: pipe-A-eDP-1
[538.053597] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.707s)
[538.064394] Starting dynamic subtest: pipe-B-eDP-1
[539.306946] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.252s)
[539.320043] Starting dynamic subtest: pipe-C-eDP-1
[540.564802] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.254s)
[540.575360] Starting dynamic subtest: pipe-D-eDP-1
[541.749744] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.184s)
[541.752100] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.402s)
[542.565051] [119/133] (484s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[542.635878] Starting subtest: invalid-multi-wait-unsubmitted
[542.637817] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[542.731875] [120/133] (484s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[542.782574] Starting subtest: single-wait-for-submit-unsubmitted
[542.884858] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[542.964510] [121/133] (484s left) kms_cursor_legacy (single-bo)
[543.088607] Starting subtest: single-bo
[543.090879] Starting dynamic subtest: pipe-A
[548.472978] Dynamic subtest pipe-A: SUCCESS (5.380s)
[548.484277] Starting dynamic subtest: pipe-B
[553.855904] Dynamic subtest pipe-B: SUCCESS (5.380s)
[553.867565] Starting dynamic subtest: pipe-C
[559.238928] Dynamic subtest pipe-C: SUCCESS (5.379s)
[559.250427] Starting dynamic subtest: pipe-D
[564.621126] Dynamic subtest pipe-D: SUCCESS (5.379s)
[564.633347] Starting dynamic subtest: all-pipes
[570.011433] Dynamic subtest all-pipes: SUCCESS (5.386s)
[570.013985] Subtest single-bo: SUCCESS (26.918s)
[570.157357] [122/133] (457s left) gem_ctx_param (non-root-set)
[570.207911] Starting subtest: non-root-set
[570.225694] Subtest non-root-set: SUCCESS (0.018s)
[570.329871] [123/133] (457s left) gem_exec_flush (basic-uc-prw-default)
[570.395275] Starting subtest: basic-uc-prw-default
[575.854075] Subtest basic-uc-prw-default: SUCCESS (5.460s)
[576.047676] [124/133] (451s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[576.114724] Starting subtest: single-wait-all-for-submit-signaled
[576.117334] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[576.229339] [125/133] (451s left) gem_create (create-ext-placement-each)
[576.396798] Starting subtest: create-ext-placement-each
[576.399009] Subtest create-ext-placement-each: SUCCESS (0.000s)
[576.490906] [126/133] (451s left) kms_flip (basic-flip-vs-dpms)
[576.601591] Starting subtest: basic-flip-vs-dpms
[576.603979] Starting dynamic subtest: A-eDP1
[579.101873] Dynamic subtest A-eDP1: SUCCESS (2.498s)
[579.112545] Starting dynamic subtest: B-eDP1
[581.288347] Dynamic subtest B-eDP1: SUCCESS (2.183s)
[581.298332] Starting dynamic subtest: C-eDP1
[583.500607] Dynamic subtest C-eDP1: SUCCESS (2.211s)
[583.512044] Starting dynamic subtest: D-eDP1
[585.692754] Dynamic subtest D-eDP1: SUCCESS (2.190s)
[585.695073] Subtest basic-flip-vs-dpms: SUCCESS (9.095s)
[586.558581] [127/133] (441s left) gem_exec_reloc (basic-gtt-cpu-active)
[586.624889] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[586.692805] [128/133] (441s left) gem_exec_balancer (bonded-false-hang)
[586.953740] Starting subtest: bonded-false-hang
[586.958013] Subtest bonded-false-hang: SKIP (0.000s)
[587.059982] [129/133] (440s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[587.158378] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[587.162519] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.000s)
[587.232160] [130/133] (440s left) gem_exec_create (legacy)
[587.295500] Starting subtest: legacy
[587.297829] Starting dynamic subtest: smem
[590.608916] Dynamic subtest smem: SUCCESS (3.312s)
[590.611508] Subtest legacy: SUCCESS (3.314s)
[590.799562] [131/133] (437s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[590.900401] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[590.904663] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[590.992491] [132/133] (436s left) gem_media_fill (media-fill)
[591.050332] Subtest media-fill: SKIP (0.000s)
[591.168228] [133/133] (436s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[591.259913] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[591.264003] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[591.364428] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: perf_pmu
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: perf
results: parsing output: 4/ for test: gem_fenced_exec_thrash
results: parsing output: 5/ for test: gem_ctx_persistence
results: parsing output: 6/ for test: kms_prime
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: gem_exec_schedule
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: gem_ctx_shared
results: parsing output: 11/ for test: gem_wait
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: gem_exec_store
results: parsing output: 15/ for test: kms_psr2_sf
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: sysfs_timeslice_duration
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: gem_lmem_swapping
results: parsing output: 20/ for test: kms_cdclk
results: parsing output: 21/ for test: syncobj_wait
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: gem_exec_params
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: i915_query
results: parsing output: 26/ for test: kms_chamelium_hpd
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_pm_rpm
results: parsing output: 30/ for test: kms_content_protection
results: parsing output: 31/ for test: syncobj_basic
results: parsing output: 32/ for test: prime_self_import
results: parsing output: 33/ for test: kms_psr
results: parsing output: 34/ for test: kms_plane_scaling
results: parsing output: 35/ for test: gem_ccs
results: parsing output: 36/ for test: prime_mmap
results: parsing output: 37/ for test: gen7_exec_parse
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: kms_cursor_crc
results: parsing output: 41/ for test: syncobj_eventfd
results: parsing output: 42/ for test: gem_render_copy
results: parsing output: 43/ for test: gem_exec_fence
results: parsing output: 44/ for test: core_getversion
results: parsing output: 45/ for test: kms_cursor_legacy
results: parsing output: 46/ for test: kms_color
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_flip_scaled_crc
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: kms_big_fb
results: parsing output: 52/ for test: kms_vblank
results: parsing output: 53/ for test: kms_sequence
results: parsing output: 54/ for test: kms_invalid_mode
results: parsing output: 55/ for test: kms_addfb_basic
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_flip
results: parsing output: 58/ for test: sw_sync
results: parsing output: 59/ for test: kms_atomic
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: gem_flink_basic
results: parsing output: 62/ for test: i915_getparams_basic
results: parsing output: 63/ for test: gem_spin_batch
results: parsing output: 64/ for test: gem_workarounds
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_rotation_crc
results: parsing output: 67/ for test: kms_psr
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_basic
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: kms_hdmi_inject
results: parsing output: 73/ for test: i915_drm_fdinfo
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: gem_ctx_persistence
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_rotation_crc
results: parsing output: 78/ for test: gem_render_copy
results: parsing output: 79/ for test: perf_pmu
results: parsing output: 80/ for test: api_intel_bb
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_exec_params
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_plane
results: parsing output: 85/ for test: kms_psr
results: parsing output: 86/ for test: kms_plane_multiple
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_render_copy
results: parsing output: 89/ for test: gem_exec_schedule
results: parsing output: 90/ for test: gem_mmap_offset
results: parsing output: 91/ for test: gem_ringfill
results: parsing output: 92/ for test: kms_rotation_crc
results: parsing output: 93/ for test: prime_busy
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_async_flips
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_pipe_b_c_ivb
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_cursor_edge_walk
results: parsing output: 101/ for test: gem_pxp
results: parsing output: 102/ for test: i915_pm_rps
results: parsing output: 103/ for test: gem_exec_params
results: parsing output: 104/ for test: i915_pm_rpm
results: parsing output: 105/ for test: kms_chamelium_edid
results: parsing output: 106/ for test: kms_draw_crc
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: perf
results: parsing output: 109/ for test: kms_atomic_transition
results: parsing output: 110/ for test: gem_mmap
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_busy
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: kms_lease
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: api_intel_bb
results: parsing output: 117/ for test: kms_pipe_crc_basic
results: parsing output: 118/ for test: syncobj_wait
results: parsing output: 119/ for test: syncobj_wait
results: parsing output: 120/ for test: kms_cursor_legacy
results: parsing output: 121/ for test: gem_ctx_param
results: parsing output: 122/ for test: gem_exec_flush
results: parsing output: 123/ for test: syncobj_timeline
results: parsing output: 124/ for test: gem_create
results: parsing output: 125/ for test: kms_flip
results: parsing output: 126/ for test: gem_exec_reloc
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: gem_exec_create
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_media_fill
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.