[102.591878] Initializing watchdogs
[102.591926]   /dev/watchdog0
[102.606286] [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]
[102.610962] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[102.613770] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[102.617293] [001/133] (960s left) kms_cursor_crc (cursor-offscreen-256x85)
[102.909122] Starting subtest: cursor-offscreen-256x85
[102.962146] Subtest cursor-offscreen-256x85: SKIP (0.048s)
[103.138376] [002/133] (960s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs)
[103.227468] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs
[103.229840] Starting dynamic subtest: pipe-A-eDP-1
[103.290079] Dynamic subtest pipe-A-eDP-1: SKIP (0.002s)
[103.297137] Starting dynamic subtest: pipe-B-eDP-1
[103.355867] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[103.364558] Starting dynamic subtest: pipe-C-eDP-1
[103.422267] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[103.431348] Starting dynamic subtest: pipe-D-eDP-1
[103.490080] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[103.494768] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.009s)
[105.528950] [003/133] (957s left) sysfs_timeslice_duration (duration)
[105.600737] Starting subtest: duration
[105.603134] Starting dynamic subtest: vecs0
[118.838488] Dynamic subtest vecs0: SUCCESS (13.238s)
[118.849091] Starting dynamic subtest: ccs0
[131.973932] Dynamic subtest ccs0: SUCCESS (13.138s)
[131.983193] Starting dynamic subtest: vcs0
[145.845640] Dynamic subtest vcs0: SUCCESS (13.868s)
[145.858984] Starting dynamic subtest: bcs0
[158.985061] Dynamic subtest bcs0: SUCCESS (13.139s)
[158.995708] Starting dynamic subtest: rcs0
[172.120812] Dynamic subtest rcs0: SUCCESS (13.135s)
[172.131049] Starting dynamic subtest: vcs1
[185.844967] Dynamic subtest vcs1: SUCCESS (13.720s)
[185.848060] Subtest duration: SUCCESS (80.247s)
[185.928307] [004/133] (877s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[186.024910] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[186.027372] Starting dynamic subtest: pipe-A-eDP-1
[202.503476] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.575s)
[202.512310] Starting dynamic subtest: pipe-B-eDP-1
[219.449948] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.421s)
[219.459541] Starting dynamic subtest: pipe-C-eDP-1
[236.428253] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.429s)
[236.437498] Starting dynamic subtest: pipe-D-eDP-1
[253.425284] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.341s)
[253.427455] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.768s)
[254.642867] [005/133] (808s left) drm_buddy (drm_buddy)
[254.689095] Starting subtest: drm_buddy
[254.693483] Subtest drm_buddy: SKIP (0.001s)
[254.744489] [006/133] (808s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[254.853009] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[255.695626] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.848s)
[255.865578] [007/133] (807s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[255.959815] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[255.961863] Starting dynamic subtest: pipe-A-eDP-1
[256.716738] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.752s)
[256.726860] Starting dynamic subtest: pipe-B-eDP-1
[258.551990] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.841s)
[258.561941] Starting dynamic subtest: pipe-C-eDP-1
[260.375379] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.828s)
[260.385413] Starting dynamic subtest: pipe-D-eDP-1
[262.214245] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.834s)
[262.216286] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.258s)
[263.356928] [008/133] (800s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[263.444211] Starting subtest: bad-pixel-format-yf-tiled-ccs
[263.446465] Starting dynamic subtest: pipe-A-eDP-1
[263.469140] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[263.480368] Starting dynamic subtest: pipe-B-eDP-1
[263.501596] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[263.509853] Starting dynamic subtest: pipe-C-eDP-1
[263.531035] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[263.538963] Starting dynamic subtest: pipe-D-eDP-1
[263.559871] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[263.563762] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.003s)
[265.709835] [009/133] (797s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[265.769568] Starting subtest: multi-wait-available-unsubmitted-submitted
[265.771903] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.001s)
[265.870792] [010/133] (797s left) perf_pmu (event-wait)
[265.919433] Starting subtest: event-wait
[265.921426] Starting dynamic subtest: rcs0
[265.925999] Dynamic subtest rcs0: SKIP (0.000s)
[265.930411] Subtest event-wait: SKIP (0.000s)
[266.022282] [011/133] (797s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[266.113233] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[266.117461] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[266.220219] [012/133] (797s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[266.311019] Starting subtest: psr-rgb565-draw-mmap-gtt
[266.314968] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[266.413887] [013/133] (797s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[266.517128] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[266.519380] Starting dynamic subtest: pipe-A-eDP-1
[272.377956] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.873s)
[272.387940] Starting dynamic subtest: pipe-B-eDP-1
[279.010792] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.635s)
[279.013180] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.510s)
[279.888826] [014/133] (783s left) kms_psr (fbc-psr2-dpms)
[279.987704] Starting subtest: fbc-psr2-dpms
[279.990005] Starting dynamic subtest: eDP-1
[279.994066] Dynamic subtest eDP-1: SKIP (0.000s)
[279.998140] Subtest fbc-psr2-dpms: SKIP (0.001s)
[280.060027] [015/133] (783s left) gem_exec_balancer (parallel-ordering)
[280.315858] Starting subtest: parallel-ordering
[281.336548] Subtest parallel-ordering: SUCCESS (1.021s)
[281.454081] [016/133] (782s left) kms_joiner (basic-big-joiner)
[281.550451] Starting subtest: basic-big-joiner
[281.554549] Subtest basic-big-joiner: SKIP (0.000s)
[281.618073] [017/133] (782s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[281.733214] Starting subtest: flip-vs-cursor-crc-atomic
[282.428630] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.696s)
[283.235498] [018/133] (780s left) gem_wait (wait)
[283.304215] Starting subtest: wait
[283.306491] Starting dynamic subtest: all-engines
[284.607101] Dynamic subtest all-engines: SUCCESS (1.302s)
[284.618857] Starting dynamic subtest: rcs0
[285.652874] Dynamic subtest rcs0: SUCCESS (1.044s)
[285.663831] Starting dynamic subtest: bcs0
[286.698548] Dynamic subtest bcs0: SUCCESS (1.043s)
[286.708525] Starting dynamic subtest: vcs0
[287.741999] Dynamic subtest vcs0: SUCCESS (1.042s)
[287.753302] Starting dynamic subtest: vcs1
[288.787565] Dynamic subtest vcs1: SUCCESS (1.043s)
[288.798740] Starting dynamic subtest: vecs0
[289.831280] Dynamic subtest vecs0: SUCCESS (1.042s)
[289.842569] Starting dynamic subtest: ccs0
[290.878398] Dynamic subtest ccs0: SUCCESS (1.045s)
[290.881044] Subtest wait: SUCCESS (7.571s)
[291.076724] [019/133] (772s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[291.174221] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[291.178366] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[291.266661] [020/133] (772s left) kms_flip (2x-flip-vs-expired-vblank)
[291.365776] Starting subtest: 2x-flip-vs-expired-vblank
[291.373894] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s)
[291.450578] [021/133] (772s left) kms_lease (page-flip-implicit-plane)
[291.538865] Starting subtest: page-flip-implicit-plane
[291.541242] Starting dynamic subtest: pipe-A-eDP-1
[292.023737] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.484s)
[292.035352] Starting dynamic subtest: pipe-B-eDP-1
[293.182425] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.158s)
[293.191636] Starting dynamic subtest: pipe-C-eDP-1
[294.288086] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.105s)
[294.298606] Starting dynamic subtest: pipe-D-eDP-1
[295.426753] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.135s)
[295.429418] Subtest page-flip-implicit-plane: SUCCESS (3.886s)
[296.260611] [022/133] (767s left) gem_exec_params (rsvd2-dirt)
[296.337666] Starting subtest: rsvd2-dirt
[296.342416] Subtest rsvd2-dirt: SKIP (0.000s)
[296.451517] [023/133] (767s left) api_intel_bb (blit-reloc-purge-cache)
[296.506991] Starting subtest: blit-reloc-purge-cache
[296.511374] Subtest blit-reloc-purge-cache: SKIP (0.001s)
[296.621093] [024/133] (767s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[296.731052] Starting subtest: fbc-shrfb-scaledprimary
[299.738883] Subtest fbc-shrfb-scaledprimary: SUCCESS (3.009s)
[300.867879] [025/133] (763s left) i915_pm_rpm (gem-idle)
[302.702248] Starting subtest: gem-idle
[308.918995] Subtest gem-idle: SUCCESS (6.219s)
[309.269764] [026/133] (754s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[310.548233] Starting subtest: x-tiled-8bpp-rotate-0
[311.750321] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.202s)
[311.872563] [027/133] (752s left) gem_exec_schedule (preempt-contexts)
[311.950436] Starting subtest: preempt-contexts
[311.952311] Starting dynamic subtest: rcs0
[311.999381] Dynamic subtest rcs0: SUCCESS (0.053s)
[312.018197] Starting dynamic subtest: bcs0
[312.056873] Dynamic subtest bcs0: SUCCESS (0.057s)
[312.068109] Starting dynamic subtest: vcs0
[312.110294] Dynamic subtest vcs0: SUCCESS (0.052s)
[312.119390] Starting dynamic subtest: vcs1
[312.159960] Dynamic subtest vcs1: SUCCESS (0.049s)
[312.172067] Starting dynamic subtest: vecs0
[312.214293] Dynamic subtest vecs0: SUCCESS (0.053s)
[312.224037] Starting dynamic subtest: ccs0
[312.262108] Dynamic subtest ccs0: SUCCESS (0.047s)
[312.264009] Subtest preempt-contexts: SUCCESS (0.317s)
[313.627918] [028/133] (750s left) gem_exec_flush (basic-uc-rw-default)
[313.683637] Starting subtest: basic-uc-rw-default
[319.135821] Subtest basic-uc-rw-default: SUCCESS (5.454s)
[319.347597] [029/133] (744s left) kms_color_pipeline (plane-lut1d)
[319.440516] Starting subtest: plane-lut1d
[319.600347] Starting dynamic subtest: pipe-A-plane-0
[319.960453] Dynamic subtest pipe-A-plane-0: FAIL (0.195s)
[319.970562] Starting dynamic subtest: pipe-A-plane-1
[320.188286] Dynamic subtest pipe-A-plane-1: FAIL (0.180s)
[320.196920] Starting dynamic subtest: pipe-A-plane-2
[320.404084] Dynamic subtest pipe-A-plane-2: FAIL (0.175s)
[321.379275] Starting dynamic subtest: pipe-B-plane-0
[321.816009] Dynamic subtest pipe-B-plane-0: FAIL (0.174s)
[321.824619] Starting dynamic subtest: pipe-B-plane-1
[322.118308] Dynamic subtest pipe-B-plane-1: FAIL (0.187s)
[322.128657] Starting dynamic subtest: pipe-B-plane-2
[322.427872] Dynamic subtest pipe-B-plane-2: FAIL (0.188s)
[323.089613] Starting dynamic subtest: pipe-C-plane-0
[323.583908] Dynamic subtest pipe-C-plane-0: FAIL (0.151s)
[323.593700] Starting dynamic subtest: pipe-C-plane-1
[323.983540] Dynamic subtest pipe-C-plane-1: FAIL (0.144s)
[323.994063] Starting dynamic subtest: pipe-C-plane-2
[324.337564] Dynamic subtest pipe-C-plane-2: FAIL (0.637s)
[325.222866] Starting dynamic subtest: pipe-D-plane-0
[325.818019] Dynamic subtest pipe-D-plane-0: FAIL (0.166s)
[325.828514] Starting dynamic subtest: pipe-D-plane-1
[326.265604] Dynamic subtest pipe-D-plane-1: FAIL (0.610s)
[326.275483] Starting dynamic subtest: pipe-D-plane-2
[326.695860] Dynamic subtest pipe-D-plane-2: FAIL (0.570s)
[326.868266] Subtest plane-lut1d: FAIL (7.428s)
[327.680162] [030/133] (736s left) gem_exec_schedule (deep)
[327.860423] Starting subtest: deep
[327.862671] Starting dynamic subtest: rcs0
[333.022314] Dynamic subtest rcs0: SKIP (5.162s)
[333.035906] Starting dynamic subtest: vcs0
[343.378417] Dynamic subtest vcs0: SUCCESS (10.359s)
[343.389297] Starting dynamic subtest: vcs1
[354.248971] Dynamic subtest vcs1: SUCCESS (10.869s)
[354.258820] Starting dynamic subtest: vecs0
[366.020498] Dynamic subtest vecs0: SUCCESS (11.764s)
[366.033331] Starting dynamic subtest: ccs0
[377.949375] Dynamic subtest ccs0: SUCCESS (11.926s)
[377.952779] Subtest deep: SUCCESS (50.090s)
[380.062659] [FACT gem_exec_schedule (deep)] new: kernel.kmod_is_loaded.vgem: true
[380.066017] [031/133] (684s left) kms_cursor_crc (cursor-suspend)
[380.202326] Starting subtest: cursor-suspend
[380.204529] Starting dynamic subtest: pipe-A-eDP-1
[412.780029] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.185s)
[412.791930] Starting dynamic subtest: pipe-D-eDP-1
[446.794051] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.775s)
[446.796075] Subtest cursor-suspend: SUCCESS (6.963s)
[447.664137] [032/133] (616s left) perf (gen8-unprivileged-single-ctx-counters)
[447.820235] Starting subtest: gen8-unprivileged-single-ctx-counters
[447.824733] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[447.863431] [033/133] (616s left) api_intel_bb (intel-bb-blit-y)
[447.913228] Starting subtest: intel-bb-blit-y
[451.725725] Subtest intel-bb-blit-y: SUCCESS (3.831s)
[451.846638] [034/133] (612s left) prime_mmap (test_refcounting)
[451.898050] Starting subtest: test_refcounting
[451.899890] Starting dynamic subtest: test_refcounting-smem
[451.929396] Dynamic subtest test_refcounting-smem: SUCCESS (0.032s)
[451.932518] Subtest test_refcounting: SUCCESS (0.033s)
[452.041447] [035/133] (612s left) kms_atomic_transition (modeset-transition)
[452.133631] Starting subtest: modeset-transition
[452.135908] Starting dynamic subtest: 1x-outputs
[463.172257] Dynamic subtest 1x-outputs: SUCCESS (11.063s)
[463.174710] Subtest modeset-transition: SUCCESS (11.065s)
[464.040965] [036/133] (600s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[464.138400] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[464.140733] Starting dynamic subtest: pipe-A
[464.158290] Dynamic subtest pipe-A: SKIP (0.017s)
[464.168490] Starting dynamic subtest: pipe-B
[464.191168] Dynamic subtest pipe-B: SKIP (0.032s)
[464.200814] Starting dynamic subtest: pipe-C
[464.223183] Dynamic subtest pipe-C: SKIP (0.030s)
[464.232598] Starting dynamic subtest: pipe-D
[464.253387] Dynamic subtest pipe-D: SKIP (0.030s)
[464.257603] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.112s)
[464.365230] [037/133] (600s left) kms_frontbuffer_tracking (fbc-suspend)
[464.462070] Starting subtest: fbc-suspend
[502.727481] Subtest fbc-suspend: SUCCESS (7.449s)
[503.920866] [038/133] (560s left) gem_pxp (display-protected-crc)
[504.061716] Starting subtest: display-protected-crc
[505.696505] Subtest display-protected-crc: SUCCESS (1.642s)
[505.817483] [039/133] (558s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[505.924822] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[505.929895] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[506.014198] [040/133] (558s left) gem_userptr_blits (dmabuf-unsync)
[506.090128] Subtest dmabuf-unsync: SKIP (0.000s)
[506.217881] [041/133] (558s left) gem_eio (context-create)
[506.438625] Starting subtest: context-create
[506.781135] Subtest context-create: SUCCESS (0.342s)
[506.919508] [042/133] (557s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[507.019854] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[513.787988] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.780s)
[513.937022] [043/133] (550s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[514.039007] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[514.044581] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[514.137706] [044/133] (550s left) kms_selftest (drm_dp_mst_helper)
[514.182042] Starting subtest: drm_dp_mst_helper
[514.241170] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[514.345206] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.105s)
[514.355472] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[514.450708] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.105s)
[514.461044] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[514.568048] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.115s)
[514.570807] Subtest drm_dp_mst_helper: SUCCESS (0.387s)
[514.734775] [045/133] (550s left) gem_spin_batch (resubmit-new)
[514.790785] Starting subtest: resubmit-new
[514.793064] Starting dynamic subtest: rcs0
[514.804012] Dynamic subtest rcs0: SUCCESS (0.013s)
[514.815961] Starting dynamic subtest: bcs0
[514.817890] Dynamic subtest bcs0: SUCCESS (0.006s)
[514.827770] Starting dynamic subtest: vcs0
[514.829659] Dynamic subtest vcs0: SUCCESS (0.006s)
[514.839777] Starting dynamic subtest: vcs1
[514.842323] Dynamic subtest vcs1: SUCCESS (0.006s)
[514.852582] Starting dynamic subtest: vecs0
[514.854900] Dynamic subtest vecs0: SUCCESS (0.005s)
[514.864589] Starting dynamic subtest: ccs0
[514.866931] Dynamic subtest ccs0: SUCCESS (0.006s)
[514.869310] Subtest resubmit-new: SUCCESS (0.050s)
[516.115413] [046/133] (548s left) testdisplay
[516.443928] [047/133] (548s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[516.540651] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[516.543530] Starting dynamic subtest: pipe-A-eDP-1
[516.682388] Dynamic subtest pipe-A-eDP-1: SKIP (0.149s)
[516.693789] Starting dynamic subtest: pipe-B-eDP-1
[517.896278] Dynamic subtest pipe-B-eDP-1: SKIP (1.212s)
[517.901713] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.364s)
[519.083469] [048/133] (545s left) i915_selftest (live)
[520.843411] Starting subtest: live
[520.846159] Starting dynamic subtest: sanitycheck
[524.416310] Dynamic subtest sanitycheck: SUCCESS (3.575s)
[524.426652] Starting dynamic subtest: uncore
[527.935968] Dynamic subtest uncore: SUCCESS (3.521s)
[527.950792] Starting dynamic subtest: workarounds
[531.915921] Dynamic subtest workarounds: SUCCESS (3.980s)
[531.925083] Starting dynamic subtest: gt_engines
[535.850111] Dynamic subtest gt_engines: SUCCESS (3.931s)
[535.865857] Starting dynamic subtest: gt_timelines
[550.919164] Dynamic subtest gt_timelines: SUCCESS (15.068s)
[550.928083] Starting dynamic subtest: gt_contexts
[554.555244] Dynamic subtest gt_contexts: SUCCESS (3.635s)
[554.569047] Starting dynamic subtest: gt_lrc
[559.754157] Dynamic subtest gt_lrc: SUCCESS (5.197s)
[559.763168] Starting dynamic subtest: gt_mocs
[563.633444] Dynamic subtest gt_mocs: SUCCESS (3.878s)
[563.643050] Starting dynamic subtest: gt_pm
[569.184489] Dynamic subtest gt_pm: SUCCESS (5.551s)
[569.195053] Starting dynamic subtest: gt_heartbeat
[572.714455] Dynamic subtest gt_heartbeat: SUCCESS (3.530s)
[572.724852] Starting dynamic subtest: gt_tlb
[583.478286] Dynamic subtest gt_tlb: SUCCESS (10.767s)
[583.486398] Starting dynamic subtest: requests
[596.328189] Dynamic subtest requests: SUCCESS (12.851s)
[596.337601] Starting dynamic subtest: migrate
[602.941146] Dynamic subtest migrate: SUCCESS (6.608s)
[602.951812] Starting dynamic subtest: active
[606.534925] Dynamic subtest active: SUCCESS (3.591s)
[606.544678] Starting dynamic subtest: objects
[612.172843] Dynamic subtest objects: SUCCESS (5.636s)
[612.181122] Starting dynamic subtest: mman
[616.311743] Dynamic subtest mman: SUCCESS (4.135s)
[616.321050] Starting dynamic subtest: dmabuf
[620.037678] Dynamic subtest dmabuf: SUCCESS (3.724s)
[620.047166] Starting dynamic subtest: vma
[623.570287] Dynamic subtest vma: SUCCESS (3.530s)
[623.580374] Starting dynamic subtest: coherency
[627.884786] Dynamic subtest coherency: SUCCESS (4.313s)
[627.897735] Starting dynamic subtest: gtt
[638.916862] Dynamic subtest gtt: SUCCESS (11.030s)
[638.926558] Starting dynamic subtest: gem
[645.744922] Dynamic subtest gem: SUCCESS (6.826s)
[645.755407] Starting dynamic subtest: evict
[650.523430] Dynamic subtest evict: SUCCESS (4.777s)
[650.533431] Starting dynamic subtest: hugepages
[660.808893] Dynamic subtest hugepages: SUCCESS (10.286s)
[660.819519] Starting dynamic subtest: gem_contexts
[687.657872] Dynamic subtest gem_contexts: SUCCESS (26.853s)
[687.668255] Starting dynamic subtest: client
[691.246053] Dynamic subtest client: SUCCESS (3.588s)
[691.255721] Starting dynamic subtest: gem_migrate
[694.773546] Dynamic subtest gem_migrate: SUCCESS (3.526s)
[694.784799] Starting dynamic subtest: reset
[699.923840] Dynamic subtest reset: SUCCESS (5.151s)
[699.934573] Starting dynamic subtest: memory_region
[703.490029] Dynamic subtest memory_region: SUCCESS (3.566s)
[703.505352] Starting dynamic subtest: hangcheck
[718.050257] Dynamic subtest hangcheck: SUCCESS (14.563s)
[718.060640] Starting dynamic subtest: execlists
[723.271422] Dynamic subtest execlists: SUCCESS (5.221s)
[723.280962] Starting dynamic subtest: ring_submission
[728.463549] Dynamic subtest ring_submission: SUCCESS (5.189s)
[728.473654] Starting dynamic subtest: perf
[732.060238] Dynamic subtest perf: SUCCESS (3.593s)
[732.070892] Starting dynamic subtest: slpc
[745.416622] Dynamic subtest slpc: SUCCESS (13.349s)
[745.426512] Starting dynamic subtest: guc
[750.307435] Dynamic subtest guc: SUCCESS (4.888s)
[750.323741] Starting dynamic subtest: guc_multi_lrc
[753.910559] Dynamic subtest guc_multi_lrc: SUCCESS (3.600s)
[753.922117] Starting dynamic subtest: guc_hang
[758.272479] Dynamic subtest guc_hang: SUCCESS (4.360s)
[758.282805] Starting dynamic subtest: late_gt_pm
[763.462983] Dynamic subtest late_gt_pm: SUCCESS (5.187s)
[763.466297] Subtest live: SUCCESS (242.639s)
[763.496700] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[763.497437] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[763.501183] [049/133] (301s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[765.920782] Subtest writes-after-reads-uncached: SKIP (0.000s)
[766.004648] [FACT gem_partial_pwrite_pread (writes-after-reads-uncached)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[766.005898] [FACT gem_partial_pwrite_pread (writes-after-reads-uncached)] new: kernel.kmod_is_loaded.i915: true
[766.009994] [050/133] (299s left) kms_getfb (getfb-handle-not-fb)
[766.101086] Starting subtest: getfb-handle-not-fb
[766.103967] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[766.184858] [051/133] (299s left) kms_psr (fbc-psr-sprite-plane-onoff)
[766.277063] Starting subtest: fbc-psr-sprite-plane-onoff
[766.279530] Starting dynamic subtest: eDP-1
[766.284159] Dynamic subtest eDP-1: SKIP (0.000s)
[766.289028] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[766.352898] [052/133] (298s left) i915_hangman (gt-error-state-capture)
[766.416080] Starting subtest: gt-error-state-capture
[766.418496] Starting dynamic subtest: rcs0
[777.030312] Dynamic subtest rcs0: SUCCESS (10.614s)
[777.043521] Starting dynamic subtest: bcs0
[789.003262] Dynamic subtest bcs0: SUCCESS (11.969s)
[789.018825] Starting dynamic subtest: vcs0
[800.968541] Dynamic subtest vcs0: SUCCESS (11.963s)
[800.981432] Starting dynamic subtest: vcs1
[812.936603] Dynamic subtest vcs1: SUCCESS (11.965s)
[812.948984] Starting dynamic subtest: vecs0
[823.945902] Dynamic subtest vecs0: SUCCESS (11.007s)
[823.958172] Starting dynamic subtest: ccs0
[833.992163] Dynamic subtest ccs0: SUCCESS (10.044s)
[833.995224] Subtest gt-error-state-capture: SUCCESS (67.577s)
[834.076390] [053/133] (231s left) perf (invalid-open-flags)
[834.217695] Starting subtest: invalid-open-flags
[834.220181] Subtest invalid-open-flags: SUCCESS (0.000s)
[834.265062] [054/133] (231s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[834.370971] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[834.375965] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[834.461548] [055/133] (230s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[834.554963] Starting subtest: async-flip-with-page-flip-events-linear
[834.941878] Starting dynamic subtest: pipe-A-eDP-1
[837.744023] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.802s)
[838.109392] Starting dynamic subtest: pipe-B-eDP-1
[840.880273] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.778s)
[841.248504] Starting dynamic subtest: pipe-C-eDP-1
[844.005168] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.764s)
[844.380899] Starting dynamic subtest: pipe-D-eDP-1
[847.135143] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.764s)
[847.137501] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.584s)
[848.317738] [056/133] (217s left) sw_sync (alloc_fence)
[848.363944] Starting subtest: alloc_fence
[848.366955] Subtest alloc_fence: SUCCESS (0.000s)
[848.422889] [057/133] (217s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[848.518451] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[848.520952] Starting dynamic subtest: pipe-A-default-mode
[849.684174] Dynamic subtest pipe-A-default-mode: SKIP (1.161s)
[849.689925] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.163s)
[849.831055] [058/133] (215s left) gem_ctx_persistence (heartbeat-close)
[849.899887] Starting subtest: heartbeat-close
[849.905087] Subtest heartbeat-close: SKIP (0.000s)
[850.027502] [059/133] (215s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[850.127137] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[850.132053] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[850.222554] [060/133] (215s left) kms_flip (2x-flip-vs-modeset)
[850.317979] Starting subtest: 2x-flip-vs-modeset
[850.330067] Subtest 2x-flip-vs-modeset: SKIP (0.011s)
[850.413018] [061/133] (215s left) sw_sync (alloc_timeline)
[850.450286] Starting subtest: alloc_timeline
[850.452840] Subtest alloc_timeline: SUCCESS (0.000s)
[850.506786] [062/133] (215s left) gem_exec_fence (syncobj-timeline-chain-engines)
[851.039853] Starting subtest: syncobj-timeline-chain-engines
[851.174507] Subtest syncobj-timeline-chain-engines: SUCCESS (0.135s)
[852.178850] [063/133] (213s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping)
[852.278424] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping
[852.283161] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[852.359144] [064/133] (213s left) perf_pmu (all-busy-check-all)
[852.406226] Starting subtest: all-busy-check-all
[854.059004] Subtest all-busy-check-all: SUCCESS (1.656s)
[854.163967] [065/133] (211s left) kms_chamelium_frames (hdmi-crc-single)
[854.546734] Subtest hdmi-crc-single: SKIP (0.000s)
[855.365622] [066/133] (210s left) kms_draw_crc (draw-method-mmap-wc)
[855.458929] Starting subtest: draw-method-mmap-wc
[855.461449] Starting dynamic subtest: xrgb8888-untiled
[855.712727] Dynamic subtest xrgb8888-untiled: SUCCESS (0.256s)
[855.723161] Starting dynamic subtest: rgb565-untiled
[855.923827] Dynamic subtest rgb565-untiled: SUCCESS (0.209s)
[855.934404] Starting dynamic subtest: xrgb2101010-untiled
[856.146553] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.220s)
[856.162645] Starting dynamic subtest: xbgr16161616f-untiled
[856.389836] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.242s)
[856.401656] Starting dynamic subtest: xrgb8888-xtiled
[856.512397] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.120s)
[856.524440] Starting dynamic subtest: rgb565-xtiled
[856.634594] Dynamic subtest rgb565-xtiled: SUCCESS (0.120s)
[856.647402] Starting dynamic subtest: xrgb2101010-xtiled
[856.757008] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.119s)
[856.767195] Starting dynamic subtest: xbgr16161616f-xtiled
[856.890426] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[856.900662] Starting dynamic subtest: xrgb8888-4tiled
[857.045757] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.155s)
[857.056126] Starting dynamic subtest: rgb565-4tiled
[857.179171] Dynamic subtest rgb565-4tiled: SUCCESS (0.132s)
[857.189161] Starting dynamic subtest: xrgb2101010-4tiled
[857.323633] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.142s)
[857.333765] Starting dynamic subtest: xbgr16161616f-4tiled
[857.489780] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.166s)
[857.492002] Subtest draw-method-mmap-wc: SUCCESS (2.034s)
[857.583942] [067/133] (208s left) kms_content_protection (atomic)
[857.726942] Starting subtest: atomic
[857.731845] Subtest atomic: SKIP (0.000s)
[857.851178] [068/133] (208s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[857.940861] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[857.942983] Starting dynamic subtest: pipe-A-eDP-1
[857.964299] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[857.976182] Starting dynamic subtest: pipe-B-eDP-1
[857.997744] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[858.007736] Starting dynamic subtest: pipe-C-eDP-1
[858.029452] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[858.038948] Starting dynamic subtest: pipe-D-eDP-1
[858.060762] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[858.064991] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.004s)
[860.252827] [069/133] (205s left) kms_psr (pr-primary-render)
[860.342961] Starting subtest: pr-primary-render
[860.348001] Subtest pr-primary-render: SKIP (0.000s)
[860.417387] [070/133] (205s left) gem_exec_flush (basic-uc-ro-default)
[860.476035] Starting subtest: basic-uc-ro-default
[865.937181] Subtest basic-uc-ro-default: SUCCESS (5.461s)
[866.137448] [071/133] (199s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[866.233313] Starting subtest: fb-8bpc-vs-panel-8bpc
[866.236001] Starting dynamic subtest: pipe-A-eDP-1
[867.641055] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.408s)
[867.644189] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.410s)
[868.518852] [072/133] (197s left) gem_madvise (dontneed-after-mmap)
[868.558112] Starting subtest: dontneed-after-mmap
[868.573312] Subtest dontneed-after-mmap: SUCCESS (0.017s)
[868.671405] [073/133] (197s left) kms_plane_alpha_blend (constant-alpha-mid)
[868.753858] Starting subtest: constant-alpha-mid
[869.038531] Starting dynamic subtest: pipe-A-eDP-1
[869.867554] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.826s)
[869.877973] Starting dynamic subtest: pipe-D-eDP-1
[871.310115] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.441s)
[871.313166] Subtest constant-alpha-mid: SUCCESS (2.558s)
[872.104069] [074/133] (194s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[872.167974] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[872.231841] [075/133] (194s left) kms_flip (2x-flip-vs-wf_vblank)
[872.329527] Starting subtest: 2x-flip-vs-wf_vblank
[872.340828] Subtest 2x-flip-vs-wf_vblank: SKIP (0.011s)
[872.421760] [076/133] (193s left) kms_cursor_crc (cursor-sliding-128x42)
[872.639645] Starting subtest: cursor-sliding-128x42
[872.683608] Subtest cursor-sliding-128x42: SKIP (0.042s)
[872.886591] [077/133] (193s left) kms_psr (psr2-suspend)
[872.993993] Starting subtest: psr2-suspend
[872.996610] Starting dynamic subtest: eDP-1
[890.657950] Dynamic subtest eDP-1: SUCCESS (2.825s)
[890.660463] Subtest psr2-suspend: SUCCESS (2.826s)
[891.446991] [078/133] (174s left) gem_mmap_offset (open-flood)
[891.508034] Starting subtest: open-flood
[913.051132] Subtest open-flood: SUCCESS (21.491s)
[913.164532] [079/133] (153s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[913.269546] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[913.274181] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[913.370594] [080/133] (153s left) gem_exec_nop (basic-sequential)
[913.441793] Starting subtest: basic-sequential
[928.328432] Subtest basic-sequential: SUCCESS (14.887s)
[928.552355] [081/133] (137s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[928.657352] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[928.662461] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[928.742458] [082/133] (137s left) gem_mmap_gtt (basic-small-bo-tiledy)
[928.797620] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[928.856794] [083/133] (137s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[928.950437] Starting subtest: y-tiled-64bpp-rotate-90
[928.955568] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[929.043567] [084/133] (137s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[929.133530] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[937.774563] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.647s)
[937.902451] [085/133] (128s left) gem_exec_alignment (single)
[937.999378] Starting subtest: single
[938.005286] Subtest single: SUCCESS (0.005s)
[938.114499] [086/133] (128s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[938.218231] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[938.223407] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[938.301103] [087/133] (128s left) syncobj_timeline (etime-single-wait-submitted)
[938.359639] Starting subtest: etime-single-wait-submitted
[938.461030] Subtest etime-single-wait-submitted: SUCCESS (0.103s)
[938.555991] [088/133] (128s left) i915_drm_fdinfo (virtual-busy-all)
[938.606873] Starting subtest: virtual-busy-all
[938.612375] Subtest virtual-busy-all: SKIP (0.000s)
[938.684830] [089/133] (128s left) gem_ctx_exec (basic)
[938.733214] Starting subtest: basic
[938.740118] Subtest basic: SUCCESS (0.008s)
[938.849867] [090/133] (128s left) gem_busy (parallel)
[939.029666] Starting subtest: parallel
[939.032403] Starting dynamic subtest: rcs0
[940.898073] Dynamic subtest rcs0: SUCCESS (1.871s)
[940.911530] Starting dynamic subtest: bcs0
[942.881615] Dynamic subtest bcs0: SUCCESS (1.981s)
[942.894885] Starting dynamic subtest: vcs0
[944.865996] Dynamic subtest vcs0: SUCCESS (1.982s)
[944.879634] Starting dynamic subtest: vcs1
[946.913305] Dynamic subtest vcs1: SUCCESS (2.045s)
[946.926778] Starting dynamic subtest: vecs0
[948.897561] Dynamic subtest vecs0: SUCCESS (1.981s)
[948.911285] Starting dynamic subtest: ccs0
[950.817614] Dynamic subtest ccs0: SUCCESS (1.917s)
[950.820626] Subtest parallel: SUCCESS (11.791s)
[951.081801] [091/133] (115s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[951.127937] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[951.130375] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[951.233789] [092/133] (115s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[951.323269] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[951.328029] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[951.415676] [093/133] (115s left) kms_vblank (ts-continuation-dpms-rpm)
[951.505392] Starting subtest: ts-continuation-dpms-rpm
[951.508211] Starting dynamic subtest: pipe-A-eDP-1
[954.006659] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s)
[954.026253] Starting dynamic subtest: pipe-D-eDP-1
[956.249787] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.239s)
[956.252464] Subtest ts-continuation-dpms-rpm: SUCCESS (4.740s)
[957.092939] [094/133] (110s left) sw_sync (timeline_closed_signaled)
[957.130536] Starting subtest: timeline_closed_signaled
[957.133182] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[957.190365] [095/133] (109s left) gem_lmem_swapping (verify)
[957.242366] Subtest verify: SKIP (0.000s)
[957.293846] [096/133] (109s left) syncobj_wait (invalid-signal-zero-handles)
[957.343759] Starting subtest: invalid-signal-zero-handles
[957.346235] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[957.457557] [097/133] (109s left) kms_pm_rpm (i2c)
[959.079793] Starting subtest: i2c
[964.655606] Subtest i2c: SUCCESS (5.577s)
[964.755968] [098/133] (102s left) vgem_basic (setversion)
[964.797645] Starting subtest: setversion
[964.802622] Subtest setversion: SUCCESS (0.000s)
[964.857717] [099/133] (102s left) gem_exec_reloc (basic-write-gtt)
[964.919226] Subtest basic-write-gtt: SKIP (0.000s)
[964.979756] [100/133] (102s left) kms_scaling_modes (scaling-mode-center)
[965.079364] Starting subtest: scaling-mode-center
[965.082130] Starting dynamic subtest: pipe-A-eDP-1
[965.181772] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.103s)
[965.193130] Starting dynamic subtest: pipe-B-eDP-1
[966.359537] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.178s)
[966.375448] Starting dynamic subtest: pipe-C-eDP-1
[967.486554] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.123s)
[967.497521] Starting dynamic subtest: pipe-D-eDP-1
[968.619411] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[968.622114] Subtest scaling-mode-center: SUCCESS (3.543s)
[969.770123] [101/133] ( 97s left) perf_pmu (frequency)
[969.827462] Starting subtest: frequency
[969.830613] Starting dynamic subtest: gt0
[971.946455] Dynamic subtest gt0: SUCCESS (2.111s)
[971.957571] Starting dynamic subtest: idle-gt0
[972.451026] Dynamic subtest idle-gt0: SUCCESS (0.504s)
[972.464248] Starting dynamic subtest: gt1
[976.869141] Dynamic subtest gt1: SUCCESS (4.411s)
[976.880752] Starting dynamic subtest: idle-gt1
[977.373187] Dynamic subtest idle-gt1: SUCCESS (0.504s)
[977.376083] Subtest frequency: SUCCESS (7.548s)
[977.472944] [102/133] ( 90s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[977.568225] Starting subtest: 4-tiled-32bpp-rotate-180
[978.936272] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.368s)
[979.069931] [103/133] ( 88s left) kms_hdr (bpc-switch-dpms)
[979.158297] Starting subtest: bpc-switch-dpms
[980.245275] Starting dynamic subtest: pipe-A-eDP-1
[984.657225] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.410s)
[984.659910] Subtest bpc-switch-dpms: SUCCESS (5.498s)
[984.760154] [104/133] ( 82s left) syncobj_timeline (wait-any-interrupted)
[984.811255] Starting subtest: wait-any-interrupted
[984.911440] Subtest wait-any-interrupted: SUCCESS (0.101s)
[984.991898] [105/133] ( 82s left) gem_exec_parallel (contexts)
[985.059635] Starting subtest: contexts
[985.062661] Starting dynamic subtest: rcs0
[988.513304] Dynamic subtest rcs0: SUCCESS (3.450s)
[988.526647] Starting dynamic subtest: bcs0
[992.892903] Dynamic subtest bcs0: SUCCESS (4.378s)
[992.906846] Starting dynamic subtest: vcs0
[997.423155] Dynamic subtest vcs0: SUCCESS (4.531s)
[997.435074] Starting dynamic subtest: vcs1
[1003.247029] Dynamic subtest vcs1: SUCCESS (5.824s)
[1003.258473] Starting dynamic subtest: vecs0
[1009.224496] Dynamic subtest vecs0: SUCCESS (5.975s)
[1009.236079] Starting dynamic subtest: ccs0
[1013.852403] Dynamic subtest ccs0: SUCCESS (4.625s)
[1013.855382] Subtest contexts: SUCCESS (28.796s)
[1014.067512] [106/133] ( 53s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[1014.183054] Starting subtest: verify-pxp-key-change-after-suspend-resume
[1021.395969] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.215s)
[1021.505753] [107/133] ( 46s left) gem_create (create-ext-placement-each)
[1021.680266] Starting subtest: create-ext-placement-each
[1021.683009] Subtest create-ext-placement-each: SUCCESS (0.000s)
[1021.787398] [108/133] ( 45s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[1021.885632] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[1021.888634] Starting dynamic subtest: pipe-A-eDP-1
[1021.914256] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[1021.927462] Starting dynamic subtest: pipe-B-eDP-1
[1021.951198] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[1021.961603] Starting dynamic subtest: pipe-C-eDP-1
[1021.985327] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[1021.995437] Starting dynamic subtest: pipe-D-eDP-1
[1022.017654] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[1022.020266] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.008s)
[1024.126256] [109/133] ( 43s left) gem_close_race (basic-process)
[1024.202610] Starting subtest: basic-process
[1024.254378] Subtest basic-process: SUCCESS (0.052s)
[1024.454995] [110/133] ( 43s left) gem_exec_schedule (u-independent)
[1024.521654] Starting subtest: u-independent
[1024.524451] Starting dynamic subtest: rcs0
[1025.890083] Dynamic subtest rcs0: SUCCESS (1.382s)
[1025.904267] Starting dynamic subtest: vcs0
[1027.882573] Dynamic subtest vcs0: SUCCESS (1.991s)
[1027.897962] Starting dynamic subtest: vcs1
[1029.857193] Dynamic subtest vcs1: SUCCESS (1.971s)
[1029.868480] Starting dynamic subtest: vecs0
[1031.929848] Dynamic subtest vecs0: SUCCESS (2.072s)
[1031.943434] Starting dynamic subtest: ccs0
[1033.889317] Dynamic subtest ccs0: SUCCESS (1.955s)
[1033.892179] Subtest u-independent: SUCCESS (9.382s)
[1034.211184] [111/133] ( 33s left) gen9_exec_parse (batch-without-end)
[1034.275147] Subtest batch-without-end: SKIP (0.000s)
[1034.344784] [112/133] ( 33s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[1039.032551] Starting subtest: dpms-mode-unset-lpsp
[1043.099953] Subtest dpms-mode-unset-lpsp: SUCCESS (4.066s)
[1043.479704] [113/133] ( 24s left) kms_chamelium_hpd (vga-hpd)
[1043.885861] Subtest vga-hpd: SKIP (0.000s)
[1044.684658] [114/133] ( 23s left) gem_lmem_swapping (heavy-multi)
[1044.730210] Subtest heavy-multi: SKIP (0.000s)
[1044.779600] [115/133] ( 23s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[1044.880568] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[1044.885481] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[1044.949500] [116/133] ( 23s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[1045.055742] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[1045.060117] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[1045.155905] [117/133] ( 22s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[1045.249911] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[1049.710216] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.464s)
[1050.919573] [118/133] ( 17s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[1051.018630] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[1058.284172] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.268s)
[1058.423963] [119/133] (  9s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[1058.535883] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[1058.540860] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[1058.633341] [120/133] (  9s left) gem_exec_balancer (noheartbeat)
[1058.901838] Starting subtest: noheartbeat
[1058.907477] Subtest noheartbeat: SKIP (0.000s)
[1059.015446] [121/133] (  9s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[1059.075787] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[1059.182170] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.106s)
[1059.260441] [122/133] (  9s left) kms_big_fb (linear-addfb)
[1059.361176] Starting subtest: linear-addfb
[1059.363437] Subtest linear-addfb: SUCCESS (0.000s)
[1059.454668] [123/133] (  8s left) perf_pmu (busy)
[1059.496064] Starting subtest: busy
[1059.498809] Starting dynamic subtest: rcs0
[1060.072269] Dynamic subtest rcs0: SUCCESS (0.578s)
[1060.086527] Starting dynamic subtest: bcs0
[1060.633782] Dynamic subtest bcs0: SUCCESS (0.558s)
[1060.648774] Starting dynamic subtest: vcs0
[1061.858251] Dynamic subtest vcs0: SUCCESS (1.220s)
[1061.872627] Starting dynamic subtest: vcs1
[1063.906033] Dynamic subtest vcs1: SUCCESS (2.044s)
[1063.920071] Starting dynamic subtest: vecs0
[1065.889806] Dynamic subtest vecs0: SUCCESS (1.981s)
[1065.904264] Starting dynamic subtest: ccs0
[1066.460172] Dynamic subtest ccs0: SUCCESS (0.568s)
[1066.463112] Subtest busy: SUCCESS (6.966s)
[1066.563133] [124/133] (  1s left) kms_force_connector_basic (force-load-detect)
[1066.641601] Starting subtest: force-load-detect
[1066.646618] Subtest force-load-detect: SKIP (0.000s)
[1066.761291] [125/133] (  1s left) gem_ctx_param (invalid-get-ringsize)
[1066.822995] Starting subtest: invalid-get-ringsize
[1066.825614] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[1066.918126] [126/133] (  1s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[1067.016296] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[1074.599762] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.585s)
[1074.695077] Overall timeout time exceeded, stopping.
[1074.714604] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: sysfs_timeslice_duration
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: drm_buddy
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: kms_ccs
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: perf_pmu
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_psr
results: parsing output: 14/ for test: gem_exec_balancer
results: parsing output: 15/ for test: kms_joiner
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: gem_wait
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_flip
results: parsing output: 20/ for test: kms_lease
results: parsing output: 21/ for test: gem_exec_params
results: parsing output: 22/ for test: api_intel_bb
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: i915_pm_rpm
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: gem_exec_schedule
results: parsing output: 27/ for test: gem_exec_flush
results: parsing output: 28/ for test: kms_color_pipeline
results: parsing output: 29/ for test: gem_exec_schedule
results: parsing output: 30/ for test: kms_cursor_crc
results: parsing output: 31/ for test: perf
results: parsing output: 32/ for test: api_intel_bb
results: parsing output: 33/ for test: prime_mmap
results: parsing output: 34/ for test: kms_atomic_transition
results: parsing output: 35/ for test: kms_plane_scaling
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_pxp
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: gem_userptr_blits
results: parsing output: 40/ for test: gem_eio
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_selftest
results: parsing output: 44/ for test: gem_spin_batch
results: parsing output: 45/ for test: testdisplay
results: parsing output: 46/ for test: kms_psr2_sf
results: parsing output: 47/ for test: i915_selftest
results: parsing output: 48/ for test: gem_partial_pwrite_pread
results: parsing output: 49/ for test: kms_getfb
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: i915_hangman
results: parsing output: 52/ for test: perf
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_async_flips
results: parsing output: 55/ for test: sw_sync
results: parsing output: 56/ for test: kms_flip_scaled_crc
results: parsing output: 57/ for test: gem_ctx_persistence
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: sw_sync
results: parsing output: 61/ for test: gem_exec_fence
results: parsing output: 62/ for test: kms_plane
results: parsing output: 63/ for test: perf_pmu
results: parsing output: 64/ for test: kms_chamelium_frames
results: parsing output: 65/ for test: kms_draw_crc
results: parsing output: 66/ for test: kms_content_protection
results: parsing output: 67/ for test: kms_ccs
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: gem_exec_flush
results: parsing output: 70/ for test: kms_dither
results: parsing output: 71/ for test: gem_madvise
results: parsing output: 72/ for test: kms_plane_alpha_blend
results: parsing output: 73/ for test: kms_pipe_b_c_ivb
results: parsing output: 74/ for test: kms_flip
results: parsing output: 75/ for test: kms_cursor_crc
results: parsing output: 76/ for test: kms_psr
results: parsing output: 77/ for test: gem_mmap_offset
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_nop
results: parsing output: 80/ for test: kms_flip_scaled_crc
results: parsing output: 81/ for test: gem_mmap_gtt
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_exec_alignment
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: i915_drm_fdinfo
results: parsing output: 88/ for test: gem_ctx_exec
results: parsing output: 89/ for test: gem_busy
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_flip_scaled_crc
results: parsing output: 92/ for test: kms_vblank
results: parsing output: 93/ for test: sw_sync
results: parsing output: 94/ for test: gem_lmem_swapping
results: parsing output: 95/ for test: syncobj_wait
results: parsing output: 96/ for test: kms_pm_rpm
results: parsing output: 97/ for test: vgem_basic
results: parsing output: 98/ for test: gem_exec_reloc
results: parsing output: 99/ for test: kms_scaling_modes
results: parsing output: 100/ for test: perf_pmu
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: kms_hdr
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: gem_exec_parallel
results: parsing output: 105/ for test: gem_pxp
results: parsing output: 106/ for test: gem_create
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: gem_close_race
results: parsing output: 109/ for test: gem_exec_schedule
results: parsing output: 110/ for test: gen9_exec_parse
results: parsing output: 111/ for test: kms_pm_rpm
results: parsing output: 112/ for test: kms_chamelium_hpd
results: parsing output: 113/ for test: gem_lmem_swapping
results: parsing output: 114/ for test: kms_psr2_sf
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_exec_balancer
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: perf_pmu
results: parsing output: 123/ for test: kms_force_connector_basic
results: parsing output: 124/ for test: gem_ctx_param
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_cursor_legacy
results: no output, setting notrun
results: parsing output: 127/ for test: kms_flip
results: no output, setting notrun
results: parsing output: 128/ for test: kms_plane_scaling
results: no output, setting notrun
results: parsing output: 129/ for test: gem_exec_whisper
results: no output, setting notrun
results: parsing output: 130/ for test: kms_plane_lowres
results: no output, setting notrun
results: parsing output: 131/ for test: gem_render_copy
results: no output, setting notrun
results: parsing output: 132/ for test: prime_vgem
results: no output, setting notrun
Done.