[63.544594] Initializing watchdogs [63.544656] /dev/watchdog0 [63.558588] [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] [63.563098] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [63.565208] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [63.568422] [001/129] (960s left) kms_frontbuffer_tracking (psr-rgb565-draw-render) [63.746307] Starting subtest: psr-rgb565-draw-render [68.716678] Subtest psr-rgb565-draw-render: SUCCESS (4.969s) [68.843171] [002/129] (955s left) drm_fdinfo (context-close-stress) [68.888624] Starting subtest: context-close-stress [79.639335] Subtest context-close-stress: SUCCESS (10.747s) [79.712850] [003/129] (944s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs) [80.024450] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs [80.027038] Starting dynamic subtest: pipe-A-eDP-1 [80.048182] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [80.055630] Starting dynamic subtest: pipe-B-eDP-1 [80.073867] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [80.079848] Starting dynamic subtest: pipe-C-eDP-1 [80.098201] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [80.104622] Starting dynamic subtest: pipe-D-eDP-1 [80.124455] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [80.126431] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.005s) [82.269744] [004/129] (941s left) kms_flip (2x-wf_vblank-ts-check) [82.371587] Starting subtest: 2x-wf_vblank-ts-check [82.384647] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s) [82.498193] [005/129] (941s left) prime_mmap (test_map_unmap) [82.549977] Starting subtest: test_map_unmap [82.552260] Starting dynamic subtest: test_map_unmap-smem [82.583467] Dynamic subtest test_map_unmap-smem: SUCCESS (0.035s) [82.585856] Subtest test_map_unmap: SUCCESS (0.035s) [82.663712] [006/129] (941s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode) [83.054908] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s) [83.875022] [007/129] (940s left) kms_properties (plane-properties-atomic) [83.971053] Starting subtest: plane-properties-atomic [83.973392] Starting dynamic subtest: pipe-A-eDP-1 [84.569602] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.595s) [84.577436] Starting dynamic subtest: pipe-B-eDP-1 [85.863313] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.293s) [85.870661] Starting dynamic subtest: pipe-C-eDP-1 [87.088623] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.223s) [87.096087] Starting dynamic subtest: pipe-D-eDP-1 [88.317111] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.228s) [88.319559] Subtest plane-properties-atomic: SUCCESS (4.343s) [89.110075] [008/129] (935s left) kms_addfb_basic (size-max) [89.209949] Starting subtest: size-max [89.212402] Subtest size-max: SUCCESS (0.000s) [89.315724] [009/129] (935s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff) [89.425830] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff [92.319093] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (2.898s) [92.469983] [010/129] (931s left) gem_lmem_swapping (parallel-random-verify) [92.511444] Subtest parallel-random-verify: SKIP (0.000s) [92.543195] [011/129] (931s left) kms_cursor_crc (cursor-rapid-movement-512x170) [92.796971] Starting subtest: cursor-rapid-movement-512x170 [92.801246] Subtest cursor-rapid-movement-512x170: SKIP (0.001s) [92.951624] [012/129] (931s left) syncobj_timeline (wait-all-for-submit-snapshot) [93.017078] Starting subtest: wait-all-for-submit-snapshot [93.100031] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s) [93.165877] [013/129] (931s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf) [98.197089] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf [98.201663] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s) [99.028871] [014/129] (925s left) gem_mmap_wc (write-read) [99.082314] Subtest write-read: SKIP (0.000s) [99.134432] [015/129] (925s left) vgem_slow (nohang) [99.255355] Starting subtest: nohang [109.705999] Subtest nohang: SUCCESS (10.449s) [109.760155] [FACT vgem_slow (nohang)] new: kernel.kmod_is_loaded.vgem: true [109.762964] [016/129] (914s left) gem_mmap (bad-object) [109.825902] Subtest bad-object: SKIP (0.000s) [109.898613] [017/129] (914s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt) [110.015215] Starting subtest: psr-2p-primscrn-indfb-plflip-blt [110.019626] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s) [110.093934] [018/129] (914s left) sw_sync (alloc_fence_invalid_timeline) [110.131452] Starting subtest: alloc_fence_invalid_timeline [110.133415] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s) [110.178182] [019/129] (914s left) gem_pread (uncached) [110.243516] Subtest uncached: SKIP (0.000s) [110.299132] [020/129] (914s left) gem_exec_fence (concurrent) [110.477915] Starting subtest: concurrent [110.482747] Subtest concurrent: SKIP (0.000s) [112.020634] [021/129] (912s left) kms_plane_cursor (primary) [112.118929] Starting subtest: primary [112.123295] Starting dynamic subtest: pipe-A-eDP-1-size-64 [114.860075] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.739s) [114.882336] Starting dynamic subtest: pipe-A-eDP-1-size-128 [117.650809] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.772s) [117.672417] Starting dynamic subtest: pipe-A-eDP-1-size-256 [120.416432] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.748s) [120.728909] Starting dynamic subtest: pipe-B-eDP-1-size-64 [124.245743] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.524s) [124.266515] Starting dynamic subtest: pipe-B-eDP-1-size-128 [127.023145] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.760s) [127.045237] Starting dynamic subtest: pipe-B-eDP-1-size-256 [129.825323] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.784s) [130.137245] Starting dynamic subtest: pipe-C-eDP-1-size-64 [133.269345] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.140s) [133.294498] Starting dynamic subtest: pipe-C-eDP-1-size-128 [135.702990] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.414s) [135.729248] Starting dynamic subtest: pipe-C-eDP-1-size-256 [138.103226] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.380s) [138.418671] Starting dynamic subtest: pipe-D-eDP-1-size-64 [141.499015] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.090s) [141.523987] Starting dynamic subtest: pipe-D-eDP-1-size-128 [143.933160] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.413s) [143.956908] Starting dynamic subtest: pipe-D-eDP-1-size-256 [146.344503] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.391s) [146.653751] Subtest primary: SUCCESS (34.533s) [147.442942] [022/129] (877s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move) [147.556774] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move [147.561075] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [147.638146] [023/129] (877s left) gem_mmap_wc (bad-object) [147.696324] Subtest bad-object: SKIP (0.000s) [147.755221] [024/129] (877s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling) [147.862271] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling [147.864572] Starting dynamic subtest: pipe-A-default-mode [148.151692] Dynamic subtest pipe-A-default-mode: SKIP (0.283s) [148.156380] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.284s) [148.966708] [025/129] (875s left) kms_fbc_dirty_rect (fbc-dirty-rectangle-out-visible-area) [149.284499] Subtest fbc-dirty-rectangle-out-visible-area: SKIP (0.000s) [149.332757] [FACT kms_fbc_dirty_rect (fbc-dirty-rectangle-out-visible-area)] new: kernel.kmod_is_loaded.xe: true [149.335909] [026/129] (875s left) kms_cursor_edge_walk (256x256-right-edge) [149.427115] Starting subtest: 256x256-right-edge [149.429396] Starting dynamic subtest: pipe-A-eDP-1 [152.830924] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.402s) [152.838980] Starting dynamic subtest: pipe-D-eDP-1 [155.936356] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.103s) [155.938628] Subtest 256x256-right-edge: SUCCESS (6.507s) [156.746721] [027/129] (868s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [156.844253] Starting subtest: compare-crc-sanitycheck-nv12 [156.846515] Starting dynamic subtest: pipe-A-eDP-1 [157.497357] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.650s) [157.505562] Starting dynamic subtest: pipe-B-eDP-1 [158.784168] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.287s) [158.791824] Starting dynamic subtest: pipe-C-eDP-1 [159.972981] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.186s) [159.984112] Starting dynamic subtest: pipe-D-eDP-1 [161.193486] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.220s) [161.195781] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.347s) [162.043370] [028/129] (862s left) kms_pm_rpm (modeset-lpsp) [164.088529] Starting subtest: modeset-lpsp [165.527695] Subtest modeset-lpsp: SUCCESS (1.438s) [165.607282] [029/129] (859s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all) [165.660638] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all [165.735661] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.012s) [165.770304] [030/129] (859s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format) [165.874817] Starting subtest: plane-scaler-unity-scaling-with-pixel-format [165.877208] Starting dynamic subtest: pipe-A [167.393226] Dynamic subtest pipe-A: SUCCESS (1.522s) [167.400734] Starting dynamic subtest: pipe-B [170.029681] Dynamic subtest pipe-B: SUCCESS (2.636s) [170.039138] Starting dynamic subtest: pipe-C [171.175975] Dynamic subtest pipe-C: SUCCESS (1.145s) [171.183777] Starting dynamic subtest: pipe-D [172.411449] Dynamic subtest pipe-D: SUCCESS (1.235s) [172.413820] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (6.540s) [173.582590] [031/129] (851s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-pwrite) [173.696058] Starting subtest: psr-1p-offscren-pri-indfb-draw-pwrite [178.702689] Subtest psr-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (5.009s) [178.819939] [032/129] (846s left) kms_big_fb (yf-tiled-64bpp-rotate-180) [178.931261] Starting subtest: yf-tiled-64bpp-rotate-180 [178.935357] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s) [179.011980] [033/129] (846s left) kms_plane_scaling (invalid-parameters) [179.116561] Starting subtest: invalid-parameters [179.119115] Starting dynamic subtest: less-than-1-height-src [179.124472] Dynamic subtest less-than-1-height-src: SUCCESS (0.010s) [179.131788] Starting dynamic subtest: less-than-1-width-src [179.137065] Dynamic subtest less-than-1-width-src: SUCCESS (0.010s) [179.138951] Subtest invalid-parameters: SUCCESS (0.021s) [179.248104] [034/129] (845s left) gem_watchdog (default-physical) [179.330470] Starting subtest: default-physical [181.085320] Subtest default-physical: SUCCESS (1.767s) [181.199116] [035/129] (844s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled) [181.262916] Starting subtest: multi-wait-for-submit-available-submitted-signaled [181.265277] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.001s) [181.370167] [036/129] (843s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite) [181.476540] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite [181.480661] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [181.579332] [037/129] (843s left) gem_tiled_partial_pwrite_pread (writes-after-reads) [181.648590] Subtest writes-after-reads: SKIP (0.000s) [181.697438] [038/129] (843s left) gem_ctx_exec (basic-close-race) [181.761774] Starting subtest: basic-close-race [187.876202] Subtest basic-close-race: SUCCESS (6.114s) [187.986474] [039/129] (837s left) syncobj_timeline (multi-wait-all-available-submitted) [188.046743] Starting subtest: multi-wait-all-available-submitted [188.049213] Subtest multi-wait-all-available-submitted: SUCCESS (0.000s) [188.147335] [040/129] (837s left) kms_big_fb (x-tiled-8bpp-rotate-0) [188.260028] Starting subtest: x-tiled-8bpp-rotate-0 [189.374379] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.112s) [189.512537] [041/129] (835s left) kms_psr (fbc-pr-primary-mmap-gtt) [189.615071] Starting subtest: fbc-pr-primary-mmap-gtt [189.619376] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s) [189.683120] [042/129] (835s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip) [189.796311] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip [189.799936] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s) [189.889257] [043/129] (835s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite) [189.994537] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite [189.998282] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [190.104795] [044/129] (835s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move) [190.209588] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move [195.451337] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (5.243s) [195.615289] [045/129] (829s left) gem_ccs (block-multicopy-inplace) [195.679746] Starting subtest: block-multicopy-inplace [195.684168] Subtest block-multicopy-inplace: SKIP (0.000s) [195.787828] [046/129] (829s left) core_getstats [195.958817] [047/129] (829s left) gem_workarounds (suspend-resume) [196.029849] Starting subtest: suspend-resume [212.216822] Subtest suspend-resume: SUCCESS (1.241s) [213.351839] [048/129] (828s left) api_intel_allocator (open-vm) [213.414455] Starting subtest: open-vm [213.416798] Subtest open-vm: SUCCESS (0.003s) [213.518158] [049/129] (828s left) syncobj_wait (invalid-single-wait-all-unsubmitted) [213.584150] Starting subtest: invalid-single-wait-all-unsubmitted [213.586537] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s) [213.674611] [050/129] (827s left) gem_mmap (bad-size) [213.735561] Subtest bad-size: SKIP (0.000s) [213.801231] [051/129] (827s left) kms_psr (fbc-psr2-sprite-blt) [213.902940] Starting subtest: fbc-psr2-sprite-blt [213.904856] Starting dynamic subtest: eDP-1 [213.909043] Dynamic subtest eDP-1: SKIP (0.000s) [213.913111] Subtest fbc-psr2-sprite-blt: SKIP (0.001s) [213.971190] [052/129] (827s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled) [214.025440] Starting subtest: invalid-multi-wait-unsubmitted-signaled [214.027753] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s) [214.131476] [053/129] (827s left) kms_cursor_crc (cursor-offscreen-128x128) [214.318217] Starting subtest: cursor-offscreen-128x128 [214.323987] Starting dynamic subtest: pipe-A-eDP-1 [216.311879] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.990s) [216.340707] Starting dynamic subtest: pipe-D-eDP-1 [218.974054] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.639s) [218.976338] Subtest cursor-offscreen-128x128: SUCCESS (4.656s) [219.803692] [054/129] (821s left) kms_pm_backlight (bad-brightness) [219.906959] Starting subtest: bad-brightness [219.968769] Starting dynamic subtest: eDP-1 [220.276727] Dynamic subtest eDP-1: SUCCESS (0.306s) [220.279233] Subtest bad-brightness: SUCCESS (0.371s) [221.132160] [055/129] (820s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc) [221.221953] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc [221.224200] Starting dynamic subtest: pipe-A-eDP-1 [221.244523] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [221.254220] Starting dynamic subtest: pipe-B-eDP-1 [221.273965] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [221.281706] Starting dynamic subtest: pipe-C-eDP-1 [221.301515] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [221.309033] Starting dynamic subtest: pipe-D-eDP-1 [221.329167] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [221.331377] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s) [223.380276] [056/129] (818s left) gem_exec_reloc (basic-write-wc-active) [223.444149] Subtest basic-write-wc-active: SKIP (0.000s) [223.515096] [057/129] (818s left) gem_exec_reloc (basic-gtt-wc-noreloc) [223.580540] Subtest basic-gtt-wc-noreloc: SKIP (0.000s) [223.648605] [058/129] (818s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted) [223.696155] Starting subtest: etime-multi-wait-all-for-submit-submitted [223.802967] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.105s) [223.874504] [059/129] (818s left) perf (blocking-parameterized) [223.980199] Starting subtest: blocking-parameterized [244.018801] Subtest blocking-parameterized: SUCCESS (19.973s) [244.062926] [060/129] (798s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size) [244.184555] Starting subtest: cursorB-vs-flipB-varying-size [244.188829] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s) [244.316047] [061/129] (797s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [244.441597] Starting subtest: basic-flip-before-cursor-varying-size [245.358224] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.917s) [246.171798] [062/129] (795s left) kms_atomic_transition (plane-all-modeset-transition-fencing) [246.283223] Starting subtest: plane-all-modeset-transition-fencing [246.287496] Subtest plane-all-modeset-transition-fencing: SKIP (0.000s) [246.349973] [063/129] (795s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs) [246.407012] Starting subtest: mixed-tiled-to-y-tiled-ccs [246.411554] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s) [246.584468] [064/129] (795s left) gem_exec_fence (syncobj-wait) [247.019812] Starting subtest: syncobj-wait [248.965945] Subtest syncobj-wait: SUCCESS (1.944s) [249.300298] [065/129] (792s left) kms_chamelium_hpd (hdmi-hpd-storm-disable) [249.690772] Subtest hdmi-hpd-storm-disable: SKIP (0.000s) [250.477299] [066/129] (791s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt) [250.580445] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt [250.584478] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [250.665045] [067/129] (791s left) gen9_exec_parse (secure-batches) [250.728424] Subtest secure-batches: SKIP (0.000s) [250.792226] [068/129] (791s left) fbdev (unaligned-read) [250.830970] Starting subtest: unaligned-read [250.847675] Subtest unaligned-read: SUCCESS (0.017s) [250.893334] [069/129] (791s left) kms_addfb_basic (too-wide) [250.993728] Starting subtest: too-wide [250.995982] Subtest too-wide: SUCCESS (0.000s) [251.091188] [070/129] (791s left) kms_invalid_mode (zero-vdisplay) [251.195004] Starting subtest: zero-vdisplay [251.197230] Starting dynamic subtest: pipe-A-eDP-1 [251.476964] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.281s) [251.487850] Starting dynamic subtest: pipe-B-eDP-1 [251.490174] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [251.497264] Starting dynamic subtest: pipe-C-eDP-1 [251.499683] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [251.507166] Starting dynamic subtest: pipe-D-eDP-1 [251.509462] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [251.511719] Subtest zero-vdisplay: SUCCESS (0.287s) [252.324197] [071/129] (790s left) gem_mmap_gtt (basic-write) [252.385052] Subtest basic-write: SKIP (0.000s) [252.447910] [072/129] (790s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf) [257.502724] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf [257.507792] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s) [258.332420] [073/129] (784s left) gem_pwrite (basic-random) [258.398621] Subtest basic-random: SKIP (0.000s) [258.446884] [074/129] (784s left) kms_flip (nonexisting-fb) [258.542994] Starting subtest: nonexisting-fb [258.545336] Starting dynamic subtest: A-eDP1 [259.722560] Dynamic subtest A-eDP1: SUCCESS (1.178s) [259.731227] Starting dynamic subtest: B-eDP1 [260.950865] Dynamic subtest B-eDP1: SUCCESS (1.226s) [260.961804] Starting dynamic subtest: C-eDP1 [262.170848] Dynamic subtest C-eDP1: SUCCESS (1.218s) [262.182249] Starting dynamic subtest: D-eDP1 [263.399742] Dynamic subtest D-eDP1: SUCCESS (1.227s) [263.402590] Subtest nonexisting-fb: SUCCESS (4.857s) [264.557099] [075/129] (778s left) kms_addfb_basic (master-rmfb) [264.612151] Starting subtest: master-rmfb [264.614485] Subtest master-rmfb: SUCCESS (0.001s) [264.753342] [076/129] (777s left) perf (non-zero-reason) [264.833861] Starting subtest: non-zero-reason [264.836352] Starting dynamic subtest: 0-rcs0 [270.319352] Dynamic subtest 0-rcs0: SUCCESS (5.480s) [270.329123] Starting dynamic subtest: 1-vecs0 [274.286683] Dynamic subtest 1-vecs0: SUCCESS (3.964s) [274.289994] Subtest non-zero-reason: SUCCESS (9.446s) [274.330931] [077/129] (768s left) api_intel_bb (reset-flags) [274.389926] Starting subtest: reset-flags [274.400144] Subtest reset-flags: SUCCESS (0.013s) [274.540790] [078/129] (768s left) gem_exec_reloc (basic-cpu-active) [274.609329] Subtest basic-cpu-active: SKIP (0.000s) [274.691501] [079/129] (768s left) kms_chamelium_color (degamma) [274.793222] Subtest degamma: SKIP (0.000s) [274.833395] [080/129] (767s left) gem_softpin (allocator-nopin) [274.899561] Starting subtest: allocator-nopin [274.987446] Subtest allocator-nopin: SUCCESS (0.088s) [275.102980] [081/129] (767s left) kms_psr (fbc-pr-basic) [275.193091] Starting subtest: fbc-pr-basic [275.197511] Subtest fbc-pr-basic: SKIP (0.000s) [275.261685] [082/129] (767s left) gem_spin_batch (legacy) [275.320853] Starting subtest: legacy [275.323021] Starting dynamic subtest: default [278.561035] Dynamic subtest default: SUCCESS (3.232s) [278.571216] Starting dynamic subtest: render [281.797321] Dynamic subtest render: SUCCESS (3.232s) [281.805749] Starting dynamic subtest: bsd [285.031665] Dynamic subtest bsd: SUCCESS (3.229s) [285.042118] Starting dynamic subtest: bsd1 [288.269018] Dynamic subtest bsd1: SUCCESS (3.233s) [288.278740] Starting dynamic subtest: bsd2 [291.504882] Dynamic subtest bsd2: SUCCESS (3.230s) [291.514524] Starting dynamic subtest: blt [294.739238] Dynamic subtest blt: SUCCESS (3.230s) [294.749231] Starting dynamic subtest: vebox [297.976193] Dynamic subtest vebox: SUCCESS (3.233s) [297.978702] Subtest legacy: SUCCESS (22.640s) [299.001120] [083/129] (743s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt) [299.105636] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt [299.109813] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [299.210786] [084/129] (743s left) kms_cursor_crc (cursor-random-64x21) [299.381421] Starting subtest: cursor-random-64x21 [299.423840] Subtest cursor-random-64x21: SKIP (0.035s) [299.654962] [085/129] (743s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size) [299.785727] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size [330.546852] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (30.758s) [331.392586] [086/129] (711s left) gem_exec_balancer (individual) [331.463630] Starting subtest: individual [332.864358] Subtest individual: SUCCESS (1.400s) [333.171213] [087/129] (709s left) gem_exec_whisper (basic-normal) [333.236314] Starting subtest: basic-normal [337.846373] Subtest basic-normal: SUCCESS (4.611s) [338.022228] [088/129] (705s left) gem_exec_schedule (preempt-other-chain) [338.092649] Starting subtest: preempt-other-chain [338.094929] Starting dynamic subtest: rcs0 [338.227600] Dynamic subtest rcs0: SUCCESS (0.137s) [338.238259] Starting dynamic subtest: bcs0 [338.356301] Dynamic subtest bcs0: SUCCESS (0.128s) [338.367771] Starting dynamic subtest: vcs0 [338.484752] Dynamic subtest vcs0: SUCCESS (0.127s) [338.498625] Starting dynamic subtest: vcs1 [338.611886] Dynamic subtest vcs1: SUCCESS (0.127s) [338.625252] Starting dynamic subtest: vecs0 [338.741104] Dynamic subtest vecs0: SUCCESS (0.129s) [338.753944] Starting dynamic subtest: ccs0 [338.865135] Dynamic subtest ccs0: SUCCESS (0.122s) [338.867810] Subtest preempt-other-chain: SUCCESS (0.774s) [339.950389] [089/129] (703s left) gem_exec_schedule (fifo) [340.030318] Starting subtest: fifo [340.032544] Starting dynamic subtest: rcs0 [340.182685] Dynamic subtest rcs0: SUCCESS (0.161s) [340.194285] Starting dynamic subtest: vcs0 [340.289383] Dynamic subtest vcs0: SUCCESS (0.106s) [340.300982] Starting dynamic subtest: vcs1 [340.400959] Dynamic subtest vcs1: SUCCESS (0.111s) [340.411536] Starting dynamic subtest: vecs0 [340.493740] Dynamic subtest vecs0: SUCCESS (0.092s) [340.505077] Starting dynamic subtest: ccs0 [340.608140] Dynamic subtest ccs0: SUCCESS (0.114s) [340.610761] Subtest fifo: SUCCESS (0.587s) [342.010134] [090/129] (701s left) kms_flip (basic-flip-vs-dpms) [342.114198] Starting subtest: basic-flip-vs-dpms [342.116514] Starting dynamic subtest: A-eDP1 [344.674368] Dynamic subtest A-eDP1: SUCCESS (2.560s) [344.683776] Starting dynamic subtest: B-eDP1 [346.838443] Dynamic subtest B-eDP1: SUCCESS (2.161s) [346.847340] Starting dynamic subtest: C-eDP1 [348.972106] Dynamic subtest C-eDP1: SUCCESS (2.134s) [348.981551] Starting dynamic subtest: D-eDP1 [351.119177] Dynamic subtest D-eDP1: SUCCESS (2.144s) [351.121574] Subtest basic-flip-vs-dpms: SUCCESS (9.009s) [351.907826] [091/129] (691s left) gem_ctx_persistence (saturated-hostile) [351.979586] Starting subtest: saturated-hostile [351.982306] Starting dynamic subtest: rcs0 [352.837122] Dynamic subtest rcs0: SUCCESS (0.857s) [352.847941] Starting dynamic subtest: bcs0 [353.625821] Dynamic subtest bcs0: SUCCESS (0.787s) [353.636857] Starting dynamic subtest: vcs0 [354.431592] Dynamic subtest vcs0: SUCCESS (0.804s) [354.441204] Starting dynamic subtest: vcs1 [355.209714] Dynamic subtest vcs1: SUCCESS (0.777s) [355.218873] Starting dynamic subtest: vecs0 [356.038444] Dynamic subtest vecs0: SUCCESS (0.827s) [356.047452] Starting dynamic subtest: ccs0 [356.891553] Dynamic subtest ccs0: SUCCESS (0.852s) [356.894028] Subtest saturated-hostile: SUCCESS (4.913s) [356.967737] [092/129] (686s left) kms_dsc (dsc-with-bpc-formats) [357.065131] Starting subtest: dsc-with-bpc-formats [357.177746] Subtest dsc-with-bpc-formats: SKIP (0.003s) [357.212627] [093/129] (686s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs) [357.302631] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs [357.304995] Starting dynamic subtest: pipe-A-eDP-1 [357.337541] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [357.347999] Starting dynamic subtest: pipe-B-eDP-1 [357.369082] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [357.377729] Starting dynamic subtest: pipe-C-eDP-1 [357.400515] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [357.409999] Starting dynamic subtest: pipe-D-eDP-1 [357.432670] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [357.436812] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s) [359.462379] [094/129] (683s left) gem_softpin (evict-snoop-interruptible) [359.520705] Starting subtest: evict-snoop-interruptible [359.525007] Subtest evict-snoop-interruptible: SKIP (0.000s) [359.638346] [095/129] (683s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs) [359.739285] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs [359.741590] Starting dynamic subtest: pipe-A-eDP-1 [359.767179] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [359.776492] Starting dynamic subtest: pipe-B-eDP-1 [359.800268] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [359.808138] Starting dynamic subtest: pipe-C-eDP-1 [359.832002] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [359.839877] Starting dynamic subtest: pipe-D-eDP-1 [359.864283] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [359.868741] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.005s) [361.919258] [096/129] (681s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt) [362.017479] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt [362.021780] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s) [362.102663] [097/129] (681s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip) [362.201476] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip [363.144965] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.945s) [363.288332] [098/129] (680s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt) [363.400129] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt [363.404055] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [363.493211] [099/129] (679s left) api_intel_allocator (alloc-simple) [363.552963] Starting subtest: alloc-simple [363.554946] Subtest alloc-simple: SUCCESS (0.003s) [363.672384] [100/129] (679s left) gem_workarounds (basic-read) [363.742950] Starting subtest: basic-read [363.753348] Subtest basic-read: SUCCESS (0.010s) [363.867536] [101/129] (679s left) perf (create-destroy-userspace-config) [363.946279] Starting subtest: create-destroy-userspace-config [363.972981] Subtest create-destroy-userspace-config: SUCCESS (0.025s) [364.016189] [102/129] (679s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling) [364.112987] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling [364.114954] Starting dynamic subtest: pipe-A-default-mode [364.399462] Dynamic subtest pipe-A-default-mode: SKIP (0.283s) [364.404306] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.284s) [365.244336] [103/129] (678s left) gem_render_tiled_blits (basic) [365.315225] Subtest basic: SKIP (0.000s) [365.368397] [104/129] (678s left) kms_vblank (query-forked-hang) [365.465008] Starting subtest: query-forked-hang [365.467235] Starting dynamic subtest: pipe-A-eDP-1 [391.625500] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.154s) [391.638371] Starting dynamic subtest: pipe-D-eDP-1 [416.643183] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.010s) [416.645617] Subtest query-forked-hang: SUCCESS (51.169s) [417.496399] [105/129] (626s left) sysfs_timeslice_duration (timeout) [417.563266] Starting subtest: timeout [417.565548] Starting dynamic subtest: vecs0 [426.910102] Dynamic subtest vecs0: SUCCESS (9.341s) [426.921755] Starting dynamic subtest: ccs0 [428.047968] Dynamic subtest ccs0: SUCCESS (1.137s) [428.057975] Starting dynamic subtest: vcs0 [437.921160] Dynamic subtest vcs0: SUCCESS (9.866s) [437.930200] Starting dynamic subtest: bcs0 [438.449041] Dynamic subtest bcs0: SUCCESS (0.528s) [438.457261] Starting dynamic subtest: rcs0 [439.682976] Dynamic subtest rcs0: SUCCESS (1.231s) [439.692518] Starting dynamic subtest: vcs1 [448.928161] Dynamic subtest vcs1: SUCCESS (9.240s) [448.930514] Subtest timeout: SUCCESS (31.355s) [448.998563] [106/129] (594s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render) [449.113203] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render [449.117491] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [449.226340] [107/129] (594s left) i915_pm_rpm (system-suspend) [451.064684] Starting subtest: system-suspend [468.988097] Subtest system-suspend: SUCCESS (2.889s) [469.344104] [108/129] (589s left) perf_pmu (invalid-init) [469.386623] Starting subtest: invalid-init [469.450790] Subtest invalid-init: FAIL (0.005s) [469.503526] [109/129] (589s left) i915_pm_freq_api (freq-reset-multiple) [469.563245] Starting subtest: freq-reset-multiple [469.566108] Starting dynamic subtest: gt0 [478.554951] Dynamic subtest gt0: SUCCESS (8.940s) [478.565373] Starting dynamic subtest: gt1 [486.528123] Dynamic subtest gt1: SUCCESS (7.955s) [486.531125] Subtest freq-reset-multiple: SUCCESS (16.897s) [486.600008] [110/129] (572s left) perf_pmu (most-busy-check-all) [486.642246] Starting subtest: most-busy-check-all [486.645012] Starting dynamic subtest: rcs0 [487.942928] Dynamic subtest rcs0: SUCCESS (1.299s) [487.955177] Starting dynamic subtest: bcs0 [489.926993] Dynamic subtest bcs0: SUCCESS (1.980s) [489.939187] Starting dynamic subtest: vcs0 [491.910830] Dynamic subtest vcs0: SUCCESS (1.979s) [491.923213] Starting dynamic subtest: vcs1 [493.894185] Dynamic subtest vcs1: SUCCESS (1.979s) [493.906937] Starting dynamic subtest: vecs0 [495.878609] Dynamic subtest vecs0: SUCCESS (1.980s) [495.890827] Starting dynamic subtest: ccs0 [497.926714] Dynamic subtest ccs0: SUCCESS (2.043s) [497.929731] Subtest most-busy-check-all: SUCCESS (11.276s) [498.036675] [111/129] (560s left) prime_mmap (test_correct_cpu_write) [498.103195] Starting subtest: test_correct_cpu_write [498.106027] Starting dynamic subtest: test_correct_cpu_write-smem [498.108975] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s) [498.112021] Subtest test_correct_cpu_write: SUCCESS (0.001s) [498.202519] [112/129] (560s left) kms_scaling_modes (scaling-mode-center) [498.312449] Starting subtest: scaling-mode-center [498.314929] Starting dynamic subtest: pipe-A-eDP-1 [498.428454] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.117s) [498.437880] Starting dynamic subtest: pipe-B-eDP-1 [499.623606] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.194s) [499.632579] Starting dynamic subtest: pipe-C-eDP-1 [500.822858] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.198s) [500.834966] Starting dynamic subtest: pipe-D-eDP-1 [501.952452] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s) [501.955372] Subtest scaling-mode-center: SUCCESS (3.641s) [503.100514] [113/129] (556s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt) [503.213838] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt [503.219010] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [503.299439] [114/129] (555s left) kms_fbcon_fbt (fbc) [503.359174] Starting subtest: fbc [508.675955] Subtest fbc: SUCCESS (5.317s) [508.773899] [115/129] (550s left) i915_query (query-regions-sanity-check) [508.846171] Starting subtest: query-regions-sanity-check [508.857507] Subtest query-regions-sanity-check: SUCCESS (0.015s) [508.982109] [116/129] (550s left) syncobj_basic (illegal-fd-to-handle) [509.049375] Starting subtest: illegal-fd-to-handle [509.051989] Subtest illegal-fd-to-handle: SUCCESS (0.000s) [509.150044] [117/129] (550s left) kms_psr (pr-sprite-plane-move) [509.253623] Starting subtest: pr-sprite-plane-move [509.258928] Subtest pr-sprite-plane-move: SKIP (0.000s) [509.320238] [118/129] (549s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc) [509.419958] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc [509.422173] Starting dynamic subtest: pipe-A-eDP-1 [510.388016] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.960s) [510.397946] Starting dynamic subtest: pipe-B-eDP-1 [511.902696] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.514s) [511.912341] Starting dynamic subtest: pipe-C-eDP-1 [513.364000] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.459s) [513.373628] Starting dynamic subtest: pipe-D-eDP-1 [514.805931] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.442s) [514.807998] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.379s) [515.960090] [119/129] (543s left) kms_atomic (plane-overlay-legacy) [516.058887] Starting subtest: plane-overlay-legacy [516.061464] Starting dynamic subtest: pipe-A-eDP-1 [516.477180] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.416s) [516.479892] Subtest plane-overlay-legacy: SUCCESS (0.418s) [517.278545] [120/129] (542s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc) [517.385295] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc [522.273997] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.891s) [522.414040] [121/129] (536s left) kms_psr (psr-cursor-plane-onoff) [522.520866] Starting subtest: psr-cursor-plane-onoff [522.523374] Starting dynamic subtest: eDP-1 [524.230429] Dynamic subtest eDP-1: SUCCESS (1.706s) [524.233046] Subtest psr-cursor-plane-onoff: SUCCESS (1.707s) [525.086566] [122/129] (534s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip) [525.204468] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip [525.209998] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s) [525.316394] [123/129] (534s left) kms_psr (fbc-psr-sprite-plane-onoff) [525.412968] Starting subtest: fbc-psr-sprite-plane-onoff [525.415466] Starting dynamic subtest: eDP-1 [525.420416] Dynamic subtest eDP-1: SKIP (0.000s) [525.425872] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s) [525.480882] [124/129] (534s left) kms_big_fb (linear-64bpp-rotate-90) [525.588897] Starting subtest: linear-64bpp-rotate-90 [525.608850] Subtest linear-64bpp-rotate-90: SKIP (0.019s) [525.729424] [125/129] (533s left) gem_exec_schedule (semaphore-user) [525.812747] Starting subtest: semaphore-user [525.864220] Subtest semaphore-user: SUCCESS (0.057s) [526.957465] [126/129] (532s left) gem_ctx_persistence (engines-cleanup) [527.025873] Starting subtest: engines-cleanup [527.029051] Starting dynamic subtest: rcs0 [527.127479] Dynamic subtest rcs0: SUCCESS (0.102s) [527.138292] Starting dynamic subtest: bcs0 [527.188612] Dynamic subtest bcs0: SUCCESS (0.059s) [527.199178] Starting dynamic subtest: vcs0 [527.259699] Dynamic subtest vcs0: SUCCESS (0.069s) [527.271272] Starting dynamic subtest: vcs1 [527.318483] Dynamic subtest vcs1: SUCCESS (0.057s) [527.329116] Starting dynamic subtest: vecs0 [527.347257] Dynamic subtest vecs0: SUCCESS (0.027s) [527.355933] Starting dynamic subtest: ccs0 [527.450418] Dynamic subtest ccs0: SUCCESS (0.102s) [527.453213] Subtest engines-cleanup: SUCCESS (0.426s) [527.525553] [127/129] (532s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move) [527.642926] Starting subtest: fbc-1p-primscrn-spr-indfb-move [530.924908] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.285s) [531.105919] [128/129] (528s left) kms_getfb (getfb2-handle-zero) [531.162013] Starting subtest: getfb2-handle-zero [531.164870] Subtest getfb2-handle-zero: SUCCESS (0.000s) [531.276590] [129/129] (528s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size) [531.396889] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size [532.376204] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (0.978s) [533.233118] Closing watchdogs Done.