[57.406052] Initializing watchdogs
[57.406104]   /dev/watchdog0
[57.420758] [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]
[57.425422] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.427758] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.431306] [001/133] (960s left) gem_bad_reloc (negative-reloc-bltcopy)
[57.518816] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[57.579826] [002/133] (960s left) gem_ctx_persistence (engines-hostile-preempt)
[57.642409] Starting subtest: engines-hostile-preempt
[57.644697] Starting dynamic subtest: rcs0
[57.729459] Dynamic subtest rcs0: SUCCESS (0.087s)
[57.739304] Starting dynamic subtest: bcs0
[57.778809] Dynamic subtest bcs0: SUCCESS (0.048s)
[57.788582] Starting dynamic subtest: vcs0
[58.898671] Dynamic subtest vcs0: SUCCESS (1.117s)
[58.906780] Starting dynamic subtest: vcs1
[60.859667] Dynamic subtest vcs1: SUCCESS (1.960s)
[60.870225] Starting dynamic subtest: vecs0
[62.907079] Dynamic subtest vecs0: SUCCESS (2.046s)
[62.916242] Starting dynamic subtest: ccs0
[62.976707] Dynamic subtest ccs0: SUCCESS (0.070s)
[62.980106] Subtest engines-hostile-preempt: SUCCESS (5.335s)
[63.066232] [003/133] (954s left) kms_setmode (basic)
[63.121731] Starting subtest: basic
[63.478815] Starting dynamic subtest: pipe-A-eDP-1
[65.552962] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.070s)
[65.905321] Starting dynamic subtest: pipe-B-eDP-1
[67.992435] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.088s)
[68.306259] Subtest basic: SUCCESS (5.180s)
[69.130853] [004/133] (948s left) kms_lease (lease-get)
[69.242780] Starting subtest: lease-get
[69.245074] Starting dynamic subtest: pipe-A-eDP-1
[69.247374] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[69.257213] Starting dynamic subtest: pipe-B-eDP-1
[69.259295] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[69.266582] Starting dynamic subtest: pipe-C-eDP-1
[69.268672] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[69.276024] Starting dynamic subtest: pipe-D-eDP-1
[69.278129] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[69.280201] Subtest lease-get: SUCCESS (0.008s)
[69.351638] [005/133] (948s left) kms_atomic_interruptible (legacy-dpms)
[69.447325] Starting subtest: legacy-dpms
[69.449588] Starting dynamic subtest: pipe-A-eDP-1
[76.856664] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.410s)
[76.858944] Subtest legacy-dpms: SUCCESS (7.411s)
[77.686754] [006/133] (940s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[77.787567] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[77.789824] Starting dynamic subtest: pipe-A
[78.837084] Dynamic subtest pipe-A: SUCCESS (1.050s)
[78.849334] Starting dynamic subtest: pipe-B
[81.004685] Dynamic subtest pipe-B: SUCCESS (2.167s)
[81.012531] Starting dynamic subtest: pipe-C
[82.205478] Dynamic subtest pipe-C: SUCCESS (1.199s)
[82.214612] Starting dynamic subtest: pipe-D
[83.445491] Dynamic subtest pipe-D: SUCCESS (1.239s)
[83.447896] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.658s)
[84.629914] [007/133] (933s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[84.734682] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[91.328087] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.596s)
[91.461798] [008/133] (926s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[91.555880] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[91.558176] Starting dynamic subtest: pipe-A-eDP-1
[91.579669] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[91.589279] Starting dynamic subtest: pipe-B-eDP-1
[91.610653] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[91.618107] Starting dynamic subtest: pipe-C-eDP-1
[91.638503] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[91.645726] Starting dynamic subtest: pipe-D-eDP-1
[91.667713] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[91.671732] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.007s)
[93.811214] [009/133] (924s left) gem_fence_thrash (bo-write-verify-threaded-x)
[93.848783] Starting subtest: bo-write-verify-threaded-x
[93.871270] Subtest bo-write-verify-threaded-x: SKIP (0.021s)
[93.933805] [010/133] (924s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[94.311554] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[95.114386] [011/133] (923s left) gem_exec_schedule (submit-golden-slice)
[95.191520] Starting subtest: submit-golden-slice
[95.193841] Starting dynamic subtest: rcs0
[95.221861] Dynamic subtest rcs0: SUCCESS (0.038s)
[95.231722] Starting dynamic subtest: bcs0
[95.251965] Dynamic subtest bcs0: SUCCESS (0.030s)
[95.259595] Starting dynamic subtest: vcs0
[95.285079] Dynamic subtest vcs0: SUCCESS (0.033s)
[95.293105] Starting dynamic subtest: vcs1
[95.320539] Dynamic subtest vcs1: SUCCESS (0.035s)
[95.329020] Starting dynamic subtest: vecs0
[95.349808] Dynamic subtest vecs0: SUCCESS (0.029s)
[95.357606] Starting dynamic subtest: ccs0
[95.389047] Dynamic subtest ccs0: SUCCESS (0.039s)
[95.391332] Subtest submit-golden-slice: SUCCESS (0.205s)
[97.004623] [012/133] (921s left) gem_exec_params (invalid-ring2)
[97.071797] Starting subtest: invalid-ring2
[97.074155] Subtest invalid-ring2: SUCCESS (0.000s)
[97.162683] [013/133] (921s left) gem_pread (self)
[97.215336] Subtest self: SKIP (0.000s)
[97.269778] [014/133] (921s left) kms_big_fb (x-tiled-addfb)
[97.380982] Starting subtest: x-tiled-addfb
[97.383196] Subtest x-tiled-addfb: SUCCESS (0.000s)
[97.490990] [015/133] (920s left) gem_softpin (32b-excludes-last-page)
[97.554103] Starting subtest: 32b-excludes-last-page
[97.558804] Subtest 32b-excludes-last-page: SUCCESS (0.004s)
[97.662668] [016/133] (920s left) kms_hdr (static-toggle-dpms)
[97.743071] Starting subtest: static-toggle-dpms
[97.749147] Subtest static-toggle-dpms: SKIP (0.000s)
[97.825758] [017/133] (920s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[97.931195] Starting subtest: psr-1p-pri-indfb-multidraw
[120.950480] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (23.022s)
[121.082386] [018/133] (897s left) gem_basic (create-fd-close)
[121.148386] Starting subtest: create-fd-close
[121.152224] Subtest create-fd-close: SUCCESS (0.000s)
[121.241015] [019/133] (897s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[121.329071] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[121.332350] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[121.422427] [020/133] (897s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[121.507037] Starting subtest: plane-lut1d-ctm3x4
[121.654913] Starting dynamic subtest: pipe-A-plane-0
[121.832571] Dynamic subtest pipe-A-plane-0: SUCCESS (0.177s)
[121.844903] Starting dynamic subtest: pipe-A-plane-1
[121.977293] Dynamic subtest pipe-A-plane-1: SUCCESS (0.143s)
[121.984720] Starting dynamic subtest: pipe-A-plane-2
[122.132713] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[123.299504] Starting dynamic subtest: pipe-B-plane-0
[123.471848] Dynamic subtest pipe-B-plane-0: SUCCESS (0.178s)
[123.480181] Starting dynamic subtest: pipe-B-plane-1
[123.626521] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[123.634981] Starting dynamic subtest: pipe-B-plane-2
[123.782158] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[125.000107] Starting dynamic subtest: pipe-C-plane-0
[125.138415] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[125.146784] Starting dynamic subtest: pipe-C-plane-1
[125.293502] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[125.301468] Starting dynamic subtest: pipe-C-plane-2
[125.449370] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[126.628963] Starting dynamic subtest: pipe-D-plane-0
[126.767063] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[126.775104] Starting dynamic subtest: pipe-D-plane-1
[126.911453] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[126.919470] Starting dynamic subtest: pipe-D-plane-2
[127.058186] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[127.345255] Subtest plane-lut1d-ctm3x4: SUCCESS (5.835s)
[128.205138] [021/133] (890s left) gem_ccs (ctrl-surf-copy)
[128.270304] Starting subtest: ctrl-surf-copy
[128.275151] Subtest ctrl-surf-copy: SKIP (0.000s)
[128.373890] [022/133] (890s left) gem_exec_parallel (engines)
[128.442492] Starting subtest: engines
[128.444893] Starting dynamic subtest: basic
[129.896094] Dynamic subtest basic: SUCCESS (1.456s)
[129.907529] Starting dynamic subtest: contexts
[134.219056] Dynamic subtest contexts: SUCCESS (4.318s)
[134.227204] Starting dynamic subtest: fds
[138.044515] Dynamic subtest fds: SUCCESS (3.823s)
[138.056204] Starting dynamic subtest: userptr
[139.861423] Dynamic subtest userptr: SUCCESS (1.814s)
[139.864281] Subtest engines: SUCCESS (11.421s)
[140.052336] [023/133] (878s left) gem_exec_balancer (sequential)
[140.122328] Starting subtest: sequential
[143.261643] Subtest sequential: SUCCESS (3.139s)
[143.594800] [024/133] (875s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[143.703549] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[143.707977] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[143.792171] [025/133] (875s left) gem_fence_thrash (bo-write-verify-x)
[143.830919] Starting subtest: bo-write-verify-x
[143.846614] Subtest bo-write-verify-x: SKIP (0.020s)
[143.911517] [026/133] (875s left) kms_pipe_crc_basic (hang-read-crc)
[143.999101] Starting subtest: hang-read-crc
[144.001258] Starting dynamic subtest: pipe-A-eDP-1
[146.097668] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.097s)
[146.110357] Starting dynamic subtest: pipe-B-eDP-1
[148.806588] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.708s)
[148.815417] Starting dynamic subtest: pipe-C-eDP-1
[151.506907] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.701s)
[151.516496] Starting dynamic subtest: pipe-D-eDP-1
[154.165947] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.656s)
[154.168372] Subtest hang-read-crc: SUCCESS (10.167s)
[154.991858] [027/133] (863s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[155.113734] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[155.116057] Starting dynamic subtest: pipe-A-eDP-1
[155.210747] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[155.220094] Starting dynamic subtest: pipe-B-eDP-1
[156.378644] Dynamic subtest pipe-B-eDP-1: SKIP (1.164s)
[156.383114] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.268s)
[157.663722] [028/133] (861s left) gem_compute (compute-square)
[157.731665] Starting subtest: compute-square
[157.738093] Subtest compute-square: SUCCESS (0.006s)
[157.855752] [029/133] (861s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-render)
[157.954767] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-render
[162.139910] Subtest fbc-1p-offscreen-pri-shrfb-draw-render: SUCCESS (4.188s)
[163.272619] [030/133] (855s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[163.371433] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[163.375690] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[163.467502] [031/133] (855s left) kms_dsc (dsc-with-bpc-formats)
[163.563971] Starting subtest: dsc-with-bpc-formats
[163.675461] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[163.713575] [032/133] (855s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[163.814086] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[163.818122] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[163.901071] [033/133] (855s left) kms_flip (wf_vblank-ts-check-interruptible)
[163.998576] Starting subtest: wf_vblank-ts-check-interruptible
[164.003656] Starting dynamic subtest: A-eDP1
[172.915651] Dynamic subtest A-eDP1: SUCCESS (8.914s)
[172.927588] Starting dynamic subtest: B-eDP1
[181.851290] Dynamic subtest B-eDP1: SUCCESS (8.933s)
[181.859616] Starting dynamic subtest: C-eDP1
[190.775618] Dynamic subtest C-eDP1: SUCCESS (8.922s)
[190.784479] Starting dynamic subtest: D-eDP1
[199.714849] Dynamic subtest D-eDP1: SUCCESS (8.936s)
[199.716751] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.716s)
[200.939459] [034/133] (818s left) gem_eio (unwedge-stress)
[201.169736] Starting subtest: unwedge-stress
[201.172150] Starting dynamic subtest: render
[206.155787] Dynamic subtest render: SUCCESS (4.983s)
[206.172940] Starting dynamic subtest: bsd1
[235.990088] Dynamic subtest bsd1: SUCCESS (29.833s)
[236.004850] Starting dynamic subtest: bsd2
[261.956547] Dynamic subtest bsd2: SUCCESS (25.965s)
[261.971756] Starting dynamic subtest: blt
[261.975764] Dynamic subtest blt: SKIP (0.000s)
[261.984038] Starting dynamic subtest: vebox
[288.411988] Dynamic subtest vebox: SUCCESS (26.452s)
[288.414608] Subtest unwedge-stress: SUCCESS (87.238s)
[289.979903] [FACT gem_eio (unwedge-stress)] new: kernel.kmod_is_loaded.vgem: true
[289.983897] [035/133] (729s left) gem_close (basic)
[290.047618] Starting subtest: basic
[290.050149] Subtest basic: SUCCESS (0.000s)
[290.161474] [036/133] (729s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[290.266176] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[290.945998] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.681s)
[291.106322] [037/133] (728s left) i915_query (query-regions-unallocated)
[291.174555] Starting subtest: query-regions-unallocated
[291.176916] Subtest query-regions-unallocated: SUCCESS (0.000s)
[291.284223] [038/133] (728s left) i915_drm_fdinfo (virtual-busy-hang)
[291.334090] Starting subtest: virtual-busy-hang
[291.338471] Subtest virtual-busy-hang: SKIP (0.000s)
[291.420172] [039/133] (728s left) gem_ctx_persistence (legacy-engines-queued)
[291.476945] Starting subtest: legacy-engines-queued
[291.479359] Starting dynamic subtest: render
[291.560043] Dynamic subtest render: SUCCESS (0.082s)
[291.574905] Starting dynamic subtest: bsd1
[292.853218] Dynamic subtest bsd1: SUCCESS (1.288s)
[292.865864] Starting dynamic subtest: bsd2
[294.835866] Dynamic subtest bsd2: SUCCESS (1.979s)
[294.848241] Starting dynamic subtest: blt
[294.884727] Dynamic subtest blt: SUCCESS (0.047s)
[294.895330] Starting dynamic subtest: vebox
[296.883911] Dynamic subtest vebox: SUCCESS (1.996s)
[296.887037] Subtest legacy-engines-queued: SUCCESS (5.407s)
[296.969249] [040/133] (722s left) kms_debugfs (display-on-read-all)
[297.057157] Starting subtest: display-on-read-all
[297.224440] Subtest display-on-read-all: SUCCESS (0.169s)
[297.335726] [041/133] (722s left) dumb_buffer (create-valid-dumb)
[297.399247] Starting subtest: create-valid-dumb
[297.401465] Subtest create-valid-dumb: SUCCESS (0.000s)
[297.516352] [042/133] (722s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[297.606083] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[297.610349] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[297.716145] [043/133] (721s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[297.821329] Starting subtest: 4-tiled-16bpp-rotate-0
[299.111364] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.290s)
[299.233881] [044/133] (720s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[299.338857] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[305.948539] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.610s)
[306.063634] [045/133] (713s left) perf_pmu (busy-check-all)
[306.115540] Starting subtest: busy-check-all
[306.117625] Starting dynamic subtest: rcs0
[306.668728] Dynamic subtest rcs0: SUCCESS (0.556s)
[306.680934] Starting dynamic subtest: bcs0
[307.218979] Dynamic subtest bcs0: SUCCESS (0.548s)
[307.231215] Starting dynamic subtest: vcs0
[308.885556] Dynamic subtest vcs0: SUCCESS (1.663s)
[308.897951] Starting dynamic subtest: vcs1
[310.934040] Dynamic subtest vcs1: SUCCESS (2.045s)
[310.945966] Starting dynamic subtest: vecs0
[312.917458] Dynamic subtest vecs0: SUCCESS (1.981s)
[312.929976] Starting dynamic subtest: ccs0
[313.473739] Dynamic subtest ccs0: SUCCESS (0.554s)
[313.476714] Subtest busy-check-all: SUCCESS (7.362s)
[313.579976] [046/133] (706s left) gem_lmem_swapping (heavy-random)
[313.637470] Subtest heavy-random: SKIP (0.000s)
[313.686554] [047/133] (706s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[313.807360] Starting subtest: cursorA-vs-flipB-toggle
[313.811875] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[313.953438] [048/133] (705s left) api_intel_allocator (reserve-simple)
[314.010088] Starting subtest: reserve-simple
[314.013044] Subtest reserve-simple: SUCCESS (0.003s)
[314.148256] [049/133] (705s left) perf (mi-rpc)
[314.268669] Starting subtest: mi-rpc
[314.272315] Subtest mi-rpc: SKIP (0.000s)
[314.312162] [050/133] (705s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[314.410748] Starting subtest: bad-pixel-format-y-tiled-ccs
[314.412999] Starting dynamic subtest: pipe-A-eDP-1
[314.436845] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[314.448345] Starting dynamic subtest: pipe-B-eDP-1
[314.468515] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[314.476986] Starting dynamic subtest: pipe-C-eDP-1
[314.494758] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[314.503199] Starting dynamic subtest: pipe-D-eDP-1
[314.521300] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[314.524771] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[316.629379] [051/133] (703s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[316.743893] Starting subtest: yf-tiled-64bpp-rotate-180
[316.748590] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[316.830463] [052/133] (703s left) gem_ctx_param (get-priority-new-ctx)
[316.885874] Starting subtest: get-priority-new-ctx
[316.888187] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[317.027766] [053/133] (703s left) vgem_slow (nohang)
[317.080222] Starting subtest: nohang
[327.283875] Subtest nohang: SUCCESS (10.205s)
[327.351167] [054/133] (692s left) gem_exec_balancer (full-pulse)
[327.412992] Starting subtest: full-pulse
[331.783062] Subtest full-pulse: SUCCESS (4.372s)
[332.117464] [055/133] (687s left) gem_ctx_bad_destroy (double-destroy)
[332.169925] Starting subtest: double-destroy
[332.172651] Subtest double-destroy: SUCCESS (0.000s)
[332.252613] [056/133] (687s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[332.354514] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[336.064423] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.713s)
[337.239783] [057/133] (682s left) vgem_basic (second-client)
[337.281600] Starting subtest: second-client
[337.306818] Subtest second-client: SUCCESS (0.022s)
[337.363084] [058/133] (682s left) kms_psr (pr-primary-render)
[337.466240] Starting subtest: pr-primary-render
[337.470271] Subtest pr-primary-render: SKIP (0.000s)
[337.543627] [059/133] (682s left) kms_vblank (ts-continuation-modeset-hang)
[337.635332] Starting subtest: ts-continuation-modeset-hang
[337.637285] Starting dynamic subtest: pipe-A-eDP-1
[363.672684] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.036s)
[363.686205] Starting dynamic subtest: pipe-D-eDP-1
[388.706669] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.029s)
[388.708637] Subtest ts-continuation-modeset-hang: SUCCESS (51.070s)
[389.562258] [060/133] (630s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[389.664072] Starting subtest: flip-primary-invalidate-overlay
[401.300366] Subtest flip-primary-invalidate-overlay: SUCCESS (11.638s)
[401.450443] [061/133] (618s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[401.556710] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[401.560206] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[401.662748] [062/133] (618s left) gem_pxp (verify-pxp-stale-ctx-execution)
[401.778560] Starting subtest: verify-pxp-stale-ctx-execution
[402.837470] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.060s)
[402.985309] [063/133] (617s left) gen9_exec_parse (secure-batches)
[403.050902] Subtest secure-batches: SKIP (0.000s)
[403.138313] [064/133] (617s left) perf (per-context-mode-unprivileged)
[403.234433] Starting subtest: per-context-mode-unprivileged
[403.238603] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[403.285453] [065/133] (617s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[403.378835] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[403.382883] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[403.453262] [066/133] (617s left) gem_mmap (bad-offset)
[403.517687] Subtest bad-offset: SKIP (0.000s)
[403.582108] [067/133] (617s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[403.678727] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[410.680059] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.005s)
[410.785519] [068/133] (609s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[410.889401] Starting subtest: y-tiled-32bpp-rotate-270
[410.893838] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[410.987553] [069/133] (609s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[411.088507] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[411.092937] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[411.203348] [070/133] (609s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[411.291745] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[411.296100] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[411.379135] [071/133] (609s left) sysfs_preempt_timeout (timeout)
[411.445832] Starting subtest: timeout
[411.448105] Starting dynamic subtest: vecs0
[420.882794] Dynamic subtest vecs0: SUCCESS (9.434s)
[420.897509] Starting dynamic subtest: ccs0
[422.238784] Dynamic subtest ccs0: SUCCESS (1.355s)
[422.250101] Starting dynamic subtest: vcs0
[431.889530] Dynamic subtest vcs0: SUCCESS (9.647s)
[431.901923] Starting dynamic subtest: bcs0
[433.178076] Dynamic subtest bcs0: SUCCESS (1.288s)
[433.190227] Starting dynamic subtest: rcs0
[434.445583] Dynamic subtest rcs0: SUCCESS (1.267s)
[434.456545] Starting dynamic subtest: vcs1
[443.915356] Dynamic subtest vcs1: SUCCESS (9.465s)
[443.917859] Subtest timeout: SUCCESS (32.469s)
[443.994423] [072/133] (576s left) i915_pm_rpm (gem-evict-pwrite)
[446.037233] Starting subtest: gem-evict-pwrite
[446.042043] Subtest gem-evict-pwrite: SKIP (0.000s)
[446.421282] [073/133] (574s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[446.532774] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[446.537705] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[446.620842] [074/133] (574s left) syncobj_basic (bad-pad-handle-to-fd)
[446.686676] Starting subtest: bad-pad-handle-to-fd
[446.688992] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[446.803966] [075/133] (574s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[446.863663] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[446.866017] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[446.984422] [076/133] (574s left) syncobj_timeline (single-wait-available-submitted)
[447.039505] Starting subtest: single-wait-available-submitted
[447.041856] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[447.131415] [077/133] (573s left) gem_vm_create (create-ext)
[447.193474] Starting subtest: create-ext
[447.198980] Subtest create-ext: SUCCESS (0.005s)
[447.326857] [078/133] (573s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[447.415706] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[447.417655] Starting dynamic subtest: pipe-A-eDP-1
[447.441801] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[447.452123] Starting dynamic subtest: pipe-B-eDP-1
[447.468771] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[447.477013] Starting dynamic subtest: pipe-C-eDP-1
[447.495742] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[447.503845] Starting dynamic subtest: pipe-D-eDP-1
[447.522780] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[447.526164] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[449.649471] [079/133] (571s left) gem_exec_params (no-vebox)
[449.707769] Starting subtest: no-vebox
[449.710170] Subtest no-vebox: SUCCESS (0.002s)
[450.966189] [080/133] (570s left) kms_flip (2x-flip-vs-fences-interruptible)
[451.065373] Starting subtest: 2x-flip-vs-fences-interruptible
[451.069898] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[451.147948] [081/133] (570s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[451.241079] Starting subtest: async-flip-with-page-flip-events-tiled
[451.614052] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[454.426900] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.812s)
[454.436067] Starting dynamic subtest: pipe-A-eDP-1-4
[456.613202] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.185s)
[456.622495] Starting dynamic subtest: pipe-A-eDP-1-4
[458.622921] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[458.631990] Starting dynamic subtest: pipe-A-eDP-1-x
[460.813847] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.189s)
[461.184090] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[463.949283] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.776s)
[463.961523] Starting dynamic subtest: pipe-B-eDP-1-4
[466.124895] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.174s)
[466.135703] Starting dynamic subtest: pipe-B-eDP-1-4
[468.133694] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.008s)
[468.143113] Starting dynamic subtest: pipe-B-eDP-1-x
[470.323719] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.189s)
[470.684935] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[473.468207] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.788s)
[473.481557] Starting dynamic subtest: pipe-C-eDP-1-4
[475.631215] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.162s)
[475.643282] Starting dynamic subtest: pipe-C-eDP-1-4
[477.641410] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[477.653927] Starting dynamic subtest: pipe-C-eDP-1-x
[479.819726] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[480.182062] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[482.999077] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.824s)
[483.009872] Starting dynamic subtest: pipe-D-eDP-1-4
[485.172896] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.173s)
[485.184766] Starting dynamic subtest: pipe-D-eDP-1-4
[487.183676] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[487.194236] Starting dynamic subtest: pipe-D-eDP-1-x
[489.361784] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.177s)
[489.364071] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.121s)
[490.595871] [082/133] (530s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[490.699400] Starting subtest: 4-tiled-64bpp-rotate-90
[490.719081] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.019s)
[490.841111] [083/133] (530s left) kms_psr (psr2-cursor-render)
[490.928690] Starting subtest: psr2-cursor-render
[490.930992] Starting dynamic subtest: eDP-1
[492.667934] Dynamic subtest eDP-1: SUCCESS (1.739s)
[492.670296] Subtest psr2-cursor-render: SUCCESS (1.741s)
[493.466993] [084/133] (527s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[493.561411] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[493.563299] Starting dynamic subtest: pipe-A-eDP-1
[494.305304] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.737s)
[494.314596] Starting dynamic subtest: pipe-B-eDP-1
[496.096203] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.789s)
[496.105820] Starting dynamic subtest: pipe-C-eDP-1
[497.958651] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.860s)
[497.973030] Starting dynamic subtest: pipe-D-eDP-1
[499.794642] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.835s)
[499.796643] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.227s)
[501.007480] [085/133] (520s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[501.130482] Starting subtest: basic-busy-flip-before-cursor-varying-size
[501.135644] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[501.282800] [086/133] (520s left) syncobj_timeline (invalid-wait-illegal-handle)
[501.336023] Starting subtest: invalid-wait-illegal-handle
[501.338258] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[501.429299] [087/133] (520s left) gem_reg_read (bad-register)
[501.488215] Starting subtest: bad-register
[501.490406] Subtest bad-register: SUCCESS (0.000s)
[501.601962] [088/133] (519s left) kms_big_fb (linear-16bpp-rotate-90)
[501.686656] Starting subtest: linear-16bpp-rotate-90
[501.706251] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[501.823830] [089/133] (519s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[501.871624] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[501.932394] [090/133] (519s left) gem_mmap_gtt (medium-copy-xy)
[501.986085] Subtest medium-copy-XY: SKIP (0.000s)
[502.042922] [091/133] (519s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[502.143486] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[502.145342] Starting dynamic subtest: pipe-A
[503.318866] Dynamic subtest pipe-A: SUCCESS (1.176s)
[503.333028] Starting dynamic subtest: pipe-B
[505.563160] Dynamic subtest pipe-B: SUCCESS (2.242s)
[505.574868] Starting dynamic subtest: pipe-C
[506.717683] Dynamic subtest pipe-C: SUCCESS (1.153s)
[506.727915] Starting dynamic subtest: pipe-D
[507.869899] Dynamic subtest pipe-D: SUCCESS (1.151s)
[507.872302] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.728s)
[509.065495] [092/133] (512s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[509.199624] Starting subtest: flip-vs-cursor-busy-crc-atomic
[510.015245] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.814s)
[510.900285] [093/133] (510s left) gem_spin_batch (resubmit-all)
[510.962736] Starting subtest: resubmit-all
[510.964942] Starting dynamic subtest: rcs0
[510.974282] Dynamic subtest rcs0: SUCCESS (0.011s)
[510.985890] Starting dynamic subtest: bcs0
[510.988097] Dynamic subtest bcs0: SUCCESS (0.002s)
[510.996994] Starting dynamic subtest: vcs0
[510.999133] Dynamic subtest vcs0: SUCCESS (0.002s)
[511.008189] Starting dynamic subtest: vcs1
[511.010308] Dynamic subtest vcs1: SUCCESS (0.002s)
[511.019156] Starting dynamic subtest: vecs0
[511.021257] Dynamic subtest vecs0: SUCCESS (0.002s)
[511.030162] Starting dynamic subtest: ccs0
[511.032297] Dynamic subtest ccs0: SUCCESS (0.003s)
[511.034466] Subtest resubmit-all: SUCCESS (0.026s)
[512.959463] [094/133] (508s left) syncobj_basic (bad-pad-fd-to-handle)
[513.013988] Starting subtest: bad-pad-fd-to-handle
[513.016322] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[513.123181] [095/133] (508s left) kms_psr (fbc-pr-cursor-plane-onoff)
[513.224804] Starting subtest: fbc-pr-cursor-plane-onoff
[513.229106] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[513.298514] [096/133] (508s left) gem_exec_fence (syncobj-timeline-repeat)
[513.858310] Starting subtest: syncobj-timeline-repeat
[514.102040] Subtest syncobj-timeline-repeat: SUCCESS (0.244s)
[514.985075] [097/133] (506s left) kms_frontbuffer_tracking (fbc-1p-rte)
[515.083529] Starting subtest: fbc-1p-rte
[520.584565] Subtest fbc-1p-rte: SUCCESS (5.505s)
[521.730621] [098/133] (500s left) vgem_basic (debugfs)
[521.785231] Starting subtest: debugfs
[521.787102] Subtest debugfs: SUCCESS (0.001s)
[521.842729] [099/133] (500s left) perf (low-oa-exponent-permissions)
[521.936973] Starting subtest: low-oa-exponent-permissions
[521.974695] Subtest low-oa-exponent-permissions: SUCCESS (0.038s)
[522.017425] [100/133] (500s left) gem_softpin (softpin)
[522.083607] Starting subtest: softpin
[554.492782] Subtest softpin: SUCCESS (32.404s)
[554.599729] [101/133] (467s left) i915_getparams_basic (basic-subslice-total)
[554.658818] Starting subtest: basic-subslice-total
[554.663161] Subtest basic-subslice-total: SUCCESS (0.000s)
[554.758048] [102/133] (467s left) kms_cursor_crc (cursor-offscreen-512x170)
[555.107023] Starting subtest: cursor-offscreen-512x170
[555.111474] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[555.250769] [103/133] (466s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[555.302042] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[555.304457] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[555.423499] [104/133] (466s left) kms_vblank (crtc-id)
[555.518255] Starting subtest: crtc-id
[555.519770] Starting dynamic subtest: pipe-A-eDP-1
[555.975463] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.455s)
[555.989967] Starting dynamic subtest: pipe-D-eDP-1
[557.144843] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.166s)
[557.146954] Subtest crtc-id: SUCCESS (1.626s)
[557.990458] [105/133] (464s left) kms_content_protection (dp-mst-type-0)
[558.140164] Starting subtest: dp-mst-type-0
[558.144519] Subtest dp-mst-type-0: SKIP (0.000s)
[558.283258] [106/133] (464s left) perf_pmu (busy-start)
[558.334067] Starting subtest: busy-start
[558.335972] Starting dynamic subtest: rcs0
[560.884902] Dynamic subtest rcs0: SUCCESS (2.551s)
[560.896437] Starting dynamic subtest: bcs0
[563.434442] Dynamic subtest bcs0: SUCCESS (2.547s)
[563.446389] Starting dynamic subtest: vcs0
[566.932975] Dynamic subtest vcs0: SUCCESS (3.494s)
[566.945312] Starting dynamic subtest: vcs1
[570.901192] Dynamic subtest vcs1: SUCCESS (3.965s)
[570.913318] Starting dynamic subtest: vecs0
[574.933372] Dynamic subtest vecs0: SUCCESS (4.029s)
[574.945074] Starting dynamic subtest: ccs0
[577.481080] Dynamic subtest ccs0: SUCCESS (2.547s)
[577.483528] Subtest busy-start: SUCCESS (19.149s)
[577.579579] [107/133] (444s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[577.685272] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[585.040750] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.355s)
[585.188239] [108/133] (437s left) gem_exec_schedule (in-order)
[585.258450] Starting subtest: in-order
[585.260612] Starting dynamic subtest: rcs0
[585.350849] Dynamic subtest rcs0: SUCCESS (0.100s)
[585.363719] Starting dynamic subtest: vcs0
[585.447673] Dynamic subtest vcs0: SUCCESS (0.096s)
[585.457666] Starting dynamic subtest: vcs1
[585.541468] Dynamic subtest vcs1: SUCCESS (0.093s)
[585.558475] Starting dynamic subtest: vecs0
[585.620516] Dynamic subtest vecs0: SUCCESS (0.078s)
[585.633024] Starting dynamic subtest: ccs0
[585.717041] Dynamic subtest ccs0: SUCCESS (0.096s)
[585.719997] Subtest in-order: SUCCESS (0.467s)
[586.997864] [109/133] (435s left) sriov_basic (enable-vfs-bind-unbind-each)
[587.051553] Starting subtest: enable-vfs-bind-unbind-each
[587.053459] Starting dynamic subtest: numvfs-1
[587.131798] Dynamic subtest numvfs-1: FAIL (0.031s)
[587.145876] Starting dynamic subtest: numvfs-2
[587.218688] Dynamic subtest numvfs-2: FAIL (0.004s)
[587.229618] Starting dynamic subtest: numvfs-3
[587.305255] Dynamic subtest numvfs-3: FAIL (0.004s)
[587.315453] Starting dynamic subtest: numvfs-4
[587.388602] Dynamic subtest numvfs-4: FAIL (0.004s)
[587.399558] Starting dynamic subtest: numvfs-5
[587.471265] Dynamic subtest numvfs-5: FAIL (0.004s)
[587.481891] Starting dynamic subtest: numvfs-6
[587.554377] Dynamic subtest numvfs-6: FAIL (0.004s)
[587.565064] Starting dynamic subtest: numvfs-7
[587.639764] Dynamic subtest numvfs-7: FAIL (0.004s)
[587.651085] Starting dynamic subtest: numvfs-random
[587.724416] Dynamic subtest numvfs-random: FAIL (0.004s)
[587.726745] Subtest enable-vfs-bind-unbind-each: FAIL (0.063s)
[587.770900] [110/133] (434s left) kms_psr (fbc-psr2-cursor-render)
[587.864740] Starting subtest: fbc-psr2-cursor-render
[587.867064] Starting dynamic subtest: eDP-1
[587.871154] Dynamic subtest eDP-1: SKIP (0.000s)
[587.875392] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[587.938651] [111/133] (434s left) syncobj_basic (bad-fd-to-handle)
[588.005545] Starting subtest: bad-fd-to-handle
[588.007171] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[588.120338] [112/133] (434s left) syncobj_eventfd (invalid-illegal-handle)
[588.178945] Starting subtest: invalid-illegal-handle
[588.180880] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[588.284734] [113/133] (434s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[588.377022] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[588.380544] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[588.477637] [114/133] (434s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[588.574648] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[588.578122] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[588.671939] [115/133] (434s left) kms_vblank (invalid)
[588.758203] Starting subtest: invalid
[588.760046] Starting dynamic subtest: pipe-A-eDP-1
[589.160818] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.403s)
[589.163252] Subtest invalid: SUCCESS (0.404s)
[589.970986] [116/133] (432s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[590.261873] Starting subtest: cursor-rapid-movement-512x512
[590.266257] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[590.438973] [117/133] (432s left) kms_getfb (getfb-handle-protection)
[590.533249] Starting subtest: getfb-handle-protection
[590.560837] Subtest getfb-handle-protection: SUCCESS (0.029s)
[590.699279] [118/133] (432s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[590.767332] Starting subtest: invalid-bsd1-flag-on-vebox
[590.769676] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[590.869489] [119/133] (431s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[590.967492] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[590.971125] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[591.062847] [120/133] (431s left) kms_psr (fbc-psr-cursor-mmap-gtt)
[591.161377] Starting subtest: fbc-psr-cursor-mmap-gtt
[591.163233] Starting dynamic subtest: eDP-1
[591.166695] Dynamic subtest eDP-1: SKIP (0.000s)
[591.170072] Subtest fbc-psr-cursor-mmap-gtt: SKIP (0.001s)
[591.237966] [121/133] (431s left) kms_pm_rpm (legacy-planes-dpms)
[593.071219] Starting subtest: legacy-planes-dpms
[593.073483] Starting dynamic subtest: plane-65
[601.667495] Dynamic subtest plane-65: SUCCESS (8.597s)
[601.680265] Starting dynamic subtest: plane-95
[610.264217] Dynamic subtest plane-95: SUCCESS (8.594s)
[610.276213] Starting dynamic subtest: plane-125
[618.576460] Dynamic subtest plane-125: SUCCESS (8.309s)
[618.589055] Starting dynamic subtest: plane-135
[626.869053] Dynamic subtest plane-135: SUCCESS (8.290s)
[626.871984] Subtest legacy-planes-dpms: SUCCESS (33.801s)
[627.246566] [122/133] (395s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[627.378789] Starting subtest: cursorA-vs-flipA-legacy
[638.505108] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.127s)
[639.375585] [123/133] (383s left) gem_exec_alignment (many)
[639.451097] Starting subtest: many
[662.489504] Subtest many: SUCCESS (23.036s)
[664.347860] [124/133] (358s left) perf (enable-disable)
[664.504556] Starting subtest: enable-disable
[664.506806] Starting dynamic subtest: 0-rcs0
[666.807081] Dynamic subtest 0-rcs0: SUCCESS (2.303s)
[666.816721] Starting dynamic subtest: 1-vecs0
[671.287851] Dynamic subtest 1-vecs0: SUCCESS (4.479s)
[671.290632] Subtest enable-disable: SUCCESS (6.784s)
[671.339759] [125/133] (351s left) gem_softpin (noreloc)
[671.399520] Starting subtest: noreloc
[676.784589] Subtest noreloc: SUCCESS (5.383s)
[676.931951] [126/133] (346s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[677.048908] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[677.053399] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[677.137910] [127/133] (346s left) kms_prop_blob (invalid-set-prop-any)
[677.192076] Starting subtest: invalid-set-prop-any
[677.194059] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[677.306719] [128/133] (345s left) gem_exec_whisper (basic-contexts-forked)
[677.363350] Starting subtest: basic-contexts-forked
[702.292569] Subtest basic-contexts-forked: SUCCESS (24.927s)
[702.481445] [129/133] (320s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[702.595421] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[702.597735] Starting dynamic subtest: pipe-A-eDP-1
[702.620074] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[702.630174] Starting dynamic subtest: pipe-B-eDP-1
[702.651151] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[702.660611] Starting dynamic subtest: pipe-C-eDP-1
[702.681455] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[702.692273] Starting dynamic subtest: pipe-D-eDP-1
[702.713209] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[702.717110] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[704.921533] [130/133] (318s left) gem_mmap_gtt (zero-extend)
[704.986749] Subtest zero-extend: SKIP (0.000s)
[705.075839] [131/133] (318s left) kms_pm_dc (dc6-dpms)
[706.183750] Starting subtest: dc6-dpms
[708.290928] Subtest dc6-dpms: SUCCESS (2.106s)
[708.382527] [132/133] (315s left) kms_chamelium_color (degamma)
[708.480251] Subtest degamma: SKIP (0.000s)
[708.527951] [133/133] (314s left) api_intel_bb (intel-bb-blit-none)
[708.583723] Starting subtest: intel-bb-blit-none
[712.618450] Subtest intel-bb-blit-none: SUCCESS (4.036s)
[712.725170] Closing watchdogs
results: parsing output: 0/ for test: gem_bad_reloc
results: parsing output: 1/ for test: gem_ctx_persistence
results: parsing output: 2/ for test: kms_setmode
results: parsing output: 3/ for test: kms_lease
results: parsing output: 4/ for test: kms_atomic_interruptible
results: parsing output: 5/ for test: kms_plane_scaling
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: gem_fence_thrash
results: parsing output: 9/ for test: kms_chamelium_hpd
results: parsing output: 10/ for test: gem_exec_schedule
results: parsing output: 11/ for test: gem_exec_params
results: parsing output: 12/ for test: gem_pread
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: gem_softpin
results: parsing output: 15/ for test: kms_hdr
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_basic
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_color_pipeline
results: parsing output: 20/ for test: gem_ccs
results: parsing output: 21/ for test: gem_exec_parallel
results: parsing output: 22/ for test: gem_exec_balancer
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: gem_fence_thrash
results: parsing output: 25/ for test: kms_pipe_crc_basic
results: parsing output: 26/ for test: kms_psr2_sf
results: parsing output: 27/ for test: gem_compute
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_dsc
results: parsing output: 31/ for test: kms_flip_scaled_crc
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: gem_eio
results: parsing output: 34/ for test: gem_close
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: i915_query
results: parsing output: 37/ for test: i915_drm_fdinfo
results: parsing output: 38/ for test: gem_ctx_persistence
results: parsing output: 39/ for test: kms_debugfs
results: parsing output: 40/ for test: dumb_buffer
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: perf_pmu
results: parsing output: 45/ for test: gem_lmem_swapping
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: api_intel_allocator
results: parsing output: 48/ for test: perf
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: gem_ctx_param
results: parsing output: 52/ for test: vgem_slow
results: parsing output: 53/ for test: gem_exec_balancer
results: parsing output: 54/ for test: gem_ctx_bad_destroy
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: vgem_basic
results: parsing output: 57/ for test: kms_psr
results: parsing output: 58/ for test: kms_vblank
results: parsing output: 59/ for test: kms_psr_stress_test
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_pxp
results: parsing output: 62/ for test: gen9_exec_parse
results: parsing output: 63/ for test: perf
results: parsing output: 64/ for test: kms_flip_scaled_crc
results: parsing output: 65/ for test: gem_mmap
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_plane
results: parsing output: 70/ for test: sysfs_preempt_timeout
results: parsing output: 71/ for test: i915_pm_rpm
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: syncobj_basic
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: gem_vm_create
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: gem_exec_params
results: parsing output: 79/ for test: kms_flip
results: parsing output: 80/ for test: kms_async_flips
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: kms_cursor_legacy
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: gem_reg_read
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_pipe_b_c_ivb
results: parsing output: 89/ for test: gem_mmap_gtt
results: parsing output: 90/ for test: kms_plane_scaling
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: gem_spin_batch
results: parsing output: 93/ for test: syncobj_basic
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: vgem_basic
results: parsing output: 98/ for test: perf
results: parsing output: 99/ for test: gem_softpin
results: parsing output: 100/ for test: i915_getparams_basic
results: parsing output: 101/ for test: kms_cursor_crc
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: kms_vblank
results: parsing output: 104/ for test: kms_content_protection
results: parsing output: 105/ for test: perf_pmu
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_schedule
results: parsing output: 108/ for test: sriov_basic
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: syncobj_basic
results: parsing output: 111/ for test: syncobj_eventfd
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_vblank
results: parsing output: 115/ for test: kms_cursor_crc
results: parsing output: 116/ for test: kms_getfb
results: parsing output: 117/ for test: gem_exec_params
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: kms_pm_rpm
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: gem_exec_alignment
results: parsing output: 123/ for test: perf
results: parsing output: 124/ for test: gem_softpin
results: parsing output: 125/ for test: kms_plane
results: parsing output: 126/ for test: kms_prop_blob
results: parsing output: 127/ for test: gem_exec_whisper
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: gem_mmap_gtt
results: parsing output: 130/ for test: kms_pm_dc
results: parsing output: 131/ for test: kms_chamelium_color
results: parsing output: 132/ for test: api_intel_bb
Done.