[73.844213] Initializing watchdogs [73.844260] /dev/watchdog0 [73.859719] [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] [73.864620] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [73.867580] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [73.870962] [001/156] (960s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-render) [73.996096] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-render [74.000518] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [74.085201] [002/156] (960s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-onoff) [74.191807] Starting subtest: hdr-1p-primscrn-spr-indfb-onoff [74.198216] Subtest hdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [74.279850] [003/156] (960s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf) [74.378096] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf [74.380410] Starting dynamic subtest: pipe-A-eDP-1 [74.491301] Dynamic subtest pipe-A-eDP-1: SKIP (0.115s) [74.502914] Starting dynamic subtest: pipe-B-eDP-1 [75.680695] Dynamic subtest pipe-B-eDP-1: SKIP (1.187s) [75.685419] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.304s) [76.867195] [004/156] (957s left) kms_pm_backlight (fade) [76.953176] Starting subtest: fade [77.029453] Starting dynamic subtest: eDP-1 [80.025173] Dynamic subtest eDP-1: SUCCESS (2.994s) [80.027334] Subtest fade: SUCCESS (3.072s) [80.890311] [005/156] (953s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [80.993936] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [80.998380] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [81.104860] [006/156] (953s left) gem_exec_fence (submit67) [81.284544] Starting subtest: submit67 [81.288805] Subtest submit67: SKIP (0.000s) [83.325720] [FACT gem_exec_fence (submit67)] new: kernel.kmod_is_loaded.vgem: true [83.329484] [007/156] (951s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs) [83.415277] Starting subtest: missing-ccs-buffer-yf-tiled-ccs [83.417555] Starting dynamic subtest: pipe-A-eDP-1 [83.440610] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [83.450218] Starting dynamic subtest: pipe-B-eDP-1 [83.471801] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [83.481732] Starting dynamic subtest: pipe-C-eDP-1 [83.503577] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [83.511371] Starting dynamic subtest: pipe-D-eDP-1 [83.533648] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [83.537816] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.003s) [85.761831] [008/156] (948s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe) [86.157112] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s) [86.970773] [009/156] (947s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling) [87.072159] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling [87.076677] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s) [87.145806] [010/156] (947s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu) [87.246718] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu [91.632014] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.390s) [92.822826] [011/156] (941s left) syncobj_wait (invalid-wait-illegal-handle) [92.880213] Starting subtest: invalid-wait-illegal-handle [92.882524] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [92.993780] [012/156] (941s left) perf (buffer-fill) [93.106887] Starting subtest: buffer-fill [93.109208] Starting dynamic subtest: 0-rcs0 [95.398676] Dynamic subtest 0-rcs0: SUCCESS (2.291s) [95.407237] Starting dynamic subtest: 1-vcs1 [99.873094] Dynamic subtest 1-vcs1: SUCCESS (4.474s) [99.875742] Subtest buffer-fill: SUCCESS (6.765s) [99.922357] [013/156] (934s left) kms_dirtyfb (default-dirtyfb-ioctl) [100.024267] Starting subtest: default-dirtyfb-ioctl [100.026541] Starting dynamic subtest: A-eDP-1 [101.634443] Dynamic subtest A-eDP-1: SUCCESS (1.611s) [101.636950] Subtest default-dirtyfb-ioctl: SUCCESS (1.612s) [102.446518] [014/156] (932s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite) [102.551098] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite [102.555404] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [102.645884] [015/156] (932s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-msflip-blt) [102.741750] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-msflip-blt [102.746284] Subtest fbcpsrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [102.831880] [016/156] (932s left) gem_mmap_gtt (big-copy-xy) [102.886315] Subtest big-copy-XY: SKIP (0.000s) [102.939886] [017/156] (932s left) kms_psr (pr-primary-page-flip) [103.031079] Starting subtest: pr-primary-page-flip [103.035366] Subtest pr-primary-page-flip: SKIP (0.000s) [103.099573] [018/156] (931s left) gem_spin_batch (resubmit-new-all) [103.157634] Starting subtest: resubmit-new-all [103.159551] Starting dynamic subtest: rcs0 [103.172907] Dynamic subtest rcs0: SUCCESS (0.017s) [103.181887] Starting dynamic subtest: bcs0 [103.184218] Dynamic subtest bcs0: SUCCESS (0.011s) [103.195548] Starting dynamic subtest: vcs0 [103.197503] Dynamic subtest vcs0: SUCCESS (0.010s) [103.206125] Starting dynamic subtest: vcs1 [103.208365] Dynamic subtest vcs1: SUCCESS (0.010s) [103.217366] Starting dynamic subtest: vecs0 [103.219566] Dynamic subtest vecs0: SUCCESS (0.011s) [103.232059] Starting dynamic subtest: ccs0 [103.233995] Dynamic subtest ccs0: SUCCESS (0.011s) [103.235931] Subtest resubmit-new-all: SUCCESS (0.071s) [104.369111] [019/156] (930s left) gem_mmap (basic-small-bo) [104.414836] Subtest basic-small-bo: SKIP (0.000s) [104.484055] [020/156] (930s left) kms_frontbuffer_tracking (fbchdr-2p-rte) [104.590956] Starting subtest: fbchdr-2p-rte [104.595273] Subtest fbchdr-2p-rte: SKIP (0.000s) [104.683309] [021/156] (930s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip) [104.788079] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip [105.434538] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.648s) [105.576257] [022/156] (929s left) i915_hangman (hangcheck-unterminated) [105.643474] Starting subtest: hangcheck-unterminated [117.291651] Subtest hangcheck-unterminated: SUCCESS (11.647s) [117.434529] [023/156] (917s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled) [117.487053] Starting subtest: multi-wait-all-for-submit-submitted-signaled [117.589533] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.103s) [117.684434] [024/156] (917s left) kms_async_flips (async-flip-with-page-flip-events-tiled) [117.786522] Starting subtest: async-flip-with-page-flip-events-tiled [118.173897] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs [120.933253] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.758s) [120.944130] Starting dynamic subtest: pipe-A-eDP-1-4 [123.106542] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.173s) [123.115449] Starting dynamic subtest: pipe-A-eDP-1-4 [125.116379] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.010s) [125.125229] Starting dynamic subtest: pipe-A-eDP-1-x [127.306653] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.190s) [127.678065] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs [130.458574] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.788s) [130.467629] Starting dynamic subtest: pipe-B-eDP-1-4 [132.644327] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.185s) [132.654675] Starting dynamic subtest: pipe-B-eDP-1-4 [134.653925] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.010s) [134.662880] Starting dynamic subtest: pipe-B-eDP-1-x [136.845053] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.190s) [137.213757] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs [139.989331] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.782s) [140.000867] Starting dynamic subtest: pipe-C-eDP-1-4 [142.161896] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.172s) [142.171044] Starting dynamic subtest: pipe-C-eDP-1-4 [144.172433] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.010s) [144.181246] Starting dynamic subtest: pipe-C-eDP-1-x [146.361692] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.189s) [146.728678] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs [149.513111] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.794s) [149.524208] Starting dynamic subtest: pipe-D-eDP-1-4 [151.687329] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.173s) [151.698607] Starting dynamic subtest: pipe-D-eDP-1-4 [153.697995] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s) [153.707193] Starting dynamic subtest: pipe-D-eDP-1-x [155.877394] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s) [155.880065] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.089s) [157.104228] [025/156] (878s left) gem_ctx_persistence (engines-persistence) [157.167370] Starting subtest: engines-persistence [157.169687] Starting dynamic subtest: rcs0 [157.978398] Dynamic subtest rcs0: SUCCESS (0.812s) [157.989728] Starting dynamic subtest: bcs0 [158.704021] Dynamic subtest bcs0: SUCCESS (0.725s) [158.713604] Starting dynamic subtest: vcs0 [159.474970] Dynamic subtest vcs0: SUCCESS (0.770s) [159.488063] Starting dynamic subtest: vcs1 [160.265207] Dynamic subtest vcs1: SUCCESS (0.788s) [160.277892] Starting dynamic subtest: vecs0 [161.007216] Dynamic subtest vecs0: SUCCESS (0.741s) [161.017094] Starting dynamic subtest: ccs0 [161.791745] Dynamic subtest ccs0: SUCCESS (0.784s) [161.794432] Subtest engines-persistence: SUCCESS (4.626s) [161.885536] [026/156] (873s left) kms_color_pipeline (plane-lut1d-post-ctm3x4) [161.986926] Starting subtest: plane-lut1d-post-ctm3x4 [162.110866] Starting dynamic subtest: pipe-A-plane-0 [162.300031] Dynamic subtest pipe-A-plane-0: SUCCESS (0.189s) [162.310130] Starting dynamic subtest: pipe-A-plane-1 [162.491244] Dynamic subtest pipe-A-plane-1: SUCCESS (0.189s) [162.500105] Starting dynamic subtest: pipe-A-plane-2 [162.622390] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s) [163.804043] Starting dynamic subtest: pipe-B-plane-0 [163.998600] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s) [164.007375] Starting dynamic subtest: pipe-B-plane-1 [164.164415] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s) [164.176539] Starting dynamic subtest: pipe-B-plane-2 [164.354623] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s) [165.545940] Starting dynamic subtest: pipe-C-plane-0 [165.671282] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s) [165.681036] Starting dynamic subtest: pipe-C-plane-1 [165.804487] Dynamic subtest pipe-C-plane-1: SUCCESS (0.133s) [165.814129] Starting dynamic subtest: pipe-C-plane-2 [165.960520] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s) [167.156336] Starting dynamic subtest: pipe-D-plane-0 [167.282240] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s) [167.291932] Starting dynamic subtest: pipe-D-plane-1 [167.449347] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s) [167.459348] Starting dynamic subtest: pipe-D-plane-2 [167.593671] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s) [167.883235] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.894s) [168.727618] [027/156] (866s left) gem_exec_schedule (u-fairslice-all) [168.806900] Starting subtest: u-fairslice-all [170.860265] Subtest u-fairslice-all: SUCCESS (2.054s) [172.306101] [028/156] (863s left) kms_big_fb (y-tiled-32bpp-rotate-180) [172.410884] Starting subtest: y-tiled-32bpp-rotate-180 [172.415140] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s) [172.528358] [029/156] (862s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu) [172.625668] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu [172.630056] Subtest fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [172.714362] [030/156] (862s left) kms_cursor_crc (cursor-offscreen-512x512) [172.994299] Starting subtest: cursor-offscreen-512x512 [172.998370] Subtest cursor-offscreen-512x512: SKIP (0.001s) [173.162647] [031/156] (862s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-plflip-blt) [173.255354] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-plflip-blt [173.259080] Subtest fbcpsrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [173.347343] [032/156] (862s left) kms_invalid_mode (bad-htotal) [173.450974] Starting subtest: bad-htotal [173.452900] Starting dynamic subtest: pipe-A-eDP-1 [173.736535] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s) [173.745710] Starting dynamic subtest: pipe-B-eDP-1 [173.747755] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [173.755861] Starting dynamic subtest: pipe-C-eDP-1 [173.758236] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [173.766665] Starting dynamic subtest: pipe-D-eDP-1 [173.768956] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [173.771239] Subtest bad-htotal: SUCCESS (0.290s) [174.551940] [033/156] (861s left) gem_ccs (block-copy-compressed) [174.619045] Starting subtest: block-copy-compressed [174.623704] Subtest block-copy-compressed: SKIP (0.000s) [174.714414] [034/156] (860s left) kms_psr (psr-sprite-mmap-gtt) [174.805911] Starting subtest: psr-sprite-mmap-gtt [174.808100] Starting dynamic subtest: eDP-1 [176.261333] Dynamic subtest eDP-1: SKIP (1.452s) [176.266124] Subtest psr-sprite-mmap-gtt: SKIP (1.454s) [176.432849] [035/156] (859s left) gem_exec_schedule (fifo) [176.506788] Starting subtest: fifo [176.508988] Starting dynamic subtest: rcs0 [176.597446] Dynamic subtest rcs0: SUCCESS (0.099s) [176.609075] Starting dynamic subtest: vcs0 [176.672984] Dynamic subtest vcs0: SUCCESS (0.075s) [176.687840] Starting dynamic subtest: vcs1 [176.746439] Dynamic subtest vcs1: SUCCESS (0.073s) [176.758419] Starting dynamic subtest: vecs0 [176.821273] Dynamic subtest vecs0: SUCCESS (0.074s) [176.833435] Starting dynamic subtest: ccs0 [176.900901] Dynamic subtest ccs0: SUCCESS (0.079s) [176.903768] Subtest fifo: SUCCESS (0.404s) [178.425847] [036/156] (857s left) kms_pipe_crc_basic (suspend-read-crc) [178.511576] Starting subtest: suspend-read-crc [178.514675] Starting dynamic subtest: pipe-A-eDP-1 [195.857969] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.689s) [195.868809] Starting dynamic subtest: pipe-B-eDP-1 [213.858336] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.233s) [213.868600] Starting dynamic subtest: pipe-C-eDP-1 [231.812284] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.128s) [231.824257] Starting dynamic subtest: pipe-D-eDP-1 [249.811424] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.052s) [249.813748] Subtest suspend-read-crc: SUCCESS (12.110s) [250.636681] [037/156] (785s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip) [250.752054] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip [250.757539] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s) [250.841681] [038/156] (785s left) kms_chamelium_edid (vga-edid-read) [251.222986] Subtest vga-edid-read: SKIP (0.000s) [252.056563] [039/156] (783s left) gem_exec_flush (basic-wb-prw-default) [252.107555] Starting subtest: basic-wb-prw-default [257.579184] Subtest basic-wb-prw-default: SUCCESS (5.465s) [257.770477] [040/156] (778s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-move) [257.868865] Starting subtest: psrhdr-2p-primscrn-cur-indfb-move [257.874254] Subtest psrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [257.970310] [041/156] (778s left) kms_flip (2x-flip-vs-panning) [258.059518] Starting subtest: 2x-flip-vs-panning [258.069584] Subtest 2x-flip-vs-panning: SKIP (0.010s) [258.150507] [042/156] (777s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc) [258.258562] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc [258.263715] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [258.343860] [043/156] (777s left) gem_pxp (create-protected-buffer) [258.463811] Starting subtest: create-protected-buffer [258.466985] Subtest create-protected-buffer: SUCCESS (0.000s) [258.582404] [044/156] (777s left) gem_exec_reloc (basic-softpin) [258.635656] Subtest basic-softpin: SKIP (0.000s) [258.715081] [045/156] (777s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc) [258.805348] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc [258.807163] Starting dynamic subtest: pipe-A-eDP-1 [258.824480] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [258.834975] Starting dynamic subtest: pipe-B-eDP-1 [258.854384] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [258.863638] Starting dynamic subtest: pipe-C-eDP-1 [258.881015] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [258.889350] Starting dynamic subtest: pipe-D-eDP-1 [258.909374] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [258.911608] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s) [261.145141] [046/156] (775s left) kms_dsc (dsc-with-bpc-formats) [261.231584] Starting subtest: dsc-with-bpc-formats [261.362731] Subtest dsc-with-bpc-formats: SKIP (0.003s) [261.419535] [047/156] (774s left) gem_tiled_swapping (non-threaded) [261.479235] Subtest non-threaded: SKIP (0.000s) [261.535640] [048/156] (774s left) kms_big_fb (linear-64bpp-rotate-0) [261.645393] Starting subtest: linear-64bpp-rotate-0 [263.225045] Subtest linear-64bpp-rotate-0: SUCCESS (1.578s) [263.385924] [049/156] (772s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier) [263.490491] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier [263.516370] Starting dynamic subtest: pipe-A-plane-0 [263.783022] Dynamic subtest pipe-A-plane-0: SUCCESS (0.267s) [263.794453] Starting dynamic subtest: pipe-A-plane-3 [264.005005] Dynamic subtest pipe-A-plane-3: SUCCESS (0.220s) [264.014270] Starting dynamic subtest: pipe-A-plane-5 [264.017697] Dynamic subtest pipe-A-plane-5: SKIP (0.000s) [265.137729] Starting dynamic subtest: pipe-B-plane-0 [265.417795] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s) [265.427958] Starting dynamic subtest: pipe-B-plane-3 [265.628842] Dynamic subtest pipe-B-plane-3: SUCCESS (0.211s) [265.641452] Starting dynamic subtest: pipe-B-plane-5 [265.646584] Dynamic subtest pipe-B-plane-5: SKIP (0.000s) [265.945709] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.456s) [266.802063] [050/156] (769s left) gen7_exec_parse (oacontrol-tracking) [266.871018] Subtest oacontrol-tracking: SKIP (0.000s) [266.946004] [051/156] (769s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff) [267.045269] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff [274.043836] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (6.999s) [274.188850] [052/156] (762s left) api_intel_bb (misplaced-blitter) [274.250574] Starting subtest: misplaced-blitter [274.256115] Subtest misplaced-blitter: SUCCESS (0.007s) [274.367499] [053/156] (762s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt) [274.476622] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt [274.481708] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [274.579717] [054/156] (761s left) gem_exec_params (secure-non-master) [274.641618] Starting subtest: secure-non-master [274.647086] Subtest secure-non-master: SKIP (0.000s) [274.740186] [055/156] (761s left) sysfs_heartbeat_interval (idempotent) [274.798686] Starting subtest: idempotent [274.801249] Starting dynamic subtest: vecs0 [274.803623] Dynamic subtest vecs0: SUCCESS (0.001s) [274.815638] Starting dynamic subtest: ccs0 [274.818182] Dynamic subtest ccs0: SUCCESS (0.001s) [274.828852] Starting dynamic subtest: vcs0 [274.831693] Dynamic subtest vcs0: SUCCESS (0.000s) [274.841563] Starting dynamic subtest: bcs0 [274.843943] Dynamic subtest bcs0: SUCCESS (0.001s) [274.853281] Starting dynamic subtest: rcs0 [274.855511] Dynamic subtest rcs0: SUCCESS (0.001s) [274.864732] Starting dynamic subtest: vcs1 [274.867079] Dynamic subtest vcs1: SUCCESS (0.000s) [274.869308] Subtest idempotent: SUCCESS (0.008s) [274.911363] [056/156] (761s left) core_setmaster (master-drop-set-user) [275.003688] Starting subtest: master-drop-set-user [275.017560] Subtest master-drop-set-user: SUCCESS (0.013s) [275.056512] [057/156] (761s left) i915_drm_fdinfo (idle) [275.098302] Starting subtest: idle [275.100463] Starting dynamic subtest: rcs0 [275.675247] Dynamic subtest rcs0: SUCCESS (0.575s) [275.689079] Starting dynamic subtest: bcs0 [276.183611] Dynamic subtest bcs0: SUCCESS (0.504s) [276.196315] Starting dynamic subtest: vcs0 [276.689963] Dynamic subtest vcs0: SUCCESS (0.503s) [276.703247] Starting dynamic subtest: vcs1 [277.196521] Dynamic subtest vcs1: SUCCESS (0.503s) [277.211955] Starting dynamic subtest: vecs0 [277.703583] Dynamic subtest vecs0: SUCCESS (0.504s) [277.714587] Starting dynamic subtest: ccs0 [278.209619] Dynamic subtest ccs0: SUCCESS (0.503s) [278.212278] Subtest idle: SUCCESS (3.110s) [278.286984] [058/156] (758s left) gem_render_copy (yf-tiled-ccs-to-x-tiled) [278.339707] Starting subtest: yf-tiled-ccs-to-x-tiled [278.345688] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s) [278.525027] [059/156] (758s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait) [280.655453] Starting subtest: modeset-non-lpsp-stress-no-wait [280.711749] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.056s) [281.056019] [060/156] (755s left) gem_exec_balancer (bonded-false-hang) [281.313069] Starting subtest: bonded-false-hang [281.318698] Subtest bonded-false-hang: SKIP (0.000s) [281.431310] [061/156] (755s left) gem_mmap_gtt (pf-nonblock) [281.486524] Subtest pf-nonblock: SKIP (0.000s) [281.551147] [062/156] (755s left) kms_psr2_sf (psr2-cursor-plane-update-sf) [281.648697] Starting subtest: psr2-cursor-plane-update-sf [281.651339] Starting dynamic subtest: pipe-A-eDP-1 [282.215605] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.569s) [282.226841] Starting dynamic subtest: pipe-B-eDP-1 [283.554375] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.341s) [283.557092] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.913s) [284.412042] [063/156] (752s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-gtt) [284.511338] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-gtt [284.516163] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [284.606794] [064/156] (752s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff) [284.715931] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff [284.721082] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [284.817299] [065/156] (752s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary) [284.923698] Starting subtest: psr-indfb-scaledprimary [290.633977] Subtest psr-indfb-scaledprimary: SUCCESS (5.717s) [290.749135] [066/156] (746s left) gem_render_copy (x-tiled) [290.811772] Starting subtest: x-tiled [290.814431] Starting dynamic subtest: smem [290.855275] Dynamic subtest smem: SUCCESS (0.043s) [290.857678] Subtest x-tiled: SUCCESS (0.044s) [291.039709] [067/156] (746s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area) [291.148268] Starting subtest: psr2-overlay-primary-update-sf-dmg-area [291.150794] Starting dynamic subtest: pipe-A-eDP-1 [297.146730] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.999s) [297.157257] Starting dynamic subtest: pipe-B-eDP-1 [303.793672] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.646s) [303.796720] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.648s) [304.668695] [068/156] (732s left) gem_caching (read-writes) [304.723390] Subtest read-writes: SKIP (0.000s) [304.791572] [069/156] (732s left) kms_pm_rpm (fences) [306.624369] Starting subtest: fences [306.630067] Subtest fences: SKIP (0.000s) [307.116104] [070/156] (730s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [307.177609] Starting subtest: multi-wait-for-submit-available-signaled [307.180658] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s) [307.286358] [071/156] (730s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted) [307.344829] Starting subtest: multi-wait-all-for-submit-unsubmitted [307.454375] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.108s) [307.531213] [072/156] (729s left) kms_vblank (wait-forked) [307.633091] Starting subtest: wait-forked [307.635724] Starting dynamic subtest: pipe-A-eDP-1 [309.453400] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.821s) [309.471823] Starting dynamic subtest: pipe-D-eDP-1 [311.998607] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.542s) [312.001563] Subtest wait-forked: SUCCESS (4.367s) [312.866242] [073/156] (724s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-blt) [312.965463] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-blt [312.970715] Subtest hdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [313.070916] [074/156] (724s left) kms_big_fb (x-tiled-8bpp-rotate-180) [313.169276] Starting subtest: x-tiled-8bpp-rotate-180 [314.429485] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.260s) [314.539396] [075/156] (722s left) syncobj_timeline (wait-for-submit-snapshot) [314.591585] Starting subtest: wait-for-submit-snapshot [314.674224] Subtest wait-for-submit-snapshot: SUCCESS (0.084s) [314.753028] [076/156] (722s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu) [314.846382] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu [314.850387] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [314.940364] [077/156] (722s left) gem_ctx_sseu (engines) [314.997672] Subtest engines: SKIP (0.000s) [315.059528] [078/156] (722s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render) [315.150334] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render [322.529806] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.380s) [322.643525] [079/156] (715s left) gem_softpin (softpin) [322.703206] Starting subtest: softpin [355.001727] Subtest softpin: SUCCESS (32.289s) [355.121970] [080/156] (682s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt) [355.229670] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt [355.234713] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [355.321251] [081/156] (682s left) syncobj_basic (bad-flags-fd-to-handle) [355.374824] Starting subtest: bad-flags-fd-to-handle [355.377339] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s) [355.475106] [082/156] (682s left) gem_partial_pwrite_pread (write-uncached) [355.533828] Subtest write-uncached: SKIP (0.000s) [355.596615] [083/156] (682s left) sw_sync (sync_busy) [355.630757] Starting subtest: sync_busy [355.633850] Subtest sync_busy: SUCCESS (0.002s) [355.695437] [084/156] (682s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite) [355.798691] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite [360.770465] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.980s) [361.930712] [085/156] (676s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-gtt) [362.039664] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-gtt [362.044671] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [362.139604] [086/156] (675s left) i915_pm_rps (min-max-config-idle) [362.201806] Starting subtest: min-max-config-idle [362.207626] Subtest min-max-config-idle: SKIP (0.001s) [362.302576] [087/156] (675s left) gem_ctx_persistence (legacy-engines-queued) [362.356753] Starting subtest: legacy-engines-queued [362.359727] Starting dynamic subtest: render [362.431091] Dynamic subtest render: SUCCESS (0.074s) [362.446811] Starting dynamic subtest: bsd1 [364.427615] Dynamic subtest bsd1: SUCCESS (1.991s) [364.441975] Starting dynamic subtest: bsd2 [366.409450] Dynamic subtest bsd2: SUCCESS (1.978s) [366.423211] Starting dynamic subtest: blt [366.461969] Dynamic subtest blt: SUCCESS (0.050s) [366.471944] Starting dynamic subtest: vebox [368.393130] Dynamic subtest vebox: SUCCESS (1.928s) [368.396644] Subtest legacy-engines-queued: SUCCESS (6.038s) [368.491651] [088/156] (669s left) kms_frontbuffer_tracking (fbcpsr-tiling-y) [368.598499] Starting subtest: fbcpsr-tiling-y [368.603010] Subtest fbcpsr-tiling-y: SKIP (0.000s) [368.705557] [089/156] (669s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt) [368.800593] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt [375.561706] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.767s) [375.699305] [090/156] (662s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move) [375.813349] Starting subtest: fbc-2p-primscrn-cur-indfb-move [375.818411] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s) [375.921204] [091/156] (662s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [376.018816] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [383.286555] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.274s) [383.432068] [092/156] (654s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled) [383.496952] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled [383.499578] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s) [383.586839] [093/156] (654s left) kms_universal_plane (disable-primary-vs-flip) [383.675516] Starting subtest: disable-primary-vs-flip [383.678082] Starting dynamic subtest: pipe-A-eDP-1 [385.247433] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.572s) [385.257881] Starting dynamic subtest: pipe-B-eDP-1 [387.490221] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.242s) [387.502360] Starting dynamic subtest: pipe-C-eDP-1 [389.666123] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.172s) [389.677340] Starting dynamic subtest: pipe-D-eDP-1 [391.857700] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.189s) [391.860390] Subtest disable-primary-vs-flip: SUCCESS (8.180s) [392.675273] [094/156] (645s left) gem_wait (wait) [392.742074] Starting subtest: wait [392.744664] Starting dynamic subtest: all-engines [394.474797] Dynamic subtest all-engines: SUCCESS (1.731s) [394.488584] Starting dynamic subtest: rcs0 [395.526824] Dynamic subtest rcs0: SUCCESS (1.050s) [395.538683] Starting dynamic subtest: bcs0 [396.578237] Dynamic subtest bcs0: SUCCESS (1.049s) [396.589997] Starting dynamic subtest: vcs0 [397.628214] Dynamic subtest vcs0: SUCCESS (1.047s) [397.641421] Starting dynamic subtest: vcs1 [398.679831] Dynamic subtest vcs1: SUCCESS (1.049s) [398.691598] Starting dynamic subtest: vecs0 [399.731752] Dynamic subtest vecs0: SUCCESS (1.050s) [399.745313] Starting dynamic subtest: ccs0 [400.787213] Dynamic subtest ccs0: SUCCESS (1.052s) [400.790077] Subtest wait: SUCCESS (8.046s) [400.991547] [095/156] (637s left) kms_content_protection (dp-mst-type-0) [401.135268] Starting subtest: dp-mst-type-0 [401.140076] Subtest dp-mst-type-0: SKIP (0.000s) [401.245243] [096/156] (637s left) gem_ccs (suspend-resume) [401.299557] Starting subtest: suspend-resume [401.305206] Subtest suspend-resume: SKIP (0.000s) [401.413939] [097/156] (637s left) kms_cursor_crc (cursor-tearing-framebuffer-change) [401.537656] Starting subtest: cursor-tearing-framebuffer-change [401.540387] Starting dynamic subtest: pipe-A-eDP-1 [402.105625] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.565s) [402.117872] Starting dynamic subtest: pipe-D-eDP-1 [403.399828] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.293s) [403.402894] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.861s) [404.226642] [098/156] (634s left) kms_plane_scaling (planes-downscale-factor-0-5) [404.322491] Starting subtest: planes-downscale-factor-0-5 [404.325002] Starting dynamic subtest: pipe-A [404.345106] Dynamic subtest pipe-A: SKIP (0.017s) [404.355729] Starting dynamic subtest: pipe-B [404.375448] Dynamic subtest pipe-B: SKIP (0.030s) [404.385750] Starting dynamic subtest: pipe-C [404.408372] Dynamic subtest pipe-C: SKIP (0.032s) [404.418541] Starting dynamic subtest: pipe-D [404.441479] Dynamic subtest pipe-D: SKIP (0.033s) [404.446099] Subtest planes-downscale-factor-0-5: SKIP (0.115s) [404.552290] [099/156] (634s left) gem_mmap (bad-size) [404.614843] Subtest bad-size: SKIP (0.000s) [404.686433] [100/156] (633s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier) [404.786606] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier [404.791522] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier: SKIP (0.000s) [404.860619] [101/156] (633s left) gem_ctx_create (basic) [404.922833] Starting subtest: basic [404.925459] Subtest basic: SUCCESS (0.000s) [405.113573] [102/156] (633s left) syncobj_timeline (multi-wait-all-for-submit-signaled) [405.162953] Starting subtest: multi-wait-all-for-submit-signaled [405.165741] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s) [405.260246] [103/156] (633s left) kms_async_flips (test-time-stamp-atomic) [405.355650] Starting subtest: test-time-stamp-atomic [405.736591] Starting dynamic subtest: pipe-A-eDP-1 [406.529840] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.790s) [406.899692] Starting dynamic subtest: pipe-B-eDP-1 [407.664676] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.770s) [408.043430] Starting dynamic subtest: pipe-C-eDP-1 [408.903711] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.866s) [409.267221] Starting dynamic subtest: pipe-D-eDP-1 [410.038343] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.779s) [410.040793] Subtest test-time-stamp-atomic: SUCCESS (4.682s) [411.202100] [104/156] (627s left) i915_power (sanity) [411.255073] Starting subtest: sanity [415.295405] Subtest sanity: SUCCESS (4.034s) [416.504139] [105/156] (622s left) perf_pmu (most-busy-check-all) [416.543813] Starting subtest: most-busy-check-all [416.547068] Starting dynamic subtest: rcs0 [418.475547] Dynamic subtest rcs0: SUCCESS (1.929s) [418.493848] Starting dynamic subtest: bcs0 [420.459439] Dynamic subtest bcs0: SUCCESS (1.981s) [420.474491] Starting dynamic subtest: vcs0 [422.506413] Dynamic subtest vcs0: SUCCESS (2.043s) [422.518458] Starting dynamic subtest: vcs1 [424.490815] Dynamic subtest vcs1: SUCCESS (1.981s) [424.505264] Starting dynamic subtest: vecs0 [426.475173] Dynamic subtest vecs0: SUCCESS (1.981s) [426.489518] Starting dynamic subtest: ccs0 [428.459415] Dynamic subtest ccs0: SUCCESS (1.981s) [428.462863] Subtest most-busy-check-all: SUCCESS (11.914s) [428.578292] [106/156] (610s left) kms_chamelium_audio (dp-audio-edid) [428.964454] Subtest dp-audio-edid: SKIP (0.000s) [429.811643] [107/156] (609s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move) [429.912430] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move [429.917043] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [430.040368] [108/156] (608s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted) [430.099115] Starting subtest: etime-single-wait-all-for-submit-submitted [430.209147] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.108s) [430.299669] [109/156] (608s left) gem_exec_fence (expired-history) [430.734470] Starting subtest: expired-history [438.185814] Subtest expired-history: SUCCESS (7.450s) [438.764537] [110/156] (600s left) kms_force_connector_basic (force-edid) [438.834433] Starting subtest: force-edid [439.208460] Subtest force-edid: SUCCESS (0.373s) [439.352192] [111/156] (599s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs) [439.452087] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs [439.454437] Starting dynamic subtest: pipe-A-eDP-1 [439.479598] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [439.493604] Starting dynamic subtest: pipe-B-eDP-1 [439.518735] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [439.529848] Starting dynamic subtest: pipe-C-eDP-1 [439.555244] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [439.565609] Starting dynamic subtest: pipe-D-eDP-1 [439.591681] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [439.596545] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.007s) [441.749298] [112/156] (597s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-wc) [441.858098] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-wc [441.862982] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [441.961508] [113/156] (597s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted) [442.010272] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted [442.118825] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.108s) [442.227470] [114/156] (597s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt) [442.317652] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt [442.323074] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [442.417869] [115/156] (596s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [442.525316] Starting subtest: cursorA-vs-flipA-atomic-transitions [453.713495] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.184s) [454.570915] [116/156] (584s left) gem_mmap_wc (write-cpu-read-wc) [454.637703] Subtest write-cpu-read-wc: SKIP (0.000s) [454.706437] [117/156] (584s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy) [454.834138] Starting subtest: 2x-cursor-vs-flip-legacy [454.839558] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s) [454.979565] [118/156] (584s left) sriov_basic (bind-unbind-vf) [455.041763] Starting subtest: bind-unbind-vf [455.044403] Starting dynamic subtest: vf-1 [455.049099] Dynamic subtest vf-1: SKIP (0.001s) [455.060290] Starting dynamic subtest: vf-2 [455.064861] Dynamic subtest vf-2: SKIP (0.001s) [455.075112] Starting dynamic subtest: vf-3 [455.080600] Dynamic subtest vf-3: SKIP (0.001s) [455.097116] Starting dynamic subtest: vf-4 [455.102069] Dynamic subtest vf-4: SKIP (0.001s) [455.111967] Starting dynamic subtest: vf-5 [455.116809] Dynamic subtest vf-5: SKIP (0.001s) [455.127791] Starting dynamic subtest: vf-6 [455.132694] Dynamic subtest vf-6: SKIP (0.001s) [455.142623] Starting dynamic subtest: vf-7 [455.147650] Dynamic subtest vf-7: SKIP (0.000s) [455.157668] Starting dynamic subtest: vf-random [455.162809] Dynamic subtest vf-random: SKIP (0.001s) [455.175697] Starting dynamic subtest: vf-last [455.180849] Dynamic subtest vf-last: SKIP (0.001s) [455.185702] Subtest bind-unbind-vf: SKIP (0.015s) [455.226201] [119/156] (584s left) kms_psr (pr-basic) [455.330814] Starting subtest: pr-basic [455.336689] Subtest pr-basic: SKIP (0.000s) [455.407364] [120/156] (584s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-pwrite) [455.504536] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-pwrite [455.508570] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [455.587105] [121/156] (583s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt) [455.676460] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt [460.233133] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.560s) [461.382610] [122/156] (578s left) kms_legacy_colorkey (basic) [461.474298] Starting subtest: basic [461.476805] Starting dynamic subtest: pipe-A [461.517458] Dynamic subtest pipe-A: SUCCESS (0.045s) [461.530867] Starting dynamic subtest: pipe-B [461.533239] Dynamic subtest pipe-B: SUCCESS (0.001s) [461.543844] Starting dynamic subtest: pipe-C [461.546566] Dynamic subtest pipe-C: SUCCESS (0.000s) [461.557617] Starting dynamic subtest: pipe-D [461.560406] Dynamic subtest pipe-D: SUCCESS (0.000s) [461.562939] Subtest basic: SUCCESS (0.051s) [461.603584] [123/156] (578s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-render) [461.701721] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-render [461.706949] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [461.781369] [124/156] (577s left) gem_exec_whisper (basic-queues-forked) [461.840728] Starting subtest: basic-queues-forked [486.506213] Subtest basic-queues-forked: SUCCESS (24.662s) [486.689981] [125/156] (553s left) gem_mmap_gtt (basic-read-write-distinct) [486.746518] Subtest basic-read-write-distinct: SKIP (0.000s) [486.802320] [126/156] (552s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [486.929607] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [486.935156] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [487.029213] [127/156] (552s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite) [487.121646] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite [487.126636] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [487.218658] [128/156] (552s left) kms_frontbuffer_tracking (fbchdr-shrfb-scaledprimary) [487.320069] Starting subtest: fbchdr-shrfb-scaledprimary [487.325493] Subtest fbchdr-shrfb-scaledprimary: SKIP (0.000s) [487.410599] [129/156] (552s left) api_intel_bb (blit-noreloc-keep-cache) [487.469667] Starting subtest: blit-noreloc-keep-cache [487.478542] Subtest blit-noreloc-keep-cache: SUCCESS (0.012s) [487.584488] [130/156] (552s left) kms_flip (plain-flip-ts-check-interruptible) [487.678404] Starting subtest: plain-flip-ts-check-interruptible [487.680601] Starting dynamic subtest: A-eDP1 [496.527252] Dynamic subtest A-eDP1: SUCCESS (8.849s) [496.537975] Starting dynamic subtest: B-eDP1 [505.415075] Dynamic subtest B-eDP1: SUCCESS (8.888s) [505.426861] Starting dynamic subtest: C-eDP1 [514.309858] Dynamic subtest C-eDP1: SUCCESS (8.893s) [514.324115] Starting dynamic subtest: D-eDP1 [523.212939] Dynamic subtest D-eDP1: SUCCESS (8.903s) [523.218569] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.544s) [524.447593] [131/156] (515s left) gem_exec_schedule (pi-common) [524.625192] Starting subtest: pi-common [524.628275] Starting dynamic subtest: rcs0 [524.663472] Dynamic subtest rcs0: SUCCESS (0.040s) [524.673746] Starting dynamic subtest: bcs0 [524.702102] Dynamic subtest bcs0: SUCCESS (0.038s) [524.717111] Starting dynamic subtest: vcs0 [524.736530] Dynamic subtest vcs0: SUCCESS (0.033s) [524.750603] Starting dynamic subtest: vcs1 [524.768724] Dynamic subtest vcs1: SUCCESS (0.031s) [524.784323] Starting dynamic subtest: vecs0 [524.797216] Dynamic subtest vecs0: SUCCESS (0.027s) [524.811837] Starting dynamic subtest: ccs0 [524.827430] Dynamic subtest ccs0: SUCCESS (0.029s) [524.829851] Subtest pi-common: SUCCESS (0.206s) [525.053711] [132/156] (514s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy) [525.158145] Starting subtest: fbcpsr-modesetfrombusy [531.335133] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.184s) [531.454099] [133/156] (508s left) perf (blocking-parameterized) [531.552606] Starting subtest: blocking-parameterized [551.572514] Subtest blocking-parameterized: SUCCESS (20.017s) [551.627287] [134/156] (488s left) kms_hdr (bpc-switch-suspend) [551.731561] Starting subtest: bpc-switch-suspend [551.734276] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010 [572.042347] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (6.321s) [572.054016] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F [593.047242] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (5.617s) [593.050072] Subtest bpc-switch-suspend: SUCCESS (11.941s) [593.149054] [135/156] (447s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt) [593.250599] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt [593.255216] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [593.339770] [136/156] (446s left) kms_busy (basic-hang) [593.439073] Starting subtest: basic-hang [593.441981] Starting dynamic subtest: flip-pipe-A [594.055121] Dynamic subtest flip-pipe-A: SUCCESS (0.615s) [594.067515] Starting dynamic subtest: modeset-pipe-A [597.753767] Dynamic subtest modeset-pipe-A: SUCCESS (3.689s) [597.765065] Starting dynamic subtest: flip-pipe-D [599.007963] Dynamic subtest flip-pipe-D: SUCCESS (1.251s) [599.020121] Starting dynamic subtest: modeset-pipe-D [602.336146] Dynamic subtest modeset-pipe-D: SUCCESS (3.324s) [602.339125] Subtest basic-hang: SUCCESS (8.885s) [603.225571] [137/156] (437s left) gem_mmap (short-mmap) [603.288063] Subtest short-mmap: SKIP (0.000s) [603.356003] [138/156] (436s left) sw_sync (timeline_closed_signaled) [603.396042] Starting subtest: timeline_closed_signaled [603.398332] Subtest timeline_closed_signaled: SUCCESS (0.000s) [603.444964] [139/156] (436s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-pgflip-blt) [603.547085] Starting subtest: fbchdr-2p-primscrn-indfb-pgflip-blt [603.552099] Subtest fbchdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [603.639507] [140/156] (436s left) kms_getfb (getfb-repeated-different-handles) [603.735837] Starting subtest: getfb-repeated-different-handles [603.738044] Subtest getfb-repeated-different-handles: SUCCESS (0.000s) [603.846534] [141/156] (436s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-blt) [603.953042] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-blt [603.957994] Subtest fbchdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [604.046115] [142/156] (436s left) kms_setmode (invalid-clone-single-crtc-stealing) [604.109005] Starting subtest: invalid-clone-single-crtc-stealing [604.117850] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s) [604.225552] [143/156] (436s left) gem_exec_reloc (basic-write-wc) [604.278246] Subtest basic-write-wc: SKIP (0.000s) [604.352820] [144/156] (436s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic) [604.462764] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic [604.466958] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s) [604.623112] [145/156] (435s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [604.685918] Starting subtest: y-tiled-to-vebox-y-tiled [604.690681] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [604.887510] [146/156] (435s left) gem_exec_whisper (basic-contexts-forked-all) [604.948273] Starting subtest: basic-contexts-forked-all [627.692662] Subtest basic-contexts-forked-all: SUCCESS (22.731s) [627.891545] [147/156] (412s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary) [627.997216] Starting subtest: psr-shrfb-scaledprimary [633.757229] Subtest psr-shrfb-scaledprimary: SUCCESS (5.769s) [633.892117] [148/156] (406s left) kms_psr (fbc-psr2-sprite-blt) [633.986268] Starting subtest: fbc-psr2-sprite-blt [633.988772] Starting dynamic subtest: eDP-1 [633.993765] Dynamic subtest eDP-1: SKIP (0.000s) [633.998671] Subtest fbc-psr2-sprite-blt: SKIP (0.001s) [634.060798] [149/156] (406s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing) [634.158393] Starting subtest: plane-use-after-nonblocking-unbind-fencing [634.160695] Starting dynamic subtest: pipe-A-eDP-1 [634.223367] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.066s) [634.526254] Starting dynamic subtest: pipe-B-eDP-1 [635.293526] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.778s) [635.584542] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.426s) [636.417591] [150/156] (404s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [636.521881] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [637.524054] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.004s) [637.656111] [151/156] (403s left) perf (enable-disable) [637.752905] Starting subtest: enable-disable [637.755678] Starting dynamic subtest: 0-rcs0 [640.045268] Dynamic subtest 0-rcs0: SUCCESS (2.290s) [640.056861] Starting dynamic subtest: 1-vcs1 [644.577147] Dynamic subtest 1-vcs1: SUCCESS (4.530s) [644.580020] Subtest enable-disable: SUCCESS (6.823s) [644.621991] [152/156] (396s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [644.732628] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu [644.737379] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [644.836987] [153/156] (396s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs) [644.937927] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs [644.940737] Starting dynamic subtest: pipe-A-eDP-1 [644.967113] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [644.979241] Starting dynamic subtest: pipe-B-eDP-1 [645.004120] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [645.014262] Starting dynamic subtest: pipe-C-eDP-1 [645.036523] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [645.046402] Starting dynamic subtest: pipe-D-eDP-1 [645.068643] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [645.072902] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs: SKIP (0.004s) [647.224120] [154/156] (393s left) kms_cursor_crc (cursor-rapid-movement-512x512) [647.506673] Starting subtest: cursor-rapid-movement-512x512 [647.512126] Subtest cursor-rapid-movement-512x512: SKIP (0.001s) [647.666751] [155/156] (393s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling) [647.751174] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling [647.756232] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s) [647.835402] [156/156] (393s left) kms_psr (psr-primary-mmap-gtt) [647.939861] Starting subtest: psr-primary-mmap-gtt [647.942255] Starting dynamic subtest: eDP-1 [649.392727] Dynamic subtest eDP-1: SKIP (1.449s) [649.398538] Subtest psr-primary-mmap-gtt: SKIP (1.452s) [649.527739] 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.