[45.398797] Initializing watchdogs
[45.398861]   /dev/watchdog0
[45.414019] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[45.419552] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[45.422018] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[45.423913] [001/154] (960s left) kms_pm_rpm (dpms-non-lpsp)
[46.850996] Starting subtest: dpms-non-lpsp
[46.853303] Subtest dpms-non-lpsp: SKIP (0.001s)
[47.039204] [002/154] (958s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-pwrite)
[47.134415] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-pwrite
[47.135874] Subtest hdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[47.209560] [003/154] (958s left) syncobj_wait (invalid-reset-zero-handles)
[47.245235] Starting subtest: invalid-reset-zero-handles
[47.246033] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[47.308922] [004/154] (958s left) syncobj_basic (bad-create-flags)
[47.343534] Starting subtest: bad-create-flags
[47.344231] Subtest bad-create-flags: SUCCESS (0.000s)
[47.397664] [005/154] (958s left) api_intel_bb (add-remove-objects)
[47.432761] Starting subtest: add-remove-objects
[47.438238] Subtest add-remove-objects: SUCCESS (0.006s)
[47.572690] [006/154] (958s left) gem_userptr_blits (relocations)
[47.612977] Starting subtest: relocations
[47.614473] Subtest relocations: SUCCESS (0.002s)
[47.746982] [007/154] (958s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[47.842678] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[47.844121] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[47.928652] [008/154] (958s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[47.965410] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[47.966188] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[48.045692] [009/154] (958s left) kms_content_protection (type1)
[48.142438] Starting subtest: type1
[48.148209] Subtest type1: SKIP (0.004s)
[48.246493] [010/154] (958s left) syncobj_timeline (invalid-transfer-non-existent-point)
[48.281985] Starting subtest: invalid-transfer-non-existent-point
[48.282772] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[48.341551] [011/154] (957s left) gem_ctx_param (root-set)
[48.375623] Starting subtest: root-set
[48.376403] Subtest root-set: SUCCESS (0.000s)
[48.439746] [012/154] (957s left) gem_render_copy (yf-tiled-ccs-to-linear)
[48.483367] Starting subtest: yf-tiled-ccs-to-linear
[48.484284] Starting dynamic subtest: smem
[48.554101] Dynamic subtest smem: SUCCESS (0.070s)
[48.554876] Subtest yf-tiled-ccs-to-linear: SUCCESS (0.071s)
[48.745686] [013/154] (957s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[48.839424] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[48.840863] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[48.941682] [014/154] (957s left) gem_exec_schedule (implicit-read-write)
[48.987294] Starting subtest: implicit-read-write
[48.988053] Starting dynamic subtest: rcs0
[49.056175] Dynamic subtest rcs0: SUCCESS (0.066s)
[49.066876] Starting dynamic subtest: bcs0
[49.124222] Dynamic subtest bcs0: SUCCESS (0.065s)
[49.134153] Starting dynamic subtest: vcs0
[49.195544] Dynamic subtest vcs0: SUCCESS (0.068s)
[49.206135] Starting dynamic subtest: vcs1
[49.263337] Dynamic subtest vcs1: SUCCESS (0.065s)
[49.273454] Starting dynamic subtest: vecs0
[49.329844] Dynamic subtest vecs0: SUCCESS (0.064s)
[49.331087] Subtest implicit-read-write: SUCCESS (0.331s)
[49.630798] [015/154] (956s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier)
[49.720467] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier
[49.721898] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier: SKIP (0.000s)
[49.784505] [016/154] (956s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[49.873750] Starting subtest: plane-scaler-unity-scaling-with-rotation
[49.874545] Starting dynamic subtest: pipe-A
[50.990409] Dynamic subtest pipe-A: SKIP (1.081s)
[50.999463] Starting dynamic subtest: pipe-B
[52.181924] Dynamic subtest pipe-B: SKIP (1.161s)
[52.190060] Starting dynamic subtest: pipe-C
[53.336965] Dynamic subtest pipe-C: SKIP (1.128s)
[53.346048] Starting dynamic subtest: pipe-D
[54.605803] Dynamic subtest pipe-D: SKIP (1.243s)
[54.607273] Subtest plane-scaler-unity-scaling-with-rotation: SKIP (4.616s)
[54.774912] [017/154] (951s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[54.881034] Starting subtest: basic-flip-before-cursor-varying-size
[55.391850] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.503s)
[55.542673] [018/154] (951s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-wc)
[55.639584] Starting subtest: fbchdr-rgb565-draw-mmap-wc
[55.641031] Subtest fbchdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[55.737852] [019/154] (950s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-msflip-blt)
[55.832241] Starting subtest: psrhdr-2p-scndscrn-indfb-msflip-blt
[55.833701] Subtest psrhdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[55.906686] [020/154] (950s left) gem_softpin (allocator-nopin-reserve)
[55.946073] Starting subtest: allocator-nopin-reserve
[56.034089] Subtest allocator-nopin-reserve: SUCCESS (0.087s)
[56.135557] [021/154] (950s left) gem_fence_thrash (bo-write-verify-threaded-none)
[56.168694] Starting subtest: bo-write-verify-threaded-none
[60.527947] Subtest bo-write-verify-threaded-none: SUCCESS (4.278s)
[60.597036] [022/154] (946s left) gem_mmap_offset (bad-flags)
[60.632345] Starting subtest: bad-flags
[60.633104] Subtest bad-flags: SUCCESS (0.000s)
[60.717781] [023/154] (946s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-rte)
[60.813585] Starting subtest: fbcpsrhdr-2p-rte
[60.814953] Subtest fbcpsrhdr-2p-rte: SKIP (0.000s)
[60.915710] [024/154] (945s left) gem_ctx_engines (independent)
[60.957220] Starting subtest: independent
[60.957957] Starting dynamic subtest: rcs0
[61.036825] Dynamic subtest rcs0: SUCCESS (0.078s)
[61.044505] Starting dynamic subtest: bcs0
[61.110555] Dynamic subtest bcs0: SUCCESS (0.072s)
[61.117808] Starting dynamic subtest: vcs0
[61.181493] Dynamic subtest vcs0: SUCCESS (0.069s)
[61.189019] Starting dynamic subtest: vcs1
[61.254805] Dynamic subtest vcs1: SUCCESS (0.072s)
[61.261220] Starting dynamic subtest: vecs0
[61.326370] Dynamic subtest vecs0: SUCCESS (0.070s)
[61.333620] Starting dynamic subtest: all-engines
[61.508466] Dynamic subtest all-engines: SUCCESS (0.171s)
[61.509347] Subtest independent: SUCCESS (0.539s)
[61.690500] [025/154] (945s left) kms_psr (fbc-psr-primary-page-flip)
[61.778785] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[61.821974] [026/154] (945s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-onoff)
[61.914388] Starting subtest: hdr-1p-primscrn-spr-indfb-onoff
[61.915763] Subtest hdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[62.015623] [027/154] (944s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[62.105100] Starting subtest: pixel-format-y-tiled-ccs-modifier
[62.106582] Subtest pixel-format-y-tiled-ccs-modifier: SKIP (0.000s)
[62.160227] [028/154] (944s left) gem_ccs (ctrl-surf-copy)
[62.200186] Starting subtest: ctrl-surf-copy
[62.201598] Subtest ctrl-surf-copy: SKIP (0.000s)
[62.264159] [029/154] (944s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[62.358098] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[62.359578] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[62.451295] [030/154] (944s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[62.550452] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[62.551869] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[62.634676] [031/154] (944s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[62.669207] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[62.773651] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[62.843773] [032/154] (944s left) gem_close_race (basic-process)
[62.888296] Starting subtest: basic-process
[62.936097] Subtest basic-process: SUCCESS (0.047s)
[63.121991] [033/154] (943s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt)
[63.213422] Starting subtest: psr-rgb101010-draw-mmap-gtt
[63.214799] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[63.314264] [034/154] (943s left) kms_cursor_crc (cursor-onscreen-256x256)
[63.416046] Starting subtest: cursor-onscreen-256x256
[63.416764] Starting dynamic subtest: pipe-A-HDMI-A-1
[64.606162] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.186s)
[64.650572] Starting dynamic subtest: pipe-D-HDMI-A-1
[65.807941] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.157s)
[65.808716] Subtest cursor-onscreen-256x256: SUCCESS (2.387s)
[65.948099] [035/154] (941s left) gem_mmap_gtt (basic-wc)
[65.988513] Starting subtest: basic-wc
[66.628067] Subtest basic-wc: SUCCESS (0.630s)
[66.698039] [036/154] (940s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[66.793520] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[66.794305] Starting dynamic subtest: pipe-A-HDMI-A-1
[66.813975] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[66.821919] Starting dynamic subtest: pipe-B-HDMI-A-1
[66.841206] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[66.850245] Starting dynamic subtest: pipe-C-HDMI-A-1
[66.869579] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[66.876925] Starting dynamic subtest: pipe-D-HDMI-A-1
[66.894591] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.002s)
[66.895969] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.014s)
[67.162563] [037/154] (940s left) i915_pm_freq_api (freq-basic-api)
[67.241633] Subtest freq-basic-api: SKIP (0.000s)
[67.320833] [038/154] (940s left) gem_vm_create (create-ext)
[67.655434] Starting subtest: create-ext
[67.657376] Subtest create-ext: SUCCESS (0.003s)
[67.941738] [039/154] (939s left) kms_psr2_su (frontbuffer-xrgb8888)
[68.035850] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[68.079617] [040/154] (939s left) i915_pm_rps (thresholds-idle)
[68.159187] Starting subtest: thresholds-idle
[68.159944] Starting dynamic subtest: gt0
[78.565285] Dynamic subtest gt0: SUCCESS (10.389s)
[78.568558] Subtest thresholds-idle: SUCCESS (10.392s)
[78.630439] [041/154] (928s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[78.725437] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[78.726818] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[78.827620] [042/154] (928s left) kms_chamelium_hpd (vga-hpd)
[78.967867] Subtest vga-hpd: SKIP (0.000s)
[79.064629] [043/154] (928s left) gem_mmap_gtt (basic-small-bo)
[79.102042] Starting subtest: basic-small-bo
[79.385770] Subtest basic-small-bo: SUCCESS (0.284s)
[79.504788] [044/154] (928s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-plflip-blt)
[79.660572] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-plflip-blt
[79.661971] Subtest fbcpsrhdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[79.752312] [045/154] (927s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[79.851996] Starting subtest: y-tiled-16bpp-rotate-180
[81.003048] Subtest y-tiled-16bpp-rotate-180: SUCCESS (1.149s)
[81.127778] [046/154] (926s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[81.222535] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[81.223974] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[81.324019] [047/154] (926s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[81.413133] Starting subtest: coverage-vs-premult-vs-constant
[81.480320] Starting dynamic subtest: pipe-A-HDMI-A-1
[81.745386] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.263s)
[81.752271] Starting dynamic subtest: pipe-D-HDMI-A-1
[82.019282] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.272s)
[82.020034] Subtest coverage-vs-premult-vs-constant: SUCCESS (0.605s)
[82.170621] [048/154] (925s left) gem_partial_pwrite_pread (writes-after-reads-display)
[82.214176] Starting subtest: writes-after-reads-display
[94.547201] Subtest writes-after-reads-display: SUCCESS (12.326s)
[94.653067] [049/154] (913s left) kms_draw_crc (draw-method-mmap-wc)
[94.747312] Starting subtest: draw-method-mmap-wc
[94.748090] Starting dynamic subtest: xrgb8888-untiled
[94.896195] Dynamic subtest xrgb8888-untiled: SUCCESS (0.149s)
[94.913046] Starting dynamic subtest: rgb565-untiled
[95.054322] Dynamic subtest rgb565-untiled: SUCCESS (0.156s)
[95.071503] Starting dynamic subtest: xrgb2101010-untiled
[95.196202] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.139s)
[95.213577] Starting dynamic subtest: xbgr16161616f-untiled
[95.337840] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.139s)
[95.355637] Starting dynamic subtest: xrgb8888-xtiled
[95.429717] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.089s)
[95.446642] Starting dynamic subtest: rgb565-xtiled
[95.521338] Dynamic subtest rgb565-xtiled: SUCCESS (0.089s)
[95.538532] Starting dynamic subtest: xrgb2101010-xtiled
[95.604769] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.081s)
[95.622591] Starting dynamic subtest: xbgr16161616f-xtiled
[95.687906] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.081s)
[95.705174] Starting dynamic subtest: xrgb8888-ytiled
[95.779793] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.089s)
[95.795177] Starting dynamic subtest: rgb565-ytiled
[95.871659] Dynamic subtest rgb565-ytiled: SUCCESS (0.090s)
[95.890392] Starting dynamic subtest: xrgb2101010-ytiled
[95.954714] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.080s)
[95.969030] Starting dynamic subtest: xbgr16161616f-ytiled
[96.039027] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.081s)
[96.042291] Subtest draw-method-mmap-wc: SUCCESS (1.294s)
[96.139810] [050/154] (911s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[96.180084] Starting subtest: invalid-multi-wait-unsubmitted
[96.180804] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[96.241327] [051/154] (911s left) gem_exec_fence (syncobj-invalid-flags)
[96.606105] Starting subtest: syncobj-invalid-flags
[96.606893] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[96.917428] [FACT gem_exec_fence (syncobj-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[96.919387] [052/154] (911s left) kms_getfb (getfb2-handle-zero)
[97.009965] Starting subtest: getfb2-handle-zero
[97.010762] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[97.110651] [053/154] (910s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[97.202720] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[97.204063] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[97.302460] [054/154] (910s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[97.394341] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[97.395683] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[97.503743] [055/154] (910s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-render)
[97.595993] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-render
[97.597420] Subtest psrhdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[97.683988] [056/154] (910s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[97.773191] Starting subtest: cursorB-vs-flipA-varying-size
[97.774737] Subtest cursorB-vs-flipA-varying-size: SKIP (0.002s)
[97.843849] [057/154] (910s left) gem_exec_schedule (semaphore-codependency)
[97.890310] Starting subtest: semaphore-codependency
[97.897545] Subtest semaphore-codependency: SUCCESS (0.007s)
[98.194830] [058/154] (910s left) gem_mmap (pf-nonblock)
[98.228795] Starting subtest: pf-nonblock
[98.230789] Subtest pf-nonblock: SUCCESS (0.002s)
[98.347325] [059/154] (909s left) perf_pmu (module-unload)
[98.389633] Starting subtest: module-unload