[57.000513] Initializing watchdogs
[57.000567]   /dev/watchdog0
[57.014831] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.019164] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.021283] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.024717] [001/156] (960s left) gem_close (basic)
[57.101962] Starting subtest: basic
[57.104249] Subtest basic: SUCCESS (0.000s)
[57.197043] [002/156] (960s left) syncobj_timeline (multi-wait-all-available-signaled)
[57.250168] Starting subtest: multi-wait-all-available-signaled
[57.252392] Subtest multi-wait-all-available-signaled: SUCCESS (0.001s)
[57.363758] [003/156] (960s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[57.466070] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[57.470125] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[57.567234] [004/156] (960s left) kms_plane_multiple (tiling-y)
[57.664256] Starting subtest: tiling-y
[57.668599] Subtest tiling-y: SKIP (0.000s)
[57.731796] [005/156] (959s left) perf_pmu (busy-idle-check-all)
[57.770671] Starting subtest: busy-idle-check-all
[57.772863] Starting dynamic subtest: rcs0
[58.426831] Dynamic subtest rcs0: SUCCESS (0.666s)
[58.436092] Starting dynamic subtest: bcs0
[59.077734] Dynamic subtest bcs0: SUCCESS (0.658s)
[59.087681] Starting dynamic subtest: vcs0
[60.290509] Dynamic subtest vcs0: SUCCESS (1.225s)
[60.301252] Starting dynamic subtest: vcs1
[62.276721] Dynamic subtest vcs1: SUCCESS (2.002s)
[62.287812] Starting dynamic subtest: vecs0
[64.323756] Dynamic subtest vecs0: SUCCESS (2.060s)
[64.334065] Starting dynamic subtest: ccs0
[64.978285] Dynamic subtest ccs0: SUCCESS (0.659s)
[64.980713] Subtest busy-idle-check-all: SUCCESS (7.276s)
[65.069920] [006/156] (952s left) syncobj_timeline (signal-point-0)
[65.121859] Starting subtest: signal-point-0
[65.123815] Subtest signal-point-0: SUCCESS (0.000s)
[65.234640] [007/156] (952s left) kms_scaling_modes (scaling-mode-full-aspect)
[65.329326] Starting subtest: scaling-mode-full-aspect
[65.331472] Starting dynamic subtest: pipe-A-eDP-1
[65.448762] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.121s)
[65.461612] Starting dynamic subtest: pipe-B-eDP-1
[66.604645] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.160s)
[66.615364] Starting dynamic subtest: pipe-C-eDP-1
[67.807350] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.206s)
[67.816564] Starting dynamic subtest: pipe-D-eDP-1
[68.938791] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.133s)
[68.941203] Subtest scaling-mode-full-aspect: SUCCESS (3.627s)
[70.097714] [008/156] (947s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[70.190841] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[70.194881] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[70.279474] [009/156] (947s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[70.376928] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[70.380930] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[70.474016] [010/156] (947s left) gem_exec_fence (syncobj-timeline-wait)
[71.008969] Starting subtest: syncobj-timeline-wait
[72.373389] Subtest syncobj-timeline-wait: SUCCESS (1.368s)
[72.908889] [FACT gem_exec_fence (syncobj-timeline-wait)] new: kernel.kmod_is_loaded.vgem: true
[72.912971] [011/156] (945s left) i915_pciid
[73.258902] [012/156] (944s left) kms_chamelium_color (ctm-limited-range)
[73.418166] Subtest ctm-limited-range: SKIP (0.000s)
[73.464833] [013/156] (944s left) kms_color (legacy-gamma-reset)
[73.604378] Starting subtest: legacy-gamma-reset
[73.606413] Starting dynamic subtest: pipe-A-eDP-1
[73.973406] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.372s)
[73.981301] Starting dynamic subtest: pipe-B-eDP-1
[73.986351] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.011s)
[73.993694] Starting dynamic subtest: pipe-C-eDP-1
[73.995972] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[74.003561] Starting dynamic subtest: pipe-D-eDP-1
[74.005440] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[74.007674] Subtest legacy-gamma-reset: SUCCESS (0.405s)
[74.822818] [014/156] (943s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt)
[74.915742] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt
[74.919842] Subtest fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[75.003057] [015/156] (943s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[75.084744] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[75.086983] Starting dynamic subtest: pipe-A-default-mode
[77.864026] Dynamic subtest pipe-A-default-mode: SUCCESS (2.782s)
[77.866390] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.782s)
[78.729305] [016/156] (939s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[78.838513] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[78.842755] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[78.931482] [017/156] (939s left) gem_ctx_sseu (engines)
[78.994181] Subtest engines: SKIP (0.000s)
[79.077589] [018/156] (939s left) gem_pxp (fail-invalid-protected-context)
[79.201365] Starting subtest: fail-invalid-protected-context
[79.203545] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[79.320187] [019/156] (938s left) gem_exec_params (no-vebox)
[79.373748] Starting subtest: no-vebox
[79.378356] Subtest no-vebox: SUCCESS (0.003s)
[81.341014] [020/156] (936s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[83.496777] Starting subtest: modeset-non-lpsp-stress-no-wait
[83.560572] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.063s)
[83.894222] [021/156] (934s left) i915_pm_rps (thresholds-idle-park)
[83.962515] Starting subtest: thresholds-idle-park
[83.966836] Subtest thresholds-idle-park: SKIP (0.001s)
[84.073835] [022/156] (934s left) gem_lmem_swapping (verify-random-ccs)
[84.119778] Subtest verify-random-ccs: SKIP (0.000s)
[84.158620] [023/156] (934s left) gem_eio (in-flight-internal-1us)
[84.360960] Starting subtest: in-flight-internal-1us
[85.373068] Subtest in-flight-internal-1us: SUCCESS (1.010s)
[85.497556] [024/156] (932s left) gem_basic (bad-close)
[85.552150] Starting subtest: bad-close
[85.556848] Subtest bad-close: SUCCESS (0.000s)
[85.638579] [025/156] (932s left) kms_flip (basic-plain-flip)
[85.727295] Starting subtest: basic-plain-flip
[85.729637] Starting dynamic subtest: A-eDP1
[87.401837] Dynamic subtest A-eDP1: SUCCESS (1.674s)
[87.412952] Starting dynamic subtest: B-eDP1
[89.045674] Dynamic subtest B-eDP1: SUCCESS (1.643s)
[89.055698] Starting dynamic subtest: C-eDP1
[90.674404] Dynamic subtest C-eDP1: SUCCESS (1.628s)
[90.685224] Starting dynamic subtest: D-eDP1
[92.315071] Dynamic subtest D-eDP1: SUCCESS (1.640s)
[92.317592] Subtest basic-plain-flip: SUCCESS (6.590s)
[93.486047] [026/156] (925s left) kms_hdmi_inject (inject-audio)
[93.830620] Starting subtest: inject-audio
[94.123070] Subtest inject-audio: SUCCESS (0.295s)
[94.753879] [027/156] (923s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[94.966039] Starting subtest: cursor-rapid-movement-128x42
[95.010674] Subtest cursor-rapid-movement-128x42: SKIP (0.040s)
[95.211838] [028/156] (923s left) kms_invalid_mode (bad-vtotal)
[95.315933] Starting subtest: bad-vtotal
[95.318188] Starting dynamic subtest: pipe-A-eDP-1
[95.604333] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[95.613307] Starting dynamic subtest: pipe-B-eDP-1
[95.615164] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[95.623447] Starting dynamic subtest: pipe-C-eDP-1
[95.625845] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[95.634050] Starting dynamic subtest: pipe-D-eDP-1
[95.636267] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[95.638529] Subtest bad-vtotal: SUCCESS (0.293s)
[96.416814] [029/156] (922s left) kms_cursor_crc (cursor-size-change)
[96.500238] Starting subtest: cursor-size-change
[96.502199] Starting dynamic subtest: pipe-A-eDP-1
[97.303810] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.803s)
[97.313990] Starting dynamic subtest: pipe-D-eDP-1
[98.748759] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.443s)
[98.751117] Subtest cursor-size-change: SUCCESS (2.249s)
[99.627652] [030/156] (919s left) gem_softpin (reverse)
[99.680992] Starting subtest: reverse
[99.688295] Subtest reverse: SUCCESS (0.007s)
[99.802456] [031/156] (918s left) syncobj_basic (bad-flags-fd-to-handle)
[99.847770] Starting subtest: bad-flags-fd-to-handle
[99.849546] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[99.960832] [032/156] (918s left) syncobj_eventfd (invalid-bad-flags)
[100.008969] Starting subtest: invalid-bad-flags
[100.011426] Subtest invalid-bad-flags: SUCCESS (0.000s)
[100.107969] [033/156] (918s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[100.161019] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[100.261897] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[100.337256] [034/156] (918s left) gem_ctx_persistence (legacy-engines-persistence)
[100.396269] Starting subtest: legacy-engines-persistence
[100.398603] Starting dynamic subtest: render
[101.207246] Dynamic subtest render: SUCCESS (0.810s)
[101.219116] Starting dynamic subtest: bsd1
[101.929098] Dynamic subtest bsd1: SUCCESS (0.720s)
[101.938665] Starting dynamic subtest: bsd2
[102.716208] Dynamic subtest bsd2: SUCCESS (0.785s)
[102.728561] Starting dynamic subtest: blt
[103.492790] Dynamic subtest blt: SUCCESS (0.775s)
[103.504080] Starting dynamic subtest: vebox
[104.256948] Dynamic subtest vebox: SUCCESS (0.762s)
[104.259721] Subtest legacy-engines-persistence: SUCCESS (3.861s)
[104.336806] [035/156] (914s left) gem_mmap_offset (mmap-unmap)
[104.382293] Starting subtest: mmap-unmap
[104.384268] Starting dynamic subtest: smem0
[127.672096] Dynamic subtest smem0: SUCCESS (23.238s)
[127.674523] Subtest mmap-unmap: SUCCESS (23.239s)
[127.727261] [036/156] (891s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[127.796119] Starting subtest: stress-mm-invalidate-close-overlap
[129.997578] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[130.074350] [037/156] (888s left) kms_vblank (query-idle)
[130.172195] Starting subtest: query-idle
[130.174549] Starting dynamic subtest: pipe-A-eDP-1
[131.915038] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.742s)
[131.930914] Starting dynamic subtest: pipe-D-eDP-1
[134.338102] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.420s)
[134.340481] Subtest query-idle: SUCCESS (4.165s)
[135.213897] [038/156] (883s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[135.313092] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[142.514745] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.208s)
[142.646121] [039/156] (876s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[142.699324] Subtest tiled-to-untiled: SKIP (0.000s)
[142.764790] [040/156] (876s left) i915_pm_rc6_residency (rc6-accuracy)
[142.811291] Starting subtest: rc6-accuracy
[142.813598] Starting dynamic subtest: gt0
[145.982519] Dynamic subtest gt0: SUCCESS (3.169s)
[145.992079] Starting dynamic subtest: gt1
[149.204188] Dynamic subtest gt1: SUCCESS (3.169s)
[149.206169] Subtest rc6-accuracy: SUCCESS (6.342s)
[149.244008] [041/156] (869s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[149.299580] Starting subtest: yf-tiled-ccs-to-yf-tiled
[149.304055] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[149.485968] [042/156] (869s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[149.594361] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[149.598596] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[149.692052] [043/156] (869s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[149.801032] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[153.476962] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.681s)
[154.667354] [044/156] (864s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-msflip-blt)
[154.759980] Starting subtest: hdr-2p-scndscrn-shrfb-msflip-blt
[154.764257] Subtest hdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[154.861106] [045/156] (864s left) gem_ctx_param (invalid-get-engines)
[154.916940] Starting subtest: invalid-get-engines
[154.919441] Subtest invalid-get-engines: SUCCESS (0.001s)
[155.028248] [046/156] (864s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[155.128878] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[155.133357] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[155.218781] [047/156] (864s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[155.322288] Starting subtest: y-tiled-32bpp-rotate-0
[155.326309] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[155.411212] [048/156] (864s left) kms_hdr (static-toggle-dpms)
[155.503717] Starting subtest: static-toggle-dpms
[155.506040] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[155.510142] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[155.519073] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[155.523514] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[155.527943] Subtest static-toggle-dpms: SKIP (0.002s)
[155.573280] [049/156] (863s left) gem_mmap_gtt (basic-small-bo)
[155.631028] Subtest basic-small-bo: SKIP (0.000s)
[155.695246] [050/156] (863s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[155.781843] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[155.785629] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[155.886635] [051/156] (863s left) gem_exec_nop (basic-parallel)
[155.963794] Starting subtest: basic-parallel
[171.331223] Subtest basic-parallel: SUCCESS (15.373s)
[171.578782] [052/156] (848s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[171.676350] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[171.680442] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[171.758302] [053/156] (847s left) gem_exec_fence (parallel)
[171.931885] Starting subtest: parallel
[171.934119] Starting dynamic subtest: rcs0
[174.083619] Dynamic subtest rcs0: SUCCESS (2.152s)
[174.093216] Starting dynamic subtest: bcs0
[176.236310] Dynamic subtest bcs0: SUCCESS (2.152s)
[176.248236] Starting dynamic subtest: vcs0
[178.386993] Dynamic subtest vcs0: SUCCESS (2.150s)
[178.397016] Starting dynamic subtest: vcs1
[180.544497] Dynamic subtest vcs1: SUCCESS (2.157s)
[180.556246] Starting dynamic subtest: vecs0
[182.694051] Dynamic subtest vecs0: SUCCESS (2.149s)
[182.706159] Starting dynamic subtest: ccs0
[184.844956] Dynamic subtest ccs0: SUCCESS (2.150s)
[184.847094] Subtest parallel: SUCCESS (12.913s)
[185.410856] [054/156] (834s left) kms_psr (psr2-cursor-mmap-cpu)
[185.511064] Starting subtest: psr2-cursor-mmap-cpu
[185.513934] Starting dynamic subtest: eDP-1
[187.251928] Dynamic subtest eDP-1: SUCCESS (1.741s)
[187.254424] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.742s)
[188.122309] [055/156] (831s left) gem_exec_params (secure-non-root)
[188.180300] Starting subtest: secure-non-root
[188.184630] Subtest secure-non-root: SKIP (0.000s)
[188.278447] [056/156] (831s left) gem_ctx_param (root-set)
[188.331994] Starting subtest: root-set
[188.334287] Subtest root-set: SUCCESS (0.001s)
[188.434993] [057/156] (831s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[188.531547] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[188.536111] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[188.625536] [058/156] (831s left) kms_chamelium_hpd (hdmi-hpd-fast)
[189.002545] Subtest hdmi-hpd-fast: SKIP (0.000s)
[189.787683] [059/156] (830s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-pwrite)
[189.884877] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-pwrite
[189.889284] Subtest psrhdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[189.983072] [060/156] (830s left) kms_chamelium_frames (hdmi-aspect-ratio)
[190.361453] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[191.184567] [061/156] (828s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[191.275190] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[191.279522] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[191.397057] [062/156] (828s left) kms_flip (dpms-vs-vblank-race)
[191.492373] Starting subtest: dpms-vs-vblank-race
[191.494729] Starting dynamic subtest: A-eDP1
[195.372992] Dynamic subtest A-eDP1: SUCCESS (3.879s)
[195.383265] Starting dynamic subtest: B-eDP1
[198.877539] Dynamic subtest B-eDP1: SUCCESS (3.502s)
[198.886793] Starting dynamic subtest: C-eDP1
[202.369739] Dynamic subtest C-eDP1: SUCCESS (3.490s)
[202.379074] Starting dynamic subtest: D-eDP1
[205.857916] Dynamic subtest D-eDP1: SUCCESS (3.489s)
[205.860620] Subtest dpms-vs-vblank-race: SUCCESS (14.369s)
[206.712667] [063/156] (813s left) gem_exec_schedule (noreorder-priority)
[206.901449] Starting subtest: noreorder-priority
[206.904226] Starting dynamic subtest: rcs0
[208.032074] Dynamic subtest rcs0: SUCCESS (1.132s)
[208.042781] Starting dynamic subtest: vcs0
[209.276446] Dynamic subtest vcs0: SUCCESS (1.243s)
[209.287992] Starting dynamic subtest: vcs1
[211.264997] Dynamic subtest vcs1: SUCCESS (1.986s)
[211.276855] Starting dynamic subtest: vecs0
[213.312806] Dynamic subtest vecs0: SUCCESS (2.021s)
[213.323666] Starting dynamic subtest: ccs0
[214.433246] Dynamic subtest ccs0: SUCCESS (1.144s)
[214.435746] Subtest noreorder-priority: SUCCESS (7.534s)
[214.618453] [064/156] (805s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[214.676781] Starting subtest: single-wait-all-for-submit-available-signaled
[214.679065] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[214.779367] [065/156] (805s left) perf_pmu (semaphore-busy)
[214.817859] Starting subtest: semaphore-busy
[214.819755] Starting dynamic subtest: rcs0
[216.452328] Dynamic subtest rcs0: SUCCESS (1.632s)
[216.461870] Starting dynamic subtest: bcs0
[218.085405] Dynamic subtest bcs0: SUCCESS (1.630s)
[218.096770] Starting dynamic subtest: vcs0
[221.875279] Dynamic subtest vcs0: SUCCESS (3.734s)
[221.886935] Starting dynamic subtest: vcs1
[227.773956] Dynamic subtest vcs1: SUCCESS (5.950s)
[227.784868] Starting dynamic subtest: vecs0
[233.840865] Dynamic subtest vecs0: SUCCESS (6.013s)
[233.852271] Starting dynamic subtest: ccs0
[236.863288] Dynamic subtest ccs0: SUCCESS (3.071s)
[236.865899] Subtest semaphore-busy: SUCCESS (22.044s)
[236.979730] [066/156] (783s left) kms_psr (fbc-pr-primary-render)
[237.072895] Starting subtest: fbc-pr-primary-render
[237.077239] Subtest fbc-pr-primary-render: SKIP (0.000s)
[237.142879] [067/156] (783s left) kms_plane_lowres (tiling-none)
[237.229786] Starting subtest: tiling-none
[237.232168] Starting dynamic subtest: pipe-A-eDP-1
[237.236819] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[237.522027] Starting dynamic subtest: pipe-B-eDP-1
[237.527026] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[237.536603] Starting dynamic subtest: pipe-C-eDP-1
[237.541511] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[237.550577] Starting dynamic subtest: pipe-D-eDP-1
[237.555550] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[237.560231] Subtest tiling-none: SKIP (0.291s)
[238.403311] [068/156] (781s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[238.499570] Starting subtest: psr2-cursor-plane-move-continuous-sf
[238.501877] Starting dynamic subtest: pipe-A-eDP-1
[248.846450] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.352s)
[248.856223] Starting dynamic subtest: pipe-B-eDP-1
[259.938567] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.091s)
[259.940967] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.446s)
[260.801903] [069/156] (759s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[260.908695] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[260.912791] Subtest fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[261.005876] [070/156] (759s left) gem_mmap_gtt (medium-copy-odd)
[261.063710] Subtest medium-copy-odd: SKIP (0.000s)
[261.131160] [071/156] (759s left) kms_feature_discovery (chamelium)
[261.213401] Starting subtest: chamelium
[261.218136] Subtest chamelium: SKIP (0.000s)
[261.286077] [072/156] (759s left) gem_cs_tlb (engines)
[261.346636] Starting subtest: engines
[261.349021] Starting dynamic subtest: rcs0
[263.502297] Dynamic subtest rcs0: SUCCESS (2.152s)
[263.512997] Starting dynamic subtest: bcs0
[265.654645] Dynamic subtest bcs0: SUCCESS (2.151s)
[265.663436] Starting dynamic subtest: vcs0
[267.862307] Dynamic subtest vcs0: SUCCESS (2.153s)
[267.873735] Starting dynamic subtest: vcs1
[270.015463] Dynamic subtest vcs1: SUCCESS (2.152s)
[270.026569] Starting dynamic subtest: vecs0
[272.165886] Dynamic subtest vecs0: SUCCESS (2.151s)
[272.175935] Starting dynamic subtest: ccs0
[274.318719] Dynamic subtest ccs0: SUCCESS (2.154s)
[274.320695] Subtest engines: SUCCESS (12.918s)
[274.410639] [073/156] (746s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[274.506529] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[281.078707] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.577s)
[281.205701] [074/156] (739s left) kms_vblank (ts-continuation-modeset-rpm)
[281.294066] Starting subtest: ts-continuation-modeset-rpm
[281.296013] Starting dynamic subtest: pipe-A-eDP-1
[283.785095] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.490s)
[283.798872] Starting dynamic subtest: pipe-D-eDP-1
[285.953236] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.164s)
[285.955582] Subtest ts-continuation-modeset-rpm: SUCCESS (4.660s)
[286.799781] [075/156] (733s left) gem_exec_reloc (basic-range)
[286.863873] Subtest basic-range: SKIP (0.000s)
[286.946731] [076/156] (733s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[287.044130] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[287.048232] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[287.138862] [077/156] (733s left) kms_content_protection (dp-mst-lic-type-1)
[287.275808] Starting subtest: dp-mst-lic-type-1
[287.280214] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[287.413573] [078/156] (733s left) gem_exec_fence (basic-await)
[287.592694] Starting subtest: basic-await
[287.594731] Starting dynamic subtest: rcs0
[287.665658] Dynamic subtest rcs0: SUCCESS (0.076s)
[287.681404] Starting dynamic subtest: bcs0
[287.743122] Dynamic subtest bcs0: SUCCESS (0.075s)
[287.752820] Starting dynamic subtest: vcs0
[287.818029] Dynamic subtest vcs0: SUCCESS (0.073s)
[287.830599] Starting dynamic subtest: vcs1
[287.892479] Dynamic subtest vcs1: SUCCESS (0.072s)
[287.904461] Starting dynamic subtest: vecs0
[287.964879] Dynamic subtest vecs0: SUCCESS (0.071s)
[287.976674] Starting dynamic subtest: ccs0
[288.037948] Dynamic subtest ccs0: SUCCESS (0.071s)
[288.040674] Subtest basic-await: SUCCESS (0.450s)
[289.375061] [079/156] (731s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[289.479121] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[289.483161] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[289.574684] [080/156] (731s left) kms_async_flips (invalid-async-flip)
[289.667177] Starting subtest: invalid-async-flip
[290.054375] Starting dynamic subtest: pipe-A-eDP-1
[290.895237] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.843s)
[291.256821] Starting dynamic subtest: pipe-B-eDP-1
[292.097091] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.848s)
[292.451912] Starting dynamic subtest: pipe-C-eDP-1
[293.296621] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.853s)
[293.654949] Starting dynamic subtest: pipe-D-eDP-1
[294.475493] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.829s)
[294.477840] Subtest invalid-async-flip: SUCCESS (4.810s)
[295.707476] [081/156] (725s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-plflip-blt)
[295.801724] Starting subtest: hdr-1p-primscrn-indfb-plflip-blt
[295.806177] Subtest hdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[295.905877] [082/156] (725s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[296.003307] Starting subtest: crc-primary-suspend-y-tiled-ccs
[296.005574] Starting dynamic subtest: pipe-A-eDP-1
[296.014445] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[296.024109] Starting dynamic subtest: pipe-B-eDP-1
[296.032977] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[296.041292] Starting dynamic subtest: pipe-C-eDP-1
[296.049645] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[296.058119] Starting dynamic subtest: pipe-D-eDP-1
[296.066544] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[296.070616] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.004s)
[298.295521] [083/156] (722s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-onoff)
[298.404314] Starting subtest: hdr-2p-primscrn-cur-indfb-onoff
[298.408859] Subtest hdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[298.506248] [084/156] (722s left) gem_userptr_blits (coherency-sync)
[298.574592] Starting subtest: coherency-sync
[298.919206] Subtest coherency-sync: SKIP (0.306s)
[299.029128] [085/156] (722s left) kms_dirtyfb (drrs-dirtyfb-ioctl)
[299.189742] Starting subtest: drrs-dirtyfb-ioctl
[299.203088] Subtest drrs-dirtyfb-ioctl: SKIP (0.002s)
[299.297099] [086/156] (721s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[299.410180] Starting subtest: x-tiled-16bpp-rotate-0
[300.756101] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.346s)
[300.889286] [087/156] (720s left) kms_prop_blob (invalid-set-prop-any)
[300.936252] Starting subtest: invalid-set-prop-any
[300.938122] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[301.021877] [088/156] (720s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[301.114208] Starting subtest: 4-tiled-32bpp-rotate-270
[301.133889] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[301.266216] [089/156] (719s left) sysfs_heartbeat_interval (nopreempt)
[301.334131] Starting subtest: nopreempt
[301.336062] Starting dynamic subtest: vecs0
[311.301196] Dynamic subtest vecs0: SUCCESS (9.962s)
[311.315694] Starting dynamic subtest: ccs0
[315.237399] Dynamic subtest ccs0: SUCCESS (3.933s)
[315.249742] Starting dynamic subtest: vcs0
[324.291225] Dynamic subtest vcs0: SUCCESS (9.051s)
[324.303785] Starting dynamic subtest: bcs0
[328.186863] Dynamic subtest bcs0: SUCCESS (3.894s)
[328.198849] Starting dynamic subtest: rcs0
[332.187891] Dynamic subtest rcs0: SUCCESS (4.001s)
[332.199452] Starting dynamic subtest: vcs1
[341.318159] Dynamic subtest vcs1: SUCCESS (9.128s)
[341.320623] Subtest nopreempt: SUCCESS (39.983s)
[341.409054] [090/156] (679s left) kms_psr (psr-cursor-mmap-cpu)
[341.504897] Starting subtest: psr-cursor-mmap-cpu
[341.507290] Starting dynamic subtest: eDP-1
[343.255038] Dynamic subtest eDP-1: SUCCESS (1.748s)
[343.257403] Subtest psr-cursor-mmap-cpu: SUCCESS (1.749s)
[344.092091] [091/156] (677s left) prime_self_import (basic-with_one_bo_two_files)
[344.132348] Starting subtest: basic-with_one_bo_two_files
[344.151333] Subtest basic-with_one_bo_two_files: SUCCESS (0.021s)
[344.259562] [092/156] (677s left) gem_mmap_gtt (basic-small-copy)
[344.313581] Subtest basic-small-copy: SKIP (0.000s)
[344.368412] [093/156] (676s left) gem_exec_balancer (bonded-sync)
[344.426753] Starting subtest: bonded-sync
[344.431145] Subtest bonded-sync: SKIP (0.000s)
[344.752706] [094/156] (676s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[344.838348] Starting subtest: basic-max-pipe-crc-check
[344.842868] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[344.890264] [095/156] (676s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[344.984025] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[344.988335] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[345.082537] [096/156] (676s left) gem_exec_reloc (basic-gtt-wc-active)
[345.147653] Subtest basic-gtt-wc-active: SKIP (0.000s)
[345.234995] [097/156] (676s left) core_auth (getclient-master-drop)
[345.272625] Starting subtest: getclient-master-drop
[345.295111] Subtest getclient-master-drop: SUCCESS (0.025s)
[345.412293] [098/156] (676s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[345.514266] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[345.518584] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[345.621475] [099/156] (675s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt)
[345.720823] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt
[345.724507] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[345.824289] [100/156] (675s left) kms_cursor_crc (cursor-offscreen-32x10)
[345.992588] Starting subtest: cursor-offscreen-32x10
[346.034523] Subtest cursor-offscreen-32x10: SKIP (0.041s)
[346.321193] [101/156] (675s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[346.416840] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[346.420639] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[346.523937] [102/156] (675s left) kms_rotation_crc (sprite-rotation-180)
[346.612337] Starting subtest: sprite-rotation-180
[353.511831] Subtest sprite-rotation-180: SUCCESS (6.901s)
[354.705674] [103/156] (667s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-plflip-blt)
[354.808591] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-plflip-blt
[354.812898] Subtest fbcpsrhdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[354.909801] [104/156] (666s left) device_reset (cold-reset-bound)
[354.959091] Starting subtest: cold-reset-bound
[354.963167] Subtest cold-reset-bound: SKIP (0.000s)
[355.029479] [105/156] (666s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-gtt)
[355.128500] Starting subtest: hdr-rgb565-draw-mmap-gtt
[355.132707] Subtest hdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[355.214426] [106/156] (666s left) gem_ppgtt (blt-vs-render-ctxn)
[355.277127] Starting subtest: blt-vs-render-ctxN
[388.003921] Subtest blt-vs-render-ctxN: SUCCESS (32.723s)
[388.130047] [107/156] (633s left) gem_exec_reloc (basic-write-gtt-noreloc)
[388.239499] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[388.318227] [108/156] (633s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[388.434846] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[388.439021] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[388.529060] [109/156] (633s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[388.617658] Starting subtest: primary-yf-tiled-reflect-x-90
[388.622081] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[388.702150] [110/156] (633s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[388.798415] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[392.443694] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.650s)
[393.615540] [111/156] (628s left) gem_exec_whisper (basic-queues-forked)
[393.678672] Starting subtest: basic-queues-forked
[416.504029] Subtest basic-queues-forked: SUCCESS (22.822s)
[416.698916] [112/156] (605s left) gem_watchdog (default-virtual)
[416.769133] Starting subtest: default-virtual
[423.052097] Subtest default-virtual: SUCCESS (6.293s)
[423.151067] [113/156] (599s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[423.252299] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[423.256232] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[423.365603] [114/156] (598s left) gem_workarounds (reset-context)
[423.436467] Starting subtest: reset-context
[423.567294] Subtest reset-context: SUCCESS (0.130s)
[423.685665] [115/156] (598s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[423.796609] Starting subtest: 2x-long-cursor-vs-flip-atomic
[423.801248] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.001s)
[423.941602] [116/156] (598s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[424.010480] Starting subtest: etime-multi-wait-all-for-submit-submitted
[424.118841] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.106s)
[424.206919] [117/156] (598s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[424.310219] Starting subtest: psr-indfb-scaledprimary
[430.115704] Subtest psr-indfb-scaledprimary: SUCCESS (5.811s)
[430.231807] [118/156] (592s left) sw_sync (sync_random_merge)
[430.270945] Starting subtest: sync_random_merge
[430.312260] Subtest sync_random_merge: SUCCESS (0.044s)
[430.386894] [119/156] (592s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[430.495895] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[430.498290] Starting dynamic subtest: pipe-A-eDP-1
[430.520881] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[430.532612] Starting dynamic subtest: pipe-B-eDP-1
[430.553164] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[430.562618] Starting dynamic subtest: pipe-C-eDP-1
[430.583483] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[430.592595] Starting dynamic subtest: pipe-D-eDP-1
[430.614324] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[430.618715] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[432.783591] [120/156] (589s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[432.874048] Starting subtest: pixel-format-y-tiled-ccs-modifier
[432.878092] Subtest pixel-format-y-tiled-ccs-modifier: SKIP (0.000s)
[432.957763] [121/156] (589s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[433.046243] Starting subtest: addfb25-y-tiled-small-legacy
[433.050450] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[433.129120] [122/156] (589s left) kms_busy (extended-pageflip-hang-oldfb)
[433.236765] Starting subtest: extended-pageflip-hang-oldfb
[433.239158] Starting dynamic subtest: pipe-A
[433.672837] Dynamic subtest pipe-A: SUCCESS (0.434s)
[433.683166] Starting dynamic subtest: pipe-D
[434.769751] Dynamic subtest pipe-D: SUCCESS (1.096s)
[434.772069] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.534s)
[435.597266] [123/156] (587s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[435.697251] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[436.544434] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.848s)
[436.730794] [124/156] (585s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[436.832106] Starting subtest: flip-vs-dpms-on-nop-interruptible
[436.834511] Starting dynamic subtest: A-eDP1
[438.662221] Dynamic subtest A-eDP1: SUCCESS (1.829s)
[438.674606] Starting dynamic subtest: B-eDP1
[440.548685] Dynamic subtest B-eDP1: SUCCESS (1.885s)
[440.559383] Starting dynamic subtest: C-eDP1
[442.332138] Dynamic subtest C-eDP1: SUCCESS (1.783s)
[442.343656] Starting dynamic subtest: D-eDP1
[444.137079] Dynamic subtest D-eDP1: SUCCESS (1.804s)
[444.142549] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.310s)
[445.318788] [125/156] (577s left) kms_cursor_crc (cursor-sliding-512x512)
[445.604071] Starting subtest: cursor-sliding-512x512
[445.608575] Subtest cursor-sliding-512x512: SKIP (0.001s)
[445.764368] [126/156] (576s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[445.855229] Starting subtest: fbcpsr-1p-rte
[452.894054] Subtest fbcpsr-1p-rte: SUCCESS (7.045s)
[453.032707] [127/156] (569s left) gem_exec_schedule (semaphore-codependency)
[453.107277] Starting subtest: semaphore-codependency
[453.113238] Subtest semaphore-codependency: SUCCESS (0.013s)
[453.479806] [128/156] (569s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[453.577750] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[453.582188] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[453.687356] [129/156] (569s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[453.785391] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[453.789627] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[453.885027] [130/156] (569s left) i915_query (query-topology-known-pci-ids)
[453.940643] Starting subtest: query-topology-known-pci-ids
[453.944718] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[454.047974] [131/156] (568s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[454.149760] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[455.272895] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.123s)
[455.395438] [132/156] (567s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[455.494748] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[455.498467] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[455.593512] [133/156] (567s left) kms_psr (fbc-psr-no-drrs)
[455.685064] Starting subtest: fbc-psr-no-drrs
[455.687082] Starting dynamic subtest: eDP-1
[455.690517] Dynamic subtest eDP-1: SKIP (0.000s)
[455.693917] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[455.757298] [134/156] (567s left) kms_pm_lpsp (kms-lpsp)
[456.853064] Starting subtest: kms-lpsp
[456.855753] Starting dynamic subtest: pipe-A-eDP-1
[456.986399] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.137s)
[457.286864] Subtest kms-lpsp: SUCCESS (0.438s)
[458.096980] [135/156] (565s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[458.196702] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[458.198899] Starting dynamic subtest: pipe-A-eDP-1
[458.256415] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[458.266693] Starting dynamic subtest: pipe-B-eDP-1
[458.324526] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[458.337656] Starting dynamic subtest: pipe-C-eDP-1
[458.395690] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[458.409429] Starting dynamic subtest: pipe-D-eDP-1
[458.467740] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[458.471873] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.009s)
[460.484984] [136/156] (562s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-blt)
[460.596779] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-blt
[460.601249] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[460.714175] [137/156] (562s left) kms_pm_backlight (bad-brightness)
[460.817981] Starting subtest: bad-brightness
[460.866441] Starting dynamic subtest: eDP-1
[461.192061] Dynamic subtest eDP-1: SUCCESS (0.324s)
[461.194468] Subtest bad-brightness: SUCCESS (0.375s)
[461.999923] [138/156] (561s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[462.052647] Starting subtest: wait-all-for-submit-delayed-submit
[462.157404] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[462.238745] [139/156] (561s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[462.299366] Starting subtest: y-tiled-to-vebox-x-tiled
[462.304096] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[462.485127] [140/156] (560s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[462.575611] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[462.577975] Starting dynamic subtest: pipe-A
[462.912340] Dynamic subtest pipe-A: SUCCESS (0.340s)
[462.922581] Starting dynamic subtest: pipe-B
[464.344509] Dynamic subtest pipe-B: SUCCESS (1.431s)
[464.356811] Starting dynamic subtest: pipe-C
[465.780485] Dynamic subtest pipe-C: SUCCESS (1.435s)
[465.790392] Starting dynamic subtest: pipe-D
[467.248366] Dynamic subtest pipe-D: SUCCESS (1.467s)
[467.250659] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.676s)
[468.392123] [141/156] (554s left) gem_exec_whisper (basic-contexts-priority)
[468.464825] Starting subtest: basic-contexts-priority
[491.668901] Subtest basic-contexts-priority: SUCCESS (23.202s)
[491.858048] [142/156] (531s left) i915_drm_fdinfo (virtual-busy)
[491.900184] Starting subtest: virtual-busy
[491.904680] Subtest virtual-busy: SKIP (0.000s)
[491.970346] [143/156] (531s left) api_intel_allocator (reserve-simple)
[492.023100] Starting subtest: reserve-simple
[492.026174] Subtest reserve-simple: SUCCESS (0.006s)
[492.129305] [144/156] (531s left) fbdev (unaligned-write)
[492.171265] Starting subtest: unaligned-write
[492.269732] Subtest unaligned-write: SUCCESS (0.100s)
[492.335544] [145/156] (531s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[492.434730] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[492.436981] Starting dynamic subtest: pipe-A-eDP-1
[492.458982] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[492.470316] Starting dynamic subtest: pipe-B-eDP-1
[492.492077] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[492.501420] Starting dynamic subtest: pipe-C-eDP-1
[492.522840] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[492.531794] Starting dynamic subtest: pipe-D-eDP-1
[492.553233] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[492.557498] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[494.686445] [146/156] (528s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt)
[494.778613] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt
[494.782728] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[494.882020] [147/156] (528s left) gem_sync (basic-each)
[494.940111] Starting subtest: basic-each
[497.267889] Subtest basic-each: SUCCESS (2.338s)
[497.469472] [148/156] (526s left) gem_basic (create-close)
[497.524095] Starting subtest: create-close
[497.528596] Subtest create-close: SUCCESS (0.000s)
[497.622471] [149/156] (526s left) gem_tiled_fence_blits (normal)
[497.694359] Subtest normal: SKIP (0.000s)
[497.760835] [150/156] (525s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[497.871583] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[497.875916] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[497.959234] [151/156] (525s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[498.044255] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[498.047981] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[498.139860] [152/156] (525s left) gem_close_race (basic-process)
[498.207829] Starting subtest: basic-process
[498.275233] Subtest basic-process: SUCCESS (0.067s)
[498.463600] [153/156] (525s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-render)
[498.562233] Starting subtest: fbcpsrhdr-rgb565-draw-render
[498.566321] Subtest fbcpsrhdr-rgb565-draw-render: SKIP (0.000s)
[498.650240] [154/156] (525s left) kms_psr (fbc-psr2-suspend)
[498.741072] Starting subtest: fbc-psr2-suspend
[498.743419] Starting dynamic subtest: eDP-1
[498.747600] Dynamic subtest eDP-1: SKIP (0.000s)
[498.752237] Subtest fbc-psr2-suspend: SKIP (0.001s)
[498.826746] [155/156] (525s left) gen9_exec_parse (bb-large)
[498.892399] Subtest bb-large: SKIP (0.000s)
[498.968320] [156/156] (525s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-indfb-msflip-blt)
[499.071422] Starting subtest: fbchdr-2p-scndscrn-indfb-msflip-blt
[499.075873] Subtest fbchdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[499.171428] Closing watchdogs
results: parsing output: 0/ for test: gem_close
results: parsing output: 1/ for test: syncobj_timeline
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_plane_multiple
results: parsing output: 4/ for test: perf_pmu
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: kms_scaling_modes
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_exec_fence
results: parsing output: 10/ for test: i915_pciid
results: parsing output: 11/ for test: kms_chamelium_color
results: parsing output: 12/ for test: kms_color
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_flip_scaled_crc
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_ctx_sseu
results: parsing output: 17/ for test: gem_pxp
results: parsing output: 18/ for test: gem_exec_params
results: parsing output: 19/ for test: kms_pm_rpm
results: parsing output: 20/ for test: i915_pm_rps
results: parsing output: 21/ for test: gem_lmem_swapping
results: parsing output: 22/ for test: gem_eio
results: parsing output: 23/ for test: gem_basic
results: parsing output: 24/ for test: kms_flip
results: parsing output: 25/ for test: kms_hdmi_inject
results: parsing output: 26/ for test: kms_cursor_crc
results: parsing output: 27/ for test: kms_invalid_mode
results: parsing output: 28/ for test: kms_cursor_crc
results: parsing output: 29/ for test: gem_softpin
results: parsing output: 30/ for test: syncobj_basic
results: parsing output: 31/ for test: syncobj_eventfd
results: parsing output: 32/ for test: syncobj_timeline
results: parsing output: 33/ for test: gem_ctx_persistence
results: parsing output: 34/ for test: gem_mmap_offset
results: parsing output: 35/ for test: gem_userptr_blits
results: parsing output: 36/ for test: kms_vblank
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: gem_set_tiling_vs_blt
results: parsing output: 39/ for test: i915_pm_rc6_residency
results: parsing output: 40/ for test: gem_render_copy
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_ctx_param
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: kms_hdr
results: parsing output: 48/ for test: gem_mmap_gtt
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_nop
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_exec_fence
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: gem_exec_params
results: parsing output: 55/ for test: gem_ctx_param
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_chamelium_hpd
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_chamelium_frames
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: gem_exec_schedule
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: perf_pmu
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: kms_plane_lowres
results: parsing output: 67/ for test: kms_psr2_sf
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_mmap_gtt
results: parsing output: 70/ for test: kms_feature_discovery
results: parsing output: 71/ for test: gem_cs_tlb
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_vblank
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_content_protection
results: parsing output: 77/ for test: gem_exec_fence
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_async_flips
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_userptr_blits
results: parsing output: 84/ for test: kms_dirtyfb
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_prop_blob
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: sysfs_heartbeat_interval
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: prime_self_import
results: parsing output: 91/ for test: gem_mmap_gtt
results: parsing output: 92/ for test: gem_exec_balancer
results: parsing output: 93/ for test: kms_multipipe_modeset
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_reloc
results: parsing output: 96/ for test: core_auth
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_cursor_crc
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_rotation_crc
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: device_reset
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_ppgtt
results: parsing output: 106/ for test: gem_exec_reloc
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_rotation_crc
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_exec_whisper
results: parsing output: 111/ for test: gem_watchdog
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_workarounds
results: parsing output: 114/ for test: kms_cursor_legacy
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: sw_sync
results: parsing output: 118/ for test: kms_ccs
results: parsing output: 119/ for test: kms_plane
results: parsing output: 120/ for test: kms_addfb_basic
results: parsing output: 121/ for test: kms_busy
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: kms_cursor_crc
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_exec_schedule
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: i915_query
results: parsing output: 130/ for test: kms_big_fb
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_psr
results: parsing output: 133/ for test: kms_pm_lpsp
results: parsing output: 134/ for test: kms_ccs
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_pm_backlight
results: parsing output: 137/ for test: syncobj_wait
results: parsing output: 138/ for test: gem_render_copy
results: parsing output: 139/ for test: kms_plane_scaling
results: parsing output: 140/ for test: gem_exec_whisper
results: parsing output: 141/ for test: i915_drm_fdinfo
results: parsing output: 142/ for test: api_intel_allocator
results: parsing output: 143/ for test: fbdev
results: parsing output: 144/ for test: kms_ccs
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: gem_sync
results: parsing output: 147/ for test: gem_basic
results: parsing output: 148/ for test: gem_tiled_fence_blits
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: gem_close_race
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: kms_psr
results: parsing output: 154/ for test: gen9_exec_parse
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.