[67.604098] Initializing watchdogs
[67.604178]   /dev/watchdog0
[67.636178] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[67.651393] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[67.656270] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[67.659747] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[69.168933] Starting subtest: fbc-tiling-linear
[72.006767] Subtest fbc-tiling-linear: SUCCESS (2.844s)
[72.369142] [002/156] (955s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[72.419924] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[72.500382] [003/156] (955s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[74.000138] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[74.003962] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[74.233949] [004/156] (954s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[75.734218] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[75.737692] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[75.903135] [005/156] (952s left) kms_force_connector_basic (prune-stale-modes)
[76.059670] Starting subtest: prune-stale-modes
[76.191543] Subtest prune-stale-modes: SUCCESS (0.132s)
[77.124562] [006/156] (951s left) perf_pmu (most-busy-check-all)
[77.181097] Starting subtest: most-busy-check-all
[77.182725] Starting dynamic subtest: rcs0
[77.749907] Dynamic subtest rcs0: SUCCESS (0.569s)
[77.761995] Starting dynamic subtest: bcs0
[78.299844] Dynamic subtest bcs0: SUCCESS (0.550s)
[78.311963] Starting dynamic subtest: vcs0
[78.847922] Dynamic subtest vcs0: SUCCESS (0.548s)
[78.859989] Starting dynamic subtest: vcs1
[79.395868] Dynamic subtest vcs1: SUCCESS (0.548s)
[79.407492] Starting dynamic subtest: vecs0
[79.942964] Dynamic subtest vecs0: SUCCESS (0.547s)
[79.944955] Subtest most-busy-check-all: SUCCESS (2.763s)
[80.056744] [007/156] (948s left) gem_ccs (large-ctrl-surf-copy)
[80.113827] Starting subtest: large-ctrl-surf-copy
[80.117187] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[80.217433] [008/156] (948s left) kms_plane_alpha_blend (coverage-7efc)
[81.709806] Starting subtest: coverage-7efc
[81.984891] Starting dynamic subtest: pipe-A-HDMI-A-1
[82.460976] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.475s)
[82.492599] Starting dynamic subtest: pipe-D-HDMI-A-1
[83.090694] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.607s)
[83.092300] Subtest coverage-7efc: SUCCESS (1.383s)
[83.290868] [009/156] (945s left) gem_mmap_gtt (fault-concurrent)
[83.340416] Subtest fault-concurrent: SKIP (0.000s)
[83.418686] [010/156] (945s left) api_intel_allocator (gem-pool)
[83.464782] Starting subtest: gem-pool
[83.489600] Subtest gem-pool: SUCCESS (0.025s)
[83.641723] [011/156] (945s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[85.140139] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[85.143310] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[85.307809] [012/156] (943s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[86.798490] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[86.801749] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[86.934788] [013/156] (942s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[88.431854] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[88.433222] Starting dynamic subtest: pipe-A-HDMI-A-1
[88.446714] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[88.456685] Starting dynamic subtest: pipe-B-HDMI-A-1
[88.470547] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[88.480900] Starting dynamic subtest: pipe-C-HDMI-A-1
[88.494994] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[88.504904] Starting dynamic subtest: pipe-D-HDMI-A-1
[88.521679] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[88.524864] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.008s)
[88.704655] [014/156] (940s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[90.197581] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[90.199201] Starting dynamic subtest: pipe-A-HDMI-A-1
[90.217789] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[90.228574] Starting dynamic subtest: pipe-B-HDMI-A-1
[90.244951] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[90.255197] Starting dynamic subtest: pipe-C-HDMI-A-1
[90.272306] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[90.282488] Starting dynamic subtest: pipe-D-HDMI-A-1
[90.299232] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[90.301873] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[90.471598] [015/156] (938s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[91.982214] Starting subtest: hdr-rgb565-draw-blt
[91.985805] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[92.159899] [016/156] (937s left) kms_pm_rpm (legacy-planes-dpms)
[96.046887] Starting subtest: legacy-planes-dpms
[96.049923] Starting dynamic subtest: plane-65
[102.974430] Dynamic subtest plane-65: SUCCESS (6.923s)
[103.024252] Starting dynamic subtest: plane-95
[109.839123] Dynamic subtest plane-95: SUCCESS (6.864s)
[109.888453] Starting dynamic subtest: plane-125
[117.605055] Dynamic subtest plane-125: SUCCESS (7.768s)
[117.654670] Starting dynamic subtest: plane-135
[124.588638] Dynamic subtest plane-135: SUCCESS (6.981s)
[124.638287] Starting dynamic subtest: plane-145
[131.571195] Dynamic subtest plane-145: SUCCESS (6.978s)
[131.623127] Starting dynamic subtest: plane-155
[138.448973] Dynamic subtest plane-155: SUCCESS (6.877s)
[138.451975] Subtest legacy-planes-dpms: SUCCESS (42.404s)
[139.466344] [017/156] (890s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[139.521882] Starting subtest: x-tiled-to-vebox-y-tiled
[139.523577] Starting dynamic subtest: smem
[139.606046] Dynamic subtest smem: SUCCESS (0.084s)
[139.616788] Starting dynamic subtest: lmem0
[140.366526] Dynamic subtest lmem0: SUCCESS (0.756s)
[140.368479] Subtest x-tiled-to-vebox-y-tiled: SUCCESS (0.841s)
[140.616182] [018/156] (888s left) sysfs_heartbeat_interval (invalid)
[140.670901] Starting subtest: invalid
[140.672653] Starting dynamic subtest: vecs0
[140.674329] Dynamic subtest vecs0: SUCCESS (0.000s)
[140.685600] Starting dynamic subtest: vcs0
[140.687526] Dynamic subtest vcs0: SUCCESS (0.000s)
[140.698370] Starting dynamic subtest: bcs0
[140.700190] Dynamic subtest bcs0: SUCCESS (0.000s)
[140.711218] Starting dynamic subtest: rcs0
[140.712753] Dynamic subtest rcs0: SUCCESS (0.000s)
[140.724761] Starting dynamic subtest: vcs1
[140.726213] Dynamic subtest vcs1: SUCCESS (0.000s)
[140.727736] Subtest invalid: SUCCESS (0.005s)
[140.785937] [019/156] (888s left) kms_frontbuffer_tracking (fbc-stridechange)
[142.285784] Starting subtest: fbc-stridechange
[144.320077] Subtest fbc-stridechange: SUCCESS (2.038s)
[144.601534] [020/156] (885s left) gem_ctx_param (basic-default)
[144.650558] Starting subtest: basic-default
[144.652435] Subtest basic-default: SUCCESS (0.000s)
[144.777266] [021/156] (885s left) kms_psr (fbc-pr-primary-mmap-gtt)
[146.268662] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[146.344947] [022/156] (883s left) i915_pm_freq_mult (media-freq)
[146.395299] Starting subtest: media-freq
[146.396925] Starting dynamic subtest: gt0
[146.400125] Dynamic subtest gt0: SKIP (0.000s)
[146.403268] Subtest media-freq: SKIP (0.001s)
[146.511365] [023/156] (883s left) prime_busy (before-wait)
[146.565048] Starting subtest: before-wait
[146.571177] Starting dynamic subtest: rcs0
[146.618320] Dynamic subtest rcs0: SUCCESS (0.047s)
[146.629984] Starting dynamic subtest: bcs0
[146.668193] Dynamic subtest bcs0: SUCCESS (0.049s)
[146.682019] Starting dynamic subtest: vcs0
[146.709322] Dynamic subtest vcs0: SUCCESS (0.041s)
[146.724514] Starting dynamic subtest: vcs1
[146.753137] Dynamic subtest vcs1: SUCCESS (0.043s)
[146.778427] Starting dynamic subtest: vecs0
[146.795184] Dynamic subtest vecs0: SUCCESS (0.041s)
[146.906922] Subtest before-wait: SUCCESS (0.338s)
[147.056195] [024/156] (882s left) gem_exec_gttfill (engines)
[147.116557] Starting subtest: engines
[147.118017] Starting dynamic subtest: rcs0
[174.681401] Dynamic subtest rcs0: SUCCESS (27.561s)
[174.692878] Starting dynamic subtest: bcs0
[202.476360] Dynamic subtest bcs0: SUCCESS (27.795s)
[202.488042] Starting dynamic subtest: vcs0
[230.307234] Dynamic subtest vcs0: SUCCESS (27.830s)
[230.318653] Starting dynamic subtest: vcs1
[258.084961] Dynamic subtest vcs1: SUCCESS (27.777s)
[258.096512] Starting dynamic subtest: vecs0
[285.864308] Dynamic subtest vecs0: SUCCESS (27.779s)
[285.865986] Subtest engines: SUCCESS (138.746s)
[286.270597] [025/156] (743s left) syncobj_timeline (etime-multi-wait-all-submitted)
[286.376434] Starting subtest: etime-multi-wait-all-submitted
[286.485889] Subtest etime-multi-wait-all-submitted: SUCCESS (0.106s)
[286.596300] [026/156] (743s left) gem_ctx_param (get-priority-new-ctx)
[286.643332] Starting subtest: get-priority-new-ctx
[286.644691] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[286.747542] [027/156] (743s left) kms_big_fb (linear-32bpp-rotate-90)
[288.268634] Starting subtest: linear-32bpp-rotate-90
[288.370372] Subtest linear-32bpp-rotate-90: SKIP (0.102s)
[288.517347] [028/156] (741s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[292.961702] Starting subtest: heavy-verify-multi-ccs
[292.963362] Starting dynamic subtest: lmem0
[292.966940] Dynamic subtest lmem0: SKIP (0.001s)
[292.970822] Subtest heavy-verify-multi-ccs: SKIP (0.001s)
[296.810666] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[296.813572] [029/156] (733s left) gem_ctx_shared (disjoint-timelines)
[300.165976] Starting subtest: disjoint-timelines
[300.275133] Subtest disjoint-timelines: SUCCESS (0.110s)
[300.606288] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[300.606993] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[300.609986] [030/156] (729s left) kms_psr (fbc-psr2-cursor-plane-move)
[302.104796] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[302.182589] [031/156] (728s left) gem_bad_reloc (negative-reloc-bltcopy)
[302.239548] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[302.337572] [032/156] (728s left) kms_chamelium_hpd (hdmi-hpd)
[303.880549] Subtest hdmi-hpd: SKIP (0.000s)
[304.016334] [033/156] (726s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[305.530907] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[305.614997] [034/156] (725s left) gem_eio (wait-10ms)
[305.756086] Starting subtest: wait-10ms
[306.126440] Subtest wait-10ms: SUCCESS (0.371s)
[307.714274] [035/156] (723s left) kms_content_protection (legacy-hdcp14)
[309.296042] Starting subtest: legacy-hdcp14
[309.302234] Subtest legacy-hdcp14: SKIP (0.002s)
[309.490124] [036/156] (721s left) syncobj_wait (reset-signaled)
[309.541978] Starting subtest: reset-signaled
[309.543669] Subtest reset-signaled: SUCCESS (0.000s)
[309.672541] [037/156] (721s left) gem_ctx_isolation (dirty-switch)
[309.732824] Starting subtest: dirty-switch
[309.734407] Starting dynamic subtest: rcs0
[310.538731] Dynamic subtest rcs0: SUCCESS (0.807s)
[310.552206] Starting dynamic subtest: bcs0
[311.380244] Dynamic subtest bcs0: SUCCESS (0.841s)
[311.393289] Starting dynamic subtest: vcs0
[312.219772] Dynamic subtest vcs0: SUCCESS (0.839s)
[312.232265] Starting dynamic subtest: vcs1
[313.077635] Dynamic subtest vcs1: SUCCESS (0.858s)
[313.089745] Starting dynamic subtest: vecs0
[313.907675] Dynamic subtest vecs0: SUCCESS (0.829s)
[313.909121] Subtest dirty-switch: SUCCESS (4.177s)
[314.193800] [038/156] (716s left) gem_flink_basic (bad-open)
[314.241638] Starting subtest: bad-open
[314.243121] Subtest bad-open: SUCCESS (0.000s)
[314.357549] [039/156] (716s left) gem_caching (writes)
[314.408986] Starting subtest: writes
[319.670518] Subtest writes: SUCCESS (5.261s)
[319.847921] [040/156] (711s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[321.343336] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[321.344856] Starting dynamic subtest: pipe-A
[322.132740] Dynamic subtest pipe-A: SUCCESS (0.790s)
[322.151209] Starting dynamic subtest: pipe-B
[322.905648] Dynamic subtest pipe-B: SUCCESS (0.772s)
[322.920599] Starting dynamic subtest: pipe-C
[323.680165] Dynamic subtest pipe-C: SUCCESS (0.773s)
[323.697053] Starting dynamic subtest: pipe-D
[324.454225] Dynamic subtest pipe-D: SUCCESS (0.773s)
[324.456253] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.113s)
[324.668845] [041/156] (706s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[324.733603] Starting subtest: single-wait-all-for-submit-available-submitted
[324.735156] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[324.884427] [042/156] (706s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[326.385289] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[326.388423] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[326.589290] [043/156] (705s left) kms_pm_rpm (system-suspend-idle)
[331.039716] Starting subtest: system-suspend-idle
[350.424664] Subtest system-suspend-idle: SUCCESS (4.389s)
[355.420713] [044/156] (680s left) kms_atomic_transition (modeset-transition)
[356.916581] Starting subtest: modeset-transition
[356.918180] Starting dynamic subtest: 1x-outputs
[359.005886] Dynamic subtest 1x-outputs: SUCCESS (2.098s)
[359.007455] Subtest modeset-transition: SUCCESS (2.101s)
[359.215613] [045/156] (676s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[360.714428] Starting subtest: pipe-fbc-rte
[360.725021] Subtest pipe-fbc-rte: SKIP (0.001s)
[360.894237] [046/156] (675s left) gem_spin_batch (resubmit-all)
[360.953993] Starting subtest: resubmit-all
[360.955702] Starting dynamic subtest: rcs0
[360.976871] Dynamic subtest rcs0: SUCCESS (0.023s)
[360.992495] Starting dynamic subtest: bcs0
[360.994342] Dynamic subtest bcs0: SUCCESS (0.003s)
[361.008675] Starting dynamic subtest: vcs0
[361.010778] Dynamic subtest vcs0: SUCCESS (0.002s)
[361.024673] Starting dynamic subtest: vcs1
[361.026467] Dynamic subtest vcs1: SUCCESS (0.003s)
[361.040399] Starting dynamic subtest: vecs0
[361.042176] Dynamic subtest vecs0: SUCCESS (0.002s)
[361.044091] Subtest resubmit-all: SUCCESS (0.038s)
[361.355860] [047/156] (674s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[362.853815] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[362.857501] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[363.062993] [048/156] (673s left) syncobj_basic (illegal-fd-to-handle)
[363.110546] Starting subtest: illegal-fd-to-handle
[363.111940] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[363.220982] [049/156] (673s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[364.717168] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[364.721095] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[364.930791] [050/156] (671s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[366.441792] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[367.711081] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.271s)
[367.928506] [051/156] (668s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[367.977896] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[368.081314] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[368.230168] [052/156] (668s left) api_intel_bb (misplaced-blitter)
[368.282605] Starting subtest: misplaced-blitter
[368.301594] Subtest misplaced-blitter: SUCCESS (0.021s)
[368.535054] [053/156] (668s left) kms_atomic_interruptible (universal-setplane-cursor)
[370.024982] Starting subtest: universal-setplane-cursor
[370.026623] Starting dynamic subtest: pipe-A-HDMI-A-1
[376.227519] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.207s)
[376.229377] Subtest universal-setplane-cursor: SUCCESS (6.210s)
[376.420604] [054/156] (660s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[377.930696] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[377.934138] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[378.105565] [055/156] (658s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[379.603965] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[379.607151] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[379.808552] [056/156] (657s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[381.301864] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[381.303419] Starting dynamic subtest: pipe-A-valid-mode
[382.646978] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.346s)
[382.648444] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.348s)
[382.855916] [057/156] (654s left) gem_pxp (create-protected-buffer)
[385.434400] Starting subtest: create-protected-buffer
[385.437996] Subtest create-protected-buffer: SKIP (0.000s)
[385.567250] [058/156] (651s left) gem_fence_thrash (bo-write-verify-threaded-x)
[385.612963] Starting subtest: bo-write-verify-threaded-x
[385.619009] Subtest bo-write-verify-threaded-x: SKIP (0.002s)
[385.697405] [059/156] (651s left) gen9_exec_parse (allowed-single)
[385.753115] Subtest allowed-single: SKIP (0.000s)
[385.828468] [060/156] (651s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[387.332693] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[387.336151] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[387.533135] [061/156] (649s left) gem_exec_balancer (bonded-semaphore)
[387.593528] Starting subtest: bonded-semaphore
[387.597188] Subtest bonded-semaphore: SKIP (0.000s)
[388.097602] [062/156] (649s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[389.590618] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[389.628394] Starting dynamic subtest: pipe-A-plane-0
[390.161431] Dynamic subtest pipe-A-plane-0: SUCCESS (0.533s)
[390.207943] Starting dynamic subtest: pipe-A-plane-3
[390.477989] Dynamic subtest pipe-A-plane-3: SUCCESS (0.312s)
[390.529753] Starting dynamic subtest: pipe-A-plane-5
[390.810618] Dynamic subtest pipe-A-plane-5: SUCCESS (0.329s)
[391.024321] Starting dynamic subtest: pipe-B-plane-0
[391.393772] Dynamic subtest pipe-B-plane-0: SUCCESS (0.399s)
[391.411574] Starting dynamic subtest: pipe-B-plane-3
[391.744097] Dynamic subtest pipe-B-plane-3: SUCCESS (0.349s)
[391.790907] Starting dynamic subtest: pipe-B-plane-5
[392.060115] Dynamic subtest pipe-B-plane-5: SUCCESS (0.313s)
[392.149892] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (2.562s)
[392.324925] [063/156] (645s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[393.825314] Starting subtest: fbc-rgb565-draw-mmap-wc
[393.828860] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[394.017851] [064/156] (643s left) kms_pwrite_crc (basic)
[395.513641] Starting subtest: basic
[396.141592] Subtest basic: SUCCESS (0.627s)
[396.373136] [065/156] (641s left) gem_flink_basic (bad-flink)
[396.422973] Starting subtest: bad-flink
[396.424788] Subtest bad-flink: SUCCESS (0.000s)
[396.579009] [066/156] (641s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[398.077728] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[398.081560] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[398.270498] [067/156] (639s left) gem_ctx_persistence (legacy-engines-persistence)
[398.329495] Starting subtest: legacy-engines-persistence
[398.331279] Starting dynamic subtest: render
[399.103391] Dynamic subtest render: SUCCESS (0.776s)
[399.124174] Starting dynamic subtest: bsd1
[399.865046] Dynamic subtest bsd1: SUCCESS (0.759s)
[399.884073] Starting dynamic subtest: bsd2
[400.636997] Dynamic subtest bsd2: SUCCESS (0.766s)
[400.655827] Starting dynamic subtest: blt
[401.406992] Dynamic subtest blt: SUCCESS (0.765s)
[401.426240] Starting dynamic subtest: vebox
[402.175035] Dynamic subtest vebox: SUCCESS (0.762s)
[402.181013] Subtest legacy-engines-persistence: SUCCESS (3.853s)
[402.329698] [068/156] (635s left) gen7_exec_parse (basic-allowed)
[402.384548] Subtest basic-allowed: SKIP (0.000s)
[402.472052] [069/156] (635s left) gem_exec_schedule (reorder-wide)
[402.653330] Subtest reorder-wide: SKIP (0.000s)
[402.920115] [070/156] (635s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[404.417947] Starting subtest: psrhdr-slowdraw
[404.421397] Subtest psrhdr-slowdraw: SKIP (0.000s)
[404.636656] [071/156] (633s left) perf (non-zero-reason)
[404.791195] Starting subtest: non-zero-reason
[404.792859] Starting dynamic subtest: 0-rcs0
[415.846021] Dynamic subtest 0-rcs0: SUCCESS (11.056s)
[415.847772] Subtest non-zero-reason: SUCCESS (11.058s)
[415.918589] [072/156] (622s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[417.420157] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[417.424031] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[417.608681] [073/156] (621s left) i915_drm_fdinfo (all-busy-check-all)
[417.662061] Starting subtest: all-busy-check-all
[417.681344] Subtest all-busy-check-all: SKIP (0.018s)
[417.812144] [074/156] (620s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[419.350108] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[419.354168] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[419.562273] [075/156] (619s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[421.054765] Starting subtest: compare-crc-sanitycheck-nv12
[421.056233] Starting dynamic subtest: pipe-A-HDMI-A-1
[421.686799] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.632s)
[421.704848] Starting dynamic subtest: pipe-B-HDMI-A-1
[422.226601] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.531s)
[422.242574] Starting dynamic subtest: pipe-C-HDMI-A-1
[422.741919] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.516s)
[422.758899] Starting dynamic subtest: pipe-D-HDMI-A-1
[423.282331] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.537s)
[423.283994] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.229s)
[423.489517] [076/156] (615s left) gem_exec_reloc (basic-write-gtt)
[423.545769] Subtest basic-write-gtt: SKIP (0.000s)
[423.660291] [077/156] (615s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[425.461022] Starting subtest: cursor-rapid-movement-max-size
[425.465309] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[425.652834] [078/156] (613s left) kms_color_pipeline (plane-lut1d)
[427.146553] Starting subtest: plane-lut1d
[427.344848] Starting dynamic subtest: pipe-A-plane-0
[427.606411] Dynamic subtest pipe-A-plane-0: SUCCESS (0.263s)
[427.631628] Starting dynamic subtest: pipe-A-plane-1
[427.792258] Dynamic subtest pipe-A-plane-1: SUCCESS (0.181s)
[427.835747] Starting dynamic subtest: pipe-A-plane-2
[427.989367] Dynamic subtest pipe-A-plane-2: SUCCESS (0.192s)
[428.283729] Starting dynamic subtest: pipe-B-plane-0
[428.444413] Dynamic subtest pipe-B-plane-0: SUCCESS (0.196s)
[428.485779] Starting dynamic subtest: pipe-B-plane-1
[428.658960] Dynamic subtest pipe-B-plane-1: SUCCESS (0.209s)
[428.693167] Starting dynamic subtest: pipe-B-plane-2
[428.840400] Dynamic subtest pipe-B-plane-2: SUCCESS (0.175s)
[429.144114] Starting dynamic subtest: pipe-C-plane-0
[429.317134] Dynamic subtest pipe-C-plane-0: SUCCESS (0.213s)
[429.363170] Starting dynamic subtest: pipe-C-plane-1
[429.535063] Dynamic subtest pipe-C-plane-1: SUCCESS (0.209s)
[429.559882] Starting dynamic subtest: pipe-C-plane-2
[429.733939] Dynamic subtest pipe-C-plane-2: SUCCESS (0.196s)
[430.060158] Starting dynamic subtest: pipe-D-plane-0
[430.223930] Dynamic subtest pipe-D-plane-0: SUCCESS (0.196s)
[430.250693] Starting dynamic subtest: pipe-D-plane-1
[430.423075] Dynamic subtest pipe-D-plane-1: SUCCESS (0.196s)
[430.464179] Starting dynamic subtest: pipe-D-plane-2
[430.639636] Dynamic subtest pipe-D-plane-2: SUCCESS (0.209s)
[430.692850] Subtest plane-lut1d: SUCCESS (3.550s)
[430.891744] [079/156] (608s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[432.403404] Starting subtest: yf-tiled-16bpp-rotate-180
[432.406494] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[432.583424] [080/156] (606s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[434.093494] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[434.096448] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[434.321156] [081/156] (605s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[435.831000] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[435.833924] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[436.016525] [082/156] (603s left) gem_exec_reloc (basic-wc-read)
[436.071368] Subtest basic-wc-read: SKIP (0.000s)
[436.201798] [083/156] (603s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[437.692565] Starting subtest: short-busy-flip-before-cursor-toggle
[437.695456] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[437.836563] [084/156] (601s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[439.334431] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[439.358305] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.022s)
[439.485422] [085/156] (600s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[441.018156] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[441.022007] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[441.210746] [086/156] (598s left) kms_draw_crc (draw-method-render)
[442.725997] Starting subtest: draw-method-render
[442.727642] Starting dynamic subtest: xrgb8888-untiled
[443.131596] Dynamic subtest xrgb8888-untiled: SUCCESS (0.404s)
[443.182034] Starting dynamic subtest: rgb565-untiled
[443.497824] Dynamic subtest rgb565-untiled: SUCCESS (0.359s)
[443.551041] Starting dynamic subtest: xrgb2101010-untiled
[443.847787] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.342s)
[443.897475] Starting dynamic subtest: xbgr16161616f-untiled
[444.230981] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.376s)
[444.278222] Starting dynamic subtest: xrgb8888-xtiled
[444.431257] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.192s)
[444.477787] Starting dynamic subtest: rgb565-xtiled
[444.631299] Dynamic subtest rgb565-xtiled: SUCCESS (0.192s)
[444.666962] Starting dynamic subtest: xrgb2101010-xtiled
[444.796763] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.159s)
[444.845910] Starting dynamic subtest: xbgr16161616f-xtiled
[444.996624] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.194s)
[445.022544] Starting dynamic subtest: xrgb8888-ytiled
[445.180394] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.177s)
[445.216242] Starting dynamic subtest: rgb565-ytiled
[445.363763] Dynamic subtest rgb565-ytiled: SUCCESS (0.176s)
[445.415335] Starting dynamic subtest: xrgb2101010-ytiled
[445.546950] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.176s)
[445.599801] Starting dynamic subtest: xbgr16161616f-ytiled
[445.747028] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.192s)
[445.756746] Subtest draw-method-render: SUCCESS (3.025s)
[445.986189] [087/156] (594s left) kms_cursor_crc (cursor-sliding-256x85)
[447.716973] Starting subtest: cursor-sliding-256x85
[447.723795] Starting dynamic subtest: pipe-A-HDMI-A-1
[452.191371] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.463s)
[452.228035] Starting dynamic subtest: pipe-D-HDMI-A-1
[456.539105] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (4.321s)
[456.540921] Subtest cursor-sliding-256x85: SUCCESS (8.814s)
[456.907932] [088/156] (583s left) kms_cursor_crc (cursor-onscreen-256x256)
[458.630017] Starting subtest: cursor-onscreen-256x256
[458.637394] Starting dynamic subtest: pipe-A-HDMI-A-1
[461.401589] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.762s)
[461.439314] Starting dynamic subtest: pipe-D-HDMI-A-1
[464.043335] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (2.617s)
[464.045141] Subtest cursor-onscreen-256x256: SUCCESS (5.410s)
[464.425625] [089/156] (575s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[465.925927] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[465.928964] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[466.137737] [090/156] (574s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[467.640647] Starting subtest: fbcpsr-slowdraw
[467.644173] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[467.843139] [091/156] (572s left) kms_lease (cursor-implicit-plane)
[469.358420] Starting subtest: cursor-implicit-plane
[469.360311] Starting dynamic subtest: pipe-A-HDMI-A-1
[469.736944] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.377s)
[469.754249] Starting dynamic subtest: pipe-B-HDMI-A-1
[469.965641] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.227s)
[469.985892] Starting dynamic subtest: pipe-C-HDMI-A-1
[470.195108] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.226s)
[470.215119] Starting dynamic subtest: pipe-D-HDMI-A-1
[470.429396] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.231s)
[470.431281] Subtest cursor-implicit-plane: SUCCESS (1.074s)
[470.644736] [092/156] (570s left) syncobj_wait (invalid-reset-zero-handles)
[470.695757] Starting subtest: invalid-reset-zero-handles
[470.697607] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[470.820803] [093/156] (569s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[472.320196] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[472.323618] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[472.512177] [094/156] (568s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[474.023010] Starting subtest: yf-tiled-64bpp-rotate-90
[474.026506] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[474.222169] [095/156] (566s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[475.724958] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[475.728462] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[475.939544] [096/156] (565s left) gem_exec_fence (long-history)
[476.334982] Starting subtest: long-history
[482.399386] Subtest long-history: SUCCESS (6.061s)
[482.823832] [097/156] (558s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[484.326417] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[484.330235] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[484.548423] [098/156] (556s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[486.041683] Starting subtest: bad-rotation-90-yf-tiled-ccs
[486.043640] Starting dynamic subtest: pipe-A-HDMI-A-1
[486.063524] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[486.079510] Starting dynamic subtest: pipe-B-HDMI-A-1
[486.098075] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[486.113315] Starting dynamic subtest: pipe-C-HDMI-A-1
[486.127806] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[486.142398] Starting dynamic subtest: pipe-D-HDMI-A-1
[486.160411] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[486.163431] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.010s)
[486.330163] [099/156] (555s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[487.822884] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[489.786101] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.964s)
[489.999516] [100/156] (551s left) kms_chamelium_audio (dp-audio-edid)
[491.553094] Subtest dp-audio-edid: SKIP (0.000s)
[491.692808] [101/156] (549s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[493.187349] Starting subtest: addfb25-yf-tiled-legacy
[493.189053] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[493.383853] [102/156] (548s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[494.884108] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[494.887879] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[495.048867] [103/156] (546s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[496.550846] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[496.556413] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[496.750590] [104/156] (545s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[498.248422] Starting subtest: fbc-2p-pri-indfb-multidraw
[498.251904] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[498.427865] [105/156] (543s left) kms_sequence (queue-busy)
[499.918837] Starting subtest: queue-busy
[499.920346] Starting dynamic subtest: pipe-A-HDMI-A-1
[502.489985] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.570s)
[502.505677] Starting dynamic subtest: pipe-B-HDMI-A-1
[504.916007] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.424s)
[504.932312] Starting dynamic subtest: pipe-C-HDMI-A-1
[507.343535] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.426s)
[507.362742] Starting dynamic subtest: pipe-D-HDMI-A-1
[509.777959] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (2.432s)
[509.779538] Subtest queue-busy: SUCCESS (9.862s)
[509.992686] [106/156] (532s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[511.484871] Starting subtest: plane-upscale-20x20-with-rotation
[511.486351] Starting dynamic subtest: pipe-A
[511.940954] Dynamic subtest pipe-A: SKIP (0.452s)
[511.956188] Starting dynamic subtest: pipe-B
[512.358880] Dynamic subtest pipe-B: SKIP (0.416s)
[512.374187] Starting dynamic subtest: pipe-C
[512.780619] Dynamic subtest pipe-C: SKIP (0.421s)
[512.795483] Starting dynamic subtest: pipe-D
[513.202096] Dynamic subtest pipe-D: SKIP (0.421s)
[513.205084] Subtest plane-upscale-20x20-with-rotation: SKIP (1.717s)
[513.408300] [107/156] (528s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[514.914544] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[514.917597] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[515.057652] [108/156] (527s left) gem_exec_reloc (basic-write-wc)
[515.113448] Subtest basic-write-wc: SKIP (0.000s)
[515.220023] [109/156] (527s left) syncobj_timeline (reset-signaled)
[515.270015] Starting subtest: reset-signaled
[515.271728] Subtest reset-signaled: SUCCESS (0.001s)
[515.390929] [110/156] (527s left) kms_psr (pr-cursor-plane-move)
[516.889583] Subtest pr-cursor-plane-move: SKIP (0.000s)
[516.966724] [111/156] (525s left) syncobj_wait (invalid-wait-illegal-handle)
[517.018509] Starting subtest: invalid-wait-illegal-handle
[517.020263] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[517.148212] [112/156] (525s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[518.645734] Starting subtest: extended-pageflip-modeset-hang-oldfb
[518.649736] Starting dynamic subtest: pipe-A
[526.387241] Dynamic subtest pipe-A: SUCCESS (7.739s)
[526.405097] Starting dynamic subtest: pipe-D
[534.113843] Dynamic subtest pipe-D: SUCCESS (7.724s)
[534.115372] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.472s)
[534.321925] [113/156] (508s left) kms_psr (psr2-primary-blt)
[535.810989] Subtest psr2-primary-blt: SKIP (0.000s)
[535.939799] [114/156] (506s left) gem_softpin (evict-snoop-interruptible)
[536.004985] Starting subtest: evict-snoop-interruptible
[536.008126] Subtest evict-snoop-interruptible: SKIP (0.000s)
[536.141185] [115/156] (506s left) kms_force_connector_basic (force-load-detect)
[536.293331] Starting subtest: force-load-detect
[536.296264] Subtest force-load-detect: SKIP (0.000s)
[537.252107] [116/156] (505s left) kms_chamelium_edid (hdmi-mode-timings)
[538.791565] Subtest hdmi-mode-timings: SKIP (0.000s)
[538.927797] [117/156] (504s left) syncobj_timeline (invalid-signal-zero-handles)
[538.978407] Starting subtest: invalid-signal-zero-handles
[538.980002] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[539.112862] [118/156] (504s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[540.613754] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[540.619032] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[540.840306] [119/156] (502s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[542.336885] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[542.339807] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[542.540275] [120/156] (500s left) gem_mmap_gtt (fault-concurrent-x)
[542.594162] Subtest fault-concurrent-X: SKIP (0.000s)
[542.672854] [121/156] (500s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[542.728995] Starting subtest: y-tiled-to-vebox-y-tiled
[542.730492] Starting dynamic subtest: smem
[542.812519] Dynamic subtest smem: SUCCESS (0.083s)
[542.827832] Starting dynamic subtest: lmem0
[543.566988] Dynamic subtest lmem0: SUCCESS (0.750s)
[543.568766] Subtest y-tiled-to-vebox-y-tiled: SUCCESS (0.836s)
[543.830248] [122/156] (499s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[545.328832] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[545.332089] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[545.513760] [123/156] (498s left) vgem_basic (debugfs)
[545.575704] Starting subtest: debugfs
[545.577426] Subtest debugfs: SUCCESS (0.000s)
[545.692547] [124/156] (498s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[547.183771] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[547.185175] Starting dynamic subtest: pipe-A-valid-mode
[548.490845] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.306s)
[548.492854] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.308s)
[548.747559] [125/156] (495s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[550.247591] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[550.250859] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[550.430380] [126/156] (493s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[551.929768] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[551.933052] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[552.108000] [127/156] (492s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[553.606919] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[553.610003] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[553.788110] [128/156] (490s left) kms_vblank (wait-forked-hang)
[555.281433] Starting subtest: wait-forked-hang
[555.283394] Starting dynamic subtest: pipe-A-HDMI-A-1
[581.504943] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (26.226s)
[581.525074] Starting dynamic subtest: pipe-D-HDMI-A-1
[606.434779] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (24.919s)
[606.436307] Subtest wait-forked-hang: SUCCESS (51.153s)
[606.646490] [129/156] (437s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[608.139713] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[608.141086] Starting dynamic subtest: pipe-A-valid-mode
[610.788523] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.647s)
[610.790013] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (2.650s)
[611.022883] [130/156] (433s left) gem_ctx_exec (basic-invalid-context)
[611.075830] Starting subtest: basic-invalid-context
[611.079844] Subtest basic-invalid-context: SUCCESS (0.004s)
[611.264964] [131/156] (433s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[612.773073] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[612.776406] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[612.963478] [132/156] (431s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[614.470916] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[614.551614] [133/156] (430s left) gem_ppgtt (blt-vs-render-ctxn)
[614.606733] Starting subtest: blt-vs-render-ctxN
[647.326862] Subtest blt-vs-render-ctxN: SUCCESS (32.716s)
[647.572457] [134/156] (397s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[647.687286] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[647.817728] [135/156] (397s left) gem_softpin (invalid)
[647.872782] Starting subtest: invalid
[647.887148] Subtest invalid: SUCCESS (0.014s)
[648.087748] [136/156] (397s left) gem_exec_capture (capture)
[648.143704] Starting subtest: capture
[648.145081] Starting dynamic subtest: rcs0-smem
[649.045697] Dynamic subtest rcs0-smem: SUCCESS (0.902s)
[649.063409] Starting dynamic subtest: rcs0-lmem0
[649.929687] Dynamic subtest rcs0-lmem0: SUCCESS (0.882s)
[649.945409] Starting dynamic subtest: bcs0-smem
[650.806230] Dynamic subtest bcs0-smem: SUCCESS (0.877s)
[650.824238] Starting dynamic subtest: bcs0-lmem0
[651.673380] Dynamic subtest bcs0-lmem0: SUCCESS (0.865s)
[651.689199] Starting dynamic subtest: vcs0-smem
[653.044276] Dynamic subtest vcs0-smem: SUCCESS (1.367s)
[653.059869] Starting dynamic subtest: vcs0-lmem0
[653.906934] Dynamic subtest vcs0-lmem0: SUCCESS (0.861s)
[653.922413] Starting dynamic subtest: vcs1-smem
[654.772304] Dynamic subtest vcs1-smem: SUCCESS (0.863s)
[654.787867] Starting dynamic subtest: vcs1-lmem0
[655.635369] Dynamic subtest vcs1-lmem0: SUCCESS (0.862s)
[655.650994] Starting dynamic subtest: vecs0-smem
[657.068632] Dynamic subtest vecs0-smem: FAIL (1.395s)
[657.084025] Starting dynamic subtest: vecs0-lmem0
[658.071700] Dynamic subtest vecs0-lmem0: FAIL (0.984s)
[658.073381] Subtest capture: FAIL (9.872s)
[658.162254] [137/156] (387s left) gem_tiled_blits (basic)
[658.218200] Subtest basic: SKIP (0.000s)
[658.301452] [138/156] (387s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[659.813049] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[659.816253] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[659.988535] [139/156] (385s left) gem_mmap_offset (partial-remap)
[660.038220] Starting subtest: partial-remap
[660.039809] Starting dynamic subtest: lmem0
[660.045152] Dynamic subtest lmem0: SUCCESS (0.005s)
[660.060478] Starting dynamic subtest: smem0
[660.064785] Dynamic subtest smem0: SUCCESS (0.005s)
[660.067578] Subtest partial-remap: SUCCESS (0.013s)
[660.281610] [140/156] (385s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[661.779869] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[661.783082] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[661.949151] [141/156] (383s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[663.445345] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[663.448424] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[663.610027] [142/156] (382s left) i915_power (sanity)
[663.657420] Starting subtest: sanity
[667.699396] Subtest sanity: SUCCESS (4.038s)
[667.889573] [143/156] (377s left) gem_exec_schedule (preempt-queue)
[667.965958] Subtest preempt-queue: SKIP (0.000s)
[668.331808] [144/156] (377s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[669.823238] Starting subtest: cursorB-vs-flipA-legacy
[669.826665] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[669.967539] [145/156] (376s left) i915_pm_rps (reset)
[670.025764] Starting subtest: reset
[674.780312] Subtest reset: SUCCESS (4.753s)
[674.936372] [146/156] (371s left) kms_psr (fbc-psr-cursor-plane-onoff)
[676.429509] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[676.516801] [147/156] (369s left) kms_setmode (clone-exclusive-crtc)
[676.565078] Starting subtest: clone-exclusive-crtc
[676.570092] Subtest clone-exclusive-crtc: SKIP (0.000s)
[676.697940] [148/156] (369s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[678.199050] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[678.202539] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[678.402970] [149/156] (368s left) kms_flip (flip-vs-rmfb)
[679.896318] Starting subtest: flip-vs-rmfb
[679.902404] Starting dynamic subtest: A-HDMI-A1
[687.676427] Dynamic subtest A-HDMI-A1: SUCCESS (7.777s)
[687.729325] Starting dynamic subtest: B-HDMI-A1
[695.434691] Dynamic subtest B-HDMI-A1: SUCCESS (7.750s)
[695.479692] Starting dynamic subtest: C-HDMI-A1
[703.178192] Dynamic subtest C-HDMI-A1: SUCCESS (7.739s)
[703.235297] Starting dynamic subtest: D-HDMI-A1
[710.937346] Dynamic subtest D-HDMI-A1: SUCCESS (7.751s)
[710.948200] Subtest flip-vs-rmfb: SUCCESS (31.058s)
[711.227183] [150/156] (335s left) gem_exec_whisper (basic-contexts-all)
[711.295870] Starting subtest: basic-contexts-all
[718.487305] Subtest basic-contexts-all: SUCCESS (7.191s)
[718.836334] [151/156] (327s left) dmabuf_sync_file (export-basic)
[718.895367] Starting subtest: export-basic
[718.897086] Subtest export-basic: SUCCESS (0.001s)
[719.050642] [152/156] (327s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[720.548262] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[720.551406] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[720.762524] [153/156] (326s left) gem_sync (basic-each)
[720.827351] Starting subtest: basic-each
[723.229044] Subtest basic-each: SUCCESS (2.404s)
[723.507105] [154/156] (323s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[724.998899] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[725.000377] Starting dynamic subtest: pipe-A-HDMI-A-1
[725.016903] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[725.032816] Starting dynamic subtest: pipe-B-HDMI-A-1
[725.049615] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[725.064934] Starting dynamic subtest: pipe-C-HDMI-A-1
[725.082498] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[725.097645] Starting dynamic subtest: pipe-D-HDMI-A-1
[725.116505] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[725.119901] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.010s)
[725.279191] [155/156] (321s left) syncobj_wait (multi-wait-for-submit-submitted)
[725.328588] Starting subtest: multi-wait-for-submit-submitted
[725.435246] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s)
[725.590295] [156/156] (321s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[727.089613] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[727.093163] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[727.254410] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_pipe_b_c_ivb
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_force_connector_basic
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: gem_ccs
results: parsing output: 7/ for test: kms_plane_alpha_blend
results: parsing output: 8/ for test: gem_mmap_gtt
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_plane
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_pm_rpm
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: sysfs_heartbeat_interval
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: i915_pm_freq_mult
results: parsing output: 22/ for test: prime_busy
results: parsing output: 23/ for test: gem_exec_gttfill
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: gem_ctx_param
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_lmem_swapping
results: parsing output: 28/ for test: gem_ctx_shared
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_bad_reloc
results: parsing output: 31/ for test: kms_chamelium_hpd
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: gem_eio
results: parsing output: 34/ for test: kms_content_protection
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: gem_ctx_isolation
results: parsing output: 37/ for test: gem_flink_basic
results: parsing output: 38/ for test: gem_caching
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_pm_rpm
results: parsing output: 43/ for test: kms_atomic_transition
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_spin_batch
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: syncobj_basic
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: api_intel_bb
results: parsing output: 52/ for test: kms_atomic_interruptible
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: gem_pxp
results: parsing output: 57/ for test: gem_fence_thrash
results: parsing output: 58/ for test: gen9_exec_parse
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_balancer
results: parsing output: 61/ for test: kms_plane
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_pwrite_crc
results: parsing output: 64/ for test: gem_flink_basic
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_ctx_persistence
results: parsing output: 67/ for test: gen7_exec_parse
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_drm_fdinfo
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_pipe_crc_basic
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: kms_color_pipeline
results: parsing output: 78/ for test: kms_big_fb
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_exec_reloc
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_draw_crc
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_lease
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: kms_addfb_basic
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_sequence
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_busy
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_softpin
results: parsing output: 114/ for test: kms_force_connector_basic
results: parsing output: 115/ for test: kms_chamelium_edid
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: gem_render_copy
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: vgem_basic
results: parsing output: 123/ for test: kms_flip_scaled_crc
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_vblank
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: gem_ctx_exec
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_psr2_sf
results: parsing output: 132/ for test: gem_ppgtt
results: parsing output: 133/ for test: gem_exec_reloc
results: parsing output: 134/ for test: gem_softpin
results: parsing output: 135/ for test: gem_exec_capture
results: parsing output: 136/ for test: gem_tiled_blits
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_mmap_offset
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: i915_power
results: parsing output: 142/ for test: gem_exec_schedule
results: parsing output: 143/ for test: kms_cursor_legacy
results: parsing output: 144/ for test: i915_pm_rps
results: parsing output: 145/ for test: kms_psr
results: parsing output: 146/ for test: kms_setmode
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: kms_flip
results: parsing output: 149/ for test: gem_exec_whisper
results: parsing output: 150/ for test: dmabuf_sync_file
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: gem_sync
results: parsing output: 153/ for test: kms_ccs
results: parsing output: 154/ for test: syncobj_wait
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.