[59.554499] Initializing watchdogs
[59.554546]   /dev/watchdog0
[59.568107] [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]
[59.572681] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.575174] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.578561] [001/133] (960s left) kms_psr (fbc-psr-primary-blt)
[59.689696] Starting subtest: fbc-psr-primary-blt
[59.692034] Starting dynamic subtest: eDP-1
[59.696426] Dynamic subtest eDP-1: SKIP (0.000s)
[59.700501] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[59.759465] [002/133] (960s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[59.850750] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[59.855227] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[59.933076] [003/133] (960s left) gem_reg_read (timestamp-moving)
[59.991788] Starting subtest: timestamp-moving
[60.992182] Subtest timestamp-moving: SUCCESS (1.005s)
[61.076534] [004/133] (959s left) gem_lmem_swapping (random-engines)
[61.116804] Subtest random-engines: SKIP (0.000s)
[61.161409] [005/133] (959s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[61.247343] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[61.249678] Starting dynamic subtest: pipe-A-default-mode
[62.428266] Dynamic subtest pipe-A-default-mode: SKIP (1.179s)
[62.432234] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.181s)
[62.579150] [006/133] (957s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[62.675415] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[62.679575] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[62.751121] [007/133] (957s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[62.850344] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[69.976076] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (7.142s)
[70.098660] [008/133] (950s left) gem_ctx_isolation (preservation-reset)
[70.265731] Starting subtest: preservation-reset
[70.267943] Starting dynamic subtest: rcs0
[70.470309] Dynamic subtest rcs0: SUCCESS (0.209s)
[70.479854] Starting dynamic subtest: bcs0
[70.704530] Dynamic subtest bcs0: SUCCESS (0.234s)
[70.716224] Starting dynamic subtest: vcs0
[70.946082] Dynamic subtest vcs0: SUCCESS (0.241s)
[70.955607] Starting dynamic subtest: vcs1
[72.090403] Dynamic subtest vcs1: SUCCESS (1.145s)
[72.097880] Starting dynamic subtest: vecs0
[74.158449] Dynamic subtest vecs0: SUCCESS (2.069s)
[74.169419] Starting dynamic subtest: ccs0
[76.158637] Dynamic subtest ccs0: SUCCESS (2.000s)
[76.161226] Subtest preservation-reset: SUCCESS (5.903s)
[76.280147] [009/133] (944s left) kms_ccs (crc-sprite-planes-basic-yf-tiled-ccs)
[76.380716] Starting subtest: crc-sprite-planes-basic-yf-tiled-ccs
[76.383051] Starting dynamic subtest: pipe-A-eDP-1
[76.439823] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[76.448747] Starting dynamic subtest: pipe-B-eDP-1
[76.505543] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[76.514084] Starting dynamic subtest: pipe-C-eDP-1
[76.570753] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[76.578922] Starting dynamic subtest: pipe-D-eDP-1
[76.635605] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[76.639816] Subtest crc-sprite-planes-basic-yf-tiled-ccs: SKIP (0.007s)
[78.536164] [010/133] (941s left) gem_create (create-valid-nonaligned)
[78.586356] Starting subtest: create-valid-nonaligned
[78.588557] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[78.770476] [011/133] (941s left) syncobj_eventfd (binary-wait-signaled)
[78.824956] Starting subtest: binary-wait-signaled
[78.827063] Subtest binary-wait-signaled: SUCCESS (0.000s)
[78.914139] [012/133] (941s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[79.008712] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[79.011048] Starting dynamic subtest: pipe-A-eDP-1
[79.033674] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[79.043128] Starting dynamic subtest: pipe-B-eDP-1
[79.064157] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[79.071463] Starting dynamic subtest: pipe-C-eDP-1
[79.093203] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[79.100336] Starting dynamic subtest: pipe-D-eDP-1
[79.122305] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[79.126716] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[81.274244] [013/133] (939s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[81.374390] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[81.378782] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[81.476851] [014/133] (939s left) fbdev (read)
[81.527672] Starting subtest: read
[82.054055] Subtest read: SUCCESS (0.523s)
[82.125180] [015/133] (938s left) gem_exec_await (wide-all)
[82.331925] Starting subtest: wide-all
[105.777060] Subtest wide-all: SUCCESS (23.426s)
[105.962660] [FACT gem_exec_await (wide-all)] new: kernel.kmod_is_loaded.vgem: true
[105.967760] [016/133] (914s left) kms_cursor_legacy (flip-vs-cursor-toggle)
[106.127035] Starting subtest: flip-vs-cursor-toggle
[115.116896] Subtest flip-vs-cursor-toggle: SUCCESS (8.988s)
[115.997570] [017/133] (904s left) i915_pm_rpm (gem-execbuf-stress)
[118.036721] Starting subtest: gem-execbuf-stress
[118.039135] Starting dynamic subtest: smem0
[130.861970] Dynamic subtest smem0: SUCCESS (12.828s)
[130.872972] Starting dynamic subtest: extra-wait-smem0
[192.712288] Dynamic subtest extra-wait-smem0: SUCCESS (61.846s)
[192.715191] Subtest gem-execbuf-stress: SUCCESS (74.677s)
[193.083012] [018/133] (827s left) gem_mmap_offset (bad-flags)
[193.137942] Starting subtest: bad-flags
[193.139908] Subtest bad-flags: SUCCESS (0.000s)
[193.231622] [019/133] (827s left) kms_content_protection (dp-mst-type-0-hdcp14)
[193.358591] Starting subtest: dp-mst-type-0-hdcp14
[193.363056] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[193.478121] [020/133] (827s left) kms_flip (plain-flip-fb-recreate-interruptible)
[193.560692] Starting subtest: plain-flip-fb-recreate-interruptible
[193.563041] Starting dynamic subtest: A-eDP1
[202.374275] Dynamic subtest A-eDP1: SUCCESS (8.812s)
[202.382409] Starting dynamic subtest: B-eDP1
[211.205774] Dynamic subtest B-eDP1: SUCCESS (8.829s)
[211.215517] Starting dynamic subtest: C-eDP1
[220.026323] Dynamic subtest C-eDP1: SUCCESS (8.819s)
[220.036283] Starting dynamic subtest: D-eDP1
[228.844508] Dynamic subtest D-eDP1: SUCCESS (8.817s)
[228.848740] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.286s)
[230.035444] [021/133] (790s left) gem_exec_store (cachelines)
[230.097133] Starting subtest: cachelines
[230.099338] Starting dynamic subtest: rcs0
[230.178876] Dynamic subtest rcs0: SUCCESS (0.083s)
[230.189802] Starting dynamic subtest: bcs0
[230.233794] Dynamic subtest bcs0: SUCCESS (0.054s)
[230.251350] Starting dynamic subtest: vcs0
[230.289072] Dynamic subtest vcs0: SUCCESS (0.055s)
[230.298325] Starting dynamic subtest: vcs1
[230.350203] Dynamic subtest vcs1: SUCCESS (0.060s)
[230.359183] Starting dynamic subtest: vecs0
[230.408880] Dynamic subtest vecs0: SUCCESS (0.058s)
[230.417908] Starting dynamic subtest: ccs0
[230.467715] Dynamic subtest ccs0: SUCCESS (0.057s)
[230.470477] Subtest cachelines: SUCCESS (0.372s)
[230.649875] [022/133] (790s left) gem_exec_balancer (nohangcheck)
[230.913852] Starting subtest: nohangcheck
[231.799399] Subtest nohangcheck: SUCCESS (0.886s)
[231.919193] [023/133] (789s left) gen9_exec_parse (bb-chained)
[231.988433] Subtest bb-chained: SKIP (0.000s)
[232.063513] [024/133] (788s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[232.160737] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[235.723072] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.566s)
[236.918016] [025/133] (784s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[237.031771] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[237.036039] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[237.124319] [026/133] (783s left) gem_spin_batch (legacy-resubmit-new)
[237.188625] Starting subtest: legacy-resubmit-new
[237.191185] Starting dynamic subtest: default
[237.196980] Dynamic subtest default: SUCCESS (0.008s)
[237.207635] Starting dynamic subtest: render
[237.209860] Dynamic subtest render: SUCCESS (0.003s)
[237.218137] Starting dynamic subtest: bsd
[237.220076] Dynamic subtest bsd: SUCCESS (0.004s)
[237.228265] Starting dynamic subtest: bsd1
[237.230480] Dynamic subtest bsd1: SUCCESS (0.006s)
[237.238746] Starting dynamic subtest: bsd2
[237.241030] Dynamic subtest bsd2: SUCCESS (0.003s)
[237.248756] Starting dynamic subtest: blt
[237.250998] Dynamic subtest blt: SUCCESS (0.005s)
[237.258770] Starting dynamic subtest: vebox
[237.260996] Dynamic subtest vebox: SUCCESS (0.004s)
[237.263237] Subtest legacy-resubmit-new: SUCCESS (0.038s)
[238.973418] [027/133] (782s left) kms_hdr (static-toggle-dpms)
[239.078239] Starting subtest: static-toggle-dpms
[239.085034] Subtest static-toggle-dpms: SKIP (0.001s)
[239.177417] [028/133] (781s left) gem_fence_thrash (bo-write-verify-none)
[239.215114] Starting subtest: bo-write-verify-none
[239.232042] Subtest bo-write-verify-none: SKIP (0.014s)
[239.289789] [029/133] (781s left) kms_pm_rpm (package-g7)
[241.014680] Starting subtest: package-g7
[241.019570] Subtest package-g7: SKIP (0.000s)
[241.567125] [030/133] (779s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[241.663138] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[241.667910] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[241.758583] [031/133] (779s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[241.855410] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[241.857731] Starting dynamic subtest: pipe-A-eDP-1
[241.877549] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.022s)
[241.886711] Starting dynamic subtest: pipe-B-eDP-1
[241.906508] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.021s)
[241.914989] Starting dynamic subtest: pipe-C-eDP-1
[241.934888] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.021s)
[241.943189] Starting dynamic subtest: pipe-D-eDP-1
[241.963249] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.021s)
[241.965747] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.089s)
[244.129489] [032/133] (777s left) gem_exec_schedule (promotion)
[244.213650] Starting subtest: promotion
[244.215699] Starting dynamic subtest: rcs0
[244.320045] Dynamic subtest rcs0: SUCCESS (0.113s)
[244.330170] Starting dynamic subtest: vcs0
[244.412899] Dynamic subtest vcs0: SUCCESS (0.092s)
[244.423855] Starting dynamic subtest: vcs1
[244.502981] Dynamic subtest vcs1: SUCCESS (0.090s)
[244.514063] Starting dynamic subtest: vecs0
[244.586972] Dynamic subtest vecs0: SUCCESS (0.083s)
[244.598480] Starting dynamic subtest: ccs0
[244.691298] Dynamic subtest ccs0: SUCCESS (0.104s)
[244.694033] Subtest promotion: SUCCESS (0.485s)
[245.992597] [033/133] (775s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt)
[246.099040] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt
[246.103065] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[246.231876] [034/133] (775s left) api_intel_bb (crc32)
[246.299997] Starting subtest: crc32
[246.302311] Starting dynamic subtest: rcs0-smem0
[246.594463] Dynamic subtest rcs0-smem0: SUCCESS (0.294s)
[246.603965] Starting dynamic subtest: bcs0-smem0
[246.917030] Dynamic subtest bcs0-smem0: SUCCESS (0.320s)
[246.929095] Starting dynamic subtest: vcs0-smem0
[247.260108] Dynamic subtest vcs0-smem0: SUCCESS (0.342s)
[247.269713] Starting dynamic subtest: vcs1-smem0
[247.598295] Dynamic subtest vcs1-smem0: SUCCESS (0.336s)
[247.610041] Starting dynamic subtest: vecs0-smem0
[247.913705] Dynamic subtest vecs0-smem0: SUCCESS (0.314s)
[247.923584] Starting dynamic subtest: ccs0-smem0
[248.233517] Dynamic subtest ccs0-smem0: SUCCESS (0.318s)
[248.236103] Subtest crc32: SUCCESS (1.935s)
[248.342968] [035/133] (773s left) kms_feature_discovery (dp-mst)
[248.449061] Starting subtest: dp-mst
[248.453361] Subtest dp-mst: SKIP (0.000s)
[248.519027] [036/133] (772s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[248.615074] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[248.617358] Starting dynamic subtest: pipe-A-eDP-1
[248.637738] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[248.647141] Starting dynamic subtest: pipe-B-eDP-1
[248.666874] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[248.674490] Starting dynamic subtest: pipe-C-eDP-1
[248.694192] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[248.702395] Starting dynamic subtest: pipe-D-eDP-1
[248.725333] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[248.727245] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.004s)
[250.877249] [037/133] (770s left) kms_busy (extended-pageflip-hang-oldfb)
[250.979214] Starting subtest: extended-pageflip-hang-oldfb
[250.981507] Starting dynamic subtest: pipe-A
[251.388975] Dynamic subtest pipe-A: SUCCESS (0.409s)
[251.399224] Starting dynamic subtest: pipe-D
[252.498679] Dynamic subtest pipe-D: SUCCESS (1.107s)
[252.501072] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.520s)
[253.350997] [038/133] (768s left) kms_psr (fbc-pr-suspend)
[253.444298] Starting subtest: fbc-pr-suspend
[253.448935] Subtest fbc-pr-suspend: SKIP (0.000s)
[253.516360] [039/133] (768s left) kms_setmode (invalid-clone-single-crtc)
[253.570902] Starting subtest: invalid-clone-single-crtc
[253.577565] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[253.684565] [040/133] (767s left) sw_sync (timeline_closed)
[253.723751] Starting subtest: timeline_closed
[253.726095] Subtest timeline_closed: SUCCESS (0.000s)
[253.810167] [041/133] (767s left) syncobj_basic (bad-create-flags)
[253.862516] Starting subtest: bad-create-flags
[253.864897] Subtest bad-create-flags: SUCCESS (0.000s)
[253.949292] [042/133] (767s left) i915_selftest (live)
[257.206034] Starting subtest: live
[257.208578] Starting dynamic subtest: sanitycheck
[261.598647] Dynamic subtest sanitycheck: SUCCESS (4.391s)
[261.608099] Starting dynamic subtest: uncore
[265.913600] Dynamic subtest uncore: SUCCESS (4.313s)
[265.922763] Starting dynamic subtest: workarounds
[283.808918] Dynamic subtest workarounds: FAIL (17.423s)
[283.817074] Starting dynamic subtest: gt_engines
[287.890027] Dynamic subtest gt_engines: SUCCESS (4.550s)
[287.898501] Starting dynamic subtest: gt_timelines
[303.618747] Dynamic subtest gt_timelines: SUCCESS (15.726s)
[303.627820] Starting dynamic subtest: gt_contexts
[307.918663] Dynamic subtest gt_contexts: SUCCESS (4.298s)
[307.928248] Starting dynamic subtest: gt_lrc
[312.282083] Dynamic subtest gt_lrc: SUCCESS (4.362s)
[312.290849] Starting dynamic subtest: gt_mocs
[315.174773] Dynamic subtest gt_mocs: SUCCESS (2.892s)
[315.180941] Starting dynamic subtest: gt_pm
[321.959085] Dynamic subtest gt_pm: SUCCESS (6.783s)
[321.968664] Starting dynamic subtest: gt_heartbeat
[326.287752] Dynamic subtest gt_heartbeat: SUCCESS (4.327s)
[326.295655] Starting dynamic subtest: gt_tlb
[335.527764] Dynamic subtest gt_tlb: SUCCESS (9.238s)
[335.541509] Starting dynamic subtest: requests
[346.920102] Dynamic subtest requests: SUCCESS (11.390s)
[346.928875] Starting dynamic subtest: migrate
[354.623342] Dynamic subtest migrate: SUCCESS (7.702s)
[354.632217] Starting dynamic subtest: active
[358.926999] Dynamic subtest active: SUCCESS (4.302s)
[358.936093] Starting dynamic subtest: objects
[363.572895] Dynamic subtest objects: SUCCESS (4.644s)
[363.581672] Starting dynamic subtest: mman
[368.317735] Dynamic subtest mman: SUCCESS (4.743s)
[368.330571] Starting dynamic subtest: dmabuf
[372.603089] Dynamic subtest dmabuf: SUCCESS (4.284s)
[372.612587] Starting dynamic subtest: vma
[376.907077] Dynamic subtest vma: SUCCESS (4.301s)
[376.916652] Starting dynamic subtest: coherency
[381.956920] Dynamic subtest coherency: SUCCESS (5.049s)
[381.965900] Starting dynamic subtest: gtt
[393.869228] Dynamic subtest gtt: SUCCESS (11.909s)
[393.876724] Starting dynamic subtest: gem
[399.623394] Dynamic subtest gem: SUCCESS (5.752s)
[399.632905] Starting dynamic subtest: evict
[403.600299] Dynamic subtest evict: SUCCESS (3.975s)
[403.614613] Starting dynamic subtest: hugepages
[412.293888] Dynamic subtest hugepages: SUCCESS (8.691s)
[412.303687] Starting dynamic subtest: gem_contexts
[440.868372] Dynamic subtest gem_contexts: SUCCESS (28.572s)
[440.877901] Starting dynamic subtest: client
[445.153319] Dynamic subtest client: SUCCESS (4.283s)
[445.162490] Starting dynamic subtest: gem_migrate
[449.426679] Dynamic subtest gem_migrate: SUCCESS (4.272s)
[449.436102] Starting dynamic subtest: reset
[452.165927] Dynamic subtest reset: SUCCESS (2.737s)
[452.175193] Starting dynamic subtest: memory_region
[456.442282] Dynamic subtest memory_region: SUCCESS (4.275s)
[456.452441] Starting dynamic subtest: hangcheck
[469.209306] Dynamic subtest hangcheck: SUCCESS (12.766s)
[469.224414] Starting dynamic subtest: execlists
[473.560062] Dynamic subtest execlists: SUCCESS (4.347s)
[473.574965] Starting dynamic subtest: ring_submission
[477.899257] Dynamic subtest ring_submission: SUCCESS (4.337s)
[477.909255] Starting dynamic subtest: perf
[482.176134] Dynamic subtest perf: SUCCESS (4.275s)
[482.185370] Starting dynamic subtest: slpc
[494.546589] Dynamic subtest slpc: SUCCESS (12.369s)
[494.561526] Starting dynamic subtest: guc
[498.408350] Dynamic subtest guc: SUCCESS (3.860s)
[498.417753] Starting dynamic subtest: guc_multi_lrc
[502.739622] Dynamic subtest guc_multi_lrc: SUCCESS (4.330s)
[502.747681] Starting dynamic subtest: guc_hang
[506.139812] Dynamic subtest guc_hang: SUCCESS (3.397s)
[506.149594] Starting dynamic subtest: late_gt_pm
[508.894341] Dynamic subtest late_gt_pm: SUCCESS (2.753s)
[508.896890] Subtest live: FAIL (251.680s)
[508.924935] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[508.925642] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[508.928723] [043/133] (512s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[510.471586] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[510.476176] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[510.594717] [FACT kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[510.596028] [FACT kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)] new: kernel.kmod_is_loaded.i915: true
[510.600445] [044/133] (511s left) i915_drm_fdinfo (virtual-busy-idle)
[510.646967] Starting subtest: virtual-busy-idle
[510.651493] Subtest virtual-busy-idle: SKIP (0.000s)
[510.717503] [045/133] (511s left) vgem_basic (bad-pad)
[510.764314] Starting subtest: bad-pad
[510.766498] Subtest bad-pad: SUCCESS (0.000s)
[510.822750] [046/133] (511s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[510.918435] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[510.922688] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[511.012894] [047/133] (510s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[511.076567] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[511.184580] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.108s)
[511.258019] [048/133] (510s left) drm_read (short-buffer-nonblock)
[511.421186] Starting subtest: short-buffer-nonblock
[511.423445] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[511.532309] [049/133] (510s left) kms_plane (pixel-format-yf-tiled-modifier)
[511.621331] Starting subtest: pixel-format-yf-tiled-modifier
[511.625402] Subtest pixel-format-yf-tiled-modifier: SKIP (0.000s)
[511.697138] [050/133] (510s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[511.753134] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[511.866994] [051/133] (510s left) kms_vblank (ts-continuation-modeset-rpm)
[511.956291] Starting subtest: ts-continuation-modeset-rpm
[511.958135] Starting dynamic subtest: pipe-A-eDP-1
[514.492307] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.536s)
[514.505943] Starting dynamic subtest: pipe-D-eDP-1
[516.739872] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.241s)
[516.742242] Subtest ts-continuation-modeset-rpm: SUCCESS (4.785s)
[517.557784] [052/133] (504s left) gem_userptr_blits (forbidden-operations)
[517.638503] Starting subtest: forbidden-operations
[517.642727] Subtest forbidden-operations: SKIP (0.000s)
[517.795979] [053/133] (504s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[517.890528] Starting subtest: x-tiled-8bpp-rotate-0
[519.092563] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.202s)
[519.215048] [054/133] (503s left) kms_properties (crtc-properties-atomic)
[519.302590] Starting subtest: crtc-properties-atomic
[519.304327] Starting dynamic subtest: pipe-A-eDP-1
[519.743088] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.439s)
[519.753753] Starting dynamic subtest: pipe-B-eDP-1
[520.920060] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.176s)
[520.930706] Starting dynamic subtest: pipe-C-eDP-1
[522.026385] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.107s)
[522.035818] Starting dynamic subtest: pipe-D-eDP-1
[523.156597] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.128s)
[523.159020] Subtest crtc-properties-atomic: SUCCESS (3.856s)
[523.969468] [055/133] (498s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[524.072610] Starting subtest: y-tiled-64bpp-rotate-90
[524.076950] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[524.166105] [056/133] (498s left) kms_psr (pr-primary-mmap-cpu)
[524.265141] Starting subtest: pr-primary-mmap-cpu
[524.269616] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[524.335403] [057/133] (498s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[524.419737] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[524.424059] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[524.490466] [058/133] (497s left) kms_vrr (max-min)
[524.584166] Starting subtest: max-min
[524.590440] Subtest max-min: SKIP (0.000s)
[524.656358] [059/133] (497s left) kms_flip (2x-plain-flip-ts-check)
[524.743329] Starting subtest: 2x-plain-flip-ts-check
[524.754204] Subtest 2x-plain-flip-ts-check: SKIP (0.010s)
[524.832714] [060/133] (497s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[524.930291] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[524.933786] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[525.013454] [061/133] (497s left) kms_psr (psr-cursor-render)
[525.109626] Starting subtest: psr-cursor-render
[525.111825] Starting dynamic subtest: eDP-1
[526.859152] Dynamic subtest eDP-1: SUCCESS (1.750s)
[526.861476] Subtest psr-cursor-render: SUCCESS (1.751s)
[527.736305] [062/133] (494s left) kms_vblank (query-busy-hang)
[527.846354] Starting subtest: query-busy-hang
[527.848756] Starting dynamic subtest: pipe-A-eDP-1
[554.658958] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.812s)
[554.672076] Starting dynamic subtest: pipe-D-eDP-1
[569.691346] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.030s)
[569.693518] Subtest query-busy-hang: SUCCESS (41.846s)
[570.501707] [063/133] (452s left) kms_addfb_basic (size-max)
[570.601176] Starting subtest: size-max
[570.603115] Subtest size-max: SUCCESS (0.000s)
[570.705842] [064/133] (451s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[570.812509] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[570.816669] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[570.910733] [065/133] (451s left) gem_ctx_engines (invalid-engines)
[570.977049] Starting subtest: invalid-engines
[570.983357] Subtest invalid-engines: SUCCESS (0.009s)
[571.202847] [066/133] (451s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[571.257433] Starting subtest: wait-all-for-submit-delayed-submit
[571.361899] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.104s)
[571.451562] [067/133] (451s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[571.542504] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[572.133372] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.592s)
[572.284335] [068/133] (450s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[572.386449] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[576.476546] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.093s)
[577.614392] [069/133] (445s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[577.710984] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[578.733495] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.023s)
[578.866821] [070/133] (444s left) kms_frontbuffer_tracking (fbc-suspend)
[578.958554] Starting subtest: fbc-suspend
[617.634892] Subtest fbc-suspend: SUCCESS (8.389s)
[618.815119] [071/133] (404s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[618.922814] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[622.558690] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.675s)
[623.751153] [072/133] (399s left) gem_exec_reloc (basic-softpin)
[623.800503] Subtest basic-softpin: SKIP (0.000s)
[623.875156] [073/133] (399s left) gem_eio (kms)
[624.109056] Starting subtest: kms
[642.712562] Subtest kms: SUCCESS (18.653s)
[643.892446] [074/133] (379s left) kms_chamelium_edid (dp-mode-timings)
[644.274199] Subtest dp-mode-timings: SKIP (0.000s)
[645.093362] [075/133] (377s left) syncobj_timeline (multi-wait-for-submit-signaled)
[645.153843] Starting subtest: multi-wait-for-submit-signaled
[645.156258] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[645.250319] [076/133] (377s left) gem_userptr_blits (coherency-sync)
[645.314092] Starting subtest: coherency-sync
[645.568937] Subtest coherency-sync: SKIP (0.221s)
[645.668965] [077/133] (377s left) gem_userptr_blits (sync-overlap)
[645.786945] Starting subtest: sync-overlap
[645.789139] Subtest sync-overlap: SUCCESS (0.000s)
[645.901689] [078/133] (377s left) gem_lmem_swapping (massive)
[645.965873] Subtest massive: SKIP (0.000s)
[646.011340] [079/133] (377s left) syncobj_wait (invalid-reset-bad-pad)
[646.070182] Starting subtest: invalid-reset-bad-pad
[646.072508] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[646.165911] [080/133] (377s left) kms_prop_blob (blob-prop-lifetime)
[646.225239] Starting subtest: blob-prop-lifetime
[646.227555] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[646.339941] [081/133] (377s left) gem_flink_basic (bad-open)
[646.386998] Starting subtest: bad-open
[646.388950] Subtest bad-open: SUCCESS (0.000s)
[646.488822] [082/133] (376s left) gem_exec_basic (basic)
[646.552131] Starting subtest: basic
[646.554015] Starting dynamic subtest: rcs0-smem
[646.555965] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[646.567004] Starting dynamic subtest: bcs0-smem
[646.568533] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[646.576967] Starting dynamic subtest: vcs0-smem
[646.578525] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[646.586596] Starting dynamic subtest: vcs1-smem
[646.588096] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[646.596365] Starting dynamic subtest: vecs0-smem
[646.597943] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[646.605968] Starting dynamic subtest: ccs0-smem
[646.607531] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[646.609085] Subtest basic: SUCCESS (0.014s)
[648.310507] [083/133] (375s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[648.428622] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[648.432588] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[648.542198] [084/133] (374s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[648.633446] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[648.635669] Starting dynamic subtest: pipe-A
[648.657197] Dynamic subtest pipe-A: SKIP (0.018s)
[648.666742] Starting dynamic subtest: pipe-B
[648.689261] Dynamic subtest pipe-B: SKIP (0.031s)
[648.698518] Starting dynamic subtest: pipe-C
[648.720697] Dynamic subtest pipe-C: SKIP (0.031s)
[648.729479] Starting dynamic subtest: pipe-D
[648.751245] Dynamic subtest pipe-D: SKIP (0.031s)
[648.755108] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.114s)
[648.859158] [085/133] (374s left) gem_ctx_persistence (hostile)
[648.917040] Starting subtest: hostile
[659.065380] Subtest hostile: SUCCESS (10.139s)
[659.211970] [086/133] (364s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[659.420831] Starting subtest: cursor-rapid-movement-128x128
[659.423093] Starting dynamic subtest: pipe-A-eDP-1
[659.993928] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.568s)
[660.019121] Starting dynamic subtest: pipe-D-eDP-1
[661.225350] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.211s)
[661.227675] Subtest cursor-rapid-movement-128x128: SUCCESS (1.803s)
[662.086679] [087/133] (361s left) kms_panel_fitting (legacy)
[662.179270] Starting subtest: legacy
[662.181529] Starting dynamic subtest: pipe-A-eDP-1
[662.742890] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.566s)
[662.770861] Starting dynamic subtest: pipe-B-eDP-1
[664.366552] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.603s)
[664.395652] Starting dynamic subtest: pipe-C-eDP-1
[665.934050] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.548s)
[665.961697] Starting dynamic subtest: pipe-D-eDP-1
[667.484060] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.530s)
[667.486518] Subtest legacy: SUCCESS (5.307s)
[668.625532] [088/133] (355s left) gem_pxp (create-protected-buffer)
[668.744676] Starting subtest: create-protected-buffer
[668.747097] Subtest create-protected-buffer: SUCCESS (0.000s)
[668.876164] [089/133] (354s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[668.933524] Starting subtest: mixed-tiled-to-y-tiled-ccs
[668.937833] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[669.117943] [090/133] (354s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[669.209690] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[676.674891] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.469s)
[676.790915] [091/133] (347s left) gem_render_copy (yf-tiled-to-vebox-linear)
[676.866152] Starting subtest: yf-tiled-to-vebox-linear
[676.871002] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[677.047902] [092/133] (346s left) syncobj_timeline (etime-multi-wait-available-unsubmitted)
[677.112000] Starting subtest: etime-multi-wait-available-unsubmitted
[677.220695] Subtest etime-multi-wait-available-unsubmitted: SUCCESS (0.107s)
[677.295701] [093/133] (346s left) kms_atomic_interruptible (legacy-setmode)
[677.391548] Starting subtest: legacy-setmode
[677.393665] Starting dynamic subtest: pipe-A-eDP-1
[684.290672] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.900s)
[684.292958] Subtest legacy-setmode: SUCCESS (6.901s)
[685.160416] [094/133] (338s left) kms_atomic (crtc-invalid-params)
[685.261810] Starting subtest: crtc-invalid-params
[685.264151] Starting dynamic subtest: pipe-A-eDP-1
[685.617805] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.357s)
[685.620204] Subtest crtc-invalid-params: SUCCESS (0.358s)
[686.497122] [095/133] (337s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-sf)
[686.593123] Starting subtest: psr2-overlay-plane-move-continuous-sf
[686.595362] Starting dynamic subtest: pipe-A-eDP-1
[692.570059] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.981s)
[692.580772] Starting dynamic subtest: pipe-B-eDP-1
[699.282081] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.711s)
[699.284394] Subtest psr2-overlay-plane-move-continuous-sf: SUCCESS (12.696s)
[700.151893] [096/133] (323s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[700.266675] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt
[700.270587] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[700.371300] [097/133] (323s left) kms_plane_alpha_blend (alpha-opaque-fb)
[700.464354] Starting subtest: alpha-opaque-fb
[700.745228] Starting dynamic subtest: pipe-A-eDP-1
[701.354388] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.608s)
[701.365326] Starting dynamic subtest: pipe-D-eDP-1
[702.694201] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.339s)
[702.696593] Subtest alpha-opaque-fb: SUCCESS (2.233s)
[703.521695] [098/133] (320s left) kms_getfb (getfb-handle-protection)
[703.617691] Starting subtest: getfb-handle-protection
[703.632841] Subtest getfb-handle-protection: SUCCESS (0.016s)
[703.767433] [099/133] (320s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[703.878916] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[703.883228] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[703.973915] [100/133] (320s left) gem_exec_flush (basic-wb-rw-default)
[704.040279] Starting subtest: basic-wb-rw-default
[709.525897] Subtest basic-wb-rw-default: SUCCESS (5.474s)
[709.698907] [101/133] (314s left) kms_cursor_legacy (forked-bo)
[709.817159] Starting subtest: forked-bo
[709.819051] Starting dynamic subtest: pipe-A
[715.354311] Dynamic subtest pipe-A: SUCCESS (5.480s)
[715.368001] Starting dynamic subtest: pipe-B
[720.834942] Dynamic subtest pipe-B: SUCCESS (5.478s)
[720.848402] Starting dynamic subtest: pipe-C
[726.332138] Dynamic subtest pipe-C: SUCCESS (5.492s)
[726.343959] Starting dynamic subtest: pipe-D
[731.813851] Dynamic subtest pipe-D: SUCCESS (5.478s)
[731.828847] Starting dynamic subtest: all-pipes
[737.343137] Dynamic subtest all-pipes: SUCCESS (5.523s)
[737.345819] Subtest forked-bo: SUCCESS (27.463s)
[737.433770] [102/133] (286s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[737.535085] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[737.539138] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[737.627776] [103/133] (286s left) gem_exec_schedule (preempt-other-chain)
[737.712014] Starting subtest: preempt-other-chain
[737.714357] Starting dynamic subtest: rcs0
[737.793182] Dynamic subtest rcs0: SUCCESS (0.087s)
[737.809653] Starting dynamic subtest: bcs0
[737.872154] Dynamic subtest bcs0: SUCCESS (0.078s)
[737.885956] Starting dynamic subtest: vcs0
[737.962364] Dynamic subtest vcs0: SUCCESS (0.089s)
[737.979251] Starting dynamic subtest: vcs1
[738.052757] Dynamic subtest vcs1: SUCCESS (0.089s)
[738.071259] Starting dynamic subtest: vecs0
[738.140194] Dynamic subtest vecs0: SUCCESS (0.087s)
[738.154948] Starting dynamic subtest: ccs0
[738.228228] Dynamic subtest ccs0: SUCCESS (0.088s)
[738.231389] Subtest preempt-other-chain: SUCCESS (0.523s)
[739.315531] [104/133] (285s left) kms_chamelium_audio (hdmi-audio-edid)
[739.721095] Subtest hdmi-audio-edid: SKIP (0.000s)
[740.523081] [105/133] (283s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[740.625285] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[747.592371] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.972s)
[747.707104] [106/133] (276s left) perf_pmu (busy-idle-no-semaphores)
[747.747454] Starting subtest: busy-idle-no-semaphores
[747.749686] Starting dynamic subtest: rcs0
[748.402414] Dynamic subtest rcs0: SUCCESS (0.655s)
[748.415734] Starting dynamic subtest: bcs0
[749.054528] Dynamic subtest bcs0: SUCCESS (0.650s)
[749.067687] Starting dynamic subtest: vcs0
[749.707186] Dynamic subtest vcs0: SUCCESS (0.650s)
[749.719079] Starting dynamic subtest: vcs1
[750.857447] Dynamic subtest vcs1: SUCCESS (1.146s)
[750.870254] Starting dynamic subtest: vecs0
[752.841454] Dynamic subtest vecs0: SUCCESS (1.982s)
[752.854772] Starting dynamic subtest: ccs0
[754.890810] Dynamic subtest ccs0: SUCCESS (2.046s)
[754.893673] Subtest busy-idle-no-semaphores: SUCCESS (7.146s)
[755.012133] [107/133] (269s left) kms_plane (plane-position-covered)
[755.122333] Starting subtest: plane-position-covered
[755.652217] Starting dynamic subtest: pipe-A-plane-1
[756.930307] Dynamic subtest pipe-A-plane-1: SUCCESS (1.279s)
[756.941264] Starting dynamic subtest: pipe-A-plane-2
[758.246405] Dynamic subtest pipe-A-plane-2: SUCCESS (1.314s)
[758.257645] Starting dynamic subtest: pipe-A-plane-3
[759.489266] Dynamic subtest pipe-A-plane-3: SUCCESS (1.243s)
[759.511212] Starting dynamic subtest: pipe-A-plane-4
[760.807731] Dynamic subtest pipe-A-plane-4: SUCCESS (1.317s)
[760.818935] Starting dynamic subtest: pipe-A-plane-5
[760.834959] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[761.992264] Starting dynamic subtest: pipe-B-plane-1
[763.278757] Dynamic subtest pipe-B-plane-1: SUCCESS (1.294s)
[763.289562] Starting dynamic subtest: pipe-B-plane-2
[764.504714] Dynamic subtest pipe-B-plane-2: SUCCESS (1.228s)
[764.516913] Starting dynamic subtest: pipe-B-plane-3
[765.737431] Dynamic subtest pipe-B-plane-3: SUCCESS (1.232s)
[765.747832] Starting dynamic subtest: pipe-B-plane-4
[767.002287] Dynamic subtest pipe-B-plane-4: SUCCESS (1.263s)
[767.014013] Starting dynamic subtest: pipe-B-plane-5
[767.028930] Dynamic subtest pipe-B-plane-5: SUCCESS (0.025s)
[767.030801] Subtest plane-position-covered: SUCCESS (11.907s)
[767.874324] [108/133] (256s left) kms_lease (implicit-plane-lease)
[767.975233] Starting subtest: implicit-plane-lease
[767.977534] Subtest implicit-plane-lease: SUCCESS (0.002s)
[768.095259] [109/133] (256s left) gem_madvise (dontneed-after-mmap)
[768.133118] Starting subtest: dontneed-after-mmap
[768.157064] Subtest dontneed-after-mmap: SUCCESS (0.024s)
[768.273645] [110/133] (256s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[768.367883] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[768.371914] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[768.462557] [111/133] (256s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[768.545444] Starting subtest: fbcpsr-indfb-scaledprimary
[774.585838] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (6.044s)
[774.737819] [112/133] (249s left) api_intel_bb (offset-control)
[774.795532] Starting subtest: offset-control
[774.801013] Subtest offset-control: SUCCESS (0.008s)
[774.928299] [113/133] (249s left) kms_cursor_crc (cursor-offscreen-32x10)
[775.096329] Starting subtest: cursor-offscreen-32x10
[775.143629] Subtest cursor-offscreen-32x10: SKIP (0.043s)
[775.413440] [114/133] (249s left) gem_exec_fence (syncobj-repeat)
[775.850014] Starting subtest: syncobj-repeat
[776.033539] Subtest syncobj-repeat: SUCCESS (0.182s)
[777.326890] [115/133] (247s left) kms_cursor_crc (cursor-sliding-max-size)
[777.659674] Starting subtest: cursor-sliding-max-size
[777.666207] Subtest cursor-sliding-max-size: SKIP (0.000s)
[777.787828] [116/133] (247s left) gem_ctx_param (invalid-ctx-get)
[777.838198] Starting subtest: invalid-ctx-get
[777.840264] Subtest invalid-ctx-get: SUCCESS (0.000s)
[777.924778] [117/133] (246s left) kms_color (invalid-ctm-matrix-sizes)
[778.019181] Starting subtest: invalid-ctm-matrix-sizes
[778.021493] Starting dynamic subtest: pipe-A
[778.023733] Dynamic subtest pipe-A: SUCCESS (0.002s)
[778.033826] Starting dynamic subtest: pipe-B
[778.036049] Dynamic subtest pipe-B: SUCCESS (0.003s)
[778.045835] Starting dynamic subtest: pipe-C
[778.047751] Dynamic subtest pipe-C: SUCCESS (0.003s)
[778.057581] Starting dynamic subtest: pipe-D
[778.059766] Dynamic subtest pipe-D: SUCCESS (0.004s)
[778.061977] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.014s)
[778.107809] [118/133] (246s left) gen9_exec_parse (shadow-peek)
[778.165718] Subtest shadow-peek: SKIP (0.000s)
[778.232447] [119/133] (246s left) dumb_buffer (create-valid-dumb)
[778.295667] Starting subtest: create-valid-dumb
[778.297621] Subtest create-valid-dumb: SUCCESS (0.000s)
[778.408849] [120/133] (246s left) syncobj_wait (invalid-reset-one-illegal-handle)
[778.456379] Starting subtest: invalid-reset-one-illegal-handle
[778.458433] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[778.551852] [121/133] (246s left) perf_pmu (interrupts-sync)
[778.596829] Starting subtest: interrupts-sync
[779.681062] Subtest interrupts-sync: SUCCESS (1.083s)
[779.812266] [122/133] (245s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[780.190686] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[781.026995] [123/133] (244s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[781.156390] Starting subtest: basic-busy-flip-before-cursor-legacy
[781.161080] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[781.315606] [124/133] (243s left) syncobj_timeline (invalid-transfer-bad-pad)
[781.378133] Starting subtest: invalid-transfer-bad-pad
[781.380453] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[781.483317] [125/133] (243s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[781.583308] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[781.587398] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[781.653988] [126/133] (243s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[781.754879] Starting subtest: addfb25-y-tiled-small-legacy
[781.759290] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[781.870512] [127/133] (243s left) sriov_basic (bind-unbind-vf)
[781.931174] Starting subtest: bind-unbind-vf
[781.933654] Starting dynamic subtest: vf-1
[782.003499] Dynamic subtest vf-1: FAIL (0.028s)
[782.012984] Starting dynamic subtest: vf-2
[782.071209] Dynamic subtest vf-2: FAIL (0.004s)
[782.081766] Starting dynamic subtest: vf-3
[782.141806] Dynamic subtest vf-3: FAIL (0.004s)
[782.152799] Starting dynamic subtest: vf-4
[782.211340] Dynamic subtest vf-4: FAIL (0.003s)
[782.221745] Starting dynamic subtest: vf-5
[782.282225] Dynamic subtest vf-5: FAIL (0.003s)
[782.292972] Starting dynamic subtest: vf-6
[782.352248] Dynamic subtest vf-6: FAIL (0.003s)
[782.362892] Starting dynamic subtest: vf-7
[782.421611] Dynamic subtest vf-7: FAIL (0.003s)
[782.430809] Starting dynamic subtest: vf-random
[782.489147] Dynamic subtest vf-random: FAIL (0.003s)
[782.499200] Starting dynamic subtest: vf-last
[782.561377] Dynamic subtest vf-last: FAIL (0.004s)
[782.563125] Subtest bind-unbind-vf: FAIL (0.489s)
[782.599994] [128/133] (242s left) kms_psr (psr2-primary-mmap-gtt)
[782.703771] Starting subtest: psr2-primary-mmap-gtt
[782.705638] Starting dynamic subtest: eDP-1
[784.144400] Dynamic subtest eDP-1: SKIP (1.437s)
[784.149217] Subtest psr2-primary-mmap-gtt: SKIP (1.440s)
[784.298946] [129/133] (241s left) kms_big_fb (linear-32bpp-rotate-90)
[784.410366] Starting subtest: linear-32bpp-rotate-90
[784.430895] Subtest linear-32bpp-rotate-90: SKIP (0.020s)
[784.545225] [130/133] (240s left) gem_exec_schedule (fairslice-all)
[784.619706] Starting subtest: fairslice-all
[786.680395] Subtest fairslice-all: SUCCESS (2.063s)
[786.979215] [131/133] (238s left) syncobj_wait (single-wait-for-submit-signaled)
[787.041372] Starting subtest: single-wait-for-submit-signaled
[787.043723] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[787.144380] [132/133] (238s left) kms_pm_dc (dc5-dpms-negative)
[788.244243] Starting subtest: dc5-dpms-negative
[788.248649] Subtest dc5-dpms-negative: SKIP (0.000s)
[788.340563] [133/133] (237s left) kms_atomic_transition (plane-all-transition-nonblocking)
[788.447615] Starting subtest: plane-all-transition-nonblocking
[788.450010] Starting dynamic subtest: pipe-A-eDP-1
[790.940193] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[791.247426] Starting dynamic subtest: pipe-B-eDP-1
[794.507043] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.270s)
[794.805187] Subtest plane-all-transition-nonblocking: SUCCESS (6.361s)
[795.640593] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: kms_flip_scaled_crc
results: parsing output: 2/ for test: gem_reg_read
results: parsing output: 3/ for test: gem_lmem_swapping
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_ctx_isolation
results: parsing output: 8/ for test: kms_ccs
results: parsing output: 9/ for test: gem_create
results: parsing output: 10/ for test: syncobj_eventfd
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: fbdev
results: parsing output: 14/ for test: gem_exec_await
results: parsing output: 15/ for test: kms_cursor_legacy
results: parsing output: 16/ for test: i915_pm_rpm
results: parsing output: 17/ for test: gem_mmap_offset
results: parsing output: 18/ for test: kms_content_protection
results: parsing output: 19/ for test: kms_flip
results: parsing output: 20/ for test: gem_exec_store
results: parsing output: 21/ for test: gem_exec_balancer
results: parsing output: 22/ for test: gen9_exec_parse
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: gem_spin_batch
results: parsing output: 26/ for test: kms_hdr
results: parsing output: 27/ for test: gem_fence_thrash
results: parsing output: 28/ for test: kms_pm_rpm
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_ccs
results: parsing output: 31/ for test: gem_exec_schedule
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: api_intel_bb
results: parsing output: 34/ for test: kms_feature_discovery
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: kms_busy
results: parsing output: 37/ for test: kms_psr
results: parsing output: 38/ for test: kms_setmode
results: parsing output: 39/ for test: sw_sync
results: parsing output: 40/ for test: syncobj_basic
results: parsing output: 41/ for test: i915_selftest
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: i915_drm_fdinfo
results: parsing output: 44/ for test: vgem_basic
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: syncobj_wait
results: parsing output: 47/ for test: drm_read
results: parsing output: 48/ for test: kms_plane
results: parsing output: 49/ for test: gem_userptr_blits
results: parsing output: 50/ for test: kms_vblank
results: parsing output: 51/ for test: gem_userptr_blits
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_properties
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: kms_plane
results: parsing output: 57/ for test: kms_vrr
results: parsing output: 58/ for test: kms_flip
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: kms_vblank
results: parsing output: 62/ for test: kms_addfb_basic
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_ctx_engines
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: kms_big_fb
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_exec_reloc
results: parsing output: 72/ for test: gem_eio
results: parsing output: 73/ for test: kms_chamelium_edid
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: gem_userptr_blits
results: parsing output: 76/ for test: gem_userptr_blits
results: parsing output: 77/ for test: gem_lmem_swapping
results: parsing output: 78/ for test: syncobj_wait
results: parsing output: 79/ for test: kms_prop_blob
results: parsing output: 80/ for test: gem_flink_basic
results: parsing output: 81/ for test: gem_exec_basic
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_plane_scaling
results: parsing output: 84/ for test: gem_ctx_persistence
results: parsing output: 85/ for test: kms_cursor_crc
results: parsing output: 86/ for test: kms_panel_fitting
results: parsing output: 87/ for test: gem_pxp
results: parsing output: 88/ for test: gem_render_copy
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: gem_render_copy
results: parsing output: 91/ for test: syncobj_timeline
results: parsing output: 92/ for test: kms_atomic_interruptible
results: parsing output: 93/ for test: kms_atomic
results: parsing output: 94/ for test: kms_psr2_sf
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_plane_alpha_blend
results: parsing output: 97/ for test: kms_getfb
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_exec_flush
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: gem_exec_schedule
results: parsing output: 103/ for test: kms_chamelium_audio
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: perf_pmu
results: parsing output: 106/ for test: kms_plane
results: parsing output: 107/ for test: kms_lease
results: parsing output: 108/ for test: gem_madvise
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: api_intel_bb
results: parsing output: 112/ for test: kms_cursor_crc
results: parsing output: 113/ for test: gem_exec_fence
results: parsing output: 114/ for test: kms_cursor_crc
results: parsing output: 115/ for test: gem_ctx_param
results: parsing output: 116/ for test: kms_color
results: parsing output: 117/ for test: gen9_exec_parse
results: parsing output: 118/ for test: dumb_buffer
results: parsing output: 119/ for test: syncobj_wait
results: parsing output: 120/ for test: perf_pmu
results: parsing output: 121/ for test: kms_chamelium_hpd
results: parsing output: 122/ for test: kms_cursor_legacy
results: parsing output: 123/ for test: syncobj_timeline
results: parsing output: 124/ for test: kms_psr2_sf
results: parsing output: 125/ for test: kms_addfb_basic
results: parsing output: 126/ for test: sriov_basic
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: kms_big_fb
results: parsing output: 129/ for test: gem_exec_schedule
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: kms_pm_dc
results: parsing output: 132/ for test: kms_atomic_transition
Done.