[143.540562] Initializing watchdogs
[143.540605]   /dev/watchdog0
[143.569229] [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]
[143.573565] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[143.575724] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[143.578693] [001/156] (960s left) gem_exec_schedule (fairslice-all)
[143.669710] Starting subtest: fairslice-all
[145.752837] Subtest fairslice-all: SUCCESS (2.082s)
[147.342945] [002/156] (956s left) gem_tiled_wc
[147.461193] [003/156] (956s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-cpu)
[147.573257] Starting subtest: fbchdr-rgb565-draw-mmap-cpu
[147.577122] Subtest fbchdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[147.667380] [004/156] (956s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-4)
[147.759634] Starting subtest: fbcpsrhdr-tiling-4
[147.764067] Subtest fbcpsrhdr-tiling-4: SKIP (0.000s)
[147.836325] [005/156] (956s left) prime_self_import (basic-llseek-size)
[147.870813] Starting subtest: basic-llseek-size
[147.890234] Subtest basic-llseek-size: SUCCESS (0.020s)
[148.004828] [006/156] (956s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[148.099142] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[152.107719] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.014s)
[153.290039] [007/156] (951s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[153.413679] Starting subtest: basic-flip-after-cursor-varying-size
[154.368745] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.955s)
[155.194126] [008/156] (949s left) kms_dirtyfb (default-dirtyfb-ioctl)
[155.283561] Starting subtest: default-dirtyfb-ioctl
[155.285907] Starting dynamic subtest: A-eDP-1
[156.901409] Dynamic subtest A-eDP-1: SUCCESS (1.616s)
[156.903432] Subtest default-dirtyfb-ioctl: SUCCESS (1.617s)
[157.730722] [009/156] (946s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[157.836423] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[164.575564] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.746s)
[164.707460] [010/156] (939s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[164.770829] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[164.773153] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[164.884870] [011/156] (939s left) api_intel_allocator (fork-simple-stress)
[164.940749] Starting subtest: fork-simple-stress
[170.342915] Subtest fork-simple-stress: SUCCESS (5.403s)
[170.471649] [012/156] (934s left) gem_exec_flush (basic-uc-set-default)
[170.556899] Starting subtest: basic-uc-set-default
[176.039193] Subtest basic-uc-set-default: SUCCESS (5.481s)
[176.239217] [013/156] (928s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[176.345374] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[176.347706] Starting dynamic subtest: pipe-A-eDP-1
[176.447612] Dynamic subtest pipe-A-eDP-1: SKIP (0.104s)
[176.459907] Starting dynamic subtest: pipe-B-eDP-1
[177.618649] Dynamic subtest pipe-B-eDP-1: SKIP (1.168s)
[177.623545] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.276s)
[178.823127] [014/156] (925s left) kms_psr (psr2-cursor-plane-move)
[178.913778] Starting subtest: psr2-cursor-plane-move
[178.916162] Starting dynamic subtest: eDP-1
[180.593439] Dynamic subtest eDP-1: SUCCESS (1.681s)
[180.595874] Subtest psr2-cursor-plane-move: SUCCESS (1.681s)
[181.510660] [015/156] (923s left) syncobj_timeline (wait-for-submit-complex)
[181.556084] Starting subtest: wait-for-submit-complex
[181.616294] Subtest wait-for-submit-complex: SUCCESS (0.061s)
[181.693786] [016/156] (923s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-move)
[181.804170] Starting subtest: hdr-2p-scndscrn-cur-indfb-move
[181.808225] Subtest hdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[181.908315] [017/156] (922s left) kms_pm_backlight (fade)
[182.004053] Starting subtest: fade
[182.049337] Starting dynamic subtest: eDP-1
[185.037836] Dynamic subtest eDP-1: SUCCESS (2.987s)
[185.040213] Subtest fade: SUCCESS (3.035s)
[185.912221] [018/156] (918s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[186.018502] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[186.022627] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[186.103215] [019/156] (918s left) gem_caching (reads)
[186.162399] Subtest reads: SKIP (0.000s)
[186.237919] [020/156] (918s left) kms_flip_event_leak (basic)
[186.320793] Starting subtest: basic
[186.323079] Starting dynamic subtest: pipe-A-eDP-1
[186.752521] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.432s)
[186.762103] Starting dynamic subtest: pipe-B-eDP-1
[187.837483] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.084s)
[187.846777] Starting dynamic subtest: pipe-C-eDP-1
[188.957416] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.118s)
[188.965028] Starting dynamic subtest: pipe-D-eDP-1
[190.084354] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[190.086296] Subtest basic: SUCCESS (3.763s)
[190.941180] [021/156] (913s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[191.036587] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[191.039035] Starting dynamic subtest: pipe-A-eDP-1
[191.063437] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[191.073218] Starting dynamic subtest: pipe-B-eDP-1
[191.095304] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[191.103227] Starting dynamic subtest: pipe-C-eDP-1
[191.125107] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[191.132976] Starting dynamic subtest: pipe-D-eDP-1
[191.155818] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[191.160054] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[193.306953] [022/156] (911s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[193.411336] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[193.415616] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[193.474892] [023/156] (911s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-move)
[193.569642] Starting subtest: psrhdr-2p-primscrn-cur-indfb-move
[193.574089] Subtest psrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[193.670047] [024/156] (911s left) kms_joiner (invalid-modeset-ultra-joiner)
[193.774523] Starting subtest: invalid-modeset-ultra-joiner
[193.779084] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[193.835831] [025/156] (911s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-move)
[193.924924] Starting subtest: hdr-1p-primscrn-cur-indfb-move
[193.929128] Subtest hdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[194.013027] [026/156] (911s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[194.117274] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[197.994575] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (3.882s)
[199.141686] [027/156] (905s left) kms_frontbuffer_tracking (psr-slowdraw)
[199.244136] Starting subtest: psr-slowdraw
[224.391694] Subtest psr-slowdraw: SUCCESS (25.151s)
[224.523228] [028/156] (880s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[224.582067] Starting subtest: invalid-single-wait-unsubmitted
[224.584193] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[224.690025] [029/156] (880s left) perf (polling)
[224.805607] Starting subtest: polling
[224.807926] Starting dynamic subtest: 0-rcs0
[234.832356] Dynamic subtest 0-rcs0: SUCCESS (10.026s)
[234.841207] Starting dynamic subtest: 1-vcs0
[244.857459] Dynamic subtest 1-vcs0: SUCCESS (10.024s)
[244.860043] Subtest polling: SUCCESS (20.051s)
[244.918038] [030/156] (860s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[245.024855] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[245.037702] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.010s)
[245.136808] [031/156] (860s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[245.246530] Starting subtest: cursorA-vs-flipA-varying-size
[256.395502] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.147s)
[257.280598] [032/156] (848s left) gem_lmem_swapping (heavy-verify-random)
[257.328800] Subtest heavy-verify-random: SKIP (0.000s)
[257.364258] [033/156] (847s left) prime_vgem (wait)
[257.477788] Starting subtest: wait
[257.480622] Starting dynamic subtest: rcs0
[257.519670] Dynamic subtest rcs0: SUCCESS (0.042s)
[257.527805] Starting dynamic subtest: bcs0
[257.584988] Dynamic subtest bcs0: SUCCESS (0.065s)
[257.593300] Starting dynamic subtest: vcs0
[257.633728] Dynamic subtest vcs0: SUCCESS (0.048s)
[257.642192] Starting dynamic subtest: vcs1
[257.679644] Dynamic subtest vcs1: SUCCESS (0.045s)
[257.688082] Starting dynamic subtest: vecs0
[257.725189] Dynamic subtest vecs0: SUCCESS (0.045s)
[257.733919] Starting dynamic subtest: ccs0
[257.769887] Dynamic subtest ccs0: SUCCESS (0.044s)
[257.772039] Subtest wait: SUCCESS (0.293s)
[257.894926] [FACT prime_vgem (wait)] new: kernel.kmod_is_loaded.vgem: true
[257.898267] [034/156] (847s left) kms_plane_alpha_blend (alpha-basic)
[258.002528] Starting subtest: alpha-basic
[258.295973] Starting dynamic subtest: pipe-A-eDP-1
[260.219395] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.919s)
[260.242285] Starting dynamic subtest: pipe-D-eDP-1
[262.979193] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.743s)
[262.981473] Subtest alpha-basic: SUCCESS (4.978s)
[263.839337] [035/156] (841s left) i915_drm_fdinfo (context-close-stress)
[263.882688] Starting subtest: context-close-stress
[274.627640] Subtest context-close-stress: SUCCESS (10.743s)
[274.718011] [036/156] (830s left) gem_eio (throttle)
[275.112937] Starting subtest: throttle
[275.451392] Subtest throttle: SUCCESS (0.337s)
[275.607599] [037/156] (829s left) perf_pmu (enable-race)
[275.649946] Starting subtest: enable-race
[275.651872] Starting dynamic subtest: rcs0
[288.420083] Dynamic subtest rcs0: SUCCESS (12.772s)
[288.430938] Starting dynamic subtest: bcs0
[301.161314] Dynamic subtest bcs0: SUCCESS (12.739s)
[301.172036] Starting dynamic subtest: vcs0
[316.291770] Dynamic subtest vcs0: SUCCESS (15.126s)
[316.301479] Starting dynamic subtest: vcs1
[332.290603] Dynamic subtest vcs1: SUCCESS (15.996s)
[332.303203] Starting dynamic subtest: vecs0
[348.291655] Dynamic subtest vecs0: SUCCESS (15.998s)
[348.301598] Starting dynamic subtest: ccs0
[361.036043] Dynamic subtest ccs0: SUCCESS (12.744s)
[361.038508] Subtest enable-race: SUCCESS (85.389s)
[361.145331] [038/156] (744s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[361.245570] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[361.249785] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[361.352860] [039/156] (744s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[361.401413] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[361.504047] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.102s)
[361.604169] [040/156] (744s left) gem_eio (wait-wedge-10ms)
[361.813744] Starting subtest: wait-wedge-10ms
[363.342258] Subtest wait-wedge-10ms: SUCCESS (1.528s)
[363.488224] [041/156] (742s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[363.595359] Starting subtest: yf-tiled-8bpp-rotate-90
[363.599803] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[363.693925] [042/156] (742s left) gem_softpin (allocator-basic-reserve)
[363.746060] Starting subtest: allocator-basic-reserve
[363.817329] Subtest allocator-basic-reserve: SUCCESS (0.071s)
[363.929384] [043/156] (741s left) gem_exec_balancer (bonded-pair)
[363.983496] Starting subtest: bonded-pair
[363.988145] Subtest bonded-pair: SKIP (0.000s)
[364.281425] [044/156] (741s left) kms_chamelium_audio (dp-audio)
[364.657172] Subtest dp-audio: SKIP (0.000s)
[365.444445] [045/156] (740s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[365.719864] Starting subtest: cursor-rapid-movement-256x85
[365.764071] Subtest cursor-rapid-movement-256x85: SKIP (0.040s)
[365.959491] [046/156] (739s left) i915_suspend (basic-s2idle-without-i915)
[365.999464] Starting subtest: basic-s2idle-without-i915
[387.337554] Subtest basic-s2idle-without-i915: SUCCESS (5.735s)
[387.464084] [047/156] (718s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[387.541954] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[387.544384] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[387.626772] [048/156] (718s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-render)
[387.723159] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-render
[387.727499] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[387.821115] [049/156] (718s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[387.921047] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[387.925417] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[388.014132] [050/156] (718s left) gem_mmap (pf-nonblock)
[388.072306] Subtest pf-nonblock: SKIP (0.000s)
[388.130240] [051/156] (717s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[388.225329] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[388.228642] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[388.322536] [052/156] (717s left) gem_basic (multigpu-create-close)
[388.373050] Starting subtest: multigpu-create-close
[388.376951] Subtest multigpu-create-close: SKIP (0.000s)
[388.477639] [053/156] (717s left) kms_frontbuffer_tracking (fbchdr-2p-shrfb-fliptrack-mmap-gtt)
[388.572880] Starting subtest: fbchdr-2p-shrfb-fliptrack-mmap-gtt
[388.576297] Subtest fbchdr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[388.663304] [054/156] (717s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[388.749539] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[392.867439] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (4.152s)
[394.090895] [055/156] (712s left) gem_exec_params (no-vebox)
[394.147246] Starting subtest: no-vebox
[394.152331] Subtest no-vebox: SUCCESS (0.002s)
[395.979482] [056/156] (710s left) gem_exec_schedule (smoketest-all)
[396.055966] Starting subtest: smoketest-all
[428.392676] Subtest smoketest-all: SUCCESS (32.321s)
[429.936541] [057/156] (676s left) kms_properties (connector-properties-legacy)
[430.040461] Starting subtest: connector-properties-legacy
[430.042655] Starting dynamic subtest: pipe-A-eDP-1
[437.815837] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.773s)
[437.827117] Starting dynamic subtest: pipe-None-DP-1
[437.831258] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[437.839551] Starting dynamic subtest: pipe-None-HDMI-A-1
[437.843732] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[437.851750] Starting dynamic subtest: pipe-None-DP-2
[437.855695] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[437.863976] Starting dynamic subtest: pipe-None-HDMI-A-2
[437.868309] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[437.876985] Starting dynamic subtest: pipe-None-DP-3
[437.881316] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[437.889966] Starting dynamic subtest: pipe-None-DP-4
[437.894145] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[437.896229] Subtest connector-properties-legacy: SUCCESS (7.785s)
[438.685129] [058/156] (667s left) gem_userptr_blits (set-cache-level)
[438.755762] Starting subtest: set-cache-level
[438.759917] Subtest set-cache-level: SKIP (0.000s)
[438.885717] [059/156] (667s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[438.981077] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[438.983360] Starting dynamic subtest: pipe-A-default-mode
[441.702523] Dynamic subtest pipe-A-default-mode: SUCCESS (2.721s)
[441.705001] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.722s)
[442.566695] [060/156] (663s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[442.623888] Subtest tiled-to-tiled: SKIP (0.000s)
[442.710233] [061/156] (663s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[442.802823] Starting subtest: plane-lut1d-ctm3x4
[442.927340] Starting dynamic subtest: pipe-A-plane-0
[443.083070] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[443.092212] Starting dynamic subtest: pipe-A-plane-1
[443.227080] Dynamic subtest pipe-A-plane-1: SUCCESS (0.144s)
[443.236726] Starting dynamic subtest: pipe-A-plane-2
[443.360310] Dynamic subtest pipe-A-plane-2: SUCCESS (0.132s)
[444.575675] Starting dynamic subtest: pipe-B-plane-0
[444.723397] Dynamic subtest pipe-B-plane-0: SUCCESS (0.155s)
[444.733837] Starting dynamic subtest: pipe-B-plane-1
[444.856646] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[444.867108] Starting dynamic subtest: pipe-B-plane-2
[445.001276] Dynamic subtest pipe-B-plane-2: SUCCESS (0.144s)
[446.210244] Starting dynamic subtest: pipe-C-plane-0
[446.335711] Dynamic subtest pipe-C-plane-0: SUCCESS (0.132s)
[446.345383] Starting dynamic subtest: pipe-C-plane-1
[446.468702] Dynamic subtest pipe-C-plane-1: SUCCESS (0.133s)
[446.478434] Starting dynamic subtest: pipe-C-plane-2
[446.602178] Dynamic subtest pipe-C-plane-2: SUCCESS (0.132s)
[447.836044] Starting dynamic subtest: pipe-D-plane-0
[447.971044] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[447.980783] Starting dynamic subtest: pipe-D-plane-1
[448.115303] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[448.128142] Starting dynamic subtest: pipe-D-plane-2
[448.259564] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[448.552284] Subtest plane-lut1d-ctm3x4: SUCCESS (5.752s)
[449.395305] [062/156] (657s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[449.492669] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[454.064145] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.579s)
[455.208173] [063/156] (651s left) gem_userptr_blits (sync-unmap-after-close)
[455.260381] Starting subtest: sync-unmap-after-close
[455.270474] Subtest sync-unmap-after-close: SUCCESS (0.011s)
[455.404895] [064/156] (651s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[455.503035] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[459.431630] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.935s)
[460.585461] [065/156] (646s left) gem_exec_schedule (thriceslice)
[460.658266] Starting subtest: thriceslice
[460.660235] Starting dynamic subtest: rcs0
[460.677306] Dynamic subtest rcs0: SUCCESS (0.030s)
[460.686983] Starting dynamic subtest: bcs0
[460.705719] Dynamic subtest bcs0: SUCCESS (0.028s)
[460.717947] Starting dynamic subtest: vcs0
[460.758624] Dynamic subtest vcs0: SUCCESS (0.050s)
[460.770750] Starting dynamic subtest: vcs1
[460.788424] Dynamic subtest vcs1: SUCCESS (0.029s)
[460.799609] Starting dynamic subtest: vecs0
[460.816321] Dynamic subtest vecs0: SUCCESS (0.028s)
[460.826849] Starting dynamic subtest: ccs0
[460.847345] Dynamic subtest ccs0: SUCCESS (0.030s)
[460.849595] Subtest thriceslice: SUCCESS (0.201s)
[461.150414] [066/156] (645s left) gem_render_copy_redux (interruptible)
[461.201776] Starting subtest: interruptible
[461.452056] Subtest interruptible: SUCCESS (0.253s)
[461.558419] [067/156] (645s left) i915_pm_rps (fence-order)
[461.605820] Starting subtest: fence-order
[464.624811] Subtest fence-order: SUCCESS (3.018s)
[464.747685] [068/156] (642s left) kms_content_protection (legacy-hdcp14)
[464.895111] Starting subtest: legacy-hdcp14
[464.899289] Subtest legacy-hdcp14: SKIP (0.000s)
[465.025163] [069/156] (641s left) gem_tiled_blits (basic)
[465.077479] Subtest basic: SKIP (0.000s)
[465.150014] [070/156] (641s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-plflip-blt)
[465.248484] Starting subtest: psrhdr-2p-primscrn-shrfb-plflip-blt
[465.252791] Subtest psrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[465.370650] [071/156] (641s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[465.465075] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[472.525734] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.069s)
[472.640371] [072/156] (634s left) kms_cursor_crc (cursor-offscreen-64x21)
[472.838612] Starting subtest: cursor-offscreen-64x21
[472.881141] Subtest cursor-offscreen-64x21: SKIP (0.040s)
[473.112435] [073/156] (633s left) kms_psr (fbc-psr-sprite-plane-onoff)
[473.209516] Starting subtest: fbc-psr-sprite-plane-onoff
[473.211859] Starting dynamic subtest: eDP-1
[473.216176] Dynamic subtest eDP-1: SKIP (0.000s)
[473.220391] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[473.281456] [074/156] (633s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[473.367798] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[473.370102] Starting dynamic subtest: pipe-A
[473.387858] Dynamic subtest pipe-A: SKIP (0.016s)
[473.403655] Starting dynamic subtest: pipe-B
[473.417154] Dynamic subtest pipe-B: SKIP (0.028s)
[473.427730] Starting dynamic subtest: pipe-C
[473.446783] Dynamic subtest pipe-C: SKIP (0.030s)
[473.456411] Starting dynamic subtest: pipe-D
[473.475491] Dynamic subtest pipe-D: SKIP (0.028s)
[473.479228] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.106s)
[473.568608] [075/156] (633s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[473.648068] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[473.650039] Starting dynamic subtest: pipe-A-eDP-1
[473.668669] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[473.679829] Starting dynamic subtest: pipe-B-eDP-1
[473.698012] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[473.706940] Starting dynamic subtest: pipe-C-eDP-1
[473.725455] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[473.734661] Starting dynamic subtest: pipe-D-eDP-1
[473.753460] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[473.756895] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[475.969999] [076/156] (631s left) core_setmaster (master-drop-set-shared-fd)
[476.058953] Starting subtest: master-drop-set-shared-fd
[476.064181] Subtest master-drop-set-shared-fd: SUCCESS (0.010s)
[476.102214] [077/156] (631s left) syncobj_wait (wait-all-interrupted)
[476.150632] Starting subtest: wait-all-interrupted
[476.259991] Subtest wait-all-interrupted: SUCCESS (0.108s)
[476.353357] [078/156] (630s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[476.449632] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[476.453804] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[476.534286] [079/156] (630s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-render)
[476.638915] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-render
[476.642830] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[476.757376] [080/156] (630s left) perf_pmu (rc6)
[476.795245] Starting subtest: rc6
[476.797335] Starting dynamic subtest: gt0
[483.163719] Dynamic subtest gt0: SUCCESS (6.368s)
[483.177131] Starting dynamic subtest: runtime-pm-gt0
[490.953949] Dynamic subtest runtime-pm-gt0: SUCCESS (7.786s)
[490.966423] Starting dynamic subtest: runtime-pm-long-gt0
[503.475081] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.517s)
[503.487949] Starting dynamic subtest: other-idle-gt0
[509.818630] Dynamic subtest other-idle-gt0: SUCCESS (6.341s)
[509.831524] Starting dynamic subtest: gt1
[516.161101] Dynamic subtest gt1: SUCCESS (6.340s)
[516.172445] Starting dynamic subtest: runtime-pm-gt1
[523.596158] Dynamic subtest runtime-pm-gt1: SUCCESS (7.433s)
[523.608434] Starting dynamic subtest: runtime-pm-long-gt1
[536.077606] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.481s)
[536.089178] Starting dynamic subtest: other-idle-gt1
[542.421488] Dynamic subtest other-idle-gt1: SUCCESS (6.342s)
[542.424345] Subtest rc6: SUCCESS (65.628s)
[543.559176] [081/156] (563s left) kms_flip (2x-flip-vs-panning-vs-hang)
[543.663158] Starting subtest: 2x-flip-vs-panning-vs-hang
[543.667329] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.003s)
[543.769771] [082/156] (563s left) gen9_exec_parse (allowed-single)
[543.834016] Subtest allowed-single: SKIP (0.000s)
[543.919610] [083/156] (563s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[544.022837] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[544.026336] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[544.101073] [084/156] (563s left) kms_feature_discovery (psr2)
[544.195776] Starting subtest: psr2
[544.197657] Subtest psr2: SUCCESS (0.000s)
[544.242755] [085/156] (563s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[544.364407] Starting subtest: cursorB-vs-flipB-legacy
[544.368398] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[544.485130] [086/156] (563s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-cpu)
[544.586457] Starting subtest: hdr-rgb565-draw-mmap-cpu
[544.589875] Subtest hdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[544.666539] [087/156] (562s left) gem_exec_flush (basic-uc-rw-default)
[544.733505] Starting subtest: basic-uc-rw-default
[550.181705] Subtest basic-uc-rw-default: SUCCESS (5.451s)
[550.346426] [088/156] (557s left) gem_cs_tlb (engines)
[550.406088] Starting subtest: engines
[550.408333] Starting dynamic subtest: rcs0
[552.561566] Dynamic subtest rcs0: SUCCESS (2.152s)
[552.575446] Starting dynamic subtest: bcs0
[554.715922] Dynamic subtest bcs0: SUCCESS (2.152s)
[554.728230] Starting dynamic subtest: vcs0
[556.866988] Dynamic subtest vcs0: SUCCESS (2.152s)
[556.876541] Starting dynamic subtest: vcs1
[559.020229] Dynamic subtest vcs1: SUCCESS (2.152s)
[559.033461] Starting dynamic subtest: vecs0
[561.172799] Dynamic subtest vecs0: SUCCESS (2.151s)
[561.182260] Starting dynamic subtest: ccs0
[563.325650] Dynamic subtest ccs0: SUCCESS (2.153s)
[563.328046] Subtest engines: SUCCESS (12.921s)
[563.415523] [089/156] (544s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[563.988026] Starting subtest: syncobj-timeline-invalid-wait
[563.994621] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.006s)
[564.976067] [090/156] (542s left) i915_module_load (reload-no-display)
[565.010618] Starting subtest: reload-no-display
[572.066659] Subtest reload-no-display: SUCCESS (7.058s)
[572.093096] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[572.093814] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[572.096955] [091/156] (535s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[573.595793] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[573.600142] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[573.675195] [FACT kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[573.676479] [FACT kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)] new: kernel.kmod_is_loaded.i915: true
[573.680974] [092/156] (534s left) kms_cursor_legacy (forked-bo)
[573.802055] Starting subtest: forked-bo
[573.804335] Starting dynamic subtest: pipe-A
[579.260181] Dynamic subtest pipe-A: SUCCESS (5.456s)
[579.281367] Starting dynamic subtest: pipe-B
[584.724924] Dynamic subtest pipe-B: SUCCESS (5.464s)
[584.734198] Starting dynamic subtest: pipe-C
[590.192328] Dynamic subtest pipe-C: SUCCESS (5.466s)
[590.207953] Starting dynamic subtest: pipe-D
[595.657307] Dynamic subtest pipe-D: SUCCESS (5.465s)
[595.671796] Starting dynamic subtest: all-pipes
[601.157425] Dynamic subtest all-pipes: SUCCESS (5.499s)
[601.160023] Subtest forked-bo: SUCCESS (27.360s)
[601.283707] [093/156] (506s left) perf_pmu (busy-hang)
[601.330524] Starting subtest: busy-hang
[601.332626] Starting dynamic subtest: rcs0
[602.523520] Dynamic subtest rcs0: SUCCESS (1.191s)
[602.534989] Starting dynamic subtest: bcs0
[603.671376] Dynamic subtest bcs0: SUCCESS (1.149s)
[603.683698] Starting dynamic subtest: vcs0
[605.434867] Dynamic subtest vcs0: SUCCESS (1.759s)
[605.444318] Starting dynamic subtest: vcs1
[607.416650] Dynamic subtest vcs1: SUCCESS (1.984s)
[607.431069] Starting dynamic subtest: vecs0
[609.402824] Dynamic subtest vecs0: SUCCESS (1.981s)
[609.412329] Starting dynamic subtest: ccs0
[610.568171] Dynamic subtest ccs0: SUCCESS (1.166s)
[610.570895] Subtest busy-hang: SUCCESS (9.241s)
[610.646073] [094/156] (497s left) kms_hdr (bpc-switch-suspend)
[610.748766] Starting subtest: bpc-switch-suspend
[610.751102] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[631.980922] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (5.676s)
[631.992360] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[652.993801] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (5.728s)
[652.996785] Subtest bpc-switch-suspend: SUCCESS (11.409s)
[653.085223] [095/156] (454s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[653.190518] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[653.192913] Starting dynamic subtest: pipe-A-eDP-1
[654.033285] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.839s)
[654.043714] Starting dynamic subtest: pipe-B-eDP-1
[655.552917] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.522s)
[655.564029] Starting dynamic subtest: pipe-C-eDP-1
[657.083990] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.533s)
[657.095486] Starting dynamic subtest: pipe-D-eDP-1
[658.591287] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.518s)
[658.593972] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.417s)
[659.750816] [096/156] (448s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[659.807724] Starting subtest: invalid-multi-wait-unsubmitted
[659.810410] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[659.887555] [097/156] (448s left) gem_exec_balancer (smoke)
[659.942276] Starting subtest: smoke
[682.456756] Subtest smoke: SUCCESS (22.559s)
[683.173977] [098/156] (424s left) gem_exec_schedule (preempt-engines)
[683.248350] Starting subtest: preempt-engines
[683.251432] Starting dynamic subtest: rcs0
[685.571209] Dynamic subtest rcs0: SUCCESS (2.333s)
[685.584658] Starting dynamic subtest: bcs0
[687.886906] Dynamic subtest bcs0: SUCCESS (2.316s)
[687.902500] Starting dynamic subtest: vcs0
[690.187787] Dynamic subtest vcs0: SUCCESS (2.299s)
[690.201964] Starting dynamic subtest: vcs1
[692.617554] Dynamic subtest vcs1: SUCCESS (2.425s)
[692.628150] Starting dynamic subtest: vecs0
[694.947527] Dynamic subtest vecs0: SUCCESS (2.303s)
[694.959406] Starting dynamic subtest: ccs0
[697.246320] Dynamic subtest ccs0: SUCCESS (2.319s)
[697.249528] Subtest preempt-engines: SUCCESS (14.006s)
[697.586992] [099/156] (410s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[697.694958] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[697.700182] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[697.757695] [100/156] (410s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[697.818133] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[697.823485] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[697.988434] [101/156] (410s left) gem_ctx_persistence (processes)
[698.042811] Starting subtest: processes
[699.800758] Subtest processes: SUCCESS (1.760s)
[699.857152] [102/156] (408s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[699.955057] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-mmap-wc
[699.960209] Subtest fbchdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[700.017564] [103/156] (408s left) kms_chamelium_frames (dp-crc-fast)
[700.404786] Subtest dp-crc-fast: SKIP (0.000s)
[701.227005] [104/156] (407s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[701.319378] Starting subtest: fbc-pr-sprite-mmap-gtt
[701.324699] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[701.374543] [105/156] (406s left) gem_mmap_offset (bad-flags)
[701.421932] Starting subtest: bad-flags
[701.424421] Subtest bad-flags: SUCCESS (0.000s)
[701.499133] [106/156] (406s left) kms_getfb (getfb-handle-valid)
[701.589080] Starting subtest: getfb-handle-valid
[701.591747] Subtest getfb-handle-valid: SUCCESS (0.000s)
[701.664075] [107/156] (406s left) gem_mmap_gtt (cpuset-big-copy-xy)
[701.719043] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[701.788516] [108/156] (406s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[701.892784] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[701.897707] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[701.947641] [109/156] (406s left) core_getclient (basic)
[701.995336] Starting subtest: basic
[701.997708] Subtest basic: SUCCESS (0.000s)
[702.068064] [110/156] (406s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[702.170280] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[702.174748] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[702.245927] [111/156] (406s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt)
[702.343221] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt
[702.347317] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[702.415886] [112/156] (406s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[702.503220] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[709.587997] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.089s)
[709.693071] [113/156] (398s left) kms_sequence (queue-busy)
[709.787189] Starting subtest: queue-busy
[709.789985] Starting dynamic subtest: pipe-A-eDP-1
[711.722811] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.935s)
[711.734018] Starting dynamic subtest: pipe-B-eDP-1
[714.296468] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.574s)
[714.308951] Starting dynamic subtest: pipe-C-eDP-1
[716.909296] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.610s)
[716.920496] Starting dynamic subtest: pipe-D-eDP-1
[719.538529] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.629s)
[719.541421] Subtest queue-busy: SUCCESS (9.754s)
[720.415101] [114/156] (388s left) gem_eio (in-flight-internal-10ms)
[720.621773] Starting subtest: in-flight-internal-10ms
[721.890259] Subtest in-flight-internal-10ms: SUCCESS (1.268s)
[721.980023] [115/156] (386s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[722.076128] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[722.086682] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s)
[722.141424] [116/156] (386s left) kms_async_flips (crc)
[722.227150] Starting subtest: crc
[722.601773] Starting dynamic subtest: pipe-A-eDP-1
[725.547478] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.946s)
[725.938167] Starting dynamic subtest: pipe-B-eDP-1
[728.885534] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.956s)
[729.261059] Starting dynamic subtest: pipe-C-eDP-1
[732.204245] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.957s)
[732.567528] Starting dynamic subtest: pipe-D-eDP-1
[735.411465] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.857s)
[735.414026] Subtest crc: SUCCESS (13.190s)
[736.571619] [117/156] (372s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[736.668076] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[736.673128] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[736.727120] [118/156] (372s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[736.828437] Starting subtest: x-tiled-32bpp-rotate-180
[738.274021] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.445s)
[738.373243] [119/156] (370s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[738.474060] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[738.479421] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[738.551164] [120/156] (370s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[738.648253] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[739.616310] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.968s)
[739.719678] [121/156] (369s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[739.819437] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[739.825013] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[739.897172] [122/156] (369s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt)
[740.010025] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt
[740.014211] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[740.089275] [123/156] (369s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[740.185409] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[740.189744] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[740.257124] [124/156] (368s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[740.353395] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[740.358118] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[740.424822] [125/156] (368s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[740.520306] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[740.524898] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[740.597219] [126/156] (368s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[740.713531] Starting subtest: protected-encrypted-src-copy-not-readible
[740.823110] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.110s)
[740.914937] [127/156] (368s left) kms_psr (psr-cursor-render)
[741.005153] Starting subtest: psr-cursor-render
[741.007669] Starting dynamic subtest: eDP-1
[742.676469] Dynamic subtest eDP-1: SUCCESS (1.674s)
[742.679275] Subtest psr-cursor-render: SUCCESS (1.675s)
[743.525945] [128/156] (365s left) kms_psr (fbc-psr2-primary-render)
[743.617099] Starting subtest: fbc-psr2-primary-render
[743.619773] Starting dynamic subtest: eDP-1
[743.624485] Dynamic subtest eDP-1: SKIP (0.000s)
[743.629368] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[743.670737] [129/156] (365s left) gem_ctx_persistence (engines-hostile-preempt)
[743.734506] Starting subtest: engines-hostile-preempt
[743.737440] Starting dynamic subtest: rcs0
[743.803770] Dynamic subtest rcs0: SUCCESS (0.069s)
[743.815814] Starting dynamic subtest: bcs0
[743.844397] Dynamic subtest bcs0: SUCCESS (0.040s)
[743.854493] Starting dynamic subtest: vcs0
[745.721914] Dynamic subtest vcs0: SUCCESS (1.875s)
[745.733524] Starting dynamic subtest: vcs1
[747.794089] Dynamic subtest vcs1: SUCCESS (2.070s)
[747.807037] Starting dynamic subtest: vecs0
[749.775679] Dynamic subtest vecs0: SUCCESS (1.980s)
[749.789311] Starting dynamic subtest: ccs0
[749.840794] Dynamic subtest ccs0: SUCCESS (0.063s)
[749.843718] Subtest engines-hostile-preempt: SUCCESS (6.109s)
[749.899349] [130/156] (359s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[750.004852] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[750.010256] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[750.090129] [131/156] (359s left) kms_plane_multiple (2x-tiling-x)
[750.184699] Starting subtest: 2x-tiling-x
[750.189694] Subtest 2x-tiling-x: SKIP (0.000s)
[750.235441] [132/156] (359s left) sysfs_heartbeat_interval (invalid)
[750.286414] Starting subtest: invalid
[750.289086] Starting dynamic subtest: vecs0
[750.291820] Dynamic subtest vecs0: SUCCESS (0.000s)
[750.304008] Starting dynamic subtest: ccs0
[750.306298] Dynamic subtest ccs0: SUCCESS (0.000s)
[750.316271] Starting dynamic subtest: vcs0
[750.318393] Dynamic subtest vcs0: SUCCESS (0.000s)
[750.328509] Starting dynamic subtest: bcs0
[750.330772] Dynamic subtest bcs0: SUCCESS (0.000s)
[750.341465] Starting dynamic subtest: rcs0
[750.344231] Dynamic subtest rcs0: SUCCESS (0.000s)
[750.355059] Starting dynamic subtest: vcs1
[750.357515] Dynamic subtest vcs1: SUCCESS (0.000s)
[750.360139] Subtest invalid: SUCCESS (0.005s)
[750.401694] [133/156] (359s left) gem_exec_reloc (basic-cpu-read-active)
[750.461741] Subtest basic-cpu-read-active: SKIP (0.000s)
[750.534531] [134/156] (359s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[750.639908] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[750.644899] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[750.708123] [135/156] (358s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[751.075244] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[751.946588] [136/156] (357s left) gem_mmap_wc (set-cache-level)
[751.998071] Subtest set-cache-level: SKIP (0.000s)
[752.065500] [137/156] (357s left) kms_pm_rpm (universal-planes-dpms)
[753.895162] Starting subtest: universal-planes-dpms
[753.897891] Starting dynamic subtest: plane-35
[762.232312] Dynamic subtest plane-35: SUCCESS (8.336s)
[762.246335] Starting dynamic subtest: plane-65
[770.582411] Dynamic subtest plane-65: SUCCESS (8.345s)
[770.597253] Starting dynamic subtest: plane-95
[779.209572] Dynamic subtest plane-95: SUCCESS (8.623s)
[779.222810] Starting dynamic subtest: plane-125
[787.527360] Dynamic subtest plane-125: SUCCESS (8.314s)
[787.540262] Starting dynamic subtest: plane-135
[795.886698] Dynamic subtest plane-135: SUCCESS (8.356s)
[795.901809] Starting dynamic subtest: plane-145
[804.385685] Dynamic subtest plane-145: SUCCESS (8.496s)
[804.388619] Subtest universal-planes-dpms: SUCCESS (50.487s)
[804.763148] [138/156] (304s left) gem_exec_alignment (many)
[804.818898] Starting subtest: many
[828.627131] Subtest many: SUCCESS (23.809s)
[829.863911] [139/156] (279s left) gem_exec_params (invalid-bsd1-flag-on-render)
[829.983743] Starting subtest: invalid-bsd1-flag-on-render
[829.986385] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[830.063776] [140/156] (279s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[830.163277] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-mmap-wc
[830.168306] Subtest hdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[830.233771] [141/156] (279s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[830.349299] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[830.353742] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[830.425924] [142/156] (279s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[830.515916] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[830.520703] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[830.599324] [143/156] (279s left) kms_atomic (plane-overlay-legacy)
[830.696355] Starting subtest: plane-overlay-legacy
[830.698539] Starting dynamic subtest: pipe-A-eDP-1
[831.134348] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.440s)
[831.136978] Subtest plane-overlay-legacy: SUCCESS (0.442s)
[831.994983] [144/156] (278s left) gem_eio (wait-1us)
[832.209326] Starting subtest: wait-1us
[832.542230] Subtest wait-1us: SUCCESS (0.333s)
[832.640164] [145/156] (277s left) perf (short-reads)
[832.758722] Starting subtest: short-reads
[832.776200] Subtest short-reads: SUCCESS (0.017s)
[832.831840] [146/156] (277s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[832.920480] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[832.922726] Starting dynamic subtest: pipe-A-eDP-1
[835.022061] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.103s)
[835.032853] Starting dynamic subtest: pipe-B-eDP-1
[837.099262] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.076s)
[837.109727] Starting dynamic subtest: pipe-C-eDP-1
[839.171073] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.068s)
[839.182911] Starting dynamic subtest: pipe-D-eDP-1
[841.204467] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.035s)
[841.206886] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.287s)
[842.353870] [147/156] (267s left) kms_lease (lease-unleased-connector)
[842.457093] Starting subtest: lease-unleased-connector
[842.459529] Starting dynamic subtest: pipe-A-eDP-1
[842.462033] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[842.476095] Starting dynamic subtest: pipe-B-eDP-1
[842.478474] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[842.490106] Starting dynamic subtest: pipe-C-eDP-1
[842.492522] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.008s)
[842.503501] Starting dynamic subtest: pipe-D-eDP-1
[842.506234] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[842.508925] Subtest lease-unleased-connector: SUCCESS (0.033s)
[842.577197] [148/156] (267s left) kms_big_fb (linear-addfb)
[842.675692] Starting subtest: linear-addfb
[842.678313] Subtest linear-addfb: SUCCESS (0.000s)
[842.754056] [149/156] (267s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[842.858248] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[842.863167] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[842.925877] [150/156] (267s left) kms_pm_dc (dc5-dpms-negative)
[844.023288] Starting subtest: dc5-dpms-negative
[844.028533] Subtest dc5-dpms-negative: SKIP (0.000s)
[844.073688] [151/156] (266s left) gem_exec_store (dword)
[844.131509] Starting subtest: dword
[844.134415] Starting dynamic subtest: rcs0
[844.212020] Dynamic subtest rcs0: SUCCESS (0.081s)
[844.223507] Starting dynamic subtest: bcs0
[844.253496] Dynamic subtest bcs0: SUCCESS (0.041s)
[844.268062] Starting dynamic subtest: vcs0
[844.298159] Dynamic subtest vcs0: SUCCESS (0.042s)
[844.309886] Starting dynamic subtest: vcs1
[844.343214] Dynamic subtest vcs1: SUCCESS (0.043s)
[844.358240] Starting dynamic subtest: vecs0
[844.386906] Dynamic subtest vecs0: SUCCESS (0.041s)
[844.400738] Starting dynamic subtest: ccs0
[844.430695] Dynamic subtest ccs0: SUCCESS (0.041s)
[844.433415] Subtest dword: SUCCESS (0.304s)
[844.594994] [152/156] (265s left) kms_vblank (wait-forked)
[844.688782] Starting subtest: wait-forked
[844.691288] Starting dynamic subtest: pipe-A-eDP-1
[846.510185] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.820s)
[846.527091] Starting dynamic subtest: pipe-D-eDP-1
[849.060346] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.550s)
[849.063348] Subtest wait-forked: SUCCESS (4.374s)
[849.923262] [153/156] (260s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[850.022170] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[851.012282] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.990s)
[851.114013] [154/156] (259s left) kms_psr (psr-sprite-mmap-gtt)
[851.213162] Starting subtest: psr-sprite-mmap-gtt
[851.215903] Starting dynamic subtest: eDP-1
[852.641671] Dynamic subtest eDP-1: SKIP (1.425s)
[852.646931] Subtest psr-sprite-mmap-gtt: SKIP (1.427s)
[852.768257] [155/156] (257s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[852.883055] Starting subtest: cursorA-vs-flipB-atomic-transitions
[852.888097] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[853.008397] [156/156] (257s left) gem_ctx_param (set-priority-not-supported)
[853.060328] Starting subtest: set-priority-not-supported
[853.065234] Subtest set-priority-not-supported: SKIP (0.000s)
[853.112170] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_schedule
results: parsing output: 1/ for test: gem_tiled_wc
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: prime_self_import
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: kms_dirtyfb
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: api_intel_allocator
results: parsing output: 11/ for test: gem_exec_flush
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_psr
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_pm_backlight
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_caching
results: parsing output: 19/ for test: kms_flip_event_leak
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: kms_psr2_sf
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_joiner
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: syncobj_timeline
results: parsing output: 28/ for test: perf
results: parsing output: 29/ for test: kms_flip
results: parsing output: 30/ for test: kms_cursor_legacy
results: parsing output: 31/ for test: gem_lmem_swapping
results: parsing output: 32/ for test: prime_vgem
results: parsing output: 33/ for test: kms_plane_alpha_blend
results: parsing output: 34/ for test: i915_drm_fdinfo
results: parsing output: 35/ for test: gem_eio
results: parsing output: 36/ for test: perf_pmu
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: syncobj_timeline
results: parsing output: 39/ for test: gem_eio
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: gem_softpin
results: parsing output: 42/ for test: gem_exec_balancer
results: parsing output: 43/ for test: kms_chamelium_audio
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: i915_suspend
results: parsing output: 46/ for test: syncobj_timeline
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_mmap
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_basic
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_exec_params
results: parsing output: 55/ for test: gem_exec_schedule
results: parsing output: 56/ for test: kms_properties
results: parsing output: 57/ for test: gem_userptr_blits
results: parsing output: 58/ for test: kms_flip_scaled_crc
results: parsing output: 59/ for test: gem_set_tiling_vs_blt
results: parsing output: 60/ for test: kms_color_pipeline
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_userptr_blits
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_exec_schedule
results: parsing output: 65/ for test: gem_render_copy_redux
results: parsing output: 66/ for test: i915_pm_rps
results: parsing output: 67/ for test: kms_content_protection
results: parsing output: 68/ for test: gem_tiled_blits
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: kms_psr
results: parsing output: 73/ for test: kms_plane_scaling
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: core_setmaster
results: parsing output: 76/ for test: syncobj_wait
results: parsing output: 77/ for test: kms_plane
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: perf_pmu
results: parsing output: 80/ for test: kms_flip
results: parsing output: 81/ for test: gen9_exec_parse
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_feature_discovery
results: parsing output: 84/ for test: kms_cursor_legacy
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_exec_flush
results: parsing output: 87/ for test: gem_cs_tlb
results: parsing output: 88/ for test: gem_exec_fence
results: parsing output: 89/ for test: i915_module_load
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: perf_pmu
results: parsing output: 93/ for test: kms_hdr
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: syncobj_wait
results: parsing output: 96/ for test: gem_exec_balancer
results: parsing output: 97/ for test: gem_exec_schedule
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_render_copy
results: parsing output: 100/ for test: gem_ctx_persistence
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_chamelium_frames
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: gem_mmap_offset
results: parsing output: 105/ for test: kms_getfb
results: parsing output: 106/ for test: gem_mmap_gtt
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: core_getclient
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_sequence
results: parsing output: 113/ for test: gem_eio
results: parsing output: 114/ for test: kms_flip
results: parsing output: 115/ for test: kms_async_flips
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_pxp
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: gem_ctx_persistence
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_plane_multiple
results: parsing output: 131/ for test: sysfs_heartbeat_interval
results: parsing output: 132/ for test: gem_exec_reloc
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_chamelium_hpd
results: parsing output: 135/ for test: gem_mmap_wc
results: parsing output: 136/ for test: kms_pm_rpm
results: parsing output: 137/ for test: gem_exec_alignment
results: parsing output: 138/ for test: gem_exec_params
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_big_fb
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: kms_atomic
results: parsing output: 143/ for test: gem_eio
results: parsing output: 144/ for test: perf
results: parsing output: 145/ for test: kms_ccs
results: parsing output: 146/ for test: kms_lease
results: parsing output: 147/ for test: kms_big_fb
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_pm_dc
results: parsing output: 150/ for test: gem_exec_store
results: parsing output: 151/ for test: kms_vblank
results: parsing output: 152/ for test: kms_big_fb
results: parsing output: 153/ for test: kms_psr
results: parsing output: 154/ for test: kms_cursor_legacy
results: parsing output: 155/ for test: gem_ctx_param
Done.