[188.503290] Initializing watchdogs
[188.503464]   /dev/watchdog0
[188.544800] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[188.559998] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[188.566965] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[188.573353] [001/132] (960s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[188.796104] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[188.805745] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.004s)
[188.923957] [002/132] (960s left) gen9_exec_parse (basic-rejected)
[189.060513] Starting subtest: basic-rejected
[189.063872] Subtest basic-rejected: SUCCESS (0.005s)
[189.312663] [003/132] (959s left) syncobj_wait (multi-wait-all-submitted)
[189.419811] Starting subtest: multi-wait-all-submitted
[189.532625] Subtest multi-wait-all-submitted: SUCCESS (0.110s)
[189.660169] [004/132] (959s left) gem_mmap_gtt (basic-small-copy-xy)
[189.757957] Starting subtest: basic-small-copy-XY
[193.026294] Subtest basic-small-copy-XY: SUCCESS (3.266s)
[193.165232] [005/132] (956s left) gem_exec_balancer (noheartbeat)
[193.561028] Starting subtest: noheartbeat
[197.928770] Subtest noheartbeat: SUCCESS (4.368s)
[198.061384] [006/132] (951s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[198.186099] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[198.298680] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.108s)
[198.425478] [007/132] (951s left) kms_dp_link_training (uhbr-sst)
[198.616267] Starting subtest: uhbr-sst
[198.620022] Subtest uhbr-sst: SKIP (0.000s)
[198.739396] [008/132] (950s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[198.923079] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[198.926122] Starting dynamic subtest: pipe-A-valid-mode
[199.850077] Dynamic subtest pipe-A-valid-mode: SKIP (0.917s)
[199.854109] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (0.920s)
[200.343098] [009/132] (949s left) perf (unprivileged-single-ctx-counters)
[200.581259] Starting subtest: unprivileged-single-ctx-counters
[200.584501] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[200.676256] [010/132] (949s left) gem_render_copy_redux (interruptible)
[200.792185] Starting subtest: interruptible
[202.187991] Subtest interruptible: SUCCESS (1.396s)
[202.312435] [011/132] (947s left) kms_scaling_modes (scaling-mode-full-aspect)
[202.492057] Starting subtest: scaling-mode-full-aspect
[202.508153] Subtest scaling-mode-full-aspect: SKIP (0.004s)
[202.626791] [012/132] (947s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[202.813062] Starting subtest: modeset-transition-nonblocking-fencing
[202.815094] Starting dynamic subtest: 1x-outputs
[206.383442] Dynamic subtest 1x-outputs: SUCCESS (3.575s)
[206.402322] Starting dynamic subtest: 2x-outputs
[213.753888] Dynamic subtest 2x-outputs: SUCCESS (7.368s)
[213.756297] Subtest modeset-transition-nonblocking-fencing: SUCCESS (10.944s)
[214.119967] [013/132] (935s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[214.316281] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[214.320277] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[214.451422] [014/132] (935s left) gem_mmap_gtt (cpuset-medium-copy)
[214.563859] Starting subtest: cpuset-medium-copy
[222.310306] Subtest cpuset-medium-copy: SUCCESS (7.745s)
[222.476124] [015/132] (927s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[222.825249] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[222.830662] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[222.952065] [016/132] (927s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[223.144061] Starting subtest: short-flip-after-cursor-atomic-transitions
[224.535366] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (1.390s)
[224.836710] [017/132] (925s left) gem_userptr_blits (vma-merge)
[225.000110] Starting subtest: vma-merge
[225.167494] Subtest vma-merge: SUCCESS (0.163s)
[225.307204] [018/132] (925s left) kms_rmfb (rmfb-ioctl)
[225.498508] Starting subtest: rmfb-ioctl
[225.503402] Starting dynamic subtest: pipe-A-HDMI-A-1
[225.803767] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.314s)
[225.845490] Starting dynamic subtest: pipe-B-HDMI-A-2
[226.247246] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.439s)
[226.285375] Starting dynamic subtest: pipe-C-HDMI-A-1
[226.694270] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.444s)
[226.696175] Subtest rmfb-ioctl: SUCCESS (1.209s)
[227.243918] [019/132] (923s left) kms_plane_scaling (invalid-num-scalers)
[227.420759] Starting subtest: invalid-num-scalers
[227.453083] Starting dynamic subtest: pipe-A-HDMI-A-1-invalid-num-scalers
[227.553691] Dynamic subtest pipe-A-HDMI-A-1-invalid-num-scalers: SUCCESS (0.100s)
[227.623556] Starting dynamic subtest: pipe-A-HDMI-A-2-invalid-num-scalers
[227.664086] Dynamic subtest pipe-A-HDMI-A-2-invalid-num-scalers: SUCCESS (0.074s)
[227.800192] Starting dynamic subtest: pipe-B-HDMI-A-1-invalid-num-scalers
[227.809254] Dynamic subtest pipe-B-HDMI-A-1-invalid-num-scalers: SUCCESS (0.076s)
[227.859374] Starting dynamic subtest: pipe-B-HDMI-A-2-invalid-num-scalers
[227.882218] Dynamic subtest pipe-B-HDMI-A-2-invalid-num-scalers: SUCCESS (0.082s)
[227.940948] Starting dynamic subtest: pipe-C-HDMI-A-1-invalid-num-scalers
[228.040399] Dynamic subtest pipe-C-HDMI-A-1-invalid-num-scalers: SUCCESS (0.132s)
[228.104875] Starting dynamic subtest: pipe-C-HDMI-A-2-invalid-num-scalers
[228.192047] Dynamic subtest pipe-C-HDMI-A-2-invalid-num-scalers: SUCCESS (0.122s)
[228.195206] Subtest invalid-num-scalers: SUCCESS (0.766s)
[228.383385] [020/132] (922s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[228.585844] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[228.591476] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.001s)
[228.724763] [021/132] (922s left) kms_cursor_crc (cursor-offscreen-256x85)
[228.930083] Starting subtest: cursor-offscreen-256x85
[228.992312] Starting dynamic subtest: pipe-A-HDMI-A-1
[231.865186] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.871s)
[231.964599] Starting dynamic subtest: pipe-C-HDMI-A-1
[234.514346] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.569s)
[234.517929] Subtest cursor-offscreen-256x85: SUCCESS (5.581s)
[234.827166] [022/132] (916s left) dmabuf_sync_file (export-multiwait)
[235.098135] Starting subtest: export-multiwait
[235.104112] Subtest export-multiwait: SUCCESS (0.007s)
[235.229736] [FACT dmabuf_sync_file (export-multiwait)] new: kernel.kmod_is_loaded.vgem: true
[235.235290] [023/132] (915s left) kms_sequence (get-forked)
[235.421126] Starting subtest: get-forked
[235.424094] Starting dynamic subtest: pipe-A-HDMI-A-1
[238.192536] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.775s)
[238.229085] Starting dynamic subtest: pipe-A-HDMI-A-2
[240.670157] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.476s)
[240.696108] Starting dynamic subtest: pipe-B-HDMI-A-1
[243.138691] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.464s)
[243.170714] Starting dynamic subtest: pipe-B-HDMI-A-2
[245.605069] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.458s)
[245.628088] Starting dynamic subtest: pipe-C-HDMI-A-1
[248.094668] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.487s)
[248.118576] Starting dynamic subtest: pipe-C-HDMI-A-2
[250.568451] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.468s)
[250.571442] Subtest get-forked: SUCCESS (15.153s)
[250.912976] [024/132] (900s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions)
[251.105605] Starting subtest: cursorA-vs-flipA-atomic-transitions
[262.421103] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.313s)
[262.792333] [025/132] (888s left) kms_chamelium_frames (hdmi-crc-multiple)
[263.373104] Subtest hdmi-crc-multiple: SKIP (0.000s)
[263.704557] [026/132] (887s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[263.903922] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[266.406313] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (2.501s)
[266.748391] [027/132] (884s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[266.967155] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[266.971298] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[267.121499] [028/132] (884s left) gem_workarounds (basic-read-fd)
[267.268091] Starting subtest: basic-read-fd
[267.288285] Subtest basic-read-fd: SUCCESS (0.022s)
[267.437440] [029/132] (884s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[267.644101] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[267.649139] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[267.787789] [030/132] (883s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[268.004712] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[268.007910] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[268.153134] [031/132] (883s left) prime_busy (after)
[268.274091] Starting subtest: after
[268.288069] Starting dynamic subtest: rcs0
[268.302543] Dynamic subtest rcs0: SUCCESS (0.012s)
[268.339208] Starting dynamic subtest: bcs0
[268.341448] Dynamic subtest bcs0: SUCCESS (0.017s)
[268.374603] Starting dynamic subtest: vcs0
[268.376149] Dynamic subtest vcs0: SUCCESS (0.019s)
[268.394664] Starting dynamic subtest: vecs0
[268.396221] Dynamic subtest vecs0: SUCCESS (0.020s)
[268.481697] Subtest after: SUCCESS (0.208s)
[268.630663] [032/132] (883s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[268.819711] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[270.991868] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SUCCESS (2.174s)
[271.203967] [033/132] (880s left) perf_pmu (busy-idle)
[271.320281] Starting subtest: busy-idle
[271.323133] Starting dynamic subtest: rcs0
[271.956059] Dynamic subtest rcs0: SUCCESS (0.633s)
[271.977028] Starting dynamic subtest: bcs0
[272.596993] Dynamic subtest bcs0: SUCCESS (0.639s)
[272.619210] Starting dynamic subtest: vcs0
[273.235824] Dynamic subtest vcs0: SUCCESS (0.633s)
[273.261347] Starting dynamic subtest: vecs0
[273.866159] Dynamic subtest vecs0: SUCCESS (0.629s)
[273.868153] Subtest busy-idle: SUCCESS (2.545s)
[274.024135] [034/132] (877s left) gem_fence_thrash (bo-write-verify-y)
[274.121539] Starting subtest: bo-write-verify-y
[275.295362] Subtest bo-write-verify-y: SUCCESS (1.172s)
[275.438178] [035/132] (876s left) gem_render_copy (yf-tiled-to-vebox-y-tiled)
[275.558584] Starting subtest: yf-tiled-to-vebox-y-tiled
[275.564282] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s)
[275.825396] [036/132] (876s left) kms_color (ctm-max)
[276.002064] Starting subtest: ctm-max
[276.004177] Starting dynamic subtest: pipe-A-HDMI-A-1
[277.007362] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.005s)
[277.046016] Starting dynamic subtest: pipe-A-HDMI-A-2
[277.718877] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.709s)
[277.745020] Starting dynamic subtest: pipe-B-HDMI-A-1
[278.431289] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.706s)
[278.485589] Starting dynamic subtest: pipe-B-HDMI-A-2
[279.090731] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.658s)
[279.130082] Starting dynamic subtest: pipe-C-HDMI-A-1
[279.752396] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.656s)
[279.792395] Starting dynamic subtest: pipe-C-HDMI-A-2
[280.409540] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.656s)
[280.415198] Subtest ctm-max: SUCCESS (4.406s)
[280.731710] [037/132] (871s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[280.952322] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[283.903119] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (2.952s)
[284.099197] [038/132] (868s left) gem_exec_schedule (submit-early-slice)
[284.248221] Starting subtest: submit-early-slice
[284.252785] Starting dynamic subtest: rcs0
[284.304189] Dynamic subtest rcs0: SUCCESS (0.054s)
[284.333458] Starting dynamic subtest: bcs0
[284.365654] Dynamic subtest bcs0: SUCCESS (0.057s)
[284.393603] Starting dynamic subtest: vcs0
[284.417402] Dynamic subtest vcs0: SUCCESS (0.053s)
[284.442384] Starting dynamic subtest: vecs0
[284.470590] Dynamic subtest vecs0: SUCCESS (0.052s)
[284.472926] Subtest submit-early-slice: SUCCESS (0.224s)
[284.869425] [039/132] (867s left) kms_getfb (getfb-addfb-different-handles)
[285.065103] Starting subtest: getfb-addfb-different-handles
[285.067099] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[285.214390] [040/132] (867s left) syncobj_timeline (single-wait-available-submitted)
[285.321484] Starting subtest: single-wait-available-submitted
[285.328222] Subtest single-wait-available-submitted: SUCCESS (0.003s)
[285.498538] [041/132] (867s left) gem_partial_pwrite_pread (write)
[285.616683] Starting subtest: write
[299.995216] Subtest write: SUCCESS (14.379s)
[300.166856] [042/132] (852s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[300.373226] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[300.378849] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.001s)
[300.516619] [043/132] (852s left) gem_tiled_blits (basic)
[300.915514] Starting subtest: basic
[300.939878] Subtest basic: SUCCESS (0.030s)
[301.122998] [044/132] (851s left) gem_exec_reloc (basic-range)
[301.388089] Starting subtest: basic-range
[301.474818] Subtest basic-range: SUCCESS (0.079s)
[301.701775] [045/132] (851s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[301.909096] Starting subtest: y-tiled-16bpp-rotate-270
[302.254174] Subtest y-tiled-16bpp-rotate-270: SKIP (0.340s)
[302.519239] [046/132] (850s left) gem_exec_async (forked-writes)
[302.661290] Starting subtest: forked-writes
[302.666052] Starting dynamic subtest: rcs0
[302.748167] Dynamic subtest rcs0: SUCCESS (0.072s)
[302.820578] Starting dynamic subtest: bcs0
[302.842344] Dynamic subtest bcs0: SUCCESS (0.102s)
[302.866636] Starting dynamic subtest: vcs0
[302.902607] Dynamic subtest vcs0: SUCCESS (0.061s)
[302.938080] Starting dynamic subtest: vecs0
[302.966749] Dynamic subtest vecs0: SUCCESS (0.057s)
[302.968504] Subtest forked-writes: SUCCESS (0.311s)
[303.256318] [047/132] (850s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip)
[303.453702] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip
[303.458122] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[303.600737] [048/132] (849s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[303.806678] Starting subtest: yf-tiled-64bpp-rotate-90
[303.810789] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[303.955707] [049/132] (849s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[304.164142] Subtest psr2-plane-move-sf-dmg-area: SKIP (0.000s)
[304.274720] [050/132] (849s left) gem_exec_whisper (basic-sync-all)
[304.404289] Starting subtest: basic-sync-all
[312.390220] Subtest basic-sync-all: SUCCESS (7.984s)
[312.653522] [051/132] (841s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[312.839702] Starting subtest: plane-all-modeset-transition-internal-panels
[312.846131] Subtest plane-all-modeset-transition-internal-panels: SKIP (0.000s)
[312.986759] [052/132] (840s left) kms_cursor_crc (cursor-suspend)
[313.200083] Starting subtest: cursor-suspend
[313.203141] Starting dynamic subtest: pipe-A-HDMI-A-1