[88.002903] Initializing watchdogs
[88.003967]   /dev/watchdog0
[88.043863] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.058645] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.065964] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.074444] [001/133] (960s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[88.361973] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[88.641505] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.272s)
[88.914518] [002/133] (959s left) gem_mmap_wc (read-write-distinct)
[89.026052] Starting subtest: read-write-distinct
[89.029382] Subtest read-write-distinct: SUCCESS (0.010s)
[89.196336] [003/133] (959s left) api_intel_bb (blit-noreloc-purge-cache)
[89.300984] Starting subtest: blit-noreloc-purge-cache
[89.319487] Subtest blit-noreloc-purge-cache: SUCCESS (0.021s)
[89.476565] [004/133] (959s left) perf_pmu (interrupts)
[89.592520] Starting subtest: interrupts
[90.773073] Subtest interrupts: SUCCESS (1.178s)
[90.947322] [FACT perf_pmu (interrupts)] new: kernel.kmod_is_loaded.vgem: true
[90.953777] [005/133] (957s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[91.159728] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[91.166389] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[91.340492] [006/133] (957s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[91.541366] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[91.547482] Subtest fbcpsr-rgb565-draw-mmap-wc: SKIP (0.000s)
[91.721418] [007/133] (957s left) sysfs_heartbeat_interval (precise)
[91.850780] Starting subtest: precise
[91.854717] Starting dynamic subtest: vecs0
[93.307195] Dynamic subtest vecs0: SUCCESS (1.460s)
[93.331288] Starting dynamic subtest: vcs0
[94.783060] Dynamic subtest vcs0: SUCCESS (1.472s)
[94.806404] Starting dynamic subtest: bcs0
[96.260609] Dynamic subtest bcs0: SUCCESS (1.478s)
[96.282998] Starting dynamic subtest: rcs0
[97.719581] Dynamic subtest rcs0: SUCCESS (1.457s)
[97.723527] Subtest precise: SUCCESS (5.873s)
[97.879266] [008/133] (951s left) kms_force_connector_basic (force-edid)
[98.134314] Starting subtest: force-edid
[98.296632] Subtest force-edid: SUCCESS (0.157s)
[98.640990] [009/133] (950s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[98.840850] Starting subtest: cursorB-vs-flipB-legacy
[110.187393] Subtest cursorB-vs-flipB-legacy: SUCCESS (11.346s)
[110.545664] [010/133] (938s left) kms_cursor_edge_walk (256x256-top-edge)
[110.740505] Starting subtest: 256x256-top-edge
[110.743685] Starting dynamic subtest: pipe-A-HDMI-A-1
[114.641452] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (3.903s)
[114.667481] Starting dynamic subtest: pipe-C-HDMI-A-1
[118.132107] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.485s)
[118.186196] Subtest 256x256-top-edge: SUCCESS (7.396s)
[118.475850] [011/133] (931s left) device_reset (unbind-cold-reset-rebind)
[118.620493] Starting subtest: unbind-cold-reset-rebind
[118.629035] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[118.800975] [012/133] (930s left) syncobj_wait (single-wait-for-submit-signaled)
[118.949573] Starting subtest: single-wait-for-submit-signaled
[118.953550] Subtest single-wait-for-submit-signaled: SUCCESS (0.001s)
[119.110464] [013/133] (930s left) gem_caching (read-writes)
[119.226050] Starting subtest: read-writes
[141.695017] Subtest read-writes: SUCCESS (22.469s)
[141.910803] [014/133] (907s left) gem_ctx_engines (execute-oneforall)
[142.045308] Starting subtest: execute-oneforall
[142.651256] Subtest execute-oneforall: SUCCESS (0.572s)
[143.245937] [015/133] (907s left) gem_render_copy (y-tiled-ccs-to-linear)
[143.605711] Starting subtest: y-tiled-ccs-to-linear
[143.608769] Starting dynamic subtest: smem
[143.705222] Dynamic subtest smem: SUCCESS (0.198s)
[143.710574] Subtest y-tiled-ccs-to-linear: SUCCESS (0.198s)
[143.997891] [016/133] (906s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[144.199918] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[144.206485] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[144.374794] [017/133] (906s left) kms_psr (fbc-pr-cursor-plane-onoff)
[144.570205] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[144.688756] [018/133] (905s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[144.893567] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[147.384946] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (2.492s)
[147.673350] [019/133] (903s left) gem_ctx_exec (basic-nohangcheck)
[147.781766] Starting subtest: basic-nohangcheck
[148.056606] Subtest basic-nohangcheck: SUCCESS (0.277s)
[148.274622] [020/133] (902s left) i915_selftest (live)
[150.347436] Starting subtest: live
[150.414186] Starting dynamic subtest: sanitycheck
[153.054159] Dynamic subtest sanitycheck: SUCCESS (2.802s)
[153.074782] Starting dynamic subtest: uncore
[155.625565] Dynamic subtest uncore: SUCCESS (2.582s)
[155.648848] Starting dynamic subtest: workarounds
[158.441853] Dynamic subtest workarounds: SUCCESS (2.801s)
[158.463753] Starting dynamic subtest: gt_engines
[161.444784] Dynamic subtest gt_engines: SUCCESS (3.013s)
[161.468895] Starting dynamic subtest: gt_timelines
[177.543042] Dynamic subtest gt_timelines: SUCCESS (16.096s)
[177.564844] Starting dynamic subtest: gt_contexts
[180.565401] Dynamic subtest gt_contexts: SUCCESS (3.019s)
[180.587480] Starting dynamic subtest: gt_lrc
[186.012727] Dynamic subtest gt_lrc: SUCCESS (5.445s)
[186.035783] Starting dynamic subtest: gt_mocs
[188.797860] Dynamic subtest gt_mocs: SUCCESS (2.779s)
[188.819509] Starting dynamic subtest: gt_pm
[198.627301] Dynamic subtest gt_pm: SUCCESS (9.832s)
[198.650241] Starting dynamic subtest: gt_heartbeat
[201.339723] Dynamic subtest gt_heartbeat: SUCCESS (2.709s)
[201.362080] Starting dynamic subtest: gt_tlb
[205.391591] Dynamic subtest gt_tlb: SUCCESS (4.051s)
[205.413877] Starting dynamic subtest: requests
[214.390539] Dynamic subtest requests: SUCCESS (8.997s)
[214.412866] Starting dynamic subtest: migrate
[219.977673] Dynamic subtest migrate: SUCCESS (5.584s)
[220.000902] Starting dynamic subtest: active
[222.927869] Dynamic subtest active: SUCCESS (2.948s)
[222.949729] Starting dynamic subtest: objects
[227.721813] Dynamic subtest objects: SUCCESS (4.791s)
[227.744100] Starting dynamic subtest: mman
[236.183224] Dynamic subtest mman: SUCCESS (8.460s)
[236.205635] Starting dynamic subtest: dmabuf
[238.968186] Dynamic subtest dmabuf: SUCCESS (2.783s)
[239.008429] Starting dynamic subtest: vma
[241.595537] Dynamic subtest vma: SUCCESS (2.626s)
[241.619650] Starting dynamic subtest: coherency
[247.276346] Dynamic subtest coherency: SUCCESS (5.637s)
[247.342088] Starting dynamic subtest: gtt
[257.580851] Dynamic subtest gtt: SUCCESS (10.343s)
[257.605460] Starting dynamic subtest: gem
[260.766611] Dynamic subtest gem: SUCCESS (3.183s)
[260.791173] Starting dynamic subtest: evict
[266.351349] Dynamic subtest evict: SUCCESS (5.582s)
[266.373573] Starting dynamic subtest: hugepages
[275.588374] Dynamic subtest hugepages: SUCCESS (9.232s)
[275.610880] Starting dynamic subtest: gem_contexts
[296.260736] Dynamic subtest gem_contexts: SUCCESS (20.671s)
[296.284414] Starting dynamic subtest: client
[298.992509] Dynamic subtest client: SUCCESS (2.728s)
[299.017740] Starting dynamic subtest: gem_migrate
[301.917591] Dynamic subtest gem_migrate: SUCCESS (2.922s)
[301.941528] Starting dynamic subtest: reset
[308.681476] Dynamic subtest reset: SUCCESS (6.761s)
[308.703878] Starting dynamic subtest: memory_region
[311.333042] Dynamic subtest memory_region: SUCCESS (2.649s)
[311.356415] Starting dynamic subtest: hangcheck
[337.185485] Dynamic subtest hangcheck: SUCCESS (25.850s)
[337.207782] Starting dynamic subtest: execlists
[349.426600] Dynamic subtest execlists: SUCCESS (12.238s)
[349.449143] Starting dynamic subtest: ring_submission
[352.317579] Dynamic subtest ring_submission: SUCCESS (2.889s)
[352.339621] Starting dynamic subtest: perf
[355.283523] Dynamic subtest perf: SUCCESS (2.963s)
[355.312106] Starting dynamic subtest: slpc
[357.972529] Dynamic subtest slpc: SUCCESS (2.685s)
[357.997468] Starting dynamic subtest: guc
[360.639100] Dynamic subtest guc: SUCCESS (2.663s)
[360.660701] Starting dynamic subtest: guc_multi_lrc
[363.509400] Dynamic subtest guc_multi_lrc: SUCCESS (2.866s)
[363.532992] Starting dynamic subtest: guc_hang
[366.145548] Dynamic subtest guc_hang: SUCCESS (2.634s)
[366.167633] Starting dynamic subtest: late_gt_pm
[369.152677] Dynamic subtest late_gt_pm: SUCCESS (3.003s)
[369.156071] Subtest live: SUCCESS (218.919s)
[369.230076] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[369.232917] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[369.240383] [021/133] (681s left) gem_userptr_blits (sync-unmap-after-close)
[372.309519] Starting subtest: sync-unmap-after-close
[372.502750] Subtest sync-unmap-after-close: SUCCESS (0.147s)
[372.973324] [FACT gem_userptr_blits (sync-unmap-after-close)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[372.976427] [FACT gem_userptr_blits (sync-unmap-after-close)] new: kernel.kmod_is_loaded.i915: true
[373.020529] [022/133] (678s left) gem_exec_suspend (basic-s3-devices)
[373.485543] Starting subtest: basic-S3-devices
[373.550518] Starting dynamic subtest: smem
[383.491239] Dynamic subtest smem: SUCCESS (10.135s)
[383.495615] Subtest basic-S3-devices: SUCCESS (10.140s)
[388.788976] [023/133] (667s left) prime_vgem (fence-wait)
[388.936605] Starting subtest: fence-wait
[388.939787] Starting dynamic subtest: rcs0
[389.963158] Dynamic subtest rcs0: SUCCESS (1.025s)
[389.989224] Starting dynamic subtest: vcs0
[390.984135] Dynamic subtest vcs0: SUCCESS (1.020s)
[391.011855] Starting dynamic subtest: vecs0
[392.006051] Dynamic subtest vecs0: SUCCESS (1.019s)
[392.009313] Subtest fence-wait: SUCCESS (3.073s)
[392.178880] [024/133] (664s left) gem_ppgtt (blt-vs-render-ctxn)
[392.292065] Starting subtest: blt-vs-render-ctxN
[425.288657] Subtest blt-vs-render-ctxN: SUCCESS (32.995s)
[425.474888] [025/133] (631s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[425.763129] Starting subtest: basic-wc-gtt-noreloc
[425.773448] Subtest basic-wc-gtt-noreloc: SUCCESS (0.012s)
[426.062576] [026/133] (630s left) kms_sequence (queue-busy)
[426.248735] Starting subtest: queue-busy
[426.253068] Starting dynamic subtest: pipe-A-HDMI-A-1
[429.206602] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.949s)
[429.265329] Starting dynamic subtest: pipe-A-HDMI-A-2
[431.828648] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.615s)
[431.871993] Starting dynamic subtest: pipe-B-HDMI-A-1
[434.445245] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.611s)
[434.492420] Starting dynamic subtest: pipe-B-HDMI-A-2
[437.048356] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.595s)
[437.092463] Starting dynamic subtest: pipe-C-HDMI-A-1
[439.652632] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.600s)
[439.695373] Starting dynamic subtest: pipe-C-HDMI-A-2
[442.256618] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.600s)
[442.260821] Subtest queue-busy: SUCCESS (16.007s)
[442.594885] [027/133] (614s left) i915_pm_rps (engine-order)
[442.725357] Starting subtest: engine-order
[456.504488] Subtest engine-order: FAIL (13.697s)
[456.615111] [028/133] (600s left) kms_psr (pr-cursor-plane-onoff)
[456.802715] Subtest pr-cursor-plane-onoff: SKIP (0.000s)
[456.925120] [029/133] (600s left) perf_pmu (busy-accuracy-50)
[457.044170] Starting subtest: busy-accuracy-50
[457.047604] Starting dynamic subtest: rcs0
[458.601846] Dynamic subtest rcs0: SUCCESS (1.552s)
[458.627470] Starting dynamic subtest: bcs0
[460.160217] Dynamic subtest bcs0: SUCCESS (1.555s)
[460.189847] Starting dynamic subtest: vcs0
[461.716191] Dynamic subtest vcs0: SUCCESS (1.552s)
[461.741709] Starting dynamic subtest: vecs0
[463.268277] Dynamic subtest vecs0: SUCCESS (1.552s)
[463.271808] Subtest busy-accuracy-50: SUCCESS (6.223s)
[463.480454] [030/133] (593s left) gem_exec_store (pages)
[463.607821] Starting subtest: pages
[463.613662] Starting dynamic subtest: rcs0
[463.707100] Dynamic subtest rcs0: SUCCESS (0.097s)
[463.732112] Starting dynamic subtest: bcs0
[463.811849] Dynamic subtest bcs0: SUCCESS (0.103s)
[463.836113] Starting dynamic subtest: vcs0
[463.912612] Dynamic subtest vcs0: SUCCESS (0.096s)
[463.941550] Starting dynamic subtest: vecs0
[464.010053] Dynamic subtest vecs0: SUCCESS (0.096s)
[464.013512] Subtest pages: SUCCESS (0.406s)
[464.323472] [031/133] (593s left) kms_big_fb (x-tiled-addfb-size-offset-overflow)
[464.532809] Starting subtest: x-tiled-addfb-size-offset-overflow
[464.536143] Subtest x-tiled-addfb-size-offset-overflow: SUCCESS (0.001s)
[464.725521] [032/133] (592s left) syncobj_timeline (multi-wait-all-signaled)
[464.862252] Starting subtest: multi-wait-all-signaled
[464.865541] Subtest multi-wait-all-signaled: SUCCESS (0.003s)
[465.057301] [033/133] (592s left) gem_ctx_bad_destroy (invalid-pad)
[465.167610] Starting subtest: invalid-pad
[465.171558] Subtest invalid-pad: SUCCESS (0.000s)
[465.341893] [034/133] (592s left) kms_getfb (getfb-handle-closed)
[465.539770] Starting subtest: getfb-handle-closed
[465.543455] Subtest getfb-handle-closed: SUCCESS (0.000s)
[465.707482] [035/133] (592s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[466.283598] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[466.573366] [036/133] (591s left) perf (missing-sample-flags)
[466.856024] Starting subtest: missing-sample-flags
[466.859325] Subtest missing-sample-flags: SUCCESS (0.002s)
[466.965124] [037/133] (591s left) i915_hangman (engine-engine-error)
[467.104654] Starting subtest: engine-engine-error
[467.107937] Starting dynamic subtest: rcs0
[467.178433] Dynamic subtest rcs0: SUCCESS (0.075s)
[467.205088] Starting dynamic subtest: bcs0
[467.212348] Dynamic subtest bcs0: SKIP (0.002s)
[467.239892] Starting dynamic subtest: vcs0
[467.259922] Dynamic subtest vcs0: SUCCESS (0.070s)
[467.284417] Starting dynamic subtest: vecs0
[467.334429] Dynamic subtest vecs0: SUCCESS (0.071s)
[467.337961] Subtest engine-engine-error: SUCCESS (0.233s)
[467.520167] [038/133] (590s left) syncobj_basic (bad-pad-handle-to-fd)
[467.631870] Starting subtest: bad-pad-handle-to-fd
[467.636091] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[467.815268] [039/133] (590s left) syncobj_timeline (wait-all-for-submit-snapshot)
[467.921158] Starting subtest: wait-all-for-submit-snapshot
[468.009967] Subtest wait-all-for-submit-snapshot: SUCCESS (0.087s)
[468.188211] [040/133] (590s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[468.385126] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[468.509513] [041/133] (589s left) kms_vblank (ts-continuation-suspend)
[468.708685] Starting subtest: ts-continuation-suspend
[468.712072] Starting dynamic subtest: pipe-A-HDMI-A-1