[61.516565] Initializing watchdogs
[61.516617]   /dev/watchdog0
[61.532273] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[61.536997] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.539598] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.541995] [001/131] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[61.653914] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[61.655902] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[61.740875] [002/131] (960s left) gem_exec_reloc (basic-wc-read-noreloc)
[61.796369] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[61.859057] [003/131] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[61.950365] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[61.952212] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[62.061517] [004/131] (960s left) syncobj_wait (wait-any-interrupted)
[62.110087] Starting subtest: wait-any-interrupted
[62.217528] Subtest wait-any-interrupted: SUCCESS (0.105s)
[62.284648] [005/131] (959s left) gen9_exec_parse (batch-zero-length)
[62.333168] Subtest batch-zero-length: SKIP (0.000s)
[62.396904] [006/131] (959s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[62.489480] Starting subtest: yf-tiled-16bpp-rotate-180
[62.491445] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[62.575830] [007/131] (959s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[62.680181] Starting subtest: 4-tiled-8bpp-rotate-180
[63.959018] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.271s)
[64.084903] [008/131] (958s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[64.164327] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[64.165286] Starting dynamic subtest: pipe-A-eDP-1
[98.231745] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.799s)
[98.240335] Starting dynamic subtest: pipe-B-eDP-1
[133.151887] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.459s)
[133.160353] Starting dynamic subtest: pipe-C-eDP-1
[168.130218] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.483s)
[168.138129] Starting dynamic subtest: pipe-D-eDP-1
[203.110667] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.403s)
[203.111840] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (17.148s)
[210.324458] [009/131] (818s left) kms_flip (2x-plain-flip-interruptible)
[210.420551] Starting subtest: 2x-plain-flip-interruptible
[210.433749] Subtest 2x-plain-flip-interruptible: SKIP (0.011s)
[210.503065] [010/131] (817s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[210.594738] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[210.597231] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.000s)
[210.661791] [011/131] (817s left) kms_pipe_stress (stress-xrgb8888-untiled)
[210.745139] Starting subtest: stress-xrgb8888-untiled
[216.858862] Subtest stress-xrgb8888-untiled: SUCCESS (5.995s)
[216.901076] [012/131] (811s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[216.984558] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[216.985447] Starting dynamic subtest: pipe-A-eDP-1
[216.994556] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[217.003542] Starting dynamic subtest: pipe-B-eDP-1
[217.012196] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[217.019868] Starting dynamic subtest: pipe-C-eDP-1
[217.027963] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[217.035206] Starting dynamic subtest: pipe-D-eDP-1
[217.044997] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[217.046653] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[219.218355] [013/131] (809s left) kms_getfb (getfb2-handle-not-fb)
[219.312443] Starting subtest: getfb2-handle-not-fb
[219.313487] Subtest getfb2-handle-not-fb: SUCCESS (0.000s)
[219.402574] [014/131] (809s left) gem_ctx_create (basic-files)
[219.459830] Starting subtest: basic-files
[221.467615] Subtest basic-files: SUCCESS (1.991s)
[223.127168] [015/131] (805s left) kms_cursor_legacy (single-move)
[223.241739] Starting subtest: single-move
[223.242800] Starting dynamic subtest: pipe-A
[228.692730] Dynamic subtest pipe-A: SUCCESS (5.379s)
[228.703853] Starting dynamic subtest: pipe-B
[234.086277] Dynamic subtest pipe-B: SUCCESS (5.379s)
[234.096453] Starting dynamic subtest: pipe-C
[239.472196] Dynamic subtest pipe-C: SUCCESS (5.379s)
[239.482325] Starting dynamic subtest: pipe-D
[244.856256] Dynamic subtest pipe-D: SUCCESS (5.379s)
[244.866273] Starting dynamic subtest: all-pipes
[250.246887] Dynamic subtest all-pipes: SUCCESS (5.386s)
[250.248122] Subtest single-move: SUCCESS (26.912s)
[250.352529] [016/131] (778s left) kms_vblank (query-forked-busy)
[250.448629] Starting subtest: query-forked-busy
[250.449473] Starting dynamic subtest: pipe-A-eDP-1
[252.327977] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.876s)
[252.337942] Starting dynamic subtest: pipe-D-eDP-1
[254.872570] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.542s)
[254.873389] Subtest query-forked-busy: SUCCESS (4.422s)
[255.669427] [017/131] (772s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[255.756561] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[255.784185] Starting dynamic subtest: pipe-A-plane-0
[256.073144] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[256.082390] Starting dynamic subtest: pipe-A-plane-3
[256.306442] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[257.368541] Starting dynamic subtest: pipe-B-plane-0
[257.661351] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[257.669551] Starting dynamic subtest: pipe-B-plane-3
[257.894630] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[258.216854] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.459s)
[259.041119] [018/131] (769s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[259.422020] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[260.243623] [019/131] (768s left) kms_chamelium_color (ctm-max)
[260.333489] Subtest ctm-max: SKIP (0.000s)
[260.373470] [020/131] (768s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[260.474519] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[267.633986] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.164s)
[267.752608] [021/131] (760s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[267.855556] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[267.857294] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[267.949576] [022/131] (760s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[268.044672] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[268.046354] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[268.147244] [023/131] (760s left) gem_wait (write-busy)
[268.209822] Starting subtest: write-busy
[268.210869] Starting dynamic subtest: all-engines
[268.855240] Dynamic subtest all-engines: SUCCESS (0.644s)
[268.871330] Starting dynamic subtest: rcs0
[269.404174] Dynamic subtest rcs0: SUCCESS (0.547s)
[269.419296] Starting dynamic subtest: bcs0
[269.951708] Dynamic subtest bcs0: SUCCESS (0.547s)
[269.966283] Starting dynamic subtest: vcs0
[271.098899] Dynamic subtest vcs0: SUCCESS (1.133s)
[271.107132] Starting dynamic subtest: vcs1
[273.083259] Dynamic subtest vcs1: SUCCESS (1.980s)
[273.091213] Starting dynamic subtest: vecs0
[275.065726] Dynamic subtest vecs0: SUCCESS (1.980s)
[275.075177] Starting dynamic subtest: ccs0
[275.603897] Dynamic subtest ccs0: SUCCESS (0.547s)
[275.605107] Subtest write-busy: SUCCESS (7.390s)
[275.795663] [FACT gem_wait (write-busy)] new: kernel.kmod_is_loaded.vgem: true
[275.798096] [024/131] (753s left) gem_mmap_gtt (hang-user)
[275.850602] Subtest hang-user: SKIP (0.000s)
[275.905487] [025/131] (752s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[275.997479] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[275.998621] Starting dynamic subtest: pipe-A-eDP-1
[276.007384] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[276.015613] Starting dynamic subtest: pipe-B-eDP-1
[276.026095] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[276.034362] Starting dynamic subtest: pipe-C-eDP-1
[276.043266] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[276.051091] Starting dynamic subtest: pipe-D-eDP-1
[276.059996] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[276.061741] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[278.283482] [026/131] (750s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[278.367905] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[278.369716] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[278.435324] [027/131] (750s left) kms_universal_plane (universal-plane-pageflip-windowed)
[278.526884] Starting subtest: universal-plane-pageflip-windowed
[278.527912] Starting dynamic subtest: pipe-A-eDP-1
[279.116476] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.588s)
[279.125466] Starting dynamic subtest: pipe-B-eDP-1
[280.327985] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.210s)
[280.337939] Starting dynamic subtest: pipe-C-eDP-1
[281.545262] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.215s)
[281.553753] Starting dynamic subtest: pipe-D-eDP-1
[282.768849] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.221s)
[282.769929] Subtest universal-plane-pageflip-windowed: SUCCESS (4.240s)
[283.615078] [028/131] (745s left) gem_ctx_isolation (preservation-s3)
[283.663559] Starting subtest: preservation-S3
[283.664481] Starting dynamic subtest: rcs0
[299.998359] Dynamic subtest rcs0: SUCCESS (1.708s)
[300.007855] Starting dynamic subtest: bcs0
[317.006737] Dynamic subtest bcs0: SUCCESS (1.723s)
[317.015889] Starting dynamic subtest: vcs0
[334.010360] Dynamic subtest vcs0: SUCCESS (3.468s)
[334.019367] Starting dynamic subtest: vcs1
[351.999690] Dynamic subtest vcs1: SUCCESS (4.978s)
[352.009573] Starting dynamic subtest: vecs0
[370.004403] Dynamic subtest vecs0: SUCCESS (4.960s)
[370.014656] Starting dynamic subtest: ccs0
[387.996623] Dynamic subtest ccs0: SUCCESS (3.219s)
[387.997750] Subtest preservation-S3: SUCCESS (20.063s)
[394.231960] [029/131] (640s left) kms_fence_pin_leak
[394.385309] [030/131] (640s left) gem_exec_endless (dispatch)
[394.455591] Starting subtest: dispatch
[394.456472] Starting dynamic subtest: rcs0
[394.461234] Dynamic subtest rcs0: SUCCESS (0.007s)
[394.469802] Starting dynamic subtest: bcs0
[394.471480] Dynamic subtest bcs0: SUCCESS (0.005s)
[394.480457] Starting dynamic subtest: vcs0
[394.482499] Dynamic subtest vcs0: SUCCESS (0.004s)
[394.491788] Starting dynamic subtest: vcs1
[394.493404] Dynamic subtest vcs1: SUCCESS (0.004s)
[394.502968] Starting dynamic subtest: vecs0
[394.504724] Dynamic subtest vecs0: SUCCESS (0.006s)
[394.512338] Starting dynamic subtest: ccs0
[394.514488] Dynamic subtest ccs0: SUCCESS (0.005s)
[394.515430] Subtest dispatch: SUCCESS (0.036s)
[396.344456] [031/131] (638s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[396.445334] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[396.447426] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[396.563177] [032/131] (638s left) kms_psr (fbc-psr2-cursor-blt)
[396.645951] Starting subtest: fbc-psr2-cursor-blt
[396.646936] Starting dynamic subtest: eDP-1
[396.648760] Dynamic subtest eDP-1: SKIP (0.000s)
[396.650526] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[396.716152] [033/131] (638s left) gem_exec_whisper (basic-sync-all)
[396.776001] Starting subtest: basic-sync-all
[403.464910] Subtest basic-sync-all: SUCCESS (6.574s)
[403.642749] [034/131] (631s left) kms_feature_discovery (display)
[403.739521] Starting subtest: display
[403.740384] Subtest display: SUCCESS (0.000s)
[403.812519] [035/131] (631s left) kms_rotation_crc (bad-tiling)
[403.895179] Starting subtest: bad-tiling
[403.896993] Subtest bad-tiling: SKIP (0.001s)
[403.990284] [036/131] (631s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[404.080213] Starting subtest: yf-tiled-addfb-size-offset-overflow
[404.082170] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[404.176990] [037/131] (631s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[404.263079] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[404.264787] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[404.355865] [038/131] (630s left) kms_chamelium_edid (vga-edid-read)
[404.727154] Subtest vga-edid-read: SKIP (0.000s)
[405.517752] [039/131] (629s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[405.611045] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[412.805063] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.155s)
[412.901489] [040/131] (622s left) syncobj_timeline (wait-all-for-submit-snapshot)
[412.949683] Starting subtest: wait-all-for-submit-snapshot
[413.031460] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[413.105507] [041/131] (622s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[413.199090] Starting subtest: 4-tiled-16bpp-rotate-270
[413.219574] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.020s)
[413.332661] [042/131] (622s left) kms_plane_scaling (planes-downscale-factor-0-75)
[413.410845] Starting subtest: planes-downscale-factor-0-75
[413.411748] Starting dynamic subtest: pipe-A
[413.431531] Dynamic subtest pipe-A: SKIP (0.019s)
[413.440232] Starting dynamic subtest: pipe-B
[413.466961] Dynamic subtest pipe-B: SKIP (0.035s)
[413.475362] Starting dynamic subtest: pipe-C
[413.503063] Dynamic subtest pipe-C: SKIP (0.035s)
[413.511232] Starting dynamic subtest: pipe-D
[413.538360] Dynamic subtest pipe-D: SKIP (0.035s)
[413.540180] Subtest planes-downscale-factor-0-75: SKIP (0.127s)
[413.634615] [043/131] (621s left) kms_cursor_crc (cursor-onscreen-128x128)
[413.831802] Starting subtest: cursor-onscreen-128x128
[413.835755] Starting dynamic subtest: pipe-A-eDP-1
[415.986183] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.144s)
[416.017684] Starting dynamic subtest: pipe-D-eDP-1
[418.818626] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.802s)
[418.819573] Subtest cursor-onscreen-128x128: SUCCESS (4.974s)
[419.704258] [044/131] (615s left) i915_selftest (live)
[423.017393] Starting subtest: live
[423.018952] Starting dynamic subtest: sanitycheck
[427.447174] Dynamic subtest sanitycheck: SUCCESS (4.425s)
[427.456671] Starting dynamic subtest: uncore
[431.861495] Dynamic subtest uncore: SUCCESS (4.414s)
[431.870847] Starting dynamic subtest: workarounds
[448.294129] Dynamic subtest workarounds: FAIL (16.386s)
[448.302039] Starting dynamic subtest: gt_engines
[452.886156] Dynamic subtest gt_engines: SUCCESS (4.630s)
[452.893952] Starting dynamic subtest: gt_timelines
[469.508300] Dynamic subtest gt_timelines: SUCCESS (16.614s)
[469.516284] Starting dynamic subtest: gt_contexts
[473.879080] Dynamic subtest gt_contexts: SUCCESS (4.367s)
[473.889029] Starting dynamic subtest: gt_lrc
[480.045806] Dynamic subtest gt_lrc: SUCCESS (6.163s)
[480.055438] Starting dynamic subtest: gt_mocs
[484.537424] Dynamic subtest gt_mocs: SUCCESS (4.488s)
[484.547120] Starting dynamic subtest: gt_pm
[491.357249] Dynamic subtest gt_pm: SUCCESS (6.816s)
[491.366731] Starting dynamic subtest: gt_heartbeat
[495.716508] Dynamic subtest gt_heartbeat: SUCCESS (4.359s)
[495.725693] Starting dynamic subtest: gt_tlb
[505.739492] Dynamic subtest gt_tlb: SUCCESS (10.024s)
[505.748982] Starting dynamic subtest: requests
[519.321281] Dynamic subtest requests: SUCCESS (13.581s)
[519.330752] Starting dynamic subtest: migrate
[527.067101] Dynamic subtest migrate: SUCCESS (7.743s)
[527.076844] Starting dynamic subtest: active
[531.410003] Dynamic subtest active: SUCCESS (4.341s)
[531.417696] Starting dynamic subtest: objects
[536.196791] Dynamic subtest objects: SUCCESS (4.783s)
[536.205502] Starting dynamic subtest: mman
[541.093595] Dynamic subtest mman: SUCCESS (4.894s)
[541.102910] Starting dynamic subtest: dmabuf
[545.460682] Dynamic subtest dmabuf: SUCCESS (4.365s)
[545.469964] Starting dynamic subtest: vma
[549.839394] Dynamic subtest vma: SUCCESS (4.376s)
[549.848747] Starting dynamic subtest: coherency
[555.413164] Dynamic subtest coherency: SUCCESS (5.570s)
[555.423455] Starting dynamic subtest: gtt
[567.475016] Dynamic subtest gtt: SUCCESS (12.058s)
[567.485139] Starting dynamic subtest: gem
[575.021031] Dynamic subtest gem: SUCCESS (7.542s)
[575.029721] Starting dynamic subtest: evict
[579.038863] Dynamic subtest evict: SUCCESS (4.014s)
[579.048283] Starting dynamic subtest: hugepages
[588.609294] Dynamic subtest hugepages: SUCCESS (9.566s)
[588.618810] Starting dynamic subtest: gem_contexts
[615.854955] Dynamic subtest gem_contexts: SUCCESS (27.237s)
[615.864885] Starting dynamic subtest: client
[618.721326] Dynamic subtest client: SUCCESS (2.865s)
[618.730029] Starting dynamic subtest: gem_migrate
[623.106712] Dynamic subtest gem_migrate: SUCCESS (4.384s)
[623.115367] Starting dynamic subtest: reset
[627.559863] Dynamic subtest reset: SUCCESS (4.452s)
[627.569358] Starting dynamic subtest: memory_region
[631.986200] Dynamic subtest memory_region: SUCCESS (4.426s)
[631.995983] Starting dynamic subtest: hangcheck
[646.303413] Dynamic subtest hangcheck: SUCCESS (14.316s)
[646.313685] Starting dynamic subtest: execlists
[650.652570] Dynamic subtest execlists: SUCCESS (4.349s)
[650.660293] Starting dynamic subtest: ring_submission
[655.007673] Dynamic subtest ring_submission: SUCCESS (4.354s)
[655.015985] Starting dynamic subtest: perf
[657.915506] Dynamic subtest perf: SUCCESS (2.905s)
[657.924917] Starting dynamic subtest: slpc
[672.538466] Dynamic subtest slpc: SUCCESS (14.628s)
[672.545721] Starting dynamic subtest: guc
[676.541889] Dynamic subtest guc: SUCCESS (3.995s)
[676.549557] Starting dynamic subtest: guc_multi_lrc
[680.894948] Dynamic subtest guc_multi_lrc: SUCCESS (4.351s)
[680.903443] Starting dynamic subtest: guc_hang
[685.827816] Dynamic subtest guc_hang: SUCCESS (4.930s)
[685.837329] Starting dynamic subtest: late_gt_pm
[690.244002] Dynamic subtest late_gt_pm: SUCCESS (4.414s)
[690.245391] Subtest live: FAIL (267.194s)
[690.270541] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[690.271436] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[690.273299] [045/131] (345s left) gem_exec_schedule (preempt-queue)
[691.837171] Subtest preempt-queue: SKIP (0.000s)
[692.102394] [FACT gem_exec_schedule (preempt-queue)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[692.103924] [FACT gem_exec_schedule (preempt-queue)] new: kernel.kmod_is_loaded.i915: true
[692.106821] [046/131] (343s left) kms_lease (lease-get)
[692.202363] Starting subtest: lease-get
[692.205100] Starting dynamic subtest: pipe-A-eDP-1
[692.207403] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[692.218278] Starting dynamic subtest: pipe-B-eDP-1
[692.219392] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[692.227901] Starting dynamic subtest: pipe-C-eDP-1
[692.228730] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[692.239560] Starting dynamic subtest: pipe-D-eDP-1
[692.240573] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[692.241406] Subtest lease-get: SUCCESS (0.015s)
[692.325646] [047/131] (343s left) i915_pm_rc6_residency (media-rc6-accuracy)
[692.381758] Starting subtest: media-rc6-accuracy
[692.384145] Subtest media-rc6-accuracy: SKIP (0.000s)
[692.503166] [048/131] (343s left) kms_psr (psr-cursor-plane-move)
[692.592361] Starting subtest: psr-cursor-plane-move
[692.593231] Starting dynamic subtest: eDP-1
[694.565571] Dynamic subtest eDP-1: SUCCESS (1.972s)
[694.567004] Subtest psr-cursor-plane-move: SUCCESS (1.973s)
[695.439816] [049/131] (340s left) intel_hwmon (hwmon-write)
[695.496382] Subtest hwmon-write: SKIP (0.000s)
[695.557225] [050/131] (340s left) kms_force_connector_basic (force-connector-state)
[695.627843] Starting subtest: force-connector-state
[696.242363] Subtest force-connector-state: SUCCESS (0.615s)
[697.176767] [051/131] (338s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[697.270258] Starting subtest: x-tiled-32bpp-rotate-0
[698.808016] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.538s)
[698.946297] [052/131] (336s left) core_auth (many-magics)
[698.990400] Starting subtest: many-magics
[699.983101] Subtest many-magics: SUCCESS (0.992s)
[700.162465] [053/131] (335s left) gem_exec_balancer (parallel-bb-first)
[700.422457] Starting subtest: parallel-bb-first
[701.489858] Subtest parallel-bb-first: SUCCESS (1.067s)
[702.392055] [054/131] (333s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[702.485087] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[702.486711] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[702.553520] [055/131] (333s left) syncobj_wait (multi-wait-all-submitted)
[702.603478] Starting subtest: multi-wait-all-submitted
[702.710396] Subtest multi-wait-all-submitted: SUCCESS (0.106s)
[702.794574] [056/131] (333s left) gem_set_tiling_vs_gtt
[702.902300] [057/131] (333s left) gem_spin_batch (resubmit-new-all)
[702.956461] Starting subtest: resubmit-new-all
[702.957498] Starting dynamic subtest: rcs0
[702.971283] Dynamic subtest rcs0: SUCCESS (0.014s)
[702.980702] Starting dynamic subtest: bcs0
[702.983035] Dynamic subtest bcs0: SUCCESS (0.010s)
[702.992076] Starting dynamic subtest: vcs0
[702.993057] Dynamic subtest vcs0: SUCCESS (0.009s)
[703.001239] Starting dynamic subtest: vcs1
[703.002182] Dynamic subtest vcs1: SUCCESS (0.009s)
[703.010650] Starting dynamic subtest: vecs0
[703.011653] Dynamic subtest vecs0: SUCCESS (0.008s)
[703.019870] Starting dynamic subtest: ccs0
[703.021007] Dynamic subtest ccs0: SUCCESS (0.009s)
[703.021997] Subtest resubmit-new-all: SUCCESS (0.065s)
[704.375098] [058/131] (331s left) kms_pwrite_crc (basic)
[704.475316] Starting subtest: basic
[705.197095] Subtest basic: SUCCESS (0.723s)
[706.283701] [059/131] (329s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[706.376093] Starting subtest: plane-lut1d-ctm3x4-lut1d
[706.523721] Starting dynamic subtest: pipe-A-plane-0
[706.691699] Dynamic subtest pipe-A-plane-0: SUCCESS (0.167s)
[706.700484] Starting dynamic subtest: pipe-A-plane-1
[706.879498] Dynamic subtest pipe-A-plane-1: SUCCESS (0.188s)
[706.888732] Starting dynamic subtest: pipe-A-plane-2
[707.045942] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[708.240243] Starting dynamic subtest: pipe-B-plane-0
[708.431877] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[708.440638] Starting dynamic subtest: pipe-B-plane-1
[708.587176] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[708.595830] Starting dynamic subtest: pipe-B-plane-2
[708.753599] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[709.928118] Starting dynamic subtest: pipe-C-plane-0
[710.086101] Dynamic subtest pipe-C-plane-0: SUCCESS (0.166s)
[710.097681] Starting dynamic subtest: pipe-C-plane-1
[710.253336] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[710.262395] Starting dynamic subtest: pipe-C-plane-2
[710.420835] Dynamic subtest pipe-C-plane-2: SUCCESS (0.165s)
[711.625280] Starting dynamic subtest: pipe-D-plane-0
[711.772487] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s)
[711.781317] Starting dynamic subtest: pipe-D-plane-1
[711.938925] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s)
[711.947092] Starting dynamic subtest: pipe-D-plane-2
[712.105866] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[712.395721] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (6.018s)
[713.265285] [060/131] (322s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[713.364789] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[713.365671] Starting dynamic subtest: pipe-A-default-mode
[714.494014] Dynamic subtest pipe-A-default-mode: SKIP (1.128s)
[714.495674] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.130s)
[714.644891] [061/131] (321s left) perf_pmu (all-busy-check-all)
[714.679540] Starting subtest: all-busy-check-all
[716.317559] Subtest all-busy-check-all: SUCCESS (1.627s)
[716.417323] [062/131] (319s left) gem_ctx_bad_destroy (invalid-ctx)
[716.461111] Starting subtest: invalid-ctx
[716.462167] Subtest invalid-ctx: SUCCESS (0.000s)
[716.525108] [063/131] (319s left) kms_force_connector_basic (force-load-detect)
[716.594598] Starting subtest: force-load-detect
[716.596460] Subtest force-load-detect: SKIP (0.000s)
[716.707707] [064/131] (319s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[716.797578] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[716.799370] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[716.888349] [065/131] (319s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[716.935798] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[716.962287] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.006s)
[717.024124] [066/131] (319s left) perf_pmu (busy-idle)
[717.057769] Starting subtest: busy-idle
[717.058601] Starting dynamic subtest: rcs0
[717.758067] Dynamic subtest rcs0: SUCCESS (0.700s)
[717.770654] Starting dynamic subtest: bcs0
[718.422880] Dynamic subtest bcs0: SUCCESS (0.662s)
[718.435929] Starting dynamic subtest: vcs0
[720.349473] Dynamic subtest vcs0: SUCCESS (1.913s)
[720.360292] Starting dynamic subtest: vcs1
[722.334939] Dynamic subtest vcs1: SUCCESS (1.982s)
[722.346107] Starting dynamic subtest: vecs0
[724.317360] Dynamic subtest vecs0: SUCCESS (1.979s)
[724.329418] Starting dynamic subtest: ccs0
[724.977775] Dynamic subtest ccs0: SUCCESS (0.668s)
[724.979131] Subtest busy-idle: SUCCESS (7.920s)
[725.096633] [067/131] (311s left) sw_sync (timeline_closed)
[725.126844] Starting subtest: timeline_closed
[725.127783] Subtest timeline_closed: SUCCESS (0.000s)
[725.181694] [068/131] (311s left) syncobj_wait (wait-any-complex)
[725.234484] Starting subtest: wait-any-complex
[725.296478] Subtest wait-any-complex: SUCCESS (0.062s)
[725.371727] [069/131] (311s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[725.470062] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[725.471760] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[725.571867] [070/131] (310s left) gem_exec_params (dr1-dirt)
[725.629386] Starting subtest: DR1-dirt
[725.630549] Subtest DR1-dirt: SUCCESS (0.000s)
[725.720622] [071/131] (310s left) kms_psr (pr-cursor-blt)
[725.806501] Starting subtest: pr-cursor-blt
[725.808240] Subtest pr-cursor-blt: SKIP (0.000s)
[725.876350] [072/131] (310s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[725.962072] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[725.962950] Starting dynamic subtest: pipe-A-eDP-1
[725.972776] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[725.982581] Starting dynamic subtest: pipe-B-eDP-1
[725.991420] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[726.000433] Starting dynamic subtest: pipe-C-eDP-1
[726.008754] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[726.017232] Starting dynamic subtest: pipe-D-eDP-1
[726.025872] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[726.027367] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[728.153469] [073/131] (308s left) gem_pread (self)
[728.197710] Subtest self: SKIP (0.000s)
[728.259121] [074/131] (308s left) kms_plane (plane-panning-bottom-right)
[728.338736] Starting subtest: plane-panning-bottom-right
[728.339652] Starting dynamic subtest: pipe-A
[730.038339] Dynamic subtest pipe-A: SUCCESS (1.698s)
[730.049830] Starting dynamic subtest: pipe-B
[732.382045] Dynamic subtest pipe-B: SUCCESS (2.342s)
[732.382955] Subtest plane-panning-bottom-right: SUCCESS (4.043s)
[733.271031] [075/131] (303s left) gem_exec_whisper (basic-fds-all)
[733.337365] Starting subtest: basic-fds-all
[744.458164] Subtest basic-fds-all: SUCCESS (11.120s)
[744.655625] [076/131] (292s left) kms_big_fb (linear-64bpp-rotate-270)
[744.750253] Starting subtest: linear-64bpp-rotate-270
[744.771002] Subtest linear-64bpp-rotate-270: SKIP (0.019s)
[744.881468] [077/131] (291s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[744.981263] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[749.210745] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.230s)
[750.368239] [078/131] (286s left) core_getversion (all-cards)
[750.400336] Starting subtest: all-cards
[750.411117] Subtest all-cards: SUCCESS (0.012s)
[750.452858] [079/131] (286s left) api_intel_allocator (gem-pool)
[750.494891] Starting subtest: gem-pool
[750.501363] Subtest gem-pool: SUCCESS (0.007s)
[750.627489] [080/131] (286s left) i915_drm_fdinfo (busy)
[750.661707] Starting subtest: busy
[750.662650] Starting dynamic subtest: rcs0
[750.696887] Dynamic subtest rcs0: SKIP (0.034s)
[750.707925] Starting dynamic subtest: bcs0
[750.709578] Dynamic subtest bcs0: SKIP (0.000s)
[750.718188] Starting dynamic subtest: vcs0
[750.719940] Dynamic subtest vcs0: SKIP (0.000s)
[750.728673] Starting dynamic subtest: vcs1
[750.730399] Dynamic subtest vcs1: SKIP (0.000s)
[750.738878] Starting dynamic subtest: vecs0
[750.740590] Dynamic subtest vecs0: SKIP (0.000s)
[750.748901] Starting dynamic subtest: ccs0
[750.750570] Dynamic subtest ccs0: SKIP (0.000s)
[750.752254] Subtest busy: SKIP (0.040s)
[750.782356] [081/131] (286s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[750.890055] Starting subtest: cursor-vs-flip-toggle
[782.278938] Subtest cursor-vs-flip-toggle: SUCCESS (31.381s)
[783.074347] [082/131] (253s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[783.205225] Starting subtest: protected-encrypted-src-copy-not-readible
[783.317372] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.113s)
[783.441710] [083/131] (253s left) gem_userptr_blits (map-fixed-invalidate-busy)
[783.500475] Starting subtest: map-fixed-invalidate-busy
[783.502242] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[783.608330] [084/131] (253s left) gem_create (create-ext-cpu-access-big)
[783.782967] Starting subtest: create-ext-cpu-access-big
[783.785089] Subtest create-ext-cpu-access-big: SKIP (0.002s)
[783.859666] [085/131] (253s left) syncobj_wait (multi-wait-all-signaled)
[783.912185] Starting subtest: multi-wait-all-signaled
[783.912967] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[784.006928] [086/131] (253s left) gem_ctx_persistence (legacy-engines-cleanup)
[784.053316] Starting subtest: legacy-engines-cleanup
[784.054159] Starting dynamic subtest: render
[784.133799] Dynamic subtest render: SUCCESS (0.080s)
[784.146173] Starting dynamic subtest: bsd1
[784.200153] Dynamic subtest bsd1: SUCCESS (0.063s)
[784.213155] Starting dynamic subtest: bsd2
[784.248818] Dynamic subtest bsd2: SUCCESS (0.046s)
[784.260912] Starting dynamic subtest: blt
[784.310363] Dynamic subtest blt: SUCCESS (0.058s)
[784.325259] Starting dynamic subtest: vebox
[784.358631] Dynamic subtest vebox: SUCCESS (0.046s)
[784.361328] Subtest legacy-engines-cleanup: SUCCESS (0.307s)
[784.436275] [087/131] (252s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[784.491411] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[784.492374] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[784.585985] [088/131] (252s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[784.635658] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[784.691697] [089/131] (252s left) kms_cursor_crc (cursor-random-64x64)
[784.846874] Starting subtest: cursor-random-64x64
[784.850487] Starting dynamic subtest: pipe-A-eDP-1
[788.205191] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.353s)
[788.236778] Starting dynamic subtest: pipe-D-eDP-1
[792.265107] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.036s)
[792.265883] Subtest cursor-random-64x64: SUCCESS (7.417s)
[793.070777] [090/131] (244s left) gem_exec_await (wide-all)
[793.286377] Starting subtest: wide-all
[816.405276] Subtest wide-all: SUCCESS (23.106s)
[816.587873] [091/131] (220s left) core_setmaster (master-drop-set-user)
[816.727588] Starting subtest: master-drop-set-user
[816.739157] Subtest master-drop-set-user: SUCCESS (0.014s)
[816.773620] [092/131] (220s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[816.887884] Starting subtest: y-tiled-64bpp-rotate-180
[816.889709] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[816.980997] [093/131] (220s left) gem_exec_schedule (smoketest)
[817.179671] Starting subtest: smoketest
[817.180710] Starting dynamic subtest: rcs0
[822.701148] Dynamic subtest rcs0: SUCCESS (5.493s)
[822.717897] Starting dynamic subtest: bcs0
[828.242885] Dynamic subtest bcs0: SUCCESS (5.506s)
[828.256093] Starting dynamic subtest: vcs0
[833.734908] Dynamic subtest vcs0: SUCCESS (5.488s)
[833.749160] Starting dynamic subtest: vcs1
[839.193755] Dynamic subtest vcs1: SUCCESS (5.494s)
[839.213308] Starting dynamic subtest: vecs0
[844.742732] Dynamic subtest vecs0: SUCCESS (5.510s)
[844.758439] Starting dynamic subtest: ccs0
[850.215255] Dynamic subtest ccs0: SUCCESS (5.508s)
[850.216252] Subtest smoketest: SUCCESS (33.008s)
[850.369982] [094/131] (186s left) prime_vgem (wait)
[850.435580] Starting subtest: wait
[850.436558] Starting dynamic subtest: rcs0
[850.515546] Dynamic subtest rcs0: SUCCESS (0.079s)
[850.527134] Starting dynamic subtest: bcs0
[850.573200] Dynamic subtest bcs0: SUCCESS (0.057s)
[850.583612] Starting dynamic subtest: vcs0
[850.619546] Dynamic subtest vcs0: SUCCESS (0.045s)
[850.629347] Starting dynamic subtest: vcs1
[850.669027] Dynamic subtest vcs1: SUCCESS (0.049s)
[850.678839] Starting dynamic subtest: vecs0
[850.714859] Dynamic subtest vecs0: SUCCESS (0.045s)
[850.725796] Starting dynamic subtest: ccs0
[850.763073] Dynamic subtest ccs0: SUCCESS (0.046s)
[850.763995] Subtest wait: SUCCESS (0.328s)
[850.877519] [095/131] (186s left) gem_ctx_shared (exec-single-timeline)
[850.938289] Starting subtest: exec-single-timeline
[850.939307] Starting dynamic subtest: rcs0
[852.023967] Dynamic subtest rcs0: SUCCESS (1.076s)
[852.040638] Starting dynamic subtest: bcs0
[853.112592] Dynamic subtest bcs0: SUCCESS (1.085s)
[853.128097] Starting dynamic subtest: vcs0
[854.201423] Dynamic subtest vcs0: SUCCESS (1.085s)
[854.215676] Starting dynamic subtest: vcs1
[855.289123] Dynamic subtest vcs1: SUCCESS (1.084s)
[855.304118] Starting dynamic subtest: vecs0
[856.377131] Dynamic subtest vecs0: SUCCESS (1.085s)
[856.390112] Starting dynamic subtest: ccs0
[857.465237] Dynamic subtest ccs0: SUCCESS (1.083s)
[857.466928] Subtest exec-single-timeline: SUCCESS (6.518s)
[858.320366] [096/131] (179s left) kms_plane_multiple (tiling-yf)
[858.411123] Starting subtest: tiling-yf
[858.413118] Subtest tiling-yf: SKIP (0.000s)
[858.493828] [097/131] (178s left) kms_psr (fbc-psr-cursor-plane-move)
[858.578350] Starting subtest: fbc-psr-cursor-plane-move
[858.579287] Starting dynamic subtest: eDP-1
[858.581156] Dynamic subtest eDP-1: SKIP (0.000s)
[858.582909] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[858.666343] [098/131] (178s left) syncobj_timeline (multi-wait-all-signaled)
[858.719972] Starting subtest: multi-wait-all-signaled
[858.720953] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[858.840329] [099/131] (178s left) syncobj_wait (wait-zero-handles)
[858.889600] Starting subtest: wait-zero-handles
[858.890541] Subtest wait-zero-handles: SUCCESS (0.000s)
[859.005164] [100/131] (178s left) kms_invalid_mode (bad-hsync-start)
[859.100039] Starting subtest: bad-hsync-start
[859.101048] Starting dynamic subtest: pipe-A-eDP-1
[859.386596] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[859.397006] Starting dynamic subtest: pipe-B-eDP-1
[859.397828] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[859.407205] Starting dynamic subtest: pipe-C-eDP-1
[859.408070] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[859.417506] Starting dynamic subtest: pipe-D-eDP-1
[859.418330] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[859.419093] Subtest bad-hsync-start: SUCCESS (0.293s)
[860.181025] [101/131] (177s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[860.301887] Starting subtest: 2x-long-flip-vs-cursor-legacy
[860.304604] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[860.458257] [102/131] (177s left) gem_exec_reloc (basic-write-gtt-noreloc)
[860.516420] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[860.575179] [103/131] (177s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[860.669221] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[864.665375] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.995s)
[865.796114] [104/131] (171s left) gem_ringfill (engines-basic)
[871.327959] Starting subtest: engines-basic
[871.329199] Starting dynamic subtest: rcs0
[871.380750] Dynamic subtest rcs0: SUCCESS (0.061s)
[871.395104] Starting dynamic subtest: bcs0
[871.426347] Dynamic subtest bcs0: SUCCESS (0.043s)
[871.440138] Starting dynamic subtest: vcs0
[871.471814] Dynamic subtest vcs0: SUCCESS (0.043s)
[871.486576] Starting dynamic subtest: vcs1
[871.516223] Dynamic subtest vcs1: SUCCESS (0.043s)
[871.527913] Starting dynamic subtest: vecs0
[871.560243] Dynamic subtest vecs0: SUCCESS (0.042s)
[871.571574] Starting dynamic subtest: ccs0
[871.606008] Dynamic subtest ccs0: SUCCESS (0.044s)
[871.608635] Subtest engines-basic: SUCCESS (0.288s)
[871.694558] [105/131] (166s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[871.791394] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[871.793354] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[871.901883] [106/131] (165s left) gem_busy (semaphore)
[872.080049] Subtest semaphore: SKIP (0.000s)
[872.310659] [107/131] (165s left) gem_exec_suspend (basic)
[872.362568] Starting subtest: basic
[872.363475] Starting dynamic subtest: smem
[874.322048] Dynamic subtest smem: SUCCESS (1.960s)
[874.325096] Subtest basic: SUCCESS (1.962s)
[874.515350] [108/131] (163s left) kms_content_protection (lic-type-0-hdcp14)
[874.653367] Starting subtest: lic-type-0-hdcp14
[874.655577] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[874.787843] [109/131] (163s left) gem_exec_fence (syncobj-repeat)
[875.231362] Starting subtest: syncobj-repeat
[875.453789] Subtest syncobj-repeat: SUCCESS (0.211s)
[876.386594] [110/131] (161s left) gem_eio (wait-immediate)
[876.617044] Starting subtest: wait-immediate
[876.991534] Subtest wait-immediate: SUCCESS (0.375s)
[877.127632] [111/131] (160s left) i915_pm_freq_mult (media-freq)
[877.173603] Starting subtest: media-freq
[877.174567] Starting dynamic subtest: gt0
[877.176261] Dynamic subtest gt0: SKIP (0.000s)
[877.187348] Starting dynamic subtest: gt1
[877.189301] Dynamic subtest gt1: SKIP (0.000s)
[877.190945] Subtest media-freq: SKIP (0.002s)
[877.267241] [112/131] (160s left) kms_psr (fbc-pr-cursor-render)
[877.351818] Starting subtest: fbc-pr-cursor-render
[877.353461] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[877.423717] [113/131] (160s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[877.475778] Starting subtest: invalid-multi-wait-all-unsubmitted
[877.477204] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[877.562455] [114/131] (160s left) gem_partial_pwrite_pread (reads)
[877.622971] Subtest reads: SKIP (0.000s)
[877.692074] [115/131] (160s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[877.773507] Starting subtest: primary-y-tiled-reflect-x-90
[877.775317] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[877.843497] [116/131] (160s left) gem_exec_create (basic)
[877.894104] Starting subtest: basic
[877.895194] Starting dynamic subtest: smem
[879.985670] Dynamic subtest smem: SUCCESS (2.092s)
[879.988663] Subtest basic: SUCCESS (2.093s)
[880.164955] [117/131] (158s left) gem_exec_params (readonly)
[880.227037] Starting subtest: readonly
[880.229477] Subtest readonly: SUCCESS (0.003s)
[880.349350] [118/131] (157s left) gem_wait (invalid-buf)
[880.410925] Starting subtest: invalid-buf
[880.411925] Subtest invalid-buf: SUCCESS (0.000s)
[880.649212] [119/131] (157s left) prime_self_import (basic-llseek-size)
[880.680065] Starting subtest: basic-llseek-size
[880.695530] Subtest basic-llseek-size: SUCCESS (0.017s)
[880.819909] [120/131] (157s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[880.915235] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[880.916958] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[881.013094] [121/131] (157s left) kms_pm_dc (dc3co-vpb-simulation)
[882.106960] Starting subtest: dc3co-vpb-simulation
[891.305836] Subtest dc3co-vpb-simulation: SKIP (9.154s)
[891.393314] [122/131] (146s left) kms_vrr (seamless-rr-switch-virtual-full)
[891.476226] Starting subtest: seamless-rr-switch-virtual-full
[891.478876] Subtest seamless-rr-switch-virtual-full: SKIP (0.000s)
[891.553490] [123/131] (146s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[891.636505] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[898.352979] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.719s)
[898.486351] [124/131] (139s left) kms_flip (2x-flip-vs-fences)
[898.572898] Starting subtest: 2x-flip-vs-fences
[898.574757] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[898.675487] [125/131] (139s left) perf (non-sampling-read-error)
[898.815486] Starting subtest: non-sampling-read-error
[898.828874] Subtest non-sampling-read-error: SUCCESS (0.014s)
[898.875969] [126/131] (139s left) kms_cursor_crc (cursor-sliding-32x10)
[899.035462] Starting subtest: cursor-sliding-32x10
[899.085291] Subtest cursor-sliding-32x10: SKIP (0.047s)
[899.375236] [127/131] (139s left) kms_flip (plain-flip-ts-check)
[899.476367] Starting subtest: plain-flip-ts-check
[899.477436] Starting dynamic subtest: A-eDP1
[908.366579] Dynamic subtest A-eDP1: SUCCESS (8.866s)
[908.377429] Starting dynamic subtest: B-eDP1
[917.266192] Dynamic subtest B-eDP1: SUCCESS (8.899s)
[917.278024] Starting dynamic subtest: C-eDP1
[926.157813] Dynamic subtest C-eDP1: SUCCESS (8.879s)
[926.168990] Starting dynamic subtest: D-eDP1
[935.055916] Dynamic subtest D-eDP1: SUCCESS (8.897s)
[935.056851] Subtest plain-flip-ts-check: SUCCESS (35.553s)
[936.152047] [128/131] (102s left) gem_exec_reloc (basic-gtt-wc-active)
[936.207399] Subtest basic-gtt-wc-active: SKIP (0.000s)
[936.262050] [129/131] (102s left) sw_sync (sync_random_merge)
[936.292700] Starting subtest: sync_random_merge
[936.340739] Subtest sync_random_merge: SUCCESS (0.048s)
[936.401827] [130/131] (102s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[936.447499] Starting subtest: invalid-bsd1-flag-on-blt
[936.448589] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[936.535963] [131/131] (102s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[936.623168] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[936.624781] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[936.723887] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_exec_reloc
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: syncobj_wait
results: parsing output: 4/ for test: gen9_exec_parse
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: kms_flip
results: parsing output: 9/ for test: kms_flip_scaled_crc
results: parsing output: 10/ for test: kms_pipe_stress
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: kms_getfb
results: parsing output: 13/ for test: gem_ctx_create
results: parsing output: 14/ for test: kms_cursor_legacy
results: parsing output: 15/ for test: kms_vblank
results: parsing output: 16/ for test: kms_plane
results: parsing output: 17/ for test: kms_chamelium_hpd
results: parsing output: 18/ for test: kms_chamelium_color
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_wait
results: parsing output: 23/ for test: gem_mmap_gtt
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: kms_psr2_sf
results: parsing output: 26/ for test: kms_universal_plane
results: parsing output: 27/ for test: gem_ctx_isolation
results: parsing output: 28/ for test: kms_fence_pin_leak
results: parsing output: 29/ for test: gem_exec_endless
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: gem_exec_whisper
results: parsing output: 33/ for test: kms_feature_discovery
results: parsing output: 34/ for test: kms_rotation_crc
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_chamelium_edid
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_plane_scaling
results: parsing output: 42/ for test: kms_cursor_crc
results: parsing output: 43/ for test: i915_selftest
results: parsing output: 44/ for test: gem_exec_schedule
results: parsing output: 45/ for test: kms_lease
results: parsing output: 46/ for test: i915_pm_rc6_residency
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: intel_hwmon
results: parsing output: 49/ for test: kms_force_connector_basic
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: core_auth
results: parsing output: 52/ for test: gem_exec_balancer
results: parsing output: 53/ for test: kms_psr2_sf
results: parsing output: 54/ for test: syncobj_wait
results: parsing output: 55/ for test: gem_set_tiling_vs_gtt
results: parsing output: 56/ for test: gem_spin_batch
results: parsing output: 57/ for test: kms_pwrite_crc
results: parsing output: 58/ for test: kms_color_pipeline
results: parsing output: 59/ for test: kms_flip_scaled_crc
results: parsing output: 60/ for test: perf_pmu
results: parsing output: 61/ for test: gem_ctx_bad_destroy
results: parsing output: 62/ for test: kms_force_connector_basic
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: sriov_basic
results: parsing output: 65/ for test: perf_pmu
results: parsing output: 66/ for test: sw_sync
results: parsing output: 67/ for test: syncobj_wait
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_exec_params
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: kms_ccs
results: parsing output: 72/ for test: gem_pread
results: parsing output: 73/ for test: kms_plane
results: parsing output: 74/ for test: gem_exec_whisper
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: core_getversion
results: parsing output: 78/ for test: api_intel_allocator
results: parsing output: 79/ for test: i915_drm_fdinfo
results: parsing output: 80/ for test: kms_cursor_legacy
results: parsing output: 81/ for test: gem_pxp
results: parsing output: 82/ for test: gem_userptr_blits
results: parsing output: 83/ for test: gem_create
results: parsing output: 84/ for test: syncobj_wait
results: parsing output: 85/ for test: gem_ctx_persistence
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: gem_mmap_gtt
results: parsing output: 88/ for test: kms_cursor_crc
results: parsing output: 89/ for test: gem_exec_await
results: parsing output: 90/ for test: core_setmaster
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: gem_exec_schedule
results: parsing output: 93/ for test: prime_vgem
results: parsing output: 94/ for test: gem_ctx_shared
results: parsing output: 95/ for test: kms_plane_multiple
results: parsing output: 96/ for test: kms_psr
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: syncobj_wait
results: parsing output: 99/ for test: kms_invalid_mode
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: gem_exec_reloc
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_ringfill
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_busy
results: parsing output: 106/ for test: gem_exec_suspend
results: parsing output: 107/ for test: kms_content_protection
results: parsing output: 108/ for test: gem_exec_fence
results: parsing output: 109/ for test: gem_eio
results: parsing output: 110/ for test: i915_pm_freq_mult
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: syncobj_timeline
results: parsing output: 113/ for test: gem_partial_pwrite_pread
results: parsing output: 114/ for test: kms_rotation_crc
results: parsing output: 115/ for test: gem_exec_create
results: parsing output: 116/ for test: gem_exec_params
results: parsing output: 117/ for test: gem_wait
results: parsing output: 118/ for test: prime_self_import
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_pm_dc
results: parsing output: 121/ for test: kms_vrr
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: perf
results: parsing output: 125/ for test: kms_cursor_crc
results: parsing output: 126/ for test: kms_flip
results: parsing output: 127/ for test: gem_exec_reloc
results: parsing output: 128/ for test: sw_sync
results: parsing output: 129/ for test: gem_exec_params
results: parsing output: 130/ for test: kms_frontbuffer_tracking
Done.