[57.809596] Initializing watchdogs
[57.809639]   /dev/watchdog0
[57.823173] [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.827628] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.829728] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.832989] [001/132] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[57.978940] Starting subtest: psr-1p-primscrn-cur-indfb-move
[65.271996] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.297s)
[65.437133] [002/132] (952s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[65.539619] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[70.238057] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.702s)
[71.389909] [003/132] (947s left) kms_async_flips (async-flip-hang)
[71.480973] Starting subtest: async-flip-hang
[71.852813] Starting dynamic subtest: pipe-A-eDP-1
[98.359800] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.506s)
[98.762421] Starting dynamic subtest: pipe-B-eDP-1
[114.416804] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.659s)
[114.788117] Starting dynamic subtest: pipe-C-eDP-1
[130.354686] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.570s)
[130.725872] Starting dynamic subtest: pipe-D-eDP-1
[146.347217] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.626s)
[146.349620] Subtest async-flip-hang: SUCCESS (74.866s)
[147.633071] [004/132] (870s left) perf (missing-sample-flags)
[147.771279] Starting subtest: missing-sample-flags
[147.773543] Subtest missing-sample-flags: SUCCESS (0.000s)
[147.807810] [005/132] (870s left) kms_big_fb (x-tiled-addfb-size-overflow)
[147.914297] Starting subtest: x-tiled-addfb-size-overflow
[147.916514] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[148.026120] [006/132] (870s left) gem_mmap_gtt (basic-copy)
[148.086097] Subtest basic-copy: SKIP (0.000s)
[148.137083] [007/132] (870s left) kms_dp_link_training (uhbr-mst)
[148.240963] Starting subtest: uhbr-mst
[148.249664] Subtest uhbr-mst: SKIP (0.000s)
[148.332182] [008/132] (870s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[148.427605] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[148.429862] Starting dynamic subtest: pipe-A-default-mode
[148.742864] Dynamic subtest pipe-A-default-mode: SKIP (0.314s)
[148.747370] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.315s)
[149.561771] [009/132] (869s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[149.665447] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[149.669848] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[149.794443] [010/132] (868s left) perf_pmu (faulting-read)
[149.841074] Starting subtest: faulting-read
[149.843468] Starting dynamic subtest: wb
[149.845744] Dynamic subtest wb: SUCCESS (0.001s)
[149.856644] Starting dynamic subtest: wc
[149.858599] Dynamic subtest wc: SUCCESS (0.000s)
[149.869958] Starting dynamic subtest: uc
[149.872233] Dynamic subtest uc: SUCCESS (0.000s)
[149.874419] Subtest faulting-read: SUCCESS (0.002s)
[149.971614] [011/132] (868s left) kms_cursor_crc (cursor-offscreen-512x512)
[150.224480] Starting subtest: cursor-offscreen-512x512
[150.228255] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[150.425388] [012/132] (868s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[150.526419] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[150.530109] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[150.656860] [013/132] (868s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[150.758267] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[155.211790] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.453s)
[156.367565] [014/132] (862s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[156.426182] Starting subtest: x-tiled-to-vebox-yf-tiled
[156.430190] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[156.630325] [015/132] (862s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[156.735834] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[156.740362] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[156.862436] [016/132] (862s left) perf (oa-formats)
[156.966682] Starting subtest: oa-formats
[156.968607] Starting dynamic subtest: 0-rcs0
[156.978760] Dynamic subtest 0-rcs0: SUCCESS (0.014s)
[156.986614] Starting dynamic subtest: 1-vcs1
[156.999895] Dynamic subtest 1-vcs1: SUCCESS (0.021s)
[157.002206] Subtest oa-formats: SUCCESS (0.036s)
[157.040262] [017/132] (861s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[157.121964] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[157.124192] Starting dynamic subtest: pipe-A-eDP-1
[157.962610] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.832s)
[157.970851] Starting dynamic subtest: pipe-B-eDP-1
[159.466194] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.500s)
[159.479123] Starting dynamic subtest: pipe-C-eDP-1
[160.953172] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.486s)
[160.961337] Starting dynamic subtest: pipe-D-eDP-1
[162.443024] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.488s)
[162.445146] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.311s)
[163.694931] [018/132] (855s left) kms_flip (basic-flip-vs-dpms)
[163.810516] Starting subtest: basic-flip-vs-dpms
[163.812914] Starting dynamic subtest: A-eDP1
[166.362043] Dynamic subtest A-eDP1: SUCCESS (2.553s)
[166.376052] Starting dynamic subtest: B-eDP1
[168.540068] Dynamic subtest B-eDP1: SUCCESS (2.175s)
[168.550687] Starting dynamic subtest: C-eDP1
[170.626281] Dynamic subtest C-eDP1: SUCCESS (2.086s)
[170.635099] Starting dynamic subtest: D-eDP1
[172.705415] Dynamic subtest D-eDP1: SUCCESS (2.079s)
[172.707853] Subtest basic-flip-vs-dpms: SUCCESS (8.900s)
[173.522364] [019/132] (845s left) gem_exec_balancer (indices)
[173.593655] Starting subtest: indices
[175.859080] Subtest indices: SUCCESS (2.263s)
[176.200811] [020/132] (842s left) gem_mmap_offset (open-flood)
[176.254189] Starting subtest: open-flood
[197.740665] Subtest open-flood: SUCCESS (21.488s)
[197.885114] [021/132] (821s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[197.980847] Starting subtest: plane-lut1d-ctm3x4
[197.995409] Starting dynamic subtest: pipe-A-eDP-1
[198.636016] Dynamic subtest pipe-A-eDP-1: FAIL (0.316s)
[199.374625] Starting dynamic subtest: pipe-B-eDP-1
[200.188743] Dynamic subtest pipe-B-eDP-1: FAIL (0.291s)
[200.701463] Starting dynamic subtest: pipe-C-eDP-1
[201.554422] Dynamic subtest pipe-C-eDP-1: FAIL (0.269s)
[202.039766] Starting dynamic subtest: pipe-D-eDP-1
[202.914069] Dynamic subtest pipe-D-eDP-1: FAIL (0.291s)
[202.916440] Subtest plane-lut1d-ctm3x4: FAIL (4.640s)
[203.441842] [022/132] (815s left) api_intel_bb (reset-bb)
[203.506058] Starting subtest: reset-bb
[203.508283] Subtest reset-bb: SUCCESS (0.004s)
[203.666861] [023/132] (815s left) i915_hangman (detector)
[203.727127] Starting subtest: detector
[203.729066] Starting dynamic subtest: rcs0
[214.899037] Dynamic subtest rcs0: SUCCESS (11.171s)
[214.910309] Starting dynamic subtest: bcs0
[226.868241] Dynamic subtest bcs0: SUCCESS (11.966s)
[226.879092] Starting dynamic subtest: vcs0
[236.851044] Dynamic subtest vcs0: SUCCESS (9.980s)
[236.863706] Starting dynamic subtest: vcs1
[248.883038] Dynamic subtest vcs1: SUCCESS (12.029s)
[248.893107] Starting dynamic subtest: vecs0
[260.850614] Dynamic subtest vecs0: SUCCESS (11.965s)
[260.862062] Starting dynamic subtest: ccs0
[272.883079] Dynamic subtest ccs0: SUCCESS (12.030s)
[272.885859] Subtest detector: SUCCESS (69.156s)
[272.987908] [024/132] (746s left) kms_color (ctm-0-75)
[273.084875] Starting subtest: ctm-0-75
[273.087087] Starting dynamic subtest: pipe-A-eDP-1
[278.003331] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.918s)
[278.012199] Starting dynamic subtest: pipe-B-eDP-1
[283.631224] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.627s)
[283.638895] Starting dynamic subtest: pipe-C-eDP-1
[289.001741] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.368s)
[289.010392] Starting dynamic subtest: pipe-D-eDP-1
[294.334596] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.333s)
[294.337039] Subtest ctm-0-75: SUCCESS (21.250s)
[295.179150] [025/132] (724s left) gem_ccs (block-copy-uncompressed)
[295.249615] Starting subtest: block-copy-uncompressed
[295.251743] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[295.271828] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.021s)
[295.280899] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[295.288459] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[295.295783] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[295.305329] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[295.316597] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[295.323158] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[295.325523] Subtest block-copy-uncompressed: SUCCESS (0.074s)
[295.499240] [026/132] (723s left) gem_exec_params (invalid-ring)
[295.559791] Starting subtest: invalid-ring
[295.562132] Subtest invalid-ring: SUCCESS (0.000s)
[295.688385] [027/132] (723s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[295.790663] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[296.459404] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.670s)
[296.612284] [028/132] (722s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[296.705383] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[296.709573] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[296.831715] [029/132] (722s left) i915_pm_rpm (system-suspend)
[299.062711] Starting subtest: system-suspend
[317.159483] Subtest system-suspend: SUCCESS (6.572s)
[317.555211] [030/132] (701s left) gem_exec_flush (basic-uc-pro-default)
[317.631031] Starting subtest: basic-uc-pro-default
[323.093558] Subtest basic-uc-pro-default: SUCCESS (5.450s)
[323.311099] [031/132] (696s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[323.365198] Starting subtest: invalid-bsd2-flag-on-vebox
[323.367628] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[323.507311] [032/132] (695s left) gem_exec_create (basic)
[323.576686] Starting subtest: basic
[323.578823] Starting dynamic subtest: smem
[327.387536] Dynamic subtest smem: SUCCESS (3.803s)
[327.390212] Subtest basic: SUCCESS (3.805s)
[327.595629] [033/132] (691s left) sysfs_preempt_timeout (timeout)
[327.664132] Starting subtest: timeout
[327.666458] Starting dynamic subtest: vecs0
[337.460711] Dynamic subtest vecs0: SUCCESS (9.784s)
[337.472312] Starting dynamic subtest: ccs0
[338.825892] Dynamic subtest ccs0: SUCCESS (1.363s)
[338.837203] Starting dynamic subtest: vcs0
[348.476809] Dynamic subtest vcs0: SUCCESS (9.643s)
[348.488139] Starting dynamic subtest: bcs0
[349.795830] Dynamic subtest bcs0: SUCCESS (1.317s)
[349.807081] Starting dynamic subtest: rcs0
[351.120091] Dynamic subtest rcs0: SUCCESS (1.321s)
[351.130730] Starting dynamic subtest: vcs1
[360.439400] Dynamic subtest vcs1: SUCCESS (9.314s)
[360.442049] Subtest timeout: SUCCESS (32.758s)
[360.541654] [034/132] (659s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[360.663658] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[360.667612] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[360.754592] [035/132] (658s left) kms_draw_crc (draw-method-mmap-wc)
[360.846183] Starting subtest: draw-method-mmap-wc
[360.848441] Starting dynamic subtest: xrgb8888-untiled
[361.055371] Dynamic subtest xrgb8888-untiled: SUCCESS (0.210s)
[361.064400] Starting dynamic subtest: rgb565-untiled
[361.255361] Dynamic subtest rgb565-untiled: SUCCESS (0.199s)
[361.264020] Starting dynamic subtest: xrgb2101010-untiled
[361.477253] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[361.485740] Starting dynamic subtest: xbgr16161616f-untiled
[361.699401] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[361.708033] Starting dynamic subtest: xrgb8888-xtiled
[361.821667] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[361.835264] Starting dynamic subtest: rgb565-xtiled
[361.966261] Dynamic subtest rgb565-xtiled: SUCCESS (0.143s)
[361.974967] Starting dynamic subtest: xrgb2101010-xtiled
[362.077685] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[362.093609] Starting dynamic subtest: xbgr16161616f-xtiled
[362.210642] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.131s)
[362.224507] Starting dynamic subtest: xrgb8888-4tiled
[362.379359] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.166s)
[362.387571] Starting dynamic subtest: rgb565-4tiled
[362.523819] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s)
[362.533844] Starting dynamic subtest: xrgb2101010-4tiled
[362.671697] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.132s)
[362.694826] Starting dynamic subtest: xbgr16161616f-4tiled
[362.825070] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.153s)
[362.837277] Subtest draw-method-mmap-wc: SUCCESS (1.966s)
[362.977527] [036/132] (656s left) kms_psr (pr-dpms)
[363.126745] Starting subtest: pr-dpms
[363.146935] Subtest pr-dpms: SKIP (0.000s)
[363.267813] [037/132] (656s left) kms_prop_blob (invalid-set-prop)
[363.369667] Starting subtest: invalid-set-prop
[363.379031] Subtest invalid-set-prop: SUCCESS (0.000s)
[363.543557] [038/132] (656s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[363.706849] Starting subtest: cursor-vs-flip-varying-size
[394.578466] Subtest cursor-vs-flip-varying-size: SUCCESS (30.877s)
[395.414112] [039/132] (624s left) kms_scaling_modes (scaling-mode-center)
[395.509914] Starting subtest: scaling-mode-center
[395.512072] Starting dynamic subtest: pipe-A-eDP-1
[395.601182] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.092s)
[395.610946] Starting dynamic subtest: pipe-B-eDP-1
[396.812862] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.210s)
[396.822634] Starting dynamic subtest: pipe-C-eDP-1
[397.921780] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.107s)
[397.932690] Starting dynamic subtest: pipe-D-eDP-1
[399.057301] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.133s)
[399.059607] Subtest scaling-mode-center: SUCCESS (3.548s)
[400.244388] [040/132] (619s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[400.353885] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[400.357947] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[400.482325] [041/132] (619s left) gem_ctx_persistence (legacy-engines-hang)
[400.551084] Starting subtest: legacy-engines-hang
[400.553278] Starting dynamic subtest: render
[400.704449] Dynamic subtest render: SUCCESS (0.154s)
[400.716193] Starting dynamic subtest: bsd1
[400.838627] Dynamic subtest bsd1: SUCCESS (0.131s)
[400.850656] Starting dynamic subtest: bsd2
[400.966408] Dynamic subtest bsd2: SUCCESS (0.125s)
[400.978314] Starting dynamic subtest: blt
[401.075439] Dynamic subtest blt: SUCCESS (0.106s)
[401.086871] Starting dynamic subtest: vebox
[401.193085] Dynamic subtest vebox: SUCCESS (0.115s)
[401.195475] Subtest legacy-engines-hang: SUCCESS (0.645s)
[401.295265] [042/132] (618s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[401.397961] Starting subtest: yf-tiled-64bpp-rotate-0
[401.401667] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[401.532078] [043/132] (618s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[401.618396] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[401.627916] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.011s)
[401.731444] [044/132] (618s left) gen9_exec_parse (basic-rejected-ctx-param)
[401.802867] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[401.877037] [045/132] (618s left) perf_pmu (pmu-read)
[401.919975] Starting subtest: pmu-read
[401.924384] Subtest pmu-read: SUCCESS (0.002s)
[402.054037] [046/132] (618s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[402.149712] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[408.723296] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.571s)
[408.896368] [047/132] (611s left) gem_exec_reloc (basic-gtt)
[408.957235] Subtest basic-gtt: SKIP (0.000s)
[409.034761] [048/132] (611s left) kms_universal_plane (disable-primary-vs-flip)
[409.131291] Starting subtest: disable-primary-vs-flip
[409.133544] Starting dynamic subtest: pipe-A-eDP-1
[410.608085] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.477s)
[410.618469] Starting dynamic subtest: pipe-B-eDP-1
[412.790351] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.182s)
[412.801241] Starting dynamic subtest: pipe-C-eDP-1
[415.030566] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.238s)
[415.044677] Starting dynamic subtest: pipe-D-eDP-1
[417.183840] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.153s)
[417.185919] Subtest disable-primary-vs-flip: SUCCESS (8.056s)
[418.003725] [049/132] (602s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[418.103131] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[418.107781] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[419.228232] [050/132] (601s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[419.332456] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[419.336924] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[419.473529] [051/132] (600s left) core_setmaster (master-drop-set-shared-fd)
[419.576486] Starting subtest: master-drop-set-shared-fd
[419.598170] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[419.637602] [052/132] (600s left) kms_chamelium_frames (dp-crc-multiple)
[420.020711] Subtest dp-crc-multiple: SKIP (0.000s)
[420.807525] [053/132] (599s left) kms_flip (2x-flip-vs-wf_vblank)
[420.903115] Starting subtest: 2x-flip-vs-wf_vblank
[420.912115] Subtest 2x-flip-vs-wf_vblank: SKIP (0.010s)
[421.049554] [054/132] (599s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[421.147935] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[421.151954] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[421.284752] [055/132] (599s left) gem_mmap_gtt (basic-write-read)
[421.355822] Subtest basic-write-read: SKIP (0.000s)
[421.418009] [056/132] (599s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[421.485026] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[421.489589] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[421.692512] [057/132] (598s left) gem_exec_reloc (basic-write-gtt-noreloc)
[421.750391] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[421.826422] [058/132] (598s left) i915_suspend (basic-s2idle-without-i915)
[421.857737] Starting subtest: basic-s2idle-without-i915
[442.804724] Subtest basic-s2idle-without-i915: SUCCESS (5.805s)
[442.937694] [059/132] (577s left) perf (gen12-invalid-class-instance)
[443.084210] Starting subtest: gen12-invalid-class-instance
[443.096924] Subtest gen12-invalid-class-instance: SUCCESS (0.011s)
[443.140316] [060/132] (577s left) prime_vgem (busy)
[443.266279] Starting subtest: busy
[443.268496] Starting dynamic subtest: rcs0
[443.335532] Dynamic subtest rcs0: SUCCESS (0.071s)
[443.343975] Starting dynamic subtest: bcs0
[443.409105] Dynamic subtest bcs0: SUCCESS (0.072s)
[443.417371] Starting dynamic subtest: vcs0
[443.456218] Dynamic subtest vcs0: SUCCESS (0.047s)
[443.464136] Starting dynamic subtest: vcs1
[443.502067] Dynamic subtest vcs1: SUCCESS (0.045s)
[443.510125] Starting dynamic subtest: vecs0
[443.549429] Dynamic subtest vecs0: SUCCESS (0.047s)
[443.557398] Starting dynamic subtest: ccs0
[443.596838] Dynamic subtest ccs0: SUCCESS (0.046s)
[443.599333] Subtest busy: SUCCESS (0.333s)
[443.757206] [FACT prime_vgem (busy)] new: kernel.kmod_is_loaded.vgem: true
[443.760438] [061/132] (577s left) kms_content_protection (content-type-change)
[443.909155] Starting subtest: content-type-change
[443.913066] Subtest content-type-change: SKIP (0.000s)
[444.062958] [062/132] (576s left) kms_flip (blocking-wf_vblank)
[444.158515] Starting subtest: blocking-wf_vblank
[444.160487] Starting dynamic subtest: A-eDP1
[453.146164] Dynamic subtest A-eDP1: SUCCESS (8.976s)
[453.163311] Starting dynamic subtest: B-eDP1
[462.083875] Dynamic subtest B-eDP1: SUCCESS (8.929s)
[462.094545] Starting dynamic subtest: C-eDP1
[471.030806] Dynamic subtest C-eDP1: SUCCESS (8.919s)
[471.044291] Starting dynamic subtest: D-eDP1
[479.943071] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[479.945408] Subtest blocking-wf_vblank: SUCCESS (35.766s)
[481.184285] [063/132] (539s left) i915_drm_fdinfo (all-busy-idle-check-all)
[481.232776] Starting subtest: all-busy-idle-check-all
[481.240261] Subtest all-busy-idle-check-all: SKIP (0.004s)
[481.341088] [064/132] (539s left) syncobj_timeline (single-wait-available-submitted)
[481.436625] Starting subtest: single-wait-available-submitted
[481.439148] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[481.565045] [065/132] (539s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[481.674690] Starting subtest: compare-crc-sanitycheck-nv12
[481.676767] Starting dynamic subtest: pipe-A-eDP-1
[482.362207] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.688s)
[482.372519] Starting dynamic subtest: pipe-B-eDP-1
[483.674029] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.308s)
[483.691711] Starting dynamic subtest: pipe-C-eDP-1
[484.889606] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.217s)
[484.899045] Starting dynamic subtest: pipe-D-eDP-1
[486.143129] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.249s)
[486.145512] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.469s)
[486.998816] [066/132] (533s left) kms_psr (fbc-psr-dpms)
[487.092032] Starting subtest: fbc-psr-dpms
[487.094311] Starting dynamic subtest: eDP-1
[487.098439] Dynamic subtest eDP-1: SKIP (0.000s)
[487.102631] Subtest fbc-psr-dpms: SKIP (0.001s)
[487.191541] [067/132] (533s left) gem_exec_schedule (preempt-hang)
[487.366074] Starting subtest: preempt-hang
[487.368847] Starting dynamic subtest: rcs0
[487.583415] Dynamic subtest rcs0: SUCCESS (0.218s)
[487.592181] Starting dynamic subtest: bcs0
[487.810840] Dynamic subtest bcs0: SUCCESS (0.225s)
[487.819197] Starting dynamic subtest: vcs0
[488.019883] Dynamic subtest vcs0: SUCCESS (0.209s)
[488.028665] Starting dynamic subtest: vcs1
[488.248354] Dynamic subtest vcs1: SUCCESS (0.226s)
[488.257679] Starting dynamic subtest: vecs0
[488.474046] Dynamic subtest vecs0: SUCCESS (0.222s)
[488.485145] Starting dynamic subtest: ccs0
[488.702474] Dynamic subtest ccs0: SUCCESS (0.228s)
[488.704470] Subtest preempt-hang: SUCCESS (1.336s)
[489.712562] [068/132] (531s left) gem_mmap_offset (perf)
[489.774032] Starting subtest: perf
[489.775865] Starting dynamic subtest: smem0
[490.138784] Dynamic subtest smem0: SUCCESS (0.363s)
[490.141711] Subtest perf: SUCCESS (0.364s)
[490.265551] [069/132] (530s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[495.243275] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[495.245227] Starting dynamic subtest: pipe-A-eDP-1
[501.755055] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.511s)
[501.764207] Starting dynamic subtest: pipe-B-eDP-1
[508.307805] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.550s)
[508.310222] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (13.065s)
[509.215678] [070/132] (511s left) kms_cursor_crc (cursor-random-32x10)
[509.386693] Starting subtest: cursor-random-32x10
[509.430267] Subtest cursor-random-32x10: SKIP (0.038s)
[509.727846] [071/132] (511s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[509.838312] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[509.842622] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[509.975704] [072/132] (511s left) gem_ctx_persistence (engines-hostile-preempt)
[510.041394] Starting subtest: engines-hostile-preempt
[510.043338] Starting dynamic subtest: rcs0
[510.146835] Dynamic subtest rcs0: SUCCESS (0.108s)
[510.158271] Starting dynamic subtest: bcs0
[510.174039] Dynamic subtest bcs0: SUCCESS (0.026s)
[510.182041] Starting dynamic subtest: vcs0
[511.571401] Dynamic subtest vcs0: SUCCESS (1.394s)
[511.583275] Starting dynamic subtest: vcs1
[513.584197] Dynamic subtest vcs1: SUCCESS (2.009s)
[513.594946] Starting dynamic subtest: vecs0
[515.615358] Dynamic subtest vecs0: SUCCESS (2.029s)
[515.626658] Starting dynamic subtest: ccs0
[515.699839] Dynamic subtest ccs0: SUCCESS (0.083s)
[515.702776] Subtest engines-hostile-preempt: SUCCESS (5.661s)
[515.806253] [073/132] (505s left) kms_psr (psr2-sprite-blt)
[515.901130] Starting subtest: psr2-sprite-blt
[515.903417] Starting dynamic subtest: eDP-1
[517.568806] Dynamic subtest eDP-1: SUCCESS (1.666s)
[517.570752] Subtest psr2-sprite-blt: SUCCESS (1.667s)
[518.460262] [074/132] (502s left) syncobj_timeline (single-wait-signaled)
[518.538239] Starting subtest: single-wait-signaled
[518.540150] Subtest single-wait-signaled: SUCCESS (0.001s)
[518.671470] [075/132] (502s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[518.776887] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[518.781268] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[518.913252] [076/132] (502s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[519.002148] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[519.004466] Starting dynamic subtest: pipe-A
[519.337396] Dynamic subtest pipe-A: SUCCESS (0.338s)
[519.346702] Starting dynamic subtest: pipe-B
[520.728842] Dynamic subtest pipe-B: SUCCESS (1.390s)
[520.742091] Starting dynamic subtest: pipe-C
[522.168542] Dynamic subtest pipe-C: SUCCESS (1.438s)
[522.179526] Starting dynamic subtest: pipe-D
[523.614423] Dynamic subtest pipe-D: SUCCESS (1.445s)
[523.616679] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.615s)
[524.802621] [077/132] (496s left) gem_close (basic)
[524.870452] Starting subtest: basic
[524.872852] Subtest basic: SUCCESS (0.000s)
[525.011953] [078/132] (496s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[525.109697] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[525.111931] Starting dynamic subtest: pipe-A-eDP-1
[525.134350] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[525.144664] Starting dynamic subtest: pipe-B-eDP-1
[525.165734] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[525.174555] Starting dynamic subtest: pipe-C-eDP-1
[525.197308] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[525.206154] Starting dynamic subtest: pipe-D-eDP-1
[525.228599] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[525.232714] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[527.276274] [079/132] (494s left) gem_create (create-ext-cpu-access-sanity-check)
[527.455710] Starting subtest: create-ext-cpu-access-sanity-check
[527.460443] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[527.548453] [080/132] (494s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[527.649163] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[532.255716] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (4.609s)
[533.392189] [081/132] (488s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[533.467884] Starting subtest: etime-single-wait-for-submit-submitted
[533.574061] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.105s)
[533.675267] [082/132] (487s left) kms_pm_rpm (modeset-lpsp)
[535.702563] Starting subtest: modeset-lpsp
[537.144956] Subtest modeset-lpsp: SUCCESS (1.445s)
[537.259164] [083/132] (484s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[537.329557] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[537.437944] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.109s)
[537.537146] [084/132] (484s left) kms_flip (flip-vs-panning-vs-hang)
[537.636297] Starting subtest: flip-vs-panning-vs-hang
[537.638295] Starting dynamic subtest: A-eDP1
[589.033031] Dynamic subtest A-eDP1: SUCCESS (51.402s)
[589.045630] Starting dynamic subtest: D-eDP1
[629.033880] Dynamic subtest D-eDP1: SUCCESS (39.993s)
[629.036352] Subtest flip-vs-panning-vs-hang: SUCCESS (91.401s)
[630.215283] [085/132] (391s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[630.312742] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[630.314979] Starting dynamic subtest: pipe-A-default-mode
[631.460806] Dynamic subtest pipe-A-default-mode: SKIP (1.145s)
[631.465023] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.147s)
[631.637265] [086/132] (390s left) sw_sync (alloc_fence_invalid_timeline)
[631.677075] Starting subtest: alloc_fence_invalid_timeline
[631.679353] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[631.748778] [087/132] (390s left) gem_flink_race (flink_close)
[631.785229] Starting subtest: flink_close
[636.885303] Subtest flink_close: SUCCESS (5.099s)
[636.987097] [088/132] (384s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[637.089032] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[637.093169] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[637.230850] [089/132] (384s left) kms_atomic (plane-primary-legacy)
[637.327764] Starting subtest: plane-primary-legacy
[637.329951] Starting dynamic subtest: pipe-A-eDP-1
[637.773754] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.443s)
[637.776056] Subtest plane-primary-legacy: SUCCESS (0.445s)
[638.671322] [090/132] (383s left) gem_ctx_bad_destroy (invalid-ctx)
[638.722805] Starting subtest: invalid-ctx
[638.725081] Subtest invalid-ctx: SUCCESS (0.000s)
[638.819981] [091/132] (383s left) gem_exec_schedule (wide)
[639.006865] Starting subtest: wide
[639.009404] Starting dynamic subtest: rcs0
[648.686108] Dynamic subtest rcs0: SUCCESS (9.676s)
[648.698520] Starting dynamic subtest: vcs0
[659.109364] Dynamic subtest vcs0: SUCCESS (10.420s)
[659.121644] Starting dynamic subtest: vcs1
[669.359189] Dynamic subtest vcs1: SUCCESS (10.247s)
[669.368070] Starting dynamic subtest: vecs0
[679.286294] Dynamic subtest vecs0: SUCCESS (9.924s)
[679.298263] Starting dynamic subtest: ccs0
[691.933734] Dynamic subtest ccs0: SUCCESS (12.644s)
[691.936348] Subtest wide: SUCCESS (52.917s)
[695.324454] [092/132] (326s left) gem_exec_fence (submit3)
[695.500930] Starting subtest: submit3
[695.505696] Subtest submit3: SKIP (0.000s)
[697.721005] [093/132] (324s left) gem_softpin (allocator-evict)
[697.783706] Starting subtest: allocator-evict
[697.786020] Starting dynamic subtest: rcs0
[721.987440] Dynamic subtest rcs0: SUCCESS (24.198s)
[721.999598] Starting dynamic subtest: bcs0
[746.105925] Dynamic subtest bcs0: SUCCESS (24.112s)
[746.116421] Starting dynamic subtest: vcs0
[769.591313] Dynamic subtest vcs0: SUCCESS (23.478s)
[769.602290] Starting dynamic subtest: vcs1
[793.285064] Dynamic subtest vcs1: SUCCESS (23.687s)
[793.297056] Starting dynamic subtest: vecs0
[816.762480] Dynamic subtest vecs0: SUCCESS (23.471s)
[816.771784] Starting dynamic subtest: ccs0
[840.448676] Dynamic subtest ccs0: SUCCESS (23.685s)
[840.451453] Subtest allocator-evict: SUCCESS (142.639s)
[840.555729] [094/132] (181s left) gem_exec_fence (syncobj-timeline-unused-fence)
[841.200769] Starting subtest: syncobj-timeline-unused-fence
[841.209641] Subtest syncobj-timeline-unused-fence: SUCCESS (0.010s)
[842.652907] [095/132] (179s left) gem_exec_endless (dispatch)
[842.729607] Starting subtest: dispatch
[842.732088] Starting dynamic subtest: rcs0
[842.740440] Dynamic subtest rcs0: SUCCESS (0.005s)
[842.752747] Starting dynamic subtest: bcs0
[842.757043] Dynamic subtest bcs0: SUCCESS (0.004s)
[842.768309] Starting dynamic subtest: vcs0
[842.772609] Dynamic subtest vcs0: SUCCESS (0.005s)
[842.781769] Starting dynamic subtest: vcs1
[842.786532] Dynamic subtest vcs1: SUCCESS (0.005s)
[842.795333] Starting dynamic subtest: vecs0
[842.799690] Dynamic subtest vecs0: SUCCESS (0.004s)
[842.808268] Starting dynamic subtest: ccs0
[842.812493] Dynamic subtest ccs0: SUCCESS (0.004s)
[842.814647] Subtest dispatch: SUCCESS (0.031s)
[844.675853] [096/132] (177s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[844.742045] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[844.744561] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.000s)
[844.876174] [097/132] (177s left) gem_flink_basic (bad-open)
[844.938550] Starting subtest: bad-open
[844.940494] Subtest bad-open: SUCCESS (0.000s)
[845.079690] [098/132] (177s left) gem_wait (await)
[845.148613] Starting subtest: await
[845.151122] Starting dynamic subtest: all-engines
[846.284825] Dynamic subtest all-engines: SUCCESS (1.135s)
[846.304959] Starting dynamic subtest: rcs0
[847.344275] Dynamic subtest rcs0: SUCCESS (1.057s)
[847.361769] Starting dynamic subtest: bcs0
[848.402837] Dynamic subtest bcs0: SUCCESS (1.056s)
[848.420512] Starting dynamic subtest: vcs0
[849.456693] Dynamic subtest vcs0: SUCCESS (1.052s)
[849.476917] Starting dynamic subtest: vcs1
[850.509879] Dynamic subtest vcs1: SUCCESS (1.051s)
[850.526362] Starting dynamic subtest: vecs0
[851.562391] Dynamic subtest vecs0: SUCCESS (1.050s)
[851.579543] Starting dynamic subtest: ccs0
[852.620484] Dynamic subtest ccs0: SUCCESS (1.056s)
[852.622965] Subtest await: SUCCESS (7.474s)
[852.838096] [099/132] (169s left) kms_cursor_legacy (forked-move)
[852.981733] Starting subtest: forked-move
[852.983705] Starting dynamic subtest: pipe-A
[858.477497] Dynamic subtest pipe-A: SUCCESS (5.495s)
[858.490747] Starting dynamic subtest: pipe-B
[863.942639] Dynamic subtest pipe-B: SUCCESS (5.463s)
[863.954736] Starting dynamic subtest: pipe-C
[869.410036] Dynamic subtest pipe-C: SUCCESS (5.466s)
[869.421764] Starting dynamic subtest: pipe-D
[874.870478] Dynamic subtest pipe-D: SUCCESS (5.458s)
[874.882425] Starting dynamic subtest: all-pipes
[880.366956] Dynamic subtest all-pipes: SUCCESS (5.494s)
[880.369496] Subtest forked-move: SUCCESS (27.388s)
[880.539557] [100/132] (141s left) gem_exec_flush (basic-uc-rw-default)
[880.612190] Starting subtest: basic-uc-rw-default
[886.079895] Subtest basic-uc-rw-default: SUCCESS (5.468s)
[886.292825] [101/132] (136s left) syncobj_wait (invalid-wait-illegal-handle)
[886.353692] Starting subtest: invalid-wait-illegal-handle
[886.356088] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[886.492137] [102/132] (135s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[891.548465] Starting subtest: fbc-psr2-cursor-plane-update-sf
[891.550779] Starting dynamic subtest: pipe-A-eDP-1
[892.325497] Dynamic subtest pipe-A-eDP-1: SKIP (0.773s)
[892.336152] Starting dynamic subtest: pipe-B-eDP-1
[893.471413] Dynamic subtest pipe-B-eDP-1: SKIP (1.145s)
[893.476069] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.922s)
[894.676421] [103/132] (127s left) kms_psr (psr-sprite-blt)
[894.773288] Starting subtest: psr-sprite-blt
[894.775626] Starting dynamic subtest: eDP-1
[896.474672] Dynamic subtest eDP-1: SUCCESS (1.701s)
[896.476942] Subtest psr-sprite-blt: SUCCESS (1.702s)
[897.334606] [104/132] (125s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[897.445347] Starting subtest: fbc-rgb565-draw-mmap-wc
[901.404282] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (3.965s)
[902.618037] [105/132] (119s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[902.717203] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[902.721255] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[902.855251] [106/132] (119s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[902.958381] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[902.962756] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[903.100356] [107/132] (119s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[903.199611] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[910.555876] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.360s)
[910.749061] [108/132] (111s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[910.856737] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[910.858992] Starting dynamic subtest: pipe-A
[910.920340] Dynamic subtest pipe-A: SUCCESS (0.065s)
[910.930230] Starting dynamic subtest: pipe-B
[912.028868] Dynamic subtest pipe-B: SUCCESS (1.106s)
[912.041060] Starting dynamic subtest: pipe-C
[913.137861] Dynamic subtest pipe-C: SUCCESS (1.107s)
[913.147075] Starting dynamic subtest: pipe-D
[914.252631] Dynamic subtest pipe-D: SUCCESS (1.114s)
[914.254888] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.398s)
[915.443466] [109/132] (107s left) kms_frontbuffer_tracking (fbc-tiling-y)
[915.536211] Starting subtest: fbc-tiling-y
[915.540272] Subtest fbc-tiling-y: SKIP (0.000s)
[915.672405] [110/132] (107s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[915.727016] Starting subtest: stress-mm-invalidate-close-overlap
[917.876730] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[918.052931] [111/132] (104s left) api_intel_allocator (fork-simple-once)
[918.109406] Starting subtest: fork-simple-once
[918.123834] Subtest fork-simple-once: SUCCESS (0.016s)
[918.271820] [112/132] (104s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[918.376129] Starting subtest: primary-y-tiled-reflect-x-90
[918.380662] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[918.480084] [113/132] (104s left) prime_mmap (test_map_unmap)
[918.534848] Starting subtest: test_map_unmap
[918.537101] Starting dynamic subtest: test_map_unmap-smem
[918.570625] Dynamic subtest test_map_unmap-smem: SUCCESS (0.036s)
[918.573054] Subtest test_map_unmap: SUCCESS (0.037s)
[918.698460] [114/132] (104s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[918.748771] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[918.817982] [115/132] (104s left) gem_partial_pwrite_pread (write-display)
[918.888136] Subtest write-display: SKIP (0.000s)
[918.985933] [116/132] (104s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[919.041702] Starting subtest: multi-wait-all-for-submit-unsubmitted
[919.149787] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.107s)
[919.254272] [117/132] (103s left) gen3_render_tiledy_blits
[919.397016] [118/132] (103s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[919.485970] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[919.487962] Starting dynamic subtest: pipe-A-eDP-1
[919.507756] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.022s)
[919.518226] Starting dynamic subtest: pipe-B-eDP-1
[919.537765] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.023s)
[919.546855] Starting dynamic subtest: pipe-C-eDP-1
[919.566417] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.020s)
[919.575367] Starting dynamic subtest: pipe-D-eDP-1
[919.596106] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.019s)
[919.598274] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.089s)
[921.804633] [119/132] (101s left) kms_chamelium_audio (hdmi-audio)
[922.200006] Subtest hdmi-audio: SKIP (0.000s)
[922.983675] [120/132] (100s left) gem_exec_whisper (basic-queues-all)
[923.066850] Starting subtest: basic-queues-all
[930.720662] Subtest basic-queues-all: SUCCESS (7.653s)
[930.926956] [121/132] ( 92s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[930.996682] Starting subtest: multi-wait-for-submit-unsubmitted
[931.101691] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[931.208801] [122/132] ( 92s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[931.302832] Starting subtest: nonblocking-crc-frame-sequence
[931.304988] Starting dynamic subtest: pipe-A-eDP-1
[932.040669] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.735s)
[932.051315] Starting dynamic subtest: pipe-B-eDP-1
[933.398132] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.356s)
[933.408730] Starting dynamic subtest: pipe-C-eDP-1
[934.791175] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.392s)
[934.802608] Starting dynamic subtest: pipe-D-eDP-1
[936.113705] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.321s)
[936.115683] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.810s)
[936.965846] [123/132] ( 86s left) kms_big_fb (linear-64bpp-rotate-0)
[937.072902] Starting subtest: linear-64bpp-rotate-0
[938.684747] Subtest linear-64bpp-rotate-0: SUCCESS (1.612s)
[938.886634] [124/132] ( 84s left) kms_addfb_basic (bad-pitch-65536)
[938.953763] Starting subtest: bad-pitch-65536
[938.956016] Subtest bad-pitch-65536: SUCCESS (0.000s)
[939.110510] [125/132] ( 84s left) syncobj_wait (invalid-signal-illegal-handle)
[939.166376] Starting subtest: invalid-signal-illegal-handle
[939.168308] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[939.293225] [126/132] ( 84s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[939.403505] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[939.407966] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[939.517631] [127/132] ( 84s left) gem_exec_schedule (semaphore-power)
[939.712546] Subtest semaphore-power: SKIP (0.000s)
[939.913308] [128/132] ( 83s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[940.010212] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[940.014553] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[940.150074] [129/132] ( 83s left) gem_exec_alignment (pi)
[940.220924] Starting subtest: pi
[990.930490] Subtest pi: SUCCESS (50.706s)
[992.100203] [130/132] ( 31s left) kms_psr (fbc-psr2-basic)
[992.244101] Starting subtest: fbc-psr2-basic
[992.246389] Starting dynamic subtest: eDP-1
[992.250766] Dynamic subtest eDP-1: SKIP (0.000s)
[992.255143] Subtest fbc-psr2-basic: SKIP (0.001s)
[992.342935] [131/132] ( 31s left) kms_properties (connector-properties-legacy)
[992.441810] Starting subtest: connector-properties-legacy
[992.444048] Starting dynamic subtest: pipe-A-eDP-1
[1000.406883] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.962s)
[1000.418213] Starting dynamic subtest: pipe-None-DP-1
[1000.422203] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[1000.431261] Starting dynamic subtest: pipe-None-HDMI-A-1
[1000.435642] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[1000.444877] Starting dynamic subtest: pipe-None-DP-2
[1000.449222] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[1000.459076] Starting dynamic subtest: pipe-None-HDMI-A-2
[1000.463235] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[1000.472069] Starting dynamic subtest: pipe-None-DP-3
[1000.476420] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[1000.485256] Starting dynamic subtest: pipe-None-DP-4
[1000.489625] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[1000.491846] Subtest connector-properties-legacy: SUCCESS (7.973s)
[1001.299842] [132/132] ( 22s left) kms_plane (plane-panning-bottom-right-suspend)
[1001.406591] Starting subtest: plane-panning-bottom-right-suspend
[1001.408706] Starting dynamic subtest: pipe-A
[1018.743743] Dynamic subtest pipe-A: SUCCESS (2.762s)
[1018.754064] Starting dynamic subtest: pipe-B
[1036.744261] Dynamic subtest pipe-B: SUCCESS (3.401s)
[1036.746439] Subtest plane-panning-bottom-right-suspend: SUCCESS (6.166s)
[1037.553569] Overall timeout time exceeded, stopping.
[1037.571442] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_async_flips
results: parsing output: 3/ for test: perf
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: gem_mmap_gtt
results: parsing output: 6/ for test: kms_dp_link_training
results: parsing output: 7/ for test: kms_flip_scaled_crc
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: perf_pmu
results: parsing output: 10/ for test: kms_cursor_crc
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_render_copy
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: perf
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: gem_exec_balancer
results: parsing output: 19/ for test: gem_mmap_offset
results: parsing output: 20/ for test: kms_color_pipeline
results: parsing output: 21/ for test: api_intel_bb
results: parsing output: 22/ for test: i915_hangman
results: parsing output: 23/ for test: kms_color
results: parsing output: 24/ for test: gem_ccs
results: parsing output: 25/ for test: gem_exec_params
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: i915_pm_rpm
results: parsing output: 29/ for test: gem_exec_flush
results: parsing output: 30/ for test: gem_exec_params
results: parsing output: 31/ for test: gem_exec_create
results: parsing output: 32/ for test: sysfs_preempt_timeout
results: parsing output: 33/ for test: kms_joiner
results: parsing output: 34/ for test: kms_draw_crc
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: kms_prop_blob
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: kms_scaling_modes
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: gem_ctx_persistence
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: kms_flip
results: parsing output: 43/ for test: gen9_exec_parse
results: parsing output: 44/ for test: perf_pmu
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: gem_exec_reloc
results: parsing output: 47/ for test: kms_universal_plane
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: core_setmaster
results: parsing output: 51/ for test: kms_chamelium_frames
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: gem_render_copy
results: parsing output: 56/ for test: gem_exec_reloc
results: parsing output: 57/ for test: i915_suspend
results: parsing output: 58/ for test: perf
results: parsing output: 59/ for test: prime_vgem
results: parsing output: 60/ for test: kms_content_protection
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: i915_drm_fdinfo
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: kms_pipe_crc_basic
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: gem_exec_schedule
results: parsing output: 67/ for test: gem_mmap_offset
results: parsing output: 68/ for test: kms_psr2_sf
results: parsing output: 69/ for test: kms_cursor_crc
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_ctx_persistence
results: parsing output: 72/ for test: kms_psr
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_plane_scaling
results: parsing output: 76/ for test: gem_close
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: gem_create
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: kms_pm_rpm
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_flip_scaled_crc
results: parsing output: 85/ for test: sw_sync
results: parsing output: 86/ for test: gem_flink_race
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_atomic
results: parsing output: 89/ for test: gem_ctx_bad_destroy
results: parsing output: 90/ for test: gem_exec_schedule
results: parsing output: 91/ for test: gem_exec_fence
results: parsing output: 92/ for test: gem_softpin
results: parsing output: 93/ for test: gem_exec_fence
results: parsing output: 94/ for test: gem_exec_endless
results: parsing output: 95/ for test: syncobj_timeline
results: parsing output: 96/ for test: gem_flink_basic
results: parsing output: 97/ for test: gem_wait
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: gem_exec_flush
results: parsing output: 100/ for test: syncobj_wait
results: parsing output: 101/ for test: kms_psr2_sf
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_plane_scaling
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: gem_userptr_blits
results: parsing output: 110/ for test: api_intel_allocator
results: parsing output: 111/ for test: kms_rotation_crc
results: parsing output: 112/ for test: prime_mmap
results: parsing output: 113/ for test: gem_mmap_gtt
results: parsing output: 114/ for test: gem_partial_pwrite_pread
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: gen3_render_tiledy_blits
results: parsing output: 117/ for test: kms_ccs
results: parsing output: 118/ for test: kms_chamelium_audio
results: parsing output: 119/ for test: gem_exec_whisper
results: parsing output: 120/ for test: syncobj_wait
results: parsing output: 121/ for test: kms_pipe_crc_basic
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: kms_addfb_basic
results: parsing output: 124/ for test: syncobj_wait
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: gem_exec_schedule
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_exec_alignment
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: kms_properties
results: parsing output: 131/ for test: kms_plane
Done.