[59.428980] Initializing watchdogs [59.429054] /dev/watchdog0 [59.442276] [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] [59.446165] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [59.448735] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [59.452024] [001/131] (960s left) kms_flip (2x-nonexisting-fb-interruptible) [59.619655] Starting subtest: 2x-nonexisting-fb-interruptible [59.634364] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.010s) [59.697608] [002/131] (960s left) i915_pm_freq_api (freq-reset-multiple) [59.770242] Starting subtest: freq-reset-multiple [59.772534] Starting dynamic subtest: gt0 [66.919212] Dynamic subtest gt0: SUCCESS (7.110s) [66.927440] Starting dynamic subtest: gt1 [73.373866] Dynamic subtest gt1: SUCCESS (6.442s) [73.376129] Subtest freq-reset-multiple: SUCCESS (13.553s) [73.443877] [003/131] (946s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf) [78.511334] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf [78.519219] Starting dynamic subtest: pipe-A-eDP-1 [89.924064] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.416s) [89.934777] Starting dynamic subtest: pipe-B-eDP-1 [101.339810] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.418s) [101.342294] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.838s) [102.176806] [004/131] (917s left) gem_exec_params (invalid-ring) [102.246491] Starting subtest: invalid-ring [102.248741] Subtest invalid-ring: SUCCESS (0.000s) [102.357406] [005/131] (917s left) kms_atomic_transition (plane-all-transition) [102.454067] Starting subtest: plane-all-transition [102.456465] Starting dynamic subtest: pipe-A-eDP-1 [104.955499] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.500s) [105.241342] Starting dynamic subtest: pipe-B-eDP-1 [108.516102] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.283s) [108.791602] Subtest plane-all-transition: SUCCESS (6.337s) [109.625824] [006/131] (910s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling) [109.722713] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling [109.725080] Starting dynamic subtest: pipe-A-default-mode [110.010707] Dynamic subtest pipe-A-default-mode: SKIP (0.283s) [110.015629] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.284s) [110.847873] [007/131] (909s left) kms_cursor_crc (cursor-rapid-movement-512x512) [111.101157] Starting subtest: cursor-rapid-movement-512x512 [111.105584] Subtest cursor-rapid-movement-512x512: SKIP (0.001s) [111.272192] [008/131] (908s left) kms_properties (crtc-properties-legacy) [111.373949] Starting subtest: crtc-properties-legacy [111.376382] Starting dynamic subtest: pipe-A-eDP-1 [111.841584] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.466s) [111.849210] Starting dynamic subtest: pipe-B-eDP-1 [112.999157] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.156s) [113.006072] Starting dynamic subtest: pipe-C-eDP-1 [114.112734] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.114s) [114.120633] Starting dynamic subtest: pipe-D-eDP-1 [115.252495] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.140s) [115.254985] Subtest crtc-properties-legacy: SUCCESS (3.879s) [116.043824] [009/131] (904s left) kms_flip (2x-wf_vblank-ts-check) [116.142292] Starting subtest: 2x-wf_vblank-ts-check [116.152600] Subtest 2x-wf_vblank-ts-check: SKIP (0.010s) [116.224823] [010/131] (904s left) kms_cursor_legacy (cursorb-vs-flipa-toggle) [116.340086] Starting subtest: cursorB-vs-flipA-toggle [116.344190] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s) [116.430250] [011/131] (903s left) kms_big_fb (4-tiled-64bpp-rotate-0) [116.530551] Starting subtest: 4-tiled-64bpp-rotate-0 [118.824159] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (2.294s) [119.346173] [012/131] (901s left) gem_exec_balancer (bonded-true-hang) [119.593956] Starting subtest: bonded-true-hang [119.599097] Subtest bonded-true-hang: SKIP (0.000s) [119.707546] [013/131] (900s left) kms_pm_rpm (dpms-non-lpsp) [121.241650] Starting subtest: dpms-non-lpsp [121.246976] Subtest dpms-non-lpsp: SKIP (0.001s) [121.981194] [014/131] (898s left) syncobj_wait (wait-for-submit-snapshot) [122.043100] Starting subtest: wait-for-submit-snapshot [122.125493] Subtest wait-for-submit-snapshot: SUCCESS (0.082s) [122.196797] [015/131] (898s left) gem_exec_params (invalid-bsd2-flag-on-blt) [122.253734] Starting subtest: invalid-bsd2-flag-on-blt [122.256201] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s) [122.354554] [016/131] (898s left) kms_addfb_basic (size-max) [122.454973] Starting subtest: size-max [122.457456] Subtest size-max: SUCCESS (0.000s) [122.565259] [017/131] (897s left) kms_getfb (getfb2-handle-protection) [122.629086] Starting subtest: getfb2-handle-protection [122.657743] Subtest getfb2-handle-protection: SUCCESS (0.031s) [122.775150] [018/131] (897s left) kms_vblank (invalid) [122.874903] Starting subtest: invalid [122.877324] Starting dynamic subtest: pipe-A-eDP-1 [123.285851] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.408s) [123.288342] Subtest invalid: SUCCESS (0.408s) [124.085165] [019/131] (896s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs-cc) [124.181979] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs-cc [124.184434] Starting dynamic subtest: pipe-A-eDP-1 [124.207578] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [124.215927] Starting dynamic subtest: pipe-B-eDP-1 [124.237702] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [124.245450] Starting dynamic subtest: pipe-C-eDP-1 [124.267844] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [124.276293] Starting dynamic subtest: pipe-D-eDP-1 [124.298860] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [124.303045] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s) [126.339071] [020/131] (894s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc) [126.456718] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc [126.460858] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [126.555722] [021/131] (894s left) kms_psr (psr2-sprite-plane-move) [126.655163] Starting subtest: psr2-sprite-plane-move [126.657579] Starting dynamic subtest: eDP-1 [128.373966] Dynamic subtest eDP-1: SUCCESS (1.718s) [128.376465] Subtest psr2-sprite-plane-move: SUCCESS (1.719s) [129.198756] [022/131] (891s left) kms_pm_backlight (bad-brightness) [129.294099] Starting subtest: bad-brightness [129.356817] Starting dynamic subtest: eDP-1 [129.678591] Dynamic subtest eDP-1: SUCCESS (0.320s) [129.680663] Subtest bad-brightness: SUCCESS (0.382s) [130.530771] [023/131] (890s left) perf_pmu (busy-idle) [130.571425] Starting subtest: busy-idle [130.573886] Starting dynamic subtest: rcs0 [131.285574] Dynamic subtest rcs0: SUCCESS (0.714s) [131.295572] Starting dynamic subtest: bcs0 [131.957112] Dynamic subtest bcs0: SUCCESS (0.671s) [131.967228] Starting dynamic subtest: vcs0 [133.936493] Dynamic subtest vcs0: SUCCESS (1.977s) [133.945901] Starting dynamic subtest: vcs1 [135.920625] Dynamic subtest vcs1: SUCCESS (1.983s) [135.930776] Starting dynamic subtest: vecs0 [137.903963] Dynamic subtest vecs0: SUCCESS (1.983s) [137.913790] Starting dynamic subtest: ccs0 [138.573793] Dynamic subtest ccs0: SUCCESS (0.671s) [138.576221] Subtest busy-idle: SUCCESS (8.004s) [138.685748] [024/131] (882s left) gem_exec_balancer (sequential) [138.748434] Starting subtest: sequential [141.645850] Subtest sequential: SUCCESS (2.898s) [141.953273] [025/131] (878s left) kms_rotation_crc (sprite-rotation-90-pos-100-0) [142.049905] Starting subtest: sprite-rotation-90-pos-100-0 [142.054282] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s) [142.119081] [026/131] (878s left) kms_lease (invalid-create-leases) [142.214890] Starting subtest: invalid-create-leases [142.219598] Subtest invalid-create-leases: SUCCESS (0.005s) [142.311945] [027/131] (878s left) gem_busy (close-race) [142.383164] Starting subtest: close-race [165.969525] Subtest close-race: SUCCESS (23.569s) [166.348738] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true [166.353838] [028/131] (854s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0) [166.504682] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0 [167.706230] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.202s) [167.936790] [029/131] (852s left) gem_exec_schedule (wide) [168.136077] Starting subtest: wide [168.138246] Starting dynamic subtest: rcs0 [176.755765] Dynamic subtest rcs0: SUCCESS (8.615s) [176.771214] Starting dynamic subtest: vcs0 [186.044950] Dynamic subtest vcs0: SUCCESS (9.287s) [186.053259] Starting dynamic subtest: vcs1 [196.801503] Dynamic subtest vcs1: SUCCESS (10.754s) [196.809753] Starting dynamic subtest: vecs0 [206.329831] Dynamic subtest vecs0: SUCCESS (9.526s) [206.340914] Starting dynamic subtest: ccs0 [217.602553] Dynamic subtest ccs0: SUCCESS (11.271s) [217.605439] Subtest wide: SUCCESS (49.454s) [218.803411] [030/131] (802s left) kms_writeback (writeback-check-output-xrgb2101010) [218.904040] Subtest writeback-check-output-XRGB2101010: SKIP (0.000s) [218.944178] [031/131] (802s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy) [219.058894] Starting subtest: 2x-long-flip-vs-cursor-legacy [219.063441] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s) [219.192203] [032/131] (801s left) kms_psr (pr-sprite-plane-onoff) [219.286305] Starting subtest: pr-sprite-plane-onoff [219.290927] Subtest pr-sprite-plane-onoff: SKIP (0.000s) [219.353702] [033/131] (801s left) gem_ccs (block-multicopy-inplace) [219.421622] Starting subtest: block-multicopy-inplace [219.426459] Subtest block-multicopy-inplace: SKIP (0.000s) [219.533478] [034/131] (801s left) gen9_exec_parse (valid-registers) [219.599819] Subtest valid-registers: SKIP (0.000s) [219.670627] [035/131] (801s left) gem_exec_reloc (basic-softpin) [219.741083] Subtest basic-softpin: SKIP (0.000s) [219.796234] [036/131] (801s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render) [219.906473] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render [219.910104] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s) [219.997225] [037/131] (801s left) kms_async_flips (invalid-async-flip) [220.100926] Starting subtest: invalid-async-flip [220.104946] Subtest invalid-async-flip: SKIP (0.000s) [221.273433] [038/131] (799s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render) [221.383732] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render [224.128289] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (2.749s) [224.280957] [039/131] (796s left) perf_pmu (most-busy-check-all) [224.318460] Starting subtest: most-busy-check-all [224.320520] Starting dynamic subtest: rcs0 [225.904912] Dynamic subtest rcs0: SUCCESS (1.584s) [225.918821] Starting dynamic subtest: bcs0 [227.952805] Dynamic subtest bcs0: SUCCESS (2.047s) [227.964367] Starting dynamic subtest: vcs0 [229.936623] Dynamic subtest vcs0: SUCCESS (1.982s) [229.948165] Starting dynamic subtest: vcs1 [231.919835] Dynamic subtest vcs1: SUCCESS (1.983s) [231.934336] Starting dynamic subtest: vecs0 [233.902905] Dynamic subtest vecs0: SUCCESS (1.983s) [233.913863] Starting dynamic subtest: ccs0 [235.951834] Dynamic subtest ccs0: SUCCESS (2.047s) [235.954564] Subtest most-busy-check-all: SUCCESS (11.631s) [236.069887] [040/131] (785s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size) [236.198852] Starting subtest: basic-busy-flip-before-cursor-varying-size [236.202784] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s) [236.291015] [041/131] (785s left) syncobj_basic (illegal-fd-to-handle) [236.351286] Starting subtest: illegal-fd-to-handle [236.353302] Subtest illegal-fd-to-handle: SUCCESS (0.000s) [236.450701] [042/131] (784s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling) [236.554153] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling [236.556487] Starting dynamic subtest: pipe-A-default-mode [239.239211] Dynamic subtest pipe-A-default-mode: SUCCESS (2.681s) [239.241388] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.682s) [240.073287] [043/131] (781s left) kms_psr (fbc-psr2-cursor-render) [240.176058] Starting subtest: fbc-psr2-cursor-render [240.178287] Starting dynamic subtest: eDP-1 [240.182568] Dynamic subtest eDP-1: SKIP (0.000s) [240.186736] Subtest fbc-psr2-cursor-render: SKIP (0.000s) [240.240311] [044/131] (781s left) gem_lmem_swapping (verify-random-ccs) [240.278723] Subtest verify-random-ccs: SKIP (0.000s) [240.315946] [045/131] (781s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render) [240.416119] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render [240.420460] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s) [240.518053] [046/131] (780s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt) [240.623764] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt [240.628190] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s) [240.729601] [047/131] (780s left) kms_color (ctm-0-50) [240.828985] Starting subtest: ctm-0-50 [240.831374] Starting dynamic subtest: pipe-A-eDP-1 [244.252814] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.423s) [244.267517] Starting dynamic subtest: pipe-B-eDP-1 [248.374259] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.119s) [248.384105] Starting dynamic subtest: pipe-C-eDP-1 [252.358041] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.983s) [252.369038] Starting dynamic subtest: pipe-D-eDP-1 [256.340697] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.983s) [256.343606] Subtest ctm-0-50: SUCCESS (15.510s) [257.188018] [048/131] (764s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt) [257.295595] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt [257.299996] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [257.392960] [049/131] (764s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen) [257.496405] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen [257.500662] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [257.606654] [050/131] (764s left) kms_psr (fbc-psr-primary-mmap-cpu) [257.706830] Starting subtest: fbc-psr-primary-mmap-cpu [257.709171] Starting dynamic subtest: eDP-1 [257.713647] Dynamic subtest eDP-1: SKIP (0.000s) [257.717788] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s) [257.772357] [051/131] (763s left) gem_lmem_swapping (heavy-verify-multi) [257.814306] Subtest heavy-verify-multi: SKIP (0.000s) [257.847182] [052/131] (763s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-gtt) [257.946690] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-gtt [257.950822] Subtest psr-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [258.055898] [053/131] (763s left) sw_sync (sync_multi_producer_single_consumer) [258.090226] Starting subtest: sync_multi_producer_single_consumer [259.165597] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s) [259.220999] [054/131] (762s left) kms_sequence (get-forked-busy) [259.325021] Starting subtest: get-forked-busy [259.328260] Starting dynamic subtest: pipe-A-eDP-1 [261.249309] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.923s) [261.258675] Starting dynamic subtest: pipe-B-eDP-1 [263.883645] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.633s) [263.891604] Starting dynamic subtest: pipe-C-eDP-1 [266.497080] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.611s) [266.505752] Starting dynamic subtest: pipe-D-eDP-1 [269.035432] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.536s) [269.037437] Subtest get-forked-busy: SUCCESS (9.707s) [269.905775] [055/131] (751s left) kms_chamelium_color (ctm-0-50) [270.000500] Subtest ctm-0-50: SKIP (0.000s) [270.038264] [056/131] (751s left) kms_plane (plane-panning-bottom-right) [270.142804] Starting subtest: plane-panning-bottom-right [270.145144] Starting dynamic subtest: pipe-A [271.755848] Dynamic subtest pipe-A: SUCCESS (1.610s) [271.764734] Starting dynamic subtest: pipe-B [274.012465] Dynamic subtest pipe-B: SUCCESS (2.255s) [274.014346] Subtest plane-panning-bottom-right: SUCCESS (3.867s) [274.814630] [057/131] (747s left) syncobj_timeline (reset-during-wait-for-submit) [274.864283] Starting subtest: reset-during-wait-for-submit [274.966886] Subtest reset-during-wait-for-submit: SUCCESS (0.103s) [275.035095] [058/131] (746s left) drm_fdinfo (virtual-busy) [275.077559] Starting subtest: virtual-busy [275.082715] Subtest virtual-busy: SKIP (0.000s) [275.132971] [059/131] (746s left) gem_exec_schedule (preempt-contexts) [275.204964] Starting subtest: preempt-contexts [275.207442] Starting dynamic subtest: rcs0 [275.245267] Dynamic subtest rcs0: SUCCESS (0.047s) [275.253345] Starting dynamic subtest: bcs0 [275.287381] Dynamic subtest bcs0: SUCCESS (0.042s) [275.295507] Starting dynamic subtest: vcs0 [275.329029] Dynamic subtest vcs0: SUCCESS (0.041s) [275.337243] Starting dynamic subtest: vcs1 [275.370296] Dynamic subtest vcs1: SUCCESS (0.038s) [275.379135] Starting dynamic subtest: vecs0 [275.408602] Dynamic subtest vecs0: SUCCESS (0.037s) [275.419760] Starting dynamic subtest: ccs0 [275.447616] Dynamic subtest ccs0: SUCCESS (0.039s) [275.450049] Subtest preempt-contexts: SUCCESS (0.248s) [277.024878] [060/131] (744s left) gem_exec_reloc (basic-cpu-wc-noreloc) [277.085796] Subtest basic-cpu-wc-noreloc: SKIP (0.000s) [277.154165] [061/131] (744s left) gem_spin_batch (legacy) [277.224514] Starting subtest: legacy [277.226973] Starting dynamic subtest: default [280.463788] Dynamic subtest default: SUCCESS (3.233s) [280.474567] Starting dynamic subtest: render [283.699921] Dynamic subtest render: SUCCESS (3.234s) [283.710031] Starting dynamic subtest: bsd [286.936089] Dynamic subtest bsd: SUCCESS (3.232s) [286.946234] Starting dynamic subtest: bsd1 [290.173927] Dynamic subtest bsd1: SUCCESS (3.235s) [290.184560] Starting dynamic subtest: bsd2 [293.411727] Dynamic subtest bsd2: SUCCESS (3.234s) [293.422085] Starting dynamic subtest: blt [296.645989] Dynamic subtest blt: SUCCESS (3.232s) [296.654147] Starting dynamic subtest: vebox [299.881785] Dynamic subtest vebox: SUCCESS (3.234s) [299.884239] Subtest legacy: SUCCESS (22.651s) [301.969078] [062/131] (720s left) gem_exec_balancer (parallel-out-fence) [302.218422] Starting subtest: parallel-out-fence [303.118410] Subtest parallel-out-fence: SUCCESS (0.900s) [303.239872] [063/131] (718s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc) [303.355225] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc [303.359204] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [303.461263] [064/131] (718s left) gem_pxp (fail-invalid-protected-context) [303.573237] Starting subtest: fail-invalid-protected-context [303.575482] Subtest fail-invalid-protected-context: SUCCESS (0.000s) [303.695060] [065/131] (718s left) gem_ctx_persistence (engines-persistence) [303.762985] Starting subtest: engines-persistence [303.765361] Starting dynamic subtest: rcs0 [304.569055] Dynamic subtest rcs0: SUCCESS (0.805s) [304.581160] Starting dynamic subtest: bcs0 [305.308132] Dynamic subtest bcs0: SUCCESS (0.738s) [305.318949] Starting dynamic subtest: vcs0 [306.123058] Dynamic subtest vcs0: SUCCESS (0.813s) [306.135073] Starting dynamic subtest: vcs1 [306.939124] Dynamic subtest vcs1: SUCCESS (0.814s) [306.951111] Starting dynamic subtest: vecs0 [307.675825] Dynamic subtest vecs0: SUCCESS (0.736s) [307.686076] Starting dynamic subtest: ccs0 [308.498466] Dynamic subtest ccs0: SUCCESS (0.821s) [308.501534] Subtest engines-persistence: SUCCESS (4.735s) [308.573514] [066/131] (713s left) gem_ctx_isolation (clean) [308.632752] Starting subtest: clean [308.635263] Starting dynamic subtest: rcs0 [308.723916] Dynamic subtest rcs0: SUCCESS (0.091s) [308.735639] Starting dynamic subtest: bcs0 [308.837072] Dynamic subtest bcs0: SUCCESS (0.113s) [308.849657] Starting dynamic subtest: vcs0 [308.932080] Dynamic subtest vcs0: SUCCESS (0.095s) [308.941198] Starting dynamic subtest: vcs1 [310.925734] Dynamic subtest vcs1: SUCCESS (1.992s) [310.937337] Starting dynamic subtest: vecs0 [312.976859] Dynamic subtest vecs0: SUCCESS (2.051s) [312.988015] Starting dynamic subtest: ccs0 [315.009803] Dynamic subtest ccs0: SUCCESS (2.032s) [315.012298] Subtest clean: SUCCESS (6.377s) [315.245160] [067/131] (706s left) kms_flip (wf_vblank-ts-check-interruptible) [315.353793] Starting subtest: wf_vblank-ts-check-interruptible [315.355967] Starting dynamic subtest: A-eDP1 [324.262808] Dynamic subtest A-eDP1: SUCCESS (8.906s) [324.271435] Starting dynamic subtest: B-eDP1 [333.188327] Dynamic subtest B-eDP1: SUCCESS (8.923s) [333.198385] Starting dynamic subtest: C-eDP1 [342.102678] Dynamic subtest C-eDP1: SUCCESS (8.913s) [342.113269] Starting dynamic subtest: D-eDP1 [351.030025] Dynamic subtest D-eDP1: SUCCESS (8.925s) [351.032694] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.675s) [352.242295] [068/131] (670s left) gem_exec_fence (syncobj-timeline-chain-engines) [352.820920] Starting subtest: syncobj-timeline-chain-engines [352.909660] Subtest syncobj-timeline-chain-engines: SUCCESS (0.090s) [354.004945] [069/131] (668s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc) [354.110556] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc [358.663986] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (4.556s) [358.792205] [070/131] (663s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc) [358.885230] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc [358.889318] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [358.984731] [071/131] (663s left) core_getstats [359.138239] [072/131] (663s left) kms_properties (connector-properties-legacy) [359.231374] Starting subtest: connector-properties-legacy [359.233766] Starting dynamic subtest: pipe-A-eDP-1 [366.921602] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.687s) [366.930969] Starting dynamic subtest: pipe-None-DP-1 [366.935426] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s) [366.943138] Starting dynamic subtest: pipe-None-HDMI-A-1 [366.947635] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s) [366.955542] Starting dynamic subtest: pipe-None-DP-2 [366.960035] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s) [366.968048] Starting dynamic subtest: pipe-None-HDMI-A-2 [366.972492] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s) [366.980425] Starting dynamic subtest: pipe-None-DP-3 [366.984845] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s) [366.992520] Starting dynamic subtest: pipe-None-DP-4 [366.997045] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s) [366.999677] Subtest connector-properties-legacy: SUCCESS (7.697s) [367.787779] [073/131] (654s left) gem_eio (wait-10ms) [367.996880] Starting subtest: wait-10ms [368.311918] Subtest wait-10ms: SUCCESS (0.314s) [368.422528] [074/131] (654s left) gem_softpin (allocator-fork) [368.495403] Starting subtest: allocator-fork [370.707396] Subtest allocator-fork: SUCCESS (2.210s) [370.851402] [075/131] (651s left) i915_query (query-topology-kernel-writes) [370.911818] Starting subtest: query-topology-kernel-writes [370.914025] Subtest query-topology-kernel-writes: SUCCESS (0.000s) [371.018148] [076/131] (651s left) kms_pipe_stress (stress-xrgb8888-ytiled) [371.118748] Starting subtest: stress-xrgb8888-ytiled [371.123229] Subtest stress-xrgb8888-ytiled: SKIP (0.000s) [371.211678] [077/131] (651s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling) [371.307206] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling [371.309426] Starting dynamic subtest: pipe-A-default-mode [372.432282] Dynamic subtest pipe-A-default-mode: SKIP (1.121s) [372.436949] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.122s) [372.579818] [078/131] (650s left) syncobj_timeline (single-wait-available-signaled) [372.648285] Starting subtest: single-wait-available-signaled [372.650178] Subtest single-wait-available-signaled: SUCCESS (0.000s) [372.763185] [079/131] (649s left) gem_set_tiling_vs_blt (tiled-to-tiled) [372.825360] Subtest tiled-to-tiled: SKIP (0.000s) [372.906561] [080/131] (649s left) gem_ctx_persistence (smoketest) [372.964248] Starting subtest: smoketest [413.838193] Subtest smoketest: SUCCESS (40.870s) [413.910278] [081/131] (608s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-pwrite) [414.019343] Starting subtest: fbc-1p-offscren-pri-indfb-draw-pwrite [417.834109] Subtest fbc-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (3.819s) [417.953237] [082/131] (604s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt) [418.049574] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt [423.220275] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (5.174s) [423.347200] [083/131] (599s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0) [423.460241] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0 [423.465581] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s) [423.568673] [084/131] (599s left) kms_plane_scaling (planes-downscale-factor-0-25) [423.663387] Starting subtest: planes-downscale-factor-0-25 [423.665903] Starting dynamic subtest: pipe-A [423.679226] Dynamic subtest pipe-A: SKIP (0.011s) [423.688922] Starting dynamic subtest: pipe-B [423.700849] Dynamic subtest pipe-B: SKIP (0.020s) [423.709608] Starting dynamic subtest: pipe-C [423.721573] Dynamic subtest pipe-C: SKIP (0.020s) [423.729874] Starting dynamic subtest: pipe-D [423.741919] Dynamic subtest pipe-D: SKIP (0.020s) [423.745814] Subtest planes-downscale-factor-0-25: SKIP (0.072s) [423.827848] [085/131] (599s left) api_intel_bb (add-remove-objects) [423.887358] Starting subtest: add-remove-objects [423.895430] Subtest add-remove-objects: SUCCESS (0.009s) [424.009259] [086/131] (598s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area) [429.021345] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area [429.026453] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s) [429.866575] [087/131] (593s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted) [429.923908] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted [430.028523] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.105s) [430.097178] [088/131] (592s left) kms_chamelium_frames (hdmi-crc-multiple) [430.496056] Subtest hdmi-crc-multiple: SKIP (0.000s) [431.273080] [089/131] (591s left) kms_rotation_crc (primary-y-tiled-reflect-x-90) [431.377834] Starting subtest: primary-y-tiled-reflect-x-90 [431.382216] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s) [431.448790] [090/131] (591s left) kms_psr (fbc-pr-sprite-mmap-gtt) [431.541160] Starting subtest: fbc-pr-sprite-mmap-gtt [431.545098] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s) [431.604790] [091/131] (591s left) gem_exec_params (invalid-bsd1-flag-on-render) [431.669155] Starting subtest: invalid-bsd1-flag-on-render [431.671561] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s) [431.779444] [092/131] (591s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip) [431.878805] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip [431.882950] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s) [431.969487] [093/131] (591s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc) [432.076217] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc [436.563923] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (4.495s) [436.717453] [094/131] (586s left) kms_flip (2x-flip-vs-wf_vblank) [436.827996] Starting subtest: 2x-flip-vs-wf_vblank [436.839227] Subtest 2x-flip-vs-wf_vblank: SKIP (0.011s) [436.912945] [095/131] (586s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted) [436.973201] Starting subtest: multi-wait-available-unsubmitted-submitted [436.975170] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s) [437.058652] [096/131] (586s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt) [437.168880] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt [437.173267] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [437.269988] [097/131] (585s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc) [437.368280] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc [437.370615] Starting dynamic subtest: pipe-A-eDP-1 [437.802919] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.408s) [437.812637] Starting dynamic subtest: pipe-B-eDP-1 [439.137048] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.330s) [439.151638] Starting dynamic subtest: pipe-C-eDP-1 [440.537322] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.397s) [440.548114] Starting dynamic subtest: pipe-D-eDP-1 [441.887584] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.351s) [441.889782] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.489s) [443.053293] [098/131] (580s left) kms_cursor_crc (cursor-onscreen-32x32) [443.183325] Starting subtest: cursor-onscreen-32x32 [443.221555] Subtest cursor-onscreen-32x32: SKIP (0.032s) [443.505696] [099/131] (579s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [443.607364] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s) [443.715992] [100/131] (579s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip) [443.823315] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip [443.827749] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s) [443.934042] [101/131] (579s left) gem_exec_flush (basic-wb-ro-before-default) [444.003780] Starting subtest: basic-wb-ro-before-default [449.477350] Subtest basic-wb-ro-before-default: SUCCESS (5.473s) [449.658047] [102/131] (573s left) i915_pm_rps (min-max-config-loaded) [449.719134] Starting subtest: min-max-config-loaded [449.728670] Subtest min-max-config-loaded: SKIP (0.000s) [449.803698] [103/131] (573s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled) [449.864623] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled [449.867057] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s) [449.972457] [104/131] (573s left) kms_big_fb (4-tiled-addfb-size-offset-overflow) [450.085034] Starting subtest: 4-tiled-addfb-size-offset-overflow [450.087258] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s) [450.191444] [105/131] (573s left) gem_exec_reloc (basic-concurrent16) [450.257228] Subtest basic-concurrent16: SKIP (0.000s) [450.333300] [106/131] (573s left) kms_pm_rpm (system-suspend-modeset) [452.169708] Starting subtest: system-suspend-modeset [472.052069] Subtest system-suspend-modeset: SUCCESS (4.637s) [472.417788] [107/131] (566s left) kms_big_fb (linear-64bpp-rotate-90) [472.533936] Starting subtest: linear-64bpp-rotate-90 [472.555086] Subtest linear-64bpp-rotate-90: SKIP (0.019s) [472.655820] [108/131] (566s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc) [472.752076] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc [475.775072] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (2.982s) [475.882369] [109/131] (563s left) sysfs_heartbeat_interval (mixed) [475.939261] Starting subtest: mixed [475.941712] Starting dynamic subtest: vecs0 [481.206948] Dynamic subtest vecs0: SUCCESS (5.220s) [481.218738] Starting dynamic subtest: ccs0 [486.416843] Dynamic subtest ccs0: SUCCESS (5.184s) [486.428676] Starting dynamic subtest: vcs0 [491.643686] Dynamic subtest vcs0: SUCCESS (5.211s) [491.655802] Starting dynamic subtest: bcs0 [496.868704] Dynamic subtest bcs0: SUCCESS (5.216s) [496.880705] Starting dynamic subtest: rcs0 [502.078821] Dynamic subtest rcs0: SUCCESS (5.204s) [502.089880] Starting dynamic subtest: vcs1 [507.341162] Dynamic subtest vcs1: SUCCESS (5.259s) [507.343962] Subtest mixed: SUCCESS (31.304s) [507.411858] [110/131] (531s left) kms_chamelium_edid (hdmi-mode-timings) [507.807703] Subtest hdmi-mode-timings: SKIP (0.000s) [508.581403] [111/131] (530s left) gem_exec_suspend (basic-s3-devices) [508.655121] Starting subtest: basic-S3-devices [508.657557] Starting dynamic subtest: smem [519.118610] Dynamic subtest smem: SUCCESS (10.459s) [519.121057] Subtest basic-S3-devices: SUCCESS (10.461s) [519.301197] [112/131] (519s left) gem_render_copy (y-tiled) [519.373704] Starting subtest: y-tiled [519.378291] Subtest y-tiled: SKIP (0.000s) [519.555337] [113/131] (519s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [519.683715] Starting subtest: basic-flip-before-cursor-atomic [520.640291] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.955s) [521.491123] [114/131] (517s left) i915_suspend (fence-restore-tiled2untiled) [521.544484] Starting subtest: fence-restore-tiled2untiled [521.549215] Subtest fence-restore-tiled2untiled: SKIP (0.000s) [521.643218] [115/131] (517s left) syncobj_wait (wait-delayed-signal) [521.698376] Starting subtest: wait-delayed-signal [521.799694] Subtest wait-delayed-signal: SUCCESS (0.103s) [521.873726] [116/131] (517s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs) [521.968375] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs [521.971104] Starting dynamic subtest: pipe-A-eDP-1 [521.994383] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [522.005123] Starting dynamic subtest: pipe-B-eDP-1 [522.026939] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [522.035766] Starting dynamic subtest: pipe-C-eDP-1 [522.057978] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [522.066447] Starting dynamic subtest: pipe-D-eDP-1 [522.088946] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [522.094417] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.004s) [524.142119] [117/131] (515s left) gem_exec_whisper (basic-queues) [524.204210] Starting subtest: basic-queues [530.097704] Subtest basic-queues: SUCCESS (5.887s) [530.278977] [118/131] (509s left) gem_render_copy (yf-tiled-ccs-to-y-tiled) [530.349710] Starting subtest: yf-tiled-ccs-to-y-tiled [530.354384] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s) [530.535004] [119/131] (508s left) gem_close_race (basic-process) [530.617864] Starting subtest: basic-process [530.694079] Subtest basic-process: SUCCESS (0.075s) [530.871555] [120/131] (508s left) kms_dsc (dsc-with-bpc) [530.970538] Starting subtest: dsc-with-bpc [531.002845] Subtest dsc-with-bpc: SKIP (0.001s) [531.040902] [121/131] (508s left) gen7_exec_parse (load-register-reg) [531.117065] Subtest load-register-reg: SKIP (0.000s) [531.193777] [122/131] (508s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs) [531.278471] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs [531.280793] Starting dynamic subtest: pipe-A-eDP-1 [531.773333] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.493s) [531.783234] Starting dynamic subtest: pipe-B-eDP-1 [531.804335] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s) [531.815779] Starting dynamic subtest: pipe-C-eDP-1 [531.835152] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.003s) [531.843780] Starting dynamic subtest: pipe-D-eDP-1 [531.862693] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s) [531.864686] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.506s) [533.997735] [123/131] (505s left) kms_joiner (basic-big-joiner) [534.102695] Starting subtest: basic-big-joiner [534.107040] Subtest basic-big-joiner: SKIP (0.000s) [534.161437] [124/131] (505s left) gem_mmap_offset (pf-nonblock) [534.234911] Starting subtest: pf-nonblock [534.240505] Subtest pf-nonblock: SUCCESS (0.006s) [534.380032] [125/131] (505s left) gem_exec_reloc (basic-wc-cpu-noreloc) [534.443635] Subtest basic-wc-cpu-noreloc: SKIP (0.000s) [534.523457] [126/131] (505s left) api_intel_bb (simple-bb-ctx) [534.578719] Starting subtest: simple-bb-ctx [534.585756] Subtest simple-bb-ctx: SUCCESS (0.008s) [534.701745] [127/131] (505s left) drm_fdinfo (busy) [534.739304] Starting subtest: busy [534.741839] Starting dynamic subtest: rcs0 [534.761668] Dynamic subtest rcs0: SKIP (0.021s) [534.773018] Starting dynamic subtest: bcs0 [534.777522] Dynamic subtest bcs0: SKIP (0.000s) [534.786175] Starting dynamic subtest: vcs0 [534.790749] Dynamic subtest vcs0: SKIP (0.000s) [534.799538] Starting dynamic subtest: vcs1 [534.803912] Dynamic subtest vcs1: SKIP (0.000s) [534.812458] Starting dynamic subtest: vecs0 [534.816843] Dynamic subtest vecs0: SKIP (0.000s) [534.825387] Starting dynamic subtest: ccs0 [534.829854] Dynamic subtest ccs0: SKIP (0.000s) [534.834285] Subtest busy: SKIP (0.025s) [534.865033] [128/131] (504s left) prime_vgem (wait) [534.943435] Starting subtest: wait [534.945561] Starting dynamic subtest: rcs0 [535.032743] Dynamic subtest rcs0: SUCCESS (0.089s) [535.043503] Starting dynamic subtest: bcs0 [535.119087] Dynamic subtest bcs0: SUCCESS (0.086s) [535.128165] Starting dynamic subtest: vcs0 [535.159485] Dynamic subtest vcs0: SUCCESS (0.040s) [535.168739] Starting dynamic subtest: vcs1 [535.205477] Dynamic subtest vcs1: SUCCESS (0.045s) [535.214518] Starting dynamic subtest: vecs0 [535.251708] Dynamic subtest vecs0: SUCCESS (0.046s) [535.260900] Starting dynamic subtest: ccs0 [535.295436] Dynamic subtest ccs0: SUCCESS (0.043s) [535.297307] Subtest wait: SUCCESS (0.352s) [535.428123] [129/131] (504s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu) [535.530121] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu [535.533889] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [535.631112] [130/131] (504s left) kms_content_protection (atomic-dpms) [535.808127] Starting subtest: atomic-dpms [539.144197] Subtest atomic-dpms: SKIP (3.332s) [540.315856] [131/131] (499s left) gem_exec_await (wide-contexts) [540.483712] Starting subtest: wide-contexts [563.942371] Subtest wide-contexts: SUCCESS (23.450s) [564.129501] Closing watchdogs Done.