[61.604544] Initializing watchdogs [61.604592] /dev/watchdog0 [61.636107] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics] [61.640802] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [61.643614] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [61.645676] [001/156] (960s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-render) [61.756440] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-render [61.758192] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [61.846630] [002/156] (960s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-onoff) [61.937908] Starting subtest: hdr-1p-primscrn-spr-indfb-onoff [61.939601] Subtest hdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [62.022558] [003/156] (960s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf) [62.115452] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf [62.116377] Starting dynamic subtest: pipe-A-eDP-1 [62.232207] Dynamic subtest pipe-A-eDP-1: SKIP (0.115s) [62.240437] Starting dynamic subtest: pipe-B-eDP-1 [63.352795] Dynamic subtest pipe-B-eDP-1: SKIP (1.112s) [63.354789] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.228s) [64.770621] [004/156] (957s left) kms_pm_backlight (fade) [64.856574] Starting subtest: fade [64.930744] Starting dynamic subtest: eDP-1 [67.933526] Dynamic subtest eDP-1: SUCCESS (2.989s) [67.934850] Subtest fade: SUCCESS (3.064s) [68.817939] [005/156] (953s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [68.912406] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [68.914059] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [69.002385] [006/156] (953s left) gem_exec_fence (submit67) [69.187037] Starting subtest: submit67 [69.189348] Subtest submit67: SKIP (0.000s) [71.386577] [FACT gem_exec_fence (submit67)] new: kernel.kmod_is_loaded.vgem: true [71.388683] [007/156] (950s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs) [71.486169] Starting subtest: missing-ccs-buffer-yf-tiled-ccs [71.487153] Starting dynamic subtest: pipe-A-eDP-1 [71.496436] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [71.503774] Starting dynamic subtest: pipe-B-eDP-1 [71.512701] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [71.519289] Starting dynamic subtest: pipe-C-eDP-1 [71.527698] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [71.533866] Starting dynamic subtest: pipe-D-eDP-1 [71.542208] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [71.543790] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.003s) [73.776574] [008/156] (948s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe) [74.146305] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s) [74.995793] [009/156] (947s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling) [75.079309] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling [75.081110] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s) [75.147405] [010/156] (947s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu) [75.233930] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu [79.619762] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.383s) [80.836514] [011/156] (941s left) syncobj_wait (invalid-wait-illegal-handle) [80.895751] Starting subtest: invalid-wait-illegal-handle [80.896641] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [80.986163] [012/156] (941s left) perf (buffer-fill) [81.093463] Starting subtest: buffer-fill [81.094703] Starting dynamic subtest: 0-rcs0 [83.369137] Dynamic subtest 0-rcs0: SUCCESS (2.275s) [83.376724] Starting dynamic subtest: 1-vcs0 [87.849372] Dynamic subtest 1-vcs0: SUCCESS (4.468s) [87.850749] Subtest buffer-fill: SUCCESS (6.744s) [87.886193] [013/156] (934s left) kms_dirtyfb (default-dirtyfb-ioctl) [87.969915] Starting subtest: default-dirtyfb-ioctl [87.971032] Starting dynamic subtest: A-eDP-1 [89.613010] Dynamic subtest A-eDP-1: SUCCESS (1.642s) [89.613880] Subtest default-dirtyfb-ioctl: SUCCESS (1.642s) [90.464716] [014/156] (932s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite) [90.567986] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite [90.569778] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [90.657911] [015/156] (931s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-msflip-blt) [90.753065] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-msflip-blt [90.754915] Subtest fbcpsrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [90.869666] [016/156] (931s left) gem_mmap_gtt (big-copy-xy) [90.923600] Subtest big-copy-XY: SKIP (0.000s) [90.984840] [017/156] (931s left) kms_psr (pr-primary-page-flip) [91.080087] Starting subtest: pr-primary-page-flip [91.082029] Subtest pr-primary-page-flip: SKIP (0.000s) [91.145726] [018/156] (931s left) gem_spin_batch (resubmit-new-all) [91.211341] Starting subtest: resubmit-new-all [91.212449] Starting dynamic subtest: rcs0 [91.227286] Dynamic subtest rcs0: SUCCESS (0.016s) [91.236389] Starting dynamic subtest: bcs0 [91.239207] Dynamic subtest bcs0: SUCCESS (0.012s) [91.245937] Starting dynamic subtest: vcs0 [91.249712] Dynamic subtest vcs0: SUCCESS (0.009s) [91.259942] Starting dynamic subtest: vcs1 [91.261150] Dynamic subtest vcs1: SUCCESS (0.008s) [91.267879] Starting dynamic subtest: vecs0 [91.268944] Dynamic subtest vecs0: SUCCESS (0.010s) [91.275486] Starting dynamic subtest: ccs0 [91.277536] Dynamic subtest ccs0: SUCCESS (0.011s) [91.278575] Subtest resubmit-new-all: SUCCESS (0.067s) [92.356121] [019/156] (930s left) gem_mmap (basic-small-bo) [92.403179] Subtest basic-small-bo: SKIP (0.000s) [92.455519] [020/156] (930s left) kms_frontbuffer_tracking (fbchdr-2p-rte) [92.545539] Starting subtest: fbchdr-2p-rte [92.547433] Subtest fbchdr-2p-rte: SKIP (0.000s) [92.640436] [021/156] (930s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip) [92.745830] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip [93.393402] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.648s) [93.560423] [022/156] (929s left) i915_hangman (hangcheck-unterminated) [93.626342] Starting subtest: hangcheck-unterminated [105.285465] Subtest hangcheck-unterminated: SUCCESS (11.646s) [105.391747] [023/156] (917s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled) [105.445327] Starting subtest: multi-wait-all-for-submit-submitted-signaled [105.546771] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.101s) [105.617610] [024/156] (917s left) kms_async_flips (async-flip-with-page-flip-events-tiled) [105.709155] Starting subtest: async-flip-with-page-flip-events-tiled [106.099339] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs [108.861072] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.752s) [108.871641] Starting dynamic subtest: pipe-A-eDP-1-4 [111.033545] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.171s) [111.043103] Starting dynamic subtest: pipe-A-eDP-1-4 [113.043136] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.010s) [113.050758] Starting dynamic subtest: pipe-A-eDP-1-x [115.236090] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.191s) [115.595306] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs [118.394567] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.799s) [118.401794] Starting dynamic subtest: pipe-B-eDP-1-4 [120.581203] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.186s) [120.591488] Starting dynamic subtest: pipe-B-eDP-1-4 [122.589515] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.008s) [122.597145] Starting dynamic subtest: pipe-B-eDP-1-x [124.780862] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.190s) [125.147691] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs [127.936329] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.787s) [127.945629] Starting dynamic subtest: pipe-C-eDP-1-4 [130.120622] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.185s) [130.128223] Starting dynamic subtest: pipe-C-eDP-1-4 [132.130679] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s) [132.138074] Starting dynamic subtest: pipe-C-eDP-1-x [134.309890] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.178s) [134.665500] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs [137.460623] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.794s) [137.470833] Starting dynamic subtest: pipe-D-eDP-1-4 [139.632870] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.172s) [139.641122] Starting dynamic subtest: pipe-D-eDP-1-4 [141.642879] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.010s) [141.650751] Starting dynamic subtest: pipe-D-eDP-1-x [143.822034] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s) [143.822928] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.100s) [145.020735] [025/156] (877s left) gem_ctx_persistence (engines-persistence) [145.082191] Starting subtest: engines-persistence [145.083078] Starting dynamic subtest: rcs0 [145.894543] Dynamic subtest rcs0: SUCCESS (0.813s) [145.904642] Starting dynamic subtest: bcs0 [146.652532] Dynamic subtest bcs0: SUCCESS (0.757s) [146.662824] Starting dynamic subtest: vcs0 [147.426987] Dynamic subtest vcs0: SUCCESS (0.773s) [147.437525] Starting dynamic subtest: vcs1 [148.183457] Dynamic subtest vcs1: SUCCESS (0.756s) [148.193362] Starting dynamic subtest: vecs0 [148.954755] Dynamic subtest vecs0: SUCCESS (0.770s) [148.965388] Starting dynamic subtest: ccs0 [149.721678] Dynamic subtest ccs0: SUCCESS (0.766s) [149.722810] Subtest engines-persistence: SUCCESS (4.641s) [149.796235] [026/156] (873s left) kms_color_pipeline (plane-lut1d-post-ctm3x4) [149.893976] Starting subtest: plane-lut1d-post-ctm3x4 [150.042388] Starting dynamic subtest: pipe-A-plane-0 [150.208679] Dynamic subtest pipe-A-plane-0: SUCCESS (0.167s) [150.218609] Starting dynamic subtest: pipe-A-plane-1 [150.364183] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s) [150.374499] Starting dynamic subtest: pipe-A-plane-2 [150.530622] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s) [151.757678] Starting dynamic subtest: pipe-B-plane-0 [151.922281] Dynamic subtest pipe-B-plane-0: SUCCESS (0.178s) [151.932325] Starting dynamic subtest: pipe-B-plane-1 [152.088718] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s) [152.098466] Starting dynamic subtest: pipe-B-plane-2 [152.266548] Dynamic subtest pipe-B-plane-2: SUCCESS (0.177s) [153.459964] Starting dynamic subtest: pipe-C-plane-0 [153.597688] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s) [153.605310] Starting dynamic subtest: pipe-C-plane-1 [153.753292] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s) [153.762204] Starting dynamic subtest: pipe-C-plane-2 [153.908728] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s) [155.088537] Starting dynamic subtest: pipe-D-plane-0 [155.234266] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s) [155.241787] Starting dynamic subtest: pipe-D-plane-1 [155.378849] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s) [155.390512] Starting dynamic subtest: pipe-D-plane-2 [155.523260] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s) [155.807872] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.914s) [156.656875] [027/156] (866s left) gem_exec_schedule (u-fairslice-all) [156.730343] Starting subtest: u-fairslice-all [158.786213] Subtest u-fairslice-all: SUCCESS (2.057s) [160.429166] [028/156] (862s left) kms_big_fb (y-tiled-32bpp-rotate-180) [160.540955] Starting subtest: y-tiled-32bpp-rotate-180 [160.542884] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s) [160.637075] [029/156] (862s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu) [160.730656] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu [160.732338] Subtest fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [160.830534] [030/156] (862s left) kms_cursor_crc (cursor-offscreen-512x512) [161.070458] Starting subtest: cursor-offscreen-512x512 [161.072310] Subtest cursor-offscreen-512x512: SKIP (0.001s) [161.231491] [031/156] (861s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-plflip-blt) [161.320143] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-plflip-blt [161.329860] Subtest fbcpsrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [161.408701] [032/156] (861s left) kms_invalid_mode (bad-htotal) [161.500516] Starting subtest: bad-htotal [161.501496] Starting dynamic subtest: pipe-A-eDP-1 [161.787216] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s) [161.796853] Starting dynamic subtest: pipe-B-eDP-1 [161.797763] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [161.805158] Starting dynamic subtest: pipe-C-eDP-1 [161.806733] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [161.813765] Starting dynamic subtest: pipe-D-eDP-1 [161.814673] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [161.815575] Subtest bad-htotal: SUCCESS (0.291s) [162.586427] [033/156] (860s left) gem_ccs (block-copy-compressed) [162.649502] Starting subtest: block-copy-compressed [162.651483] Subtest block-copy-compressed: SKIP (0.000s) [162.743729] [034/156] (860s left) kms_psr (psr-sprite-mmap-gtt) [162.829524] Starting subtest: psr-sprite-mmap-gtt [162.830552] Starting dynamic subtest: eDP-1 [164.300732] Dynamic subtest eDP-1: SKIP (1.462s) [164.303156] Subtest psr-sprite-mmap-gtt: SKIP (1.465s) [164.450062] [035/156] (858s left) gem_exec_schedule (fifo) [164.519441] Starting subtest: fifo [164.520434] Starting dynamic subtest: rcs0 [164.615394] Dynamic subtest rcs0: SUCCESS (0.096s) [164.625113] Starting dynamic subtest: vcs0 [164.691343] Dynamic subtest vcs0: SUCCESS (0.076s) [164.701622] Starting dynamic subtest: vcs1 [164.766420] Dynamic subtest vcs1: SUCCESS (0.075s) [164.777509] Starting dynamic subtest: vecs0 [164.842068] Dynamic subtest vecs0: SUCCESS (0.076s) [164.852054] Starting dynamic subtest: ccs0 [164.923825] Dynamic subtest ccs0: SUCCESS (0.080s) [164.925423] Subtest fifo: SUCCESS (0.405s) [166.391069] [036/156] (856s left) kms_pipe_crc_basic (suspend-read-crc) [166.479384] Starting subtest: suspend-read-crc [166.480354] Starting dynamic subtest: pipe-A-eDP-1 [183.875921] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.085s) [183.885769] Starting dynamic subtest: pipe-B-eDP-1 [202.862142] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.579s) [202.871997] Starting dynamic subtest: pipe-C-eDP-1 [221.812230] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.510s) [221.821210] Starting dynamic subtest: pipe-D-eDP-1 [240.833896] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.461s) [240.834781] Subtest suspend-read-crc: SUCCESS (13.640s) [246.706051] [037/156] (781s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip) [246.807206] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip [246.809080] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s) [246.898600] [038/156] (781s left) kms_chamelium_edid (vga-edid-read) [247.281869] Subtest vga-edid-read: SKIP (0.000s) [248.099050] [039/156] (780s left) gem_exec_flush (basic-wb-prw-default) [248.156228] Starting subtest: basic-wb-prw-default [253.727270] Subtest basic-wb-prw-default: SUCCESS (5.457s) [253.911966] [040/156] (774s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-move) [254.016579] Starting subtest: psrhdr-2p-primscrn-cur-indfb-move [254.018224] Subtest psrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [254.101571] [041/156] (774s left) kms_flip (2x-flip-vs-panning) [254.187515] Starting subtest: 2x-flip-vs-panning [254.198016] Subtest 2x-flip-vs-panning: SKIP (0.011s) [254.276662] [042/156] (774s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc) [254.373655] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc [254.375407] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [254.474312] [043/156] (774s left) gem_pxp (create-protected-buffer) [254.597698] Starting subtest: create-protected-buffer [254.598866] Subtest create-protected-buffer: SUCCESS (0.000s) [254.709520] [044/156] (773s left) gem_exec_reloc (basic-softpin) [254.779017] Subtest basic-softpin: SKIP (0.000s) [254.851733] [045/156] (773s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc) [254.947366] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc [254.948541] Starting dynamic subtest: pipe-A-eDP-1 [254.961662] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [254.974599] Starting dynamic subtest: pipe-B-eDP-1 [254.982917] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [254.991098] Starting dynamic subtest: pipe-C-eDP-1 [254.998903] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [255.007331] Starting dynamic subtest: pipe-D-eDP-1 [255.014772] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [255.015655] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s) [257.227307] [046/156] (771s left) kms_dsc (dsc-with-bpc-formats) [257.320507] Starting subtest: dsc-with-bpc-formats [257.361159] Subtest dsc-with-bpc-formats: SKIP (0.003s) [257.392870] [047/156] (771s left) gem_tiled_swapping (non-threaded) [257.442550] Subtest non-threaded: SKIP (0.000s) [257.518850] [048/156] (771s left) kms_big_fb (linear-64bpp-rotate-0) [257.619108] Starting subtest: linear-64bpp-rotate-0 [259.132355] Subtest linear-64bpp-rotate-0: SUCCESS (1.502s) [259.298552] [049/156] (769s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier) [259.379379] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier [259.406643] Starting dynamic subtest: pipe-A-plane-0 [259.675014] Dynamic subtest pipe-A-plane-0: SUCCESS (0.267s) [259.683738] Starting dynamic subtest: pipe-A-plane-3 [259.910020] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s) [259.919573] Starting dynamic subtest: pipe-A-plane-5 [259.921427] Dynamic subtest pipe-A-plane-5: SKIP (0.000s) [261.053314] Starting dynamic subtest: pipe-B-plane-0 [261.320353] Dynamic subtest pipe-B-plane-0: SUCCESS (0.277s) [261.329191] Starting dynamic subtest: pipe-B-plane-3 [261.532529] Dynamic subtest pipe-B-plane-3: SUCCESS (0.210s) [261.541486] Starting dynamic subtest: pipe-B-plane-5 [261.543189] Dynamic subtest pipe-B-plane-5: SKIP (0.000s) [261.845347] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.452s) [262.768316] [050/156] (766s left) gen7_exec_parse (oacontrol-tracking) [262.824987] Subtest oacontrol-tracking: SKIP (0.000s) [262.931027] [051/156] (766s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff) [263.016860] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff [270.090349] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.054s) [270.233654] [052/156] (758s left) api_intel_bb (misplaced-blitter) [270.276420] Starting subtest: misplaced-blitter [270.282996] Subtest misplaced-blitter: SUCCESS (0.007s) [270.396370] [053/156] (758s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt) [270.490730] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt [270.492525] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [270.579297] [054/156] (758s left) gem_exec_params (secure-non-master) [270.638396] Starting subtest: secure-non-master [270.640052] Subtest secure-non-master: SKIP (0.000s) [270.753134] [055/156] (758s left) sysfs_heartbeat_interval (idempotent) [270.808190] Starting subtest: idempotent [270.809345] Starting dynamic subtest: vecs0 [270.810523] Dynamic subtest vecs0: SUCCESS (0.001s) [270.821792] Starting dynamic subtest: ccs0 [270.822704] Dynamic subtest ccs0: SUCCESS (0.001s) [270.831240] Starting dynamic subtest: vcs0 [270.832330] Dynamic subtest vcs0: SUCCESS (0.000s) [270.840750] Starting dynamic subtest: bcs0 [270.841812] Dynamic subtest bcs0: SUCCESS (0.001s) [270.850021] Starting dynamic subtest: rcs0 [270.851128] Dynamic subtest rcs0: SUCCESS (0.000s) [270.859744] Starting dynamic subtest: vcs1 [270.860761] Dynamic subtest vcs1: SUCCESS (0.000s) [270.861890] Subtest idempotent: SUCCESS (0.008s) [270.909921] [056/156] (758s left) core_setmaster (master-drop-set-user) [270.989035] Starting subtest: master-drop-set-user [271.002117] Subtest master-drop-set-user: SUCCESS (0.013s) [271.034524] [057/156] (758s left) i915_drm_fdinfo (idle) [271.069644] Starting subtest: idle [271.070555] Starting dynamic subtest: rcs0 [271.646160] Dynamic subtest rcs0: SUCCESS (0.574s) [271.659875] Starting dynamic subtest: bcs0 [272.161371] Dynamic subtest bcs0: SUCCESS (0.503s) [272.172649] Starting dynamic subtest: vcs0 [272.667763] Dynamic subtest vcs0: SUCCESS (0.503s) [272.678840] Starting dynamic subtest: vcs1 [273.174313] Dynamic subtest vcs1: SUCCESS (0.503s) [273.185614] Starting dynamic subtest: vecs0 [273.680740] Dynamic subtest vecs0: SUCCESS (0.503s) [273.691897] Starting dynamic subtest: ccs0 [274.186304] Dynamic subtest ccs0: SUCCESS (0.503s) [274.187576] Subtest idle: SUCCESS (3.104s) [274.262969] [058/156] (755s left) gem_render_copy (yf-tiled-ccs-to-x-tiled) [274.318394] Starting subtest: yf-tiled-ccs-to-x-tiled [274.320307] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s) [274.500268] [059/156] (754s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait) [276.226743] Starting subtest: modeset-non-lpsp-stress-no-wait [276.275837] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.051s) [276.739985] [060/156] (752s left) gem_exec_balancer (bonded-false-hang) [277.006455] Starting subtest: bonded-false-hang [277.008292] Subtest bonded-false-hang: SKIP (0.000s) [277.120393] [061/156] (752s left) gem_mmap_gtt (pf-nonblock) [277.174034] Subtest pf-nonblock: SKIP (0.000s) [277.246621] [062/156] (752s left) kms_psr2_sf (psr2-cursor-plane-update-sf) [277.334295] Starting subtest: psr2-cursor-plane-update-sf [277.335176] Starting dynamic subtest: pipe-A-eDP-1 [277.914027] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.579s) [277.922599] Starting dynamic subtest: pipe-B-eDP-1 [279.245786] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.330s) [279.246627] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.911s) [280.113680] [063/156] (749s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-gtt) [280.202496] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-gtt [280.204242] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [280.304835] [064/156] (749s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff) [280.397062] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff [280.398943] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [280.491584] [065/156] (749s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary) [280.579420] Starting subtest: psr-indfb-scaledprimary [286.358733] Subtest psr-indfb-scaledprimary: SUCCESS (5.781s) [286.480114] [066/156] (743s left) gem_render_copy (x-tiled) [286.529983] Starting subtest: x-tiled [286.531076] Starting dynamic subtest: smem [286.573334] Dynamic subtest smem: SUCCESS (0.043s) [286.574630] Subtest x-tiled: SUCCESS (0.044s) [286.754874] [067/156] (742s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area) [286.838868] Starting subtest: psr2-overlay-primary-update-sf-dmg-area [286.839743] Starting dynamic subtest: pipe-A-eDP-1 [292.818049] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.979s) [292.829367] Starting dynamic subtest: pipe-B-eDP-1 [299.482437] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.662s) [299.483416] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.644s) [300.360559] [068/156] (729s left) gem_caching (read-writes) [300.425429] Subtest read-writes: SKIP (0.000s) [300.476327] [069/156] (729s left) kms_pm_rpm (fences) [302.198190] Starting subtest: fences [302.200735] Subtest fences: SKIP (0.000s) [302.704689] [070/156] (727s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [302.753680] Starting subtest: multi-wait-for-submit-available-signaled [302.754875] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s) [302.836461] [071/156] (726s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted) [302.891481] Starting subtest: multi-wait-all-for-submit-unsubmitted [302.996756] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.104s) [303.087519] [072/156] (726s left) kms_vblank (wait-forked) [303.171359] Starting subtest: wait-forked [303.172369] Starting dynamic subtest: pipe-A-eDP-1 [304.992546] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.819s) [305.003743] Starting dynamic subtest: pipe-D-eDP-1 [307.498175] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.502s) [307.499192] Subtest wait-forked: SUCCESS (4.325s) [308.363714] [073/156] (721s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-blt) [308.463003] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-blt [308.464866] Subtest hdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [308.567073] [074/156] (721s left) kms_big_fb (x-tiled-8bpp-rotate-180) [308.679098] Starting subtest: x-tiled-8bpp-rotate-180 [309.893516] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.214s) [310.024682] [075/156] (719s left) syncobj_timeline (wait-for-submit-snapshot) [310.068782] Starting subtest: wait-for-submit-snapshot [310.150669] Subtest wait-for-submit-snapshot: SUCCESS (0.082s) [310.235917] [076/156] (719s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu) [310.324555] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu [310.326180] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [310.413686] [077/156] (719s left) gem_ctx_sseu (engines) [310.465696] Subtest engines: SKIP (0.000s) [310.532722] [078/156] (719s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render) [310.625401] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render [318.132744] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.507s) [318.241196] [079/156] (711s left) gem_softpin (softpin) [318.299837] Starting subtest: softpin [350.693997] Subtest softpin: SUCCESS (32.375s) [350.808945] [080/156] (679s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt) [350.903192] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt [350.905026] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [351.008273] [081/156] (679s left) syncobj_basic (bad-flags-fd-to-handle) [351.053138] Starting subtest: bad-flags-fd-to-handle [351.054153] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s) [351.141949] [082/156] (679s left) gem_partial_pwrite_pread (write-uncached) [351.186366] Subtest write-uncached: SKIP (0.000s) [351.244586] [083/156] (678s left) sw_sync (sync_busy) [351.274179] Starting subtest: sync_busy [351.277710] Subtest sync_busy: SUCCESS (0.002s) [351.326918] [084/156] (678s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite) [351.416447] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite [356.522214] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (5.107s) [357.735055] [085/156] (672s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-gtt) [357.818571] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-gtt [357.820368] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [357.910311] [086/156] (672s left) i915_pm_rps (min-max-config-idle) [357.974045] Starting subtest: min-max-config-idle [357.975630] Subtest min-max-config-idle: SKIP (0.000s) [358.088890] [087/156] (672s left) gem_ctx_persistence (legacy-engines-queued) [358.137070] Starting subtest: legacy-engines-queued [358.137913] Starting dynamic subtest: render [358.206340] Dynamic subtest render: SUCCESS (0.070s) [358.221481] Starting dynamic subtest: bsd1 [359.988340] Dynamic subtest bsd1: SUCCESS (1.768s) [359.997433] Starting dynamic subtest: bsd2 [362.035162] Dynamic subtest bsd2: SUCCESS (2.044s) [362.045628] Starting dynamic subtest: blt [362.082235] Dynamic subtest blt: SUCCESS (0.053s) [362.092773] Starting dynamic subtest: vebox [364.017008] Dynamic subtest vebox: SUCCESS (1.921s) [364.018282] Subtest legacy-engines-queued: SUCCESS (5.872s) [364.098086] [088/156] (666s left) kms_frontbuffer_tracking (fbcpsr-tiling-y) [364.212249] Starting subtest: fbcpsr-tiling-y [364.213850] Subtest fbcpsr-tiling-y: SKIP (0.000s) [364.328804] [089/156] (666s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt) [364.417681] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt [371.220842] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.804s) [371.363022] [090/156] (659s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move) [371.461757] Starting subtest: fbc-2p-primscrn-cur-indfb-move [371.463424] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s) [371.540969] [091/156] (658s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [371.633743] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [379.062535] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.430s) [379.187343] [092/156] (651s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled) [379.249901] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled [379.250861] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s) [379.355440] [093/156] (651s left) kms_universal_plane (disable-primary-vs-flip) [379.454318] Starting subtest: disable-primary-vs-flip [379.455293] Starting dynamic subtest: pipe-A-eDP-1 [381.055879] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.600s) [381.065756] Starting dynamic subtest: pipe-B-eDP-1 [383.313127] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.256s) [383.323340] Starting dynamic subtest: pipe-C-eDP-1 [385.570391] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.256s) [385.580793] Starting dynamic subtest: pipe-D-eDP-1 [387.842508] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.270s) [387.843533] Subtest disable-primary-vs-flip: SUCCESS (8.388s) [388.729056] [094/156] (641s left) gem_wait (wait) [388.787574] Starting subtest: wait [388.788724] Starting dynamic subtest: all-engines [390.033734] Dynamic subtest all-engines: SUCCESS (1.235s) [390.043881] Starting dynamic subtest: rcs0 [391.071241] Dynamic subtest rcs0: SUCCESS (1.044s) [391.083841] Starting dynamic subtest: bcs0 [392.117711] Dynamic subtest bcs0: SUCCESS (1.044s) [392.129983] Starting dynamic subtest: vcs0 [393.164948] Dynamic subtest vcs0: SUCCESS (1.044s) [393.176797] Starting dynamic subtest: vcs1 [394.211280] Dynamic subtest vcs1: SUCCESS (1.043s) [394.223548] Starting dynamic subtest: vecs0 [395.257049] Dynamic subtest vecs0: SUCCESS (1.043s) [395.271007] Starting dynamic subtest: ccs0 [396.307243] Dynamic subtest ccs0: SUCCESS (1.047s) [396.308650] Subtest wait: SUCCESS (7.520s) [396.506394] [095/156] (634s left) kms_content_protection (dp-mst-type-0) [396.650758] Starting subtest: dp-mst-type-0 [396.652363] Subtest dp-mst-type-0: SKIP (0.000s) [396.793563] [096/156] (633s left) gem_ccs (suspend-resume) [396.847893] Starting subtest: suspend-resume [396.849710] Subtest suspend-resume: SKIP (0.000s) [396.945112] [097/156] (633s left) kms_cursor_crc (cursor-tearing-framebuffer-change) [397.043986] Starting subtest: cursor-tearing-framebuffer-change [397.044808] Starting dynamic subtest: pipe-A-eDP-1 [397.597192] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.551s) [397.606408] Starting dynamic subtest: pipe-D-eDP-1 [398.864224] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.266s) [398.865214] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.820s) [399.701595] [098/156] (631s left) kms_plane_scaling (planes-downscale-factor-0-5) [399.789093] Starting subtest: planes-downscale-factor-0-5 [399.790171] Starting dynamic subtest: pipe-A [399.808085] Dynamic subtest pipe-A: SKIP (0.018s) [399.816774] Starting dynamic subtest: pipe-B [399.840332] Dynamic subtest pipe-B: SKIP (0.031s) [399.849327] Starting dynamic subtest: pipe-C [399.872550] Dynamic subtest pipe-C: SKIP (0.032s) [399.881077] Starting dynamic subtest: pipe-D [399.904536] Dynamic subtest pipe-D: SKIP (0.031s) [399.906386] Subtest planes-downscale-factor-0-5: SKIP (0.115s) [400.021453] [099/156] (630s left) gem_mmap (bad-size) [400.078040] Subtest bad-size: SKIP (0.000s) [400.131974] [100/156] (630s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier) [400.224600] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier [400.226387] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier: SKIP (0.000s) [400.311819] [101/156] (630s left) gem_ctx_create (basic) [400.364441] Starting subtest: basic [400.365422] Subtest basic: SUCCESS (0.000s) [400.544188] [102/156] (630s left) syncobj_timeline (multi-wait-all-for-submit-signaled) [400.593439] Starting subtest: multi-wait-all-for-submit-signaled [400.594373] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s) [400.681386] [103/156] (630s left) kms_async_flips (test-time-stamp-atomic) [400.770882] Starting subtest: test-time-stamp-atomic [401.147376] Starting dynamic subtest: pipe-A-eDP-1 [401.906542] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.758s) [402.285873] Starting dynamic subtest: pipe-B-eDP-1 [403.137850] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.858s) [403.508721] Starting dynamic subtest: pipe-C-eDP-1 [404.272681] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.769s) [404.636368] Starting dynamic subtest: pipe-D-eDP-1 [405.401077] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.772s) [405.402101] Subtest test-time-stamp-atomic: SUCCESS (4.629s) [406.559574] [104/156] (624s left) i915_power (sanity) [406.604011] Starting subtest: sanity [410.653469] Subtest sanity: SUCCESS (4.038s) [412.104137] [105/156] (618s left) perf_pmu (most-busy-check-all) [412.138029] Starting subtest: most-busy-check-all [412.138980] Starting dynamic subtest: rcs0 [413.969004] Dynamic subtest rcs0: SUCCESS (1.821s) [413.980711] Starting dynamic subtest: bcs0 [415.056829] Dynamic subtest bcs0: SUCCESS (1.085s) [415.067631] Starting dynamic subtest: vcs0 [417.041173] Dynamic subtest vcs0: SUCCESS (1.981s) [417.050259] Starting dynamic subtest: vcs1 [419.025479] Dynamic subtest vcs1: SUCCESS (1.981s) [419.034457] Starting dynamic subtest: vecs0 [421.073094] Dynamic subtest vecs0: SUCCESS (2.044s) [421.091319] Starting dynamic subtest: ccs0 [423.057345] Dynamic subtest ccs0: SUCCESS (1.981s) [423.058536] Subtest most-busy-check-all: SUCCESS (10.910s) [423.156866] [106/156] (607s left) kms_chamelium_audio (dp-audio-edid) [423.546358] Subtest dp-audio-edid: SKIP (0.000s) [424.374241] [107/156] (606s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move) [424.469823] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move [424.471536] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [424.559154] [108/156] (606s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted) [424.615515] Starting subtest: etime-single-wait-all-for-submit-submitted [424.716366] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.101s) [424.805231] [109/156] (606s left) gem_exec_fence (expired-history) [425.242491] Starting subtest: expired-history [432.783930] Subtest expired-history: SUCCESS (7.531s) [433.362792] [110/156] (597s left) kms_force_connector_basic (force-edid) [433.440447] Starting subtest: force-edid [433.817657] Subtest force-edid: SUCCESS (0.379s) [433.970739] [111/156] (597s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs) [434.059557] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs [434.060593] Starting dynamic subtest: pipe-A-eDP-1 [434.070013] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [434.087938] Starting dynamic subtest: pipe-B-eDP-1 [434.097413] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [434.107463] Starting dynamic subtest: pipe-C-eDP-1 [434.116655] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [434.125604] Starting dynamic subtest: pipe-D-eDP-1 [434.134918] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [434.136751] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s) [436.320879] [112/156] (594s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-wc) [436.412242] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-wc [436.414030] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [436.500199] [113/156] (594s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted) [436.551687] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted [436.670179] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.108s) [436.745768] [114/156] (594s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt) [436.835155] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt [436.836909] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [436.921770] [115/156] (594s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [437.034981] Starting subtest: cursorA-vs-flipA-atomic-transitions [448.203332] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.166s) [449.020102] [116/156] (582s left) gem_mmap_wc (write-cpu-read-wc) [449.079137] Subtest write-cpu-read-wc: SKIP (0.000s) [449.145712] [117/156] (582s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy) [449.265829] Starting subtest: 2x-cursor-vs-flip-legacy [449.268787] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s) [449.406726] [118/156] (582s left) sriov_basic (bind-unbind-vf) [449.465580] Starting subtest: bind-unbind-vf [449.466693] Starting dynamic subtest: vf-1 [449.468746] Dynamic subtest vf-1: SKIP (0.001s) [449.479243] Starting dynamic subtest: vf-2 [449.480902] Dynamic subtest vf-2: SKIP (0.001s) [449.489766] Starting dynamic subtest: vf-3 [449.491542] Dynamic subtest vf-3: SKIP (0.001s) [449.500564] Starting dynamic subtest: vf-4 [449.502528] Dynamic subtest vf-4: SKIP (0.001s) [449.511421] Starting dynamic subtest: vf-5 [449.513177] Dynamic subtest vf-5: SKIP (0.001s) [449.524175] Starting dynamic subtest: vf-6 [449.525955] Dynamic subtest vf-6: SKIP (0.001s) [449.535245] Starting dynamic subtest: vf-7 [449.537186] Dynamic subtest vf-7: SKIP (0.001s) [449.546191] Starting dynamic subtest: vf-random [449.547988] Dynamic subtest vf-random: SKIP (0.001s) [449.556848] Starting dynamic subtest: vf-last [449.558789] Dynamic subtest vf-last: SKIP (0.001s) [449.560516] Subtest bind-unbind-vf: SKIP (0.012s) [449.593287] [119/156] (581s left) kms_psr (pr-basic) [449.688503] Starting subtest: pr-basic [449.690333] Subtest pr-basic: SKIP (0.000s) [449.757467] [120/156] (581s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-pwrite) [449.845313] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-pwrite [449.846972] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [449.944405] [121/156] (581s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt) [450.036447] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt [454.647287] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.610s) [455.833806] [122/156] (575s left) kms_legacy_colorkey (basic) [455.917470] Starting subtest: basic [455.918300] Starting dynamic subtest: pipe-A [455.961983] Dynamic subtest pipe-A: SUCCESS (0.045s) [455.974046] Starting dynamic subtest: pipe-B [455.974859] Dynamic subtest pipe-B: SUCCESS (0.001s) [455.983632] Starting dynamic subtest: pipe-C [455.984400] Dynamic subtest pipe-C: SUCCESS (0.000s) [455.993094] Starting dynamic subtest: pipe-D [455.993849] Dynamic subtest pipe-D: SUCCESS (0.000s) [455.994584] Subtest basic: SUCCESS (0.052s) [456.038862] [123/156] (575s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-render) [456.124779] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-render [456.128039] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [456.218915] [124/156] (575s left) gem_exec_whisper (basic-queues-forked) [456.278598] Starting subtest: basic-queues-forked [479.288302] Subtest basic-queues-forked: SUCCESS (22.992s) [479.470208] [125/156] (552s left) gem_mmap_gtt (basic-read-write-distinct) [479.527669] Subtest basic-read-write-distinct: SKIP (0.000s) [479.609361] [126/156] (552s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [479.704737] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [479.706374] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [479.814020] [127/156] (551s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite) [479.902457] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite [479.904321] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [479.992128] [128/156] (551s left) kms_frontbuffer_tracking (fbchdr-shrfb-scaledprimary) [480.084115] Starting subtest: fbchdr-shrfb-scaledprimary [480.086027] Subtest fbchdr-shrfb-scaledprimary: SKIP (0.000s) [480.203458] [129/156] (551s left) api_intel_bb (blit-noreloc-keep-cache) [480.259345] Starting subtest: blit-noreloc-keep-cache [480.269713] Subtest blit-noreloc-keep-cache: SUCCESS (0.011s) [480.377488] [130/156] (551s left) kms_flip (plain-flip-ts-check-interruptible) [480.471753] Starting subtest: plain-flip-ts-check-interruptible [480.474175] Starting dynamic subtest: A-eDP1 [489.324225] Dynamic subtest A-eDP1: SUCCESS (8.822s) [489.336527] Starting dynamic subtest: B-eDP1 [498.226610] Dynamic subtest B-eDP1: SUCCESS (8.897s) [498.239636] Starting dynamic subtest: C-eDP1 [507.128325] Dynamic subtest C-eDP1: SUCCESS (8.883s) [507.146334] Starting dynamic subtest: D-eDP1 [516.022161] Dynamic subtest D-eDP1: SUCCESS (8.891s) [516.022936] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.507s) [517.151465] [131/156] (514s left) gem_exec_schedule (pi-common) [517.345194] Starting subtest: pi-common [517.346072] Starting dynamic subtest: rcs0 [517.371241] Dynamic subtest rcs0: SUCCESS (0.035s) [517.380920] Starting dynamic subtest: bcs0 [517.410086] Dynamic subtest bcs0: SUCCESS (0.038s) [517.419756] Starting dynamic subtest: vcs0 [517.445630] Dynamic subtest vcs0: SUCCESS (0.035s) [517.454915] Starting dynamic subtest: vcs1 [517.477846] Dynamic subtest vcs1: SUCCESS (0.031s) [517.491469] Starting dynamic subtest: vecs0 [517.507824] Dynamic subtest vecs0: SUCCESS (0.029s) [517.521999] Starting dynamic subtest: ccs0 [517.535398] Dynamic subtest ccs0: SUCCESS (0.027s) [517.537350] Subtest pi-common: SUCCESS (0.200s) [517.756508] [132/156] (514s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy) [517.854614] Starting subtest: fbcpsr-modesetfrombusy [524.151820] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.296s) [524.334271] [133/156] (507s left) perf (blocking-parameterized) [524.444941] Starting subtest: blocking-parameterized [544.475277] Subtest blocking-parameterized: SUCCESS (20.014s) [544.512092] [134/156] (487s left) kms_hdr (bpc-switch-suspend) [544.610381] Starting subtest: bpc-switch-suspend [544.611324] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010 [565.073915] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (6.021s) [565.085224] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F [586.072902] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (6.007s) [586.073912] Subtest bpc-switch-suspend: SUCCESS (12.032s) [591.265066] [135/156] (445s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt) [591.382999] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt [591.384901] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [591.492052] [136/156] (445s left) kms_busy (basic-hang) [591.594322] Starting subtest: basic-hang [591.595260] Starting dynamic subtest: flip-pipe-A [592.196889] Dynamic subtest flip-pipe-A: SUCCESS (0.584s) [592.208446] Starting dynamic subtest: modeset-pipe-A [595.885817] Dynamic subtest modeset-pipe-A: SUCCESS (3.602s) [595.896456] Starting dynamic subtest: flip-pipe-D [597.166186] Dynamic subtest flip-pipe-D: SUCCESS (1.257s) [597.177266] Starting dynamic subtest: modeset-pipe-D [600.547610] Dynamic subtest modeset-pipe-D: SUCCESS (3.340s) [600.548869] Subtest basic-hang: SUCCESS (8.789s) [601.433648] [137/156] (435s left) gem_mmap (short-mmap) [601.486870] Subtest short-mmap: SKIP (0.000s) [601.546018] [138/156] (435s left) sw_sync (timeline_closed_signaled) [601.574925] Starting subtest: timeline_closed_signaled [601.575987] Subtest timeline_closed_signaled: SUCCESS (0.000s) [601.624494] [139/156] (435s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-pgflip-blt) [601.720074] Starting subtest: fbchdr-2p-primscrn-indfb-pgflip-blt [601.721696] Subtest fbchdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [601.819094] [140/156] (435s left) kms_getfb (getfb-repeated-different-handles) [601.905209] Starting subtest: getfb-repeated-different-handles [601.906221] Subtest getfb-repeated-different-handles: SUCCESS (0.000s) [602.004225] [141/156] (435s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-blt) [602.096018] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-blt [602.097549] Subtest fbchdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [602.191299] [142/156] (435s left) kms_setmode (invalid-clone-single-crtc-stealing) [602.243144] Starting subtest: invalid-clone-single-crtc-stealing [602.245779] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s) [602.355719] [143/156] (435s left) gem_exec_reloc (basic-write-wc) [602.408919] Subtest basic-write-wc: SKIP (0.000s) [602.478838] [144/156] (435s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic) [602.593199] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic [602.595230] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s) [602.733986] [145/156] (434s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [602.793262] Starting subtest: y-tiled-to-vebox-y-tiled [602.795247] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [602.976687] [146/156] (434s left) gem_exec_whisper (basic-contexts-forked-all) [603.046854] Starting subtest: basic-contexts-forked-all [623.488825] Subtest basic-contexts-forked-all: SUCCESS (20.342s) [623.623022] [147/156] (414s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary) [623.726176] Starting subtest: psr-shrfb-scaledprimary [629.424445] Subtest psr-shrfb-scaledprimary: SUCCESS (5.698s) [629.559204] [148/156] (408s left) kms_psr (fbc-psr2-sprite-blt) [629.644460] Starting subtest: fbc-psr2-sprite-blt [629.645524] Starting dynamic subtest: eDP-1 [629.647372] Dynamic subtest eDP-1: SKIP (0.000s) [629.649126] Subtest fbc-psr2-sprite-blt: SKIP (0.001s) [629.717819] [149/156] (407s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing) [629.811117] Starting subtest: plane-use-after-nonblocking-unbind-fencing [629.812313] Starting dynamic subtest: pipe-A-eDP-1 [629.876500] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.066s) [630.185022] Starting dynamic subtest: pipe-B-eDP-1 [630.960678] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.784s) [631.254666] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.444s) [632.095483] [150/156] (405s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [632.203518] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [633.261634] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.058s) [633.381464] [151/156] (404s left) perf (enable-disable) [633.469426] Starting subtest: enable-disable [633.470793] Starting dynamic subtest: 0-rcs0 [635.804921] Dynamic subtest 0-rcs0: SUCCESS (2.287s) [635.817695] Starting dynamic subtest: 1-vcs0 [640.298163] Dynamic subtest 1-vcs0: SUCCESS (4.526s) [640.299029] Subtest enable-disable: SUCCESS (6.816s) [640.332943] [152/156] (397s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [640.429522] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu [640.431269] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [640.531441] [153/156] (397s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs) [640.616470] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs [640.617316] Starting dynamic subtest: pipe-A-eDP-1 [640.625684] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [640.635997] Starting dynamic subtest: pipe-B-eDP-1 [640.644315] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [640.654823] Starting dynamic subtest: pipe-C-eDP-1 [640.664023] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [640.675008] Starting dynamic subtest: pipe-D-eDP-1 [640.682887] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [640.684391] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs: SKIP (0.004s) [642.898496] [154/156] (394s left) kms_cursor_crc (cursor-rapid-movement-512x512) [643.193230] Starting subtest: cursor-rapid-movement-512x512 [643.194919] Subtest cursor-rapid-movement-512x512: SKIP (0.001s) [643.347843] [155/156] (394s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling) [643.442334] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling [643.444274] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s) [643.510533] [156/156] (394s left) kms_psr (psr-primary-mmap-gtt) [643.600258] Starting subtest: psr-primary-mmap-gtt [643.601147] Starting dynamic subtest: eDP-1 [645.074717] Dynamic subtest eDP-1: SKIP (1.461s) [645.076883] Subtest psr-primary-mmap-gtt: SKIP (1.463s) [645.243324] 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.