[89.784265] Initializing watchdogs
[89.784438]   /dev/watchdog0
[89.822751] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.837423] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.844491] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.852786] [001/131] (960s left) gem_exec_nop (basic-sequential)
[90.022842] Starting subtest: basic-sequential
[93.108530] Subtest basic-sequential: SUCCESS (3.095s)
[93.387930] [002/131] (957s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[93.574429] Starting subtest: 2x-long-cursor-vs-flip-atomic
[147.249871] Subtest 2x-long-cursor-vs-flip-atomic: SUCCESS (53.677s)
[147.567210] [003/131] (902s left) gem_mmap_wc (write-read-distinct)
[147.682600] Starting subtest: write-read-distinct
[147.693739] Subtest write-read-distinct: SUCCESS (0.013s)
[147.861804] [004/131] (902s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[148.047347] Starting subtest: nonblocking-crc-frame-sequence
[148.050495] Starting dynamic subtest: pipe-A-HDMI-A-1
[149.082896] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.037s)
[149.101577] Starting dynamic subtest: pipe-B-HDMI-A-2
[149.808321] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.721s)
[149.826706] Starting dynamic subtest: pipe-C-HDMI-A-1
[150.596273] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.732s)
[150.603327] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.502s)
[150.847401] [005/131] (899s left) i915_drm_fdinfo (virtual-busy)
[150.963072] Starting subtest: virtual-busy
[153.125213] Subtest virtual-busy: SUCCESS (2.163s)
[153.295735] [006/131] (897s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[153.495948] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[153.504042] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[153.664826] [007/131] (897s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[153.862290] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[153.869132] Subtest fbcpsr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[154.030937] [008/131] (896s left) sysfs_defaults (readonly)
[154.147426] Starting subtest: readonly
[154.150842] Starting dynamic subtest: vecs0
[154.155240] Dynamic subtest vecs0: SUCCESS (0.002s)
[154.173479] Starting dynamic subtest: vcs0
[154.177592] Dynamic subtest vcs0: SUCCESS (0.002s)
[154.195679] Starting dynamic subtest: bcs0
[154.199702] Dynamic subtest bcs0: SUCCESS (0.001s)
[154.225313] Starting dynamic subtest: rcs0
[154.229081] Dynamic subtest rcs0: SUCCESS (0.002s)
[154.233348] Subtest readonly: SUCCESS (0.014s)
[154.332183] [009/131] (896s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[154.438848] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[154.548088] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.109s)
[154.695370] [010/131] (896s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[154.897369] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[154.904744] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[155.065590] [011/131] (896s left) syncobj_timeline (reset-signaled)
[155.175951] Starting subtest: reset-signaled
[155.179732] Subtest reset-signaled: SUCCESS (0.002s)
[155.344483] [012/131] (895s left) gem_exec_reloc (basic-wc-gtt)
[155.456282] Starting subtest: basic-wc-gtt
[155.475836] Subtest basic-wc-gtt: SUCCESS (0.019s)
[155.730671] [013/131] (895s left) gen9_exec_parse (batch-zero-length)
[155.855702] Starting subtest: batch-zero-length
[155.859396] Subtest batch-zero-length: SUCCESS (0.003s)
[156.145656] [014/131] (895s left) gem_ctx_shared (detached-shared-gtt)
[156.274796] Starting subtest: detached-shared-gtt
[158.435130] Subtest detached-shared-gtt: SUCCESS (2.152s)
[158.718968] [015/131] (892s left) perf_pmu (rc6-all-gts)
[158.832522] Starting subtest: rc6-all-gts
[158.839987] Subtest rc6-all-gts: SKIP (0.000s)
[158.995466] [016/131] (892s left) kms_plane (plane-position-covered)
[159.179960] Starting subtest: plane-position-covered
[159.951974] Starting dynamic subtest: pipe-A-plane-1
[160.523033] Dynamic subtest pipe-A-plane-1: SUCCESS (0.570s)
[160.541438] Starting dynamic subtest: pipe-A-plane-2
[161.074142] Dynamic subtest pipe-A-plane-2: SUCCESS (0.553s)
[161.093454] Starting dynamic subtest: pipe-A-plane-3
[161.612276] Dynamic subtest pipe-A-plane-3: SUCCESS (0.536s)
[161.632048] Starting dynamic subtest: pipe-A-plane-4
[161.666268] Dynamic subtest pipe-A-plane-4: SUCCESS (0.009s)
[162.108280] Starting dynamic subtest: pipe-B-plane-1
[162.619941] Dynamic subtest pipe-B-plane-1: SUCCESS (0.545s)
[162.641239] Starting dynamic subtest: pipe-B-plane-2
[163.141434] Dynamic subtest pipe-B-plane-2: SUCCESS (0.524s)
[163.173622] Starting dynamic subtest: pipe-B-plane-3
[163.656454] Dynamic subtest pipe-B-plane-3: SUCCESS (0.515s)
[163.677064] Starting dynamic subtest: pipe-B-plane-4
[163.683368] Dynamic subtest pipe-B-plane-4: SUCCESS (0.009s)
[163.737669] Subtest plane-position-covered: SUCCESS (4.486s)
[164.015922] [017/131] (887s left) gem_mmap_gtt (fault-concurrent)
[164.124157] Starting subtest: fault-concurrent
[167.548024] Subtest fault-concurrent: SUCCESS (3.428s)
[167.746047] [018/131] (884s left) kms_psr (psr-sprite-render)
[167.936766] Subtest psr-sprite-render: SKIP (0.000s)
[168.044985] [019/131] (883s left) gem_mmap_gtt (hang-user)
[168.147741] Starting subtest: hang-user
[183.093917] Subtest hang-user: SUCCESS (14.934s)
[183.266144] [020/131] (868s left) template (a)
[183.378701] Starting subtest: A
[183.381884] Subtest A: SUCCESS (0.000s)
[183.548624] [021/131] (868s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[183.734823] Starting subtest: cursorA-vs-flipB-atomic
[194.983758] Subtest cursorA-vs-flipB-atomic: SUCCESS (11.255s)
[195.319417] [022/131] (856s left) kms_rotation_crc (primary-rotation-90)
[195.502551] Starting subtest: primary-rotation-90
[210.827064] Subtest primary-rotation-90: SUCCESS (15.325s)
[211.109208] [023/131] (841s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[211.321250] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[214.406655] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (3.089s)
[214.708302] [024/131] (837s left) kms_universal_plane (universal-plane-sanity)
[214.893405] Starting subtest: universal-plane-sanity
[214.902388] Starting dynamic subtest: pipe-A-HDMI-A-1
[215.760367] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.859s)
[215.819050] Starting dynamic subtest: pipe-B-HDMI-A-2
[216.288507] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.519s)
[216.309101] Starting dynamic subtest: pipe-C-HDMI-A-1
[216.893184] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.542s)
[216.937121] Subtest universal-plane-sanity: SUCCESS (1.938s)
[217.156075] [025/131] (835s left) gem_exec_schedule (u-fairslice-all)
[217.294211] Starting subtest: u-fairslice-all
[219.407114] Subtest u-fairslice-all: SUCCESS (2.112s)
[219.819842] [026/131] (832s left) gem_exec_fence (nb-await)
[220.077277] Starting subtest: nb-await
[220.080709] Starting dynamic subtest: rcs0
[220.151264] Dynamic subtest rcs0: SUCCESS (0.075s)
[220.172204] Starting dynamic subtest: bcs0
[220.226259] Dynamic subtest bcs0: SUCCESS (0.071s)
[220.246660] Starting dynamic subtest: vcs0
[220.301031] Dynamic subtest vcs0: SUCCESS (0.070s)
[220.321548] Starting dynamic subtest: vecs0
[220.370692] Dynamic subtest vecs0: SUCCESS (0.068s)
[220.375648] Subtest nb-await: SUCCESS (0.297s)
[221.190587] [FACT gem_exec_fence (nb-await)] new: kernel.kmod_is_loaded.vgem: true
[221.197373] [027/131] (831s left) kms_hdr (invalid-hdr)
[221.378986] Starting subtest: invalid-hdr
[250.290071] Subtest invalid-hdr: SKIP (28.801s)
[250.681739] [028/131] (802s left) kms_debugfs (display-on-read-all)
[250.903983] Starting subtest: display-on-read-all
[251.360081] Subtest display-on-read-all: SUCCESS (0.392s)
[251.879744] [029/131] (801s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[252.341551] Starting subtest: plane-primary-overlay-mutable-zpos
[253.152572] Subtest plane-primary-overlay-mutable-zpos: SKIP (0.949s)
[253.698253] [030/131] (799s left) i915_pm_rps (engine-order)
[253.821079] Starting subtest: engine-order
[267.310208] Subtest engine-order: SUCCESS (13.480s)
[267.533245] [031/131] (785s left) i915_drm_fdinfo (virtual-busy-hang-all)
[267.645090] Starting subtest: virtual-busy-hang-all
[267.649296] Subtest virtual-busy-hang-all: SUCCESS (0.002s)
[267.832874] [032/131] (785s left) kms_psr (fbc-psr-primary-mmap-cpu)
[268.029226] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s)
[268.150884] [033/131] (785s left) gem_exec_schedule (out-order)
[268.288711] Starting subtest: out-order
[268.292043] Starting dynamic subtest: rcs0
[268.408556] Dynamic subtest rcs0: SUCCESS (0.121s)
[268.434069] Starting dynamic subtest: bcs0
[268.546466] Dynamic subtest bcs0: SUCCESS (0.135s)
[268.570160] Starting dynamic subtest: vcs0
[268.680201] Dynamic subtest vcs0: SUCCESS (0.127s)
[268.706485] Starting dynamic subtest: vecs0
[268.802395] Dynamic subtest vecs0: SUCCESS (0.119s)
[268.810925] Subtest out-order: SUCCESS (0.519s)
[269.233804] [034/131] (784s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[269.480153] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[269.483350] Starting dynamic subtest: pipe-A-valid-mode
[269.822792] Dynamic subtest pipe-A-valid-mode: SKIP (0.384s)
[269.829178] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.389s)
[270.149474] [035/131] (783s left) core_getclient (basic)
[270.258077] Starting subtest: basic
[270.261668] Subtest basic: SUCCESS (0.000s)
[270.447454] [036/131] (783s left) prime_busy (after-wait)
[270.563157] Starting subtest: after-wait
[270.583343] Starting dynamic subtest: rcs0
[270.588157] Dynamic subtest rcs0: SUCCESS (0.011s)
[270.611326] Starting dynamic subtest: bcs0
[270.614758] Dynamic subtest bcs0: SUCCESS (0.010s)
[270.636665] Starting dynamic subtest: vcs0
[270.639844] Dynamic subtest vcs0: SUCCESS (0.023s)
[270.661697] Starting dynamic subtest: vecs0
[270.666261] Dynamic subtest vecs0: SUCCESS (0.011s)
[270.757459] Subtest after-wait: SUCCESS (0.194s)
[270.962541] [037/131] (783s left) gem_exec_schedule (u-submit-golden-slice)
[271.101237] Starting subtest: u-submit-golden-slice
[271.104976] Starting dynamic subtest: rcs0
[271.148467] Dynamic subtest rcs0: SUCCESS (0.046s)
[271.176882] Starting dynamic subtest: bcs0
[271.205800] Dynamic subtest bcs0: SUCCESS (0.051s)
[271.233520] Starting dynamic subtest: vcs0
[271.259114] Dynamic subtest vcs0: SUCCESS (0.050s)
[271.281212] Starting dynamic subtest: vecs0
[271.320853] Dynamic subtest vecs0: SUCCESS (0.054s)
[271.324230] Subtest u-submit-golden-slice: SUCCESS (0.221s)
[271.748645] [038/131] (782s left) gem_eio (in-flight-contexts-immediate)
[271.888283] Starting subtest: in-flight-contexts-immediate
[284.011109] Subtest in-flight-contexts-immediate: SUCCESS (12.119s)
[284.282181] [039/131] (769s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[286.938833] Starting subtest: verify-pxp-key-change-after-suspend-resume
[286.947264] Subtest verify-pxp-key-change-after-suspend-resume: SKIP (0.000s)
[287.126286] [040/131] (767s left) gem_softpin (evict-active-interruptible)
[287.256139] Starting subtest: evict-active-interruptible
[287.276634] Subtest evict-active-interruptible: SUCCESS (0.020s)
[287.463328] [041/131] (767s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[287.673651] Starting subtest: 4-tiled-8bpp-rotate-180
[287.681549] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s)
[287.863681] [042/131] (766s left) gem_userptr_blits (relocations)
[287.988824] Starting subtest: relocations
[287.995196] Subtest relocations: SKIP (0.003s)
[288.232140] [043/131] (766s left) kms_busy (extended-pageflip-hang-newfb)
[288.429392] Starting subtest: extended-pageflip-hang-newfb
[288.434153] Starting dynamic subtest: pipe-A
[292.940141] Dynamic subtest pipe-A: SUCCESS (4.503s)
[292.999474] Starting dynamic subtest: pipe-C
[296.878449] Dynamic subtest pipe-C: SUCCESS (3.925s)
[296.881953] Subtest extended-pageflip-hang-newfb: SUCCESS (8.450s)
[297.269420] [044/131] (757s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[297.387560] Starting subtest: single-wait-for-submit-unsubmitted
[297.492127] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.108s)
[297.671831] [045/131] (757s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[297.872184] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[301.134079] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.266s)
[301.464192] [046/131] (753s left) kms_plane_cursor (primary)
[301.650488] Starting subtest: primary
[301.680958] Starting dynamic subtest: pipe-A-HDMI-A-1-size-64
[304.268120] Dynamic subtest pipe-A-HDMI-A-1-size-64: SUCCESS (2.615s)
[304.338140] Starting dynamic subtest: pipe-A-HDMI-A-1-size-128
[306.751394] Dynamic subtest pipe-A-HDMI-A-1-size-128: SUCCESS (2.430s)
[306.823694] Starting dynamic subtest: pipe-A-HDMI-A-1-size-256
[309.236031] Dynamic subtest pipe-A-HDMI-A-1-size-256: SUCCESS (2.428s)
[309.542941] Starting dynamic subtest: pipe-B-HDMI-A-2-size-64
[312.005552] Dynamic subtest pipe-B-HDMI-A-2-size-64: SUCCESS (2.518s)
[312.060502] Starting dynamic subtest: pipe-B-HDMI-A-2-size-128
[314.472756] Dynamic subtest pipe-B-HDMI-A-2-size-128: SUCCESS (2.431s)
[314.526462] Starting dynamic subtest: pipe-B-HDMI-A-2-size-256
[316.938798] Dynamic subtest pipe-B-HDMI-A-2-size-256: SUCCESS (2.431s)
[317.234143] Starting dynamic subtest: pipe-C-HDMI-A-1-size-64
[319.678060] Dynamic subtest pipe-C-HDMI-A-1-size-64: SUCCESS (2.503s)
[319.732146] Starting dynamic subtest: pipe-C-HDMI-A-1-size-128
[322.261983] Dynamic subtest pipe-C-HDMI-A-1-size-128: SUCCESS (2.430s)
[322.328282] Starting dynamic subtest: pipe-C-HDMI-A-1-size-256
[324.593402] Dynamic subtest pipe-C-HDMI-A-1-size-256: SUCCESS (2.415s)
[324.828980] Subtest primary: SUCCESS (23.180s)
[325.166548] [047/131] (729s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[325.382746] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[325.390255] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[325.564981] [048/131] (729s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[325.766443] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[325.772970] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[325.949281] [049/131] (729s left) gem_exec_balancer (bonded-true-hang)
[326.201553] Starting subtest: bonded-true-hang
[330.679042] Subtest bonded-true-hang: SUCCESS (4.477s)
[330.884271] [050/131] (724s left) gem_exec_fence (syncobj-timeline-repeat)
[331.569942] Starting subtest: syncobj-timeline-repeat
[332.852699] Subtest syncobj-timeline-repeat: SUCCESS (1.283s)
[333.145668] [051/131] (722s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[333.256734] Starting subtest: etime-multi-wait-for-submit-submitted
[333.365231] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.108s)
[333.547249] [052/131] (722s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[333.659046] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[333.799164] [053/131] (721s left) syncobj_wait (multi-wait-submitted)
[333.912985] Starting subtest: multi-wait-submitted
[334.020207] Subtest multi-wait-submitted: SUCCESS (0.110s)
[334.198427] [054/131] (721s left) gem_ppgtt (blt-vs-render-ctx0)
[334.310505] Starting subtest: blt-vs-render-ctx0
[367.374105] Subtest blt-vs-render-ctx0: SUCCESS (33.058s)
[367.573542] [055/131] (688s left) kms_cdclk (mode-transition)
[367.795364] Subtest mode-transition: SKIP (0.000s)
[367.913199] [056/131] (688s left) kms_vrr (max-min)
[368.128267] Starting subtest: max-min
[368.141707] Subtest max-min: SKIP (0.000s)
[368.315552] [057/131] (687s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[368.535913] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[368.543260] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[368.735335] [058/131] (687s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[368.976562] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[368.982806] Starting dynamic subtest: pipe-A-valid-mode
[369.927469] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.995s)
[369.931894] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SUCCESS (1.000s)
[370.315995] [059/131] (686s left) kms_psr (fbc-pr-no-drrs)
[370.506324] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[370.634440] [060/131] (685s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[370.850968] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[370.854668] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[381.947156] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.093s)
[381.977091] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[392.916108] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.961s)
[392.947630] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[403.913354] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.991s)
[403.916931] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (33.070s)
[404.921909] [061/131] (651s left) drm_read (short-buffer-nonblock)
[405.683043] Starting subtest: short-buffer-nonblock
[405.686700] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[405.875898] [062/131] (651s left) syncobj_wait (multi-wait-submitted-signaled)
[406.009051] Starting subtest: multi-wait-submitted-signaled
[406.015132] Subtest multi-wait-submitted-signaled: SUCCESS (0.003s)
[406.189988] [063/131] (650s left) kms_flip (2x-flip-vs-suspend)
[406.381241] Starting subtest: 2x-flip-vs-suspend
[406.385794] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2