[58.550706] Initializing watchdogs [58.550761] /dev/watchdog0 [58.566731] [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] [58.571462] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [58.574072] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [58.577191] [001/156] (960s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-render) [58.709625] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-render [58.713923] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [58.806583] [002/156] (960s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-onoff) [58.907187] Starting subtest: hdr-1p-primscrn-spr-indfb-onoff [58.911785] Subtest hdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [59.001174] [003/156] (960s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf) [59.107970] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf [59.110269] Starting dynamic subtest: pipe-A-eDP-1 [59.210054] Dynamic subtest pipe-A-eDP-1: SKIP (0.104s) [59.220140] Starting dynamic subtest: pipe-B-eDP-1 [60.347185] Dynamic subtest pipe-B-eDP-1: SKIP (1.125s) [60.351594] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.231s) [61.539828] [004/156] (957s left) kms_pm_backlight (fade) [61.636214] Starting subtest: fade [61.710084] Starting dynamic subtest: eDP-1 [64.723686] Dynamic subtest eDP-1: SUCCESS (2.993s) [64.726079] Subtest fade: SUCCESS (3.068s) [65.597636] [005/156] (953s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [65.694690] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [65.699156] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [65.795602] [006/156] (953s left) gem_exec_fence (submit67) [65.971937] Starting subtest: submit67 [65.976628] Subtest submit67: SKIP (0.000s) [67.373236] [FACT gem_exec_fence (submit67)] new: kernel.kmod_is_loaded.vgem: true [67.376201] [007/156] (951s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs) [67.468586] Starting subtest: missing-ccs-buffer-yf-tiled-ccs [67.470790] Starting dynamic subtest: pipe-A-eDP-1 [67.494424] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [67.504069] Starting dynamic subtest: pipe-B-eDP-1 [67.526509] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [67.534248] Starting dynamic subtest: pipe-C-eDP-1 [67.556018] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [67.563617] Starting dynamic subtest: pipe-D-eDP-1 [67.585601] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [67.589995] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.003s) [69.772661] [008/156] (949s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe) [70.147716] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s) [70.992274] [009/156] (948s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling) [71.085805] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling [71.089891] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s) [71.161400] [010/156] (948s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu) [71.259240] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu [75.873566] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.612s) [77.039896] [011/156] (942s left) syncobj_wait (invalid-wait-illegal-handle) [77.100806] Starting subtest: invalid-wait-illegal-handle [77.102954] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [77.210793] [012/156] (942s left) perf (buffer-fill) [77.328946] Starting subtest: buffer-fill [77.331323] Starting dynamic subtest: 0-rcs0 [79.619096] Dynamic subtest 0-rcs0: SUCCESS (2.287s) [79.628277] Starting dynamic subtest: 1-vcs0 [84.091909] Dynamic subtest 1-vcs0: SUCCESS (4.470s) [84.094357] Subtest buffer-fill: SUCCESS (6.758s) [84.141938] [013/156] (935s left) kms_dirtyfb (default-dirtyfb-ioctl) [84.245913] Starting subtest: default-dirtyfb-ioctl [84.248303] Starting dynamic subtest: A-eDP-1 [85.859866] Dynamic subtest A-eDP-1: SUCCESS (1.613s) [85.862257] Subtest default-dirtyfb-ioctl: SUCCESS (1.614s) [86.679386] [014/156] (932s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite) [86.781949] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite [86.786283] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [86.875096] [015/156] (932s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-msflip-blt) [86.971128] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-msflip-blt [86.975074] Subtest fbcpsrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [87.063769] [016/156] (932s left) gem_mmap_gtt (big-copy-xy) [87.124670] Subtest big-copy-XY: SKIP (0.000s) [87.182966] [017/156] (932s left) kms_psr (pr-primary-page-flip) [87.274833] Starting subtest: pr-primary-page-flip [87.279352] Subtest pr-primary-page-flip: SKIP (0.000s) [87.370438] [018/156] (932s left) gem_spin_batch (resubmit-new-all) [87.436313] Starting subtest: resubmit-new-all [87.438606] Starting dynamic subtest: rcs0 [87.452092] Dynamic subtest rcs0: SUCCESS (0.017s) [87.460229] Starting dynamic subtest: bcs0 [87.465368] Dynamic subtest bcs0: SUCCESS (0.012s) [87.475691] Starting dynamic subtest: vcs0 [87.477970] Dynamic subtest vcs0: SUCCESS (0.011s) [87.486075] Starting dynamic subtest: vcs1 [87.488484] Dynamic subtest vcs1: SUCCESS (0.010s) [87.496278] Starting dynamic subtest: vecs0 [87.498470] Dynamic subtest vecs0: SUCCESS (0.009s) [87.506407] Starting dynamic subtest: ccs0 [87.508627] Dynamic subtest ccs0: SUCCESS (0.009s) [87.510508] Subtest resubmit-new-all: SUCCESS (0.071s) [89.364615] [019/156] (930s left) gem_mmap (basic-small-bo) [89.415206] Subtest basic-small-bo: SKIP (0.000s) [89.473342] [020/156] (930s left) kms_frontbuffer_tracking (fbchdr-2p-rte) [89.570204] Starting subtest: fbchdr-2p-rte [89.574273] Subtest fbchdr-2p-rte: SKIP (0.000s) [89.670640] [021/156] (930s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip) [89.779400] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip [90.403226] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.626s) [90.576164] [022/156] (929s left) i915_hangman (hangcheck-unterminated) [90.648437] Starting subtest: hangcheck-unterminated [102.273860] Subtest hangcheck-unterminated: SUCCESS (11.620s) [102.401153] [023/156] (917s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled) [102.461897] Starting subtest: multi-wait-all-for-submit-submitted-signaled [102.564761] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.101s) [102.643215] [024/156] (917s left) kms_async_flips (async-flip-with-page-flip-events-tiled) [102.743191] Starting subtest: async-flip-with-page-flip-events-tiled [103.125874] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs [105.957706] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.829s) [105.969048] Starting dynamic subtest: pipe-A-eDP-1-4 [108.143062] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.185s) [108.154147] Starting dynamic subtest: pipe-A-eDP-1-4 [110.152989] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s) [110.161768] Starting dynamic subtest: pipe-A-eDP-1-x [112.343665] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.190s) [112.709642] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs [115.478903] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.773s) [115.490066] Starting dynamic subtest: pipe-B-eDP-1-4 [117.665907] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.186s) [117.674546] Starting dynamic subtest: pipe-B-eDP-1-4 [119.695254] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s) [119.706642] Starting dynamic subtest: pipe-B-eDP-1-x [121.864789] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.190s) [122.228661] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs [125.010829] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.787s) [125.021171] Starting dynamic subtest: pipe-C-eDP-1-4 [127.186827] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.175s) [127.198202] Starting dynamic subtest: pipe-C-eDP-1-4 [129.195105] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s) [129.203944] Starting dynamic subtest: pipe-C-eDP-1-x [131.386358] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.190s) [131.756501] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs [134.543237] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.795s) [134.554813] Starting dynamic subtest: pipe-D-eDP-1-4 [136.729357] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.185s) [136.740844] Starting dynamic subtest: pipe-D-eDP-1-4 [138.739727] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.010s) [138.748594] Starting dynamic subtest: pipe-D-eDP-1-x [140.917713] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s) [140.920231] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.169s) [142.131213] [025/156] (877s left) gem_ctx_persistence (engines-persistence) [142.184637] Starting subtest: engines-persistence [142.187096] Starting dynamic subtest: rcs0 [143.013097] Dynamic subtest rcs0: SUCCESS (0.828s) [143.024838] Starting dynamic subtest: bcs0 [143.776680] Dynamic subtest bcs0: SUCCESS (0.763s) [143.788181] Starting dynamic subtest: vcs0 [144.557696] Dynamic subtest vcs0: SUCCESS (0.780s) [144.568564] Starting dynamic subtest: vcs1 [145.287725] Dynamic subtest vcs1: SUCCESS (0.728s) [145.298800] Starting dynamic subtest: vecs0 [146.055882] Dynamic subtest vecs0: SUCCESS (0.767s) [146.066211] Starting dynamic subtest: ccs0 [146.849357] Dynamic subtest ccs0: SUCCESS (0.792s) [146.852081] Subtest engines-persistence: SUCCESS (4.665s) [146.929615] [026/156] (873s left) kms_color_pipeline (plane-lut1d-post-ctm3x4) [147.022809] Starting subtest: plane-lut1d-post-ctm3x4 [147.169379] Starting dynamic subtest: pipe-A-plane-0 [147.358614] Dynamic subtest pipe-A-plane-0: SUCCESS (0.189s) [147.370611] Starting dynamic subtest: pipe-A-plane-1 [147.491428] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s) [147.500636] Starting dynamic subtest: pipe-A-plane-2 [147.647669] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s) [148.832580] Starting dynamic subtest: pipe-B-plane-0 [149.002719] Dynamic subtest pipe-B-plane-0: SUCCESS (0.178s) [149.013421] Starting dynamic subtest: pipe-B-plane-1 [149.146719] Dynamic subtest pipe-B-plane-1: SUCCESS (0.144s) [149.156110] Starting dynamic subtest: pipe-B-plane-2 [149.315567] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s) [150.546416] Starting dynamic subtest: pipe-C-plane-0 [150.680777] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s) [150.690135] Starting dynamic subtest: pipe-C-plane-1 [150.849312] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s) [150.859067] Starting dynamic subtest: pipe-C-plane-2 [151.002572] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s) [152.177762] Starting dynamic subtest: pipe-D-plane-0 [152.314360] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s) [152.323419] Starting dynamic subtest: pipe-D-plane-1 [152.458860] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s) [152.467860] Starting dynamic subtest: pipe-D-plane-2 [152.592172] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s) [152.876767] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.855s) [153.752973] [027/156] (866s left) gem_exec_schedule (u-fairslice-all) [153.835801] Starting subtest: u-fairslice-all [155.893326] Subtest u-fairslice-all: SUCCESS (2.061s) [157.330555] [028/156] (862s left) kms_big_fb (y-tiled-32bpp-rotate-180) [157.439222] Starting subtest: y-tiled-32bpp-rotate-180 [157.443788] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s) [157.566825] [029/156] (862s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu) [157.662192] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu [157.666419] Subtest fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [157.749046] [030/156] (862s left) kms_cursor_crc (cursor-offscreen-512x512) [158.007478] Starting subtest: cursor-offscreen-512x512 [158.011801] Subtest cursor-offscreen-512x512: SKIP (0.001s) [158.186162] [031/156] (862s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-plflip-blt) [158.282110] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-plflip-blt [158.285458] Subtest fbcpsrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [158.387629] [032/156] (861s left) kms_invalid_mode (bad-htotal) [158.479171] Starting subtest: bad-htotal [158.481191] Starting dynamic subtest: pipe-A-eDP-1 [158.767226] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s) [158.776110] Starting dynamic subtest: pipe-B-eDP-1 [158.778160] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [158.788492] Starting dynamic subtest: pipe-C-eDP-1 [158.790692] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [158.799094] Starting dynamic subtest: pipe-D-eDP-1 [158.801319] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [158.803510] Subtest bad-htotal: SUCCESS (0.294s) [159.579632] [033/156] (860s left) gem_ccs (block-copy-compressed) [159.630592] Starting subtest: block-copy-compressed [159.634936] Subtest block-copy-compressed: SKIP (0.000s) [159.729814] [034/156] (860s left) kms_psr (psr-sprite-mmap-gtt) [159.813696] Starting subtest: psr-sprite-mmap-gtt [159.815975] Starting dynamic subtest: eDP-1 [161.281860] Dynamic subtest eDP-1: SKIP (1.464s) [161.286720] Subtest psr-sprite-mmap-gtt: SKIP (1.466s) [161.441216] [035/156] (859s left) gem_exec_schedule (fifo) [161.511865] Starting subtest: fifo [161.513878] Starting dynamic subtest: rcs0 [161.598789] Dynamic subtest rcs0: SUCCESS (0.097s) [161.609758] Starting dynamic subtest: vcs0 [161.675179] Dynamic subtest vcs0: SUCCESS (0.076s) [161.686048] Starting dynamic subtest: vcs1 [161.757744] Dynamic subtest vcs1: SUCCESS (0.082s) [161.768165] Starting dynamic subtest: vecs0 [161.842430] Dynamic subtest vecs0: SUCCESS (0.084s) [161.856619] Starting dynamic subtest: ccs0 [161.923450] Dynamic subtest ccs0: SUCCESS (0.079s) [161.926056] Subtest fifo: SUCCESS (0.421s) [163.378320] [036/156] (857s left) kms_pipe_crc_basic (suspend-read-crc) [163.468580] Starting subtest: suspend-read-crc [163.471353] Starting dynamic subtest: pipe-A-eDP-1 [181.653247] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.067s) [181.666905] Starting dynamic subtest: pipe-B-eDP-1 [200.665567] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.655s) [200.676012] Starting dynamic subtest: pipe-C-eDP-1 [219.637073] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.543s) [219.647352] Starting dynamic subtest: pipe-D-eDP-1 [238.620545] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.461s) [238.623231] Subtest suspend-read-crc: SUCCESS (13.730s) [248.461461] [037/156] (781s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip) [248.585885] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip [248.591754] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s) [248.687595] [038/156] (780s left) kms_chamelium_edid (vga-edid-read) [249.071362] Subtest vga-edid-read: SKIP (0.000s) [249.869824] [039/156] (779s left) gem_exec_flush (basic-wb-prw-default) [249.935188] Starting subtest: basic-wb-prw-default [255.446543] Subtest basic-wb-prw-default: SUCCESS (5.463s) [255.647919] [040/156] (774s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-move) [255.748501] Starting subtest: psrhdr-2p-primscrn-cur-indfb-move [255.753555] Subtest psrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [255.840258] [041/156] (773s left) kms_flip (2x-flip-vs-panning) [255.933751] Starting subtest: 2x-flip-vs-panning [255.943545] Subtest 2x-flip-vs-panning: SKIP (0.010s) [256.030051] [042/156] (773s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc) [256.138274] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc [256.143463] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [256.238066] [043/156] (773s left) gem_pxp (create-protected-buffer) [256.363265] Starting subtest: create-protected-buffer [256.366139] Subtest create-protected-buffer: SUCCESS (0.000s) [256.473789] [044/156] (773s left) gem_exec_reloc (basic-softpin) [256.535448] Subtest basic-softpin: SKIP (0.000s) [256.604560] [045/156] (773s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc) [256.690940] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc [256.693132] Starting dynamic subtest: pipe-A-eDP-1 [256.713306] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [256.724603] Starting dynamic subtest: pipe-B-eDP-1 [256.744506] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [256.754534] Starting dynamic subtest: pipe-C-eDP-1 [256.774122] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [256.784474] Starting dynamic subtest: pipe-D-eDP-1 [256.804678] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [256.807102] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s) [258.971413] [046/156] (770s left) kms_dsc (dsc-with-bpc-formats) [259.069428] Starting subtest: dsc-with-bpc-formats [259.205373] Subtest dsc-with-bpc-formats: SKIP (0.003s) [259.247722] [047/156] (770s left) gem_tiled_swapping (non-threaded) [259.304535] Subtest non-threaded: SKIP (0.000s) [259.367143] [048/156] (770s left) kms_big_fb (linear-64bpp-rotate-0) [259.472093] Starting subtest: linear-64bpp-rotate-0 [261.131335] Subtest linear-64bpp-rotate-0: SUCCESS (1.637s) [261.301505] [049/156] (768s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier) [261.395624] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier [261.422370] Starting dynamic subtest: pipe-A-plane-0 [261.692094] Dynamic subtest pipe-A-plane-0: SUCCESS (0.267s) [261.702492] Starting dynamic subtest: pipe-A-plane-3 [261.918925] Dynamic subtest pipe-A-plane-3: SUCCESS (0.221s) [261.928579] Starting dynamic subtest: pipe-A-plane-5 [261.933445] Dynamic subtest pipe-A-plane-5: SKIP (0.000s) [263.004717] Starting dynamic subtest: pipe-B-plane-0 [263.299068] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s) [263.311777] Starting dynamic subtest: pipe-B-plane-3 [263.512653] Dynamic subtest pipe-B-plane-3: SUCCESS (0.210s) [263.523417] Starting dynamic subtest: pipe-B-plane-5 [263.528540] Dynamic subtest pipe-B-plane-5: SKIP (0.000s) [263.822781] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.403s) [264.700679] [050/156] (765s left) gen7_exec_parse (oacontrol-tracking) [264.757539] Subtest oacontrol-tracking: SKIP (0.000s) [264.828169] [051/156] (765s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff) [264.922804] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff [272.005745] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.051s) [272.181596] [052/156] (758s left) api_intel_bb (misplaced-blitter) [272.245604] Starting subtest: misplaced-blitter [272.253474] Subtest misplaced-blitter: SUCCESS (0.009s) [272.372254] [053/156] (757s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt) [272.474644] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt [272.479938] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [272.572761] [054/156] (757s left) gem_exec_params (secure-non-master) [272.622602] Starting subtest: secure-non-master [272.628469] Subtest secure-non-master: SKIP (0.000s) [272.724310] [055/156] (757s left) sysfs_heartbeat_interval (idempotent) [272.786448] Starting subtest: idempotent [272.788781] Starting dynamic subtest: vecs0 [272.791340] Dynamic subtest vecs0: SUCCESS (0.001s) [272.802049] Starting dynamic subtest: ccs0 [272.804276] Dynamic subtest ccs0: SUCCESS (0.001s) [272.813850] Starting dynamic subtest: vcs0 [272.816288] Dynamic subtest vcs0: SUCCESS (0.000s) [272.825710] Starting dynamic subtest: bcs0 [272.828555] Dynamic subtest bcs0: SUCCESS (0.001s) [272.838518] Starting dynamic subtest: rcs0 [272.841310] Dynamic subtest rcs0: SUCCESS (0.000s) [272.850763] Starting dynamic subtest: vcs1 [272.853228] Dynamic subtest vcs1: SUCCESS (0.000s) [272.855536] Subtest idempotent: SUCCESS (0.008s) [272.896977] [056/156] (757s left) core_setmaster (master-drop-set-user) [272.965389] Starting subtest: master-drop-set-user [272.976082] Subtest master-drop-set-user: SUCCESS (0.012s) [273.016026] [057/156] (757s left) i915_drm_fdinfo (idle) [273.050534] Starting subtest: idle [273.052947] Starting dynamic subtest: rcs0 [273.634905] Dynamic subtest rcs0: SUCCESS (0.582s) [273.647769] Starting dynamic subtest: bcs0 [274.143132] Dynamic subtest bcs0: SUCCESS (0.503s) [274.156398] Starting dynamic subtest: vcs0 [274.650209] Dynamic subtest vcs0: SUCCESS (0.503s) [274.663296] Starting dynamic subtest: vcs1 [275.156751] Dynamic subtest vcs1: SUCCESS (0.503s) [275.170365] Starting dynamic subtest: vecs0 [275.663487] Dynamic subtest vecs0: SUCCESS (0.503s) [275.676563] Starting dynamic subtest: ccs0 [276.170075] Dynamic subtest ccs0: SUCCESS (0.503s) [276.173054] Subtest idle: SUCCESS (3.112s) [276.249033] [058/156] (754s left) gem_render_copy (yf-tiled-ccs-to-x-tiled) [276.308523] Starting subtest: yf-tiled-ccs-to-x-tiled [276.313726] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s) [276.499385] [059/156] (754s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait) [278.043030] Starting subtest: modeset-non-lpsp-stress-no-wait [278.098216] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.056s) [278.822160] [060/156] (751s left) gem_exec_balancer (bonded-false-hang) [279.083719] Starting subtest: bonded-false-hang [279.089396] Subtest bonded-false-hang: SKIP (0.000s) [279.205065] [061/156] (751s left) gem_mmap_gtt (pf-nonblock) [279.268393] Subtest pf-nonblock: SKIP (0.000s) [279.327952] [062/156] (751s left) kms_psr2_sf (psr2-cursor-plane-update-sf) [279.417334] Starting subtest: psr2-cursor-plane-update-sf [279.419734] Starting dynamic subtest: pipe-A-eDP-1 [279.977389] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.564s) [279.989184] Starting dynamic subtest: pipe-B-eDP-1 [281.322041] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.342s) [281.324439] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.909s) [282.208762] [063/156] (748s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-gtt) [282.305507] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-gtt [282.310360] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [282.402842] [064/156] (748s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff) [282.504961] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff [282.509249] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [282.602300] [065/156] (748s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary) [282.697260] Starting subtest: psr-indfb-scaledprimary [288.459102] Subtest psr-indfb-scaledprimary: SUCCESS (5.765s) [288.572100] [066/156] (742s left) gem_render_copy (x-tiled) [288.631278] Starting subtest: x-tiled [288.633510] Starting dynamic subtest: smem [288.673718] Dynamic subtest smem: SUCCESS (0.045s) [288.676641] Subtest x-tiled: SUCCESS (0.046s) [288.876579] [067/156] (742s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area) [288.977494] Starting subtest: psr2-overlay-primary-update-sf-dmg-area [288.980005] Starting dynamic subtest: pipe-A-eDP-1 [294.918677] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.941s) [294.929575] Starting dynamic subtest: pipe-B-eDP-1 [301.578510] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.660s) [301.581187] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.603s) [302.458378] [068/156] (728s left) gem_caching (read-writes) [302.515440] Subtest read-writes: SKIP (0.000s) [302.590195] [069/156] (728s left) kms_pm_rpm (fences) [304.114885] Starting subtest: fences [304.120268] Subtest fences: SKIP (0.000s) [304.902541] [070/156] (726s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [304.948717] Starting subtest: multi-wait-for-submit-available-signaled [304.951562] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s) [305.046261] [071/156] (726s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted) [305.092560] Starting subtest: multi-wait-all-for-submit-unsubmitted [305.200463] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.106s) [305.280986] [072/156] (725s left) kms_vblank (wait-forked) [305.377182] Starting subtest: wait-forked [305.379609] Starting dynamic subtest: pipe-A-eDP-1 [307.185952] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.809s) [307.203570] Starting dynamic subtest: pipe-D-eDP-1 [309.682587] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.493s) [309.685545] Subtest wait-forked: SUCCESS (4.306s) [310.556846] [073/156] (720s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-blt) [310.671171] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-blt [310.676434] Subtest hdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [310.759554] [074/156] (720s left) kms_big_fb (x-tiled-8bpp-rotate-180) [310.858961] Starting subtest: x-tiled-8bpp-rotate-180 [312.097209] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.238s) [312.234001] [075/156] (719s left) syncobj_timeline (wait-for-submit-snapshot) [312.283740] Starting subtest: wait-for-submit-snapshot [312.366304] Subtest wait-for-submit-snapshot: SUCCESS (0.083s) [312.459649] [076/156] (718s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu) [312.571972] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu [312.576969] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [312.673343] [077/156] (718s left) gem_ctx_sseu (engines) [312.729055] Subtest engines: SKIP (0.000s) [312.794573] [078/156] (718s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render) [312.881725] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render [320.166408] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.287s) [320.280328] [079/156] (711s left) gem_softpin (softpin) [320.342385] Starting subtest: softpin [352.601712] Subtest softpin: SUCCESS (32.244s) [352.713123] [080/156] (678s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt) [352.822676] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt [352.827344] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [352.921393] [081/156] (678s left) syncobj_basic (bad-flags-fd-to-handle) [352.973439] Starting subtest: bad-flags-fd-to-handle [352.975971] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s) [353.085646] [082/156] (678s left) gem_partial_pwrite_pread (write-uncached) [353.147581] Subtest write-uncached: SKIP (0.000s) [353.218246] [083/156] (678s left) sw_sync (sync_busy) [353.255541] Starting subtest: sync_busy [353.258546] Subtest sync_busy: SUCCESS (0.002s) [353.317993] [084/156] (678s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite) [353.418165] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite [358.377737] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.970s) [359.500907] [085/156] (672s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-gtt) [359.597509] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-gtt [359.602591] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [359.692608] [086/156] (672s left) i915_pm_rps (min-max-config-idle) [359.745639] Starting subtest: min-max-config-idle [359.751424] Subtest min-max-config-idle: SKIP (0.001s) [359.850579] [087/156] (671s left) gem_ctx_persistence (legacy-engines-queued) [359.904050] Starting subtest: legacy-engines-queued [359.906721] Starting dynamic subtest: render [359.974409] Dynamic subtest render: SUCCESS (0.070s) [359.986284] Starting dynamic subtest: bsd1 [361.706775] Dynamic subtest bsd1: SUCCESS (1.729s) [361.721795] Starting dynamic subtest: bsd2 [363.690104] Dynamic subtest bsd2: SUCCESS (1.979s) [363.702981] Starting dynamic subtest: blt [363.724399] Dynamic subtest blt: SUCCESS (0.033s) [363.734289] Starting dynamic subtest: vebox [365.737030] Dynamic subtest vebox: SUCCESS (2.010s) [365.739569] Subtest legacy-engines-queued: SUCCESS (5.835s) [365.821736] [088/156] (666s left) kms_frontbuffer_tracking (fbcpsr-tiling-y) [365.936683] Starting subtest: fbcpsr-tiling-y [365.942531] Subtest fbcpsr-tiling-y: SKIP (0.000s) [366.039387] [089/156] (665s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt) [366.142594] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt [372.992532] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.855s) [373.115632] [090/156] (658s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move) [373.216568] Starting subtest: fbc-2p-primscrn-cur-indfb-move [373.221623] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s) [373.314129] [091/156] (658s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [373.406162] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [380.735191] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.335s) [380.873563] [092/156] (651s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled) [380.926648] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled [380.929556] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s) [381.023535] [093/156] (651s left) kms_universal_plane (disable-primary-vs-flip) [381.115359] Starting subtest: disable-primary-vs-flip [381.117388] Starting dynamic subtest: pipe-A-eDP-1 [382.644751] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.530s) [382.656721] Starting dynamic subtest: pipe-B-eDP-1 [384.916638] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.271s) [384.929593] Starting dynamic subtest: pipe-C-eDP-1 [387.170762] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.249s) [387.181538] Starting dynamic subtest: pipe-D-eDP-1 [389.432429] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.262s) [389.435442] Subtest disable-primary-vs-flip: SUCCESS (8.318s) [390.314140] [094/156] (641s left) gem_wait (wait) [390.383010] Starting subtest: wait [390.385376] Starting dynamic subtest: all-engines [391.691384] Dynamic subtest all-engines: SUCCESS (1.306s) [391.703140] Starting dynamic subtest: rcs0 [392.742471] Dynamic subtest rcs0: SUCCESS (1.049s) [392.760308] Starting dynamic subtest: bcs0 [393.795007] Dynamic subtest bcs0: SUCCESS (1.050s) [393.807550] Starting dynamic subtest: vcs0 [394.846633] Dynamic subtest vcs0: SUCCESS (1.049s) [394.858476] Starting dynamic subtest: vcs1 [395.898389] Dynamic subtest vcs1: SUCCESS (1.049s) [395.912219] Starting dynamic subtest: vecs0 [396.950149] Dynamic subtest vecs0: SUCCESS (1.049s) [396.963742] Starting dynamic subtest: ccs0 [398.003314] Dynamic subtest ccs0: SUCCESS (1.050s) [398.006345] Subtest wait: SUCCESS (7.620s) [398.216993] [095/156] (633s left) kms_content_protection (dp-mst-type-0) [398.377479] Starting subtest: dp-mst-type-0 [398.382751] Subtest dp-mst-type-0: SKIP (0.000s) [398.493073] [096/156] (633s left) gem_ccs (suspend-resume) [398.556657] Starting subtest: suspend-resume [398.561750] Subtest suspend-resume: SKIP (0.000s) [398.654163] [097/156] (633s left) kms_cursor_crc (cursor-tearing-framebuffer-change) [398.766451] Starting subtest: cursor-tearing-framebuffer-change [398.768734] Starting dynamic subtest: pipe-A-eDP-1 [399.345232] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.575s) [399.358125] Starting dynamic subtest: pipe-D-eDP-1 [400.540488] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.194s) [400.543101] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.772s) [401.381652] [098/156] (630s left) kms_plane_scaling (planes-downscale-factor-0-5) [401.476225] Starting subtest: planes-downscale-factor-0-5 [401.478586] Starting dynamic subtest: pipe-A [401.501753] Dynamic subtest pipe-A: SKIP (0.018s) [401.513533] Starting dynamic subtest: pipe-B [401.531512] Dynamic subtest pipe-B: SKIP (0.030s) [401.541899] Starting dynamic subtest: pipe-C [401.559717] Dynamic subtest pipe-C: SKIP (0.030s) [401.570041] Starting dynamic subtest: pipe-D [401.591126] Dynamic subtest pipe-D: SKIP (0.030s) [401.596227] Subtest planes-downscale-factor-0-5: SKIP (0.110s) [401.687204] [099/156] (630s left) gem_mmap (bad-size) [401.739959] Subtest bad-size: SKIP (0.000s) [401.797531] [100/156] (630s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier) [401.892523] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier [401.897601] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier: SKIP (0.000s) [401.967125] [101/156] (630s left) gem_ctx_create (basic) [402.021737] Starting subtest: basic [402.024740] Subtest basic: SUCCESS (0.000s) [402.219000] [102/156] (630s left) syncobj_timeline (multi-wait-all-for-submit-signaled) [402.270590] Starting subtest: multi-wait-all-for-submit-signaled [402.273100] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s) [402.370275] [103/156] (630s left) kms_async_flips (test-time-stamp-atomic) [402.465559] Starting subtest: test-time-stamp-atomic [402.835415] Starting dynamic subtest: pipe-A-eDP-1 [403.692004] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.853s) [404.059290] Starting dynamic subtest: pipe-B-eDP-1 [404.824745] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.770s) [405.192224] Starting dynamic subtest: pipe-C-eDP-1 [405.969468] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.782s) [406.345250] Starting dynamic subtest: pipe-D-eDP-1 [407.121584] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.783s) [407.124513] Subtest test-time-stamp-atomic: SUCCESS (4.656s) [408.282997] [104/156] (624s left) i915_power (sanity) [408.351749] Starting subtest: sanity [412.395551] Subtest sanity: SUCCESS (4.038s) [413.795192] [105/156] (618s left) perf_pmu (most-busy-check-all) [413.835326] Starting subtest: most-busy-check-all [413.837655] Starting dynamic subtest: rcs0 [415.756490] Dynamic subtest rcs0: SUCCESS (1.921s) [415.771559] Starting dynamic subtest: bcs0 [417.740264] Dynamic subtest bcs0: SUCCESS (1.980s) [417.754547] Starting dynamic subtest: vcs0 [419.731719] Dynamic subtest vcs0: SUCCESS (1.979s) [419.744358] Starting dynamic subtest: vcs1 [421.771166] Dynamic subtest vcs1: SUCCESS (2.046s) [421.785290] Starting dynamic subtest: vecs0 [423.755142] Dynamic subtest vecs0: SUCCESS (1.980s) [423.768011] Starting dynamic subtest: ccs0 [425.741593] Dynamic subtest ccs0: SUCCESS (1.983s) [425.744539] Subtest most-busy-check-all: SUCCESS (11.906s) [425.844877] [106/156] (606s left) kms_chamelium_audio (dp-audio-edid) [426.225926] Subtest dp-audio-edid: SKIP (0.000s) [427.098628] [107/156] (605s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move) [427.201499] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move [427.206416] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [427.304206] [108/156] (605s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted) [427.365458] Starting subtest: etime-single-wait-all-for-submit-submitted [427.472248] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.108s) [427.561207] [109/156] (605s left) gem_exec_fence (expired-history) [428.003367] Starting subtest: expired-history [435.531393] Subtest expired-history: SUCCESS (7.527s) [435.967539] [110/156] (596s left) kms_force_connector_basic (force-edid) [436.047407] Starting subtest: force-edid [436.419413] Subtest force-edid: SUCCESS (0.374s) [436.576507] [111/156] (596s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs) [436.666452] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs [436.669046] Starting dynamic subtest: pipe-A-eDP-1 [436.695658] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [436.707589] Starting dynamic subtest: pipe-B-eDP-1 [436.732530] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [436.743369] Starting dynamic subtest: pipe-C-eDP-1 [436.768503] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [436.779097] Starting dynamic subtest: pipe-D-eDP-1 [436.804513] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [436.809544] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s) [438.932743] [112/156] (593s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-wc) [439.037329] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-wc [439.042513] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [439.138221] [113/156] (593s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted) [439.185757] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted [439.295471] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.109s) [439.391153] [114/156] (593s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt) [439.491027] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt [439.495658] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [439.581068] [115/156] (593s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [439.696435] Starting subtest: cursorA-vs-flipA-atomic-transitions [450.867542] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.165s) [451.738985] [116/156] (581s left) gem_mmap_wc (write-cpu-read-wc) [451.795332] Subtest write-cpu-read-wc: SKIP (0.000s) [451.856249] [117/156] (581s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy) [451.980190] Starting subtest: 2x-cursor-vs-flip-legacy [451.985251] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s) [452.137462] [118/156] (581s left) sriov_basic (bind-unbind-vf) [452.196720] Starting subtest: bind-unbind-vf [452.199438] Starting dynamic subtest: vf-1 [452.204490] Dynamic subtest vf-1: SKIP (0.001s) [452.215661] Starting dynamic subtest: vf-2 [452.220734] Dynamic subtest vf-2: SKIP (0.001s) [452.231889] Starting dynamic subtest: vf-3 [452.237539] Dynamic subtest vf-3: SKIP (0.000s) [452.248010] Starting dynamic subtest: vf-4 [452.253483] Dynamic subtest vf-4: SKIP (0.000s) [452.267589] Starting dynamic subtest: vf-5 [452.273227] Dynamic subtest vf-5: SKIP (0.000s) [452.283896] Starting dynamic subtest: vf-6 [452.289380] Dynamic subtest vf-6: SKIP (0.001s) [452.299958] Starting dynamic subtest: vf-7 [452.304756] Dynamic subtest vf-7: SKIP (0.000s) [452.317336] Starting dynamic subtest: vf-random [452.322509] Dynamic subtest vf-random: SKIP (0.000s) [452.335134] Starting dynamic subtest: vf-last [452.340431] Dynamic subtest vf-last: SKIP (0.001s) [452.345498] Subtest bind-unbind-vf: SKIP (0.012s) [452.388120] [119/156] (580s left) kms_psr (pr-basic) [452.472485] Starting subtest: pr-basic [452.478256] Subtest pr-basic: SKIP (0.000s) [452.551658] [120/156] (580s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-pwrite) [452.655248] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-pwrite [452.660105] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [452.761846] [121/156] (580s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt) [452.854145] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt [457.323735] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.472s) [458.453077] [122/156] (574s left) kms_legacy_colorkey (basic) [458.541001] Starting subtest: basic [458.543525] Starting dynamic subtest: pipe-A [458.584937] Dynamic subtest pipe-A: SUCCESS (0.045s) [458.597502] Starting dynamic subtest: pipe-B [458.599664] Dynamic subtest pipe-B: SUCCESS (0.000s) [458.610511] Starting dynamic subtest: pipe-C [458.613255] Dynamic subtest pipe-C: SUCCESS (0.000s) [458.624002] Starting dynamic subtest: pipe-D [458.626586] Dynamic subtest pipe-D: SUCCESS (0.000s) [458.629551] Subtest basic: SUCCESS (0.050s) [458.670942] [123/156] (574s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-render) [458.775160] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-render [458.779717] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [458.876776] [124/156] (574s left) gem_exec_whisper (basic-queues-forked) [458.937974] Starting subtest: basic-queues-forked [481.886122] Subtest basic-queues-forked: SUCCESS (22.939s) [482.074662] [125/156] (551s left) gem_mmap_gtt (basic-read-write-distinct) [482.140169] Subtest basic-read-write-distinct: SKIP (0.000s) [482.205162] [126/156] (551s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [482.309717] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [482.315175] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [482.391136] [127/156] (551s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite) [482.491294] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite [482.496329] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [482.572837] [128/156] (551s left) kms_frontbuffer_tracking (fbchdr-shrfb-scaledprimary) [482.677791] Starting subtest: fbchdr-shrfb-scaledprimary [482.683205] Subtest fbchdr-shrfb-scaledprimary: SKIP (0.000s) [482.778017] [129/156] (550s left) api_intel_bb (blit-noreloc-keep-cache) [482.837487] Starting subtest: blit-noreloc-keep-cache [482.850223] Subtest blit-noreloc-keep-cache: SUCCESS (0.014s) [482.994220] [130/156] (550s left) kms_flip (plain-flip-ts-check-interruptible) [483.095347] Starting subtest: plain-flip-ts-check-interruptible [483.097779] Starting dynamic subtest: A-eDP1 [492.014665] Dynamic subtest A-eDP1: SUCCESS (8.921s) [492.029371] Starting dynamic subtest: B-eDP1 [500.911261] Dynamic subtest B-eDP1: SUCCESS (8.897s) [500.924601] Starting dynamic subtest: C-eDP1 [509.796123] Dynamic subtest C-eDP1: SUCCESS (8.883s) [509.809375] Starting dynamic subtest: D-eDP1 [518.702682] Dynamic subtest D-eDP1: SUCCESS (8.907s) [518.705498] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.618s) [519.936833] [131/156] (513s left) gem_exec_schedule (pi-common) [520.136032] Starting subtest: pi-common [520.138328] Starting dynamic subtest: rcs0 [520.167731] Dynamic subtest rcs0: SUCCESS (0.035s) [520.178641] Starting dynamic subtest: bcs0 [520.208450] Dynamic subtest bcs0: SUCCESS (0.040s) [520.218939] Starting dynamic subtest: vcs0 [520.239764] Dynamic subtest vcs0: SUCCESS (0.030s) [520.250330] Starting dynamic subtest: vcs1 [520.274486] Dynamic subtest vcs1: SUCCESS (0.034s) [520.288859] Starting dynamic subtest: vecs0 [520.304724] Dynamic subtest vecs0: SUCCESS (0.029s) [520.320260] Starting dynamic subtest: ccs0 [520.333256] Dynamic subtest ccs0: SUCCESS (0.027s) [520.335883] Subtest pi-common: SUCCESS (0.202s) [520.565029] [132/156] (513s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy) [520.667227] Starting subtest: fbcpsr-modesetfrombusy [526.848410] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.188s) [526.969753] [133/156] (506s left) perf (blocking-parameterized) [527.078856] Starting subtest: blocking-parameterized [547.097780] Subtest blocking-parameterized: SUCCESS (20.017s) [547.174938] [134/156] (486s left) kms_hdr (bpc-switch-suspend) [547.261890] Starting subtest: bpc-switch-suspend [547.264238] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010 [567.894989] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (6.056s) [567.908487] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F [588.894459] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (6.069s) [588.897404] Subtest bpc-switch-suspend: SUCCESS (12.127s) [593.044391] [135/156] (444s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt) [593.166872] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt [593.171231] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [593.277461] [136/156] (444s left) kms_busy (basic-hang) [593.375295] Starting subtest: basic-hang [593.377612] Starting dynamic subtest: flip-pipe-A [594.111286] Dynamic subtest flip-pipe-A: SUCCESS (0.725s) [594.123684] Starting dynamic subtest: modeset-pipe-A [597.875891] Dynamic subtest modeset-pipe-A: SUCCESS (3.672s) [597.888347] Starting dynamic subtest: flip-pipe-D [599.159665] Dynamic subtest flip-pipe-D: SUCCESS (1.259s) [599.170941] Starting dynamic subtest: modeset-pipe-D [602.544679] Dynamic subtest modeset-pipe-D: SUCCESS (3.337s) [602.546927] Subtest basic-hang: SUCCESS (9.000s) [603.432268] [137/156] (434s left) gem_mmap (short-mmap) [603.494824] Subtest short-mmap: SKIP (0.000s) [603.555790] [138/156] (434s left) sw_sync (timeline_closed_signaled) [603.591325] Starting subtest: timeline_closed_signaled [603.593591] Subtest timeline_closed_signaled: SUCCESS (0.000s) [603.644393] [139/156] (434s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-pgflip-blt) [603.754150] Starting subtest: fbchdr-2p-primscrn-indfb-pgflip-blt [603.758374] Subtest fbchdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [603.868381] [140/156] (434s left) kms_getfb (getfb-repeated-different-handles) [603.974524] Starting subtest: getfb-repeated-different-handles [603.976793] Subtest getfb-repeated-different-handles: SUCCESS (0.000s) [604.080383] [141/156] (434s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-blt) [604.188282] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-blt [604.192542] Subtest fbchdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [604.284642] [142/156] (433s left) kms_setmode (invalid-clone-single-crtc-stealing) [604.343943] Starting subtest: invalid-clone-single-crtc-stealing [604.350832] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s) [604.454560] [143/156] (433s left) gem_exec_reloc (basic-write-wc) [604.514257] Subtest basic-write-wc: SKIP (0.000s) [604.589380] [144/156] (433s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic) [604.712287] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic [604.716192] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s) [604.858113] [145/156] (433s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [604.914707] Starting subtest: y-tiled-to-vebox-y-tiled [604.918154] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [605.105758] [146/156] (433s left) gem_exec_whisper (basic-contexts-forked-all) [605.172258] Starting subtest: basic-contexts-forked-all [626.139300] Subtest basic-contexts-forked-all: SUCCESS (20.906s) [626.336102] [147/156] (412s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary) [626.441273] Starting subtest: psr-shrfb-scaledprimary [632.140239] Subtest psr-shrfb-scaledprimary: SUCCESS (5.703s) [632.256957] [148/156] (406s left) kms_psr (fbc-psr2-sprite-blt) [632.341570] Starting subtest: fbc-psr2-sprite-blt [632.343924] Starting dynamic subtest: eDP-1 [632.348276] Dynamic subtest eDP-1: SKIP (0.000s) [632.352715] Subtest fbc-psr2-sprite-blt: SKIP (0.001s) [632.420851] [149/156] (406s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing) [632.503448] Starting subtest: plane-use-after-nonblocking-unbind-fencing [632.505849] Starting dynamic subtest: pipe-A-eDP-1 [632.572236] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.070s) [632.882688] Starting dynamic subtest: pipe-B-eDP-1 [633.672586] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.801s) [633.980675] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.476s) [634.797748] [150/156] (403s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [634.902048] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [635.959151] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.057s) [636.085136] [151/156] (402s left) perf (enable-disable) [636.166828] Starting subtest: enable-disable [636.168953] Starting dynamic subtest: 0-rcs0 [638.465485] Dynamic subtest 0-rcs0: SUCCESS (2.295s) [638.477362] Starting dynamic subtest: 1-vcs1 [643.015630] Dynamic subtest 1-vcs1: SUCCESS (4.547s) [643.018244] Subtest enable-disable: SUCCESS (6.844s) [643.059145] [152/156] (395s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [643.169252] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu [643.173391] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [643.280362] [153/156] (395s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs) [643.368724] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs [643.371043] Starting dynamic subtest: pipe-A-eDP-1 [643.393344] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [643.406352] Starting dynamic subtest: pipe-B-eDP-1 [643.427694] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [643.441159] Starting dynamic subtest: pipe-C-eDP-1 [643.461830] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [643.473183] Starting dynamic subtest: pipe-D-eDP-1 [643.494769] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [643.498750] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs: SKIP (0.005s) [645.609604] [154/156] (393s left) kms_cursor_crc (cursor-rapid-movement-512x512) [645.893242] Starting subtest: cursor-rapid-movement-512x512 [645.897786] Subtest cursor-rapid-movement-512x512: SKIP (0.001s) [646.054966] [155/156] (392s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling) [646.142338] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling [646.146636] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s) [646.216953] [156/156] (392s left) kms_psr (psr-primary-mmap-gtt) [646.302369] Starting subtest: psr-primary-mmap-gtt [646.304623] Starting dynamic subtest: eDP-1 [647.775609] Dynamic subtest eDP-1: SKIP (1.470s) [647.780962] Subtest psr-primary-mmap-gtt: SKIP (1.471s) [647.935736] Closing watchdogs results: parsing output: 0/ for test: kms_frontbuffer_tracking results: parsing output: 1/ for test: kms_frontbuffer_tracking results: parsing output: 2/ for test: kms_psr2_sf results: parsing output: 3/ for test: kms_pm_backlight results: parsing output: 4/ for test: kms_frontbuffer_tracking results: parsing output: 5/ for test: gem_exec_fence results: parsing output: 6/ for test: kms_ccs results: parsing output: 7/ for test: kms_chamelium_hpd results: parsing output: 8/ for test: kms_flip_scaled_crc results: parsing output: 9/ for test: kms_frontbuffer_tracking results: parsing output: 10/ for test: syncobj_wait results: parsing output: 11/ for test: perf results: parsing output: 12/ for test: kms_dirtyfb results: parsing output: 13/ for test: kms_frontbuffer_tracking results: parsing output: 14/ for test: kms_frontbuffer_tracking results: parsing output: 15/ for test: gem_mmap_gtt results: parsing output: 16/ for test: kms_psr results: parsing output: 17/ for test: gem_spin_batch results: parsing output: 18/ for test: gem_mmap results: parsing output: 19/ for test: kms_frontbuffer_tracking results: parsing output: 20/ for test: kms_big_fb results: parsing output: 21/ for test: i915_hangman results: parsing output: 22/ for test: syncobj_wait results: parsing output: 23/ for test: kms_async_flips results: parsing output: 24/ for test: gem_ctx_persistence results: parsing output: 25/ for test: kms_color_pipeline results: parsing output: 26/ for test: gem_exec_schedule results: parsing output: 27/ for test: kms_big_fb results: parsing output: 28/ for test: kms_frontbuffer_tracking results: parsing output: 29/ for test: kms_cursor_crc results: parsing output: 30/ for test: kms_frontbuffer_tracking results: parsing output: 31/ for test: kms_invalid_mode results: parsing output: 32/ for test: gem_ccs results: parsing output: 33/ for test: kms_psr results: parsing output: 34/ for test: gem_exec_schedule results: parsing output: 35/ for test: kms_pipe_crc_basic results: parsing output: 36/ for test: kms_big_fb results: parsing output: 37/ for test: kms_chamelium_edid results: parsing output: 38/ for test: gem_exec_flush results: parsing output: 39/ for test: kms_frontbuffer_tracking results: parsing output: 40/ for test: kms_flip results: parsing output: 41/ for test: kms_frontbuffer_tracking results: parsing output: 42/ for test: gem_pxp results: parsing output: 43/ for test: gem_exec_reloc results: parsing output: 44/ for test: kms_ccs results: parsing output: 45/ for test: kms_dsc results: parsing output: 46/ for test: gem_tiled_swapping results: parsing output: 47/ for test: kms_big_fb results: parsing output: 48/ for test: kms_plane results: parsing output: 49/ for test: gen7_exec_parse results: parsing output: 50/ for test: kms_frontbuffer_tracking results: parsing output: 51/ for test: api_intel_bb results: parsing output: 52/ for test: kms_frontbuffer_tracking results: parsing output: 53/ for test: gem_exec_params results: parsing output: 54/ for test: sysfs_heartbeat_interval results: parsing output: 55/ for test: core_setmaster results: parsing output: 56/ for test: i915_drm_fdinfo results: parsing output: 57/ for test: gem_render_copy results: parsing output: 58/ for test: kms_pm_rpm results: parsing output: 59/ for test: gem_exec_balancer results: parsing output: 60/ for test: gem_mmap_gtt results: parsing output: 61/ for test: kms_psr2_sf results: parsing output: 62/ for test: kms_frontbuffer_tracking results: parsing output: 63/ for test: kms_frontbuffer_tracking results: parsing output: 64/ for test: kms_frontbuffer_tracking results: parsing output: 65/ for test: gem_render_copy results: parsing output: 66/ for test: kms_psr2_sf results: parsing output: 67/ for test: gem_caching results: parsing output: 68/ for test: kms_pm_rpm results: parsing output: 69/ for test: syncobj_timeline results: parsing output: 70/ for test: syncobj_wait results: parsing output: 71/ for test: kms_vblank results: parsing output: 72/ for test: kms_frontbuffer_tracking results: parsing output: 73/ for test: kms_big_fb results: parsing output: 74/ for test: syncobj_timeline results: parsing output: 75/ for test: kms_frontbuffer_tracking results: parsing output: 76/ for test: gem_ctx_sseu results: parsing output: 77/ for test: kms_frontbuffer_tracking results: parsing output: 78/ for test: gem_softpin results: parsing output: 79/ for test: kms_frontbuffer_tracking results: parsing output: 80/ for test: syncobj_basic results: parsing output: 81/ for test: gem_partial_pwrite_pread results: parsing output: 82/ for test: sw_sync results: parsing output: 83/ for test: kms_frontbuffer_tracking results: parsing output: 84/ for test: kms_frontbuffer_tracking results: parsing output: 85/ for test: i915_pm_rps results: parsing output: 86/ for test: gem_ctx_persistence results: parsing output: 87/ for test: kms_frontbuffer_tracking results: parsing output: 88/ for test: kms_frontbuffer_tracking results: parsing output: 89/ for test: kms_frontbuffer_tracking results: parsing output: 90/ for test: kms_frontbuffer_tracking results: parsing output: 91/ for test: syncobj_wait results: parsing output: 92/ for test: kms_universal_plane results: parsing output: 93/ for test: gem_wait results: parsing output: 94/ for test: kms_content_protection results: parsing output: 95/ for test: gem_ccs results: parsing output: 96/ for test: kms_cursor_crc results: parsing output: 97/ for test: kms_plane_scaling results: parsing output: 98/ for test: gem_mmap results: parsing output: 99/ for test: kms_plane results: parsing output: 100/ for test: gem_ctx_create results: parsing output: 101/ for test: syncobj_timeline results: parsing output: 102/ for test: kms_async_flips results: parsing output: 103/ for test: i915_power results: parsing output: 104/ for test: perf_pmu results: parsing output: 105/ for test: kms_chamelium_audio results: parsing output: 106/ for test: kms_frontbuffer_tracking results: parsing output: 107/ for test: syncobj_timeline results: parsing output: 108/ for test: gem_exec_fence results: parsing output: 109/ for test: kms_force_connector_basic results: parsing output: 110/ for test: kms_ccs results: parsing output: 111/ for test: kms_frontbuffer_tracking results: parsing output: 112/ for test: syncobj_timeline results: parsing output: 113/ for test: kms_frontbuffer_tracking results: parsing output: 114/ for test: kms_cursor_legacy results: parsing output: 115/ for test: gem_mmap_wc results: parsing output: 116/ for test: kms_cursor_legacy results: parsing output: 117/ for test: sriov_basic results: parsing output: 118/ for test: kms_psr results: parsing output: 119/ for test: kms_frontbuffer_tracking results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: kms_legacy_colorkey results: parsing output: 122/ for test: kms_frontbuffer_tracking results: parsing output: 123/ for test: gem_exec_whisper results: parsing output: 124/ for test: gem_mmap_gtt results: parsing output: 125/ for test: kms_frontbuffer_tracking results: parsing output: 126/ for test: kms_frontbuffer_tracking results: parsing output: 127/ for test: kms_frontbuffer_tracking results: parsing output: 128/ for test: api_intel_bb results: parsing output: 129/ for test: kms_flip results: parsing output: 130/ for test: gem_exec_schedule results: parsing output: 131/ for test: kms_frontbuffer_tracking results: parsing output: 132/ for test: perf results: parsing output: 133/ for test: kms_hdr results: parsing output: 134/ for test: kms_frontbuffer_tracking results: parsing output: 135/ for test: kms_busy results: parsing output: 136/ for test: gem_mmap results: parsing output: 137/ for test: sw_sync results: parsing output: 138/ for test: kms_frontbuffer_tracking results: parsing output: 139/ for test: kms_getfb results: parsing output: 140/ for test: kms_frontbuffer_tracking results: parsing output: 141/ for test: kms_setmode results: parsing output: 142/ for test: gem_exec_reloc results: parsing output: 143/ for test: kms_cursor_legacy results: parsing output: 144/ for test: gem_render_copy results: parsing output: 145/ for test: gem_exec_whisper results: parsing output: 146/ for test: kms_frontbuffer_tracking results: parsing output: 147/ for test: kms_psr results: parsing output: 148/ for test: kms_atomic_transition results: parsing output: 149/ for test: kms_big_fb results: parsing output: 150/ for test: perf results: parsing output: 151/ for test: kms_frontbuffer_tracking results: parsing output: 152/ for test: kms_ccs results: parsing output: 153/ for test: kms_cursor_crc results: parsing output: 154/ for test: kms_flip_scaled_crc results: parsing output: 155/ for test: kms_psr Done.