[57.956325] Initializing watchdogs
[57.956458]   /dev/watchdog0
[57.973129] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[57.979629] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.982245] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.985592] [001/132] (960s left) kms_rotation_crc (bad-tiling)
[58.084720] Starting subtest: bad-tiling
[58.089059] Subtest bad-tiling: SKIP (0.001s)
[58.165894] [002/132] (960s left) kms_draw_crc (draw-method-render)
[58.237768] Starting subtest: draw-method-render
[58.239758] Starting dynamic subtest: xrgb8888-untiled
[58.492285] Dynamic subtest xrgb8888-untiled: SUCCESS (0.257s)
[58.500444] Starting dynamic subtest: rgb565-untiled
[58.702521] Dynamic subtest rgb565-untiled: SUCCESS (0.211s)
[58.711249] Starting dynamic subtest: xrgb2101010-untiled
[58.912194] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.211s)
[58.920686] Starting dynamic subtest: xbgr16161616f-untiled
[59.133366] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[59.141465] Starting dynamic subtest: xrgb8888-xtiled
[59.255041] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[59.263475] Starting dynamic subtest: rgb565-xtiled
[59.365333] Dynamic subtest rgb565-xtiled: SUCCESS (0.111s)
[59.373325] Starting dynamic subtest: xrgb2101010-xtiled
[59.487183] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[59.495666] Starting dynamic subtest: xbgr16161616f-xtiled
[59.620116] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[59.628132] Starting dynamic subtest: xrgb8888-4tiled
[59.741685] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[59.749210] Starting dynamic subtest: rgb565-4tiled
[59.852048] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[59.860333] Starting dynamic subtest: xrgb2101010-4tiled
[59.973927] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.122s)
[59.982404] Starting dynamic subtest: xbgr16161616f-4tiled
[60.106534] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[60.108704] Subtest draw-method-render: SUCCESS (1.879s)
[60.196987] [003/132] (958s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[60.264207] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[60.290308] Starting dynamic subtest: pipe-A-plane-0
[60.580415] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[60.587944] Starting dynamic subtest: pipe-A-plane-3
[60.810666] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[60.818733] Starting dynamic subtest: pipe-A-plane-5
[60.823385] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[61.892376] Starting dynamic subtest: pipe-B-plane-0
[62.160067] Dynamic subtest pipe-B-plane-0: SUCCESS (0.278s)
[62.168340] Starting dynamic subtest: pipe-B-plane-3
[62.393204] Dynamic subtest pipe-B-plane-3: SUCCESS (0.232s)
[62.400442] Starting dynamic subtest: pipe-B-plane-5
[62.404608] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[62.701670] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.445s)
[63.557865] [004/132] (955s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[63.640093] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[63.644090] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[63.740324] [005/132] (954s left) gem_userptr_blits (dmabuf-sync)
[63.804253] Starting subtest: dmabuf-sync
[63.809363] Subtest dmabuf-sync: SKIP (0.003s)
[63.933554] [006/132] (954s left) gem_ccs (suspend-resume)
[63.988858] Starting subtest: suspend-resume
[63.993632] Subtest suspend-resume: SKIP (0.000s)
[64.079133] [007/132] (954s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[64.183124] Starting subtest: basic-flip-after-cursor-atomic
[65.126139] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.945s)
[65.985330] [008/132] (952s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[66.069585] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[70.324763] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (4.267s)
[71.487235] [009/132] (947s left) syncobj_timeline (multi-wait-available-submitted)
[71.539951] Starting subtest: multi-wait-available-submitted
[71.542383] Subtest multi-wait-available-submitted: SUCCESS (0.001s)
[71.637103] [010/132] (947s left) kms_psr (fbc-pr-primary-blt)
[71.719996] Starting subtest: fbc-pr-primary-blt
[71.724604] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[71.787825] [011/132] (947s left) gen7_exec_parse (chained-batch)
[71.849634] Subtest chained-batch: SKIP (0.000s)
[71.907916] [012/132] (946s left) gem_eio (in-flight-suspend)
[72.175512] Starting subtest: in-flight-suspend
[115.884313] Subtest in-flight-suspend: SUCCESS (14.396s)
[116.034177] [FACT gem_eio (in-flight-suspend)] new: kernel.kmod_is_loaded.vgem: true
[116.037478] [013/132] (902s left) gem_exec_params (invalid-bsd-ring)
[116.101889] Starting subtest: invalid-bsd-ring
[116.104004] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[116.198336] [014/132] (902s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[116.265014] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[116.267717] Starting dynamic subtest: pipe-A-eDP-1
[118.349257] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.101s)
[118.359083] Starting dynamic subtest: pipe-B-eDP-1
[120.571552] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.240s)
[120.580927] Starting dynamic subtest: pipe-C-eDP-1
[122.641422] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.081s)
[122.651373] Starting dynamic subtest: pipe-D-eDP-1
[124.746218] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.115s)
[124.748504] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.541s)
[125.982578] [015/132] (893s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[126.089186] Starting subtest: 4-tiled-32bpp-rotate-270
[126.112560] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[126.211104] [016/132] (892s left) kms_psr (psr-basic)
[126.284835] Starting subtest: psr-basic
[126.287133] Starting dynamic subtest: eDP-1
[127.867714] Dynamic subtest eDP-1: SUCCESS (1.588s)
[127.870395] Subtest psr-basic: SUCCESS (1.589s)
[128.748530] [017/132] (890s left) kms_content_protection (suspend-resume)
[128.866142] Starting subtest: suspend-resume
[128.871476] Subtest suspend-resume: SKIP (0.000s)
[128.998135] [018/132] (890s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[129.086582] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[129.091524] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[129.186819] [019/132] (889s left) kms_plane_scaling (plane-downscale-factor-0-75-with-rotation)
[129.265325] Starting subtest: plane-downscale-factor-0-75-with-rotation
[129.267812] Starting dynamic subtest: pipe-A
[129.515102] Dynamic subtest pipe-A: SUCCESS (0.252s)
[129.525266] Starting dynamic subtest: pipe-B
[130.809597] Dynamic subtest pipe-B: SUCCESS (1.297s)
[130.821353] Starting dynamic subtest: pipe-C
[132.132159] Dynamic subtest pipe-C: SUCCESS (1.325s)
[132.143588] Starting dynamic subtest: pipe-D
[133.464473] Dynamic subtest pipe-D: SUCCESS (1.333s)
[133.467300] Subtest plane-downscale-factor-0-75-with-rotation: SUCCESS (4.211s)
[134.661820] [020/132] (884s left) gem_reg_read (timestamp-monotonic)
[134.710400] Starting subtest: timestamp-monotonic
[138.721200] Subtest timestamp-monotonic: SUCCESS (4.017s)
[138.794162] [021/132] (880s left) gem_ctx_persistence (legacy-engines-mixed-process)
[138.849216] Starting subtest: legacy-engines-mixed-process
[138.851893] Starting dynamic subtest: render
[140.480395] Dynamic subtest render: SUCCESS (1.633s)
[140.491252] Starting dynamic subtest: bsd1
[142.538952] Dynamic subtest bsd1: SUCCESS (2.059s)
[142.550744] Starting dynamic subtest: bsd2
[144.592220] Dynamic subtest bsd2: SUCCESS (2.053s)
[144.604478] Starting dynamic subtest: blt
[146.504288] Dynamic subtest blt: SUCCESS (1.912s)
[146.516131] Starting dynamic subtest: vebox
[148.550394] Dynamic subtest vebox: SUCCESS (2.043s)
[148.553393] Subtest legacy-engines-mixed-process: SUCCESS (9.708s)
[148.641968] [022/132] (870s left) gem_mmap_gtt (ptrace)
[148.706309] Subtest ptrace: SKIP (0.000s)
[148.776530] [023/132] (870s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping)
[148.852716] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping
[148.858331] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[148.929431] [024/132] (870s left) gem_ctx_isolation (preservation-s3)
[148.988662] Starting subtest: preservation-S3
[148.991479] Starting dynamic subtest: rcs0
[165.337753] Dynamic subtest rcs0: SUCCESS (1.328s)
[165.349330] Starting dynamic subtest: bcs0
[181.341329] Dynamic subtest bcs0: SUCCESS (1.377s)
[181.351456] Starting dynamic subtest: vcs0
[197.331422] Dynamic subtest vcs0: SUCCESS (2.915s)
[197.344897] Starting dynamic subtest: vcs1
[215.350477] Dynamic subtest vcs1: SUCCESS (5.087s)
[215.360839] Starting dynamic subtest: vecs0
[233.333514] Dynamic subtest vecs0: SUCCESS (4.992s)
[233.348265] Starting dynamic subtest: ccs0
[251.340330] Dynamic subtest ccs0: SUCCESS (3.234s)
[251.342606] Subtest preservation-S3: SUCCESS (18.938s)
[251.575826] [025/132] (767s left) gem_exec_schedule (preempt-queue-contexts)
[251.654536] Subtest preempt-queue-contexts: SKIP (0.000s)
[251.935329] [026/132] (767s left) kms_cursor_crc (cursor-random-128x42)
[252.162832] Starting subtest: cursor-random-128x42
[252.207655] Subtest cursor-random-128x42: SKIP (0.039s)
[252.408618] [027/132] (767s left) sw_sync (sync_busy_fork)
[252.446382] Starting subtest: sync_busy_fork
[253.458235] Subtest sync_busy_fork: SUCCESS (1.021s)
[253.520443] [028/132] (766s left) i915_drm_fdinfo (busy)
[253.560280] Starting subtest: busy
[253.562282] Starting dynamic subtest: rcs0
[253.572240] Dynamic subtest rcs0: SKIP (0.011s)
[253.587239] Starting dynamic subtest: bcs0
[253.591239] Dynamic subtest bcs0: SKIP (0.010s)
[253.600269] Starting dynamic subtest: vcs0
[253.604413] Dynamic subtest vcs0: SKIP (0.000s)
[253.613287] Starting dynamic subtest: vcs1
[253.617241] Dynamic subtest vcs1: SKIP (0.000s)
[253.626302] Starting dynamic subtest: vecs0
[253.630330] Dynamic subtest vecs0: SKIP (0.000s)
[253.639149] Starting dynamic subtest: ccs0
[253.643228] Dynamic subtest ccs0: SKIP (0.000s)
[253.647327] Subtest busy: SKIP (0.025s)
[253.683749] [029/132] (765s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[253.768473] Starting subtest: y-tiled-32bpp-rotate-270
[253.772646] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[253.866704] [030/132] (765s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[253.922028] Starting subtest: invalid-bsd2-flag-on-blt
[253.924197] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[254.037962] [031/132] (765s left) prime_vgem (busy)
[254.102824] Starting subtest: busy
[254.105026] Starting dynamic subtest: rcs0
[254.145149] Dynamic subtest rcs0: SUCCESS (0.043s)
[254.154322] Starting dynamic subtest: bcs0
[254.191460] Dynamic subtest bcs0: SUCCESS (0.045s)
[254.201331] Starting dynamic subtest: vcs0
[254.236949] Dynamic subtest vcs0: SUCCESS (0.045s)
[254.246147] Starting dynamic subtest: vcs1
[254.284556] Dynamic subtest vcs1: SUCCESS (0.047s)
[254.293744] Starting dynamic subtest: vecs0
[254.328973] Dynamic subtest vecs0: SUCCESS (0.044s)
[254.338854] Starting dynamic subtest: ccs0
[254.375049] Dynamic subtest ccs0: SUCCESS (0.046s)
[254.377448] Subtest busy: SUCCESS (0.275s)
[254.515921] [032/132] (765s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[254.578985] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[254.678611] [033/132] (765s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[254.747296] Starting subtest: plane-upscale-20x20-with-pixel-format
[254.749100] Starting dynamic subtest: pipe-A
[255.787672] Dynamic subtest pipe-A: SUCCESS (1.049s)
[255.796513] Starting dynamic subtest: pipe-B
[257.914119] Dynamic subtest pipe-B: SUCCESS (2.138s)
[257.923273] Starting dynamic subtest: pipe-C
[259.107036] Dynamic subtest pipe-C: SUCCESS (1.197s)
[259.117475] Starting dynamic subtest: pipe-D
[260.332558] Dynamic subtest pipe-D: SUCCESS (1.229s)
[260.334826] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.618s)
[261.522658] [034/132] (758s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs)
[261.599141] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs
[261.601363] Starting dynamic subtest: pipe-A-eDP-1
[261.625245] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[261.636589] Starting dynamic subtest: pipe-B-eDP-1
[261.659940] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[261.669238] Starting dynamic subtest: pipe-C-eDP-1
[261.693180] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[261.703770] Starting dynamic subtest: pipe-D-eDP-1
[261.727690] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[261.731716] Subtest random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[263.804039] [035/132] (756s left) syncobj_wait (multi-wait-all-submitted-signaled)
[263.854471] Starting subtest: multi-wait-all-submitted-signaled
[263.962244] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.108s)
[264.053783] [036/132] (755s left) gem_exec_reloc (basic-gtt)
[264.129376] Subtest basic-gtt: SKIP (0.000s)
[264.191639] [037/132] (755s left) gem_exec_params (batch-first)
[264.237976] Starting subtest: batch-first
[264.243348] Subtest batch-first: SUCCESS (0.005s)
[264.358241] [038/132] (755s left) kms_cursor_legacy (single-move)
[264.461342] Starting subtest: single-move
[264.463610] Starting dynamic subtest: pipe-A
[269.833030] Dynamic subtest pipe-A: SUCCESS (5.380s)
[269.845706] Starting dynamic subtest: pipe-B
[275.209370] Dynamic subtest pipe-B: SUCCESS (5.379s)
[275.221275] Starting dynamic subtest: pipe-C
[280.586415] Dynamic subtest pipe-C: SUCCESS (5.379s)
[280.598450] Starting dynamic subtest: pipe-D
[285.970486] Dynamic subtest pipe-D: SUCCESS (5.379s)
[285.982835] Starting dynamic subtest: all-pipes
[291.360269] Dynamic subtest all-pipes: SUCCESS (5.386s)
[291.362775] Subtest single-move: SUCCESS (26.917s)
[291.506766] [039/132] (728s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[291.581993] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[291.584290] Starting dynamic subtest: pipe-A-eDP-1
[291.606852] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[291.618321] Starting dynamic subtest: pipe-B-eDP-1
[291.640247] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[291.650060] Starting dynamic subtest: pipe-C-eDP-1
[291.671475] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[291.680618] Starting dynamic subtest: pipe-D-eDP-1
[291.702387] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[291.706392] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[293.840165] [040/132] (726s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[293.916070] Starting subtest: fbc-psr2-primary-mmap-cpu
[293.918377] Starting dynamic subtest: eDP-1
[293.922632] Dynamic subtest eDP-1: SKIP (0.000s)
[293.926973] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[293.991199] [041/132] (726s left) kms_busy (extended-pageflip-hang-newfb)
[294.079243] Starting subtest: extended-pageflip-hang-newfb
[294.081525] Starting dynamic subtest: pipe-A
[320.860943] Dynamic subtest pipe-A: SUCCESS (26.785s)
[320.870746] Starting dynamic subtest: pipe-D
[345.885591] Dynamic subtest pipe-D: SUCCESS (25.030s)
[345.887920] Subtest extended-pageflip-hang-newfb: SUCCESS (51.820s)
[346.691232] [042/132] (673s left) kms_prop_blob (invalid-set-prop)
[346.755993] Starting subtest: invalid-set-prop
[346.758004] Subtest invalid-set-prop: SUCCESS (0.000s)
[346.848810] [043/132] (673s left) kms_flip (flip-vs-wf_vblank-interruptible)
[346.924690] Starting subtest: flip-vs-wf_vblank-interruptible
[346.926985] Starting dynamic subtest: A-eDP1
[348.839518] Dynamic subtest A-eDP1: SUCCESS (1.913s)
[348.851627] Starting dynamic subtest: B-eDP1
[350.768493] Dynamic subtest B-eDP1: SUCCESS (1.927s)
[350.782249] Starting dynamic subtest: C-eDP1
[352.686381] Dynamic subtest C-eDP1: SUCCESS (1.915s)
[352.700799] Starting dynamic subtest: D-eDP1
[354.609858] Dynamic subtest D-eDP1: SUCCESS (1.921s)
[354.615266] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.687s)
[355.841155] [044/132] (664s left) prime_udl
[355.936860] [045/132] (664s left) perf_pmu (gt-awake)
[355.977288] Starting subtest: gt-awake
[365.071682] Subtest gt-awake: SUCCESS (9.092s)
[365.182598] [046/132] (655s left) kms_addfb_basic (basic-y-tiled-legacy)
[365.238265] Starting subtest: basic-y-tiled-legacy
[365.242520] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[365.363593] [047/132] (655s left) drm_read (short-buffer-nonblock)
[365.493492] Starting subtest: short-buffer-nonblock
[365.495771] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[365.612688] [048/132] (654s left) gem_exec_schedule (deep)
[365.799260] Starting subtest: deep
[365.801702] Starting dynamic subtest: rcs0
[370.651534] Dynamic subtest rcs0: SKIP (4.849s)
[370.664357] Starting dynamic subtest: vcs0
[380.384485] Dynamic subtest vcs0: SUCCESS (9.731s)
[380.399565] Starting dynamic subtest: vcs1
[391.010988] Dynamic subtest vcs1: SUCCESS (10.625s)
[391.026127] Starting dynamic subtest: vecs0
[401.109753] Dynamic subtest vecs0: SUCCESS (10.099s)
[401.123523] Starting dynamic subtest: ccs0
[413.155650] Dynamic subtest ccs0: SUCCESS (12.044s)
[413.159184] Subtest deep: SUCCESS (47.357s)
[414.657680] [049/132] (605s left) gem_exec_gttfill (basic)
[414.724570] Starting subtest: basic
[418.128245] Subtest basic: SUCCESS (3.407s)
[418.323123] [050/132] (602s left) gem_exec_fence (nb-await)
[418.535880] Starting subtest: nb-await
[418.538262] Starting dynamic subtest: rcs0
[418.615398] Dynamic subtest rcs0: SUCCESS (0.079s)
[418.629329] Starting dynamic subtest: bcs0
[418.674719] Dynamic subtest bcs0: SUCCESS (0.057s)
[418.688374] Starting dynamic subtest: vcs0
[418.734634] Dynamic subtest vcs0: SUCCESS (0.059s)
[418.748016] Starting dynamic subtest: vcs1
[418.794638] Dynamic subtest vcs1: SUCCESS (0.058s)
[418.807819] Starting dynamic subtest: vecs0
[418.855657] Dynamic subtest vecs0: SUCCESS (0.059s)
[418.868968] Starting dynamic subtest: ccs0
[418.915516] Dynamic subtest ccs0: SUCCESS (0.058s)
[418.918165] Subtest nb-await: SUCCESS (0.381s)
[421.146915] [051/132] (599s left) i915_query (query-regions-garbage-items)
[421.206269] Starting subtest: query-regions-garbage-items
[421.208517] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[421.310382] [052/132] (599s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[421.689776] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[422.537630] [053/132] (598s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[422.618561] Starting subtest: fbcpsr-slowdraw
[447.766773] Subtest fbcpsr-slowdraw: SUCCESS (25.159s)
[447.894027] [054/132] (572s left) gem_mmap (bad-offset)
[447.960825] Subtest bad-offset: SKIP (0.000s)
[448.018031] [055/132] (572s left) i915_pm_freq_mult (media-freq)
[448.065480] Starting subtest: media-freq
[448.067675] Starting dynamic subtest: gt0
[448.072076] Dynamic subtest gt0: SKIP (0.000s)
[448.083335] Starting dynamic subtest: gt1
[448.087800] Dynamic subtest gt1: SKIP (0.000s)
[448.092242] Subtest media-freq: SKIP (0.001s)
[448.147727] [056/132] (572s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[448.234755] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[448.238854] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[448.340668] [057/132] (572s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[448.413922] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[448.418378] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[448.509666] [058/132] (572s left) gem_mmap_gtt (basic-small-copy-odd)
[448.570646] Subtest basic-small-copy-odd: SKIP (0.000s)
[448.633487] [059/132] (572s left) i915_hangman (engine-engine-hang)
[448.701100] Starting subtest: engine-engine-hang
[448.702863] Starting dynamic subtest: rcs0
[450.028152] Dynamic subtest rcs0: SUCCESS (1.326s)
[450.041096] Starting dynamic subtest: bcs0
[452.075623] Dynamic subtest bcs0: SUCCESS (2.045s)
[452.091037] Starting dynamic subtest: vcs0
[454.059554] Dynamic subtest vcs0: SUCCESS (1.982s)
[454.073184] Starting dynamic subtest: vcs1
[456.043958] Dynamic subtest vcs1: SUCCESS (1.982s)
[456.059005] Starting dynamic subtest: vecs0
[458.028145] Dynamic subtest vecs0: SUCCESS (1.983s)
[458.045979] Starting dynamic subtest: ccs0
[460.011711] Dynamic subtest ccs0: SUCCESS (1.981s)
[460.014896] Subtest engine-engine-hang: SUCCESS (11.314s)
[460.143148] [060/132] (560s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[460.226558] Starting subtest: x-tiled-32bpp-rotate-90
[460.246271] Subtest x-tiled-32bpp-rotate-90: SKIP (0.019s)
[460.370018] [061/132] (560s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[460.440067] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[464.405005] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (3.970s)
[465.563044] [062/132] (555s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[465.654149] Starting subtest: read-crc-frame-sequence
[465.656489] Starting dynamic subtest: pipe-A-eDP-1
[466.364193] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.709s)
[466.375607] Starting dynamic subtest: pipe-B-eDP-1
[467.739623] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.373s)
[467.754273] Starting dynamic subtest: pipe-C-eDP-1
[469.128775] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.389s)
[469.140000] Starting dynamic subtest: pipe-D-eDP-1
[470.453935] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[470.456092] Subtest read-crc-frame-sequence: SUCCESS (4.802s)
[471.269985] [063/132] (549s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[471.358670] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[471.362495] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[471.467229] [064/132] (549s left) gem_caching (read-writes)
[471.524641] Subtest read-writes: SKIP (0.000s)
[471.608786] [065/132] (549s left) syncobj_timeline (device-signal-unordered)
[471.679634] Starting subtest: device-signal-unordered
[471.682047] Subtest device-signal-unordered: SUCCESS (0.001s)
[471.778278] [066/132] (549s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[471.873617] Starting subtest: cursorA-vs-flipA-atomic
[483.041499] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.162s)
[483.912571] [067/132] (537s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[483.986515] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[483.988875] Starting dynamic subtest: pipe-A-default-mode
[485.178116] Dynamic subtest pipe-A-default-mode: SKIP (1.189s)
[485.182625] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.190s)
[485.320603] [068/132] (535s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[485.413176] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[492.430294] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.018s)
[492.530261] [069/132] (528s left) kms_cursor_crc (cursor-alpha-opaque)
[492.611492] Starting subtest: cursor-alpha-opaque
[492.613340] Starting dynamic subtest: pipe-A-eDP-1
[493.234960] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.624s)
[493.245675] Starting dynamic subtest: pipe-D-eDP-1
[494.541345] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.302s)
[494.543568] Subtest cursor-alpha-opaque: SUCCESS (1.929s)
[495.433034] [070/132] (525s left) gem_exec_reloc (basic-write-cpu-active)
[495.486879] Subtest basic-write-cpu-active: SKIP (0.000s)
[495.561483] [071/132] (525s left) syncobj_timeline (signal-point-0)
[495.622152] Starting subtest: signal-point-0
[495.624356] Subtest signal-point-0: SUCCESS (0.000s)
[495.711787] [072/132] (525s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[495.787294] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[495.789579] Starting dynamic subtest: pipe-A-eDP-1
[495.894666] Dynamic subtest pipe-A-eDP-1: SKIP (0.111s)
[495.906427] Starting dynamic subtest: pipe-B-eDP-1
[496.998896] Dynamic subtest pipe-B-eDP-1: SKIP (1.103s)
[497.003464] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.216s)
[498.195929] [073/132] (523s left) gem_exec_balancer (bonded-semaphore)
[498.258449] Starting subtest: bonded-semaphore
[498.262905] Subtest bonded-semaphore: SKIP (0.000s)
[498.553285] [074/132] (522s left) gem_ctx_bad_destroy (double-destroy)
[498.600316] Starting subtest: double-destroy
[498.602824] Subtest double-destroy: SUCCESS (0.000s)
[498.676802] [075/132] (522s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[498.761139] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[502.989133] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.231s)
[504.186844] [076/132] (517s left) gem_ctx_shared (q-smoketest)
[504.256690] Starting subtest: Q-smoketest
[504.259030] Starting dynamic subtest: rcs0
[509.801123] Dynamic subtest rcs0: SUCCESS (5.496s)
[509.816531] Starting dynamic subtest: bcs0
[515.293111] Dynamic subtest bcs0: SUCCESS (5.486s)
[515.311659] Starting dynamic subtest: vcs0
[520.766371] Dynamic subtest vcs0: SUCCESS (5.476s)
[520.780977] Starting dynamic subtest: vcs1
[526.287023] Dynamic subtest vcs1: SUCCESS (5.495s)
[526.299858] Starting dynamic subtest: vecs0
[531.768829] Dynamic subtest vecs0: SUCCESS (5.500s)
[531.782721] Starting dynamic subtest: ccs0
[537.273761] Dynamic subtest ccs0: SUCCESS (5.504s)
[537.275842] Subtest Q-smoketest: SUCCESS (32.965s)
[537.413492] [077/132] (484s left) gem_ctx_param (set-priority-invalid-size)
[537.467779] Starting subtest: set-priority-invalid-size
[537.470246] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[537.547480] [078/132] (484s left) kms_frontbuffer_tracking (psr-1p-rte)
[537.637356] Starting subtest: psr-1p-rte
[543.082960] Subtest psr-1p-rte: SUCCESS (5.448s)
[543.197716] [079/132] (478s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[543.275970] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[543.280291] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[543.365123] [080/132] (478s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[543.408718] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[543.410884] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[543.498855] [081/132] (478s left) gem_close (basic)
[543.549765] Starting subtest: basic
[543.552003] Subtest basic: SUCCESS (0.000s)
[543.647335] [082/132] (478s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[543.714592] Starting subtest: plane-ctm3x4-lut1d
[543.839528] Starting dynamic subtest: pipe-A-plane-0
[543.995462] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[544.006690] Starting dynamic subtest: pipe-A-plane-1
[544.173986] Dynamic subtest pipe-A-plane-1: SUCCESS (0.177s)
[544.184094] Starting dynamic subtest: pipe-A-plane-2
[544.305443] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[545.508977] Starting dynamic subtest: pipe-B-plane-0
[545.644086] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[545.655364] Starting dynamic subtest: pipe-B-plane-1
[545.799597] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[545.810665] Starting dynamic subtest: pipe-B-plane-2
[545.932956] Dynamic subtest pipe-B-plane-2: SUCCESS (0.132s)
[547.119328] Starting dynamic subtest: pipe-C-plane-0
[547.243075] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[547.254156] Starting dynamic subtest: pipe-C-plane-1
[547.377171] Dynamic subtest pipe-C-plane-1: SUCCESS (0.133s)
[547.387862] Starting dynamic subtest: pipe-C-plane-2
[547.509561] Dynamic subtest pipe-C-plane-2: SUCCESS (0.132s)
[548.669501] Starting dynamic subtest: pipe-D-plane-0
[548.793844] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[548.804476] Starting dynamic subtest: pipe-D-plane-1
[548.928327] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[548.949046] Starting dynamic subtest: pipe-D-plane-2
[549.082675] Dynamic subtest pipe-D-plane-2: SUCCESS (0.154s)
[549.370334] Subtest plane-ctm3x4-lut1d: SUCCESS (5.654s)
[550.241164] [083/132] (471s left) vgem_basic (dmabuf-mmap)
[550.292222] Starting subtest: dmabuf-mmap
[550.323939] Subtest dmabuf-mmap: SUCCESS (0.034s)
[550.386692] [084/132] (471s left) syncobj_timeline (invalid-transfer-bad-pad)
[550.438007] Starting subtest: invalid-transfer-bad-pad
[550.440349] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[550.538948] [085/132] (471s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[550.589536] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[550.594164] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[550.772814] [086/132] (471s left) kms_joiner (invalid-modeset-ultra-joiner)
[550.868601] Starting subtest: invalid-modeset-ultra-joiner
[550.872771] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[550.943653] [087/132] (471s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[550.984843] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[551.025761] [088/132] (471s left) kms_psr (fbc-psr-primary-mmap-gtt)
[551.104145] Starting subtest: fbc-psr-primary-mmap-gtt
[551.106448] Starting dynamic subtest: eDP-1
[551.110696] Dynamic subtest eDP-1: SKIP (0.000s)
[551.114881] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[551.180713] [089/132] (470s left) kms_big_fb (linear-16bpp-rotate-0)
[551.262588] Starting subtest: linear-16bpp-rotate-0
[552.697491] Subtest linear-16bpp-rotate-0: SUCCESS (1.434s)
[552.806917] [090/132] (469s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[552.883195] Starting subtest: psr-indfb-scaledprimary
[558.591231] Subtest psr-indfb-scaledprimary: SUCCESS (5.709s)
[558.685428] [091/132] (463s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[558.789674] Starting subtest: flip-vs-cursor-legacy
[566.100495] Subtest flip-vs-cursor-legacy: SUCCESS (7.310s)
[566.974178] [092/132] (455s left) kms_vrr (seamless-rr-switch-virtual)
[567.047842] Starting subtest: seamless-rr-switch-virtual
[567.053950] Subtest seamless-rr-switch-virtual: SKIP (0.000s)
[567.170663] [093/132] (455s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[567.237945] Starting subtest: flip-vs-dpms-on-nop-interruptible
[567.240325] Starting dynamic subtest: A-eDP1
[569.076439] Dynamic subtest A-eDP1: SUCCESS (1.838s)
[569.091933] Starting dynamic subtest: B-eDP1
[570.900040] Dynamic subtest B-eDP1: SUCCESS (1.821s)
[570.915778] Starting dynamic subtest: C-eDP1
[572.702115] Dynamic subtest C-eDP1: SUCCESS (1.799s)
[572.716983] Starting dynamic subtest: D-eDP1
[574.546665] Dynamic subtest D-eDP1: SUCCESS (1.842s)
[574.551778] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.315s)
[575.723544] [094/132] (446s left) kms_pm_lpsp (screens-disabled)
[576.807762] Starting subtest: screens-disabled
[576.812016] Subtest screens-disabled: SKIP (0.000s)
[576.886642] [095/132] (445s left) kms_atomic_transition (plane-all-transition)
[576.969691] Starting subtest: plane-all-transition
[576.971935] Starting dynamic subtest: pipe-A-eDP-1
[579.462643] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[579.789086] Starting dynamic subtest: pipe-B-eDP-1
[582.970865] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.196s)
[583.272847] Subtest plane-all-transition: SUCCESS (6.304s)
[584.095882] [096/132] (438s left) gem_pxp (fail-invalid-protected-context)
[584.228544] Starting subtest: fail-invalid-protected-context
[584.230977] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[584.359568] [097/132] (438s left) gem_exec_schedule (semaphore-noskip)
[584.438927] Starting subtest: semaphore-noskip
[584.838680] Subtest semaphore-noskip: SUCCESS (0.408s)
[585.191790] [098/132] (437s left) kms_scaling_modes (scaling-mode-full)
[585.255282] Starting subtest: scaling-mode-full
[585.257557] Starting dynamic subtest: pipe-A-eDP-1
[585.347308] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.093s)
[585.362358] Starting dynamic subtest: pipe-B-eDP-1
[586.532425] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.183s)
[586.546714] Starting dynamic subtest: pipe-C-eDP-1
[587.661984] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.126s)
[587.675442] Starting dynamic subtest: pipe-D-eDP-1
[588.789498] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.124s)
[588.791763] Subtest scaling-mode-full: SUCCESS (3.537s)
[589.941215] [099/132] (432s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[589.996291] Starting subtest: invalid-bsd2-flag-on-vebox
[589.998589] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[590.098596] [100/132] (432s left) api_intel_allocator (reserve-simple)
[590.173741] Starting subtest: reserve-simple
[590.176168] Subtest reserve-simple: SUCCESS (0.003s)
[590.306686] [101/132] (432s left) gem_wait (invalid-flags)
[590.364805] Starting subtest: invalid-flags
[590.366814] Subtest invalid-flags: SUCCESS (0.000s)
[590.581625] [102/132] (432s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[590.664738] Starting subtest: fbc-rgb101010-draw-pwrite
[595.986236] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.326s)
[597.134609] [103/132] (425s left) kms_vblank (query-idle-hang)
[597.199216] Starting subtest: query-idle-hang
[597.201519] Starting dynamic subtest: pipe-A-eDP-1
[623.898287] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.699s)
[623.914245] Starting dynamic subtest: pipe-D-eDP-1
[648.843711] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.948s)
[648.846035] Subtest query-idle-hang: SUCCESS (51.652s)
[649.688766] [104/132] (373s left) gem_exec_balancer (sequential)
[649.751978] Starting subtest: sequential
[652.832785] Subtest sequential: SUCCESS (3.081s)
[653.158402] [105/132] (369s left) kms_universal_plane (disable-primary-vs-flip)
[653.243308] Starting subtest: disable-primary-vs-flip
[653.245700] Starting dynamic subtest: pipe-A-eDP-1
[654.766185] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.522s)
[654.777884] Starting dynamic subtest: pipe-B-eDP-1
[656.950814] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.183s)
[656.962095] Starting dynamic subtest: pipe-C-eDP-1
[659.198220] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.245s)
[659.210050] Starting dynamic subtest: pipe-D-eDP-1
[661.371777] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.174s)
[661.374212] Subtest disable-primary-vs-flip: SUCCESS (8.129s)
[662.267460] [106/132] (360s left) syncobj_wait (invalid-reset-one-illegal-handle)
[662.323234] Starting subtest: invalid-reset-one-illegal-handle
[662.325499] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[662.408949] [107/132] (360s left) gem_pxp (protected-raw-src-copy-not-readible)
[662.462026] Starting subtest: protected-raw-src-copy-not-readible
[662.523301] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.060s)
[662.655576] [108/132] (360s left) kms_flip (dpms-vs-vblank-race-interruptible)
[662.731483] Starting subtest: dpms-vs-vblank-race-interruptible
[662.733758] Starting dynamic subtest: A-eDP1
[666.517577] Dynamic subtest A-eDP1: SUCCESS (3.786s)
[666.531942] Starting dynamic subtest: B-eDP1
[670.100157] Dynamic subtest B-eDP1: SUCCESS (3.578s)
[670.112147] Starting dynamic subtest: C-eDP1
[674.724198] Dynamic subtest C-eDP1: SUCCESS (4.624s)
[674.735850] Starting dynamic subtest: D-eDP1
[679.358397] Dynamic subtest D-eDP1: SUCCESS (4.632s)
[679.360531] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (16.633s)
[680.254228] [109/132] (342s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[680.332445] Subtest untiled-to-tiled: SKIP (0.000s)
[680.399655] [110/132] (342s left) gem_spin_batch (resubmit-new)
[680.458565] Starting subtest: resubmit-new
[680.460810] Starting dynamic subtest: rcs0
[680.465814] Dynamic subtest rcs0: SUCCESS (0.007s)
[680.477753] Starting dynamic subtest: bcs0
[680.479861] Dynamic subtest bcs0: SUCCESS (0.005s)
[680.490479] Starting dynamic subtest: vcs0
[680.492862] Dynamic subtest vcs0: SUCCESS (0.004s)
[680.503000] Starting dynamic subtest: vcs1
[680.505076] Dynamic subtest vcs1: SUCCESS (0.004s)
[680.514961] Starting dynamic subtest: vecs0
[680.517179] Dynamic subtest vecs0: SUCCESS (0.004s)
[680.527323] Starting dynamic subtest: ccs0
[680.529469] Dynamic subtest ccs0: SUCCESS (0.004s)
[680.531635] Subtest resubmit-new: SUCCESS (0.032s)
[682.117053] [111/132] (341s left) drm_mm (drm_mm)
[682.161724] Starting subtest: drm_mm
[682.193655] Starting dynamic subtest: drm_test_mm_init
[682.299407] Dynamic subtest drm_test_mm_init: SUCCESS (0.103s)
[682.311258] Starting dynamic subtest: drm_test_mm_debug
[682.393425] Dynamic subtest drm_test_mm_debug: SUCCESS (0.093s)
[682.406038] Starting dynamic subtest: drm_test_mm_align32
[682.490853] Dynamic subtest drm_test_mm_align32: SUCCESS (0.098s)
[682.501527] Starting dynamic subtest: drm_test_mm_align64
[682.596375] Dynamic subtest drm_test_mm_align64: SUCCESS (0.104s)
[682.606185] Starting dynamic subtest: drm_test_mm_lowest
[682.699245] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.101s)
[682.711091] Starting dynamic subtest: drm_test_mm_highest
[682.816550] Dynamic subtest drm_test_mm_highest: SUCCESS (0.117s)
[682.818492] Subtest drm_mm: SUCCESS (0.657s)
[682.983377] [112/132] (340s left) kms_joiner (basic-big-joiner)
[683.059356] Starting subtest: basic-big-joiner
[683.063396] Subtest basic-big-joiner: SKIP (0.000s)
[683.127046] [113/132] (340s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[683.204777] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[683.207004] Starting dynamic subtest: pipe-A-eDP-1
[683.226777] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[683.239067] Starting dynamic subtest: pipe-B-eDP-1
[683.257357] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[683.267340] Starting dynamic subtest: pipe-C-eDP-1
[683.285956] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[683.296303] Starting dynamic subtest: pipe-D-eDP-1
[683.314241] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[683.315819] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[685.423977] [114/132] (337s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[685.497363] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[685.501321] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[685.603505] [115/132] (337s left) api_intel_bb (intel-bb-blit-none)
[685.652097] Starting subtest: intel-bb-blit-none
[689.531337] Subtest intel-bb-blit-none: SUCCESS (3.880s)
[689.653067] [116/132] (333s left) kms_addfb_basic (unused-offsets)
[689.714472] Starting subtest: unused-offsets
[689.716669] Subtest unused-offsets: SUCCESS (0.000s)
[689.840850] [117/132] (333s left) gem_ccs (block-multicopy-inplace)
[689.892476] Starting subtest: block-multicopy-inplace
[689.896920] Subtest block-multicopy-inplace: SKIP (0.000s)
[690.022627] [118/132] (333s left) gem_ctx_param (basic)
[690.088412] Starting subtest: basic
[690.091016] Subtest basic: SUCCESS (0.002s)
[690.201117] [119/132] (333s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-wc)
[690.282943] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-wc
[690.287454] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[690.378690] [120/132] (333s left) gem_userptr_blits (sd-probe)
[690.443085] Starting subtest: sd-probe
[690.445400] Subtest sd-probe: SUCCESS (0.000s)
[690.571753] [121/132] (332s left) kms_chamelium_frames (hdmi-crc-fast)
[690.925079] Subtest hdmi-crc-fast: SKIP (0.000s)
[691.733168] [122/132] (331s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[691.809465] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[691.811774] Starting dynamic subtest: pipe-A-eDP-1
[691.867640] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[691.877875] Starting dynamic subtest: pipe-B-eDP-1
[691.935079] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[691.947146] Starting dynamic subtest: pipe-C-eDP-1
[692.003915] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[692.015890] Starting dynamic subtest: pipe-D-eDP-1
[692.072717] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[692.076771] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.009s)
[694.107465] [123/132] (329s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[694.197811] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[695.142790] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (0.945s)
[695.268006] [124/132] (328s left) kms_psr (pr-dpms)
[695.335529] Starting subtest: pr-dpms
[695.339657] Subtest pr-dpms: SKIP (0.000s)
[695.425603] [125/132] (328s left) gem_exec_suspend (basic-s0)
[695.497009] Starting subtest: basic-S0
[695.498934] Starting dynamic subtest: smem
[716.202453] Dynamic subtest smem: SUCCESS (6.611s)
[716.205125] Subtest basic-S0: SUCCESS (6.613s)
[716.399069] [126/132] (307s left) kms_cursor_edge_walk (64x64-top-edge)
[716.471129] Starting subtest: 64x64-top-edge
[716.473229] Starting dynamic subtest: pipe-A-eDP-1
[719.916463] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.465s)
[719.927753] Starting dynamic subtest: pipe-D-eDP-1
[723.114392] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.209s)
[723.116750] Subtest 64x64-top-edge: SUCCESS (6.680s)
[723.918184] [127/132] (299s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[724.013759] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[731.624682] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.632s)
[731.750412] [128/132] (292s left) kms_atomic (test-only)
[731.837704] Starting subtest: test-only
[731.884687] Starting dynamic subtest: pipe-A-eDP-1
[734.372257] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.492s)
[734.374600] Subtest test-only: SUCCESS (2.539s)
[735.186256] [129/132] (288s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[735.262929] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[735.266973] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[735.361796] [130/132] (288s left) kms_properties (plane-properties-atomic)
[735.441302] Starting subtest: plane-properties-atomic
[735.443503] Starting dynamic subtest: pipe-A-eDP-1
[736.008953] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.567s)
[736.019918] Starting dynamic subtest: pipe-B-eDP-1
[737.263448] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.256s)
[737.274877] Starting dynamic subtest: pipe-C-eDP-1
[738.491756] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.227s)
[738.506252] Starting dynamic subtest: pipe-D-eDP-1
[739.726097] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.233s)
[739.728399] Subtest plane-properties-atomic: SUCCESS (4.288s)
[740.527581] [131/132] (283s left) sysfs_heartbeat_interval (nopreempt)
[740.582292] Starting subtest: nopreempt
[740.584843] Starting dynamic subtest: vecs0
[750.183653] Dynamic subtest vecs0: SUCCESS (9.600s)
[750.201643] Starting dynamic subtest: ccs0
[754.209523] Dynamic subtest ccs0: SUCCESS (4.026s)
[754.223427] Starting dynamic subtest: vcs0
[764.196664] Dynamic subtest vcs0: SUCCESS (9.986s)
[764.210240] Starting dynamic subtest: bcs0
[768.105290] Dynamic subtest bcs0: SUCCESS (3.909s)
[768.118786] Starting dynamic subtest: rcs0
[772.095258] Dynamic subtest rcs0: SUCCESS (3.990s)
[772.108897] Starting dynamic subtest: vcs1
[781.210447] Dynamic subtest vcs1: SUCCESS (9.085s)
[781.214670] Subtest nopreempt: SUCCESS (40.608s)
[781.256022] [132/132] (242s left) gem_exec_flush (basic-uc-pro-default)
[781.324145] Starting subtest: basic-uc-pro-default
[786.834971] Subtest basic-uc-pro-default: SUCCESS (5.512s)
[787.036286] Closing watchdogs
results: parsing output: 0/ for test: kms_rotation_crc
results: parsing output: 1/ for test: kms_draw_crc
results: parsing output: 2/ for test: kms_plane
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_userptr_blits
results: parsing output: 5/ for test: gem_ccs
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: gen7_exec_parse
results: parsing output: 11/ for test: gem_eio
results: parsing output: 12/ for test: gem_exec_params
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: kms_content_protection
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_plane_scaling
results: parsing output: 19/ for test: gem_reg_read
results: parsing output: 20/ for test: gem_ctx_persistence
results: parsing output: 21/ for test: gem_mmap_gtt
results: parsing output: 22/ for test: kms_plane
results: parsing output: 23/ for test: gem_ctx_isolation
results: parsing output: 24/ for test: gem_exec_schedule
results: parsing output: 25/ for test: kms_cursor_crc
results: parsing output: 26/ for test: sw_sync
results: parsing output: 27/ for test: i915_drm_fdinfo
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: gem_exec_params
results: parsing output: 30/ for test: prime_vgem
results: parsing output: 31/ for test: kms_addfb_basic
results: parsing output: 32/ for test: kms_plane_scaling
results: parsing output: 33/ for test: kms_ccs
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: gem_exec_reloc
results: parsing output: 36/ for test: gem_exec_params
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: kms_busy
results: parsing output: 41/ for test: kms_prop_blob
results: parsing output: 42/ for test: kms_flip
results: parsing output: 43/ for test: prime_udl
results: parsing output: 44/ for test: perf_pmu
results: parsing output: 45/ for test: kms_addfb_basic
results: parsing output: 46/ for test: drm_read
results: parsing output: 47/ for test: gem_exec_schedule
results: parsing output: 48/ for test: gem_exec_gttfill
results: parsing output: 49/ for test: gem_exec_fence
results: parsing output: 50/ for test: i915_query
results: parsing output: 51/ for test: kms_chamelium_hpd
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_mmap
results: parsing output: 54/ for test: i915_pm_freq_mult
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_mmap_gtt
results: parsing output: 58/ for test: i915_hangman
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_pipe_crc_basic
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_caching
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: kms_cursor_legacy
results: parsing output: 66/ for test: kms_flip_scaled_crc
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_cursor_crc
results: parsing output: 69/ for test: gem_exec_reloc
results: parsing output: 70/ for test: syncobj_timeline
results: parsing output: 71/ for test: kms_psr2_sf
results: parsing output: 72/ for test: gem_exec_balancer
results: parsing output: 73/ for test: gem_ctx_bad_destroy
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_ctx_shared
results: parsing output: 76/ for test: gem_ctx_param
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: syncobj_timeline
results: parsing output: 80/ for test: gem_close
results: parsing output: 81/ for test: kms_color_pipeline
results: parsing output: 82/ for test: vgem_basic
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: gem_render_copy
results: parsing output: 85/ for test: kms_joiner
results: parsing output: 86/ for test: gem_lmem_swapping
results: parsing output: 87/ for test: kms_psr
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_cursor_legacy
results: parsing output: 91/ for test: kms_vrr
results: parsing output: 92/ for test: kms_flip
results: parsing output: 93/ for test: kms_pm_lpsp
results: parsing output: 94/ for test: kms_atomic_transition
results: parsing output: 95/ for test: gem_pxp
results: parsing output: 96/ for test: gem_exec_schedule
results: parsing output: 97/ for test: kms_scaling_modes
results: parsing output: 98/ for test: gem_exec_params
results: parsing output: 99/ for test: api_intel_allocator
results: parsing output: 100/ for test: gem_wait
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_vblank
results: parsing output: 103/ for test: gem_exec_balancer
results: parsing output: 104/ for test: kms_universal_plane
results: parsing output: 105/ for test: syncobj_wait
results: parsing output: 106/ for test: gem_pxp
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: gem_set_tiling_vs_blt
results: parsing output: 109/ for test: gem_spin_batch
results: parsing output: 110/ for test: drm_mm
results: parsing output: 111/ for test: kms_joiner
results: parsing output: 112/ for test: kms_ccs
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: api_intel_bb
results: parsing output: 115/ for test: kms_addfb_basic
results: parsing output: 116/ for test: gem_ccs
results: parsing output: 117/ for test: gem_ctx_param
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_userptr_blits
results: parsing output: 120/ for test: kms_chamelium_frames
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: gem_exec_suspend
results: parsing output: 125/ for test: kms_cursor_edge_walk
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_atomic
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_properties
results: parsing output: 130/ for test: sysfs_heartbeat_interval
results: parsing output: 131/ for test: gem_exec_flush
Done.