[60.596560] Initializing watchdogs
[60.596624]   /dev/watchdog0
[60.616152] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[60.626820] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[60.630370] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.632660] [001/134] (960s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[63.065543] Starting subtest: dpms-mode-unset-non-lpsp
[64.426658] Subtest dpms-mode-unset-non-lpsp: SUCCESS (1.362s)
[64.855195] [002/134] (956s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[65.039552] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[65.041285] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[65.220621] [003/134] (956s left) gem_lmem_swapping (heavy-multi)
[68.181235] Starting subtest: heavy-multi
[68.182014] Starting dynamic subtest: lmem0
[73.763077] Dynamic subtest lmem0: SUCCESS (5.583s)
[73.764040] Subtest heavy-multi: SUCCESS (5.583s)
[75.022062] [FACT gem_lmem_swapping (heavy-multi)] deleted: kernel.kmod_is_loaded.i915: true
[75.023719] [004/134] (946s left) kms_plane (plane-position-hole)
[76.751703] Starting subtest: plane-position-hole
[77.213606] Starting dynamic subtest: pipe-A-plane-1
[77.658070] Dynamic subtest pipe-A-plane-1: SUCCESS (0.444s)
[77.666612] Starting dynamic subtest: pipe-A-plane-2
[78.113335] Dynamic subtest pipe-A-plane-2: SUCCESS (0.455s)
[78.122043] Starting dynamic subtest: pipe-A-plane-3
[78.564912] Dynamic subtest pipe-A-plane-3: SUCCESS (0.452s)
[78.573772] Starting dynamic subtest: pipe-A-plane-4
[79.017970] Dynamic subtest pipe-A-plane-4: SUCCESS (0.453s)
[79.026433] Starting dynamic subtest: pipe-A-plane-5
[79.037086] Dynamic subtest pipe-A-plane-5: SUCCESS (0.018s)
[79.396564] Starting dynamic subtest: pipe-B-plane-1
[79.809600] Dynamic subtest pipe-B-plane-1: SUCCESS (0.421s)
[79.818389] Starting dynamic subtest: pipe-B-plane-2
[80.267868] Dynamic subtest pipe-B-plane-2: SUCCESS (0.458s)
[80.276338] Starting dynamic subtest: pipe-B-plane-3
[80.689262] Dynamic subtest pipe-B-plane-3: SUCCESS (0.421s)
[80.697875] Starting dynamic subtest: pipe-B-plane-4
[81.091136] Dynamic subtest pipe-B-plane-4: SUCCESS (0.402s)
[81.099497] Starting dynamic subtest: pipe-B-plane-5
[81.111232] Dynamic subtest pipe-B-plane-5: SUCCESS (0.019s)
[81.112166] Subtest plane-position-hole: SUCCESS (4.359s)
[81.398063] [FACT kms_plane (plane-position-hole)] new: kernel.kmod_is_loaded.i915: true
[81.400797] [005/134] (939s left) gem_exec_reloc (basic-cpu-wc-active)
[81.494737] Subtest basic-cpu-wc-active: SKIP (0.000s)
[81.570627] [006/134] (939s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[81.752965] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (0.000s)
[81.803687] [007/134] (939s left) kms_cdclk (plane-scaling)
[81.984532] Starting subtest: plane-scaling
[81.985400] Starting dynamic subtest: pipe-A-DP-3
[82.077255] Dynamic subtest pipe-A-DP-3: SKIP (0.091s)
[82.089450] Starting dynamic subtest: pipe-B-DP-3
[82.091417] Dynamic subtest pipe-B-DP-3: SKIP (0.003s)
[82.101971] Starting dynamic subtest: pipe-C-DP-3
[82.103790] Dynamic subtest pipe-C-DP-3: SKIP (0.003s)
[82.112157] Starting dynamic subtest: pipe-D-DP-3
[82.113931] Dynamic subtest pipe-D-DP-3: SKIP (0.003s)
[82.115900] Subtest plane-scaling: SKIP (0.104s)
[82.339298] [008/134] (939s left) gem_mmap_gtt (fault-concurrent-x)
[82.407997] Subtest fault-concurrent-X: SKIP (0.000s)
[82.466942] [009/134] (939s left) kms_psr (fbc-psr-basic)
[82.647011] Subtest fbc-psr-basic: SKIP (0.000s)
[82.701077] [010/134] (938s left) gem_exec_schedule (semaphore-user)
[82.782408] Starting subtest: semaphore-user
[82.866514] Subtest semaphore-user: SUCCESS (0.084s)
[83.271793] [011/134] (938s left) api_intel_bb (add-remove-objects)
[83.347928] Starting subtest: add-remove-objects
[83.379867] Subtest add-remove-objects: SUCCESS (0.033s)
[83.527281] [012/134] (938s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[83.716459] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[83.718253] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[83.909199] [013/134] (937s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[84.087985] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[96.737995] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (12.650s)
[96.995330] [014/134] (924s left) kms_setmode (invalid-clone-exclusive-crtc)
[97.065240] Starting subtest: invalid-clone-exclusive-crtc
[97.067985] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[97.247686] [015/134] (924s left) i915_query (query-topology-kernel-writes)
[97.314125] Starting subtest: query-topology-kernel-writes
[97.314981] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[97.471726] [016/134] (924s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[97.655693] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[97.657380] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[97.849082] [017/134] (924s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[98.029709] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[98.031269] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[98.231602] [018/134] (923s left) kms_dsc (dsc-with-output-formats)
[98.409779] Starting subtest: dsc-with-output-formats
[98.417910] Subtest dsc-with-output-formats: SKIP (0.001s)
[98.538054] [019/134] (923s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[98.714773] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[98.715595] Starting dynamic subtest: pipe-A-DP-3
[98.719067] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[98.727510] Starting dynamic subtest: pipe-B-DP-3
[98.730960] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[98.739269] Starting dynamic subtest: pipe-C-DP-3
[98.742793] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[98.750970] Starting dynamic subtest: pipe-D-DP-3
[98.754201] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[98.755858] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[99.195961] [020/134] (923s left) gem_ctx_shared (q-smoketest-all)
[99.281466] Starting subtest: Q-smoketest-all
[131.589828] Subtest Q-smoketest-all: SUCCESS (32.301s)
[131.936389] [021/134] (890s left) kms_joiner (basic-big-joiner)
[132.115909] Starting subtest: basic-big-joiner
[132.117586] Subtest basic-big-joiner: SKIP (0.000s)
[132.258351] [022/134] (890s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[132.436399] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[134.801667] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (2.365s)
[135.012459] [023/134] (887s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[135.193997] Starting subtest: basic-max-pipe-crc-check
[135.196883] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[135.268874] [024/134] (887s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[135.449684] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[135.451288] Subtest fbcpsr-1p-pri-indfb-multidraw: SKIP (0.000s)
[135.623528] [025/134] (887s left) kms_atomic_interruptible (legacy-pageflip)
[135.803166] Starting subtest: legacy-pageflip
[135.804060] Starting dynamic subtest: pipe-A-DP-3
[142.115063] Dynamic subtest pipe-A-DP-3: SUCCESS (6.311s)
[142.115981] Subtest legacy-pageflip: SUCCESS (6.312s)
[142.357119] [026/134] (880s left) gem_exec_suspend (basic-s3-devices)
[142.438153] Starting subtest: basic-S3-devices
[142.439081] Starting dynamic subtest: smem
[152.684863] Dynamic subtest smem: SUCCESS (10.247s)
[152.694527] Starting dynamic subtest: lmem0
[162.888731] Dynamic subtest lmem0: SUCCESS (10.203s)
[162.889655] Subtest basic-S3-devices: SUCCESS (20.451s)
[163.226308] [027/134] (859s left) kms_properties (colorop-properties-legacy)
[163.403313] Starting subtest: colorop-properties-legacy
[163.404185] Starting dynamic subtest: pipe-A-DP-3
[163.576456] Dynamic subtest pipe-A-DP-3: SUCCESS (0.172s)
[163.596227] Starting dynamic subtest: pipe-B-DP-3
[163.759722] Dynamic subtest pipe-B-DP-3: SUCCESS (0.181s)
[163.773571] Starting dynamic subtest: pipe-C-DP-3
[163.935066] Dynamic subtest pipe-C-DP-3: SUCCESS (0.173s)
[163.947821] Starting dynamic subtest: pipe-D-DP-3
[164.121882] Dynamic subtest pipe-D-DP-3: SUCCESS (0.186s)
[164.122756] Subtest colorop-properties-legacy: SUCCESS (0.720s)
[164.414165] [028/134] (858s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[164.605755] Starting subtest: fbc-rgb101010-draw-pwrite
[167.552553] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (2.947s)
[167.746180] [029/134] (855s left) kms_plane_multiple (2x-tiling-yf)
[167.928515] Starting subtest: 2x-tiling-yf
[167.930253] Subtest 2x-tiling-yf: SKIP (0.000s)
[168.083700] [030/134] (854s left) i915_query (query-garbage)
[168.151115] Starting subtest: query-garbage
[168.152034] Subtest query-garbage: SUCCESS (0.000s)
[168.294821] [031/134] (854s left) gem_exec_schedule (noreorder)
[168.501711] Starting subtest: noreorder
[168.502598] Starting dynamic subtest: rcs0
[168.543534] Dynamic subtest rcs0: SUCCESS (0.052s)
[168.553699] Starting dynamic subtest: bcs0
[168.615532] Dynamic subtest bcs0: SUCCESS (0.071s)
[168.627776] Starting dynamic subtest: vcs0
[168.683585] Dynamic subtest vcs0: SUCCESS (0.067s)
[168.711408] Starting dynamic subtest: vcs1
[168.782471] Dynamic subtest vcs1: SUCCESS (0.097s)
[168.821866] Starting dynamic subtest: vecs0
[168.886344] Dynamic subtest vecs0: SUCCESS (0.101s)
[168.926264] Starting dynamic subtest: vecs1
[168.977888] Dynamic subtest vecs1: SUCCESS (0.089s)
[169.006375] Starting dynamic subtest: ccs0
[169.062223] Dynamic subtest ccs0: SUCCESS (0.082s)
[169.063995] Subtest noreorder: SUCCESS (0.572s)
[169.397655] [032/134] (853s left) syncobj_timeline (wait-all-for-submit-complex)
[169.464349] Starting subtest: wait-all-for-submit-complex
[169.683048] Subtest wait-all-for-submit-complex: SUCCESS (0.218s)
[169.848391] [033/134] (853s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[170.025485] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[170.051414] Starting dynamic subtest: pipe-A-plane-0
[171.118522] Dynamic subtest pipe-A-plane-0: SUCCESS (1.067s)
[171.134801] Starting dynamic subtest: pipe-A-plane-3
[171.751736] Dynamic subtest pipe-A-plane-3: SUCCESS (0.632s)
[171.784430] Starting dynamic subtest: pipe-A-plane-5
[171.786746] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[171.982529] Starting dynamic subtest: pipe-B-plane-0
[172.940244] Dynamic subtest pipe-B-plane-0: SUCCESS (0.967s)
[172.950930] Starting dynamic subtest: pipe-B-plane-3
[173.557455] Dynamic subtest pipe-B-plane-3: SUCCESS (0.616s)
[173.579359] Starting dynamic subtest: pipe-B-plane-5
[173.581157] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[173.648064] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (3.622s)
[174.007433] [034/134] (849s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[174.186289] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[174.188072] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[174.552478] [035/134] (848s left) kms_universal_plane (universal-plane-sanity)
[174.730365] Starting subtest: universal-plane-sanity
[174.732463] Starting dynamic subtest: pipe-A-DP-3
[175.294477] Dynamic subtest pipe-A-DP-3: SUCCESS (0.563s)
[175.310116] Starting dynamic subtest: pipe-B-DP-3
[175.761989] Dynamic subtest pipe-B-DP-3: SUCCESS (0.465s)
[175.776235] Starting dynamic subtest: pipe-C-DP-3
[176.212331] Dynamic subtest pipe-C-DP-3: SUCCESS (0.448s)
[176.224341] Starting dynamic subtest: pipe-D-DP-3
[176.657219] Dynamic subtest pipe-D-DP-3: SUCCESS (0.443s)
[176.658158] Subtest universal-plane-sanity: SUCCESS (1.926s)
[176.972459] [036/134] (846s left) gem_ccs (suspend-resume)
[177.047362] Starting subtest: suspend-resume
[177.048278] Starting dynamic subtest: linear-compressed-compfmt0-smem-lmem0
[195.531525] Dynamic subtest linear-compressed-compfmt0-smem-lmem0: SUCCESS (3.953s)
[195.541856] Starting dynamic subtest: linear-compressed-compfmt0-lmem0-lmem0
[213.637810] Dynamic subtest linear-compressed-compfmt0-lmem0-lmem0: SUCCESS (3.676s)
[213.647989] Starting dynamic subtest: xmajor-compressed-compfmt0-smem-lmem0
[231.574390] Dynamic subtest xmajor-compressed-compfmt0-smem-lmem0: SUCCESS (3.641s)
[231.584629] Starting dynamic subtest: xmajor-compressed-compfmt0-lmem0-lmem0
[249.687974] Dynamic subtest xmajor-compressed-compfmt0-lmem0-lmem0: SUCCESS (3.690s)
[249.698103] Starting dynamic subtest: tile4-compressed-compfmt0-smem-lmem0
[267.551993] Dynamic subtest tile4-compressed-compfmt0-smem-lmem0: SUCCESS (3.587s)
[267.561983] Starting dynamic subtest: tile4-compressed-compfmt0-lmem0-lmem0
[285.631009] Dynamic subtest tile4-compressed-compfmt0-lmem0-lmem0: SUCCESS (3.684s)
[285.640989] Starting dynamic subtest: tile64-compressed-compfmt0-smem-lmem0
[303.559081] Dynamic subtest tile64-compressed-compfmt0-smem-lmem0: SUCCESS (3.601s)
[303.569329] Starting dynamic subtest: tile64-compressed-compfmt0-lmem0-lmem0
[321.687322] Dynamic subtest tile64-compressed-compfmt0-lmem0-lmem0: SUCCESS (3.711s)
[321.688275] Subtest suspend-resume: SUCCESS (29.551s)
[321.877508] [037/134] (701s left) gem_tiled_blits (normal)
[321.953670] Subtest normal: SKIP (0.000s)
[322.040465] [038/134] (701s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[322.224622] Starting subtest: plane-lut1d-ctm3x4-lut1d
[322.247633] Starting dynamic subtest: pipe-A-DP-3
[322.630446] Dynamic subtest pipe-A-DP-3: SUCCESS (0.383s)
[322.826999] Starting dynamic subtest: pipe-B-DP-3
[323.165756] Dynamic subtest pipe-B-DP-3: SUCCESS (0.348s)
[323.338411] Starting dynamic subtest: pipe-C-DP-3
[323.676904] Dynamic subtest pipe-C-DP-3: SUCCESS (0.348s)
[323.884110] Starting dynamic subtest: pipe-D-DP-3
[324.222172] Dynamic subtest pipe-D-DP-3: SUCCESS (0.348s)
[324.328818] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (2.104s)
[324.608972] [039/134] (699s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[324.784603] Starting subtest: fbc-dirtyfb-ioctl
[324.785483] Starting dynamic subtest: A-DP-3
[326.263761] Dynamic subtest A-DP-3: SUCCESS (1.479s)
[326.264645] Subtest fbc-dirtyfb-ioctl: SUCCESS (1.481s)
[326.547848] [040/134] (697s left) core_sysfs (read-all-entries)
[326.618685] Starting subtest: read-all-entries
[326.622445] Subtest read-all-entries: SUCCESS (0.004s)
[326.742491] [041/134] (697s left) kms_scaling_modes (scaling-mode-full)
[326.921741] Starting subtest: scaling-mode-full
[326.926942] Subtest scaling-mode-full: SKIP (0.001s)
[327.085431] [042/134] (696s left) gem_caching (reads)
[327.158133] Starting subtest: reads
[330.195444] Subtest reads: SUCCESS (3.037s)
[330.394977] [043/134] (693s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[330.569102] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[330.571471] Starting dynamic subtest: pipe-A-valid-mode
[330.643471] Dynamic subtest pipe-A-valid-mode: SKIP (0.072s)
[330.645143] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.073s)
[330.873272] [044/134] (693s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[331.060379] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[332.311521] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.251s)
[332.555845] [045/134] (691s left) gem_exec_balancer (nohangcheck)
[332.896474] Starting subtest: nohangcheck
[334.400561] Subtest nohangcheck: SUCCESS (1.506s)
[334.574396] [046/134] (689s left) kms_vblank (ts-continuation-idle-hang)
[334.752553] Starting subtest: ts-continuation-idle-hang
[334.753442] Starting dynamic subtest: pipe-A-DP-3
[361.679688] Dynamic subtest pipe-A-DP-3: SUCCESS (26.932s)
[361.693308] Starting dynamic subtest: pipe-D-DP-3
[386.617457] Dynamic subtest pipe-D-DP-3: SUCCESS (24.939s)
[386.618344] Subtest ts-continuation-idle-hang: SUCCESS (51.877s)
[386.918271] [047/134] (637s left) gem_exec_reloc (basic-concurrent16)
[386.998759] Subtest basic-concurrent16: SKIP (0.000s)
[387.086633] [048/134] (637s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[387.157647] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[387.158589] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.001s)
[387.353124] [049/134] (637s left) kms_flip (plain-flip-ts-check-interruptible)
[387.540436] Starting subtest: plain-flip-ts-check-interruptible
[387.544089] Starting dynamic subtest: A-DP3
[395.660001] Dynamic subtest A-DP3: SUCCESS (8.117s)
[395.678603] Starting dynamic subtest: B-DP3
[403.702141] Dynamic subtest B-DP3: SUCCESS (8.041s)
[403.738772] Starting dynamic subtest: C-DP3
[411.756909] Dynamic subtest C-DP3: SUCCESS (8.051s)
[411.770205] Starting dynamic subtest: D-DP3
[419.801844] Dynamic subtest D-DP3: SUCCESS (8.045s)
[419.804134] Subtest plain-flip-ts-check-interruptible: SUCCESS (32.271s)
[420.150079] [050/134] (604s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[420.329502] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[420.330400] Starting dynamic subtest: pipe-A-valid-mode
[422.885045] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.551s)
[422.886514] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SUCCESS (2.555s)
[423.195308] [051/134] (601s left) gem_flink_race (flink_close)
[423.238774] Starting subtest: flink_close
[428.312696] Subtest flink_close: SUCCESS (5.075s)
[428.507977] [052/134] (596s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[428.710485] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[429.798292] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (1.087s)
[430.062767] [053/134] (594s left) kms_properties (connector-properties-atomic)
[430.241091] Starting subtest: connector-properties-atomic
[430.241995] Starting dynamic subtest: pipe-A-DP-3
[431.774005] Dynamic subtest pipe-A-DP-3: SUCCESS (1.532s)
[431.789225] Starting dynamic subtest: pipe-None-DP-1
[431.791249] Dynamic subtest pipe-None-DP-1: SUCCESS (0.002s)
[431.804051] Starting dynamic subtest: pipe-None-DP-2
[431.806413] Dynamic subtest pipe-None-DP-2: SUCCESS (0.002s)
[431.817099] Starting dynamic subtest: pipe-None-HDMI-A-1
[431.819375] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.002s)
[431.829755] Starting dynamic subtest: pipe-None-HDMI-A-2
[431.831968] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.002s)
[431.842244] Starting dynamic subtest: pipe-None-DP-4
[431.844043] Dynamic subtest pipe-None-DP-4: SUCCESS (0.002s)
[431.854456] Starting dynamic subtest: pipe-None-HDMI-A-3
[431.856243] Dynamic subtest pipe-None-HDMI-A-3: SUCCESS (0.002s)
[431.857192] Subtest connector-properties-atomic: SUCCESS (1.558s)
[432.084314] [054/134] (592s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[432.265244] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[432.266869] Subtest fbcpsr-rgb565-draw-mmap-wc: SKIP (0.000s)
[432.468602] [055/134] (592s left) perf_pmu (busy-double-start)
[432.530855] Starting subtest: busy-double-start
[432.531742] Starting dynamic subtest: rcs0
[436.149619] Dynamic subtest rcs0: SUCCESS (3.619s)
[436.164469] Starting dynamic subtest: bcs0
[439.747406] Dynamic subtest bcs0: SUCCESS (3.596s)
[439.762426] Starting dynamic subtest: vcs0
[443.319783] Dynamic subtest vcs0: SUCCESS (3.568s)
[443.335716] Starting dynamic subtest: vcs1
[446.932039] Dynamic subtest vcs1: FAIL (3.579s)
[446.945009] Starting dynamic subtest: vecs0
[450.501607] Dynamic subtest vecs0: SUCCESS (3.598s)
[450.516400] Starting dynamic subtest: vecs1
[454.094690] Dynamic subtest vecs1: FAIL (3.565s)
[454.107995] Starting dynamic subtest: ccs0
[457.652341] Dynamic subtest ccs0: FAIL (3.561s)
[457.653336] Subtest busy-double-start: FAIL (25.105s)
[457.797472] [056/134] (567s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[457.984366] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[457.986114] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[458.162667] [057/134] (566s left) prime_busy (hang-wait)
[458.234783] Starting subtest: hang-wait
[458.237275] Starting dynamic subtest: rcs0
[484.604100] Dynamic subtest rcs0: SUCCESS (26.371s)
[484.615570] Starting dynamic subtest: bcs0
[491.708710] Dynamic subtest bcs0: SUCCESS (7.105s)
[491.720177] Starting dynamic subtest: vcs0
[498.743699] Dynamic subtest vcs0: SUCCESS (7.035s)
[498.755082] Starting dynamic subtest: vcs1
[505.724635] Dynamic subtest vcs1: SUCCESS (6.981s)
[505.736291] Starting dynamic subtest: vecs0
[512.701700] Dynamic subtest vecs0: SUCCESS (6.978s)
[512.713053] Starting dynamic subtest: vecs1
[519.736648] Dynamic subtest vecs1: SUCCESS (7.035s)
[519.748319] Starting dynamic subtest: ccs0
[533.569179] Dynamic subtest ccs0: SUCCESS (13.834s)
[533.570274] Subtest hang-wait: SUCCESS (75.348s)
[533.737295] [058/134] (491s left) gem_exec_store (cachelines)
[533.818163] Starting subtest: cachelines
[533.819162] Starting dynamic subtest: rcs0
[533.969961] Dynamic subtest rcs0: SUCCESS (0.151s)
[534.004366] Starting dynamic subtest: bcs0
[534.040680] Dynamic subtest bcs0: SUCCESS (0.068s)
[534.057858] Starting dynamic subtest: vcs0
[534.096594] Dynamic subtest vcs0: SUCCESS (0.052s)
[534.114585] Starting dynamic subtest: vcs1
[534.150969] Dynamic subtest vcs1: SUCCESS (0.053s)
[534.186137] Starting dynamic subtest: vecs0
[534.218017] Dynamic subtest vecs0: SUCCESS (0.065s)
[534.249542] Starting dynamic subtest: vecs1
[534.281585] Dynamic subtest vecs1: SUCCESS (0.061s)
[534.306328] Starting dynamic subtest: ccs0
[534.343694] Dynamic subtest ccs0: SUCCESS (0.060s)
[534.344748] Subtest cachelines: SUCCESS (0.526s)
[534.656191] [059/134] (490s left) gem_exec_balancer (parallel-keep-submit-fence)
[534.986616] Starting subtest: parallel-keep-submit-fence
[537.207714] Subtest parallel-keep-submit-fence: SUCCESS (2.222s)
[537.365192] [060/134] (487s left) i915_drm_fdinfo (context-close-stress)
[537.432562] Starting subtest: context-close-stress
[548.179350] Subtest context-close-stress: SUCCESS (10.746s)
[548.305099] [061/134] (477s left) kms_getfb (getfb-handle-closed)
[549.078085] Starting subtest: getfb-handle-closed
[549.078982] Subtest getfb-handle-closed: SUCCESS (0.000s)
[549.265817] [062/134] (476s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[549.441783] Starting subtest: primary-x-tiled-reflect-x-180
[559.730924] Subtest primary-x-tiled-reflect-x-180: SUCCESS (10.283s)
[560.159269] [063/134] (465s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[560.340358] Starting subtest: pipe-fbc-rte
[560.345306] Subtest pipe-fbc-rte: SKIP (0.000s)
[560.528344] [064/134] (465s left) kms_flip (bo-too-big-interruptible)
[560.708405] Starting subtest: bo-too-big-interruptible
[560.713280] Starting dynamic subtest: A-DP3
[561.012227] Dynamic subtest A-DP3: SUCCESS (0.299s)
[561.032769] Starting dynamic subtest: B-DP3
[561.247874] Dynamic subtest B-DP3: SUCCESS (0.234s)
[561.278640] Starting dynamic subtest: C-DP3
[561.470852] Dynamic subtest C-DP3: SUCCESS (0.220s)
[561.508279] Starting dynamic subtest: D-DP3
[561.719449] Dynamic subtest D-DP3: SUCCESS (0.245s)
[561.722800] Subtest bo-too-big-interruptible: SUCCESS (1.013s)
[562.116530] [065/134] (463s left) gem_softpin (reverse)
[562.194837] Starting subtest: reverse
[562.203576] Subtest reverse: SUCCESS (0.009s)
[562.413125] [066/134] (463s left) prime_vgem (basic-read)
[562.566133] Starting subtest: basic-read
[562.571814] Subtest basic-read: SKIP (0.005s)
[562.773444] [FACT prime_vgem (basic-read)] new: kernel.kmod_is_loaded.vgem: true
[562.776021] [067/134] (463s left) perf_pmu (pmu-read)
[562.844922] Starting subtest: pmu-read
[562.849380] Subtest pmu-read: SUCCESS (0.004s)
[563.084641] [068/134] (462s left) prime_self_import (basic-llseek-size)
[563.131278] Starting subtest: basic-llseek-size
[563.159062] Subtest basic-llseek-size: SUCCESS (0.029s)
[563.335393] [069/134] (462s left) kms_getfb (getfb2-accept-nv12)
[563.522504] Starting subtest: getfb2-accept-nv12
[563.524644] Subtest getfb2-accept-nv12: SUCCESS (0.002s)
[563.735551] [070/134] (462s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[563.920541] Starting subtest: basic-busy-flip-before-cursor-atomic
[563.922246] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[564.085255] [071/134] (462s left) sysfs_heartbeat_interval (invalid)
[564.173551] Starting subtest: invalid
[564.174472] Starting dynamic subtest: vecs0
[564.175384] Dynamic subtest vecs0: SUCCESS (0.000s)
[564.187878] Starting dynamic subtest: ccs0
[564.189007] Dynamic subtest ccs0: SUCCESS (0.000s)
[564.200779] Starting dynamic subtest: vcs0
[564.201820] Dynamic subtest vcs0: SUCCESS (0.000s)
[564.213572] Starting dynamic subtest: bcs0
[564.214494] Dynamic subtest bcs0: SUCCESS (0.000s)
[564.226271] Starting dynamic subtest: vecs1
[564.227360] Dynamic subtest vecs1: SUCCESS (0.000s)
[564.239203] Starting dynamic subtest: rcs0
[564.240356] Dynamic subtest rcs0: SUCCESS (0.000s)
[564.251966] Starting dynamic subtest: vcs1
[564.253087] Dynamic subtest vcs1: SUCCESS (0.000s)
[564.254224] Subtest invalid: SUCCESS (0.010s)
[564.315718] [072/134] (462s left) prime_mmap (test_forked_cpu_write)
[564.393072] Starting subtest: test_forked_cpu_write
[564.393990] Starting dynamic subtest: test_forked_cpu_write-smem
[564.401737] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.008s)
[564.415216] Starting dynamic subtest: test_forked_cpu_write-lmem0
[564.416219] Dynamic subtest test_forked_cpu_write-lmem0: SUCCESS (0.009s)
[564.417381] Subtest test_forked_cpu_write: SUCCESS (0.019s)
[564.602550] [073/134] (461s left) sw_sync (sync_busy_fork_unixsocket)
[564.649580] Starting subtest: sync_busy_fork_unixsocket
[564.657661] Subtest sync_busy_fork_unixsocket: SUCCESS (0.009s)
[564.804840] [074/134] (461s left) gem_render_copy (linear-to-vebox-yf-tiled)
[564.887763] Starting subtest: linear-to-vebox-yf-tiled
[564.889636] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[565.160419] [075/134] (461s left) kms_pm_rpm (modeset-lpsp)
[567.191332] Starting subtest: modeset-lpsp
[567.466770] Subtest modeset-lpsp: SKIP (0.278s)
[567.931142] [076/134] (458s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[568.134357] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[568.136133] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[568.306504] [077/134] (458s left) gem_userptr_blits (sync-unmap-cycles)
[568.397773] Starting subtest: sync-unmap-cycles
[573.770581] Subtest sync-unmap-cycles: SUCCESS (5.370s)
[574.008411] [078/134] (452s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[574.206059] Starting subtest: y-tiled-8bpp-rotate-270
[574.207767] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[574.365601] [079/134] (452s left) kms_plane_scaling (planes-upscale-factor-0-25)
[574.548098] Starting subtest: planes-upscale-factor-0-25
[574.548983] Starting dynamic subtest: pipe-A
[574.649649] Dynamic subtest pipe-A: SUCCESS (0.101s)
[574.671544] Starting dynamic subtest: pipe-B
[574.863056] Dynamic subtest pipe-B: SUCCESS (0.212s)
[574.888075] Starting dynamic subtest: pipe-C
[575.092541] Dynamic subtest pipe-C: SUCCESS (0.228s)
[575.107758] Starting dynamic subtest: pipe-D
[575.289271] Dynamic subtest pipe-D: SUCCESS (0.195s)
[575.290367] Subtest planes-upscale-factor-0-25: SUCCESS (0.742s)
[575.673652] [080/134] (451s left) kms_async_flips (alternate-sync-async-flip)
[575.863506] Starting subtest: alternate-sync-async-flip
[576.054420] Starting dynamic subtest: pipe-A-DP-3
[578.352665] Dynamic subtest pipe-A-DP-3: SUCCESS (2.297s)
[578.599229] Starting dynamic subtest: pipe-B-DP-3
[580.713255] Dynamic subtest pipe-B-DP-3: SUCCESS (2.124s)
[580.945425] Starting dynamic subtest: pipe-C-DP-3
[583.058842] Dynamic subtest pipe-C-DP-3: SUCCESS (2.124s)
[583.285976] Starting dynamic subtest: pipe-D-DP-3
[585.400781] Dynamic subtest pipe-D-DP-3: SUCCESS (2.126s)
[585.401745] Subtest alternate-sync-async-flip: SUCCESS (9.535s)
[585.939853] [081/134] (441s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[586.124971] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[586.127386] Starting dynamic subtest: pipe-A-DP-3
[586.130874] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[586.142887] Starting dynamic subtest: pipe-B-DP-3
[586.146398] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[586.158165] Starting dynamic subtest: pipe-C-DP-3
[586.161601] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[586.173154] Starting dynamic subtest: pipe-D-DP-3
[586.176583] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[586.178228] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[586.640263] [082/134] (440s left) gen9_exec_parse (bb-secure)
[586.724639] Subtest bb-secure: SKIP (0.000s)
[586.809375] [083/134] (440s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[587.000343] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[587.002034] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[587.209045] [084/134] (440s left) gem_exec_fence (submit67)
[587.415968] Starting subtest: submit67
[587.417929] Subtest submit67: SKIP (0.000s)
[588.166161] [085/134] (439s left) gem_ctx_persistence (engines-queued)
[588.248784] Starting subtest: engines-queued
[588.249692] Starting dynamic subtest: rcs0
[588.369739] Dynamic subtest rcs0: SUCCESS (0.120s)
[588.393014] Starting dynamic subtest: bcs0
[588.447408] Dynamic subtest bcs0: SUCCESS (0.075s)
[588.492937] Starting dynamic subtest: vcs0
[588.539770] Dynamic subtest vcs0: SUCCESS (0.087s)
[588.570908] Starting dynamic subtest: vcs1
[588.581438] Dynamic subtest vcs1: SUCCESS (0.039s)
[588.602218] Starting dynamic subtest: vecs0
[588.648413] Dynamic subtest vecs0: SUCCESS (0.065s)
[588.663734] Starting dynamic subtest: vecs1
[588.676374] Dynamic subtest vecs1: SUCCESS (0.027s)
[588.689963] Starting dynamic subtest: ccs0
[588.847401] Dynamic subtest ccs0: SUCCESS (0.170s)
[588.848913] Subtest engines-queued: SUCCESS (0.599s)
[589.030891] [086/134] (438s left) kms_chamelium_color (ctm-red-to-blue)
[589.220995] Subtest ctm-red-to-blue: SKIP (0.000s)
[589.276903] [087/134] (438s left) kms_psr2_su (page_flip-p010)
[589.370262] Subtest page_flip-P010: SKIP (0.000s)
[589.447348] [088/134] (438s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[589.627197] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[589.629554] Starting dynamic subtest: pipe-A-valid-mode
[594.015180] Dynamic subtest pipe-A-valid-mode: SUCCESS (4.385s)
[594.016150] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SUCCESS (4.387s)
[594.295040] [089/134] (433s left) i915_drm_fdinfo (virtual-idle)
[594.363651] Starting subtest: virtual-idle
[597.977673] Subtest virtual-idle: SUCCESS (3.611s)
[598.132910] [090/134] (429s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[598.214841] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[598.216788] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[598.497370] [091/134] (429s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs)
[598.692598] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs
[598.694347] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s)
[599.027053] [092/134] (428s left) kms_cursor_crc (cursor-onscreen-256x85)
[599.461315] Starting subtest: cursor-onscreen-256x85
[599.465710] Starting dynamic subtest: pipe-A-DP-3
[602.074380] Dynamic subtest pipe-A-DP-3: SUCCESS (2.605s)
[602.122765] Starting dynamic subtest: pipe-D-DP-3
[604.885854] Dynamic subtest pipe-D-DP-3: SUCCESS (2.773s)
[604.886923] Subtest cursor-onscreen-256x85: SUCCESS (5.424s)
[605.270295] [093/134] (422s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[605.725134] Starting subtest: cursor-rapid-movement-512x512
[605.726707] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[606.017188] [094/134] (422s left) i915_suspend (forcewake)
[606.110425] Starting subtest: forcewake
[645.451796] Subtest forcewake: SUCCESS (7.130s)
[645.580182] [095/134] (382s left) syncobj_timeline (signal-array)
[645.660038] Starting subtest: signal-array
[645.660964] Subtest signal-array: SUCCESS (0.000s)
[645.829430] [096/134] (382s left) gem_exec_params (batch-first)
[645.912130] Starting subtest: batch-first
[645.946414] Subtest batch-first: SUCCESS (0.034s)
[646.174605] [097/134] (382s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[646.366945] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[646.368674] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[646.575190] [098/134] (381s left) kms_psr (psr2-primary-mmap-gtt)
[646.761262] Subtest psr2-primary-mmap-gtt: SKIP (0.000s)
[646.820403] [099/134] (381s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[647.020027] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[647.021698] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[647.235447] [100/134] (381s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[647.431058] Starting subtest: fbc-rgb565-draw-mmap-gtt
[647.432810] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[647.608267] [101/134] (380s left) kms_pipe_stress (stress-xrgb8888-xtiled)
[647.801313] Starting subtest: stress-xrgb8888-xtiled
[655.476004] Subtest stress-xrgb8888-xtiled: SUCCESS (7.675s)
[655.731151] [102/134] (372s left) gem_exec_params (rel-constants-invalid)
[655.827698] Starting subtest: rel-constants-invalid
[655.828649] Subtest rel-constants-invalid: SUCCESS (0.000s)
[656.002157] [103/134] (372s left) gem_eio (in-flight-external)
[656.272136] Starting subtest: in-flight-external
[657.195021] Subtest in-flight-external: SUCCESS (0.923s)
[657.465723] [104/134] (371s left) gem_wait (busy)
[657.554467] Starting subtest: busy
[657.555366] Starting dynamic subtest: all-engines
[658.146164] Dynamic subtest all-engines: SUCCESS (0.591s)
[658.167205] Starting dynamic subtest: rcs0
[658.685885] Dynamic subtest rcs0: SUCCESS (0.538s)
[658.699957] Starting dynamic subtest: bcs0
[659.224866] Dynamic subtest bcs0: SUCCESS (0.538s)
[659.238660] Starting dynamic subtest: vcs0
[659.763765] Dynamic subtest vcs0: SUCCESS (0.538s)
[659.777252] Starting dynamic subtest: vcs1
[660.302693] Dynamic subtest vcs1: SUCCESS (0.538s)
[660.316269] Starting dynamic subtest: vecs0
[660.842938] Dynamic subtest vecs0: SUCCESS (0.539s)
[660.856830] Starting dynamic subtest: vecs1
[661.381877] Dynamic subtest vecs1: SUCCESS (0.538s)
[661.395684] Starting dynamic subtest: ccs0
[661.920817] Dynamic subtest ccs0: SUCCESS (0.538s)
[661.921827] Subtest busy: SUCCESS (4.367s)
[662.212756] [105/134] (366s left) fbdev (pan)
[662.258845] Starting subtest: pan
[662.310532] Subtest pan: SUCCESS (0.052s)
[662.510811] [106/134] (366s left) kms_chamelium_hpd (hdmi-hpd-storm)
[662.787852] Subtest hdmi-hpd-storm: SKIP (0.000s)
[662.939714] [107/134] (366s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[663.131030] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[663.132765] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[663.306723] [108/134] (365s left) kms_async_flips (test-time-stamp-atomic)
[663.505065] Starting subtest: test-time-stamp-atomic
[663.733395] Starting dynamic subtest: pipe-A-DP-3
[664.058621] Dynamic subtest pipe-A-DP-3: SUCCESS (0.324s)
[664.306446] Starting dynamic subtest: pipe-B-DP-3
[664.437100] Dynamic subtest pipe-B-DP-3: SUCCESS (0.143s)
[664.668009] Starting dynamic subtest: pipe-C-DP-3
[664.805834] Dynamic subtest pipe-C-DP-3: SUCCESS (0.149s)
[665.033938] Starting dynamic subtest: pipe-D-DP-3
[665.161679] Dynamic subtest pipe-D-DP-3: SUCCESS (0.140s)
[665.162772] Subtest test-time-stamp-atomic: SUCCESS (1.658s)
[665.689709] [109/134] (363s left) kms_psr (pr-sprite-mmap-gtt)
[665.871360] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[665.934505] [110/134] (363s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[666.123914] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[666.125687] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[666.297108] [111/134] (363s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[666.492836] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[667.787968] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.294s)
[668.006250] [112/134] (361s left) gem_exec_schedule (smoketest-all)
[668.097575] Starting subtest: smoketest-all
[700.424568] Subtest smoketest-all: SUCCESS (32.311s)
[700.886704] [113/134] (328s left) vgem_basic (create)
[700.955255] Starting subtest: create
[700.956164] Subtest create: SUCCESS (0.000s)
[701.103946] [114/134] (328s left) gem_ctx_isolation (dirty-create)
[701.194725] Starting subtest: dirty-create
[701.195568] Starting dynamic subtest: rcs0
[701.802147] Dynamic subtest rcs0: SUCCESS (0.607s)
[701.817912] Starting dynamic subtest: bcs0
[702.515159] Dynamic subtest bcs0: SUCCESS (0.712s)
[702.531058] Starting dynamic subtest: vcs0
[703.191389] Dynamic subtest vcs0: SUCCESS (0.675s)
[703.205413] Starting dynamic subtest: vcs1
[703.846542] Dynamic subtest vcs1: SUCCESS (0.654s)
[703.862205] Starting dynamic subtest: vecs0
[704.494883] Dynamic subtest vecs0: SUCCESS (0.647s)
[704.510751] Starting dynamic subtest: vecs1
[705.176970] Dynamic subtest vecs1: SUCCESS (0.681s)
[705.192242] Starting dynamic subtest: ccs0
[705.844343] Dynamic subtest ccs0: SUCCESS (0.666s)
[705.845310] Subtest dirty-create: SUCCESS (4.650s)
[706.162695] [115/134] (323s left) kms_content_protection (uevent-hdcp14)
[706.420277] Starting subtest: uevent-hdcp14
[706.455239] Starting dynamic subtest: pipe-A-DP-3
[740.573608] Dynamic subtest pipe-A-DP-3: FAIL (34.069s)
[740.627754] Subtest uevent-hdcp14: FAIL (34.209s)
[740.938383] [116/134] (288s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[741.021208] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[741.124094] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.102s)
[741.300601] [117/134] (288s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[741.494995] Starting subtest: yf-tiled-32bpp-rotate-180
[741.496880] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[741.705496] [118/134] (288s left) gem_sync (basic-many-each)
[741.792956] Starting subtest: basic-many-each
[744.333361] Subtest basic-many-each: SUCCESS (2.541s)
[744.633932] [119/134] (285s left) kms_cursor_edge_walk (256x256-right-edge)
[744.818137] Starting subtest: 256x256-right-edge
[744.819032] Starting dynamic subtest: pipe-A-DP-3
[748.280743] Dynamic subtest pipe-A-DP-3: SUCCESS (3.462s)
[748.296295] Starting dynamic subtest: pipe-D-DP-3
[751.586104] Dynamic subtest pipe-D-DP-3: SUCCESS (3.303s)
[751.587069] Subtest 256x256-right-edge: SUCCESS (6.770s)
[751.923377] [120/134] (278s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[752.110053] Starting subtest: basic-flip-before-cursor-atomic
[753.157695] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.048s)
[753.467102] [121/134] (276s left) kms_flip (nonblocking-read)
[753.655953] Starting subtest: nonblocking-read
[753.656826] Subtest nonblocking-read: SUCCESS (0.000s)
[753.841836] [122/134] (276s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[754.038356] Starting subtest: yf-tiled-64bpp-rotate-180
[754.040057] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[754.241369] [123/134] (276s left) gem_exec_reloc (basic-wc-gtt-active)
[754.347668] Subtest basic-wc-gtt-active: SKIP (0.000s)
[754.455246] [124/134] (276s left) gem_mmap_wc (write-prefaulted)
[754.530966] Subtest write-prefaulted: SKIP (0.000s)
[754.617546] [125/134] (275s left) api_intel_bb (full-batch)
[754.691356] Starting subtest: full-batch
[754.719650] Subtest full-batch: SUCCESS (0.029s)
[754.935407] [126/134] (275s left) gem_eio (in-flight-internal-10ms)
[755.239809] Starting subtest: in-flight-internal-10ms
[756.200891] Subtest in-flight-internal-10ms: SUCCESS (0.962s)
[756.500863] [127/134] (274s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[756.687912] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[756.689676] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[756.904290] [128/134] (273s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[757.096061] Starting subtest: cursorA-vs-flipA-toggle
[768.055009] Subtest cursorA-vs-flipA-toggle: SUCCESS (10.960s)
[768.372757] [129/134] (262s left) syncobj_timeline (etime-multi-wait-all-submitted)
[768.459227] Starting subtest: etime-multi-wait-all-submitted
[768.564320] Subtest etime-multi-wait-all-submitted: SUCCESS (0.104s)
[768.737567] [130/134] (262s left) gem_exec_reloc (basic-cpu-noreloc)
[768.821511] Subtest basic-cpu-noreloc: SKIP (0.000s)
[768.919417] [131/134] (262s left) gem_mmap (bad-offset)
[768.993096] Subtest bad-offset: SKIP (0.000s)
[769.082046] [132/134] (262s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[769.268077] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[769.269960] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.002s)
[769.404366] [133/134] (261s left) i915_pm_rc6_residency (rc6-idle)
[769.480433] Starting subtest: rc6-idle
[769.502022] Starting dynamic subtest: gt0-rcs0
[793.861273] Dynamic subtest gt0-rcs0: SUCCESS (24.361s)
[793.877598] Starting dynamic subtest: gt0-bcs0
[818.173907] Dynamic subtest gt0-bcs0: SUCCESS (24.310s)
[818.192563] Starting dynamic subtest: gt0-vcs0
[842.519138] Dynamic subtest gt0-vcs0: SUCCESS (24.339s)
[842.571266] Starting dynamic subtest: gt0-vecs0
[866.755643] Dynamic subtest gt0-vecs0: SUCCESS (24.231s)
[866.774100] Starting dynamic subtest: gt0-ccs0
[891.222090] Dynamic subtest gt0-ccs0: SUCCESS (24.462s)
[891.227939] Subtest rc6-idle: SUCCESS (121.744s)
[891.433753] [134/134] (139s left) kms_psr (fbc-psr2-primary-page-flip)
[891.618999] Subtest fbc-psr2-primary-page-flip: SKIP (0.000s)
[891.682726] Closing watchdogs
results: parsing output: 0/ for test: kms_pm_rpm
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_lmem_swapping
results: parsing output: 3/ for test: kms_plane
results: parsing output: 4/ for test: gem_exec_reloc
results: parsing output: 5/ for test: kms_psr2_sf
results: parsing output: 6/ for test: kms_cdclk
results: parsing output: 7/ for test: gem_mmap_gtt
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: gem_exec_schedule
results: parsing output: 10/ for test: api_intel_bb
results: parsing output: 11/ for test: kms_big_fb
results: parsing output: 12/ for test: kms_cursor_legacy
results: parsing output: 13/ for test: kms_setmode
results: parsing output: 14/ for test: i915_query
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_dsc
results: parsing output: 18/ for test: kms_ccs
results: parsing output: 19/ for test: gem_ctx_shared
results: parsing output: 20/ for test: kms_joiner
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_multipipe_modeset
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_atomic_interruptible
results: parsing output: 25/ for test: gem_exec_suspend
results: parsing output: 26/ for test: kms_properties
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_plane_multiple
results: parsing output: 29/ for test: i915_query
results: parsing output: 30/ for test: gem_exec_schedule
results: parsing output: 31/ for test: syncobj_timeline
results: parsing output: 32/ for test: kms_plane
results: parsing output: 33/ for test: kms_ccs
results: parsing output: 34/ for test: kms_universal_plane
results: parsing output: 35/ for test: gem_ccs
results: parsing output: 36/ for test: gem_tiled_blits
results: parsing output: 37/ for test: kms_color_pipeline
results: parsing output: 38/ for test: kms_dirtyfb
results: parsing output: 39/ for test: core_sysfs
results: parsing output: 40/ for test: kms_scaling_modes
results: parsing output: 41/ for test: gem_caching
results: parsing output: 42/ for test: kms_flip_scaled_crc
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: gem_exec_balancer
results: parsing output: 45/ for test: kms_vblank
results: parsing output: 46/ for test: gem_exec_reloc
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: kms_flip_scaled_crc
results: parsing output: 50/ for test: gem_flink_race
results: parsing output: 51/ for test: kms_big_fb
results: parsing output: 52/ for test: kms_properties
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: perf_pmu
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: prime_busy
results: parsing output: 57/ for test: gem_exec_store
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: i915_drm_fdinfo
results: parsing output: 60/ for test: kms_getfb
results: parsing output: 61/ for test: kms_rotation_crc
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_flip
results: parsing output: 64/ for test: gem_softpin
results: parsing output: 65/ for test: prime_vgem
results: parsing output: 66/ for test: perf_pmu
results: parsing output: 67/ for test: prime_self_import
results: parsing output: 68/ for test: kms_getfb
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: sysfs_heartbeat_interval
results: parsing output: 71/ for test: prime_mmap
results: parsing output: 72/ for test: sw_sync
results: parsing output: 73/ for test: gem_render_copy
results: parsing output: 74/ for test: kms_pm_rpm
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_userptr_blits
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_plane_scaling
results: parsing output: 79/ for test: kms_async_flips
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: gen9_exec_parse
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_exec_fence
results: parsing output: 84/ for test: gem_ctx_persistence
results: parsing output: 85/ for test: kms_chamelium_color
results: parsing output: 86/ for test: kms_psr2_su
results: parsing output: 87/ for test: kms_flip_scaled_crc
results: parsing output: 88/ for test: i915_drm_fdinfo
results: parsing output: 89/ for test: gem_render_copy
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: kms_cursor_crc
results: parsing output: 92/ for test: kms_cursor_crc
results: parsing output: 93/ for test: i915_suspend
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: gem_exec_params
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_pipe_stress
results: parsing output: 101/ for test: gem_exec_params
results: parsing output: 102/ for test: gem_eio
results: parsing output: 103/ for test: gem_wait
results: parsing output: 104/ for test: fbdev
results: parsing output: 105/ for test: kms_chamelium_hpd
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_async_flips
results: parsing output: 108/ for test: kms_psr
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: gem_exec_schedule
results: parsing output: 112/ for test: vgem_basic
results: parsing output: 113/ for test: gem_ctx_isolation
results: parsing output: 114/ for test: kms_content_protection
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: gem_sync
results: parsing output: 118/ for test: kms_cursor_edge_walk
results: parsing output: 119/ for test: kms_cursor_legacy
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: gem_exec_reloc
results: parsing output: 123/ for test: gem_mmap_wc
results: parsing output: 124/ for test: api_intel_bb
results: parsing output: 125/ for test: gem_eio
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_cursor_legacy
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: gem_exec_reloc
results: parsing output: 130/ for test: gem_mmap
results: parsing output: 131/ for test: kms_cursor_legacy
results: parsing output: 132/ for test: i915_pm_rc6_residency
results: parsing output: 133/ for test: kms_psr
Done.