[59.471796] Initializing watchdogs
[59.471849]   /dev/watchdog0
[59.486852] [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.491363] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.493481] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.495571] [001/131] (960s left) kms_psr (psr-cursor-plane-onoff)
[59.605130] Starting subtest: psr-cursor-plane-onoff
[59.606099] Starting dynamic subtest: eDP-1
[61.367528] Dynamic subtest eDP-1: SUCCESS (1.753s)
[61.368572] Subtest psr-cursor-plane-onoff: SUCCESS (1.753s)
[62.267061] [002/131] (957s left) kms_joiner (basic-ultra-joiner)
[62.361835] Starting subtest: basic-ultra-joiner
[62.363504] Subtest basic-ultra-joiner: SKIP (0.000s)
[62.446455] [003/131] (957s left) gem_tiled_pread_basic
[62.556834] [004/131] (957s left) gem_exec_fence (syncobj-invalid-flags)
[62.988275] Starting subtest: syncobj-invalid-flags
[62.989392] Subtest syncobj-invalid-flags: SUCCESS (0.001s)
[65.043073] [FACT gem_exec_fence (syncobj-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[65.044619] [005/131] (955s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[65.164466] Starting subtest: cursorB-vs-flipB-toggle
[65.166189] Subtest cursorB-vs-flipB-toggle: SKIP (0.001s)
[65.332055] [006/131] (954s left) kms_pipe_stress (stress-xrgb8888-untiled)
[65.436510] Starting subtest: stress-xrgb8888-untiled
[71.384788] Subtest stress-xrgb8888-untiled: SUCCESS (5.938s)
[71.429824] [007/131] (948s left) syncobj_timeline (wait-for-submit-complex)
[71.502611] Starting subtest: wait-for-submit-complex
[71.564887] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[71.660399] [008/131] (948s left) prime_mmap (test_aperture_limit)
[71.715405] Starting subtest: test_aperture_limit
[71.716400] Starting dynamic subtest: test_aperture_limit-smem
[71.725932] Dynamic subtest test_aperture_limit-smem: SKIP (0.010s)
[71.727785] Subtest test_aperture_limit: SKIP (0.010s)
[71.850800] [009/131] (948s left) gem_mmap_wc (read-write)
[71.908267] Subtest read-write: SKIP (0.000s)
[71.963439] [010/131] (948s left) gem_exec_parallel (fds)
[72.025646] Starting subtest: fds
[72.026837] Starting dynamic subtest: rcs0
[74.802893] Dynamic subtest rcs0: SUCCESS (2.772s)
[74.812230] Starting dynamic subtest: bcs0
[78.227403] Dynamic subtest bcs0: SUCCESS (3.422s)
[78.236292] Starting dynamic subtest: vcs0
[82.260329] Dynamic subtest vcs0: SUCCESS (4.029s)
[82.269610] Starting dynamic subtest: vcs1
[86.173302] Dynamic subtest vcs1: SUCCESS (3.910s)
[86.182418] Starting dynamic subtest: vecs0
[90.219863] Dynamic subtest vecs0: SUCCESS (4.044s)
[90.229208] Starting dynamic subtest: ccs0
[93.717577] Dynamic subtest ccs0: SUCCESS (3.496s)
[93.719057] Subtest fds: SUCCESS (21.678s)
[93.923782] [011/131] (926s left) gem_ctx_param (basic)
[93.974828] Starting subtest: basic
[93.975824] Subtest basic: SUCCESS (0.001s)
[94.102330] [012/131] (926s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[94.211470] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[94.213189] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[94.371994] [013/131] (926s left) kms_cursor_crc (cursor-tearing-position-change)
[94.471764] Starting subtest: cursor-tearing-position-change
[94.472630] Starting dynamic subtest: pipe-A-eDP-1
[95.045822] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.573s)
[95.052070] Starting dynamic subtest: pipe-D-eDP-1
[96.318782] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.272s)
[96.319690] Subtest cursor-tearing-position-change: SUCCESS (1.847s)
[97.162644] [014/131] (923s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[97.253872] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[97.255632] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[97.374375] [015/131] (923s left) kms_feature_discovery (display)
[97.463242] Starting subtest: display
[97.464088] Subtest display: SUCCESS (0.000s)
[97.550431] [016/131] (922s left) kms_psr2_su (page_flip-xrgb8888)
[97.594685] Subtest page_flip-XRGB8888: SKIP (0.000s)
[97.655788] [017/131] (922s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[97.743725] Starting subtest: fbcpsr-shrfb-scaledprimary
[103.552689] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.808s)
[103.740093] [018/131] (916s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[103.837150] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[103.837995] Starting dynamic subtest: pipe-A-eDP-1
[103.846511] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[103.853223] Starting dynamic subtest: pipe-B-eDP-1
[103.861427] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[103.868031] Starting dynamic subtest: pipe-C-eDP-1
[103.876340] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[103.883268] Starting dynamic subtest: pipe-D-eDP-1
[103.891672] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[103.893289] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[106.082210] [019/131] (914s left) gem_lmem_swapping (heavy-verify-random)
[106.112672] Subtest heavy-verify-random: SKIP (0.000s)
[106.143280] [020/131] (914s left) prime_self_import (reimport-vs-gem_close-race)
[106.169972] Starting subtest: reimport-vs-gem_close-race
[111.277574] Subtest reimport-vs-gem_close-race: SUCCESS (5.106s)
[111.375127] [021/131] (909s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[111.463751] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[115.925733] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (4.462s)
[117.095437] [022/131] (903s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[117.193390] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[117.194294] Starting dynamic subtest: pipe-A-eDP-1
[117.202698] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[117.210873] Starting dynamic subtest: pipe-B-eDP-1
[117.219460] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[117.225728] Starting dynamic subtest: pipe-C-eDP-1
[117.234246] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[117.240068] Starting dynamic subtest: pipe-D-eDP-1
[117.248499] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[117.250216] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[119.354843] [023/131] (901s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[119.453948] Starting subtest: primary-yf-tiled-reflect-x-90
[119.455801] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[119.545248] [024/131] (901s left) gem_pxp (fail-invalid-protected-context)
[119.676254] Starting subtest: fail-invalid-protected-context
[119.677393] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[119.796363] [025/131] (900s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[124.758747] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[124.761686] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.003s)
[125.611716] [026/131] (895s left) kms_invalid_mode (int-max-clock)
[125.717869] Starting subtest: int-max-clock
[125.718880] Starting dynamic subtest: pipe-A-eDP-1
[126.007605] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[126.014784] Starting dynamic subtest: pipe-B-eDP-1
[126.015608] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[126.021572] Starting dynamic subtest: pipe-C-eDP-1
[126.022422] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[126.028405] Starting dynamic subtest: pipe-D-eDP-1
[126.029260] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[126.030065] Subtest int-max-clock: SUCCESS (0.295s)
[126.839351] [027/131] (893s left) i915_query (engine-info)
[126.897445] Starting subtest: engine-info
[126.901404] Subtest engine-info: SUCCESS (0.005s)
[127.041161] [028/131] (893s left) kms_big_fb (4-tiled-addfb-size-overflow)
[127.134838] Starting subtest: 4-tiled-addfb-size-overflow
[127.135658] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[127.267487] [029/131] (893s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[127.353886] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[127.354792] Starting dynamic subtest: pipe-A
[127.371691] Dynamic subtest pipe-A: SKIP (0.015s)
[127.378226] Starting dynamic subtest: pipe-B
[127.398949] Dynamic subtest pipe-B: SKIP (0.027s)
[127.405234] Starting dynamic subtest: pipe-C
[127.427091] Dynamic subtest pipe-C: SKIP (0.027s)
[127.433387] Starting dynamic subtest: pipe-D
[127.454162] Dynamic subtest pipe-D: SKIP (0.027s)
[127.455880] Subtest plane-downscale-factor-0-5-with-pixel-format: SKIP (0.099s)
[127.591050] [030/131] (893s left) gem_exec_params (invalid-bsd-ring)
[127.647097] Starting subtest: invalid-bsd-ring
[127.648145] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[127.778501] [031/131] (893s left) kms_addfb_basic (bad-pitch-65536)
[127.847955] Starting subtest: bad-pitch-65536
[127.849419] Subtest bad-pitch-65536: SUCCESS (0.000s)
[128.017246] [032/131] (892s left) kms_prime (basic-crc-hybrid)
[128.097596] Subtest basic-crc-hybrid: SKIP (0.000s)
[128.205090] [033/131] (892s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[128.254547] Starting subtest: multi-wait-for-submit-submitted-signaled
[128.255388] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[128.378463] [034/131] (892s left) gem_spin_batch (legacy)
[128.432603] Starting subtest: legacy
[128.433524] Starting dynamic subtest: default
[131.673519] Dynamic subtest default: SUCCESS (3.236s)
[131.682251] Starting dynamic subtest: render
[134.908694] Dynamic subtest render: SUCCESS (3.233s)
[134.917476] Starting dynamic subtest: bsd
[138.144578] Dynamic subtest bsd: SUCCESS (3.234s)
[138.153253] Starting dynamic subtest: bsd1
[141.378464] Dynamic subtest bsd1: SUCCESS (3.231s)
[141.386976] Starting dynamic subtest: bsd2
[144.617887] Dynamic subtest bsd2: SUCCESS (3.235s)
[144.626396] Starting dynamic subtest: blt
[147.854085] Dynamic subtest blt: SUCCESS (3.234s)
[147.862696] Starting dynamic subtest: vebox
[151.091246] Dynamic subtest vebox: SUCCESS (3.235s)
[151.092358] Subtest legacy: SUCCESS (22.657s)
[152.009077] [035/131] (869s left) sriov_basic (enable-vfs-autoprobe-on)
[152.053192] Starting subtest: enable-vfs-autoprobe-on
[152.054100] Starting dynamic subtest: numvfs-1
[152.082689] Dynamic subtest numvfs-1: FAIL (0.010s)
[152.090305] Starting dynamic subtest: numvfs-2
[152.117285] Dynamic subtest numvfs-2: FAIL (0.004s)
[152.123643] Starting dynamic subtest: numvfs-3
[152.150147] Dynamic subtest numvfs-3: FAIL (0.004s)
[152.156348] Starting dynamic subtest: numvfs-4
[152.181587] Dynamic subtest numvfs-4: FAIL (0.004s)
[152.187612] Starting dynamic subtest: numvfs-5
[152.213267] Dynamic subtest numvfs-5: FAIL (0.004s)
[152.219317] Starting dynamic subtest: numvfs-6
[152.244732] Dynamic subtest numvfs-6: FAIL (0.004s)
[152.250520] Starting dynamic subtest: numvfs-7
[152.276056] Dynamic subtest numvfs-7: FAIL (0.004s)
[152.281887] Starting dynamic subtest: numvfs-random
[152.307297] Dynamic subtest numvfs-random: FAIL (0.004s)
[152.313161] Starting dynamic subtest: numvfs-all
[152.338631] Dynamic subtest numvfs-all: FAIL (0.003s)
[152.339459] Subtest enable-vfs-autoprobe-on: FAIL (0.044s)
[152.365496] [036/131] (868s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[152.461426] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[158.950416] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.489s)
[159.113603] [037/131] (862s left) i915_query (query-topology-matches-eu-total)
[159.170502] Starting subtest: query-topology-matches-eu-total
[159.171545] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[159.287875] [038/131] (861s left) kms_rotation_crc (sprite-rotation-180)
[159.384643] Starting subtest: sprite-rotation-180
[164.353092] Subtest sprite-rotation-180: SUCCESS (4.971s)
[165.619137] [039/131] (855s left) gem_ctx_shared (q-smoketest)
[165.679711] Starting subtest: Q-smoketest
[165.680624] Starting dynamic subtest: rcs0
[171.223643] Dynamic subtest rcs0: SUCCESS (5.523s)
[171.234987] Starting dynamic subtest: bcs0
[176.717320] Dynamic subtest bcs0: SUCCESS (5.495s)
[176.724265] Starting dynamic subtest: vcs0
[182.182588] Dynamic subtest vcs0: SUCCESS (5.462s)
[182.190393] Starting dynamic subtest: vcs1
[187.662975] Dynamic subtest vcs1: SUCCESS (5.482s)
[187.673459] Starting dynamic subtest: vecs0
[193.140164] Dynamic subtest vecs0: SUCCESS (5.474s)
[193.150897] Starting dynamic subtest: ccs0
[198.648751] Dynamic subtest ccs0: SUCCESS (5.505s)
[198.649639] Subtest Q-smoketest: SUCCESS (32.950s)
[198.832844] [040/131] (822s left) kms_content_protection (content-type-change)
[198.965611] Starting subtest: content-type-change
[198.968207] Subtest content-type-change: SKIP (0.000s)
[199.127744] [041/131] (822s left) perf_pmu (all-busy-check-all)
[199.168694] Starting subtest: all-busy-check-all
[200.922208] Subtest all-busy-check-all: SUCCESS (1.750s)
[201.047535] [042/131] (820s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[201.145287] Starting subtest: bad-pixel-format-yf-tiled-ccs
[201.146140] Starting dynamic subtest: pipe-A-eDP-1
[201.154710] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[201.162187] Starting dynamic subtest: pipe-B-eDP-1
[201.170817] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[201.177931] Starting dynamic subtest: pipe-C-eDP-1
[201.185785] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[201.192399] Starting dynamic subtest: pipe-D-eDP-1
[201.200529] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[201.202121] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.004s)
[203.372941] [043/131] (817s left) syncobj_wait (wait-for-submit-snapshot)
[203.445856] Starting subtest: wait-for-submit-snapshot
[203.526305] Subtest wait-for-submit-snapshot: SUCCESS (0.081s)
[203.620809] [044/131] (817s left) syncobj_wait (multi-wait-all-submitted-signaled)
[203.667213] Starting subtest: multi-wait-all-submitted-signaled
[203.772501] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.103s)
[203.866437] [045/131] (817s left) gem_exec_params (cliprects_ptr-dirt)
[203.924760] Starting subtest: cliprects_ptr-dirt
[203.926057] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[204.054517] [046/131] (817s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[204.152220] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[204.153941] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[204.256148] [047/131] (817s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[204.355423] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[204.356394] Starting dynamic subtest: pipe-A
[204.692653] Dynamic subtest pipe-A: SUCCESS (0.338s)
[204.701092] Starting dynamic subtest: pipe-B
[206.155212] Dynamic subtest pipe-B: SUCCESS (1.461s)
[206.163597] Starting dynamic subtest: pipe-C
[207.584387] Dynamic subtest pipe-C: SUCCESS (1.427s)
[207.596041] Starting dynamic subtest: pipe-D
[209.009174] Dynamic subtest pipe-D: SUCCESS (1.424s)
[209.010103] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.656s)
[210.195796] [048/131] (811s left) kms_plane_cursor (primary)
[210.288574] Starting subtest: primary
[210.290245] Starting dynamic subtest: pipe-A-eDP-1-size-64
[212.861087] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.572s)
[212.886423] Starting dynamic subtest: pipe-A-eDP-1-size-128
[215.439876] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.560s)
[215.465538] Starting dynamic subtest: pipe-A-eDP-1-size-256
[218.051942] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.593s)
[218.360889] Starting dynamic subtest: pipe-B-eDP-1-size-64
[221.634495] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.281s)
[221.664515] Starting dynamic subtest: pipe-B-eDP-1-size-128
[224.190869] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.537s)
[224.215789] Starting dynamic subtest: pipe-B-eDP-1-size-256
[226.792246] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.582s)
[227.102312] Starting dynamic subtest: pipe-C-eDP-1-size-64
[229.927406] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.833s)
[229.953821] Starting dynamic subtest: pipe-C-eDP-1-size-128
[232.049868] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.102s)
[232.076080] Starting dynamic subtest: pipe-C-eDP-1-size-256
[234.197640] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.125s)
[234.508422] Starting dynamic subtest: pipe-D-eDP-1-size-64
[237.378190] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.881s)
[237.411696] Starting dynamic subtest: pipe-D-eDP-1-size-128
[239.544858] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.146s)
[239.570234] Starting dynamic subtest: pipe-D-eDP-1-size-256
[241.693094] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.124s)
[241.995380] Subtest primary: SUCCESS (31.706s)
[242.837979] [049/131] (778s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[242.931994] Starting subtest: fbc-psr2-primary-mmap-gtt
[242.933036] Starting dynamic subtest: eDP-1
[242.934994] Dynamic subtest eDP-1: SKIP (0.000s)
[242.936923] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.003s)
[243.031832] [050/131] (778s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[243.148503] Starting subtest: fbc-psr-cursor-mmap-cpu
[243.149421] Starting dynamic subtest: eDP-1
[243.151202] Dynamic subtest eDP-1: SKIP (0.000s)
[243.152890] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[243.244352] [051/131] (778s left) kms_vblank (ts-continuation-dpms-rpm)
[243.389641] Starting subtest: ts-continuation-dpms-rpm
[243.390932] Starting dynamic subtest: pipe-A-eDP-1
[245.902143] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.554s)
[245.911932] Starting dynamic subtest: pipe-D-eDP-1
[248.145603] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.240s)
[248.146565] Subtest ts-continuation-dpms-rpm: SUCCESS (4.799s)
[248.968078] [052/131] (772s left) gem_exec_reloc (basic-wc-active)
[249.028100] Subtest basic-wc-active: SKIP (0.000s)
[249.103271] [053/131] (772s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[249.155567] Starting subtest: y-tiled-to-vebox-x-tiled
[249.157246] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[249.354171] [054/131] (772s left) syncobj_basic (bad-pad-handle-to-fd)
[249.408050] Starting subtest: bad-pad-handle-to-fd
[249.408918] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[249.535336] [055/131] (772s left) gem_exec_basic (basic)
[249.587735] Starting subtest: basic
[249.588664] Starting dynamic subtest: rcs0-smem
[249.591287] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[249.599071] Starting dynamic subtest: bcs0-smem
[249.599923] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[249.606878] Starting dynamic subtest: vcs0-smem
[249.607718] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[249.614429] Starting dynamic subtest: vcs1-smem
[249.615298] Dynamic subtest vcs1-smem: SUCCESS (0.002s)
[249.622032] Starting dynamic subtest: vecs0-smem
[249.622902] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[249.629560] Starting dynamic subtest: ccs0-smem
[249.630481] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[249.631375] Subtest basic: SUCCESS (0.015s)
[250.955971] [056/131] (770s left) gem_mmap_gtt (medium-copy-odd)
[251.023315] Subtest medium-copy-odd: SKIP (0.000s)
[251.098073] [057/131] (770s left) syncobj_timeline (device-submit-unordered)
[251.146975] Starting subtest: device-submit-unordered
[251.148058] Subtest device-submit-unordered: SUCCESS (0.000s)
[251.259987] [058/131] (770s left) dumb_buffer (create-clear)
[251.310726] Starting subtest: create-clear
[285.147264] Subtest create-clear: SUCCESS (33.829s)
[285.284457] [059/131] (736s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[285.381139] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[285.382893] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[285.508867] [060/131] (736s left) gem_mmap_gtt (basic-short)
[285.571779] Subtest basic-short: SKIP (0.000s)
[285.637015] [061/131] (736s left) kms_flip (basic-flip-vs-modeset)
[285.738282] Starting subtest: basic-flip-vs-modeset
[285.740396] Starting dynamic subtest: A-eDP1
[288.322462] Dynamic subtest A-eDP1: SUCCESS (2.582s)
[288.329679] Starting dynamic subtest: B-eDP1
[290.477519] Dynamic subtest B-eDP1: SUCCESS (2.153s)
[290.484743] Starting dynamic subtest: C-eDP1
[292.652292] Dynamic subtest C-eDP1: SUCCESS (2.174s)
[292.659285] Starting dynamic subtest: D-eDP1
[294.810503] Dynamic subtest D-eDP1: SUCCESS (2.157s)
[294.813061] Subtest basic-flip-vs-modeset: SUCCESS (9.074s)
[295.640640] [062/131] (726s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[295.738596] Starting subtest: random-ccs-data-y-tiled-ccs
[295.739608] Starting dynamic subtest: pipe-A-eDP-1
[295.749503] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[295.757116] Starting dynamic subtest: pipe-B-eDP-1
[295.766755] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[295.773795] Starting dynamic subtest: pipe-C-eDP-1
[295.783089] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[295.789670] Starting dynamic subtest: pipe-D-eDP-1
[295.799218] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[295.800859] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[297.923179] [063/131] (724s left) i915_drm_fdinfo (isolation)
[297.963279] Starting subtest: isolation
[297.964106] Starting dynamic subtest: rcs0
[297.991159] Dynamic subtest rcs0: SKIP (0.027s)
[297.997893] Starting dynamic subtest: bcs0
[298.019902] Dynamic subtest bcs0: SKIP (0.028s)
[298.028225] Starting dynamic subtest: vcs0
[298.029825] Dynamic subtest vcs0: SKIP (0.000s)
[298.036474] Starting dynamic subtest: vcs1
[298.038117] Dynamic subtest vcs1: SKIP (0.000s)
[298.045225] Starting dynamic subtest: vecs0
[298.046829] Dynamic subtest vecs0: SKIP (0.000s)
[298.053613] Starting dynamic subtest: ccs0
[298.055240] Dynamic subtest ccs0: SKIP (0.000s)
[298.056852] Subtest isolation: SKIP (0.062s)
[298.110558] [064/131] (723s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[303.088381] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[303.091713] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[303.963741] [065/131] (718s left) gem_exec_schedule (submit-late-slice)
[304.038364] Starting subtest: submit-late-slice
[304.039546] Starting dynamic subtest: rcs0
[304.072902] Dynamic subtest rcs0: SUCCESS (0.035s)
[304.082481] Starting dynamic subtest: bcs0
[304.101588] Dynamic subtest bcs0: SUCCESS (0.028s)
[304.108954] Starting dynamic subtest: vcs0
[304.130389] Dynamic subtest vcs0: SUCCESS (0.028s)
[304.137551] Starting dynamic subtest: vcs1
[304.157442] Dynamic subtest vcs1: SUCCESS (0.026s)
[304.164341] Starting dynamic subtest: vecs0
[304.187933] Dynamic subtest vecs0: SUCCESS (0.030s)
[304.195199] Starting dynamic subtest: ccs0
[304.218794] Dynamic subtest ccs0: SUCCESS (0.030s)
[304.219729] Subtest submit-late-slice: SUCCESS (0.181s)
[306.091029] [066/131] (715s left) gem_flink_race (flink_name)
[306.123971] Starting subtest: flink_name
[311.530663] Subtest flink_name: SUCCESS (5.402s)
[311.671908] [067/131] (710s left) i915_hangman (hangcheck-unterminated)
[311.734246] Starting subtest: hangcheck-unterminated
[322.870863] Subtest hangcheck-unterminated: SUCCESS (11.135s)
[323.026934] [068/131] (699s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[323.111380] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[323.112210] Starting dynamic subtest: pipe-A-default-mode
[324.313732] Dynamic subtest pipe-A-default-mode: SKIP (1.199s)
[324.315784] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.201s)
[324.533979] [069/131] (697s left) gem_mmap_gtt (fault-concurrent-y)
[324.589032] Subtest fault-concurrent-Y: SKIP (0.000s)
[324.654107] [070/131] (697s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[324.707115] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[324.708081] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[324.826109] [071/131] (697s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[324.966206] Starting subtest: basic-busy-flip-before-cursor-atomic
[324.967945] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[325.157479] [072/131] (697s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[325.249650] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[325.251214] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[325.368175] [073/131] (696s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[325.452383] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[325.453246] Starting dynamic subtest: pipe-A-eDP-1
[341.919409] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.500s)
[341.927677] Starting dynamic subtest: pipe-B-eDP-1
[359.927021] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.763s)
[359.935367] Starting dynamic subtest: pipe-C-eDP-1
[377.889220] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.737s)
[377.897912] Starting dynamic subtest: pipe-D-eDP-1
[395.882692] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.751s)
[395.883978] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (13.757s)
[403.149292] [074/131] (625s left) gem_busy (close-race)
[403.231251] Starting subtest: close-race
[428.538308] Subtest close-race: SUCCESS (25.052s)
[428.957656] [075/131] (599s left) kms_psr (pr-primary-blt)
[429.075917] Starting subtest: pr-primary-blt
[429.077693] Subtest pr-primary-blt: SKIP (0.000s)
[429.172105] [076/131] (599s left) gem_ctx_persistence (hang)
[429.237230] Starting subtest: hang
[429.239007] Subtest hang: SKIP (0.000s)
[429.371376] [077/131] (599s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[429.475339] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[430.282491] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.807s)
[430.495676] [078/131] (598s left) gem_ctx_param (invalid-get-no-zeromap)
[430.548513] Starting subtest: invalid-get-no-zeromap
[430.549694] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[430.669781] [079/131] (597s left) gem_watchdog (default-virtual)
[430.730399] Starting subtest: default-virtual
[437.018630] Subtest default-virtual: SUCCESS (6.284s)
[437.131901] [080/131] (591s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[437.237798] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[437.248451] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[437.382392] [081/131] (591s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt)
[437.488321] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt
[437.490055] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[437.622125] [082/131] (591s left) perf_pmu (enable-race)
[437.656153] Starting subtest: enable-race
[437.657205] Starting dynamic subtest: rcs0
[450.425415] Dynamic subtest rcs0: SUCCESS (12.763s)
[450.435340] Starting dynamic subtest: bcs0
[463.164551] Dynamic subtest bcs0: SUCCESS (12.731s)
[463.176387] Starting dynamic subtest: vcs0
[478.584135] Dynamic subtest vcs0: SUCCESS (15.407s)
[478.595277] Starting dynamic subtest: vcs1
[494.583742] Dynamic subtest vcs1: SUCCESS (15.991s)
[494.595547] Starting dynamic subtest: vecs0
[510.520442] Dynamic subtest vecs0: SUCCESS (15.940s)
[510.531039] Starting dynamic subtest: ccs0
[523.289458] Dynamic subtest ccs0: SUCCESS (12.770s)
[523.291687] Subtest enable-race: SUCCESS (85.616s)
[523.426542] [083/131] (505s left) core_auth (getclient-master-drop)
[523.457262] Starting subtest: getclient-master-drop
[523.475643] Subtest getclient-master-drop: SUCCESS (0.020s)
[523.611720] [084/131] (505s left) perf (sysctl-defaults)
[523.706830] Starting subtest: sysctl-defaults
[523.708091] Subtest sysctl-defaults: SUCCESS (0.000s)
[523.785156] [085/131] (504s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[523.885972] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[523.887030] Starting dynamic subtest: pipe-A-default-mode
[524.171195] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[524.172935] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.285s)
[525.025659] [086/131] (503s left) api_intel_allocator (reopen)
[525.098671] Starting subtest: reopen
[525.106274] Subtest reopen: SUCCESS (0.009s)
[525.270313] [087/131] (503s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[525.394787] Starting subtest: short-flip-after-cursor-toggle
[526.309778] Subtest short-flip-after-cursor-toggle: SUCCESS (0.914s)
[527.156864] [088/131] (501s left) kms_flip (flip-vs-modeset-vs-hang)
[527.264498] Starting subtest: flip-vs-modeset-vs-hang
[527.266982] Starting dynamic subtest: A-eDP1
[578.113625] Dynamic subtest A-eDP1: SUCCESS (50.834s)
[578.124673] Starting dynamic subtest: D-eDP1
[628.089046] Dynamic subtest D-eDP1: SUCCESS (49.962s)
[628.092574] Subtest flip-vs-modeset-vs-hang: SUCCESS (100.805s)
[628.507338] [089/131] (400s left) syncobj_timeline (invalid-transfer-illegal-handle)
[628.573389] Starting subtest: invalid-transfer-illegal-handle
[628.574297] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[628.708994] [090/131] (400s left) gem_busy (parallel)
[628.900621] Starting subtest: parallel
[628.902019] Starting dynamic subtest: rcs0
[630.584690] Dynamic subtest rcs0: SUCCESS (1.682s)
[630.596702] Starting dynamic subtest: bcs0
[632.568849] Dynamic subtest bcs0: SUCCESS (1.982s)
[632.580434] Starting dynamic subtest: vcs0
[634.551616] Dynamic subtest vcs0: SUCCESS (1.982s)
[634.565159] Starting dynamic subtest: vcs1
[636.600515] Dynamic subtest vcs1: SUCCESS (2.045s)
[636.611859] Starting dynamic subtest: vecs0
[638.585236] Dynamic subtest vecs0: SUCCESS (1.982s)
[638.597031] Starting dynamic subtest: ccs0
[640.568874] Dynamic subtest ccs0: SUCCESS (1.981s)
[640.570512] Subtest parallel: SUCCESS (11.670s)
[640.834178] [091/131] (388s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[640.928405] Starting subtest: yf-tiled-8bpp-rotate-270
[640.930201] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[641.044417] [092/131] (387s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[641.147182] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[642.174662] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.028s)
[642.336713] [093/131] (386s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[642.717575] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[643.495607] [094/131] (385s left) gen7_exec_parse (chained-batch)
[643.555108] Subtest chained-batch: SKIP (0.000s)
[643.642595] [095/131] (385s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[643.729407] Starting subtest: plane-use-after-nonblocking-unbind
[643.730383] Starting dynamic subtest: pipe-A-eDP-1
[643.789544] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.061s)
[644.125518] Starting dynamic subtest: pipe-B-eDP-1
[644.937736] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.820s)
[645.222602] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.494s)
[646.116730] [096/131] (383s left) sysfs_heartbeat_interval (precise)
[646.174252] Starting subtest: precise
[646.175322] Starting dynamic subtest: vecs0
[653.556798] Dynamic subtest vecs0: SUCCESS (7.382s)
[653.579100] Starting dynamic subtest: ccs0
[654.819864] Dynamic subtest ccs0: SUCCESS (1.259s)
[654.828401] Starting dynamic subtest: vcs0
[662.607943] Dynamic subtest vcs0: SUCCESS (7.788s)
[662.621073] Starting dynamic subtest: bcs0
[663.916062] Dynamic subtest bcs0: SUCCESS (1.303s)
[663.927018] Starting dynamic subtest: rcs0
[665.254945] Dynamic subtest rcs0: SUCCESS (1.338s)
[665.264380] Starting dynamic subtest: vcs1
[672.591917] Dynamic subtest vcs1: SUCCESS (7.334s)
[672.593049] Subtest precise: SUCCESS (26.418s)
[672.685987] [097/131] (356s left) gem_mmap (bad-size)
[672.731735] Subtest bad-size: SKIP (0.000s)
[672.795414] [098/131] (356s left) gem_pwrite (basic-self)
[672.851933] Subtest basic-self: SKIP (0.000s)
[672.917531] [099/131] (356s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[672.986682] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[673.079102] [100/131] (356s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[673.179423] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[673.181057] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[673.312318] [101/131] (355s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[673.413469] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[673.415214] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[673.548759] [102/131] (355s left) kms_selftest (drm_damage_helper)
[673.589871] Starting subtest: drm_damage_helper
[673.638335] Starting dynamic subtest: drm_test_damage_iter_no_damage
[673.784195] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.145s)
[673.793032] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[673.909101] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.123s)
[673.917669] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[674.039466] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.131s)
[674.047763] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[674.166358] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.127s)
[674.175965] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[674.294214] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.124s)
[674.303748] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[674.380794] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.087s)
[674.389119] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[674.504562] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.123s)
[674.514392] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[674.630667] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.124s)
[674.639068] Starting dynamic subtest: drm_test_damage_iter_single_damage
[674.745021] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.113s)
[674.754857] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[674.877006] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.132s)
[674.885612] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[675.001610] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.122s)
[675.011123] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[675.117975] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.116s)
[675.126425] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[675.249382] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.130s)
[675.258507] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[675.381363] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.130s)
[675.391224] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[675.525020] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.142s)
[675.543330] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[675.667319] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.142s)
[675.676621] Starting dynamic subtest: drm_test_damage_iter_damage
[675.800893] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.133s)
[675.810627] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[675.934909] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.134s)
[675.943914] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[676.072206] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.135s)
[676.081894] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[676.203124] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.131s)
[676.212759] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[676.348104] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.144s)
[676.349080] Subtest drm_damage_helper: SUCCESS (2.756s)
[676.557905] [103/131] (352s left) gem_exec_schedule (fairslice)
[676.625194] Starting subtest: fairslice
[676.626031] Starting dynamic subtest: rcs0
[678.651573] Dynamic subtest rcs0: SUCCESS (2.026s)
[678.660410] Starting dynamic subtest: bcs0
[680.677271] Dynamic subtest bcs0: SUCCESS (2.024s)
[680.686416] Starting dynamic subtest: vcs0
[682.698869] Dynamic subtest vcs0: SUCCESS (2.020s)
[682.708330] Starting dynamic subtest: vcs1
[684.716210] Dynamic subtest vcs1: SUCCESS (2.017s)
[684.726162] Starting dynamic subtest: vecs0
[686.734167] Dynamic subtest vecs0: SUCCESS (2.017s)
[686.743517] Starting dynamic subtest: ccs0
[688.752357] Dynamic subtest ccs0: SUCCESS (2.019s)
[688.753139] Subtest fairslice: SUCCESS (12.127s)
[689.071086] [104/131] (340s left) gem_exec_schedule (pi-common)
[689.258081] Starting subtest: pi-common
[689.259169] Starting dynamic subtest: rcs0
[689.289286] Dynamic subtest rcs0: SUCCESS (0.032s)
[689.297943] Starting dynamic subtest: bcs0
[689.319332] Dynamic subtest bcs0: SUCCESS (0.029s)
[689.328402] Starting dynamic subtest: vcs0
[689.351282] Dynamic subtest vcs0: SUCCESS (0.031s)
[689.360132] Starting dynamic subtest: vcs1
[689.382798] Dynamic subtest vcs1: SUCCESS (0.030s)
[689.391595] Starting dynamic subtest: vecs0
[689.413658] Dynamic subtest vecs0: SUCCESS (0.030s)
[689.422706] Starting dynamic subtest: ccs0
[689.442403] Dynamic subtest ccs0: SUCCESS (0.028s)
[689.443296] Subtest pi-common: SUCCESS (0.186s)
[689.684135] [105/131] (339s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[691.723480] Starting subtest: dpms-mode-unset-non-lpsp
[692.865609] Subtest dpms-mode-unset-non-lpsp: SKIP (1.143s)
[693.247593] [106/131] (336s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[693.348572] Starting subtest: y-tiled-64bpp-rotate-180
[693.350586] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[693.471988] [107/131] (336s left) kms_flip (2x-flip-vs-panning)
[693.569713] Starting subtest: 2x-flip-vs-panning
[693.580020] Subtest 2x-flip-vs-panning: SKIP (0.011s)
[693.678665] [108/131] (335s left) kms_async_flips (async-flip-dpms)
[693.774734] Starting subtest: async-flip-dpms
[694.150338] Starting dynamic subtest: pipe-A-eDP-1
[697.949718] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.799s)
[698.318073] Starting dynamic subtest: pipe-B-eDP-1
[701.125879] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.815s)
[701.501629] Starting dynamic subtest: pipe-C-eDP-1
[704.285913] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.792s)
[704.662984] Starting dynamic subtest: pipe-D-eDP-1
[707.447229] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.792s)
[707.448082] Subtest async-flip-dpms: SUCCESS (13.673s)
[708.685311] [109/131] (320s left) kms_sequence (get-idle)
[708.787322] Starting subtest: get-idle
[708.788266] Starting dynamic subtest: pipe-A-eDP-1
[710.612362] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.825s)
[710.623040] Starting dynamic subtest: pipe-B-eDP-1
[713.089749] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.475s)
[713.098329] Starting dynamic subtest: pipe-C-eDP-1
[715.517575] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.426s)
[715.526658] Starting dynamic subtest: pipe-D-eDP-1
[717.947066] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.428s)
[717.948017] Subtest get-idle: SUCCESS (9.159s)
[718.805617] [110/131] (310s left) perf (gen12-mi-rpc)
[718.917349] Starting subtest: gen12-mi-rpc
[718.918268] Starting dynamic subtest: rcs0
[718.937248] Dynamic subtest rcs0: SUCCESS (0.018s)
[718.938186] Subtest gen12-mi-rpc: SUCCESS (0.020s)
[718.975250] [111/131] (310s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[719.113191] Starting subtest: psr-2p-pri-indfb-multidraw
[719.114873] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[719.247131] [112/131] (310s left) gem_ctx_param (basic-default)
[719.302957] Starting subtest: basic-default
[719.304009] Subtest basic-default: SUCCESS (0.000s)
[719.404636] [113/131] (310s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[719.635245] Starting subtest: cursor-rapid-movement-256x85
[719.711699] Subtest cursor-rapid-movement-256x85: SKIP (0.074s)
[719.908376] [114/131] (309s left) i915_pm_rpm (gem-evict-pwrite)
[721.754375] Starting subtest: gem-evict-pwrite
[721.757389] Subtest gem-evict-pwrite: SKIP (0.000s)
[722.219005] [115/131] (307s left) gem_exec_nop (basic-series)
[722.284078] Starting subtest: basic-series
[738.554066] Subtest basic-series: SUCCESS (16.266s)
[738.792442] [116/131] (291s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[738.886009] Starting subtest: invalid-multi-wait-all-unsubmitted
[738.887009] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[739.010679] [117/131] (290s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[739.108975] Starting subtest: bad-aux-stride-y-tiled-ccs
[739.109872] Starting dynamic subtest: pipe-A-eDP-1
[739.118640] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[739.127892] Starting dynamic subtest: pipe-B-eDP-1
[739.136663] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[739.147657] Starting dynamic subtest: pipe-C-eDP-1
[739.156039] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[739.164567] Starting dynamic subtest: pipe-D-eDP-1
[739.173314] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[739.175148] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.005s)
[741.273831] [118/131] (288s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[741.367018] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[741.368725] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[741.490829] [119/131] (288s left) kms_cursor_crc (cursor-onscreen-256x85)
[741.735484] Starting subtest: cursor-onscreen-256x85
[741.785469] Subtest cursor-onscreen-256x85: SKIP (0.047s)
[742.021152] [120/131] (288s left) gem_exec_whisper (basic-fds-forked)
[742.084308] Starting subtest: basic-fds-forked
[766.039788] Subtest basic-fds-forked: SUCCESS (23.949s)
[766.243822] [121/131] (263s left) kms_frontbuffer_tracking (psr-rgb101010-draw-render)
[766.341464] Starting subtest: psr-rgb101010-draw-render
[773.534302] Subtest psr-rgb101010-draw-render: SUCCESS (7.191s)
[773.686982] [122/131] (256s left) gem_compute (compute-square)
[773.750129] Starting subtest: compute-square
[773.754047] Subtest compute-square: SUCCESS (0.007s)
[773.888152] [123/131] (256s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[773.983523] Starting subtest: fbcpsr-modesetfrombusy
[780.151585] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.168s)
[780.308740] [124/131] (249s left) kms_atomic (plane-cursor-legacy)
[780.395327] Starting subtest: plane-cursor-legacy
[780.396273] Starting dynamic subtest: pipe-A-eDP-1
[780.782652] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.387s)
[780.783621] Subtest plane-cursor-legacy: SUCCESS (0.389s)
[781.601564] [125/131] (248s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[781.716682] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[781.718438] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[781.848555] [126/131] (248s left) gem_exec_params (invalid-flag)
[781.902349] Starting subtest: invalid-flag
[781.903505] Subtest invalid-flag: SUCCESS (0.000s)
[782.029023] [127/131] (248s left) kms_chamelium_edid (hdmi-edid-read)
[782.413715] Subtest hdmi-edid-read: SKIP (0.000s)
[783.192017] [128/131] (247s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[783.284767] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[783.286580] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[783.433699] [129/131] (246s left) gem_pipe_control_store_loop (fresh-buffer)
[783.490145] Starting subtest: fresh-buffer
[785.644733] Subtest fresh-buffer: SUCCESS (2.152s)
[785.797007] [130/131] (244s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[785.892760] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[786.881855] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (0.989s)
[787.032342] [131/131] (243s left) gem_exec_balancer (full-late-pulse)
[787.097992] Starting subtest: full-late-pulse
[791.483336] Subtest full-late-pulse: SUCCESS (4.385s)
[791.831836] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: kms_joiner
results: parsing output: 2/ for test: gem_tiled_pread_basic
results: parsing output: 3/ for test: gem_exec_fence
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: kms_pipe_stress
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: prime_mmap
results: parsing output: 8/ for test: gem_mmap_wc
results: parsing output: 9/ for test: gem_exec_parallel
results: parsing output: 10/ for test: gem_ctx_param
results: parsing output: 11/ for test: kms_cursor_legacy
results: parsing output: 12/ for test: kms_cursor_crc
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_feature_discovery
results: parsing output: 15/ for test: kms_psr2_su
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_ccs
results: parsing output: 18/ for test: gem_lmem_swapping
results: parsing output: 19/ for test: prime_self_import
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: kms_rotation_crc
results: parsing output: 23/ for test: gem_pxp
results: parsing output: 24/ for test: kms_psr2_sf
results: parsing output: 25/ for test: kms_invalid_mode
results: parsing output: 26/ for test: i915_query
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_plane_scaling
results: parsing output: 29/ for test: gem_exec_params
results: parsing output: 30/ for test: kms_addfb_basic
results: parsing output: 31/ for test: kms_prime
results: parsing output: 32/ for test: syncobj_wait
results: parsing output: 33/ for test: gem_spin_batch
results: parsing output: 34/ for test: sriov_basic
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: i915_query
results: parsing output: 37/ for test: kms_rotation_crc
results: parsing output: 38/ for test: gem_ctx_shared
results: parsing output: 39/ for test: kms_content_protection
results: parsing output: 40/ for test: perf_pmu
results: parsing output: 41/ for test: kms_ccs
results: parsing output: 42/ for test: syncobj_wait
results: parsing output: 43/ for test: syncobj_wait
results: parsing output: 44/ for test: gem_exec_params
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_plane_scaling
results: parsing output: 47/ for test: kms_plane_cursor
results: parsing output: 48/ for test: kms_psr
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: kms_vblank
results: parsing output: 51/ for test: gem_exec_reloc
results: parsing output: 52/ for test: gem_render_copy
results: parsing output: 53/ for test: syncobj_basic
results: parsing output: 54/ for test: gem_exec_basic
results: parsing output: 55/ for test: gem_mmap_gtt
results: parsing output: 56/ for test: syncobj_timeline
results: parsing output: 57/ for test: dumb_buffer
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_mmap_gtt
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: i915_drm_fdinfo
results: parsing output: 63/ for test: kms_psr2_sf
results: parsing output: 64/ for test: gem_exec_schedule
results: parsing output: 65/ for test: gem_flink_race
results: parsing output: 66/ for test: i915_hangman
results: parsing output: 67/ for test: kms_flip_scaled_crc
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: kms_cursor_legacy
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_ccs
results: parsing output: 73/ for test: gem_busy
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: gem_ctx_persistence
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: gem_ctx_param
results: parsing output: 78/ for test: gem_watchdog
results: parsing output: 79/ for test: kms_flip
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: perf_pmu
results: parsing output: 82/ for test: core_auth
results: parsing output: 83/ for test: perf
results: parsing output: 84/ for test: kms_flip_scaled_crc
results: parsing output: 85/ for test: api_intel_allocator
results: parsing output: 86/ for test: kms_cursor_legacy
results: parsing output: 87/ for test: kms_flip
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: gem_busy
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: kms_chamelium_hpd
results: parsing output: 93/ for test: gen7_exec_parse
results: parsing output: 94/ for test: kms_atomic_transition
results: parsing output: 95/ for test: sysfs_heartbeat_interval
results: parsing output: 96/ for test: gem_mmap
results: parsing output: 97/ for test: gem_pwrite
results: parsing output: 98/ for test: gem_exec_reloc
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_selftest
results: parsing output: 102/ for test: gem_exec_schedule
results: parsing output: 103/ for test: gem_exec_schedule
results: parsing output: 104/ for test: kms_pm_rpm
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: kms_async_flips
results: parsing output: 108/ for test: kms_sequence
results: parsing output: 109/ for test: perf
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_ctx_param
results: parsing output: 112/ for test: kms_cursor_crc
results: parsing output: 113/ for test: i915_pm_rpm
results: parsing output: 114/ for test: gem_exec_nop
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: kms_ccs
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_cursor_crc
results: parsing output: 119/ for test: gem_exec_whisper
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_compute
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_atomic
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_exec_params
results: parsing output: 126/ for test: kms_chamelium_edid
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_pipe_control_store_loop
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: gem_exec_balancer
Done.