[91.283255] Initializing watchdogs
[91.283485]   /dev/watchdog0
[91.324389] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.339465] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.348744] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.354628] [001/156] (960s left) gem_mmap (big-bo)
[91.473705] Starting subtest: big-bo
[92.390259] Subtest big-bo: SUCCESS (0.917s)
[92.576215] [002/156] (959s left) kms_flip (flip-vs-panning-interruptible)
[92.913241] Starting subtest: flip-vs-panning-interruptible
[92.915226] Starting dynamic subtest: A-HDMI-A1
[98.703735] Dynamic subtest A-HDMI-A1: SUCCESS (5.788s)
[98.728411] Starting dynamic subtest: B-HDMI-A1
[104.116792] Dynamic subtest B-HDMI-A1: SUCCESS (5.404s)
[104.140805] Starting dynamic subtest: C-HDMI-A1
[109.525810] Dynamic subtest C-HDMI-A1: SUCCESS (5.410s)
[109.551019] Starting dynamic subtest: A-HDMI-A2
[114.973312] Dynamic subtest A-HDMI-A2: SUCCESS (5.443s)
[114.995314] Starting dynamic subtest: B-HDMI-A2
[120.402579] Dynamic subtest B-HDMI-A2: SUCCESS (5.424s)
[120.424943] Starting dynamic subtest: C-HDMI-A2
[125.817031] Dynamic subtest C-HDMI-A2: SUCCESS (5.409s)
[125.820416] Subtest flip-vs-panning-interruptible: SUCCESS (32.903s)
[126.415322] [003/156] (925s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[126.643087] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[126.648301] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[126.797031] [004/156] (925s left) perf (global-sseu-config)
[127.092813] Starting subtest: global-sseu-config
[127.094403] Starting dynamic subtest: 0-rcs0
[127.157851] Dynamic subtest 0-rcs0: SUCCESS (0.063s)
[127.160272] Subtest global-sseu-config: SUCCESS (0.064s)
[127.268339] [005/156] (924s left) gem_exec_schedule (preempt-queue-contexts-chain)
[127.415087] Starting subtest: preempt-queue-contexts-chain
[127.416841] Starting dynamic subtest: rcs0
[128.278156] Dynamic subtest rcs0: SUCCESS (0.862s)
[128.339786] Starting dynamic subtest: bcs0
[129.104170] Dynamic subtest bcs0: SUCCESS (0.813s)
[129.165262] Starting dynamic subtest: vcs0
[129.912109] Dynamic subtest vcs0: SUCCESS (0.803s)
[129.987183] Starting dynamic subtest: vecs0
[130.679482] Dynamic subtest vecs0: SUCCESS (0.769s)
[130.684408] Subtest preempt-queue-contexts-chain: SUCCESS (3.254s)
[131.098701] [006/156] (921s left) kms_pm_rpm (pm-caching)
[133.036462] Starting subtest: pm-caching
[134.398458] Subtest pm-caching: SUCCESS (1.362s)
[134.823097] [007/156] (917s left) gem_exec_fence (syncobj-signal)
[135.466109] Starting subtest: syncobj-signal
[135.471151] Subtest syncobj-signal: SUCCESS (0.009s)
[135.862923] [FACT gem_exec_fence (syncobj-signal)] new: kernel.kmod_is_loaded.vgem: true
[135.868441] [008/156] (916s left) i915_drm_fdinfo (busy-idle-check-all)
[135.987917] Starting subtest: busy-idle-check-all
[135.990697] Starting dynamic subtest: rcs0
[136.622076] Dynamic subtest rcs0: SUCCESS (0.632s)
[136.662818] Starting dynamic subtest: bcs0
[137.263501] Dynamic subtest bcs0: SUCCESS (0.644s)
[137.293805] Starting dynamic subtest: vcs0
[137.898413] Dynamic subtest vcs0: SUCCESS (0.628s)
[137.924917] Starting dynamic subtest: vecs0
[138.525411] Dynamic subtest vecs0: SUCCESS (0.626s)
[138.527302] Subtest busy-idle-check-all: SUCCESS (2.539s)
[138.667388] [009/156] (913s left) syncobj_wait (wait-all-for-submit-complex)
[138.779304] Starting subtest: wait-all-for-submit-complex
[139.003655] Subtest wait-all-for-submit-complex: SUCCESS (0.227s)
[139.149614] [010/156] (913s left) gem_mmap_gtt (fault-concurrent-y)
[139.260070] Starting subtest: fault-concurrent-Y
[142.512756] Subtest fault-concurrent-Y: SUCCESS (3.253s)
[142.683934] [011/156] (910s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[142.879065] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[142.884414] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[143.036719] [012/156] (909s left) i915_pm_rpm (debugfs-forcewake-user)
[144.991597] Starting subtest: debugfs-forcewake-user
[155.565173] Subtest debugfs-forcewake-user: SUCCESS (10.573s)
[156.069628] [013/156] (896s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[156.270490] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-wc
[156.275105] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[156.426367] [014/156] (896s left) gem_exec_balancer (invalid-balancer)
[156.558230] Starting subtest: invalid-balancer
[161.020170] Subtest invalid-balancer: SUCCESS (4.464s)
[161.296256] [015/156] (891s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[161.497214] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[161.771846] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.269s)
[162.053143] [016/156] (891s left) gem_eio (hibernate)
[162.209109] Starting subtest: hibernate
[177.123182] Subtest hibernate: SUCCESS (14.907s)
[183.470267] [017/156] (875s left) syncobj_timeline (wait-all-interrupted)
[183.590305] Starting subtest: wait-all-interrupted
[183.695036] Subtest wait-all-interrupted: SUCCESS (0.107s)
[183.853186] [018/156] (875s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt)
[184.097192] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt
[184.101220] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[184.249909] [019/156] (875s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier)
[184.434137] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier
[184.439316] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SKIP (0.000s)
[184.613278] [020/156] (875s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[184.811073] Starting subtest: plane-upscale-20x20-with-modifiers
[184.813152] Starting dynamic subtest: pipe-A
[185.662832] Dynamic subtest pipe-A: SUCCESS (0.858s)
[185.720650] Starting dynamic subtest: pipe-B
[186.598924] Dynamic subtest pipe-B: SUCCESS (0.934s)
[186.642276] Starting dynamic subtest: pipe-C
[187.511854] Dynamic subtest pipe-C: SUCCESS (0.909s)
[187.514139] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (2.709s)
[188.152712] [021/156] (871s left) vgem_basic (second-client)
[188.276102] Starting subtest: second-client
[188.310584] Subtest second-client: SUCCESS (0.032s)
[188.481237] [022/156] (871s left) i915_query (query-topology-unsupported)
[188.589423] Starting subtest: query-topology-unsupported
[188.593621] Subtest query-topology-unsupported: SKIP (0.000s)
[188.743894] [023/156] (871s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-blt)
[188.956473] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-blt
[188.960942] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[189.118813] [024/156] (871s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[189.331089] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[191.429564] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SUCCESS (2.098s)
[191.645275] [025/156] (868s left) perf_pmu (pmu-read)
[191.765870] Starting subtest: pmu-read
[191.776198] Subtest pmu-read: SUCCESS (0.010s)
[191.933309] [026/156] (868s left) syncobj_basic (bad-pad-handle-to-fd)
[192.040211] Starting subtest: bad-pad-handle-to-fd
[192.043290] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[192.214933] [027/156] (868s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[192.424948] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[195.047500] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SUCCESS (2.622s)
[195.268220] [028/156] (865s left) kms_big_fb (x-tiled-addfb)
[195.477273] Starting subtest: x-tiled-addfb
[195.480676] Subtest x-tiled-addfb: SUCCESS (0.002s)
[195.632137] [029/156] (865s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-msflip-blt)
[195.861179] Starting subtest: psrhdr-1p-primscrn-shrfb-msflip-blt
[195.865190] Subtest psrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[196.016993] [030/156] (864s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[196.219170] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[196.223145] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[196.397145] [031/156] (864s left) gem_readwrite (write-bad-handle)
[196.509522] Starting subtest: write-bad-handle
[196.513650] Subtest write-bad-handle: SUCCESS (0.000s)
[196.666317] [032/156] (864s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[196.857071] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[196.860179] Starting dynamic subtest: pipe-A-HDMI-A-1
[196.884156] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[196.927311] Starting dynamic subtest: pipe-A-HDMI-A-2
[196.989052] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s)
[197.021459] Starting dynamic subtest: pipe-B-HDMI-A-1
[197.037195] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[197.063044] Starting dynamic subtest: pipe-B-HDMI-A-2
[197.078483] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[197.103834] Starting dynamic subtest: pipe-C-HDMI-A-1
[197.118578] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[197.144653] Starting dynamic subtest: pipe-C-HDMI-A-2
[197.159348] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s)
[197.162435] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.029s)
[197.962161] [033/156] (863s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-render)
[198.186295] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-render
[198.190379] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[198.350334] [034/156] (862s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[198.545763] Starting subtest: basic-flip-before-cursor-legacy
[199.900328] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.353s)
[200.283973] [035/156] (861s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[200.395659] Starting subtest: write-cpu-read-wc-unflushed
[200.498803] Subtest write-cpu-read-wc-unflushed: SUCCESS (0.108s)
[200.653519] [036/156] (860s left) i915_query (query-topology-kernel-writes)
[200.773312] Starting subtest: query-topology-kernel-writes
[200.775062] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[200.933925] [037/156] (860s left) i915_power (sanity)
[201.043136] Starting subtest: sanity
[205.066553] Subtest sanity: SUCCESS (4.024s)
[205.236063] [038/156] (856s left) gem_reg_read (timestamp-monotonic)
[205.341562] Starting subtest: timestamp-monotonic
[210.173238] Subtest timestamp-monotonic: SUCCESS (4.832s)
[210.317690] [039/156] (851s left) kms_async_flips (invalid-async-flip)
[210.508958] Starting subtest: invalid-async-flip
[211.130142] Starting dynamic subtest: pipe-A-HDMI-A-1
[211.388162] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.256s)
[211.713140] Starting dynamic subtest: pipe-A-HDMI-A-2
[212.009499] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.323s)
[212.336645] Starting dynamic subtest: pipe-B-HDMI-A-1
[212.546326] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.238s)
[212.884435] Starting dynamic subtest: pipe-B-HDMI-A-2
[213.059722] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.217s)
[213.415185] Starting dynamic subtest: pipe-C-HDMI-A-1
[213.620938] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.244s)
[213.951649] Starting dynamic subtest: pipe-C-HDMI-A-2
[214.183812] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.268s)
[214.185517] Subtest invalid-async-flip: SUCCESS (3.678s)
[214.824659] [040/156] (847s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[215.032771] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[215.038877] Starting dynamic subtest: pipe-A-valid-mode
[216.092750] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.059s)
[216.095063] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SUCCESS (1.066s)
[216.460948] [041/156] (845s left) kms_frontbuffer_tracking (psr-2p-rte)
[216.670656] Starting subtest: psr-2p-rte
[216.674391] Subtest psr-2p-rte: SKIP (0.000s)
[216.828146] [042/156] (845s left) kms_psr (fbc-psr-sprite-plane-onoff)
[217.020208] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.000s)
[217.149307] [043/156] (845s left) kms_cursor_crc (cursor-alpha-opaque)
[217.349804] Starting subtest: cursor-alpha-opaque
[217.351778] Starting dynamic subtest: pipe-A-HDMI-A-1
[218.269253] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.916s)
[218.330519] Starting dynamic subtest: pipe-C-HDMI-A-1
[218.886836] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.610s)
[218.889061] Subtest cursor-alpha-opaque: SUCCESS (1.543s)
[219.280794] [044/156] (842s left) kms_frontbuffer_tracking (psrhdr-suspend)
[219.498791] Starting subtest: psrhdr-suspend
[219.504137] Subtest psrhdr-suspend: SKIP (0.000s)
[219.661453] [045/156] (842s left) kms_hdmi_inject (inject-4k)
[220.201541] Starting subtest: inject-4k
[220.510207] Subtest inject-4k: SUCCESS (0.313s)
[221.048244] [046/156] (841s left) kms_rotation_crc (primary-rotation-270)
[221.247544] Starting subtest: primary-rotation-270
[237.383584] Subtest primary-rotation-270: SUCCESS (16.143s)
[237.708780] [047/156] (824s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[237.838164] Starting subtest: basic-wc-gtt-noreloc
[237.846628] Subtest basic-wc-gtt-noreloc: SUCCESS (0.010s)
[238.104555] [048/156] (824s left) kms_vrr (negative-basic)
[238.307827] Starting subtest: negative-basic
[238.317292] Subtest negative-basic: SKIP (0.000s)
[238.453089] [049/156] (824s left) kms_psr (psr-primary-blt)
[238.638151] Subtest psr-primary-blt: SKIP (0.000s)
[238.758075] [050/156] (824s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[238.876322] Starting subtest: multi-wait-for-submit-available-signaled
[238.882387] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.004s)
[239.036601] [051/156] (823s left) perf (oa-exponents)
[239.248721] Starting subtest: oa-exponents
[239.250891] Starting dynamic subtest: 0-rcs0
[242.909245] Dynamic subtest 0-rcs0: SUCCESS (3.659s)
[242.912097] Subtest oa-exponents: SUCCESS (3.663s)
[243.043404] [052/156] (820s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[243.241394] Starting subtest: cursorB-vs-flipB-toggle
[254.622094] Subtest cursorB-vs-flipB-toggle: SUCCESS (11.383s)
[255.015131] [053/156] (808s left) gem_exec_schedule (timeslicing)
[255.160077] Starting subtest: timeslicing
[255.161785] Starting dynamic subtest: rcs0
[255.169544] Dynamic subtest rcs0: SUCCESS (0.010s)
[255.221291] Starting dynamic subtest: bcs0
[255.223963] Dynamic subtest bcs0: SUCCESS (0.008s)
[255.252470] Starting dynamic subtest: vcs0
[255.254839] Dynamic subtest vcs0: SUCCESS (0.008s)
[255.283540] Starting dynamic subtest: vecs0
[255.286397] Dynamic subtest vecs0: SUCCESS (0.012s)
[255.289209] Subtest timeslicing: SUCCESS (0.055s)
[255.620439] [054/156] (807s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[255.804152] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[255.807488] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[255.950908] [055/156] (807s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt)
[256.155865] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt
[256.161336] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[256.317674] [056/156] (807s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-pwrite)
[256.535348] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-pwrite
[256.538781] Subtest hdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[256.701239] [057/156] (806s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[256.924241] Starting subtest: fbc-tiling-linear
[259.211601] Subtest fbc-tiling-linear: SUCCESS (2.289s)
[259.504091] [058/156] (804s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu)
[259.718160] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu
[262.143623] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (2.430s)
[262.498457] [059/156] (801s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[263.107661] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[263.459418] [060/156] (800s left) perf (per-context-mode-unprivileged)
[263.672605] Starting subtest: per-context-mode-unprivileged
[263.677261] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[263.796931] [061/156] (800s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[264.007979] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[266.716188] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (2.708s)
[267.052755] [062/156] (797s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[267.260057] Starting subtest: addfb25-yf-tiled-legacy
[267.261993] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s)
[267.424382] [063/156] (796s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[267.645251] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[267.921679] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.278s)
[268.221439] [064/156] (796s left) syncobj_timeline (etime-multi-wait-available-unsubmitted)
[268.338153] Starting subtest: etime-multi-wait-available-unsubmitted
[268.445712] Subtest etime-multi-wait-available-unsubmitted: SUCCESS (0.108s)
[268.606287] [065/156] (795s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[268.827144] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[268.831171] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[268.993111] [066/156] (795s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[270.898254] Starting subtest: dpms-mode-unset-lpsp
[271.226935] Subtest dpms-mode-unset-lpsp: SKIP (0.324s)
[271.718183] [067/156] (793s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-sf)
[271.920298] Subtest psr2-overlay-plane-move-continuous-sf: SKIP (0.000s)
[272.050366] [068/156] (792s left) kms_cursor_crc (cursor-suspend)
[272.251263] Starting subtest: cursor-suspend
[272.253255] Starting dynamic subtest: pipe-A-HDMI-A-1