[56.578831] Initializing watchdogs
[56.578880]   /dev/watchdog0
[56.594401] [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.599116] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.601590] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.604830] [001/155] (960s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[56.725437] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[56.727609] Starting dynamic subtest: pipe-A-eDP-1
[62.867562] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.277s)
[62.877520] Starting dynamic subtest: pipe-B-eDP-1
[69.490370] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.681s)
[69.492595] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.960s)
[70.360818] [002/155] (946s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[70.455397] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[70.457839] Starting dynamic subtest: pipe-A-default-mode
[73.179264] Dynamic subtest pipe-A-default-mode: SUCCESS (2.734s)
[73.181675] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.735s)
[74.032375] [003/155] (943s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[74.163092] Starting subtest: basic-busy-flip-before-cursor-legacy
[74.167621] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[74.303177] [004/155] (942s left) i915_pm_rpm (system-suspend)
[76.449552] Starting subtest: system-suspend
[95.737546] Subtest system-suspend: SUCCESS (3.395s)
[101.138567] [005/155] (921s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[101.250618] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[101.255056] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.000s)
[101.319008] [006/155] (920s left) gem_exec_fence (submit3)
[101.508079] Starting subtest: submit3
[101.512608] Subtest submit3: SKIP (0.000s)
[103.312895] [FACT gem_exec_fence (submit3)] new: kernel.kmod_is_loaded.vgem: true
[103.315756] [007/155] (919s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-onoff)
[103.411054] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-onoff
[103.415244] Subtest psrhdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[103.498843] [008/155] (918s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[103.589741] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[103.591975] Starting dynamic subtest: pipe-A-eDP-1
[104.495895] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.933s)
[104.504834] Starting dynamic subtest: pipe-B-eDP-1
[106.089648] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.639s)
[106.097852] Starting dynamic subtest: pipe-C-eDP-1
[107.607572] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.555s)
[107.617845] Starting dynamic subtest: pipe-D-eDP-1
[109.099833] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.522s)
[109.102195] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.652s)
[110.311767] [009/155] (912s left) gem_exec_reloc (basic-wc-read-active)
[110.385241] Subtest basic-wc-read-active: SKIP (0.000s)
[110.457984] [010/155] (912s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[110.555178] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[110.559190] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[110.655186] [011/155] (911s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[110.757471] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[118.008131] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.325s)
[118.145565] [012/155] (904s left) perf (invalid-oa-metric-set-id)
[118.286840] Starting subtest: invalid-oa-metric-set-id
[118.298468] Subtest invalid-oa-metric-set-id: SUCCESS (0.011s)
[118.341388] [013/155] (904s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-plflip-blt)
[118.437470] Starting subtest: hdr-1p-primscrn-indfb-plflip-blt
[118.441774] Subtest hdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[118.539828] [014/155] (904s left) gem_exec_reloc (basic-write-read-active)
[118.600875] Subtest basic-write-read-active: SKIP (0.000s)
[118.666282] [015/155] (904s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[118.755955] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[118.759879] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[118.850736] [016/155] (903s left) syncobj_wait (invalid-reset-one-illegal-handle)
[118.908641] Starting subtest: invalid-reset-one-illegal-handle
[118.911712] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[119.011375] [017/155] (903s left) gem_exec_whisper (basic-fds-all)
[119.062138] Starting subtest: basic-fds-all
[131.168160] Subtest basic-fds-all: SUCCESS (12.128s)
[131.351070] [018/155] (891s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[131.628508] Starting subtest: cursor-rapid-movement-512x512
[131.632878] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[131.794025] [019/155] (891s left) kms_psr (fbc-pr-sprite-plane-onoff)
[131.889907] Starting subtest: fbc-pr-sprite-plane-onoff
[131.894415] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[131.965155] [020/155] (890s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[132.056593] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[132.061035] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[132.158419] [021/155] (890s left) sriov_basic (enable-vfs-autoprobe-off)
[132.220928] Starting subtest: enable-vfs-autoprobe-off
[132.223236] Starting dynamic subtest: numvfs-1
[132.227861] Dynamic subtest numvfs-1: SKIP (0.001s)
[132.239866] Starting dynamic subtest: numvfs-2
[132.244284] Dynamic subtest numvfs-2: SKIP (0.001s)
[132.253182] Starting dynamic subtest: numvfs-3
[132.257601] Dynamic subtest numvfs-3: SKIP (0.001s)
[132.267153] Starting dynamic subtest: numvfs-4
[132.271544] Dynamic subtest numvfs-4: SKIP (0.001s)
[132.280578] Starting dynamic subtest: numvfs-5
[132.285010] Dynamic subtest numvfs-5: SKIP (0.001s)
[132.293553] Starting dynamic subtest: numvfs-6
[132.297437] Dynamic subtest numvfs-6: SKIP (0.001s)
[132.308591] Starting dynamic subtest: numvfs-7
[132.312148] Dynamic subtest numvfs-7: SKIP (0.001s)
[132.320714] Starting dynamic subtest: numvfs-random
[132.324360] Dynamic subtest numvfs-random: SKIP (0.001s)
[132.332991] Starting dynamic subtest: numvfs-all
[132.336549] Dynamic subtest numvfs-all: SKIP (0.001s)
[132.340138] Subtest enable-vfs-autoprobe-off: SKIP (0.009s)
[132.379515] [022/155] (890s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[132.433368] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[132.498008] [023/155] (890s left) gem_create (create-ext-set-pat)
[132.661163] Starting subtest: create-ext-set-pat
[132.664082] Subtest create-ext-set-pat: SUCCESS (0.000s)
[132.778435] [024/155] (890s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[132.869855] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[132.872015] Starting dynamic subtest: pipe-A-eDP-1
[132.893963] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[132.906196] Starting dynamic subtest: pipe-B-eDP-1
[132.927770] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[132.937310] Starting dynamic subtest: pipe-C-eDP-1
[132.959567] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[132.968787] Starting dynamic subtest: pipe-D-eDP-1
[132.991066] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[132.995098] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[135.181249] [025/155] (887s left) device_reset (unbind-cold-reset-rebind)
[135.235650] Starting subtest: unbind-cold-reset-rebind
[135.240045] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[135.311109] [026/155] (887s left) gem_exec_fence (syncobj-timeline-wait)
[135.891636] Starting subtest: syncobj-timeline-wait
[137.230850] Subtest syncobj-timeline-wait: SUCCESS (1.339s)
[139.348321] [027/155] (883s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-msflip-blt)
[139.454894] Starting subtest: fbchdr-2p-primscrn-indfb-msflip-blt
[139.459195] Subtest fbchdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[139.563431] [028/155] (883s left) kms_plane_scaling (invalid-num-scalers)
[139.663096] Starting subtest: invalid-num-scalers
[139.665440] Starting dynamic subtest: pipe-A-eDP-1-invalid-num-scalers
[139.683766] Dynamic subtest pipe-A-eDP-1-invalid-num-scalers: SUCCESS (0.020s)
[139.693482] Starting dynamic subtest: pipe-B-eDP-1-invalid-num-scalers
[139.717715] Dynamic subtest pipe-B-eDP-1-invalid-num-scalers: SUCCESS (0.034s)
[139.727815] Starting dynamic subtest: pipe-C-eDP-1-invalid-num-scalers
[139.753649] Dynamic subtest pipe-C-eDP-1-invalid-num-scalers: SUCCESS (0.034s)
[139.763683] Starting dynamic subtest: pipe-D-eDP-1-invalid-num-scalers
[139.788593] Dynamic subtest pipe-D-eDP-1-invalid-num-scalers: SUCCESS (0.034s)
[139.791256] Subtest invalid-num-scalers: SUCCESS (0.127s)
[139.899434] [029/155] (883s left) gem_exec_params (invalid-flag)
[139.952900] Starting subtest: invalid-flag
[139.955174] Subtest invalid-flag: SUCCESS (0.000s)
[140.055990] [030/155] (883s left) gem_userptr_blits (stress-mm)
[140.127458] Starting subtest: stress-mm
[145.495845] Subtest stress-mm: SUCCESS (5.369s)
[145.615137] [031/155] (877s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-blt)
[145.717155] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-blt
[145.721743] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[145.805264] [032/155] (877s left) kms_dirtyfb (default-dirtyfb-ioctl)
[145.902916] Starting subtest: default-dirtyfb-ioctl
[145.905120] Starting dynamic subtest: A-eDP-1
[147.533574] Dynamic subtest A-eDP-1: SUCCESS (1.629s)
[147.535461] Subtest default-dirtyfb-ioctl: SUCCESS (1.630s)
[148.413970] [033/155] (875s left) gem_mmap_offset (perf)
[148.464589] Starting subtest: perf
[148.466685] Starting dynamic subtest: smem0
[148.829666] Dynamic subtest smem0: SUCCESS (0.365s)
[148.831716] Subtest perf: SUCCESS (0.365s)
[148.921767] [034/155] (874s left) i915_suspend (debugfs-reader)
[148.969643] Starting subtest: debugfs-reader
[167.936493] Subtest debugfs-reader: SUCCESS (4.279s)
[173.098022] [035/155] (855s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-onoff)
[173.205969] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-onoff
[173.209884] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[173.291592] [036/155] (855s left) gem_exec_flush (basic-batch-kernel-default-uc)
[173.349745] Starting subtest: basic-batch-kernel-default-uc
[180.381697] Subtest basic-batch-kernel-default-uc: SUCCESS (7.238s)
[180.566936] [037/155] (848s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-fullscreen)
[180.669613] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-fullscreen
[180.673612] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[180.773468] [038/155] (847s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[180.880988] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[185.408773] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.591s)
[186.556827] [039/155] (842s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[186.653760] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[190.912956] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (4.291s)
[192.122073] [040/155] (836s left) vgem_basic (create)
[192.162024] Starting subtest: create
[192.164223] Subtest create: SUCCESS (0.000s)
[192.218832] [041/155] (836s left) kms_plane_multiple (tiling-y)
[192.315966] Starting subtest: tiling-y
[192.320129] Subtest tiling-y: SKIP (0.000s)
[192.388347] [042/155] (836s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[192.483989] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[197.056089] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (4.592s)
[198.207241] [043/155] (830s left) core_setmaster_vs_auth
[198.346631] [044/155] (830s left) kms_atomic_interruptible (atomic-setmode)
[198.435707] Starting subtest: atomic-setmode
[198.437886] Starting dynamic subtest: pipe-A-eDP-1
[205.320348] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.893s)
[205.322509] Subtest atomic-setmode: SUCCESS (6.894s)
[206.126234] [045/155] (822s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[206.219604] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[206.231208] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[206.309311] [046/155] (822s left) perf_pmu (render-node-busy-idle)
[206.383287] Starting subtest: render-node-busy-idle
[206.385712] Starting dynamic subtest: rcs0
[207.073928] Dynamic subtest rcs0: SUCCESS (0.689s)
[207.086760] Starting dynamic subtest: bcs0
[207.736101] Dynamic subtest bcs0: SUCCESS (0.660s)
[207.747492] Starting dynamic subtest: vcs0
[208.434794] Dynamic subtest vcs0: FAIL (0.644s)
[208.446047] Starting dynamic subtest: vcs1
[209.616025] Dynamic subtest vcs1: FAIL (1.165s)
[209.626980] Starting dynamic subtest: vecs0
[211.597646] Dynamic subtest vecs0: FAIL (1.981s)
[211.608999] Starting dynamic subtest: ccs0
[213.581096] Dynamic subtest ccs0: FAIL (1.982s)
[213.583350] Subtest render-node-busy-idle: FAIL (7.129s)
[213.626518] [047/155] (815s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[213.754734] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[213.758817] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[213.852617] [048/155] (815s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[213.949348] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[221.770239] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.825s)
[221.891282] [049/155] (807s left) gem_ctx_persistence (legacy-engines-mixed-process)
[221.949844] Starting subtest: legacy-engines-mixed-process
[221.952198] Starting dynamic subtest: render
[223.918278] Dynamic subtest render: SUCCESS (1.965s)
[223.928339] Starting dynamic subtest: bsd1
[225.901619] Dynamic subtest bsd1: SUCCESS (1.985s)
[225.914636] Starting dynamic subtest: bsd2
[227.950717] Dynamic subtest bsd2: SUCCESS (2.047s)
[227.966032] Starting dynamic subtest: blt
[229.915845] Dynamic subtest blt: SUCCESS (1.963s)
[229.927610] Starting dynamic subtest: vebox
[231.902964] Dynamic subtest vebox: SUCCESS (1.979s)
[231.905295] Subtest legacy-engines-mixed-process: SUCCESS (9.949s)
[232.003912] [050/155] (797s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-fullscreen)
[232.108602] Starting subtest: fbchdr-1p-primscrn-spr-indfb-fullscreen
[232.113183] Subtest fbchdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[232.211071] [051/155] (797s left) api_intel_allocator (open-vm)
[232.265875] Starting subtest: open-vm
[232.268269] Subtest open-vm: SUCCESS (0.003s)
[232.396689] [052/155] (796s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[232.494678] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[232.499053] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[232.601696] [053/155] (796s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[232.703599] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[232.708069] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[232.801545] [054/155] (796s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[232.890242] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[232.892516] Starting dynamic subtest: pipe-A-eDP-1
[233.177990] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.263s)
[233.189499] Starting dynamic subtest: pipe-B-eDP-1
[234.526510] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.341s)
[234.537846] Starting dynamic subtest: pipe-C-eDP-1
[235.835035] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.307s)
[235.846548] Starting dynamic subtest: pipe-D-eDP-1
[237.053277] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.219s)
[237.055656] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.135s)
[238.254992] [055/155] (791s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[238.361736] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[238.366063] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[238.443852] [056/155] (791s left) sysfs_heartbeat_interval (invalid)
[238.497749] Starting subtest: invalid
[238.500056] Starting dynamic subtest: vecs0
[238.502250] Dynamic subtest vecs0: SUCCESS (0.000s)
[238.513166] Starting dynamic subtest: ccs0
[238.515271] Dynamic subtest ccs0: SUCCESS (0.000s)
[238.525297] Starting dynamic subtest: vcs0
[238.527455] Dynamic subtest vcs0: SUCCESS (0.000s)
[238.537327] Starting dynamic subtest: bcs0
[238.539602] Dynamic subtest bcs0: SUCCESS (0.000s)
[238.549182] Starting dynamic subtest: rcs0
[238.551505] Dynamic subtest rcs0: SUCCESS (0.000s)
[238.561159] Starting dynamic subtest: vcs1
[238.563328] Dynamic subtest vcs1: SUCCESS (0.000s)
[238.565522] Subtest invalid: SUCCESS (0.006s)
[238.607031] [057/155] (790s left) i915_drm_fdinfo (all-busy-idle-check-all)
[238.648369] Starting subtest: all-busy-idle-check-all
[238.655427] Subtest all-busy-idle-check-all: SKIP (0.004s)
[238.724227] [058/155] (790s left) kms_feature_discovery (display-2x)
[238.845451] Starting subtest: display-2x
[238.849552] Subtest display-2x: SKIP (0.000s)
[238.931832] [059/155] (790s left) kms_psr (fbc-psr-sprite-blt)
[239.024072] Starting subtest: fbc-psr-sprite-blt
[239.026362] Starting dynamic subtest: eDP-1
[239.030420] Dynamic subtest eDP-1: SKIP (0.000s)
[239.034531] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[239.098144] [060/155] (790s left) kms_properties (crtc-properties-atomic)
[239.200288] Starting subtest: crtc-properties-atomic
[239.201926] Starting dynamic subtest: pipe-A-eDP-1
[239.634893] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.433s)
[239.646479] Starting dynamic subtest: pipe-B-eDP-1
[240.814561] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.178s)
[240.824879] Starting dynamic subtest: pipe-C-eDP-1
[241.934213] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.119s)
[241.945152] Starting dynamic subtest: pipe-D-eDP-1
[243.070436] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s)
[243.072420] Subtest crtc-properties-atomic: SUCCESS (3.869s)
[243.888435] [061/155] (785s left) kms_addfb_basic (invalid-set-prop)
[243.944419] Starting subtest: invalid-set-prop
[243.946561] Subtest invalid-set-prop: SUCCESS (0.000s)
[244.067863] [062/155] (785s left) perf (gen12-unprivileged-single-ctx-counters)
[244.158972] Starting subtest: gen12-unprivileged-single-ctx-counters
[244.161247] Starting dynamic subtest: rcs0
[244.196875] Dynamic subtest rcs0: SUCCESS (0.038s)
[244.199056] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.039s)
[244.246028] [063/155] (785s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[244.387698] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[244.391668] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[244.477671] [064/155] (785s left) kms_vblank (ts-continuation-dpms-rpm)
[244.571050] Starting subtest: ts-continuation-dpms-rpm
[244.573211] Starting dynamic subtest: pipe-A-eDP-1
[247.080935] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.508s)
[247.098793] Starting dynamic subtest: pipe-D-eDP-1
[249.327943] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.245s)
[249.330173] Subtest ts-continuation-dpms-rpm: SUCCESS (4.757s)
[250.237424] [065/155] (779s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[250.339514] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[251.362502] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.024s)
[251.486647] [066/155] (778s left) gem_exec_async (forked-writes)
[251.546254] Starting subtest: forked-writes
[251.548578] Starting dynamic subtest: rcs0
[251.572262] Dynamic subtest rcs0: SUCCESS (0.026s)
[251.584222] Starting dynamic subtest: bcs0
[251.597633] Dynamic subtest bcs0: SUCCESS (0.023s)
[251.608547] Starting dynamic subtest: vcs0
[251.619624] Dynamic subtest vcs0: SUCCESS (0.020s)
[251.634307] Starting dynamic subtest: vcs1
[251.644202] Dynamic subtest vcs1: SUCCESS (0.022s)
[251.655680] Starting dynamic subtest: vecs0
[251.665564] Dynamic subtest vecs0: SUCCESS (0.023s)
[251.676033] Starting dynamic subtest: ccs0
[251.687952] Dynamic subtest ccs0: SUCCESS (0.021s)
[251.689892] Subtest forked-writes: SUCCESS (0.143s)
[251.925572] [067/155] (778s left) i915_hangman (gt-engine-error)
[251.984318] Starting subtest: gt-engine-error
[251.986269] Starting dynamic subtest: rcs0
[253.887279] Dynamic subtest rcs0: SUCCESS (1.903s)
[253.898984] Starting dynamic subtest: bcs0
[255.870977] Dynamic subtest bcs0: SUCCESS (1.981s)
[255.887791] Starting dynamic subtest: vcs0
[257.918634] Dynamic subtest vcs0: SUCCESS (2.045s)
[257.931331] Starting dynamic subtest: vcs1
[259.902729] Dynamic subtest vcs1: SUCCESS (1.981s)
[259.916069] Starting dynamic subtest: vecs0
[261.904004] Dynamic subtest vecs0: SUCCESS (1.980s)
[261.916459] Starting dynamic subtest: ccs0
[263.871249] Dynamic subtest ccs0: SUCCESS (1.985s)
[263.874139] Subtest gt-engine-error: SUCCESS (11.890s)
[263.997144] [068/155] (766s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[264.097567] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[264.102051] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[264.222222] [069/155] (765s left) gem_mmap_wc (bad-size)
[264.277704] Subtest bad-size: SKIP (0.000s)
[264.340737] [070/155] (765s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[264.444477] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-wc
[264.448851] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[264.555685] [071/155] (765s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[264.655862] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[264.660206] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[264.754558] [072/155] (765s left) kms_getfb (getfb-handle-valid)
[264.848051] Starting subtest: getfb-handle-valid
[264.850350] Subtest getfb-handle-valid: SUCCESS (0.000s)
[264.957230] [073/155] (765s left) syncobj_timeline (invalid-wait-bad-flags)
[265.010390] Starting subtest: invalid-wait-bad-flags
[265.012392] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[265.123558] [074/155] (765s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[265.229995] Starting subtest: extended-pageflip-modeset-hang-oldfb
[265.231835] Starting dynamic subtest: pipe-A
[273.145291] Dynamic subtest pipe-A: SUCCESS (7.915s)
[273.158400] Starting dynamic subtest: pipe-D
[281.731063] Dynamic subtest pipe-D: SUCCESS (8.582s)
[281.733398] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.506s)
[282.596147] [075/155] (747s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[282.700633] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[282.704726] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[282.828247] [076/155] (747s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-blt)
[282.931896] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-blt
[282.936355] Subtest hdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[283.034341] [077/155] (747s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[283.095592] Starting subtest: wait-all-for-submit-delayed-submit
[283.199566] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[283.284593] [078/155] (747s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[283.400674] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[284.358235] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.959s)
[285.229756] [079/155] (745s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[285.329358] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[285.333471] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[285.402410] [080/155] (745s left) kms_big_fb (linear-64bpp-rotate-270)
[285.499578] Starting subtest: linear-64bpp-rotate-270
[285.521560] Subtest linear-64bpp-rotate-270: SKIP (0.021s)
[285.629056] [081/155] (745s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[285.735956] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[285.740240] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[285.826672] [082/155] (744s left) gem_workarounds (basic-read-fd)
[285.895314] Starting subtest: basic-read-fd
[285.907952] Subtest basic-read-fd: SUCCESS (0.012s)
[286.028307] [083/155] (744s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[286.141052] Starting subtest: basic-flip-after-cursor-legacy
[287.058598] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.915s)
[287.873312] [084/155] (742s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-render)
[287.977348] Starting subtest: fbchdr-rgb101010-draw-render
[287.981549] Subtest fbchdr-rgb101010-draw-render: SKIP (0.000s)
[288.075116] [085/155] (742s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[288.174952] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[294.777761] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.610s)
[294.913385] [086/155] (735s left) kms_psr (psr2-cursor-plane-move)
[295.007549] Starting subtest: psr2-cursor-plane-move
[295.009862] Starting dynamic subtest: eDP-1
[296.681279] Dynamic subtest eDP-1: SUCCESS (1.675s)
[296.683630] Subtest psr2-cursor-plane-move: SUCCESS (1.676s)
[297.587958] [087/155] (733s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[297.704702] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[299.164025] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.459s)
[300.042145] [088/155] (730s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[300.146586] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[300.151042] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[300.248026] [089/155] (730s left) kms_psr (psr-primary-mmap-gtt)
[300.344953] Starting subtest: psr-primary-mmap-gtt
[300.347262] Starting dynamic subtest: eDP-1
[301.835663] Dynamic subtest eDP-1: SKIP (1.488s)
[301.840947] Subtest psr-primary-mmap-gtt: SKIP (1.490s)
[301.999394] [090/155] (729s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[302.102597] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[302.804881] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.703s)
[302.991791] [091/155] (728s left) kms_invalid_mode (bad-htotal)
[303.083133] Starting subtest: bad-htotal
[303.085666] Starting dynamic subtest: pipe-A-eDP-1
[303.379239] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.300s)
[303.390746] Starting dynamic subtest: pipe-B-eDP-1
[303.392953] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[303.402997] Starting dynamic subtest: pipe-C-eDP-1
[303.405056] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[303.415501] Starting dynamic subtest: pipe-D-eDP-1
[303.417942] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[303.420488] Subtest bad-htotal: SUCCESS (0.306s)
[304.216289] [092/155] (726s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[304.307873] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[304.310159] Starting dynamic subtest: pipe-A-eDP-1
[304.418230] Dynamic subtest pipe-A-eDP-1: SKIP (0.112s)
[304.431156] Starting dynamic subtest: pipe-B-eDP-1
[305.575103] Dynamic subtest pipe-B-eDP-1: SKIP (1.153s)
[305.579521] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.268s)
[306.762610] [093/155] (724s left) kms_color (ctm-blue-to-red)
[306.862800] Starting subtest: ctm-blue-to-red
[306.864998] Starting dynamic subtest: pipe-A-eDP-1
[307.574002] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.712s)
[307.586105] Starting dynamic subtest: pipe-B-eDP-1
[309.011534] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.436s)
[309.022825] Starting dynamic subtest: pipe-C-eDP-1
[310.368728] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.357s)
[310.379231] Starting dynamic subtest: pipe-D-eDP-1
[311.695155] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.327s)
[311.697657] Subtest ctm-blue-to-red: SUCCESS (4.836s)
[312.534377] [094/155] (718s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[312.582844] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[312.685714] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.102s)
[312.776522] [095/155] (718s left) gen9_exec_parse (bb-chained)
[312.833368] Subtest bb-chained: SKIP (0.000s)
[312.917504] [096/155] (718s left) gem_linear_blits (normal)
[313.376809] Starting subtest: normal
[317.408987] Subtest normal: SUCCESS (4.033s)
[317.547514] [097/155] (713s left) syncobj_wait (wait-delayed-signal)
[317.667771] Starting subtest: wait-delayed-signal
[317.771387] Subtest wait-delayed-signal: SUCCESS (0.102s)
[317.858301] [098/155] (713s left) kms_legacy_colorkey (basic)
[317.967894] Starting subtest: basic
[317.969767] Starting dynamic subtest: pipe-A
[318.011773] Dynamic subtest pipe-A: SUCCESS (0.045s)
[318.024808] Starting dynamic subtest: pipe-B
[318.027221] Dynamic subtest pipe-B: SUCCESS (0.001s)
[318.037579] Starting dynamic subtest: pipe-C
[318.039588] Dynamic subtest pipe-C: SUCCESS (0.000s)
[318.049546] Starting dynamic subtest: pipe-D
[318.051561] Dynamic subtest pipe-D: SUCCESS (0.000s)
[318.053596] Subtest basic: SUCCESS (0.050s)
[318.092336] [099/155] (713s left) gem_pxp (verify-pxp-stale-buf-execution)
[318.213959] Starting subtest: verify-pxp-stale-buf-execution
[319.274175] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.063s)
[319.393209] [100/155] (712s left) gem_exec_params (no-vebox)
[319.449981] Starting subtest: no-vebox
[319.452300] Subtest no-vebox: SUCCESS (0.003s)
[320.994384] [101/155] (710s left) gem_eio (in-flight-internal-10ms)
[321.223026] Starting subtest: in-flight-internal-10ms
[322.990499] Subtest in-flight-internal-10ms: SUCCESS (1.768s)
[323.141344] [102/155] (708s left) gem_exec_balancer (persistence)
[323.414882] Starting subtest: persistence
[324.356162] Subtest persistence: SUCCESS (0.940s)
[324.486022] [103/155] (707s left) prime_mmap_kms (buffer-sharing)
[324.586075] Starting subtest: buffer-sharing
[325.031313] Subtest buffer-sharing: SUCCESS (0.445s)
[325.848087] [104/155] (705s left) kms_frontbuffer_tracking (fbc-1p-rte)
[325.955171] Starting subtest: fbc-1p-rte
[331.417441] Subtest fbc-1p-rte: SUCCESS (5.471s)
[332.592865] [105/155] (699s left) gem_exec_schedule (implicit-write-read)
[332.671495] Starting subtest: implicit-write-read
[332.673930] Starting dynamic subtest: rcs0
[332.769749] Dynamic subtest rcs0: SUCCESS (0.107s)
[332.790205] Starting dynamic subtest: vcs0
[332.854418] Dynamic subtest vcs0: SUCCESS (0.084s)
[332.870330] Starting dynamic subtest: vcs1
[332.943448] Dynamic subtest vcs1: SUCCESS (0.086s)
[332.957959] Starting dynamic subtest: vecs0
[333.029939] Dynamic subtest vecs0: SUCCESS (0.085s)
[333.044327] Starting dynamic subtest: ccs0
[333.123862] Dynamic subtest ccs0: SUCCESS (0.094s)
[333.126603] Subtest implicit-write-read: SUCCESS (0.462s)
[334.011254] [106/155] (697s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[334.123553] Starting subtest: cursorA-vs-flipB-atomic-transitions
[334.128141] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[334.269170] [107/155] (697s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[334.367537] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[334.372003] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[334.472167] [108/155] (697s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-render)
[334.571784] Starting subtest: hdr-rgb101010-draw-render
[334.575957] Subtest hdr-rgb101010-draw-render: SKIP (0.000s)
[334.669617] [109/155] (697s left) kms_rotation_crc (primary-rotation-90)
[334.756196] Starting subtest: primary-rotation-90
[334.760582] Subtest primary-rotation-90: SKIP (0.001s)
[334.828453] [110/155] (697s left) gem_userptr_blits (unsync-unmap-cycles)
[334.891555] Subtest unsync-unmap-cycles: SKIP (0.000s)
[335.020026] [111/155] (696s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[335.122964] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[335.126438] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[335.237724] [112/155] (696s left) kms_frontbuffer_tracking (fbc-tiling-4)
[335.327962] Starting subtest: fbc-tiling-4
[339.564593] Subtest fbc-tiling-4: SUCCESS (4.242s)
[340.768264] [113/155] (691s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[340.861502] Starting subtest: async-flip-with-page-flip-events-linear
[341.240763] Starting dynamic subtest: pipe-A-eDP-1
[344.019782] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.779s)
[344.393694] Starting dynamic subtest: pipe-B-eDP-1
[347.155400] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.770s)
[347.531040] Starting dynamic subtest: pipe-C-eDP-1
[350.280722] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.760s)
[350.649946] Starting dynamic subtest: pipe-D-eDP-1
[353.411206] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.769s)
[353.413780] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.555s)
[354.602532] [114/155] (677s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-blt)
[354.710647] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-blt
[354.715048] Subtest psrhdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[354.805756] [115/155] (677s left) syncobj_timeline (wait-any-complex)
[354.853334] Starting subtest: wait-any-complex
[354.913889] Subtest wait-any-complex: SUCCESS (0.062s)
[354.993297] [116/155] (677s left) gen7_exec_parse (basic-allocation)
[355.052003] Subtest basic-allocation: SKIP (0.000s)
[355.129685] [117/155] (677s left) gem_ctx_persistence (engines-hostile-preempt)
[355.178299] Starting subtest: engines-hostile-preempt
[355.180534] Starting dynamic subtest: rcs0
[355.241613] Dynamic subtest rcs0: SUCCESS (0.063s)
[355.255322] Starting dynamic subtest: bcs0
[355.281281] Dynamic subtest bcs0: SUCCESS (0.038s)
[355.293436] Starting dynamic subtest: vcs0
[356.871626] Dynamic subtest vcs0: SUCCESS (1.588s)
[356.885613] Starting dynamic subtest: vcs1
[358.924425] Dynamic subtest vcs1: SUCCESS (2.050s)
[358.938124] Starting dynamic subtest: vecs0
[360.902746] Dynamic subtest vecs0: SUCCESS (1.976s)
[360.916683] Starting dynamic subtest: ccs0
[360.957636] Dynamic subtest ccs0: SUCCESS (0.053s)
[360.959927] Subtest engines-hostile-preempt: SUCCESS (5.780s)
[361.085920] [118/155] (671s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[361.146675] Starting subtest: etime-single-wait-all-available-unsubmitted
[361.252944] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.104s)
[361.333249] [119/155] (670s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[361.433204] Starting subtest: y-tiled-64bpp-rotate-180
[361.437196] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[361.527730] [120/155] (670s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs)
[361.612659] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs
[361.614785] Starting dynamic subtest: pipe-A-eDP-1
[361.640400] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[361.654454] Starting dynamic subtest: pipe-B-eDP-1
[361.677005] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[361.687092] Starting dynamic subtest: pipe-C-eDP-1
[361.709986] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[361.720109] Starting dynamic subtest: pipe-D-eDP-1
[361.744509] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[361.748898] Subtest random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[363.869313] [121/155] (668s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[363.966972] Starting subtest: 4-tiled-16bpp-rotate-180
[365.368640] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.401s)
[365.496033] [122/155] (666s left) gem_exec_balancer (bonded-dual)
[365.551649] Starting subtest: bonded-dual
[365.555278] Subtest bonded-dual: SKIP (0.000s)
[365.839708] [123/155] (666s left) kms_chamelium_color (gamma)
[365.941417] Subtest gamma: SKIP (0.000s)
[365.987594] [124/155] (666s left) kms_cursor_crc (cursor-offscreen-512x512)
[366.272991] Starting subtest: cursor-offscreen-512x512
[366.277370] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[366.455864] [125/155] (666s left) gem_exec_schedule (u-semaphore-codependency)
[366.523432] Starting subtest: u-semaphore-codependency
[366.528702] Subtest u-semaphore-codependency: SUCCESS (0.013s)
[366.876156] [126/155] (665s left) api_intel_bb (render-ccs)
[366.930554] Starting subtest: render-ccs
[367.289633] Subtest render-ccs: SUCCESS (0.360s)
[367.411287] [127/155] (665s left) gem_bad_reloc (negative-reloc)
[367.461521] Subtest negative-reloc: SKIP (0.000s)
[367.538696] [128/155] (665s left) kms_universal_plane (cursor-fb-leak)
[367.631878] Starting subtest: cursor-fb-leak
[367.634189] Starting dynamic subtest: pipe-A-eDP-1
[367.952774] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.322s)
[367.968115] Starting dynamic subtest: pipe-B-eDP-1
[369.289988] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.335s)
[369.305983] Starting dynamic subtest: pipe-C-eDP-1
[370.709174] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.415s)
[370.723453] Starting dynamic subtest: pipe-D-eDP-1
[372.056965] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.345s)
[372.059538] Subtest cursor-fb-leak: SUCCESS (4.426s)
[373.218506] [129/155] (659s left) gem_lmem_swapping (verify-random)
[373.267313] Subtest verify-random: SKIP (0.000s)
[373.309238] [130/155] (659s left) kms_vblank (crtc-id)
[373.396983] Starting subtest: crtc-id
[373.399197] Starting dynamic subtest: pipe-A-eDP-1
[373.838509] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.442s)
[373.854018] Starting dynamic subtest: pipe-D-eDP-1
[374.947935] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.105s)
[374.950281] Subtest crtc-id: SUCCESS (1.551s)
[375.795100] [131/155] (657s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[375.888537] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[375.890751] Starting dynamic subtest: pipe-A-eDP-1
[381.770495] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.882s)
[381.785696] Starting dynamic subtest: pipe-B-eDP-1
[388.425414] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.654s)
[388.427936] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.539s)
[389.311463] [132/155] (643s left) kms_psr (psr-primary-render)
[389.408976] Starting subtest: psr-primary-render
[389.411376] Starting dynamic subtest: eDP-1
[391.208236] Dynamic subtest eDP-1: SUCCESS (1.799s)
[391.210761] Subtest psr-primary-render: SUCCESS (1.800s)
[392.077962] [133/155] (640s left) gem_spin_batch (user-each)
[392.145446] Starting subtest: user-each
[395.508726] Subtest user-each: SUCCESS (3.362s)
[395.951169] [134/155] (637s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[396.056488] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[396.060727] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[396.162010] [135/155] (636s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-onoff)
[396.260121] Starting subtest: psrhdr-2p-primscrn-cur-indfb-onoff
[396.264486] Subtest psrhdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[396.360022] [136/155] (636s left) kms_hdr (static-toggle)
[396.452178] Starting subtest: static-toggle
[396.454553] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[396.458733] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[396.471094] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[396.475565] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[396.480160] Subtest static-toggle: SKIP (0.003s)
[396.541960] [137/155] (636s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-blt)
[396.650805] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-blt
[396.655195] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[396.757638] [138/155] (636s left) kms_content_protection (mei-interface)
[396.892091] Starting subtest: mei-interface
[396.895926] Subtest mei-interface: SKIP (0.001s)
[397.024608] [139/155] (636s left) kms_flip (dpms-off-confusion-interruptible)
[397.124274] Starting subtest: dpms-off-confusion-interruptible
[397.126245] Starting dynamic subtest: A-eDP1
[405.778245] Dynamic subtest A-eDP1: SUCCESS (8.651s)
[405.794217] Starting dynamic subtest: B-eDP1
[414.407291] Dynamic subtest B-eDP1: SUCCESS (8.624s)
[414.418964] Starting dynamic subtest: C-eDP1
[423.021477] Dynamic subtest C-eDP1: SUCCESS (8.613s)
[423.035421] Starting dynamic subtest: D-eDP1
[431.654336] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[431.659247] Subtest dpms-off-confusion-interruptible: SUCCESS (34.531s)
[432.847997] [140/155] (600s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[432.951941] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[432.955970] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[433.059047] [141/155] (600s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-pwrite)
[433.161593] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-pwrite
[433.166135] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[433.259864] [142/155] (600s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-indfb-pgflip-blt)
[433.359697] Starting subtest: fbchdr-2p-scndscrn-indfb-pgflip-blt
[433.364029] Subtest fbchdr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[433.464939] [143/155] (599s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[433.564982] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[433.569220] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[433.673232] [144/155] (599s left) kms_cursor_crc (cursor-sliding-32x32)
[433.813849] Starting subtest: cursor-sliding-32x32
[433.862317] Subtest cursor-sliding-32x32: SKIP (0.044s)
[434.157250] [145/155] (599s left) gem_gpgpu_fill (offset-16x16)
[434.224984] Starting subtest: offset-16x16
[434.229182] Subtest offset-16x16: SUCCESS (0.006s)
[434.359411] [146/155] (599s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[434.454625] Starting subtest: plane-upscale-20x20-with-pixel-format
[434.456561] Starting dynamic subtest: pipe-A
[435.502470] Dynamic subtest pipe-A: SUCCESS (1.050s)
[435.513427] Starting dynamic subtest: pipe-B
[437.643742] Dynamic subtest pipe-B: SUCCESS (2.140s)
[437.654753] Starting dynamic subtest: pipe-C
[438.848434] Dynamic subtest pipe-C: SUCCESS (1.203s)
[438.859657] Starting dynamic subtest: pipe-D
[440.085618] Dynamic subtest pipe-D: SUCCESS (1.236s)
[440.088253] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.633s)
[441.296841] [147/155] (592s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[441.403622] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[441.405971] Starting dynamic subtest: pipe-A-eDP-1
[441.429289] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[441.441691] Starting dynamic subtest: pipe-B-eDP-1
[441.462892] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[441.474143] Starting dynamic subtest: pipe-C-eDP-1
[441.495921] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[441.506787] Starting dynamic subtest: pipe-D-eDP-1
[441.529217] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[441.533498] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[443.643968] [148/155] (589s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[443.701498] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[443.703862] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[443.796269] [149/155] (589s left) gem_mmap_gtt (hang)
[443.858670] Subtest hang: SKIP (0.000s)
[443.920456] [150/155] (589s left) kms_pm_rpm (fences)
[446.059319] Starting subtest: fences
[446.064340] Subtest fences: SKIP (0.000s)
[446.462728] [151/155] (587s left) kms_plane_lowres (tiling-4)
[446.553961] Starting subtest: tiling-4
[446.556286] Starting dynamic subtest: pipe-A-eDP-1
[446.560919] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[446.858472] Starting dynamic subtest: pipe-B-eDP-1
[446.863059] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[446.873831] Starting dynamic subtest: pipe-C-eDP-1
[446.878550] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[446.889389] Starting dynamic subtest: pipe-D-eDP-1
[446.893801] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[446.898048] Subtest tiling-4: SKIP (0.302s)
[447.674087] [152/155] (586s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[448.070234] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[448.895203] [153/155] (584s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[449.005625] Starting subtest: x-tiled-32bpp-rotate-180
[450.416298] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.412s)
[450.561281] [154/155] (583s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[450.608468] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[450.612172] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[450.807610] [155/155] (583s left) perf (blocking)
[450.921595] Starting subtest: blocking
[450.923971] Starting dynamic subtest: 0-rcs0
[460.932793] Dynamic subtest 0-rcs0: SUCCESS (10.009s)
[460.944936] Starting dynamic subtest: 1-vcs1
[470.953291] Dynamic subtest 1-vcs1: SUCCESS (10.020s)
[470.955697] Subtest blocking: SUCCESS (20.032s)
[471.004410] Closing watchdogs
results: parsing output: 0/ for test: kms_psr2_sf
results: parsing output: 1/ for test: kms_flip_scaled_crc
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: i915_pm_rpm
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: gem_exec_fence
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: gem_exec_reloc
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: perf
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_exec_reloc
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: syncobj_wait
results: parsing output: 16/ for test: gem_exec_whisper
results: parsing output: 17/ for test: kms_cursor_crc
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: sriov_basic
results: parsing output: 21/ for test: kms_pipe_b_c_ivb
results: parsing output: 22/ for test: gem_create
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: device_reset
results: parsing output: 25/ for test: gem_exec_fence
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_plane_scaling
results: parsing output: 28/ for test: gem_exec_params
results: parsing output: 29/ for test: gem_userptr_blits
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_dirtyfb
results: parsing output: 32/ for test: gem_mmap_offset
results: parsing output: 33/ for test: i915_suspend
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_exec_flush
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: vgem_basic
results: parsing output: 40/ for test: kms_plane_multiple
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: core_setmaster_vs_auth
results: parsing output: 43/ for test: kms_atomic_interruptible
results: parsing output: 44/ for test: kms_flip
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: gem_ctx_persistence
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: api_intel_allocator
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_ccs
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: sysfs_heartbeat_interval
results: parsing output: 56/ for test: i915_drm_fdinfo
results: parsing output: 57/ for test: kms_feature_discovery
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: kms_properties
results: parsing output: 60/ for test: kms_addfb_basic
results: parsing output: 61/ for test: perf
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_vblank
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: gem_exec_async
results: parsing output: 66/ for test: i915_hangman
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_mmap_wc
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_getfb
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: kms_busy
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: syncobj_wait
results: parsing output: 77/ for test: kms_cursor_legacy
results: parsing output: 78/ for test: kms_plane
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_workarounds
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_psr
results: parsing output: 86/ for test: kms_cursor_legacy
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: kms_invalid_mode
results: parsing output: 91/ for test: kms_psr2_sf
results: parsing output: 92/ for test: kms_color
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: gen9_exec_parse
results: parsing output: 95/ for test: gem_linear_blits
results: parsing output: 96/ for test: syncobj_wait
results: parsing output: 97/ for test: kms_legacy_colorkey
results: parsing output: 98/ for test: gem_pxp
results: parsing output: 99/ for test: gem_exec_params
results: parsing output: 100/ for test: gem_eio
results: parsing output: 101/ for test: gem_exec_balancer
results: parsing output: 102/ for test: prime_mmap_kms
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: kms_cursor_legacy
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_rotation_crc
results: parsing output: 109/ for test: gem_userptr_blits
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_async_flips
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: gen7_exec_parse
results: parsing output: 116/ for test: gem_ctx_persistence
results: parsing output: 117/ for test: syncobj_timeline
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: gem_exec_balancer
results: parsing output: 122/ for test: kms_chamelium_color
results: parsing output: 123/ for test: kms_cursor_crc
results: parsing output: 124/ for test: gem_exec_schedule
results: parsing output: 125/ for test: api_intel_bb
results: parsing output: 126/ for test: gem_bad_reloc
results: parsing output: 127/ for test: kms_universal_plane
results: parsing output: 128/ for test: gem_lmem_swapping
results: parsing output: 129/ for test: kms_vblank
results: parsing output: 130/ for test: kms_psr2_sf
results: parsing output: 131/ for test: kms_psr
results: parsing output: 132/ for test: gem_spin_batch
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: kms_hdr
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_content_protection
results: parsing output: 138/ for test: kms_flip
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_cursor_crc
results: parsing output: 144/ for test: gem_gpgpu_fill
results: parsing output: 145/ for test: kms_plane_scaling
results: parsing output: 146/ for test: kms_ccs
results: parsing output: 147/ for test: syncobj_timeline
results: parsing output: 148/ for test: gem_mmap_gtt
results: parsing output: 149/ for test: kms_pm_rpm
results: parsing output: 150/ for test: kms_plane_lowres
results: parsing output: 151/ for test: kms_chamelium_hpd
results: parsing output: 152/ for test: kms_big_fb
results: parsing output: 153/ for test: gem_render_copy
results: parsing output: 154/ for test: perf
Done.