[59.636824] Initializing watchdogs
[59.636959]   /dev/watchdog0
[59.653481] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.657889] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.660110] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.663825] [001/132] (960s left) gem_exec_schedule (submit-early-slice)
[59.762268] Starting subtest: submit-early-slice
[59.765138] Starting dynamic subtest: rcs0
[59.825384] Dynamic subtest rcs0: SUCCESS (0.067s)
[59.838300] Starting dynamic subtest: bcs0
[59.887755] Dynamic subtest bcs0: SUCCESS (0.060s)
[59.897252] Starting dynamic subtest: vcs0
[59.943874] Dynamic subtest vcs0: SUCCESS (0.057s)
[59.954252] Starting dynamic subtest: vcs1
[60.000724] Dynamic subtest vcs1: SUCCESS (0.056s)
[60.009722] Starting dynamic subtest: vecs0
[60.050715] Dynamic subtest vecs0: SUCCESS (0.049s)
[60.060432] Starting dynamic subtest: ccs0
[60.106367] Dynamic subtest ccs0: SUCCESS (0.055s)
[60.108838] Subtest submit-early-slice: SUCCESS (0.345s)
[60.967381] [002/132] (959s left) kms_vblank (query-forked-busy-hang)
[61.083084] Starting subtest: query-forked-busy-hang
[61.085327] Starting dynamic subtest: pipe-A-eDP-1
[87.648165] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.506s)
[87.661589] Starting dynamic subtest: pipe-D-eDP-1
[102.691251] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.032s)
[102.693608] Subtest query-forked-busy-hang: SUCCESS (41.542s)
[103.561939] [003/132] (916s left) kms_dsc (dsc-fractional-bpp)
[103.657176] Starting subtest: dsc-fractional-bpp
[103.670753] Subtest dsc-fractional-bpp: SKIP (0.000s)
[103.727260] [004/132] (916s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[103.830042] Starting subtest: yf-tiled-16bpp-rotate-90
[103.834192] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[103.920197] [005/132] (916s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[104.017471] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[104.022030] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[104.134533] [006/132] (916s left) prime_udl
[104.215196] [007/132] (916s left) gem_softpin (zero)
[104.280135] Starting subtest: zero
[104.286447] Subtest zero: SUCCESS (0.008s)
[104.403722] [008/132] (916s left) gem_ringfill (legacy-basic)
[109.912939] Starting subtest: legacy-basic
[109.915365] Starting dynamic subtest: default
[109.969273] Dynamic subtest default: SUCCESS (0.057s)
[109.978996] Starting dynamic subtest: render
[110.012381] Dynamic subtest render: SUCCESS (0.042s)
[110.021125] Starting dynamic subtest: bsd
[110.056782] Dynamic subtest bsd: SUCCESS (0.043s)
[110.067996] Starting dynamic subtest: bsd1
[110.096240] Dynamic subtest bsd1: SUCCESS (0.039s)
[110.104846] Starting dynamic subtest: bsd2
[110.139605] Dynamic subtest bsd2: SUCCESS (0.041s)
[110.148040] Starting dynamic subtest: blt
[110.188286] Dynamic subtest blt: SUCCESS (0.046s)
[110.203074] Starting dynamic subtest: vebox
[110.238366] Dynamic subtest vebox: SUCCESS (0.048s)
[110.241100] Subtest legacy-basic: SUCCESS (0.329s)
[110.315983] [FACT gem_ringfill (legacy-basic)] new: kernel.kmod_is_loaded.vgem: true
[110.319254] [009/132] (910s left) kms_big_fb (linear-64bpp-rotate-90)
[110.422349] Starting subtest: linear-64bpp-rotate-90
[110.444428] Subtest linear-64bpp-rotate-90: SKIP (0.019s)
[110.544432] [010/132] (909s left) kms_properties (colorop-properties-legacy)
[110.623377] Starting subtest: colorop-properties-legacy
[110.625773] Starting dynamic subtest: pipe-A-eDP-1
[111.014356] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.390s)
[111.028718] Starting dynamic subtest: pipe-B-eDP-1
[112.114961] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.099s)
[112.124409] Starting dynamic subtest: pipe-C-eDP-1
[113.156841] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.039s)
[113.165990] Starting dynamic subtest: pipe-D-eDP-1
[114.197524] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.039s)
[114.199820] Subtest colorop-properties-legacy: SUCCESS (3.571s)
[114.999621] [011/132] (905s left) prime_busy (after)
[115.053176] Starting subtest: after
[115.055559] Starting dynamic subtest: rcs0
[115.090355] Dynamic subtest rcs0: SUCCESS (0.035s)
[115.097606] Starting dynamic subtest: bcs0
[115.141745] Dynamic subtest bcs0: SUCCESS (0.051s)
[115.150871] Starting dynamic subtest: vcs0
[115.182835] Dynamic subtest vcs0: SUCCESS (0.041s)
[115.191050] Starting dynamic subtest: vcs1
[115.223773] Dynamic subtest vcs1: SUCCESS (0.041s)
[115.231424] Starting dynamic subtest: vecs0
[115.264171] Dynamic subtest vecs0: SUCCESS (0.040s)
[115.272330] Starting dynamic subtest: ccs0
[115.305297] Dynamic subtest ccs0: SUCCESS (0.041s)
[115.413927] Subtest after: SUCCESS (0.359s)
[115.498685] [012/132] (905s left) syncobj_timeline (wait-zero-handles)
[115.550979] Starting subtest: wait-zero-handles
[115.553067] Subtest wait-zero-handles: SUCCESS (0.000s)
[115.644566] [013/132] (904s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[115.744809] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[115.749365] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[115.833186] [014/132] (904s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[115.938987] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[115.941378] Starting dynamic subtest: pipe-A-eDP-1
[116.025218] Dynamic subtest pipe-A-eDP-1: SKIP (0.090s)
[116.034200] Starting dynamic subtest: pipe-B-eDP-1
[117.176920] Dynamic subtest pipe-B-eDP-1: SKIP (1.151s)
[117.181790] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.243s)
[118.358184] [015/132] (902s left) gem_lmem_swapping (heavy-verify-random-ccs)
[118.401234] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[118.444541] [016/132] (902s left) gem_ctx_param (invalid-ctx-get)
[118.495590] Starting subtest: invalid-ctx-get
[118.498039] Subtest invalid-ctx-get: SUCCESS (0.000s)
[118.569463] [017/132] (902s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[118.652126] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[118.657992] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[119.785777] [018/132] (901s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite)
[119.890655] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite
[119.894987] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[119.986492] [019/132] (900s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[120.095349] Starting subtest: fbcpsr-rgb565-draw-render
[127.215284] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.123s)
[127.354569] [020/132] (893s left) gem_ctx_param (invalid-get-engines)
[127.414098] Starting subtest: invalid-get-engines
[127.416578] Subtest invalid-get-engines: SUCCESS (0.002s)
[127.530211] [021/132] (893s left) gen9_exec_parse (shadow-peek)
[127.588525] Subtest shadow-peek: SKIP (0.000s)
[127.669996] [022/132] (893s left) gem_readwrite (beyond-eob)
[127.725901] Subtest beyond-EOB: SKIP (0.000s)
[127.787862] [023/132] (893s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[127.849807] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[127.953866] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.102s)
[128.026049] [024/132] (893s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[128.130801] Starting subtest: y-tiled-32bpp-rotate-0
[128.134935] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[128.239778] [025/132] (892s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[128.333109] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[128.335370] Starting dynamic subtest: pipe-A-eDP-1
[128.356069] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[128.365511] Starting dynamic subtest: pipe-B-eDP-1
[128.384320] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[128.391878] Starting dynamic subtest: pipe-C-eDP-1
[128.411126] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[128.418403] Starting dynamic subtest: pipe-D-eDP-1
[128.437833] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[128.440032] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[130.590277] [026/132] (890s left) kms_psr (psr2-sprite-blt)
[130.684544] Starting subtest: psr2-sprite-blt
[130.686784] Starting dynamic subtest: eDP-1
[132.391437] Dynamic subtest eDP-1: SUCCESS (1.708s)
[132.393798] Subtest psr2-sprite-blt: SUCCESS (1.708s)
[133.272034] [027/132] (887s left) gem_exec_reloc (basic-concurrent0)
[133.324789] Subtest basic-concurrent0: SKIP (0.000s)
[133.394126] [028/132] (887s left) gem_eio (hibernate)
[133.614759] Starting subtest: hibernate
[148.925058] Subtest hibernate: SUCCESS (15.306s)
[155.054118] [029/132] (872s left) kms_scaling_modes (scaling-mode-center)
[155.183909] Starting subtest: scaling-mode-center
[155.186283] Starting dynamic subtest: pipe-A-eDP-1
[155.292870] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.111s)
[155.302086] Starting dynamic subtest: pipe-B-eDP-1
[156.474296] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.180s)
[156.484989] Starting dynamic subtest: pipe-C-eDP-1
[157.614520] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.138s)
[157.632720] Starting dynamic subtest: pipe-D-eDP-1
[158.744074] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[158.746530] Subtest scaling-mode-center: SUCCESS (3.562s)
[159.906030] [030/132] (867s left) perf (short-reads)
[160.037579] Starting subtest: short-reads
[160.049633] Subtest short-reads: SUCCESS (0.014s)
[160.090419] [031/132] (867s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[160.185043] Starting subtest: addfb25-y-tiled-small-legacy
[160.189365] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[160.277776] [032/132] (867s left) gem_exec_reloc (basic-write-read-noreloc)
[160.336590] Subtest basic-write-read-noreloc: SKIP (0.000s)
[160.401487] [033/132] (867s left) gem_mmap_offset (oob-read)
[160.454064] Starting subtest: oob-read
[160.456274] Subtest oob-read: SUCCESS (0.000s)
[160.561748] [034/132] (866s left) gem_exec_balancer (bonded-false-hang)
[160.820019] Starting subtest: bonded-false-hang
[160.825071] Subtest bonded-false-hang: SKIP (0.000s)
[160.929119] [035/132] (866s left) core_getclient (basic)
[160.987797] Starting subtest: basic
[160.989698] Subtest basic: SUCCESS (0.000s)
[161.082238] [036/132] (866s left) kms_cursor_crc (cursor-sliding-64x64)
[161.259455] Starting subtest: cursor-sliding-64x64
[161.261768] Starting dynamic subtest: pipe-A-eDP-1
[164.526884] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.266s)
[164.556654] Starting dynamic subtest: pipe-D-eDP-1
[168.406847] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.855s)
[168.409292] Subtest cursor-sliding-64x64: SUCCESS (7.147s)
[169.274052] [037/132] (858s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[169.361576] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[173.075650] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.717s)
[174.227186] [038/132] (853s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[174.329800] Starting subtest: basic-max-pipe-crc-check
[174.334426] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[174.380893] [039/132] (853s left) kms_chamelium_edid (hdmi-mode-timings)
[174.771483] Subtest hdmi-mode-timings: SKIP (0.000s)
[175.610510] [040/132] (852s left) kms_pm_rpm (fences)
[177.142631] Starting subtest: fences
[177.147422] Subtest fences: SKIP (0.000s)
[177.883108] [041/132] (849s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[177.974972] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[178.004282] Starting dynamic subtest: pipe-A-plane-0
[178.293215] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[178.303031] Starting dynamic subtest: pipe-A-plane-3
[178.526298] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[178.535253] Starting dynamic subtest: pipe-A-plane-5
[178.539907] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[179.660308] Starting dynamic subtest: pipe-B-plane-0
[179.963757] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[179.975682] Starting dynamic subtest: pipe-B-plane-3
[180.208431] Dynamic subtest pipe-B-plane-3: SUCCESS (0.244s)
[180.218293] Starting dynamic subtest: pipe-B-plane-5
[180.222834] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[180.523310] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.549s)
[181.334408] [042/132] (846s left) dmabuf_sync_file (export-basic)
[181.372944] Starting subtest: export-basic
[181.375116] Subtest export-basic: SUCCESS (0.001s)
[181.475309] [043/132] (846s left) gem_softpin (softpin)
[181.543066] Starting subtest: softpin
[213.780607] Subtest softpin: SUCCESS (32.232s)
[213.907105] [044/132] (814s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc)
[214.006818] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc
[214.009122] Starting dynamic subtest: pipe-A-eDP-1
[214.031429] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[214.040508] Starting dynamic subtest: pipe-B-eDP-1
[214.060846] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[214.069090] Starting dynamic subtest: pipe-C-eDP-1
[214.089286] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[214.097748] Starting dynamic subtest: pipe-D-eDP-1
[214.118413] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[214.122555] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[216.306281] [045/132] (811s left) api_intel_allocator (simple-allocator)
[216.362075] Starting subtest: simple-allocator
[216.364409] Starting dynamic subtest: basic
[216.366543] Dynamic subtest basic: SUCCESS (0.003s)
[216.380092] Starting dynamic subtest: parallel-one
[216.537024] Dynamic subtest parallel-one: SUCCESS (0.172s)
[216.550967] Starting dynamic subtest: print
[216.553828] Dynamic subtest print: SUCCESS (0.000s)
[216.562735] Starting dynamic subtest: reuse
[216.564647] Dynamic subtest reuse: SUCCESS (0.000s)
[216.573064] Starting dynamic subtest: reserve
[216.574887] Dynamic subtest reserve: SUCCESS (0.000s)
[216.582907] Starting dynamic subtest: fork-reopen-allocator
[216.584751] Dynamic subtest fork-reopen-allocator: SUCCESS (0.011s)
[216.586656] Subtest simple-allocator: SUCCESS (0.192s)
[216.673285] [046/132] (811s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[216.734683] Subtest writes-after-reads: SKIP (0.000s)
[216.790313] [047/132] (811s left) kms_color (ctm-0-50)
[216.888207] Starting subtest: ctm-0-50
[216.890297] Starting dynamic subtest: pipe-A-eDP-1
[220.441617] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.553s)
[220.451636] Starting dynamic subtest: pipe-B-eDP-1
[224.624526] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.182s)
[224.634850] Starting dynamic subtest: pipe-C-eDP-1
[228.594432] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.969s)
[228.604063] Starting dynamic subtest: pipe-D-eDP-1
[232.569587] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.974s)
[232.571567] Subtest ctm-0-50: SUCCESS (15.681s)
[233.401613] [048/132] (794s left) i915_pm_rps (min-max-config-idle)
[233.460352] Starting subtest: min-max-config-idle
[233.464818] Subtest min-max-config-idle: SKIP (0.001s)
[233.562537] [049/132] (794s left) kms_flip (modeset-vs-vblank-race-interruptible)
[233.666449] Starting subtest: modeset-vs-vblank-race-interruptible
[233.668789] Starting dynamic subtest: A-eDP1
[238.324423] Dynamic subtest A-eDP1: SUCCESS (4.657s)
[238.338845] Starting dynamic subtest: B-eDP1
[242.942333] Dynamic subtest B-eDP1: SUCCESS (4.615s)
[242.952209] Starting dynamic subtest: C-eDP1
[247.483844] Dynamic subtest C-eDP1: SUCCESS (4.540s)
[247.492849] Starting dynamic subtest: D-eDP1
[251.953882] Dynamic subtest D-eDP1: SUCCESS (4.468s)
[251.958983] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.291s)
[253.134774] [050/132] (775s left) kms_flip (wf_vblank-ts-check-interruptible)
[253.232802] Starting subtest: wf_vblank-ts-check-interruptible
[253.235158] Starting dynamic subtest: A-eDP1
[262.189241] Dynamic subtest A-eDP1: SUCCESS (8.955s)
[262.200865] Starting dynamic subtest: B-eDP1
[271.120875] Dynamic subtest B-eDP1: SUCCESS (8.928s)
[271.134518] Starting dynamic subtest: C-eDP1
[280.050361] Dynamic subtest C-eDP1: SUCCESS (8.926s)
[280.063723] Starting dynamic subtest: D-eDP1
[288.985303] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[288.990483] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.754s)
[290.224672] [051/132] (738s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[290.323286] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[290.327141] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[290.426256] [052/132] (737s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[290.522064] Starting subtest: psr-modesetfrombusy
[296.684897] Subtest psr-modesetfrombusy: SUCCESS (6.167s)
[296.824977] [053/132] (731s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[296.916592] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[296.918615] Starting dynamic subtest: pipe-A-eDP-1
[296.938086] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[296.950073] Starting dynamic subtest: pipe-B-eDP-1
[296.967372] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[296.975704] Starting dynamic subtest: pipe-C-eDP-1
[296.993471] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[297.001645] Starting dynamic subtest: pipe-D-eDP-1
[297.019640] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[297.023265] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.004s)
[299.265862] [054/132] (729s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[299.325744] Starting subtest: map-fixed-invalidate-overlap
[299.330301] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[299.471150] [055/132] (728s left) fbdev (info)
[299.507755] Starting subtest: info
[299.509722] Subtest info: SUCCESS (0.000s)
[299.577184] [056/132] (728s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[299.966209] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[300.761665] [057/132] (727s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[300.856599] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[309.571307] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.718s)
[309.685541] [058/132] (718s left) perf_pmu (interrupts-sync)
[309.727163] Starting subtest: interrupts-sync
[310.834680] Subtest interrupts-sync: SUCCESS (1.108s)
[310.940380] [059/132] (717s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[311.041328] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[315.564552] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (4.526s)
[316.712427] [060/132] (711s left) gem_ctx_sseu (mmap-args)
[316.777482] Subtest mmap-args: SKIP (0.000s)
[316.849420] [061/132] (711s left) kms_psr (pr-sprite-plane-move)
[316.953853] Starting subtest: pr-sprite-plane-move
[316.957724] Subtest pr-sprite-plane-move: SKIP (0.000s)
[317.024820] [062/132] (711s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[317.129245] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[323.676312] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.552s)
[323.785562] [063/132] (704s left) kms_invalid_mode (zero-vdisplay)
[323.873961] Starting subtest: zero-vdisplay
[323.876143] Starting dynamic subtest: pipe-A-eDP-1
[324.171275] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.297s)
[324.180713] Starting dynamic subtest: pipe-B-eDP-1
[324.182917] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[324.191642] Starting dynamic subtest: pipe-C-eDP-1
[324.193824] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[324.202556] Starting dynamic subtest: pipe-D-eDP-1
[324.204459] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[324.206367] Subtest zero-vdisplay: SUCCESS (0.303s)
[325.037347] [064/132] (703s left) gem_eio (wait-10ms)
[325.274573] Starting subtest: wait-10ms
[325.633141] Subtest wait-10ms: SUCCESS (0.359s)
[325.747328] [065/132] (703s left) kms_frontbuffer_tracking (psr-1p-rte)
[325.832659] Starting subtest: psr-1p-rte
[331.276855] Subtest psr-1p-rte: SUCCESS (5.448s)
[331.432502] [066/132] (697s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[331.543587] Starting subtest: 4-tiled-16bpp-rotate-0
[332.835452] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.292s)
[332.947963] [067/132] (695s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[333.047403] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[333.058392] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s)
[333.168972] [068/132] (695s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[333.269113] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[333.272650] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[333.375774] [069/132] (695s left) prime_mmap_kms (buffer-sharing)
[333.465430] Starting subtest: buffer-sharing
[333.923310] Subtest buffer-sharing: SUCCESS (0.459s)
[334.801138] [070/132] (694s left) kms_sequence (queue-busy)
[334.904049] Starting subtest: queue-busy
[334.905948] Starting dynamic subtest: pipe-A-eDP-1
[336.827449] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.921s)
[336.838090] Starting dynamic subtest: pipe-B-eDP-1
[339.399834] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.571s)
[339.409844] Starting dynamic subtest: pipe-C-eDP-1
[342.002684] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.601s)
[342.013457] Starting dynamic subtest: pipe-D-eDP-1
[344.549705] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.548s)
[344.551826] Subtest queue-busy: SUCCESS (9.647s)
[345.355270] [071/132] (683s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[345.452594] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[345.456957] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[345.550177] [072/132] (683s left) gem_exec_schedule (u-submit-late-slice)
[345.616708] Starting subtest: u-submit-late-slice
[345.618825] Starting dynamic subtest: rcs0
[345.653914] Dynamic subtest rcs0: SUCCESS (0.044s)
[345.663715] Starting dynamic subtest: bcs0
[345.690764] Dynamic subtest bcs0: SUCCESS (0.036s)
[345.699946] Starting dynamic subtest: vcs0
[345.723885] Dynamic subtest vcs0: SUCCESS (0.032s)
[345.733670] Starting dynamic subtest: vcs1
[345.755088] Dynamic subtest vcs1: SUCCESS (0.030s)
[345.765080] Starting dynamic subtest: vecs0
[345.788873] Dynamic subtest vecs0: SUCCESS (0.033s)
[345.798730] Starting dynamic subtest: ccs0
[345.827682] Dynamic subtest ccs0: SUCCESS (0.038s)
[345.829982] Subtest u-submit-late-slice: SUCCESS (0.219s)
[346.986838] [073/132] (682s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[347.111366] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[347.115953] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[347.221336] [074/132] (682s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[347.268419] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[347.376675] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.108s)
[347.467400] [075/132] (681s left) gem_render_linear_blits (basic)
[347.517696] Starting subtest: basic
[347.540910] Subtest basic: SUCCESS (0.023s)
[347.626093] [076/132] (681s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[347.716122] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[347.718412] Starting dynamic subtest: pipe-A
[348.056452] Dynamic subtest pipe-A: SUCCESS (0.342s)
[348.066956] Starting dynamic subtest: pipe-B
[349.444501] Dynamic subtest pipe-B: SUCCESS (1.387s)
[349.454498] Starting dynamic subtest: pipe-C
[350.879487] Dynamic subtest pipe-C: SUCCESS (1.434s)
[350.888802] Starting dynamic subtest: pipe-D
[352.312376] Dynamic subtest pipe-D: SUCCESS (1.432s)
[352.314757] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.598s)
[353.488838] [077/132] (675s left) syncobj_timeline (single-wait-for-submit-signaled)
[353.558892] Starting subtest: single-wait-for-submit-signaled
[353.561123] Subtest single-wait-for-submit-signaled: SUCCESS (0.001s)
[353.675717] [078/132] (675s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[353.786830] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[353.791162] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[353.883392] [079/132] (675s left) kms_psr (fbc-pr-primary-render)
[353.984349] Starting subtest: fbc-pr-primary-render
[353.988470] Subtest fbc-pr-primary-render: SKIP (0.000s)
[354.055685] [080/132] (675s left) gem_ctx_engines (execute-one)
[354.110519] Starting subtest: execute-one
[356.856744] Subtest execute-one: SUCCESS (2.746s)
[357.046104] [081/132] (672s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[357.152196] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[364.146062] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.994s)
[364.298933] [082/132] (665s left) gem_exec_capture (capture-invisible)
[364.353083] Starting subtest: capture-invisible
[364.355491] Starting dynamic subtest: smem0
[364.359456] Dynamic subtest smem0: SKIP (0.000s)
[364.363748] Subtest capture-invisible: SKIP (0.001s)
[364.464048] [083/132] (665s left) gem_eio (in-flight-immediate)
[364.698515] Starting subtest: in-flight-immediate
[372.958101] Subtest in-flight-immediate: SUCCESS (8.259s)
[373.113945] [084/132] (656s left) kms_cursor_crc (cursor-alpha-opaque)
[373.200393] Starting subtest: cursor-alpha-opaque
[373.202529] Starting dynamic subtest: pipe-A-eDP-1
[373.820067] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.620s)
[373.830381] Starting dynamic subtest: pipe-D-eDP-1
[375.084923] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.264s)
[375.087739] Subtest cursor-alpha-opaque: SUCCESS (1.887s)
[375.903623] [085/132] (653s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[375.965530] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[376.027519] [086/132] (653s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[376.090420] Starting subtest: y-tiled-ccs-to-x-tiled
[376.095062] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[376.278703] [087/132] (653s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[376.336534] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[376.338957] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.000s)
[376.437147] [088/132] (653s left) perf (invalid-oa-exponent)
[376.552315] Starting subtest: invalid-oa-exponent
[376.564429] Subtest invalid-oa-exponent: SUCCESS (0.011s)
[376.611306] [089/132] (653s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[376.698491] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[376.700859] Starting dynamic subtest: pipe-A-default-mode
[379.451135] Dynamic subtest pipe-A-default-mode: SUCCESS (2.750s)
[379.453305] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.751s)
[380.289637] [090/132] (649s left) i915_selftest (mock)
[382.016233] Starting subtest: mock
[382.027694] Starting dynamic subtest: sanitycheck
[382.688175] Dynamic subtest sanitycheck: SUCCESS (0.692s)
[382.698431] Starting dynamic subtest: shmem
[383.360058] Dynamic subtest shmem: SUCCESS (0.670s)
[383.370764] Starting dynamic subtest: fence
[391.071283] Dynamic subtest fence: SUCCESS (7.708s)
[391.081437] Starting dynamic subtest: scatterlist
[392.763452] Dynamic subtest scatterlist: SUCCESS (1.690s)
[392.774236] Starting dynamic subtest: syncmap
[395.704718] Dynamic subtest syncmap: SUCCESS (2.938s)
[395.715722] Starting dynamic subtest: uncore
[396.380314] Dynamic subtest uncore: SUCCESS (0.674s)
[396.390722] Starting dynamic subtest: ring
[397.107342] Dynamic subtest ring: SUCCESS (0.725s)
[397.117466] Starting dynamic subtest: engine
[397.818824] Dynamic subtest engine: SUCCESS (0.709s)
[397.828995] Starting dynamic subtest: timelines
[401.619952] Dynamic subtest timelines: SUCCESS (3.798s)
[401.629835] Starting dynamic subtest: requests
[405.283742] Dynamic subtest requests: SUCCESS (3.662s)
[405.298890] Starting dynamic subtest: objects
[406.059243] Dynamic subtest objects: SUCCESS (0.774s)
[406.069248] Starting dynamic subtest: phys
[406.829866] Dynamic subtest phys: SUCCESS (0.769s)
[406.840039] Starting dynamic subtest: dmabuf
[407.586865] Dynamic subtest dmabuf: SUCCESS (0.756s)
[407.596827] Starting dynamic subtest: vma
[413.247930] Dynamic subtest vma: SUCCESS (5.658s)
[413.259351] Starting dynamic subtest: evict
[414.659911] Dynamic subtest evict: SUCCESS (1.410s)
[414.670283] Starting dynamic subtest: gtt
[417.980430] Dynamic subtest gtt: SUCCESS (3.319s)
[417.990786] Starting dynamic subtest: hugepages
[418.882956] Dynamic subtest hugepages: SUCCESS (0.902s)
[418.892769] Starting dynamic subtest: memory_region
[421.383093] Dynamic subtest memory_region: SUCCESS (2.497s)
[421.386034] Subtest mock: SUCCESS (39.383s)
[421.415761] [FACT i915_selftest (mock)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[421.416458] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[421.419661] [091/132] (608s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[423.053747] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[429.990487] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.937s)
[430.134038] [FACT kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[430.135272] [FACT kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)] new: kernel.kmod_is_loaded.i915: true
[430.139493] [092/132] (599s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[430.248357] Starting subtest: fbc-psr-sprite-mmap-gtt
[430.250638] Starting dynamic subtest: eDP-1
[430.254713] Dynamic subtest eDP-1: SKIP (0.000s)
[430.258717] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.001s)
[430.320336] [093/132] (599s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[430.460992] Starting subtest: dp-mst-type-1-suspend-resume
[430.465584] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[430.615195] [094/132] (599s left) kms_big_fb (x-tiled-addfb)
[430.712072] Starting subtest: x-tiled-addfb
[430.714425] Subtest x-tiled-addfb: SUCCESS (0.000s)
[430.807597] [095/132] (599s left) kms_big_fb (4-tiled-addfb-size-overflow)
[430.902019] Starting subtest: 4-tiled-addfb-size-overflow
[430.904311] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[431.003897] [096/132] (599s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[431.098245] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[431.101797] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[431.193383] [097/132] (599s left) kms_lease (cursor-implicit-plane)
[431.285409] Starting subtest: cursor-implicit-plane
[431.287376] Starting dynamic subtest: pipe-A-eDP-1
[431.761133] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.475s)
[431.772525] Starting dynamic subtest: pipe-B-eDP-1
[432.889485] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.129s)
[432.899122] Starting dynamic subtest: pipe-C-eDP-1
[434.009645] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.118s)
[434.019972] Starting dynamic subtest: pipe-D-eDP-1
[435.152747] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.141s)
[435.155058] Subtest cursor-implicit-plane: SUCCESS (3.871s)
[436.020090] [098/132] (594s left) kms_fbcon_fbt (fbc-suspend)
[436.077594] Starting subtest: fbc-suspend
[485.026376] Subtest fbc-suspend: SUCCESS (19.882s)
[487.374220] [099/132] (543s left) gem_vm_create (create-ext)
[487.444265] Starting subtest: create-ext
[487.450336] Subtest create-ext: SUCCESS (0.006s)
[487.557945] [100/132] (543s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[487.653727] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[487.658230] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[487.754023] [101/132] (543s left) gem_mmap (bad-object)
[487.817718] Subtest bad-object: SKIP (0.000s)
[487.883923] [102/132] (543s left) gem_reg_read (timestamp-moving)
[487.945378] Starting subtest: timestamp-moving
[488.947075] Subtest timestamp-moving: SUCCESS (0.963s)
[489.039956] [103/132] (542s left) gem_pxp (display-protected-crc)
[489.152119] Starting subtest: display-protected-crc
[490.879712] Subtest display-protected-crc: SUCCESS (1.675s)
[491.006049] [104/132] (540s left) syncobj_timeline (invalid-signal-illegal-point)
[491.077418] Starting subtest: invalid-signal-illegal-point
[491.079799] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[491.175635] [105/132] (540s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[491.279030] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[491.281409] Starting dynamic subtest: pipe-A-default-mode
[494.017821] Dynamic subtest pipe-A-default-mode: SUCCESS (2.673s)
[494.019795] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.675s)
[494.904272] [106/132] (536s left) gem_partial_pwrite_pread (reads)
[494.960762] Subtest reads: SKIP (0.000s)
[495.028753] [107/132] (536s left) perf (gen12-unprivileged-single-ctx-counters)
[495.163981] Starting subtest: gen12-unprivileged-single-ctx-counters
[495.166082] Starting dynamic subtest: rcs0
[495.202076] Dynamic subtest rcs0: SUCCESS (0.039s)
[495.204373] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.040s)
[495.257044] [108/132] (536s left) kms_plane (plane-position-hole)
[495.370734] Starting subtest: plane-position-hole
[495.900533] Starting dynamic subtest: pipe-A-plane-1
[497.252399] Dynamic subtest pipe-A-plane-1: SUCCESS (1.332s)
[497.262347] Starting dynamic subtest: pipe-A-plane-2
[498.612929] Dynamic subtest pipe-A-plane-2: SUCCESS (1.343s)
[498.622831] Starting dynamic subtest: pipe-A-plane-3
[499.923735] Dynamic subtest pipe-A-plane-3: SUCCESS (1.296s)
[499.934225] Starting dynamic subtest: pipe-A-plane-4
[501.265679] Dynamic subtest pipe-A-plane-4: SUCCESS (1.331s)
[501.275905] Starting dynamic subtest: pipe-A-plane-5
[501.291780] Dynamic subtest pipe-A-plane-5: SUCCESS (0.023s)
[502.523005] Starting dynamic subtest: pipe-B-plane-1
[503.873158] Dynamic subtest pipe-B-plane-1: SUCCESS (1.351s)
[503.884971] Starting dynamic subtest: pipe-B-plane-2
[505.174779] Dynamic subtest pipe-B-plane-2: SUCCESS (1.292s)
[505.184868] Starting dynamic subtest: pipe-B-plane-3
[506.524913] Dynamic subtest pipe-B-plane-3: SUCCESS (1.343s)
[506.534943] Starting dynamic subtest: pipe-B-plane-4
[507.865616] Dynamic subtest pipe-B-plane-4: SUCCESS (1.334s)
[507.876193] Starting dynamic subtest: pipe-B-plane-5
[507.893709] Dynamic subtest pipe-B-plane-5: SUCCESS (0.027s)
[507.895873] Subtest plane-position-hole: SUCCESS (12.420s)
[508.743359] [109/132] (523s left) gem_exec_schedule (implicit-read-write)
[508.813855] Starting subtest: implicit-read-write
[508.815775] Starting dynamic subtest: rcs0
[508.917727] Dynamic subtest rcs0: SUCCESS (0.113s)
[508.930341] Starting dynamic subtest: vcs0
[509.000202] Dynamic subtest vcs0: SUCCESS (0.081s)
[509.011876] Starting dynamic subtest: vcs1
[509.078042] Dynamic subtest vcs1: SUCCESS (0.077s)
[509.090785] Starting dynamic subtest: vecs0
[509.163097] Dynamic subtest vecs0: SUCCESS (0.084s)
[509.175393] Starting dynamic subtest: ccs0
[509.242390] Dynamic subtest ccs0: SUCCESS (0.077s)
[509.244968] Subtest implicit-read-write: SUCCESS (0.438s)
[511.087143] [110/132] (520s left) gem_exec_flush (basic-batch-kernel-default-uc)
[511.156506] Starting subtest: basic-batch-kernel-default-uc
[517.871418] Subtest basic-batch-kernel-default-uc: SUCCESS (6.704s)
[518.061093] [111/132] (513s left) gem_ctx_persistence (heartbeat-stop)
[518.117313] Starting subtest: heartbeat-stop
[518.121809] Subtest heartbeat-stop: SKIP (0.000s)
[518.228367] [112/132] (513s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[518.433680] Starting subtest: cursor-rapid-movement-128x128
[518.440849] Starting dynamic subtest: pipe-A-eDP-1
[518.999201] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.556s)
[519.029332] Starting dynamic subtest: pipe-D-eDP-1
[520.177548] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.157s)
[520.179938] Subtest cursor-rapid-movement-128x128: SUCCESS (1.742s)
[521.033937] [113/132] (510s left) kms_cdclk (plane-scaling)
[521.130237] Starting subtest: plane-scaling
[521.132330] Starting dynamic subtest: pipe-A-eDP-1
[521.429540] Dynamic subtest pipe-A-eDP-1: SKIP (0.297s)
[521.439845] Starting dynamic subtest: pipe-B-eDP-1
[521.444317] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[521.453850] Starting dynamic subtest: pipe-C-eDP-1
[521.458243] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[521.467345] Starting dynamic subtest: pipe-D-eDP-1
[521.471569] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[521.475702] Subtest plane-scaling: SKIP (0.305s)
[522.260213] [114/132] (509s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier)
[522.359763] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier
[522.364122] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier: SKIP (0.000s)
[522.432067] [115/132] (509s left) perf_pmu (idle)
[522.473970] Starting subtest: idle
[522.476310] Starting dynamic subtest: rcs0
[523.023312] Dynamic subtest rcs0: SUCCESS (0.547s)
[523.036362] Starting dynamic subtest: bcs0
[523.540332] Dynamic subtest bcs0: SUCCESS (0.513s)
[523.553228] Starting dynamic subtest: vcs0
[524.055501] Dynamic subtest vcs0: SUCCESS (0.512s)
[524.067923] Starting dynamic subtest: vcs1
[524.571037] Dynamic subtest vcs1: SUCCESS (0.511s)
[524.583266] Starting dynamic subtest: vecs0
[525.087148] Dynamic subtest vecs0: SUCCESS (0.513s)
[525.098918] Starting dynamic subtest: ccs0
[525.608285] Dynamic subtest ccs0: SUCCESS (0.515s)
[525.610700] Subtest idle: SUCCESS (3.129s)
[525.725664] [116/132] (506s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[525.829273] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[525.833660] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[525.903822] [117/132] (506s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[525.957530] Starting subtest: invalid-bsd1-flag-on-vebox
[525.959880] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[526.055683] [118/132] (506s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[526.142597] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[526.144518] Starting dynamic subtest: pipe-A-eDP-1
[527.511368] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.367s)
[527.795083] Starting dynamic subtest: pipe-B-eDP-1
[528.825721] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.039s)
[529.093826] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.948s)
[529.956023] [119/132] (502s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[530.043598] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[530.052743] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[530.137316] [120/132] (502s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[530.239983] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[530.243885] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[530.341015] [121/132] (501s left) gen7_exec_parse (basic-rejected)
[530.396407] Subtest basic-rejected: SKIP (0.000s)
[530.464861] [122/132] (501s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[530.559605] Starting subtest: psr2-cursor-plane-update-sf
[530.561906] Starting dynamic subtest: pipe-A-eDP-1
[531.116814] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.561s)
[531.127369] Starting dynamic subtest: pipe-B-eDP-1
[532.428607] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.310s)
[532.430883] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.873s)
[533.294333] [123/132] (499s left) gem_exec_fence (syncobj-timeline-chain-engines)
[533.838905] Starting subtest: syncobj-timeline-chain-engines
[533.933109] Subtest syncobj-timeline-chain-engines: SUCCESS (0.095s)
[535.109518] [124/132] (497s left) gem_workarounds (reset-fd)
[535.183960] Starting subtest: reset-fd
[535.368997] Subtest reset-fd: SUCCESS (0.184s)
[535.509271] [125/132] (496s left) i915_module_load (resize-bar)
[535.548795] Starting subtest: resize-bar
[535.561715] Subtest resize-bar: SKIP (0.012s)
[535.611911] [126/132] (496s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[535.713533] Starting subtest: crc-primary-suspend-y-tiled-ccs
[535.715645] Starting dynamic subtest: pipe-A-eDP-1
[535.724051] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[535.734910] Starting dynamic subtest: pipe-B-eDP-1
[535.743521] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[535.752952] Starting dynamic subtest: pipe-C-eDP-1
[535.761375] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[535.770715] Starting dynamic subtest: pipe-D-eDP-1
[535.779179] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[535.783426] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.009s)
[537.961124] [127/132] (494s left) syncobj_timeline (etime-single-wait-submitted)
[538.007397] Starting subtest: etime-single-wait-submitted
[538.112484] Subtest etime-single-wait-submitted: SUCCESS (0.105s)
[538.189037] [128/132] (494s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[538.302179] Starting subtest: short-busy-flip-before-cursor-toggle
[538.306544] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[538.445704] [129/132] (494s left) gem_pxp (reject-modify-context-protection-on)
[538.504904] Starting subtest: reject-modify-context-protection-on
[538.507195] Subtest reject-modify-context-protection-on: SUCCESS (0.001s)
[538.613069] [130/132] (494s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[538.726896] Starting subtest: cursorB-vs-flipB-legacy
[538.731398] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[538.872924] [131/132] (493s left) kms_flip (busy-flip)
[538.960526] Starting subtest: busy-flip
[538.962724] Starting dynamic subtest: A-eDP1
[540.676944] Dynamic subtest A-eDP1: SUCCESS (1.717s)
[540.691920] Starting dynamic subtest: B-eDP1
[542.321757] Dynamic subtest B-eDP1: SUCCESS (1.641s)
[542.334134] Starting dynamic subtest: C-eDP1
[543.953052] Dynamic subtest C-eDP1: SUCCESS (1.627s)
[543.964407] Starting dynamic subtest: D-eDP1
[545.603658] Dynamic subtest D-eDP1: SUCCESS (1.647s)
[545.608632] Subtest busy-flip: SUCCESS (6.647s)
[546.783661] [132/132] (485s left) perf (whitelisted-registers-userspace-config)
[546.903315] Starting subtest: whitelisted-registers-userspace-config
[546.905821] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[546.949088] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_schedule
results: parsing output: 1/ for test: kms_vblank
results: parsing output: 2/ for test: kms_dsc
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: prime_udl
results: parsing output: 6/ for test: gem_softpin
results: parsing output: 7/ for test: gem_ringfill
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: kms_properties
results: parsing output: 10/ for test: prime_busy
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_psr2_sf
results: parsing output: 14/ for test: gem_lmem_swapping
results: parsing output: 15/ for test: gem_ctx_param
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: gen9_exec_parse
results: parsing output: 21/ for test: gem_readwrite
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: gem_exec_reloc
results: parsing output: 27/ for test: gem_eio
results: parsing output: 28/ for test: kms_scaling_modes
results: parsing output: 29/ for test: perf
results: parsing output: 30/ for test: kms_addfb_basic
results: parsing output: 31/ for test: gem_exec_reloc
results: parsing output: 32/ for test: gem_mmap_offset
results: parsing output: 33/ for test: gem_exec_balancer
results: parsing output: 34/ for test: core_getclient
results: parsing output: 35/ for test: kms_cursor_crc
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_multipipe_modeset
results: parsing output: 38/ for test: kms_chamelium_edid
results: parsing output: 39/ for test: kms_pm_rpm
results: parsing output: 40/ for test: kms_plane
results: parsing output: 41/ for test: dmabuf_sync_file
results: parsing output: 42/ for test: gem_softpin
results: parsing output: 43/ for test: kms_ccs
results: parsing output: 44/ for test: api_intel_allocator
results: parsing output: 45/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 46/ for test: kms_color
results: parsing output: 47/ for test: i915_pm_rps
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_ccs
results: parsing output: 53/ for test: gem_userptr_blits
results: parsing output: 54/ for test: fbdev
results: parsing output: 55/ for test: kms_chamelium_hpd
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: perf_pmu
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_ctx_sseu
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_invalid_mode
results: parsing output: 63/ for test: gem_eio
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: prime_mmap_kms
results: parsing output: 69/ for test: kms_sequence
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_exec_schedule
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: syncobj_wait
results: parsing output: 74/ for test: gem_render_linear_blits
results: parsing output: 75/ for test: kms_plane_scaling
results: parsing output: 76/ for test: syncobj_timeline
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_psr
results: parsing output: 79/ for test: gem_ctx_engines
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_capture
results: parsing output: 82/ for test: gem_eio
results: parsing output: 83/ for test: kms_cursor_crc
results: parsing output: 84/ for test: gem_mmap_gtt
results: parsing output: 85/ for test: gem_render_copy
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: perf
results: parsing output: 88/ for test: kms_flip_scaled_crc
results: parsing output: 89/ for test: i915_selftest
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_psr
results: parsing output: 92/ for test: kms_content_protection
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_lease
results: parsing output: 97/ for test: kms_fbcon_fbt
results: parsing output: 98/ for test: gem_vm_create
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_mmap
results: parsing output: 101/ for test: gem_reg_read
results: parsing output: 102/ for test: gem_pxp
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: kms_flip_scaled_crc
results: parsing output: 105/ for test: gem_partial_pwrite_pread
results: parsing output: 106/ for test: perf
results: parsing output: 107/ for test: kms_plane
results: parsing output: 108/ for test: gem_exec_schedule
results: parsing output: 109/ for test: gem_exec_flush
results: parsing output: 110/ for test: gem_ctx_persistence
results: parsing output: 111/ for test: kms_cursor_crc
results: parsing output: 112/ for test: kms_cdclk
results: parsing output: 113/ for test: kms_plane
results: parsing output: 114/ for test: perf_pmu
results: parsing output: 115/ for test: kms_flip_scaled_crc
results: parsing output: 116/ for test: gem_exec_params
results: parsing output: 117/ for test: kms_atomic_transition
results: parsing output: 118/ for test: kms_flip
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gen7_exec_parse
results: parsing output: 121/ for test: kms_psr2_sf
results: parsing output: 122/ for test: gem_exec_fence
results: parsing output: 123/ for test: gem_workarounds
results: parsing output: 124/ for test: i915_module_load
results: parsing output: 125/ for test: kms_ccs
results: parsing output: 126/ for test: syncobj_timeline
results: parsing output: 127/ for test: kms_cursor_legacy
results: parsing output: 128/ for test: gem_pxp
results: parsing output: 129/ for test: kms_cursor_legacy
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: perf
Done.