[88.085848] Initializing watchdogs
[88.086136]   /dev/watchdog0
[88.125287] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.140208] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.146650] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.151577] [001/133] (960s left) gem_exec_schedule (pi-distinct-iova)
[88.440086] Starting subtest: pi-distinct-iova
[88.444095] Starting dynamic subtest: rcs0
[88.512047] Dynamic subtest rcs0: SUCCESS (0.066s)
[88.535853] Starting dynamic subtest: bcs0
[88.591109] Dynamic subtest bcs0: SUCCESS (0.077s)
[88.611094] Starting dynamic subtest: vcs0
[88.660160] Dynamic subtest vcs0: SUCCESS (0.065s)
[88.685173] Starting dynamic subtest: vecs0
[88.722690] Dynamic subtest vecs0: SUCCESS (0.062s)
[88.728731] Subtest pi-distinct-iova: SUCCESS (0.279s)
[88.992184] [002/133] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[89.200961] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[89.205288] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[89.343815] [003/133] (959s left) prime_mmap (test_reprime)
[89.452701] Starting subtest: test_reprime
[89.455090] Starting dynamic subtest: test_reprime-smem
[89.463257] Dynamic subtest test_reprime-smem: SUCCESS (0.010s)
[89.464555] Subtest test_reprime: SUCCESS (0.010s)
[89.594555] [004/133] (959s left) i915_pm_rpm (gem-execbuf-stress)
[91.579192] Starting subtest: gem-execbuf-stress
[91.580669] Starting dynamic subtest: smem0
[94.255744] Dynamic subtest smem0: SUCCESS (2.674s)
[94.272395] Starting dynamic subtest: extra-wait-smem0
[146.956115] Dynamic subtest extra-wait-smem0: SUCCESS (52.698s)
[146.957265] Subtest gem-execbuf-stress: SUCCESS (55.375s)
[147.403830] [005/133] (901s left) kms_dp_link_training (uhbr-sst)
[147.582211] Starting subtest: uhbr-sst
[147.586334] Subtest uhbr-sst: SKIP (0.000s)
[147.701489] [006/133] (901s left) kms_vblank (invalid)
[147.889214] Starting subtest: invalid
[147.891086] Starting dynamic subtest: pipe-A-HDMI-A-1
[148.592240] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.701s)
[148.593288] Subtest invalid: SUCCESS (0.706s)
[148.945642] [007/133] (900s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[149.063329] Starting subtest: legacy-engines-hostile-preempt
[149.066140] Starting dynamic subtest: render
[149.150095] Dynamic subtest render: SUCCESS (0.084s)
[149.168019] Starting dynamic subtest: bsd
[149.236235] Dynamic subtest bsd: SUCCESS (0.085s)
[149.253923] Starting dynamic subtest: blt
[149.318457] Dynamic subtest blt: SUCCESS (0.081s)
[149.339118] Starting dynamic subtest: vebox
[149.403366] Dynamic subtest vebox: SUCCESS (0.081s)
[149.404733] Subtest legacy-engines-hostile-preempt: SUCCESS (0.342s)
[149.532156] [008/133] (899s left) sw_sync (sync_merge_same)
[149.634093] Starting subtest: sync_merge_same
[149.635666] Subtest sync_merge_same: SUCCESS (0.001s)
[149.746550] [009/133] (899s left) gem_wait (write-busy)
[149.881556] Starting subtest: write-busy
[149.883937] Starting dynamic subtest: all-engines
[150.485096] Dynamic subtest all-engines: SUCCESS (0.604s)
[150.509947] Starting dynamic subtest: rcs0
[151.002098] Dynamic subtest rcs0: SUCCESS (0.517s)
[151.026402] Starting dynamic subtest: bcs0
[151.522128] Dynamic subtest bcs0: SUCCESS (0.520s)
[151.543646] Starting dynamic subtest: vcs0
[152.042411] Dynamic subtest vcs0: SUCCESS (0.520s)
[152.061325] Starting dynamic subtest: vecs0
[152.564291] Dynamic subtest vecs0: SUCCESS (0.520s)
[152.568054] Subtest write-busy: SUCCESS (2.687s)
[152.837633] [FACT gem_wait (write-busy)] new: kernel.kmod_is_loaded.vgem: true
[152.841535] [010/133] (896s left) kms_flip (2x-wf_vblank-ts-check)
[153.022875] Starting subtest: 2x-wf_vblank-ts-check
[153.031091] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[164.315186] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.288s)
[164.335229] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[175.512879] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (11.199s)
[175.532869] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[186.694059] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (11.169s)
[186.699057] Subtest 2x-wf_vblank-ts-check: SUCCESS (33.669s)
[187.306487] [011/133] (862s left) kms_prop_blob (blob-prop-validate)
[187.411184] Starting subtest: blob-prop-validate
[187.412349] Subtest blob-prop-validate: SUCCESS (0.000s)
[187.552483] [012/133] (861s left) gem_exec_store (cachelines)
[187.677383] Starting subtest: cachelines
[187.678919] Starting dynamic subtest: rcs0
[187.708688] Dynamic subtest rcs0: SUCCESS (0.030s)
[187.726693] Starting dynamic subtest: bcs0
[187.737351] Dynamic subtest bcs0: SUCCESS (0.026s)
[187.754558] Starting dynamic subtest: vcs0
[187.757598] Dynamic subtest vcs0: SUCCESS (0.023s)
[187.784144] Starting dynamic subtest: vecs0
[187.786676] Dynamic subtest vecs0: SUCCESS (0.023s)
[187.787881] Subtest cachelines: SUCCESS (0.109s)
[188.051783] [013/133] (861s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[188.228323] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[188.231053] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[188.920794] [014/133] (860s left) kms_cursor_crc (cursor-offscreen-128x42)
[189.134055] Starting subtest: cursor-offscreen-128x42
[189.200075] Starting dynamic subtest: pipe-A-HDMI-A-1
[192.050965] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.844s)
[192.172675] Starting dynamic subtest: pipe-C-HDMI-A-1
[194.745200] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.584s)
[194.747070] Subtest cursor-offscreen-128x42: SUCCESS (5.608s)
[195.093221] [015/133] (854s left) kms_flip (2x-flip-vs-dpms-on-nop)
[195.287213] Starting subtest: 2x-flip-vs-dpms-on-nop
[195.289233] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[197.082419] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (1.791s)
[197.101737] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[198.780427] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (1.693s)
[198.800107] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[200.494342] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (1.709s)
[200.495340] Subtest 2x-flip-vs-dpms-on-nop: SUCCESS (5.211s)
[201.098201] [016/133] (848s left) gem_pread (self)
[201.208646] Starting subtest: self
[201.222107] Subtest self: SUCCESS (0.017s)
[201.362514] [017/133] (848s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[201.540160] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[201.549065] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[204.111092] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (2.566s)
[204.132995] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[206.169715] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (2.054s)
[206.190864] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[208.184231] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (2.010s)
[208.185096] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (6.647s)
[208.536113] [018/133] (841s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[208.723075] Starting subtest: cursorA-vs-flipA-atomic
[219.994096] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.267s)
[220.317077] [019/133] (829s left) gem_partial_pwrite_pread (reads-uncached)
[220.434555] Starting subtest: reads-uncached
[226.791625] Subtest reads-uncached: SUCCESS (6.361s)
[226.954241] [020/133] (823s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[227.145171] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.000s)
[227.257862] [021/133] (823s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[227.460253] Subtest psr2-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[227.576737] [022/133] (822s left) kms_psr (psr2-no-drrs)
[227.770105] Subtest psr2-no-drrs: SKIP (0.000s)
[227.875800] [023/133] (822s left) gem_pxp (protected-raw-src-copy-not-readible)
[230.523783] Starting subtest: protected-raw-src-copy-not-readible
[230.527151] Subtest protected-raw-src-copy-not-readible: SKIP (0.000s)
[230.651556] [024/133] (819s left) kms_atomic (plane-invalid-params)
[230.840202] Starting subtest: plane-invalid-params
[230.841288] Starting dynamic subtest: pipe-A-HDMI-A-1
[231.450630] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.608s)
[231.452368] Subtest plane-invalid-params: SUCCESS (0.614s)
[231.762341] [025/133] (818s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[231.974114] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[234.425806] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.453s)
[234.630440] [026/133] (816s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[234.827269] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[234.831724] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[234.963644] [027/133] (815s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[235.179213] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[235.181329] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[235.328426] [028/133] (815s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[235.527222] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[235.531204] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[235.679070] [029/133] (815s left) gem_exec_whisper (basic-sync)
[235.807380] Starting subtest: basic-sync
[241.040988] Subtest basic-sync: SUCCESS (5.235s)
[241.300433] [030/133] (809s left) kms_plane (plane-panning-bottom-right)
[241.484101] Starting subtest: plane-panning-bottom-right
[241.487145] Starting dynamic subtest: pipe-A
[242.736157] Dynamic subtest pipe-A: SUCCESS (1.249s)
[242.772374] Starting dynamic subtest: pipe-B
[243.643083] Dynamic subtest pipe-B: SUCCESS (0.899s)
[243.644263] Subtest plane-panning-bottom-right: SUCCESS (2.163s)
[243.946394] [031/133] (807s left) kms_cursor_edge_walk (64x64-right-edge)
[244.145104] Starting subtest: 64x64-right-edge
[244.150964] Starting dynamic subtest: pipe-A-HDMI-A-1
[248.108873] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (3.956s)
[248.152082] Starting dynamic subtest: pipe-C-HDMI-A-1
[251.557627] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.441s)
[251.559479] Subtest 64x64-right-edge: SUCCESS (7.414s)
[251.856309] [032/133] (799s left) api_intel_bb (misplaced-blitter)
[251.966373] Starting subtest: misplaced-blitter
[251.982291] Subtest misplaced-blitter: SUCCESS (0.016s)
[252.136947] [033/133] (799s left) kms_chamelium_audio (hdmi-audio)
[252.712286] Subtest hdmi-audio: SKIP (0.000s)
[253.019906] [034/133] (798s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[253.133202] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[253.134722] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.002s)
[253.269874] [035/133] (798s left) kms_content_protection (content-type-change)
[253.459315] Starting subtest: content-type-change
[253.479277] Subtest content-type-change: SKIP (0.016s)
[253.621851] [036/133] (798s left) gem_exec_parallel (fds)
[253.752955] Starting subtest: fds
[253.754144] Starting dynamic subtest: rcs0
[263.870527] Dynamic subtest rcs0: SUCCESS (10.121s)
[263.895184] Starting dynamic subtest: bcs0
[269.584906] Dynamic subtest bcs0: SUCCESS (5.712s)
[269.611294] Starting dynamic subtest: vcs0
[274.531295] Dynamic subtest vcs0: SUCCESS (4.940s)
[274.569246] Starting dynamic subtest: vecs0
[285.204955] Dynamic subtest vecs0: SUCCESS (10.673s)
[285.207259] Subtest fds: SUCCESS (31.459s)
[285.484574] [037/133] (766s left) gem_mmap_wc (close)
[285.586022] Starting subtest: close
[285.596855] Subtest close: SUCCESS (0.011s)
[285.741937] [038/133] (766s left) kms_frontbuffer_tracking (psr-2p-rte)
[285.949132] Starting subtest: psr-2p-rte
[285.951091] Subtest psr-2p-rte: SKIP (0.000s)
[286.105338] [039/133] (765s left) kms_pm_rpm (modeset-lpsp)
[288.040858] Starting subtest: modeset-lpsp
[288.248899] Subtest modeset-lpsp: SKIP (0.206s)
[288.662836] [040/133] (763s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[288.876353] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[291.353868] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (2.477s)
[291.630940] [041/133] (760s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[291.840814] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[291.845094] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[291.997458] [042/133] (760s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[292.584114] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[292.888138] [043/133] (759s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[293.095625] Starting subtest: fbc-rgb101010-draw-mmap-wc
[296.114583] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (3.022s)
[296.422597] [044/133] (756s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[296.530282] Starting subtest: etime-multi-wait-all-available-unsubmitted
[296.634364] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.105s)
[296.780174] [045/133] (755s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[296.885503] Subtest enable-vfs-bind-unbind-each-numvfs-all: SKIP (0.000s)
[296.997270] [046/133] (755s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[297.181089] Starting subtest: 2x-long-flip-vs-cursor-atomic
[302.832588] Subtest 2x-long-flip-vs-cursor-atomic: SUCCESS (5.649s)
[303.159103] [047/133] (749s left) gem_mmap_offset (isolation)
[303.267173] Starting subtest: isolation
[303.287267] Subtest isolation: SUCCESS (0.018s)
[303.439776] [048/133] (749s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[304.003845] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[304.304148] [049/133] (748s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[304.428943] Starting subtest: y-tiled-to-vebox-y-tiled
[304.430762] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.001s)
[304.688481] [050/133] (748s left) syncobj_timeline (wait-zero-handles)
[304.802205] Starting subtest: wait-zero-handles
[304.803290] Subtest wait-zero-handles: SUCCESS (0.000s)
[304.940906] [051/133] (748s left) gem_exec_schedule (submit-golden-slice)
[305.083125] Starting subtest: submit-golden-slice
[305.085187] Starting dynamic subtest: rcs0
[305.126642] Dynamic subtest rcs0: SUCCESS (0.043s)
[305.158587] Starting dynamic subtest: bcs0
[305.181184] Dynamic subtest bcs0: SUCCESS (0.048s)
[305.207047] Starting dynamic subtest: vcs0
[305.230655] Dynamic subtest vcs0: SUCCESS (0.047s)
[305.268421] Starting dynamic subtest: vecs0
[305.275820] Dynamic subtest vecs0: SUCCESS (0.038s)
[305.277257] Subtest submit-golden-slice: SUCCESS (0.193s)
[305.676283] [052/133] (747s left) gem_ctx_param (set-priority-range)
[305.783100] Starting subtest: set-priority-range
[305.836919] Subtest set-priority-range: SUCCESS (0.057s)
[305.991434] [053/133] (747s left) gem_ctx_sseu (invalid-args)
[306.101111] Subtest invalid-args: SKIP (0.000s)
[306.213914] [054/133] (747s left) gem_ctx_exec (basic-invalid-context)
[306.324972] Starting subtest: basic-invalid-context
[306.330485] Subtest basic-invalid-context: SUCCESS (0.010s)
[306.491577] [055/133] (747s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[306.681055] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[306.780762] [056/133] (746s left) gem_media_vme
[307.015406] [057/133] (746s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[307.202172] Starting subtest: psr-dirtyfb-ioctl
[307.210091] Subtest psr-dirtyfb-ioctl: SKIP (0.001s)
[307.338438] [058/133] (746s left) perf (non-sampling-read-error)
[307.555165] Starting subtest: non-sampling-read-error
[307.581200] Subtest non-sampling-read-error: SUCCESS (0.022s)
[307.673111] [059/133] (746s left) gem_ctx_param (invalid-size-get)
[307.778768] Starting subtest: invalid-size-get
[307.779846] Subtest invalid-size-get: SUCCESS (0.000s)
[307.953406] [060/133] (746s left) gem_exec_schedule (fifo)
[308.088809] Starting subtest: fifo
[308.092862] Starting dynamic subtest: rcs0
[308.208954] Dynamic subtest rcs0: SUCCESS (0.111s)
[308.242799] Starting dynamic subtest: bcs0
[308.334280] Dynamic subtest bcs0: SUCCESS (0.128s)
[308.368616] Starting dynamic subtest: vcs0
[308.457904] Dynamic subtest vcs0: SUCCESS (0.116s)
[308.487912] Starting dynamic subtest: vecs0
[308.581808] Dynamic subtest vecs0: SUCCESS (0.119s)
[308.590205] Subtest fifo: SUCCESS (0.491s)
[308.975684] [061/133] (745s left) prime_vgem (basic-write)
[309.104449] Starting subtest: basic-write
[309.226690] Subtest basic-write: SUCCESS (0.121s)
[309.374884] [062/133] (744s left) gem_ctx_isolation (dirty-create)
[309.505169] Starting subtest: dirty-create
[309.507995] Starting dynamic subtest: rcs0
[310.061507] Dynamic subtest rcs0: SUCCESS (0.552s)
[310.088853] Starting dynamic subtest: bcs0
[310.995179] Dynamic subtest bcs0: SUCCESS (0.931s)
[311.024401] Starting dynamic subtest: vcs0
[311.560089] Dynamic subtest vcs0: SUCCESS (0.560s)
[311.589678] Starting dynamic subtest: vecs0
[312.119328] Dynamic subtest vecs0: SUCCESS (0.556s)
[312.121149] Subtest dirty-create: SUCCESS (2.613s)
[312.375397] [063/133] (741s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[312.572075] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[315.828510] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.255s)
[316.117765] [064/133] (738s left) kms_fbcon_fbt (fbc-suspend)
[316.232122] Starting subtest: fbc-suspend