[88.735729] Initializing watchdogs
[88.735901]   /dev/watchdog0
[88.777550] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.791989] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.798944] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.806732] [001/133] (960s left) i915_pm_rps (reset)
[88.948712] Starting subtest: reset
[93.536033] Subtest reset: SUCCESS (4.587s)
[93.681336] [002/133] (955s left) kms_fbcon_fbt (psr)
[93.790354] Starting subtest: psr
[93.798181] Subtest psr: SKIP (0.000s)
[93.992252] [003/133] (955s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[94.186485] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[94.195669] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[105.300119] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.104s)
[105.321391] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[116.299264] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.996s)
[116.320348] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[127.263045] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.958s)
[127.266267] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (33.071s)
[127.846155] [004/133] (921s left) perf_pmu (busy)
[127.962166] Starting subtest: busy
[127.965961] Starting dynamic subtest: rcs0
[128.503054] Dynamic subtest rcs0: SUCCESS (0.539s)
[128.525875] Starting dynamic subtest: bcs0
[129.033436] Dynamic subtest bcs0: SUCCESS (0.530s)
[129.054459] Starting dynamic subtest: vcs0
[129.571943] Dynamic subtest vcs0: SUCCESS (0.535s)
[129.592071] Starting dynamic subtest: vecs0
[130.108109] Dynamic subtest vecs0: SUCCESS (0.537s)
[130.112470] Subtest busy: SUCCESS (2.147s)
[130.247504] [005/133] (919s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[130.361975] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[130.469069] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.109s)
[130.614222] [006/133] (919s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[130.724114] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[130.727564] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.003s)
[130.864583] [007/133] (918s left) gem_exec_whisper (basic-fds-priority)
[131.004618] Starting subtest: basic-fds-priority
[140.714904] Subtest basic-fds-priority: SUCCESS (9.710s)
[140.983794] [008/133] (908s left) dumb_buffer (map-valid)
[141.096394] Starting subtest: map-valid
[141.099695] Subtest map-valid: SUCCESS (0.000s)
[141.226819] [009/133] (908s left) gem_spin_batch (legacy-resubmit-new)
[141.353205] Starting subtest: legacy-resubmit-new
[141.356779] Starting dynamic subtest: default
[141.362372] Dynamic subtest default: SUCCESS (0.007s)
[141.399463] Starting dynamic subtest: render
[141.403408] Dynamic subtest render: SUCCESS (0.006s)
[141.424117] Starting dynamic subtest: bsd
[141.428136] Dynamic subtest bsd: SUCCESS (0.006s)
[141.450409] Starting dynamic subtest: bsd1
[141.455052] Dynamic subtest bsd1: SUCCESS (0.006s)
[141.476231] Starting dynamic subtest: blt
[141.480049] Dynamic subtest blt: SUCCESS (0.008s)
[141.503815] Starting dynamic subtest: vebox
[141.508900] Dynamic subtest vebox: SUCCESS (0.006s)
[141.513494] Subtest legacy-resubmit-new: SUCCESS (0.046s)
[141.664599] [010/133] (908s left) kms_psr (fbc-psr2-primary-render)
[141.861153] Subtest fbc-psr2-primary-render: SKIP (0.000s)
[141.974205] [011/133] (908s left) kms_flip (basic-flip-vs-dpms)
[142.177265] Starting subtest: basic-flip-vs-dpms
[142.182505] Starting dynamic subtest: A-HDMI-A1
[143.339129] Dynamic subtest A-HDMI-A1: SUCCESS (1.158s)
[143.369123] Starting dynamic subtest: B-HDMI-A1
[144.359303] Dynamic subtest B-HDMI-A1: SUCCESS (1.013s)
[144.379875] Starting dynamic subtest: C-HDMI-A1
[145.357613] Dynamic subtest C-HDMI-A1: SUCCESS (0.999s)
[145.380435] Starting dynamic subtest: A-HDMI-A2
[146.137611] Dynamic subtest A-HDMI-A2: SUCCESS (0.772s)
[146.166617] Starting dynamic subtest: B-HDMI-A2
[147.126320] Dynamic subtest B-HDMI-A2: SUCCESS (0.988s)
[147.156100] Starting dynamic subtest: C-HDMI-A2
[148.142095] Dynamic subtest C-HDMI-A2: SUCCESS (1.016s)
[148.148282] Subtest basic-flip-vs-dpms: SUCCESS (5.965s)
[148.538572] [012/133] (901s left) gem_eio (in-flight-contexts-1us)
[148.687206] Starting subtest: in-flight-contexts-1us
[159.957501] Subtest in-flight-contexts-1us: SUCCESS (11.270s)
[160.211669] [013/133] (890s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[160.418847] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[160.425706] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[160.562743] [014/133] (889s left) kms_lease (setcrtc-implicit-plane)
[160.746276] Starting subtest: setcrtc-implicit-plane
[160.751076] Starting dynamic subtest: pipe-A-HDMI-A-1
[161.510104] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.758s)
[161.543480] Starting dynamic subtest: pipe-A-HDMI-A-2
[161.992181] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.467s)
[162.024058] Starting dynamic subtest: pipe-B-HDMI-A-1
[162.418260] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.415s)
[162.447170] Starting dynamic subtest: pipe-B-HDMI-A-2
[162.870095] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.454s)
[162.907066] Starting dynamic subtest: pipe-C-HDMI-A-1
[163.336446] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.460s)
[163.370581] Starting dynamic subtest: pipe-C-HDMI-A-2
[163.827736] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.489s)
[163.835555] Subtest setcrtc-implicit-plane: SUCCESS (3.082s)
[164.159461] [015/133] (886s left) kms_atomic_interruptible (atomic-setmode)
[164.353179] Starting subtest: atomic-setmode
[164.357397] Starting dynamic subtest: pipe-A-HDMI-A-1
[171.224814] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.864s)
[171.228375] Subtest atomic-setmode: SUCCESS (6.869s)
[171.549811] [016/133] (879s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[171.742149] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[171.749890] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[171.881174] [017/133] (878s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[172.084081] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[172.095085] Subtest fbcpsr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[172.231712] [018/133] (878s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[172.428151] Starting subtest: cursorB-vs-flipB-toggle
[183.731446] Subtest cursorB-vs-flipB-toggle: SUCCESS (11.306s)
[184.091661] [019/133] (866s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[184.217834] Starting subtest: tiled-to-tiled
[184.342932] Subtest tiled-to-tiled: SUCCESS (0.119s)
[184.489490] [020/133] (866s left) gen9_exec_parse (secure-batches)
[184.620908] Starting subtest: secure-batches
[184.624933] Subtest secure-batches: SUCCESS (0.000s)
[184.886681] [021/133] (866s left) kms_chamelium_audio (dp-audio-edid)
[185.483651] Subtest dp-audio-edid: SKIP (0.000s)
[185.814548] [022/133] (865s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[186.018666] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[186.025205] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[186.161919] [023/133] (865s left) gem_exec_schedule (implicit-boths)
[186.317813] Starting subtest: implicit-boths
[186.320931] Starting dynamic subtest: rcs0
[186.491830] Dynamic subtest rcs0: SUCCESS (0.125s)
[186.560352] Starting dynamic subtest: bcs0
[186.630864] Dynamic subtest bcs0: SUCCESS (0.127s)
[186.700100] Starting dynamic subtest: vcs0
[186.769668] Dynamic subtest vcs0: SUCCESS (0.117s)
[186.837470] Starting dynamic subtest: vecs0
[186.905591] Dynamic subtest vecs0: SUCCESS (0.113s)
[186.974415] Subtest implicit-boths: SUCCESS (0.497s)
[187.547054] [024/133] (864s left) kms_plane_scaling (planes-downscale-factor-0-75)
[187.942434] Starting subtest: planes-downscale-factor-0-75
[187.945844] Starting dynamic subtest: pipe-A
[188.185249] Dynamic subtest pipe-A: SUCCESS (0.322s)
[188.235388] Starting dynamic subtest: pipe-B
[188.550910] Dynamic subtest pipe-B: SUCCESS (0.363s)
[188.577512] Starting dynamic subtest: pipe-C
[188.629063] Dynamic subtest pipe-C: SUCCESS (0.072s)
[188.632873] Subtest planes-downscale-factor-0-75: SUCCESS (0.765s)
[189.119738] [025/133] (862s left) kms_plane_alpha_blend (alpha-transparent-fb)
[189.313144] Starting subtest: alpha-transparent-fb
[189.467479] Starting dynamic subtest: pipe-A-HDMI-A-1
[190.844496] Dynamic subtest pipe-A-HDMI-A-1: FAIL (0.455s)
[190.867184] Starting dynamic subtest: pipe-C-HDMI-A-1
[191.906409] Dynamic subtest pipe-C-HDMI-A-1: FAIL (1.289s)
[191.910443] Subtest alpha-transparent-fb: FAIL (1.906s)
[192.012942] [026/133] (859s left) i915_drm_fdinfo (memory-info-resident)
[192.133509] Starting subtest: memory-info-resident
[192.137774] Starting dynamic subtest: smem0
[193.189954] Dynamic subtest smem0: SUCCESS (1.058s)
[193.193668] Subtest memory-info-resident: SUCCESS (1.061s)
[193.334344] [027/133] (858s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[193.467197] Starting subtest: multi-wait-all-for-submit-unsubmitted
[193.555315] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[193.697805] [028/133] (858s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[193.910080] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[196.410422] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (2.506s)
[196.628418] [029/133] (855s left) kms_vblank (query-forked-hang)
[196.822121] Starting subtest: query-forked-hang
[196.827087] Starting dynamic subtest: pipe-A-HDMI-A-1
[203.865175] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.041s)
[203.890044] Starting dynamic subtest: pipe-A-HDMI-A-2
[210.885821] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (7.013s)
[210.920068] Starting dynamic subtest: pipe-C-HDMI-A-1
[217.846287] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.951s)
[217.901409] Starting dynamic subtest: pipe-C-HDMI-A-2
[224.829905] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (6.976s)
[224.833949] Subtest query-forked-hang: SUCCESS (28.017s)
[225.193665] [030/133] (827s left) kms_pm_rpm (system-suspend-idle)
[227.110502] Starting subtest: system-suspend-idle
[243.848400] Subtest system-suspend-idle: SUCCESS (2.258s)
[251.316753] [031/133] (808s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[251.545952] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[254.380068] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (2.840s)
[254.691051] [032/133] (804s left) gem_caching (writes)
[254.817792] Starting subtest: writes
[269.292608] Subtest writes: SUCCESS (14.470s)
[269.469042] [033/133] (790s left) gem_lmem_swapping (smem-oom)
[269.590175] Subtest smem-oom: SKIP (0.000s)
[269.692781] [034/133] (790s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[269.895146] Starting subtest: fbc-rgb565-draw-mmap-wc
[272.670493] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.778s)
[272.949693] [035/133] (786s left) gem_exercise_blt (fast-copy)
[273.069119] Starting subtest: fast-copy
[273.072348] Starting dynamic subtest: linear-smem-smem
[273.121983] Dynamic subtest linear-smem-smem: SUCCESS (0.051s)
[273.148672] Starting dynamic subtest: ymajor-smem-smem
[273.166789] Dynamic subtest ymajor-smem-smem: SUCCESS (0.042s)
[273.192459] Starting dynamic subtest: yfmajor-smem-smem
[273.206990] Dynamic subtest yfmajor-smem-smem: SUCCESS (0.038s)
[273.234010] Starting dynamic subtest: tile64-smem-smem
[273.249165] Dynamic subtest tile64-smem-smem: SUCCESS (0.039s)
[273.254043] Subtest fast-copy: SUCCESS (0.181s)
[273.397972] [036/133] (786s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[273.606964] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[273.614357] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[273.751420] [037/133] (786s left) gem_exec_schedule (preempt-user)
[273.898317] Starting subtest: preempt-user
[273.901984] Starting dynamic subtest: rcs0
[273.952302] Dynamic subtest rcs0: SUCCESS (0.057s)
[273.983861] Starting dynamic subtest: bcs0
[274.023480] Dynamic subtest bcs0: SUCCESS (0.070s)
[274.059366] Starting dynamic subtest: vcs0
[274.076589] Dynamic subtest vcs0: SUCCESS (0.047s)
[274.109044] Starting dynamic subtest: vecs0
[274.126008] Dynamic subtest vecs0: SUCCESS (0.047s)
[274.129798] Subtest preempt-user: SUCCESS (0.234s)
[274.513099] [038/133] (785s left) kms_properties (get_properties-sanity-non-atomic)
[274.700005] Starting subtest: get_properties-sanity-non-atomic
[274.703971] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s)
[274.850113] [039/133] (785s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[275.054716] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[275.061321] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[275.200100] [040/133] (785s left) gem_ctx_persistence (idempotent)
[275.321600] Starting subtest: idempotent
[275.326860] Subtest idempotent: SUCCESS (0.000s)
[275.464103] [041/133] (784s left) kms_feature_discovery (display-3x)
[275.655973] Starting subtest: display-3x
[275.664233] Subtest display-3x: SKIP (0.000s)
[275.800430] [042/133] (784s left) kms_psr (fbc-pr-basic)
[276.001973] Subtest fbc-pr-basic: SKIP (0.000s)
[276.126018] [043/133] (784s left) gem_exec_balancer (full-late-pulse)
[276.251410] Starting subtest: full-late-pulse
[280.834717] Subtest full-late-pulse: SUCCESS (4.574s)
[281.116622] [044/133] (779s left) gem_exec_schedule (preempt-hang)
[281.365270] Starting subtest: preempt-hang
[281.369315] Starting dynamic subtest: rcs0
[281.633635] Dynamic subtest rcs0: SUCCESS (0.259s)
[281.670437] Starting dynamic subtest: bcs0
[281.907406] Dynamic subtest bcs0: SUCCESS (0.270s)
[281.935193] Starting dynamic subtest: vcs0
[282.160467] Dynamic subtest vcs0: SUCCESS (0.251s)
[282.189773] Starting dynamic subtest: vecs0
[282.400359] Dynamic subtest vecs0: SUCCESS (0.243s)
[282.408136] Subtest preempt-hang: SUCCESS (1.033s)
[282.686248] [045/133] (778s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[282.884589] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[282.893212] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s)
[283.019765] [046/133] (777s left) kms_chamelium_frames (hdmi-aspect-ratio)
[283.600933] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[283.886489] [047/133] (777s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[284.115659] Starting subtest: x-tiled-8bpp-rotate-270
[284.464633] Subtest x-tiled-8bpp-rotate-270: SKIP (0.347s)
[284.755331] [048/133] (776s left) gem_exec_params (sol-reset-not-gen7)
[284.885189] Starting subtest: sol-reset-not-gen7
[284.888809] Subtest sol-reset-not-gen7: SUCCESS (0.003s)
[285.048871] [049/133] (776s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[285.271119] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[287.741034] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SUCCESS (2.473s)
[287.979446] [050/133] (773s left) perf (invalid-oa-format-id)
[288.207224] Starting subtest: invalid-oa-format-id
[288.226844] Subtest invalid-oa-format-id: SUCCESS (0.024s)
[288.336725] [051/133] (773s left) kms_busy (extended-pageflip-hang-newfb)
[288.547937] Starting subtest: extended-pageflip-hang-newfb
[288.551915] Starting dynamic subtest: pipe-A
[292.423510] Dynamic subtest pipe-A: SUCCESS (3.873s)
[292.474072] Starting dynamic subtest: pipe-C
[296.368480] Dynamic subtest pipe-C: SUCCESS (3.934s)
[296.372073] Subtest extended-pageflip-hang-newfb: SUCCESS (7.829s)
[296.748025] [052/133] (764s left) gem_eio (context-create)
[296.891959] Starting subtest: context-create
[296.946941] Subtest context-create: SUCCESS (0.052s)
[297.208704] [053/133] (764s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[297.399388] Starting subtest: crc-primary-suspend-y-tiled-ccs
[297.406050] Starting dynamic subtest: pipe-A-HDMI-A-1