[56.941412] Initializing watchdogs
[56.941460]   /dev/watchdog0
[56.955375] [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]
[56.959758] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.961988] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.965305] [001/156] (960s left) kms_plane_scaling (intel-max-src-size)
[57.080199] Starting subtest: intel-max-src-size
[57.086965] Subtest intel-max-src-size: SKIP (0.000s)
[57.183696] [002/156] (960s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-fullscreen)
[57.294614] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-fullscreen
[57.299083] Subtest psrhdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[57.396773] [003/156] (960s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[57.493746] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[57.497813] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[57.596847] [004/156] (959s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[57.694870] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[62.380718] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.756s)
[63.564997] [005/156] (954s left) kms_addfb_basic (tile-pitch-mismatch)
[63.615080] Starting subtest: tile-pitch-mismatch
[63.619460] Subtest tile-pitch-mismatch: SKIP (0.000s)
[63.759549] [006/156] (953s left) kms_lease (atomic-implicit-crtc)
[63.861260] Starting subtest: atomic-implicit-crtc
[63.863902] Starting dynamic subtest: pipe-A-eDP-1
[63.876199] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[63.884400] Starting dynamic subtest: pipe-B-eDP-1
[63.895191] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[63.902325] Starting dynamic subtest: pipe-C-eDP-1
[63.912805] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.009s)
[63.919694] Starting dynamic subtest: pipe-D-eDP-1
[63.930164] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.010s)
[63.932273] Subtest atomic-implicit-crtc: SUCCESS (0.033s)
[64.026508] [007/156] (953s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[64.123756] Starting subtest: fbcpsr-rgb101010-draw-blt
[68.889796] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (4.799s)
[68.999464] [008/156] (948s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-pwrite)
[69.102789] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-pwrite
[69.107287] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[69.208195] [009/156] (948s left) prime_vgem (basic-fence-mmap)
[69.304814] Starting subtest: basic-fence-mmap
[69.309604] Subtest basic-fence-mmap: SKIP (0.000s)
[69.421564] [FACT prime_vgem (basic-fence-mmap)] new: kernel.kmod_is_loaded.vgem: true
[69.424953] [010/156] (948s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[69.486924] Starting subtest: invalid-multi-wait-unsubmitted
[69.489289] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[69.594903] [011/156] (948s left) kms_color (ctm-max)
[69.680779] Starting subtest: ctm-max
[69.682688] Starting dynamic subtest: pipe-A-eDP-1
[70.396946] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.720s)
[70.406059] Starting dynamic subtest: pipe-B-eDP-1
[71.849132] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.455s)
[71.857839] Starting dynamic subtest: pipe-C-eDP-1
[73.240549] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.396s)
[73.250829] Starting dynamic subtest: pipe-D-eDP-1
[74.649333] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.410s)
[74.651365] Subtest ctm-max: SUCCESS (4.982s)
[75.455246] [012/156] (942s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[75.509035] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[75.511417] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.001s)
[75.611850] [013/156] (942s left) gen9_exec_parse (bb-start-cmd)
[75.664116] Subtest bb-start-cmd: SKIP (0.000s)
[75.732377] [014/156] (942s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[75.829484] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[75.833596] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[75.930204] [015/156] (942s left) gem_mmap_wc (bad-offset)
[75.985685] Subtest bad-offset: SKIP (0.000s)
[76.038865] [016/156] (942s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[76.130089] Starting subtest: psr-rgb565-draw-mmap-cpu
[83.022748] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.906s)
[83.132054] [017/156] (934s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[83.225388] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[90.135061] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.914s)
[90.258424] [018/156] (927s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[90.368534] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[91.214508] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.848s)
[91.375919] [019/156] (926s left) i915_drm_fdinfo (all-busy-idle-check-all)
[91.420617] Starting subtest: all-busy-idle-check-all
[91.425076] Subtest all-busy-idle-check-all: SKIP (0.004s)
[91.483601] [020/156] (926s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-blt)
[91.600249] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-blt
[91.604494] Subtest fbchdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[91.701757] [021/156] (926s left) kms_vblank (ts-continuation-dpms-rpm)
[91.795309] Starting subtest: ts-continuation-dpms-rpm
[91.797644] Starting dynamic subtest: pipe-A-eDP-1
[94.324748] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.526s)
[94.340487] Starting dynamic subtest: pipe-D-eDP-1
[96.555662] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.229s)
[96.557722] Subtest ts-continuation-dpms-rpm: SUCCESS (4.758s)
[97.434468] [022/156] (920s left) kms_dp_link_training (uhbr-sst)
[97.543558] Starting subtest: uhbr-sst
[97.552640] Subtest uhbr-sst: SKIP (0.000s)
[97.610539] [023/156] (920s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[97.709125] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[104.814055] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.111s)
[104.923427] [024/156] (913s left) syncobj_wait (multi-wait-submitted)
[104.979359] Starting subtest: multi-wait-submitted
[105.087495] Subtest multi-wait-submitted: SUCCESS (0.108s)
[105.162447] [025/156] (913s left) kms_flip (2x-flip-vs-dpms-on-nop)
[105.256453] Starting subtest: 2x-flip-vs-dpms-on-nop
[105.265646] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.011s)
[105.342247] [026/156] (913s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[105.430614] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[105.434869] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[105.528942] [027/156] (912s left) kms_addfb_basic (addfb25-bad-modifier)
[105.623694] Starting subtest: addfb25-bad-modifier
[105.625585] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[105.718623] [028/156] (912s left) kms_frontbuffer_tracking (fbchdr-2p-rte)
[105.809127] Starting subtest: fbchdr-2p-rte
[105.813081] Subtest fbchdr-2p-rte: SKIP (0.000s)
[105.888899] [029/156] (912s left) gem_userptr_blits (coherency-unsync)
[105.956459] Subtest coherency-unsync: SKIP (0.000s)
[106.081860] [030/156] (912s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-blt)
[106.184222] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-blt
[106.187854] Subtest psrhdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[106.279050] [031/156] (912s left) kms_properties (connector-properties-legacy)
[106.365113] Starting subtest: connector-properties-legacy
[106.366850] Starting dynamic subtest: pipe-A-eDP-1
[114.464776] Dynamic subtest pipe-A-eDP-1: SUCCESS (8.097s)
[114.472647] Starting dynamic subtest: pipe-None-DP-1
[114.476698] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[114.483871] Starting dynamic subtest: pipe-None-HDMI-A-1
[114.488238] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[114.495732] Starting dynamic subtest: pipe-None-DP-2
[114.500170] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[114.507649] Starting dynamic subtest: pipe-None-HDMI-A-2
[114.514609] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[114.521906] Starting dynamic subtest: pipe-None-DP-3
[114.526284] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[114.533730] Starting dynamic subtest: pipe-None-DP-4
[114.538626] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[114.541048] Subtest connector-properties-legacy: SUCCESS (8.107s)
[115.337098] [032/156] (903s left) gem_reg_read (bad-register)
[115.392318] Starting subtest: bad-register
[115.394582] Subtest bad-register: SUCCESS (0.000s)
[115.486152] [033/156] (903s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[115.580687] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[115.584991] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[115.673577] [034/156] (903s left) kms_chamelium_color (gamma)
[115.772733] Subtest gamma: SKIP (0.000s)
[115.816863] [035/156] (903s left) api_intel_bb (simple-bb-ctx)
[115.871279] Starting subtest: simple-bb-ctx
[115.877554] Subtest simple-bb-ctx: SUCCESS (0.008s)
[115.995355] [036/156] (902s left) perf_pmu (busy-accuracy-98)
[116.036307] Starting subtest: busy-accuracy-98
[116.040445] Starting dynamic subtest: rcs0
[119.074808] Dynamic subtest rcs0: SUCCESS (3.037s)
[119.086779] Starting dynamic subtest: bcs0
[122.119768] Dynamic subtest bcs0: SUCCESS (3.044s)
[122.132710] Starting dynamic subtest: vcs0
[125.165605] Dynamic subtest vcs0: SUCCESS (3.044s)
[125.178053] Starting dynamic subtest: vcs1
[128.209623] Dynamic subtest vcs1: SUCCESS (3.042s)
[128.221088] Starting dynamic subtest: vecs0
[131.249751] Dynamic subtest vecs0: SUCCESS (3.038s)
[131.258751] Starting dynamic subtest: ccs0
[134.298056] Dynamic subtest ccs0: SUCCESS (3.047s)
[134.300556] Subtest busy-accuracy-98: SUCCESS (18.261s)
[134.421946] [037/156] (884s left) gem_exec_schedule (smoketest-all)
[134.494636] Starting subtest: smoketest-all
[166.883579] Subtest smoketest-all: SUCCESS (32.347s)
[168.364147] [038/156] (850s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt)
[168.467658] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt
[168.471736] Subtest fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[168.579899] [039/156] (850s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[168.685422] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-wc
[168.689768] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[168.794208] [040/156] (850s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-fullscreen)
[168.891740] Starting subtest: hdr-1p-primscrn-spr-indfb-fullscreen
[168.895923] Subtest hdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[168.998195] [041/156] (850s left) kms_flip (2x-busy-flip)
[169.086209] Starting subtest: 2x-busy-flip
[169.095319] Subtest 2x-busy-flip: SKIP (0.011s)
[169.203529] [042/156] (849s left) kms_frontbuffer_tracking (psr-1p-rte)
[169.314514] Starting subtest: psr-1p-rte
[174.722272] Subtest psr-1p-rte: SUCCESS (5.415s)
[174.848969] [043/156] (844s left) kms_cursor_legacy (flip-vs-cursor-toggle)
[174.963333] Starting subtest: flip-vs-cursor-toggle
[183.963051] Subtest flip-vs-cursor-toggle: SUCCESS (8.996s)
[184.775803] [044/156] (834s left) kms_frontbuffer_tracking (psr-2p-rte)
[184.884585] Starting subtest: psr-2p-rte
[184.888883] Subtest psr-2p-rte: SKIP (0.000s)
[184.968559] [045/156] (834s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[185.214772] Starting subtest: cursor-rapid-movement-256x85
[185.260088] Subtest cursor-rapid-movement-256x85: SKIP (0.042s)
[185.420486] [046/156] (833s left) syncobj_wait (wait-for-submit-snapshot)
[185.467649] Starting subtest: wait-for-submit-snapshot
[185.547724] Subtest wait-for-submit-snapshot: SUCCESS (0.081s)
[185.635950] [047/156] (833s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[185.738337] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[185.741829] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[185.858614] [048/156] (833s left) i915_hangman (hangcheck-unterminated)
[185.929034] Starting subtest: hangcheck-unterminated
[197.282191] Subtest hangcheck-unterminated: SUCCESS (11.351s)
[197.425276] [049/156] (821s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-mmap-wc)
[197.539867] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-mmap-wc
[197.543898] Subtest psrhdr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[197.641749] [050/156] (821s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[197.690714] Starting subtest: y-tiled-to-vebox-y-tiled
[197.695098] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[197.891757] [051/156] (821s left) gem_render_copy (linear-to-vebox-yf-tiled)
[197.958895] Starting subtest: linear-to-vebox-yf-tiled
[197.963615] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[198.144321] [052/156] (821s left) gem_create (busy-create)
[198.212349] Starting subtest: busy-create
[198.214720] Starting dynamic subtest: smem0
[231.291402] Dynamic subtest smem0: SUCCESS (33.076s)
[231.294246] Subtest busy-create: SUCCESS (33.077s)
[231.478069] [053/156] (788s left) kms_rotation_crc (sprite-rotation-180)
[231.586030] Starting subtest: sprite-rotation-180
[238.297485] Subtest sprite-rotation-180: SUCCESS (6.714s)
[239.545936] [054/156] (780s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[239.640087] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[239.642330] Starting dynamic subtest: pipe-A-eDP-1
[240.365272] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.718s)
[240.373846] Starting dynamic subtest: pipe-B-eDP-1
[242.144305] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.778s)
[242.153105] Starting dynamic subtest: pipe-C-eDP-1
[243.875673] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.726s)
[243.884180] Starting dynamic subtest: pipe-D-eDP-1
[245.611524] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.739s)
[245.613739] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (5.965s)
[246.772057] [055/156] (772s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[246.881383] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[246.885680] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[246.960569] [056/156] (772s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-move)
[247.064579] Starting subtest: fbchdr-1p-primscrn-cur-indfb-move
[247.068784] Subtest fbchdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[247.147745] [057/156] (772s left) kms_plane (pixel-format-linear-modifier)
[247.240161] Starting subtest: pixel-format-linear-modifier
[247.265284] Starting dynamic subtest: pipe-A-plane-0
[247.798093] Dynamic subtest pipe-A-plane-0: SUCCESS (0.533s)
[247.807239] Starting dynamic subtest: pipe-A-plane-3
[248.231518] Dynamic subtest pipe-A-plane-3: SUCCESS (0.433s)
[248.240855] Starting dynamic subtest: pipe-A-plane-5
[248.431757] Dynamic subtest pipe-A-plane-5: SUCCESS (0.199s)
[249.566729] Starting dynamic subtest: pipe-B-plane-0
[250.170623] Dynamic subtest pipe-B-plane-0: SUCCESS (0.611s)
[250.179353] Starting dynamic subtest: pipe-B-plane-3
[250.603763] Dynamic subtest pipe-B-plane-3: SUCCESS (0.433s)
[250.612682] Starting dynamic subtest: pipe-B-plane-5
[250.803378] Dynamic subtest pipe-B-plane-5: SUCCESS (0.199s)
[251.110993] Subtest pixel-format-linear-modifier: SUCCESS (3.873s)
[251.935126] [058/156] (767s left) kms_psr (fbc-pr-primary-mmap-gtt)
[252.024908] Starting subtest: fbc-pr-primary-mmap-gtt
[252.029402] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[252.105623] [059/156] (767s left) kms_addfb_basic (invalid-get-prop)
[252.153819] Starting subtest: invalid-get-prop
[252.156075] Subtest invalid-get-prop: SUCCESS (0.000s)
[252.292819] [060/156] (767s left) gem_exec_balancer (persistence)
[252.547792] Starting subtest: persistence
[253.451183] Subtest persistence: SUCCESS (0.902s)
[253.561354] [061/156] (766s left) gem_mmap_gtt (cpuset-big-copy)
[253.618318] Subtest cpuset-big-copy: SKIP (0.000s)
[253.685948] [062/156] (766s left) kms_atomic (atomic-invalid-params)
[253.786696] Starting subtest: atomic-invalid-params
[253.789042] Starting dynamic subtest: pipe-A-eDP-1
[254.177249] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.390s)
[254.179397] Subtest atomic-invalid-params: SUCCESS (0.392s)
[255.064252] [063/156] (764s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[255.192878] Starting subtest: cursorB-vs-flipA-varying-size
[255.197640] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[255.337276] [064/156] (764s left) gen7_exec_parse (basic-allowed)
[255.385108] Subtest basic-allowed: SKIP (0.000s)
[255.455865] [065/156] (764s left) kms_busy (extended-modeset-hang-oldfb)
[255.545082] Starting subtest: extended-modeset-hang-oldfb
[255.547361] Starting dynamic subtest: pipe-A
[267.329832] Dynamic subtest pipe-A: SUCCESS (11.784s)
[267.342159] Starting dynamic subtest: pipe-D
[279.886898] Dynamic subtest pipe-D: SUCCESS (12.557s)
[279.889360] Subtest extended-modeset-hang-oldfb: SUCCESS (24.345s)
[280.779660] [066/156] (739s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[280.900382] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[287.904524] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.011s)
[288.019520] [067/156] (732s left) gem_mmap_offset (partial-unmap)
[288.071827] Starting subtest: partial-unmap
[288.074200] Starting dynamic subtest: smem0
[288.556834] Dynamic subtest smem0: SUCCESS (0.485s)
[288.559937] Subtest partial-unmap: SUCCESS (0.486s)
[288.687592] [068/156] (731s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[288.799114] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[295.587303] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.795s)
[295.732870] [069/156] (724s left) kms_universal_plane (universal-plane-pageflip-windowed)
[295.825047] Starting subtest: universal-plane-pageflip-windowed
[295.827385] Starting dynamic subtest: pipe-A-eDP-1
[296.394785] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.571s)
[296.407188] Starting dynamic subtest: pipe-B-eDP-1
[297.621998] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.224s)
[297.630965] Starting dynamic subtest: pipe-C-eDP-1
[298.818236] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.197s)
[298.830503] Starting dynamic subtest: pipe-D-eDP-1
[300.050437] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.228s)
[300.052807] Subtest universal-plane-pageflip-windowed: SUCCESS (4.228s)
[300.959602] [070/156] (719s left) gem_exec_whisper (basic-fds-priority-all)
[301.031950] Starting subtest: basic-fds-priority-all
[324.492042] Subtest basic-fds-priority-all: SUCCESS (23.457s)
[324.677289] [071/156] (695s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-fullscreen)
[324.785143] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-fullscreen
[324.789108] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[324.875791] [072/156] (695s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[324.973192] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[324.977174] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[325.077486] [073/156] (695s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[326.598369] Starting subtest: gem-execbuf-stress-pc8
[326.641575] Subtest gem-execbuf-stress-pc8: SKIP (0.040s)
[327.372469] [074/156] (693s left) kms_draw_crc (draw-method-render)
[327.461137] Starting subtest: draw-method-render
[327.463265] Starting dynamic subtest: xrgb8888-untiled
[327.738561] Dynamic subtest xrgb8888-untiled: SUCCESS (0.277s)
[327.747870] Starting dynamic subtest: rgb565-untiled
[327.959277] Dynamic subtest rgb565-untiled: SUCCESS (0.222s)
[327.968267] Starting dynamic subtest: xrgb2101010-untiled
[328.182214] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.220s)
[328.192293] Starting dynamic subtest: xbgr16161616f-untiled
[328.414584] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.232s)
[328.425967] Starting dynamic subtest: xrgb8888-xtiled
[328.536502] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.119s)
[328.545859] Starting dynamic subtest: rgb565-xtiled
[328.647863] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[328.659084] Starting dynamic subtest: xrgb2101010-xtiled
[328.770076] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[328.779749] Starting dynamic subtest: xbgr16161616f-xtiled
[328.903465] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[328.913900] Starting dynamic subtest: xrgb8888-4tiled
[329.025628] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[329.035230] Starting dynamic subtest: rgb565-4tiled
[329.147792] Dynamic subtest rgb565-4tiled: SUCCESS (0.121s)
[329.159882] Starting dynamic subtest: xrgb2101010-4tiled
[329.260083] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.109s)
[329.269487] Starting dynamic subtest: xbgr16161616f-4tiled
[329.392298] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[329.394229] Subtest draw-method-render: SUCCESS (1.934s)
[329.498182] [075/156] (691s left) gem_exec_balancer (bonded-semaphore)
[329.553750] Starting subtest: bonded-semaphore
[329.557770] Subtest bonded-semaphore: SKIP (0.000s)
[329.855582] [076/156] (690s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[329.943517] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[329.945842] Starting dynamic subtest: pipe-A-default-mode
[331.121773] Dynamic subtest pipe-A-default-mode: SKIP (1.177s)
[331.125604] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.178s)
[331.267299] [077/156] (689s left) perf_pmu (multi-client)
[331.310893] Starting subtest: multi-client
[331.313059] Starting dynamic subtest: rcs0
[332.380605] Dynamic subtest rcs0: SUCCESS (1.067s)
[332.393486] Starting dynamic subtest: bcs0
[333.431394] Dynamic subtest bcs0: SUCCESS (1.048s)
[333.443409] Starting dynamic subtest: vcs0
[334.483388] Dynamic subtest vcs0: SUCCESS (1.049s)
[334.495778] Starting dynamic subtest: vcs1
[336.312762] Dynamic subtest vcs1: SUCCESS (1.826s)
[336.325254] Starting dynamic subtest: vecs0
[338.299071] Dynamic subtest vecs0: SUCCESS (1.983s)
[338.309314] Starting dynamic subtest: ccs0
[340.281787] Dynamic subtest ccs0: SUCCESS (1.981s)
[340.284417] Subtest multi-client: SUCCESS (8.970s)
[340.403906] [078/156] (680s left) gem_eio (create)
[340.595984] Starting subtest: create
[340.867354] Subtest create: SUCCESS (0.269s)
[341.001669] [079/156] (679s left) kms_big_fb (linear-16bpp-rotate-0)
[341.111990] Starting subtest: linear-16bpp-rotate-0
[342.546412] Subtest linear-16bpp-rotate-0: SUCCESS (1.434s)
[342.667354] [080/156] (678s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[342.756385] Starting subtest: addfb25-y-tiled-legacy
[342.758213] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[342.857143] [081/156] (677s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[342.904731] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[343.005061] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.101s)
[343.081682] [082/156] (677s left) kms_psr (psr2-cursor-mmap-gtt)
[343.164568] Starting subtest: psr2-cursor-mmap-gtt
[343.166554] Starting dynamic subtest: eDP-1
[344.866178] Dynamic subtest eDP-1: SUCCESS (1.702s)
[344.868434] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.702s)
[345.759276] [083/156] (675s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[345.871323] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[345.875622] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[345.972458] [084/156] (674s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[346.068319] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[346.070220] Starting dynamic subtest: pipe-A-eDP-1
[363.210643] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.536s)
[363.221891] Starting dynamic subtest: pipe-B-eDP-1
[380.168664] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.462s)
[380.181414] Starting dynamic subtest: pipe-C-eDP-1
[397.085571] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.392s)
[397.097323] Starting dynamic subtest: pipe-D-eDP-1
[414.119079] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.429s)
[414.121197] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.822s)
[415.331109] [085/156] (605s left) kms_hdr (bpc-switch-dpms)
[415.426777] Starting subtest: bpc-switch-dpms
[415.428945] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[420.908191] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (5.583s)
[420.923683] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[426.512384] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (5.652s)
[426.514852] Subtest bpc-switch-dpms: SUCCESS (11.239s)
[426.639782] [086/156] (594s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[426.746509] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[426.750479] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[426.816824] [087/156] (594s left) kms_frontbuffer_tracking (hdr-rgb565-draw-render)
[426.923238] Starting subtest: hdr-rgb565-draw-render
[426.927624] Subtest hdr-rgb565-draw-render: SKIP (0.000s)
[427.026924] [088/156] (594s left) kms_plane_scaling (planes-upscale-20x20)
[427.110692] Starting subtest: planes-upscale-20x20
[427.112893] Starting dynamic subtest: pipe-A
[427.170511] Dynamic subtest pipe-A: SUCCESS (0.062s)
[427.182710] Starting dynamic subtest: pipe-B
[428.286962] Dynamic subtest pipe-B: SUCCESS (1.122s)
[428.300017] Starting dynamic subtest: pipe-C
[429.391714] Dynamic subtest pipe-C: SUCCESS (1.108s)
[429.406131] Starting dynamic subtest: pipe-D
[430.521443] Dynamic subtest pipe-D: SUCCESS (1.134s)
[430.523822] Subtest planes-upscale-20x20: SUCCESS (3.430s)
[431.688467] [089/156] (589s left) kms_lease (lease-revoke)
[431.787836] Starting subtest: lease-revoke
[431.790191] Starting dynamic subtest: pipe-A-eDP-1
[431.818756] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.031s)
[431.830681] Starting dynamic subtest: pipe-B-eDP-1
[431.848071] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.029s)
[431.858522] Starting dynamic subtest: pipe-C-eDP-1
[431.876493] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.027s)
[431.887234] Starting dynamic subtest: pipe-D-eDP-1
[431.906644] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.026s)
[431.908909] Subtest lease-revoke: SUCCESS (0.120s)
[432.029203] [090/156] (589s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[432.117566] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[432.119874] Starting dynamic subtest: pipe-A-eDP-1
[432.140393] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.022s)
[432.151527] Starting dynamic subtest: pipe-B-eDP-1
[432.171706] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.027s)
[432.181945] Starting dynamic subtest: pipe-C-eDP-1
[432.202003] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.021s)
[432.212244] Starting dynamic subtest: pipe-D-eDP-1
[432.233036] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.021s)
[432.235352] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.096s)
[434.531840] [091/156] (586s left) kms_getfb (getfb-handle-closed)
[434.622391] Starting subtest: getfb-handle-closed
[434.624542] Subtest getfb-handle-closed: SUCCESS (0.000s)
[434.730506] [092/156] (586s left) kms_frontbuffer_tracking (fbc-2p-rte)
[434.832467] Starting subtest: fbc-2p-rte
[434.836465] Subtest fbc-2p-rte: SKIP (0.000s)
[434.938547] [093/156] (586s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-pwrite)
[435.041688] Starting subtest: fbcpsrhdr-rgb101010-draw-pwrite
[435.045510] Subtest fbcpsrhdr-rgb101010-draw-pwrite: SKIP (0.000s)
[435.154227] [094/156] (586s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[435.251834] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[435.255728] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[435.355045] [095/156] (585s left) gem_softpin (softpin)
[435.405115] Starting subtest: softpin
[467.649123] Subtest softpin: SUCCESS (32.256s)
[467.762319] [096/156] (553s left) kms_cursor_crc (cursor-offscreen-128x42)
[467.999935] Starting subtest: cursor-offscreen-128x42
[468.049242] Subtest cursor-offscreen-128x42: SKIP (0.042s)
[468.237743] [097/156] (553s left) kms_properties (crtc-properties-legacy)
[468.324466] Starting subtest: crtc-properties-legacy
[468.326804] Starting dynamic subtest: pipe-A-eDP-1
[468.757492] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.433s)
[468.768046] Starting dynamic subtest: pipe-B-eDP-1
[469.965065] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.207s)
[469.976242] Starting dynamic subtest: pipe-C-eDP-1
[471.087346] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.120s)
[471.101733] Starting dynamic subtest: pipe-D-eDP-1
[472.228379] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.138s)
[472.230314] Subtest crtc-properties-legacy: SUCCESS (3.905s)
[473.033177] [098/156] (548s left) kms_chamelium_hpd (hdmi-hpd)
[473.416510] Subtest hdmi-hpd: SKIP (0.000s)
[474.243268] [099/156] (547s left) kms_pm_rpm (dpms-non-lpsp)
[475.865381] Starting subtest: dpms-non-lpsp
[475.870337] Subtest dpms-non-lpsp: SKIP (0.001s)
[476.502021] [100/156] (545s left) gem_exec_schedule (u-semaphore-noskip)
[476.581870] Starting subtest: u-semaphore-noskip
[476.992985] Subtest u-semaphore-noskip: SUCCESS (0.415s)
[477.334864] [101/156] (544s left) prime_busy (hang-wait)
[477.391931] Starting subtest: hang-wait
[477.394290] Starting dynamic subtest: rcs0
[503.162025] Dynamic subtest rcs0: SUCCESS (25.776s)
[503.172054] Starting dynamic subtest: bcs0
[510.322484] Dynamic subtest bcs0: SUCCESS (7.159s)
[510.332283] Starting dynamic subtest: vcs0
[517.293270] Dynamic subtest vcs0: SUCCESS (6.967s)
[517.306295] Starting dynamic subtest: vcs1
[524.335145] Dynamic subtest vcs1: SUCCESS (7.034s)
[524.345601] Starting dynamic subtest: vecs0
[531.304395] Dynamic subtest vecs0: SUCCESS (6.966s)
[531.314680] Starting dynamic subtest: ccs0
[545.209984] Dynamic subtest ccs0: SUCCESS (13.899s)
[545.212160] Subtest hang-wait: SUCCESS (67.815s)
[545.338203] [102/156] (476s left) gem_basic (multigpu-create-close)
[545.396277] Starting subtest: multigpu-create-close
[545.400631] Subtest multigpu-create-close: SKIP (0.000s)
[545.487413] [103/156] (476s left) kms_flip (2x-plain-flip-interruptible)
[545.579580] Starting subtest: 2x-plain-flip-interruptible
[545.591114] Subtest 2x-plain-flip-interruptible: SKIP (0.010s)
[545.662866] [104/156] (476s left) kms_plane_alpha_blend (constant-alpha-mid)
[545.759375] Starting subtest: constant-alpha-mid
[546.031768] Starting dynamic subtest: pipe-A-eDP-1
[546.947483] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.913s)
[546.958348] Starting dynamic subtest: pipe-D-eDP-1
[548.446435] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.499s)
[548.448999] Subtest constant-alpha-mid: SUCCESS (2.689s)
[549.337684] [105/156] (472s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[549.433014] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[549.437097] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[549.514199] [106/156] (472s left) drm_read (short-buffer-wakeup)
[549.648510] Starting subtest: short-buffer-wakeup
[581.861765] Subtest short-buffer-wakeup: SUCCESS (32.216s)
[582.006554] [107/156] (439s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[582.114675] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[583.093420] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (0.979s)
[583.216830] [108/156] (438s left) syncobj_wait (single-wait-submitted)
[583.274158] Starting subtest: single-wait-submitted
[583.379232] Subtest single-wait-submitted: SUCCESS (0.105s)
[583.468976] [109/156] (438s left) kms_scaling_modes (scaling-mode-full)
[583.559554] Starting subtest: scaling-mode-full
[583.561878] Starting dynamic subtest: pipe-A-eDP-1
[583.648822] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.091s)
[583.661500] Starting dynamic subtest: pipe-B-eDP-1
[584.857495] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.206s)
[584.869117] Starting dynamic subtest: pipe-C-eDP-1
[585.985130] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.127s)
[585.996907] Starting dynamic subtest: pipe-D-eDP-1
[587.109857] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.123s)
[587.112084] Subtest scaling-mode-full: SUCCESS (3.554s)
[588.282198] [110/156] (433s left) sysfs_timeslice_duration (invalid)
[588.332058] Starting subtest: invalid
[588.334313] Starting dynamic subtest: vecs0
[588.336677] Dynamic subtest vecs0: SUCCESS (0.000s)
[588.346966] Starting dynamic subtest: ccs0
[588.348861] Dynamic subtest ccs0: SUCCESS (0.000s)
[588.358861] Starting dynamic subtest: vcs0
[588.361115] Dynamic subtest vcs0: SUCCESS (0.000s)
[588.371061] Starting dynamic subtest: bcs0
[588.373051] Dynamic subtest bcs0: SUCCESS (0.000s)
[588.382466] Starting dynamic subtest: rcs0
[588.384373] Dynamic subtest rcs0: SUCCESS (0.000s)
[588.393920] Starting dynamic subtest: vcs1
[588.395884] Dynamic subtest vcs1: SUCCESS (0.000s)
[588.397834] Subtest invalid: SUCCESS (0.006s)
[588.435517] [111/156] (433s left) syncobj_timeline (etime-single-wait-for-submit-available-unsubmitted)
[588.478342] Starting subtest: etime-single-wait-for-submit-available-unsubmitted
[588.587172] Subtest etime-single-wait-for-submit-available-unsubmitted: SUCCESS (0.108s)
[588.664666] [112/156] (433s left) gem_exec_reloc (basic-wc)
[588.723613] Subtest basic-wc: SKIP (0.000s)
[588.788957] [113/156] (433s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[588.853891] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[588.925356] [114/156] (433s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[589.028239] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[589.033632] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[589.114065] [115/156] (433s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[589.210561] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[589.212820] Starting dynamic subtest: pipe-A-eDP-1
[589.236017] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[589.248776] Starting dynamic subtest: pipe-B-eDP-1
[589.269999] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[589.279983] Starting dynamic subtest: pipe-C-eDP-1
[589.300706] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[589.310791] Starting dynamic subtest: pipe-D-eDP-1
[589.333992] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[589.338356] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[591.534386] [116/156] (430s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[591.636797] Starting subtest: fbc-farfromfence-mmap-gtt
[591.641048] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[591.734234] [117/156] (430s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[591.840602] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[591.844601] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[591.939283] [118/156] (430s left) i915_suspend (basic-s2idle-without-i915)
[591.974147] Starting subtest: basic-s2idle-without-i915
[614.384400] Subtest basic-s2idle-without-i915: SUCCESS (6.996s)
[614.513695] [119/156] (407s left) gem_exec_schedule (implicit-boths)
[614.600606] Starting subtest: implicit-boths
[614.602978] Starting dynamic subtest: rcs0
[614.704593] Dynamic subtest rcs0: SUCCESS (0.113s)
[614.717540] Starting dynamic subtest: vcs0
[614.790452] Dynamic subtest vcs0: SUCCESS (0.089s)
[614.801538] Starting dynamic subtest: vcs1
[614.869960] Dynamic subtest vcs1: SUCCESS (0.081s)
[614.882797] Starting dynamic subtest: vecs0
[614.947359] Dynamic subtest vecs0: SUCCESS (0.079s)
[614.959081] Starting dynamic subtest: ccs0
[615.029151] Dynamic subtest ccs0: SUCCESS (0.083s)
[615.031757] Subtest implicit-boths: SUCCESS (0.449s)
[616.286311] [120/156] (406s left) gem_ctx_param (invalid-ctx-set)
[616.340091] Starting subtest: invalid-ctx-set
[616.342542] Subtest invalid-ctx-set: SUCCESS (0.001s)
[616.442654] [121/156] (405s left) gem_exec_reloc (basic-gtt-noreloc)
[616.502856] Subtest basic-gtt-noreloc: SKIP (0.000s)
[616.580841] [122/156] (405s left) kms_cursor_edge_walk (256x256-top-bottom)
[616.672207] Starting subtest: 256x256-top-bottom
[616.674287] Starting dynamic subtest: pipe-A-eDP-1
[620.039805] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.425s)
[620.049952] Starting dynamic subtest: pipe-D-eDP-1
[623.137400] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.134s)
[623.139653] Subtest 256x256-top-bottom: SUCCESS (6.561s)
[623.936623] [123/156] (398s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[624.468917] Starting subtest: syncobj-timeline-multiple-ext-nodes
[624.471503] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.003s)
[626.254750] [124/156] (396s left) api_intel_allocator (alloc-simple)
[626.314555] Starting subtest: alloc-simple
[626.317181] Subtest alloc-simple: SUCCESS (0.005s)
[626.447509] [125/156] (396s left) gem_shrink (reclaim)
[626.495245] Starting subtest: reclaim
[628.877824] Subtest reclaim: SUCCESS (2.390s)
[629.030846] [126/156] (393s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[629.128081] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[629.132241] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[629.210636] [127/156] (393s left) kms_psr (fbc-psr2-primary-page-flip)
[629.294925] Starting subtest: fbc-psr2-primary-page-flip
[629.297219] Starting dynamic subtest: eDP-1
[629.301381] Dynamic subtest eDP-1: SKIP (0.000s)
[629.305585] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[629.369665] [128/156] (393s left) kms_force_connector_basic (force-edid)
[629.438530] Starting subtest: force-edid
[629.808204] Subtest force-edid: SUCCESS (0.371s)
[629.958176] [129/156] (392s left) syncobj_timeline (wait-any-snapshot)
[630.003570] Starting subtest: wait-any-snapshot
[630.084917] Subtest wait-any-snapshot: SUCCESS (0.082s)
[630.158048] [130/156] (392s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-move)
[630.246181] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-move
[630.249491] Subtest psrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[630.361142] [131/156] (392s left) gem_ctx_param (invalid-get-ringsize)
[630.418790] Starting subtest: invalid-get-ringsize
[630.420928] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[630.544050] [132/156] (392s left) gem_sync (basic-store-all)
[630.614290] Starting subtest: basic-store-all
[633.609638] Subtest basic-store-all: SUCCESS (3.012s)
[633.800118] [133/156] (389s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[633.851594] Starting subtest: multi-wait-all-for-submit-available-signaled
[633.853979] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[633.968603] [134/156] (388s left) kms_prime (d3hot)
[634.053748] Subtest D3hot: SKIP (0.000s)
[634.167699] [135/156] (388s left) kms_psr (psr2-primary-mmap-cpu)
[634.263152] Starting subtest: psr2-primary-mmap-cpu
[634.265455] Starting dynamic subtest: eDP-1
[635.970475] Dynamic subtest eDP-1: SUCCESS (1.710s)
[635.972468] Subtest psr2-primary-mmap-cpu: SUCCESS (1.711s)
[636.802429] [136/156] (386s left) kms_frontbuffer_tracking (fbchdr-farfromfence-mmap-gtt)
[636.895110] Starting subtest: fbchdr-farfromfence-mmap-gtt
[636.899249] Subtest fbchdr-farfromfence-mmap-gtt: SKIP (0.000s)
[637.012352] [137/156] (386s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[637.110281] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[637.118522] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[637.254794] [138/156] (385s left) sw_sync (alloc_fence_invalid_timeline)
[637.294340] Starting subtest: alloc_fence_invalid_timeline
[637.296557] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[637.365123] [139/156] (385s left) gem_partial_pwrite_pread (write-snoop)
[637.424353] Subtest write-snoop: SKIP (0.000s)
[637.491447] [140/156] (385s left) kms_psr (fbc-psr-cursor-mmap-gtt)
[637.582102] Starting subtest: fbc-psr-cursor-mmap-gtt
[637.584342] Starting dynamic subtest: eDP-1
[637.588642] Dynamic subtest eDP-1: SKIP (0.000s)
[637.592856] Subtest fbc-psr-cursor-mmap-gtt: SKIP (0.001s)
[637.653944] [141/156] (385s left) gem_exec_schedule (pi-userfault)
[637.823926] Starting subtest: pi-userfault
[637.826457] Starting dynamic subtest: rcs0
[637.832258] Dynamic subtest rcs0: SUCCESS (0.010s)
[637.844621] Starting dynamic subtest: bcs0
[637.846874] Dynamic subtest bcs0: SUCCESS (0.011s)
[637.856834] Starting dynamic subtest: vcs0
[637.858754] Dynamic subtest vcs0: SUCCESS (0.009s)
[637.868348] Starting dynamic subtest: vcs1
[637.870543] Dynamic subtest vcs1: SUCCESS (0.010s)
[637.880286] Starting dynamic subtest: vecs0
[637.882371] Dynamic subtest vecs0: SUCCESS (0.007s)
[637.891703] Starting dynamic subtest: ccs0
[637.893845] Dynamic subtest ccs0: SUCCESS (0.008s)
[637.895964] Subtest pi-userfault: SUCCESS (0.059s)
[638.063101] [142/156] (385s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[638.205218] Starting subtest: dp-mst-type-1-suspend-resume
[638.209723] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[638.335462] [143/156] (384s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[638.436086] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[638.440460] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[638.543708] [144/156] (384s left) gem_spin_batch (legacy)
[638.601167] Starting subtest: legacy
[638.603492] Starting dynamic subtest: default
[641.839647] Dynamic subtest default: SUCCESS (3.237s)
[641.852606] Starting dynamic subtest: render
[645.073828] Dynamic subtest render: SUCCESS (3.235s)
[645.085606] Starting dynamic subtest: bsd
[648.314326] Dynamic subtest bsd: SUCCESS (3.236s)
[648.326288] Starting dynamic subtest: bsd1
[651.550168] Dynamic subtest bsd1: SUCCESS (3.231s)
[651.560515] Starting dynamic subtest: bsd2
[654.790048] Dynamic subtest bsd2: SUCCESS (3.234s)
[654.800922] Starting dynamic subtest: blt
[658.027719] Dynamic subtest blt: SUCCESS (3.233s)
[658.041115] Starting dynamic subtest: vebox
[661.267440] Dynamic subtest vebox: SUCCESS (3.237s)
[661.269628] Subtest legacy: SUCCESS (22.667s)
[663.226440] [145/156] (360s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[663.331350] Starting subtest: 4-tiled-64bpp-rotate-180
[664.966852] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.636s)
[665.157777] [146/156] (358s left) i915_query (query-topology-kernel-writes)
[665.217533] Starting subtest: query-topology-kernel-writes
[665.219353] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[665.326448] [147/156] (358s left) gem_ctx_persistence (heartbeat-many)
[665.382200] Starting subtest: heartbeat-many
[665.385729] Subtest heartbeat-many: SKIP (0.000s)
[665.473712] [148/156] (358s left) gem_madvise (dontneed-after-mmap)
[665.509189] Starting subtest: dontneed-after-mmap
[665.530060] Subtest dontneed-after-mmap: SUCCESS (0.023s)
[665.629104] [149/156] (357s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[665.723864] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[665.725724] Starting dynamic subtest: pipe-A-eDP-1
[665.775857] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[665.787193] Starting dynamic subtest: pipe-B-eDP-1
[665.834665] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[665.844895] Starting dynamic subtest: pipe-C-eDP-1
[665.891881] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[665.902105] Starting dynamic subtest: pipe-D-eDP-1
[665.948837] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[665.952685] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[667.995576] [150/156] (355s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[668.093960] Starting subtest: y-tiled-32bpp-rotate-270
[668.098336] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[668.189472] [151/156] (355s left) gem_lmem_swapping (heavy-verify-multi)
[668.227972] Subtest heavy-verify-multi: SKIP (0.000s)
[668.271803] [152/156] (355s left) gem_eio (wait-wedge-immediate)
[668.479763] Starting subtest: wait-wedge-immediate
[670.265244] Subtest wait-wedge-immediate: SUCCESS (1.786s)
[670.382417] [153/156] (353s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[670.432616] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[670.495374] [154/156] (353s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[670.583980] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[670.586280] Starting dynamic subtest: pipe-A-eDP-1
[670.716639] Dynamic subtest pipe-A-eDP-1: SKIP (0.134s)
[670.727204] Starting dynamic subtest: pipe-B-eDP-1
[671.946088] Dynamic subtest pipe-B-eDP-1: SKIP (1.227s)
[671.950614] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.364s)
[673.198103] [155/156] (350s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-indfb-pgflip-blt)
[673.299845] Starting subtest: hdr-2p-scndscrn-indfb-pgflip-blt
[673.304215] Subtest hdr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[673.387848] [156/156] (350s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[673.487287] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[673.491390] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[673.571018] Closing watchdogs
results: parsing output: 0/ for test: kms_plane_scaling
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_addfb_basic
results: parsing output: 5/ for test: kms_lease
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: prime_vgem
results: parsing output: 9/ for test: syncobj_wait
results: parsing output: 10/ for test: kms_color
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: gen9_exec_parse
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: gem_mmap_wc
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: i915_drm_fdinfo
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_vblank
results: parsing output: 21/ for test: kms_dp_link_training
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: syncobj_wait
results: parsing output: 24/ for test: kms_flip
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_addfb_basic
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: gem_userptr_blits
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_properties
results: parsing output: 31/ for test: gem_reg_read
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_chamelium_color
results: parsing output: 34/ for test: api_intel_bb
results: parsing output: 35/ for test: perf_pmu
results: parsing output: 36/ for test: gem_exec_schedule
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_flip
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_cursor_legacy
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: syncobj_wait
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: i915_hangman
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_render_copy
results: parsing output: 50/ for test: gem_render_copy
results: parsing output: 51/ for test: gem_create
results: parsing output: 52/ for test: kms_rotation_crc
results: parsing output: 53/ for test: kms_ccs
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_plane
results: parsing output: 57/ for test: kms_psr
results: parsing output: 58/ for test: kms_addfb_basic
results: parsing output: 59/ for test: gem_exec_balancer
results: parsing output: 60/ for test: gem_mmap_gtt
results: parsing output: 61/ for test: kms_atomic
results: parsing output: 62/ for test: kms_cursor_legacy
results: parsing output: 63/ for test: gen7_exec_parse
results: parsing output: 64/ for test: kms_busy
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_mmap_offset
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_universal_plane
results: parsing output: 69/ for test: gem_exec_whisper
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_pm_rpm
results: parsing output: 73/ for test: kms_draw_crc
results: parsing output: 74/ for test: gem_exec_balancer
results: parsing output: 75/ for test: kms_flip_scaled_crc
results: parsing output: 76/ for test: perf_pmu
results: parsing output: 77/ for test: gem_eio
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_addfb_basic
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: kms_psr
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: kms_hdr
results: parsing output: 85/ for test: kms_psr2_sf
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_plane_scaling
results: parsing output: 88/ for test: kms_lease
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: kms_getfb
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_softpin
results: parsing output: 95/ for test: kms_cursor_crc
results: parsing output: 96/ for test: kms_properties
results: parsing output: 97/ for test: kms_chamelium_hpd
results: parsing output: 98/ for test: kms_pm_rpm
results: parsing output: 99/ for test: gem_exec_schedule
results: parsing output: 100/ for test: prime_busy
results: parsing output: 101/ for test: gem_basic
results: parsing output: 102/ for test: kms_flip
results: parsing output: 103/ for test: kms_plane_alpha_blend
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: drm_read
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: syncobj_wait
results: parsing output: 108/ for test: kms_scaling_modes
results: parsing output: 109/ for test: sysfs_timeslice_duration
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: gem_exec_reloc
results: parsing output: 112/ for test: gem_exec_reloc
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: i915_suspend
results: parsing output: 118/ for test: gem_exec_schedule
results: parsing output: 119/ for test: gem_ctx_param
results: parsing output: 120/ for test: gem_exec_reloc
results: parsing output: 121/ for test: kms_cursor_edge_walk
results: parsing output: 122/ for test: gem_exec_fence
results: parsing output: 123/ for test: api_intel_allocator
results: parsing output: 124/ for test: gem_shrink
results: parsing output: 125/ for test: kms_plane
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: kms_force_connector_basic
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_ctx_param
results: parsing output: 131/ for test: gem_sync
results: parsing output: 132/ for test: syncobj_timeline
results: parsing output: 133/ for test: kms_prime
results: parsing output: 134/ for test: kms_psr
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_flip
results: parsing output: 137/ for test: sw_sync
results: parsing output: 138/ for test: gem_partial_pwrite_pread
results: parsing output: 139/ for test: kms_psr
results: parsing output: 140/ for test: gem_exec_schedule
results: parsing output: 141/ for test: kms_content_protection
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: gem_spin_batch
results: parsing output: 144/ for test: kms_big_fb
results: parsing output: 145/ for test: i915_query
results: parsing output: 146/ for test: gem_ctx_persistence
results: parsing output: 147/ for test: gem_madvise
results: parsing output: 148/ for test: kms_ccs
results: parsing output: 149/ for test: kms_big_fb
results: parsing output: 150/ for test: gem_lmem_swapping
results: parsing output: 151/ for test: gem_eio
results: parsing output: 152/ for test: kms_pipe_b_c_ivb
results: parsing output: 153/ for test: kms_psr2_sf
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.