[37.072532] Initializing watchdogs [37.072560] /dev/watchdog0 [37.079728] [001/130] (960s left) kms_cursor_legacy (cursor-vs-flip-atomic) [37.200186] Starting subtest: cursor-vs-flip-atomic [65.916355] Subtest cursor-vs-flip-atomic: SUCCESS (28.715s) [66.830406] [002/130] (930s left) syncobj_timeline (invalid-signal-zero-handles) [66.891255] Starting subtest: invalid-signal-zero-handles [66.893685] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [66.953796] [003/130] (930s left) kms_flip (2x-flip-vs-panning-interruptible) [67.043044] Starting subtest: 2x-flip-vs-panning-interruptible [67.058415] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.013s) [67.109273] [004/130] (930s left) gem_render_copy (x-tiled-to-vebox-y-tiled) [67.162671] Starting subtest: x-tiled-to-vebox-y-tiled [67.167312] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s) [67.320650] [005/130] (930s left) gem_exec_whisper (basic-contexts) [67.385071] Starting subtest: basic-contexts [72.081116] Subtest basic-contexts: SUCCESS (4.696s) [72.237945] [006/130] (925s left) kms_big_fb (y-tiled-32bpp-rotate-90) [72.335127] Starting subtest: y-tiled-32bpp-rotate-90 [72.339191] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s) [72.422284] [007/130] (925s left) gem_exec_whisper (basic-forked-all) [72.473872] Starting subtest: basic-forked-all [84.394063] Subtest basic-forked-all: SUCCESS (11.918s) [84.550663] [008/130] (913s left) kms_lease (possible-crtcs-filtering) [84.627787] Starting subtest: possible-crtcs-filtering [84.632657] Subtest possible-crtcs-filtering: SUCCESS (0.004s) [84.721264] [009/130] (912s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs) [84.807863] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs [84.810244] Starting dynamic subtest: pipe-A-eDP-1 [84.877357] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [84.884613] Starting dynamic subtest: pipe-B-eDP-1 [84.940595] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [84.948046] Starting dynamic subtest: pipe-C-eDP-1 [85.005731] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [85.012944] Starting dynamic subtest: pipe-D-eDP-1 [85.072909] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [85.077141] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s) [87.179355] [010/130] (910s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf) [92.292843] Starting subtest: fbc-psr2-cursor-plane-update-sf [92.295402] Starting dynamic subtest: pipe-A-eDP-1 [93.166856] Dynamic subtest pipe-A-eDP-1: SKIP (0.869s) [93.175151] Starting dynamic subtest: pipe-B-eDP-1 [94.386527] Dynamic subtest pipe-B-eDP-1: SKIP (1.218s) [94.391143] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (2.090s) [95.627565] [011/130] (902s left) syncobj_timeline (device-signal-unordered) [95.673467] Starting subtest: device-signal-unordered [95.675554] Subtest device-signal-unordered: SUCCESS (0.001s) [95.728864] [012/130] (902s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf) [100.845553] Starting subtest: pr-overlay-plane-update-continuous-sf [100.851217] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.002s) [101.707437] [013/130] (896s left) i915_pm_freq_api (freq-reset) [101.760195] Starting subtest: freq-reset [101.762593] Starting dynamic subtest: gt0 [101.958654] Dynamic subtest gt0: SUCCESS (0.196s) [101.966145] Starting dynamic subtest: gt1 [102.133691] Dynamic subtest gt1: SUCCESS (0.172s) [102.136369] Subtest freq-reset: SUCCESS (0.368s) [102.179135] [014/130] (895s left) core_getversion (basic) [102.211718] Starting subtest: basic [102.216415] Subtest basic: SUCCESS (0.000s) [102.247111] [015/130] (895s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait) [104.565486] Starting subtest: modeset-non-lpsp-stress-no-wait [104.618768] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.052s) [104.897363] [016/130] (892s left) gem_exec_reloc (basic-wc-gtt-noreloc) [104.949053] Subtest basic-wc-gtt-noreloc: SKIP (0.000s) [105.006626] [017/130] (892s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt) [105.098163] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt [105.102261] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [105.165413] [018/130] (892s left) sw_sync (sync_multi_producer_single_consumer) [105.194482] Starting subtest: sync_multi_producer_single_consumer [106.268560] Subtest sync_multi_producer_single_consumer: SUCCESS (1.074s) [106.303350] [019/130] (891s left) gem_exec_params (rel-constants-invalid-rel-gen5) [106.353863] Starting subtest: rel-constants-invalid-rel-gen5 [106.356524] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s) [106.441677] [020/130] (891s left) gem_exec_parallel (basic) [106.496315] Starting subtest: basic [106.498854] Starting dynamic subtest: rcs0 [107.879131] Dynamic subtest rcs0: SUCCESS (1.383s) [107.885324] Starting dynamic subtest: bcs0 [109.284723] Dynamic subtest bcs0: SUCCESS (1.405s) [109.294175] Starting dynamic subtest: vcs0 [111.402846] Dynamic subtest vcs0: SUCCESS (2.117s) [111.411343] Starting dynamic subtest: vcs1 [113.322864] Dynamic subtest vcs1: SUCCESS (1.919s) [113.331833] Starting dynamic subtest: vecs0 [115.370460] Dynamic subtest vecs0: SUCCESS (2.047s) [115.379676] Starting dynamic subtest: ccs0 [116.792730] Dynamic subtest ccs0: SUCCESS (1.423s) [116.795327] Subtest basic: SUCCESS (10.297s) [116.951303] [021/130] (880s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt) [117.054666] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt [117.059322] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s) [117.118941] [022/130] (880s left) kms_cursor_legacy (forked-bo) [117.227999] Starting subtest: forked-bo [117.230283] Starting dynamic subtest: pipe-A [122.648906] Dynamic subtest pipe-A: SUCCESS (5.419s) [122.658070] Starting dynamic subtest: pipe-B [128.066689] Dynamic subtest pipe-B: SUCCESS (5.418s) [128.074689] Starting dynamic subtest: pipe-C [133.481699] Dynamic subtest pipe-C: SUCCESS (5.416s) [133.488904] Starting dynamic subtest: pipe-D [138.902696] Dynamic subtest pipe-D: SUCCESS (5.420s) [138.910432] Starting dynamic subtest: all-pipes [144.341891] Dynamic subtest all-pipes: SUCCESS (5.438s) [144.344757] Subtest forked-bo: SUCCESS (27.111s) [144.418742] [023/130] (853s left) syncobj_wait (single-wait-all-submitted) [144.470209] Starting subtest: single-wait-all-submitted [144.575459] Subtest single-wait-all-submitted: SUCCESS (0.104s) [144.628931] [024/130] (853s left) gem_exec_reloc (basic-cpu-noreloc) [144.682947] Subtest basic-cpu-noreloc: SKIP (0.000s) [144.724701] [025/130] (853s left) syncobj_wait (invalid-signal-illegal-handle) [144.767600] Starting subtest: invalid-signal-illegal-handle [144.769912] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [144.824398] [026/130] (853s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270) [144.909798] Starting subtest: primary-yf-tiled-reflect-x-270 [144.914359] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s) [144.960163] [027/130] (852s left) gem_basic (multigpu-create-close) [145.011426] Starting subtest: multigpu-create-close [145.015594] Subtest multigpu-create-close: SKIP (0.000s) [145.069918] [028/130] (852s left) vgem_basic (bad-fence) [145.159714] Starting subtest: bad-fence [145.162179] Subtest bad-fence: SUCCESS (0.000s) [145.191769] [029/130] (852s left) gem_mmap_gtt (basic-read-write-distinct) [145.241579] Subtest basic-read-write-distinct: SKIP (0.000s) [145.281438] [030/130] (852s left) gem_flink_race (flink_name) [145.309409] Starting subtest: flink_name [150.708300] Subtest flink_name: SUCCESS (5.396s) [150.783296] [031/130] (847s left) vgem_basic (setversion) [150.815082] Starting subtest: setversion [150.819526] Subtest setversion: SUCCESS (0.000s) [150.845372] [032/130] (847s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy) [150.948028] Starting subtest: 2x-long-flip-vs-cursor-legacy [150.952415] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s) [151.036305] [033/130] (846s left) syncobj_timeline (wait-any-interrupted) [151.082435] Starting subtest: wait-any-interrupted [151.182997] Subtest wait-any-interrupted: SUCCESS (0.102s) [151.232949] [034/130] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [151.342128] Starting subtest: basic-busy-flip-before-cursor-legacy [151.346819] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [151.461922] [035/130] (846s left) kms_rotation_crc (bad-pixel-format) [151.547455] Starting subtest: bad-pixel-format [151.552061] Subtest bad-pixel-format: SKIP (0.001s) [151.599388] [036/130] (846s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt) [151.696272] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt [151.700485] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [151.747717] [037/130] (846s left) gem_mmap_gtt (bad-object) [151.798950] Subtest bad-object: SKIP (0.000s) [151.840051] [038/130] (846s left) gem_spin_batch (resubmit) [151.894458] Starting subtest: resubmit [151.896606] Starting dynamic subtest: rcs0 [151.900632] Dynamic subtest rcs0: SUCCESS (0.006s) [151.908737] Starting dynamic subtest: bcs0 [151.910859] Dynamic subtest bcs0: SUCCESS (0.002s) [151.916340] Starting dynamic subtest: vcs0 [151.918193] Dynamic subtest vcs0: SUCCESS (0.001s) [151.923628] Starting dynamic subtest: vcs1 [151.925366] Dynamic subtest vcs1: SUCCESS (0.002s) [151.930729] Starting dynamic subtest: vecs0 [151.932411] Dynamic subtest vecs0: SUCCESS (0.002s) [151.938312] Starting dynamic subtest: ccs0 [151.939933] Dynamic subtest ccs0: SUCCESS (0.002s) [151.941549] Subtest resubmit: SUCCESS (0.016s) [152.129594] [039/130] (845s left) kms_universal_plane (universal-plane-sanity) [152.215497] Starting subtest: universal-plane-sanity [152.217501] Starting dynamic subtest: pipe-A-eDP-1 [152.789043] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.570s) [152.798933] Starting dynamic subtest: pipe-B-eDP-1 [154.068578] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.280s) [154.078403] Starting dynamic subtest: pipe-C-eDP-1 [155.354662] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.286s) [155.363972] Starting dynamic subtest: pipe-D-eDP-1 [156.605292] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.248s) [156.607911] Subtest universal-plane-sanity: SUCCESS (4.388s) [157.516139] [040/130] (840s left) gem_exec_schedule (semaphore-codependency) [157.600384] Starting subtest: semaphore-codependency [157.602595] Subtest semaphore-codependency: SUCCESS (0.009s) [157.918990] [041/130] (840s left) syncobj_wait (invalid-signal-one-illegal-handle) [157.958966] Starting subtest: invalid-signal-one-illegal-handle [157.961885] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s) [158.004681] [042/130] (840s left) kms_content_protection (atomic) [158.136045] Starting subtest: atomic [161.653831] Subtest atomic: SKIP (3.515s) [162.895602] [043/130] (835s left) gem_exec_fair (basic-none-share) [162.965863] Subtest basic-none-share: SKIP (0.000s) [162.990139] [044/130] (835s left) kms_big_fb (yf-tiled-16bpp-rotate-0) [163.090618] Starting subtest: yf-tiled-16bpp-rotate-0 [163.095040] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s) [163.152805] [045/130] (835s left) gem_caching (read-writes) [163.211106] Subtest read-writes: SKIP (0.000s) [163.251997] [046/130] (834s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled) [163.297967] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled [163.299927] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s) [163.352029] [047/130] (834s left) gem_eio (in-flight-contexts-immediate) [163.565080] Starting subtest: in-flight-contexts-immediate [175.348894] Subtest in-flight-contexts-immediate: SUCCESS (11.782s) [175.491090] [048/130] (822s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt) [175.588471] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt [175.592996] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [175.678488] [049/130] (822s left) kms_draw_crc (fill-fb) [175.772111] Starting subtest: fill-fb [176.167630] Subtest fill-fb: SUCCESS (0.397s) [176.251169] [050/130] (822s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip) [176.341768] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip [176.899222] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.558s) [177.033581] [051/130] (821s left) syncobj_timeline (reset-signaled) [177.076035] Starting subtest: reset-signaled [177.078066] Subtest reset-signaled: SUCCESS (0.000s) [177.124263] [052/130] (821s left) kms_atomic (atomic-invalid-params) [177.210714] Starting subtest: atomic-invalid-params [177.212987] Starting dynamic subtest: pipe-A-eDP-1 [177.599097] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.386s) [177.601840] Subtest atomic-invalid-params: SUCCESS (0.387s) [178.506501] [053/130] (819s left) perf_pmu (enable-race) [178.535650] Starting subtest: enable-race [178.537906] Starting dynamic subtest: rcs0 [191.281816] Dynamic subtest rcs0: SUCCESS (12.747s) [191.288547] Starting dynamic subtest: bcs0 [204.011621] Dynamic subtest bcs0: SUCCESS (12.729s) [204.018409] Starting dynamic subtest: vcs0 [219.371204] Dynamic subtest vcs0: SUCCESS (15.357s) [219.380579] Starting dynamic subtest: vcs1 [235.372928] Dynamic subtest vcs1: SUCCESS (16.001s) [235.381611] Starting dynamic subtest: vecs0 [251.307173] Dynamic subtest vecs0: SUCCESS (15.934s) [251.316671] Starting dynamic subtest: ccs0 [264.155720] Dynamic subtest ccs0: SUCCESS (12.849s) [264.158827] Subtest enable-race: SUCCESS (85.619s) [264.239720] [054/130] (734s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt) [264.334980] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt [264.339683] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s) [264.403392] [055/130] (733s left) gem_eio (create) [264.603876] Starting subtest: create [264.895032] Subtest create: SUCCESS (0.291s) [264.988762] [056/130] (733s left) drm_fdinfo (context-close-stress) [265.026257] Starting subtest: context-close-stress [275.770650] Subtest context-close-stress: SUCCESS (10.744s) [275.880278] [057/130] (722s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc) [276.121928] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc [276.124177] Starting dynamic subtest: pipe-A-eDP-1 [276.869446] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.736s) [276.878084] Starting dynamic subtest: pipe-B-eDP-1 [278.762608] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.893s) [278.769744] Starting dynamic subtest: pipe-C-eDP-1 [280.615934] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.853s) [280.623931] Starting dynamic subtest: pipe-D-eDP-1 [282.528456] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.912s) [282.530499] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (6.396s) [283.724231] [058/130] (714s left) kms_plane (plane-position-covered) [283.807837] Starting subtest: plane-position-covered [284.286387] Starting dynamic subtest: pipe-A-plane-1 [285.632619] Dynamic subtest pipe-A-plane-1: SUCCESS (1.348s) [285.641188] Starting dynamic subtest: pipe-A-plane-2 [286.991817] Dynamic subtest pipe-A-plane-2: SUCCESS (1.358s) [287.000242] Starting dynamic subtest: pipe-A-plane-3 [288.335141] Dynamic subtest pipe-A-plane-3: SUCCESS (1.344s) [288.344808] Starting dynamic subtest: pipe-A-plane-4 [289.664822] Dynamic subtest pipe-A-plane-4: SUCCESS (1.329s) [289.673055] Starting dynamic subtest: pipe-A-plane-5 [289.686210] Dynamic subtest pipe-A-plane-5: SUCCESS (0.023s) [290.944049] Starting dynamic subtest: pipe-B-plane-1 [292.280333] Dynamic subtest pipe-B-plane-1: SUCCESS (1.345s) [292.288597] Starting dynamic subtest: pipe-B-plane-2 [293.624156] Dynamic subtest pipe-B-plane-2: SUCCESS (1.344s) [293.632785] Starting dynamic subtest: pipe-B-plane-3 [294.966518] Dynamic subtest pipe-B-plane-3: SUCCESS (1.340s) [294.974582] Starting dynamic subtest: pipe-B-plane-4 [296.306941] Dynamic subtest pipe-B-plane-4: SUCCESS (1.341s) [296.314622] Starting dynamic subtest: pipe-B-plane-5 [296.327958] Dynamic subtest pipe-B-plane-5: SUCCESS (0.022s) [296.330286] Subtest plane-position-covered: SUCCESS (12.519s) [297.176434] [059/130] (701s left) gem_partial_pwrite_pread (write-uncached) [297.230443] Subtest write-uncached: SKIP (0.000s) [297.261469] [060/130] (701s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc) [297.364639] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc [297.369041] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [297.430869] [061/130] (701s left) i915_suspend (forcewake) [297.484939] Starting subtest: forcewake [329.384885] Subtest forcewake: SUCCESS (1.972s) [329.413938] [062/130] (698s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt) [329.511574] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt [334.125985] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (4.615s) [334.228631] [063/130] (694s left) kms_chamelium_color (ctm-red-to-blue) [334.321050] Subtest ctm-red-to-blue: SKIP (0.000s) [334.344279] [064/130] (694s left) gem_mmap_gtt (fault-concurrent-x) [334.399484] Subtest fault-concurrent-X: SKIP (0.000s) [334.435178] [065/130] (693s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling) [334.524983] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling [334.527328] Starting dynamic subtest: pipe-A-default-mode [337.359429] Dynamic subtest pipe-A-default-mode: SUCCESS (2.830s) [337.361990] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.831s) [338.270202] [066/130] (690s left) gem_exec_fence (basic-await) [338.438296] Starting subtest: basic-await [338.440874] Starting dynamic subtest: rcs0 [338.506184] Dynamic subtest rcs0: SUCCESS (0.067s) [338.515392] Starting dynamic subtest: bcs0 [338.574859] Dynamic subtest bcs0: SUCCESS (0.069s) [338.585394] Starting dynamic subtest: vcs0 [338.640833] Dynamic subtest vcs0: SUCCESS (0.065s) [338.651846] Starting dynamic subtest: vcs1 [338.706281] Dynamic subtest vcs1: SUCCESS (0.065s) [338.716783] Starting dynamic subtest: vecs0 [338.772626] Dynamic subtest vecs0: SUCCESS (0.066s) [338.782083] Starting dynamic subtest: ccs0 [338.837839] Dynamic subtest ccs0: SUCCESS (0.064s) [338.840471] Subtest basic-await: SUCCESS (0.400s) [340.343961] [067/130] (688s left) kms_psr (psr2-cursor-mmap-cpu) [340.427236] Starting subtest: psr2-cursor-mmap-cpu [340.430034] Starting dynamic subtest: eDP-1 [342.189921] Dynamic subtest eDP-1: SUCCESS (1.761s) [342.192767] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.761s) [343.067053] [068/130] (685s left) kms_frontbuffer_tracking (fbc-2p-rte) [343.156682] Starting subtest: fbc-2p-rte [343.161072] Subtest fbc-2p-rte: SKIP (0.000s) [343.224445] [069/130] (685s left) kms_big_fb (linear-64bpp-rotate-180) [343.321365] Starting subtest: linear-64bpp-rotate-180 [344.710342] Subtest linear-64bpp-rotate-180: SUCCESS (1.388s) [344.851874] [070/130] (683s left) kms_flip (2x-flip-vs-suspend-interruptible) [344.942986] Starting subtest: 2x-flip-vs-suspend-interruptible [344.959017] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.014s) [345.007900] [071/130] (683s left) prime_vgem (basic-fence-blt) [345.053543] Starting subtest: basic-fence-blt [345.133967] Subtest basic-fence-blt: SUCCESS (0.080s) [345.244610] [072/130] (683s left) gem_pipe_control_store_loop (reused-buffer) [345.295971] Starting subtest: reused-buffer [347.447973] Subtest reused-buffer: SUCCESS (2.150s) [347.567548] [073/130] (680s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt) [347.661316] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt [347.665627] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [347.730752] [074/130] (680s left) kms_content_protection (uevent) [347.861463] Starting subtest: uevent [351.364725] Subtest uevent: SKIP (3.500s) [352.603301] [075/130] (675s left) i915_query (test-query-geometry-subslices) [352.664041] Starting subtest: test-query-geometry-subslices [352.666585] Subtest test-query-geometry-subslices: SUCCESS (0.000s) [352.760951] [076/130] (675s left) gem_userptr_blits (map-fixed-invalidate-overlap) [352.823402] Starting subtest: map-fixed-invalidate-overlap [352.828002] Subtest map-fixed-invalidate-overlap: SKIP (0.000s) [352.918912] [077/130] (675s left) kms_psr (fbc-psr-sprite-render) [353.006937] Starting subtest: fbc-psr-sprite-render [353.009233] Starting dynamic subtest: eDP-1 [353.013567] Dynamic subtest eDP-1: SKIP (0.000s) [353.017887] Subtest fbc-psr-sprite-render: SKIP (0.000s) [353.058782] [078/130] (675s left) kms_setmode (invalid-clone-single-crtc-stealing) [353.106556] Starting subtest: invalid-clone-single-crtc-stealing [353.113830] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s) [353.171115] [079/130] (675s left) kms_plane_scaling (planes-downscale-factor-0-75) [353.264059] Starting subtest: planes-downscale-factor-0-75 [353.266427] Starting dynamic subtest: pipe-A [353.281428] Dynamic subtest pipe-A: SKIP (0.012s) [353.289829] Starting dynamic subtest: pipe-B [353.304256] Dynamic subtest pipe-B: SKIP (0.022s) [353.311629] Starting dynamic subtest: pipe-C [353.326445] Dynamic subtest pipe-C: SKIP (0.022s) [353.333729] Starting dynamic subtest: pipe-D [353.350759] Dynamic subtest pipe-D: SKIP (0.022s) [353.355179] Subtest planes-downscale-factor-0-75: SKIP (0.080s) [353.414809] [080/130] (675s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size) [353.531049] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size [383.362045] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (29.823s) [384.223785] [081/130] (644s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions) [384.327836] Starting subtest: cursorB-vs-flipB-atomic-transitions [384.332621] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s) [384.418241] [082/130] (644s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats) [384.796876] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s) [385.672212] [083/130] (643s left) gem_mmap_wc (bad-offset) [385.725257] Subtest bad-offset: SKIP (0.000s) [385.755313] [084/130] (642s left) gem_userptr_blits (probe) [385.813826] Starting subtest: probe [385.867769] Subtest probe: SUCCESS (0.054s) [385.959886] [085/130] (642s left) kms_pm_rpm (basic-pci-d3-state) [387.469831] Starting subtest: basic-pci-d3-state [388.912644] Subtest basic-pci-d3-state: SUCCESS (1.444s) [388.977589] [086/130] (639s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip) [389.068608] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip [389.578719] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.514s) [389.718897] [087/130] (639s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt) [389.820290] Starting subtest: psr-rgb101010-draw-mmap-gtt [389.824438] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s) [389.888393] [088/130] (638s left) kms_lease (lease-get) [389.981016] Starting subtest: lease-get [389.983426] Starting dynamic subtest: pipe-A-eDP-1 [389.985784] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [389.993424] Starting dynamic subtest: pipe-B-eDP-1 [389.995682] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [390.002862] Starting dynamic subtest: pipe-C-eDP-1 [390.005073] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [390.012256] Starting dynamic subtest: pipe-D-eDP-1 [390.014469] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [390.016706] Subtest lease-get: SUCCESS (0.006s) [390.072496] [089/130] (638s left) kms_cursor_edge_walk (256x256-left-edge) [390.160965] Starting subtest: 256x256-left-edge [390.163253] Starting dynamic subtest: pipe-A-eDP-1 [393.674769] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.509s) [393.683397] Starting dynamic subtest: pipe-D-eDP-1 [396.906793] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.233s) [396.909260] Subtest 256x256-left-edge: SUCCESS (6.744s) [397.804331] [090/130] (631s left) kms_cursor_crc (cursor-random-512x512) [398.009838] Starting subtest: cursor-random-512x512 [398.014745] Subtest cursor-random-512x512: SKIP (0.001s) [398.124440] [091/130] (630s left) gem_ctx_persistence (legacy-engines-cleanup) [398.168870] Starting subtest: legacy-engines-cleanup [398.170759] Starting dynamic subtest: render [398.228901] Dynamic subtest render: SUCCESS (0.061s) [398.238117] Starting dynamic subtest: bsd1 [398.276144] Dynamic subtest bsd1: SUCCESS (0.045s) [398.287040] Starting dynamic subtest: bsd2 [398.327204] Dynamic subtest bsd2: SUCCESS (0.049s) [398.337190] Starting dynamic subtest: blt [398.366941] Dynamic subtest blt: SUCCESS (0.038s) [398.375726] Starting dynamic subtest: vebox [398.418333] Dynamic subtest vebox: SUCCESS (0.049s) [398.421260] Subtest legacy-engines-cleanup: SUCCESS (0.250s) [398.476085] [092/130] (630s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render) [398.569996] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render [401.735732] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (3.169s) [401.849226] [093/130] (627s left) gem_set_tiling_vs_blt (tiled-to-tiled) [401.896301] Subtest tiled-to-tiled: SKIP (0.000s) [401.956115] [094/130] (626s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen) [402.041697] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen [406.353337] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.312s) [406.451764] [095/130] (622s left) kms_invalid_mode (zero-clock) [406.534198] Starting subtest: zero-clock [406.536543] Starting dynamic subtest: pipe-A-eDP-1 [406.817858] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.282s) [406.825182] Starting dynamic subtest: pipe-B-eDP-1 [406.827231] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [406.834137] Starting dynamic subtest: pipe-C-eDP-1 [406.836154] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [406.843284] Starting dynamic subtest: pipe-D-eDP-1 [406.845690] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [406.848190] Subtest zero-clock: SUCCESS (0.288s) [407.709488] [096/130] (621s left) gem_create (create-ext-placement-all) [407.862020] Starting subtest: create-ext-placement-all [407.865383] Subtest create-ext-placement-all: SUCCESS (0.000s) [407.953334] [097/130] (620s left) gem_ctx_param (invalid-get-no-zeromap) [407.991774] Starting subtest: invalid-get-no-zeromap [407.994213] Subtest invalid-get-no-zeromap: SUCCESS (0.001s) [408.069369] [098/130] (620s left) kms_big_fb (y-tiled-16bpp-rotate-270) [408.162116] Starting subtest: y-tiled-16bpp-rotate-270 [408.166722] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s) [408.223864] [099/130] (620s left) gem_exec_params (rs-invalid) [408.282140] Starting subtest: rs-invalid [408.288439] Subtest rs-invalid: SUCCESS (0.003s) [408.394282] [100/130] (620s left) drm_fdinfo (virtual-busy-idle) [408.421214] Starting subtest: virtual-busy-idle [408.425562] Subtest virtual-busy-idle: SKIP (0.000s) [408.468714] [101/130] (620s left) kms_draw_crc (draw-method-pwrite) [408.566569] Starting subtest: draw-method-pwrite [408.568642] Starting dynamic subtest: xrgb8888-untiled [408.985881] Dynamic subtest xrgb8888-untiled: SUCCESS (0.421s) [408.994256] Starting dynamic subtest: rgb565-untiled [409.330906] Dynamic subtest rgb565-untiled: SUCCESS (0.344s) [409.343972] Starting dynamic subtest: xrgb2101010-untiled [409.740915] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.410s) [409.748625] Starting dynamic subtest: xrgb8888-xtiled [411.019798] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.277s) [411.028979] Starting dynamic subtest: rgb565-xtiled [411.820032] Dynamic subtest rgb565-xtiled: SUCCESS (0.800s) [411.831279] Starting dynamic subtest: xrgb2101010-xtiled [413.075074] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.254s) [413.082584] Starting dynamic subtest: xrgb8888-4tiled [413.875669] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.800s) [413.883969] Starting dynamic subtest: rgb565-4tiled [414.308948] Dynamic subtest rgb565-4tiled: SUCCESS (0.433s) [414.317387] Starting dynamic subtest: xrgb2101010-4tiled [415.097336] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.788s) [415.099452] Subtest draw-method-pwrite: SUCCESS (6.533s) [415.155213] [102/130] (613s left) gem_mmap_wc (copy) [415.203912] Subtest copy: SKIP (0.000s) [415.247278] [103/130] (613s left) i915_hangman (engine-error-state-capture) [415.298282] Starting subtest: engine-error-state-capture [415.300559] Starting dynamic subtest: rcs0 [419.223771] Dynamic subtest rcs0: SUCCESS (3.924s) [419.234828] Starting dynamic subtest: bcs0 [425.239319] Dynamic subtest bcs0: SUCCESS (6.014s) [425.248861] Starting dynamic subtest: vcs0 [431.191767] Dynamic subtest vcs0: SUCCESS (5.952s) [431.201932] Starting dynamic subtest: vcs1 [436.247548] Dynamic subtest vcs1: SUCCESS (5.055s) [436.258609] Starting dynamic subtest: vecs0 [442.262782] Dynamic subtest vecs0: SUCCESS (6.015s) [442.273334] Starting dynamic subtest: ccs0 [448.214955] Dynamic subtest ccs0: SUCCESS (5.951s) [448.218193] Subtest engine-error-state-capture: SUCCESS (32.917s) [448.274331] [104/130] (580s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render) [448.370513] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render [448.374945] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s) [448.459938] [105/130] (580s left) gem_exec_schedule (implicit-write-read) [448.512584] Starting subtest: implicit-write-read [448.514336] Starting dynamic subtest: rcs0 [448.594726] Dynamic subtest rcs0: SUCCESS (0.094s) [448.606747] Starting dynamic subtest: vcs0 [448.674031] Dynamic subtest vcs0: SUCCESS (0.079s) [448.683540] Starting dynamic subtest: vcs1 [448.742009] Dynamic subtest vcs1: SUCCESS (0.067s) [448.755586] Starting dynamic subtest: vecs0 [448.809760] Dynamic subtest vecs0: SUCCESS (0.067s) [448.820506] Starting dynamic subtest: ccs0 [448.878571] Dynamic subtest ccs0: SUCCESS (0.069s) [448.880978] Subtest implicit-write-read: SUCCESS (0.379s) [450.386826] [106/130] (578s left) prime_self_import (basic-with_fd_dup) [450.414204] Starting subtest: basic-with_fd_dup [450.437446] Subtest basic-with_fd_dup: SUCCESS (0.024s) [450.531627] [107/130] (578s left) kms_ccs (bad-aux-stride-y-tiled-ccs) [450.623900] Starting subtest: bad-aux-stride-y-tiled-ccs [450.626108] Starting dynamic subtest: pipe-A-eDP-1 [450.648368] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [450.655390] Starting dynamic subtest: pipe-B-eDP-1 [450.678364] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [450.685219] Starting dynamic subtest: pipe-C-eDP-1 [450.707129] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [450.714239] Starting dynamic subtest: pipe-D-eDP-1 [450.736616] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [450.741067] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.003s) [452.958442] [108/130] (576s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs) [453.038270] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs [453.040803] Starting dynamic subtest: pipe-A-eDP-1 [453.065306] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [453.074859] Starting dynamic subtest: pipe-B-eDP-1 [453.098154] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [453.105519] Starting dynamic subtest: pipe-C-eDP-1 [453.128259] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [453.135569] Starting dynamic subtest: pipe-D-eDP-1 [453.157739] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [453.161785] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.003s) [455.390402] [109/130] (573s left) kms_vblank (ts-continuation-modeset-rpm) [455.478053] Starting subtest: ts-continuation-modeset-rpm [455.480347] Starting dynamic subtest: pipe-A-eDP-1 [457.172799] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.691s) [457.187400] Starting dynamic subtest: pipe-D-eDP-1 [459.479006] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.303s) [459.481660] Subtest ts-continuation-modeset-rpm: SUCCESS (3.997s) [460.379672] [110/130] (568s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move) [460.478606] Starting subtest: fbc-2p-primscrn-cur-indfb-move [460.482824] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s) [460.545660] [111/130] (568s left) gem_exec_suspend (basic-s3-devices) [460.603875] Starting subtest: basic-S3-devices [460.606407] Starting dynamic subtest: smem [471.289709] Dynamic subtest smem: SUCCESS (10.683s) [471.292564] Subtest basic-S3-devices: SUCCESS (10.684s) [471.451427] [112/130] (557s left) kms_ccs (crc-primary-basic-yf-tiled-ccs) [471.545029] Starting subtest: crc-primary-basic-yf-tiled-ccs [471.547402] Starting dynamic subtest: pipe-A-eDP-1 [471.571749] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [471.579872] Starting dynamic subtest: pipe-B-eDP-1 [471.602086] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [471.609704] Starting dynamic subtest: pipe-C-eDP-1 [471.632005] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [471.639446] Starting dynamic subtest: pipe-D-eDP-1 [471.662016] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [471.666131] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.004s) [473.885838] [113/130] (555s left) kms_psr2_su (page_flip-p010) [473.938915] Subtest page_flip-P010: SKIP (0.000s) [473.976778] [114/130] (555s left) api_intel_allocator (gem-pool) [474.024653] Starting subtest: gem-pool [474.029068] Subtest gem-pool: SUCCESS (0.007s) [474.116747] [115/130] (555s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25) [474.205062] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25 [474.207519] Starting dynamic subtest: pipe-A [474.216738] Dynamic subtest pipe-A: SKIP (0.004s) [474.226329] Starting dynamic subtest: pipe-B [474.235583] Dynamic subtest pipe-B: SKIP (0.014s) [474.243160] Starting dynamic subtest: pipe-C [474.252341] Dynamic subtest pipe-C: SKIP (0.014s) [474.259650] Starting dynamic subtest: pipe-D [474.267319] Dynamic subtest pipe-D: SKIP (0.014s) [474.271143] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.047s) [474.312334] [116/130] (554s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs) [474.357064] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs [474.361636] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s) [474.518331] [117/130] (554s left) gem_ctx_param (get-priority-new-ctx) [474.569424] Starting subtest: get-priority-new-ctx [474.572045] Subtest get-priority-new-ctx: SUCCESS (0.001s) [474.635420] [118/130] (554s left) perf_pmu (busy-double-start) [474.665157] Starting subtest: busy-double-start [474.667522] Starting dynamic subtest: rcs0 [478.305691] Dynamic subtest rcs0: FAIL (3.579s) [478.315173] Starting dynamic subtest: bcs0 [481.845079] Dynamic subtest bcs0: SUCCESS (3.600s) [481.856623] Starting dynamic subtest: vcs0 [485.822058] Dynamic subtest vcs0: SUCCESS (3.976s) [485.832956] Starting dynamic subtest: vcs1 [489.790198] Dynamic subtest vcs1: SUCCESS (3.967s) [489.799965] Starting dynamic subtest: vecs0 [493.758231] Dynamic subtest vecs0: SUCCESS (3.967s) [493.768601] Starting dynamic subtest: ccs0 [497.399364] Dynamic subtest ccs0: FAIL (3.564s) [497.401344] Subtest busy-double-start: FAIL (22.657s) [497.421742] [119/130] (531s left) gen9_exec_parse (bb-chained) [497.480005] Subtest bb-chained: SKIP (0.000s) [497.525468] [120/130] (531s left) kms_psr (psr-primary-mmap-cpu) [497.622687] Starting subtest: psr-primary-mmap-cpu [497.624959] Starting dynamic subtest: eDP-1 [499.397181] Dynamic subtest eDP-1: SUCCESS (1.771s) [499.399932] Subtest psr-primary-mmap-cpu: SUCCESS (1.772s) [500.318009] [121/130] (528s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt) [500.421221] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt [500.425475] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s) [500.488877] [122/130] (528s left) gem_exec_balancer (hang) [500.700380] Starting subtest: hang [502.280289] Subtest hang: SUCCESS (1.580s) [502.390451] [123/130] (526s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render) [502.486692] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render [502.491149] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [502.554872] [124/130] (526s left) kms_psr (fbc-psr2-primary-mmap-gtt) [502.641508] Starting subtest: fbc-psr2-primary-mmap-gtt [502.643995] Starting dynamic subtest: eDP-1 [502.648787] Dynamic subtest eDP-1: SKIP (0.000s) [502.653438] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s) [502.691174] [125/130] (526s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff) [502.781367] Starting subtest: psr-2p-primscrn-spr-indfb-onoff [502.785493] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s) [502.850822] [126/130] (526s left) gem_exec_reloc (basic-write-wc-noreloc) [502.903477] Subtest basic-write-wc-noreloc: SKIP (0.000s) [502.950920] [127/130] (526s left) kms_rotation_crc (primary-4-tiled-reflect-x-180) [503.029004] Starting subtest: primary-4-tiled-reflect-x-180 [509.944587] Subtest primary-4-tiled-reflect-x-180: SUCCESS (6.915s) [511.196645] [128/130] (518s left) syncobj_wait (reset-multiple-signaled) [511.254987] Starting subtest: reset-multiple-signaled [511.257938] Subtest reset-multiple-signaled: SUCCESS (0.000s) [511.319758] [129/130] (518s left) kms_addfb_basic (size-max) [511.403141] Starting subtest: size-max [511.405538] Subtest size-max: SUCCESS (0.000s) [511.490774] [130/130] (517s left) kms_getfb (getfb-repeated-different-handles) [511.542957] Starting subtest: getfb-repeated-different-handles [511.545495] Subtest getfb-repeated-different-handles: SUCCESS (0.000s) [511.628724] Closing watchdogs Done.