[70.978236] Initializing watchdogs [70.978287] /dev/watchdog0 [70.993619] [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] [70.997889] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [71.000197] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [71.003495] [001/156] (960s left) syncobj_timeline (etime-single-wait-available-unsubmitted) [71.092733] Starting subtest: etime-single-wait-available-unsubmitted [71.197963] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.105s) [71.276218] [002/156] (960s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping) [71.376376] Starting subtest: pixel-format-4-tiled-modifier-source-clamping [71.415049] Starting dynamic subtest: pipe-A-plane-0 [71.815899] Dynamic subtest pipe-A-plane-0: SUCCESS (0.400s) [71.825255] Starting dynamic subtest: pipe-A-plane-3 [72.114867] Dynamic subtest pipe-A-plane-3: SUCCESS (0.299s) [73.209727] Starting dynamic subtest: pipe-B-plane-0 [73.612986] Dynamic subtest pipe-B-plane-0: SUCCESS (0.411s) [73.620934] Starting dynamic subtest: pipe-B-plane-3 [73.924080] Dynamic subtest pipe-B-plane-3: SUCCESS (0.310s) [74.238835] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (2.860s) [75.091862] [003/156] (956s left) gem_eio (context-create) [75.282440] Starting subtest: context-create [75.532657] Subtest context-create: SUCCESS (0.253s) [75.645847] [004/156] (955s left) kms_draw_crc (draw-method-mmap-cpu) [75.734435] Starting subtest: draw-method-mmap-cpu [75.736713] Starting dynamic subtest: xrgb8888-untiled [75.998994] Dynamic subtest xrgb8888-untiled: SUCCESS (0.266s) [76.008185] Starting dynamic subtest: rgb565-untiled [76.221326] Dynamic subtest rgb565-untiled: SUCCESS (0.222s) [76.230843] Starting dynamic subtest: xrgb2101010-untiled [76.443525] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s) [76.454101] Starting dynamic subtest: xbgr16161616f-untiled [76.676122] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s) [76.685550] Starting dynamic subtest: xrgb8888-xtiled [76.798919] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s) [76.808145] Starting dynamic subtest: rgb565-xtiled [76.932143] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s) [76.941714] Starting dynamic subtest: xrgb2101010-xtiled [77.054051] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s) [77.067602] Starting dynamic subtest: xbgr16161616f-xtiled [77.187764] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s) [77.197377] Starting dynamic subtest: xrgb8888-4tiled [77.331882] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s) [77.341359] Starting dynamic subtest: rgb565-4tiled [77.476315] Dynamic subtest rgb565-4tiled: SUCCESS (0.143s) [77.485618] Starting dynamic subtest: xrgb2101010-4tiled [77.609594] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.133s) [77.618120] Starting dynamic subtest: xbgr16161616f-4tiled [77.754277] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.144s) [77.756614] Subtest draw-method-mmap-cpu: SUCCESS (2.022s) [77.854183] [005/156] (953s left) gem_ctx_isolation (dirty-create) [77.919436] Starting subtest: dirty-create [77.921333] Starting dynamic subtest: rcs0 [78.113589] Dynamic subtest rcs0: SUCCESS (0.193s) [78.123469] Starting dynamic subtest: bcs0 [78.309662] Dynamic subtest bcs0: SUCCESS (0.195s) [78.320334] Starting dynamic subtest: vcs0 [78.521623] Dynamic subtest vcs0: SUCCESS (0.212s) [78.532494] Starting dynamic subtest: vcs1 [80.509448] Dynamic subtest vcs1: SUCCESS (1.987s) [80.518550] Starting dynamic subtest: vecs0 [82.505879] Dynamic subtest vecs0: SUCCESS (1.996s) [82.516932] Starting dynamic subtest: ccs0 [84.495371] Dynamic subtest ccs0: SUCCESS (1.989s) [84.497898] Subtest dirty-create: SUCCESS (6.576s) [84.733353] [006/156] (946s left) gem_spin_batch (resubmit) [84.799063] Starting subtest: resubmit [84.801520] Starting dynamic subtest: rcs0 [84.809807] Dynamic subtest rcs0: SUCCESS (0.011s) [84.822288] Starting dynamic subtest: bcs0 [84.824646] Dynamic subtest bcs0: SUCCESS (0.004s) [84.833212] Starting dynamic subtest: vcs0 [84.835108] Dynamic subtest vcs0: SUCCESS (0.003s) [84.842427] Starting dynamic subtest: vcs1 [84.844414] Dynamic subtest vcs1: SUCCESS (0.004s) [84.852263] Starting dynamic subtest: vecs0 [84.854172] Dynamic subtest vecs0: SUCCESS (0.003s) [84.861498] Starting dynamic subtest: ccs0 [84.863410] Dynamic subtest ccs0: SUCCESS (0.005s) [84.865294] Subtest resubmit: SUCCESS (0.032s) [85.074816] [007/156] (946s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-blt) [85.191776] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-blt [85.196287] Subtest psrhdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [85.297465] [008/156] (946s left) api_intel_allocator (alloc-simple) [85.346810] Starting subtest: alloc-simple [85.348957] Subtest alloc-simple: SUCCESS (0.003s) [85.477707] [009/156] (946s left) gem_exec_balancer (invalid-balancer) [85.537805] Starting subtest: invalid-balancer [86.452469] Subtest invalid-balancer: SUCCESS (0.913s) [86.752093] [010/156] (945s left) kms_atomic_transition (modeset-transition) [86.846184] Starting subtest: modeset-transition [86.848540] Starting dynamic subtest: 1x-outputs [97.744208] Dynamic subtest 1x-outputs: SUCCESS (10.899s) [97.746646] Subtest modeset-transition: SUCCESS (10.900s) [98.603207] [011/156] (933s left) gem_mmap_offset (mmap-unmap) [98.663622] Starting subtest: mmap-unmap [98.665542] Starting dynamic subtest: smem0 [121.833266] Dynamic subtest smem0: SUCCESS (23.170s) [121.835980] Subtest mmap-unmap: SUCCESS (23.170s) [121.968103] [012/156] (909s left) kms_big_fb (y-tiled-64bpp-rotate-0) [122.072464] Starting subtest: y-tiled-64bpp-rotate-0 [122.077068] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s) [122.172876] [013/156] (909s left) kms_psr (psr2-cursor-mmap-cpu) [122.262350] Starting subtest: psr2-cursor-mmap-cpu [122.264683] Starting dynamic subtest: eDP-1 [123.987913] Dynamic subtest eDP-1: SUCCESS (1.727s) [123.990296] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.727s) [124.801361] [014/156] (907s left) gem_exec_reloc (basic-cpu-gtt) [124.863851] Subtest basic-cpu-gtt: SKIP (0.000s) [124.940829] [015/156] (907s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-onoff) [125.034689] Starting subtest: psrhdr-1p-primscrn-cur-indfb-onoff [125.039206] Subtest psrhdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s) [125.147417] [016/156] (906s left) kms_rotation_crc (primary-rotation-270) [125.235614] Starting subtest: primary-rotation-270 [125.240260] Subtest primary-rotation-270: SKIP (0.001s) [125.317215] [017/156] (906s left) i915_pm_rps (thresholds-idle-park) [125.387620] Starting subtest: thresholds-idle-park [125.392049] Subtest thresholds-idle-park: SKIP (0.001s) [125.486172] [018/156] (906s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle) [125.606230] Starting subtest: short-busy-flip-before-cursor-toggle [125.610984] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s) [125.761161] [019/156] (906s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-shrfb-plflip-blt) [125.855123] Starting subtest: fbchdr-1p-primscrn-shrfb-plflip-blt [125.858536] Subtest fbchdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [125.967925] [020/156] (906s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu) [126.067323] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu [126.071073] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [126.174858] [021/156] (906s left) kms_psr (fbc-psr2-cursor-mmap-cpu) [126.272204] Starting subtest: fbc-psr2-cursor-mmap-cpu [126.274241] Starting dynamic subtest: eDP-1 [126.277929] Dynamic subtest eDP-1: SKIP (0.000s) [126.281621] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s) [126.353184] [022/156] (905s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc) [126.447381] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc [126.450899] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [126.536747] [023/156] (905s left) kms_cursor_crc (cursor-sliding-64x64) [126.723952] Starting subtest: cursor-sliding-64x64 [126.728585] Starting dynamic subtest: pipe-A-eDP-1 [129.941650] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.212s) [129.973756] Starting dynamic subtest: pipe-D-eDP-1 [133.855392] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.888s) [133.857703] Subtest cursor-sliding-64x64: SUCCESS (7.130s) [134.655479] [024/156] (897s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt) [134.759768] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt [142.117016] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.364s) [142.237822] [025/156] (890s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt) [142.334270] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt [149.197466] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.867s) [149.358119] [026/156] (883s left) gem_mmap_wc (write-wc-read-gtt) [149.413417] Subtest write-wc-read-gtt: SKIP (0.000s) [149.470836] [027/156] (883s left) syncobj_wait (invalid-single-wait-unsubmitted) [149.522196] Starting subtest: invalid-single-wait-unsubmitted [149.524629] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s) [149.624400] [028/156] (882s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip) [149.716606] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip [149.720668] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s) [149.806164] [029/156] (882s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc) [149.896386] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc [149.898717] Starting dynamic subtest: pipe-A-eDP-1 [150.395877] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.473s) [150.406138] Starting dynamic subtest: pipe-B-eDP-1 [151.792857] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.391s) [151.803632] Starting dynamic subtest: pipe-C-eDP-1 [153.174972] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.382s) [153.185835] Starting dynamic subtest: pipe-D-eDP-1 [154.515248] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.341s) [154.517523] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.590s) [155.692503] [030/156] (876s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt) [155.790889] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt [155.795308] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [155.909112] [031/156] (876s left) kms_hdmi_inject (inject-4k) [156.271949] Starting subtest: inject-4k [156.593573] Subtest inject-4k: SUCCESS (0.323s) [157.151469] [032/156] (875s left) kms_psr (psr-primary-render) [157.237263] Starting subtest: psr-primary-render [157.239472] Starting dynamic subtest: eDP-1 [159.011659] Dynamic subtest eDP-1: SUCCESS (1.775s) [159.014114] Subtest psr-primary-render: SUCCESS (1.776s) [159.836123] [033/156] (872s left) gem_exec_schedule (fifo) [159.898037] Starting subtest: fifo [159.900085] Starting dynamic subtest: rcs0 [159.986305] Dynamic subtest rcs0: SUCCESS (0.097s) [159.998075] Starting dynamic subtest: vcs0 [160.071109] Dynamic subtest vcs0: SUCCESS (0.084s) [160.082414] Starting dynamic subtest: vcs1 [160.149042] Dynamic subtest vcs1: SUCCESS (0.077s) [160.161643] Starting dynamic subtest: vecs0 [160.229591] Dynamic subtest vecs0: SUCCESS (0.081s) [160.237967] Starting dynamic subtest: ccs0 [160.326201] Dynamic subtest ccs0: SUCCESS (0.095s) [160.329083] Subtest fifo: SUCCESS (0.436s) [161.374738] [034/156] (871s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc) [161.469951] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc [161.472142] Starting dynamic subtest: pipe-A-eDP-1 [161.480758] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [161.490574] Starting dynamic subtest: pipe-B-eDP-1 [161.499873] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [161.509276] Starting dynamic subtest: pipe-C-eDP-1 [161.517642] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [161.526187] Starting dynamic subtest: pipe-D-eDP-1 [161.534271] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [161.538206] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s) [163.729347] [035/156] (869s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [163.778178] Starting subtest: y-tiled-to-vebox-y-tiled [163.782305] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [163.968311] [036/156] (868s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling) [164.053124] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling [164.057392] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.000s) [164.121439] [037/156] (868s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt) [164.212752] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt [164.216961] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s) [164.297462] [038/156] (868s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move) [164.409859] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move [164.414084] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [164.492227] [039/156] (868s left) kms_cdclk (plane-scaling) [164.583633] Starting subtest: plane-scaling [164.585813] Starting dynamic subtest: pipe-A-eDP-1 [164.888726] Dynamic subtest pipe-A-eDP-1: SKIP (0.302s) [164.899346] Starting dynamic subtest: pipe-B-eDP-1 [164.904120] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [164.912540] Starting dynamic subtest: pipe-C-eDP-1 [164.917224] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [164.925629] Starting dynamic subtest: pipe-D-eDP-1 [164.929706] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [164.933860] Subtest plane-scaling: SKIP (0.309s) [165.728702] [040/156] (867s left) gem_mmap (basic) [165.779833] Subtest basic: SKIP (0.000s) [165.841789] [041/156] (867s left) kms_flip (2x-plain-flip-interruptible) [165.942076] Starting subtest: 2x-plain-flip-interruptible [165.952600] Subtest 2x-plain-flip-interruptible: SKIP (0.010s) [166.028365] [042/156] (867s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-onoff) [166.123058] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-onoff [166.127251] Subtest fbchdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [166.224303] [043/156] (866s left) kms_big_fb (linear-64bpp-rotate-180) [166.322630] Starting subtest: linear-64bpp-rotate-180 [168.023534] Subtest linear-64bpp-rotate-180: SUCCESS (1.701s) [168.201736] [044/156] (864s left) kms_cursor_crc (cursor-onscreen-max-size) [168.509142] Starting subtest: cursor-onscreen-max-size [168.515574] Subtest cursor-onscreen-max-size: SKIP (0.000s) [168.658128] [045/156] (864s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-move) [168.773159] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-move [168.777406] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s) [168.888710] [046/156] (864s left) kms_getfb (getfb-reject-nv12) [168.986968] Starting subtest: getfb-reject-nv12 [168.989176] Subtest getfb-reject-nv12: SUCCESS (0.001s) [169.079685] [047/156] (864s left) syncobj_timeline (wait-all-for-submit-snapshot) [169.140137] Starting subtest: wait-all-for-submit-snapshot [169.221601] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s) [169.304486] [048/156] (864s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled) [169.351351] Starting subtest: multi-wait-for-submit-unsubmitted-signaled [169.353193] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s) [169.439421] [049/156] (863s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf) [169.528553] Starting subtest: pr-overlay-plane-update-continuous-sf [169.531843] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s) [169.598346] [050/156] (863s left) gem_exec_whisper (basic-queues) [169.648584] Starting subtest: basic-queues [177.293752] Subtest basic-queues: SUCCESS (7.640s) [177.476816] [051/156] (855s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt) [177.574628] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt [177.578948] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [177.666153] [052/156] (855s left) i915_pm_rps (reset) [177.716789] Starting subtest: reset [186.168816] Subtest reset: SUCCESS (8.447s) [186.280170] [053/156] (847s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-wc) [186.382703] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-wc [186.387068] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [186.495277] [054/156] (847s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-move) [186.607141] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-move [186.611174] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [186.717316] [055/156] (846s left) syncobj_wait (wait-any-snapshot) [186.778556] Starting subtest: wait-any-snapshot [186.858697] Subtest wait-any-snapshot: SUCCESS (0.081s) [186.937375] [056/156] (846s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt) [187.025564] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt [193.840921] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.819s) [194.001959] [057/156] (839s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render) [194.105726] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render [194.109778] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s) [194.197469] [058/156] (839s left) kms_cursor_crc (cursor-random-32x10) [194.354748] Starting subtest: cursor-random-32x10 [194.403033] Subtest cursor-random-32x10: SKIP (0.043s) [194.706219] [059/156] (839s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-cpu) [194.808668] Starting subtest: hdr-rgb101010-draw-mmap-cpu [194.812982] Subtest hdr-rgb101010-draw-mmap-cpu: SKIP (0.000s) [194.907586] [060/156] (838s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite) [194.996285] Starting subtest: fbcpsr-rgb101010-draw-pwrite [199.998501] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (5.005s) [200.102550] [061/156] (833s left) kms_flip (flip-vs-absolute-wf_vblank) [200.188814] Starting subtest: flip-vs-absolute-wf_vblank [200.191301] Starting dynamic subtest: A-eDP1 [209.131665] Dynamic subtest A-eDP1: SUCCESS (8.942s) [209.145787] Starting dynamic subtest: B-eDP1 [218.034375] Dynamic subtest B-eDP1: SUCCESS (8.900s) [218.043753] Starting dynamic subtest: C-eDP1 [226.970451] Dynamic subtest C-eDP1: SUCCESS (8.888s) [226.980554] Starting dynamic subtest: D-eDP1 [235.877561] Dynamic subtest D-eDP1: SUCCESS (8.902s) [235.880082] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.642s) [237.013634] [062/156] (796s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-onoff) [237.110837] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-onoff [237.115279] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [237.198987] [063/156] (796s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-msflip-blt) [237.302592] Starting subtest: hdr-2p-primscrn-indfb-msflip-blt [237.306885] Subtest hdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s) [237.398716] [064/156] (796s left) gem_basic (create-close) [237.452706] Starting subtest: create-close [237.456782] Subtest create-close: SUCCESS (0.000s) [237.548512] [065/156] (796s left) gem_partial_pwrite_pread (write-uncached) [237.598988] Subtest write-uncached: SKIP (0.000s) [237.670632] [066/156] (796s left) kms_psr (fbc-psr-basic) [237.768283] Starting subtest: fbc-psr-basic [237.770427] Starting dynamic subtest: eDP-1 [237.774403] Dynamic subtest eDP-1: SKIP (0.000s) [237.778329] Subtest fbc-psr-basic: SKIP (0.001s) [237.840597] [067/156] (796s left) gem_ctx_persistence (userptr) [237.899925] Starting subtest: userptr [238.097042] Subtest userptr: SUCCESS (0.197s) [238.185838] [068/156] (795s left) gem_render_copy (y-tiled-ccs-to-y-tiled) [238.242745] Starting subtest: y-tiled-ccs-to-y-tiled [238.246681] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s) [238.447453] [069/156] (795s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant) [238.531528] Starting subtest: coverage-vs-premult-vs-constant [238.814897] Starting dynamic subtest: pipe-A-eDP-1 [239.390206] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.575s) [239.400824] Starting dynamic subtest: pipe-D-eDP-1 [240.700280] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.307s) [240.703021] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.170s) [241.516344] [070/156] (792s left) kms_flip (flip-vs-rmfb-interruptible) [241.610405] Starting subtest: flip-vs-rmfb-interruptible [241.612820] Starting dynamic subtest: A-eDP1 [250.280475] Dynamic subtest A-eDP1: SUCCESS (8.670s) [250.289871] Starting dynamic subtest: B-eDP1 [258.967958] Dynamic subtest B-eDP1: SUCCESS (8.686s) [258.978817] Starting dynamic subtest: C-eDP1 [267.558534] Dynamic subtest C-eDP1: SUCCESS (8.589s) [267.568486] Starting dynamic subtest: D-eDP1 [276.165577] Dynamic subtest D-eDP1: SUCCESS (8.605s) [276.170264] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.561s) [277.415682] [071/156] (756s left) gem_ctx_param (invalid-param-set) [277.474986] Starting subtest: invalid-param-set [277.477358] Subtest invalid-param-set: SUCCESS (0.000s) [277.547791] [072/156] (756s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [277.648218] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [277.652684] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [277.734835] [073/156] (756s left) gen9_exec_parse (batch-invalid-length) [277.784871] Subtest batch-invalid-length: SKIP (0.000s) [277.861587] [074/156] (756s left) dumb_buffer (invalid-bpp) [277.924076] Starting subtest: invalid-bpp [277.926299] Subtest invalid-bpp: SUCCESS (0.000s) [278.011548] [075/156] (756s left) kms_content_protection (dp-mst-type-1-suspend-resume) [278.150944] Starting subtest: dp-mst-type-1-suspend-resume [278.155597] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s) [278.288055] [076/156] (756s left) perf_pmu (interrupts) [278.325997] Starting subtest: interrupts [279.494982] Subtest interrupts: SUCCESS (1.169s) [279.598552] [FACT perf_pmu (interrupts)] new: kernel.kmod_is_loaded.vgem: true [279.601950] [077/156] (754s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-render) [279.705939] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-render [279.710209] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s) [279.797508] [078/156] (754s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-pgflip-blt) [279.896875] Starting subtest: hdr-2p-scndscrn-shrfb-pgflip-blt [279.900963] Subtest hdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s) [279.983363] [079/156] (754s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode) [280.360526] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s) [281.193630] [080/156] (753s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels) [281.292377] Starting subtest: plane-all-modeset-transition-internal-panels [281.294358] Starting dynamic subtest: pipe-A-eDP-1 [282.626694] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.334s) [282.925209] Starting dynamic subtest: pipe-B-eDP-1 [283.924921] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.009s) [284.193680] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.901s) [285.021648] [081/156] (749s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt) [285.125578] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt [285.129688] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [285.219779] [082/156] (749s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted) [285.267322] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted [285.374263] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s) [285.452225] [083/156] (749s left) kms_vblank (ts-continuation-modeset) [285.540479] Starting subtest: ts-continuation-modeset [285.542440] Starting dynamic subtest: pipe-A-eDP-1 [287.041524] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.502s) [287.058782] Starting dynamic subtest: pipe-D-eDP-1 [289.235786] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.189s) [289.238167] Subtest ts-continuation-modeset: SUCCESS (3.695s) [290.115826] [084/156] (744s left) gem_pread (snoop) [290.176980] Subtest snoop: SKIP (0.000s) [290.235807] [085/156] (744s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite) [290.335893] Starting subtest: psr-rgb101010-draw-pwrite [297.997716] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.665s) [298.111487] [086/156] (736s left) gem_ctx_param (non-root-set) [298.172764] Starting subtest: non-root-set [298.193905] Subtest non-root-set: SUCCESS (0.021s) [298.299395] [087/156] (736s left) kms_sequence (get-forked-busy) [298.384189] Starting subtest: get-forked-busy [298.386338] Starting dynamic subtest: pipe-A-eDP-1 [300.333497] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.950s) [300.344665] Starting dynamic subtest: pipe-B-eDP-1 [302.976069] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.639s) [302.989034] Starting dynamic subtest: pipe-C-eDP-1 [305.561449] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.584s) [305.576220] Starting dynamic subtest: pipe-D-eDP-1 [308.150403] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.587s) [308.152761] Subtest get-forked-busy: SUCCESS (9.765s) [309.009354] [088/156] (725s left) kms_pm_backlight (fade) [309.097676] Starting subtest: fade [309.148966] Starting dynamic subtest: eDP-1 [312.149330] Dynamic subtest eDP-1: SUCCESS (3.002s) [312.151636] Subtest fade: SUCCESS (3.053s) [313.004194] [089/156] (721s left) syncobj_timeline (invalid-signal-illegal-handle) [313.065323] Starting subtest: invalid-signal-illegal-handle [313.067866] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [313.159395] [090/156] (721s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing) [313.260150] Starting subtest: plane-all-transition-nonblocking-fencing [313.262462] Starting dynamic subtest: pipe-A-eDP-1 [315.754053] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s) [316.078936] Starting dynamic subtest: pipe-B-eDP-1 [319.312186] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.246s) [319.613573] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.352s) [320.412843] [091/156] (714s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-move) [320.515954] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-move [320.523364] Subtest fbchdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [320.599982] [092/156] (714s left) syncobj_wait (single-wait-all-for-submit-unsubmitted) [320.642497] Starting subtest: single-wait-all-for-submit-unsubmitted [320.749842] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.107s) [320.830258] [093/156] (714s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc) [320.920335] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc [320.922640] Starting dynamic subtest: pipe-A-eDP-1 [320.944078] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [320.955695] Starting dynamic subtest: pipe-B-eDP-1 [320.974766] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [320.984163] Starting dynamic subtest: pipe-C-eDP-1 [321.003166] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [321.012213] Starting dynamic subtest: pipe-D-eDP-1 [321.031331] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [321.033216] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s) [323.220967] [094/156] (711s left) kms_lease (lease-invalid-connector) [323.315261] Starting subtest: lease-invalid-connector [323.317622] Starting dynamic subtest: pipe-A-eDP-1 [323.319802] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [323.329151] Starting dynamic subtest: pipe-B-eDP-1 [323.331527] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [323.340887] Starting dynamic subtest: pipe-C-eDP-1 [323.343179] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [323.352078] Starting dynamic subtest: pipe-D-eDP-1 [323.354065] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [323.356041] Subtest lease-invalid-connector: SUCCESS (0.004s) [323.393326] [095/156] (711s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-wc) [323.489807] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-wc [323.494195] Subtest fbcpsrhdr-rgb101010-draw-mmap-wc: SKIP (0.000s) [323.599307] [096/156] (711s left) gem_exec_params (rel-constants-invalid-rel-gen5) [323.662649] Starting subtest: rel-constants-invalid-rel-gen5 [323.664629] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s) [323.763584] [097/156] (711s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling) [323.858113] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling [323.862767] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s) [323.942139] [098/156] (711s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-gtt) [324.040999] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-gtt [324.045240] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [324.140586] [099/156] (711s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted) [324.200295] Starting subtest: multi-wait-all-for-submit-unsubmitted [324.300043] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.101s) [324.378333] [100/156] (711s left) gem_pxp (verify-pxp-execution-after-suspend-resume) [324.505050] Starting subtest: verify-pxp-execution-after-suspend-resume [331.631330] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.126s) [331.760518] [101/156] (703s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [331.877120] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu [331.881486] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [331.977502] [102/156] (703s left) perf (invalid-create-userspace-config) [332.105872] Starting subtest: invalid-create-userspace-config [332.108130] Subtest invalid-create-userspace-config: SUCCESS (0.001s) [332.150861] [103/156] (703s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-render) [332.240996] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-render [332.245509] Subtest fbchdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s) [332.345807] [104/156] (703s left) kms_psr (psr-primary-mmap-gtt) [332.440345] Starting subtest: psr-primary-mmap-gtt [332.443680] Starting dynamic subtest: eDP-1 [333.884853] Dynamic subtest eDP-1: SKIP (1.440s) [333.890143] Subtest psr-primary-mmap-gtt: SKIP (1.443s) [334.045407] [105/156] (701s left) kms_getfb (getfb-handle-not-fb) [334.140888] Starting subtest: getfb-handle-not-fb [334.143067] Subtest getfb-handle-not-fb: SUCCESS (0.000s) [334.237253] [106/156] (701s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs) [334.332342] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs [334.334441] Starting dynamic subtest: pipe-A-eDP-1 [334.354752] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.023s) [334.367297] Starting dynamic subtest: pipe-B-eDP-1 [334.387763] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.024s) [334.398383] Starting dynamic subtest: pipe-C-eDP-1 [334.418240] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.026s) [334.430201] Starting dynamic subtest: pipe-D-eDP-1 [334.450658] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s) [334.452973] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.100s) [336.708486] [107/156] (699s left) drm_read (empty-block) [336.846475] Starting subtest: empty-block [337.847058] Subtest empty-block: SUCCESS (1.000s) [337.994964] [108/156] (697s left) gem_media_vme [338.123978] [109/156] (697s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt) [338.231592] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt [338.235810] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [338.340520] [110/156] (697s left) kms_pm_rpm (dpms-lpsp) [340.480582] Starting subtest: dpms-lpsp [340.790350] Subtest dpms-lpsp: SUCCESS (0.312s) [340.880897] [111/156] (695s left) kms_color (legacy-gamma) [340.980060] Starting subtest: legacy-gamma [340.982294] Starting dynamic subtest: pipe-A-eDP-1 [341.700855] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.719s) [341.711988] Starting dynamic subtest: pipe-B-eDP-1 [343.074778] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.376s) [343.086253] Starting dynamic subtest: pipe-C-eDP-1 [344.421268] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.344s) [344.432276] Starting dynamic subtest: pipe-D-eDP-1 [345.845511] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.422s) [345.847425] Subtest legacy-gamma: SUCCESS (4.865s) [346.703986] [112/156] (689s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [346.803043] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [346.807647] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [346.907424] [113/156] (689s left) prime_vgem (wait) [346.977503] Starting subtest: wait [346.979983] Starting dynamic subtest: rcs0 [347.020735] Dynamic subtest rcs0: SUCCESS (0.043s) [347.031129] Starting dynamic subtest: bcs0 [347.080727] Dynamic subtest bcs0: SUCCESS (0.059s) [347.090846] Starting dynamic subtest: vcs0 [347.126796] Dynamic subtest vcs0: SUCCESS (0.045s) [347.136826] Starting dynamic subtest: vcs1 [347.173061] Dynamic subtest vcs1: SUCCESS (0.045s) [347.183127] Starting dynamic subtest: vecs0 [347.217095] Dynamic subtest vecs0: SUCCESS (0.043s) [347.227328] Starting dynamic subtest: ccs0 [347.265975] Dynamic subtest ccs0: SUCCESS (0.048s) [347.268219] Subtest wait: SUCCESS (0.289s) [347.385269] [114/156] (688s left) gen9_exec_parse (unaligned-jump) [347.438061] Subtest unaligned-jump: SKIP (0.000s) [347.514472] [115/156] (688s left) syncobj_wait (single-wait-for-submit-submitted) [347.576745] Starting subtest: single-wait-for-submit-submitted [347.685963] Subtest single-wait-for-submit-submitted: SUCCESS (0.108s) [347.778670] [116/156] (688s left) gem_vm_create (invalid-destroy) [347.837557] Starting subtest: invalid-destroy [347.839767] Subtest invalid-destroy: SUCCESS (0.002s) [347.944096] [117/156] (688s left) gem_lmem_swapping (massive) [347.987685] Subtest massive: SKIP (0.000s) [348.036166] [118/156] (688s left) kms_cursor_crc (cursor-size-change) [348.127100] Starting subtest: cursor-size-change [348.128930] Starting dynamic subtest: pipe-A-eDP-1 [348.925655] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.797s) [348.936910] Starting dynamic subtest: pipe-D-eDP-1 [350.453079] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.526s) [350.455279] Subtest cursor-size-change: SUCCESS (2.326s) [351.304582] [119/156] (684s left) gem_exec_fence (nb-await) [351.487537] Starting subtest: nb-await [351.490062] Starting dynamic subtest: rcs0 [351.555244] Dynamic subtest rcs0: SUCCESS (0.067s) [351.570124] Starting dynamic subtest: bcs0 [351.613443] Dynamic subtest bcs0: SUCCESS (0.056s) [351.626176] Starting dynamic subtest: vcs0 [351.673511] Dynamic subtest vcs0: SUCCESS (0.057s) [351.687072] Starting dynamic subtest: vcs1 [351.733583] Dynamic subtest vcs1: SUCCESS (0.058s) [351.745909] Starting dynamic subtest: vecs0 [351.794606] Dynamic subtest vecs0: SUCCESS (0.059s) [351.813596] Starting dynamic subtest: ccs0 [351.851534] Dynamic subtest ccs0: SUCCESS (0.056s) [351.854086] Subtest nb-await: SUCCESS (0.365s) [353.329243] [120/156] (682s left) kms_flip (flip-vs-wf_vblank-interruptible) [353.432430] Starting subtest: flip-vs-wf_vblank-interruptible [353.434740] Starting dynamic subtest: A-eDP1 [355.317143] Dynamic subtest A-eDP1: SUCCESS (1.883s) [355.328947] Starting dynamic subtest: B-eDP1 [357.221949] Dynamic subtest B-eDP1: SUCCESS (1.903s) [357.233159] Starting dynamic subtest: C-eDP1 [359.110444] Dynamic subtest C-eDP1: SUCCESS (1.885s) [359.121723] Starting dynamic subtest: D-eDP1 [361.013017] Dynamic subtest D-eDP1: SUCCESS (1.900s) [361.018215] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.584s) [362.203892] [121/156] (674s left) sysfs_heartbeat_interval (invalid) [362.266682] Starting subtest: invalid [362.269280] Starting dynamic subtest: vecs0 [362.271958] Dynamic subtest vecs0: SUCCESS (0.000s) [362.284341] Starting dynamic subtest: ccs0 [362.286651] Dynamic subtest ccs0: SUCCESS (0.000s) [362.297141] Starting dynamic subtest: vcs0 [362.299480] Dynamic subtest vcs0: SUCCESS (0.000s) [362.310117] Starting dynamic subtest: bcs0 [362.312970] Dynamic subtest bcs0: SUCCESS (0.000s) [362.327297] Starting dynamic subtest: rcs0 [362.329727] Dynamic subtest rcs0: SUCCESS (0.000s) [362.339517] Starting dynamic subtest: vcs1 [362.341805] Dynamic subtest vcs1: SUCCESS (0.000s) [362.344097] Subtest invalid: SUCCESS (0.005s) [362.386746] [122/156] (674s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt) [362.478753] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt [362.482881] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s) [362.578983] [123/156] (673s left) i915_hangman (gt-engine-error) [362.649156] Starting subtest: gt-engine-error [362.651389] Starting dynamic subtest: rcs0 [364.249644] Dynamic subtest rcs0: SUCCESS (1.600s) [364.261741] Starting dynamic subtest: bcs0 [366.232963] Dynamic subtest bcs0: SUCCESS (1.981s) [366.246363] Starting dynamic subtest: vcs0 [368.219719] Dynamic subtest vcs0: SUCCESS (1.984s) [368.235448] Starting dynamic subtest: vcs1 [370.264655] Dynamic subtest vcs1: SUCCESS (2.042s) [370.277984] Starting dynamic subtest: vecs0 [372.248409] Dynamic subtest vecs0: SUCCESS (1.981s) [372.261249] Starting dynamic subtest: ccs0 [374.233960] Dynamic subtest ccs0: SUCCESS (1.983s) [374.236700] Subtest gt-engine-error: SUCCESS (11.587s) [374.330990] [124/156] (662s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite) [374.454090] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite [380.748429] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.300s) [381.922735] [125/156] (654s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping) [382.008377] Starting subtest: pixel-format-x-tiled-modifier-source-clamping [382.033895] Starting dynamic subtest: pipe-A-plane-0 [382.411091] Dynamic subtest pipe-A-plane-0: SUCCESS (0.378s) [382.422987] Starting dynamic subtest: pipe-A-plane-3 [382.733623] Dynamic subtest pipe-A-plane-3: SUCCESS (0.321s) [383.859072] Starting dynamic subtest: pipe-B-plane-0 [384.238442] Dynamic subtest pipe-B-plane-0: SUCCESS (0.389s) [384.253699] Starting dynamic subtest: pipe-B-plane-3 [384.538343] Dynamic subtest pipe-B-plane-3: SUCCESS (0.297s) [384.850702] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (2.843s) [385.706573] [126/156] (650s left) syncobj_wait (wait-all-complex) [385.758681] Starting subtest: wait-all-complex [385.973723] Subtest wait-all-complex: SUCCESS (0.213s) [386.063415] [127/156] (650s left) kms_flip (2x-dpms-vs-vblank-race-interruptible) [386.156623] Starting subtest: 2x-dpms-vs-vblank-race-interruptible [386.168447] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.010s) [386.283480] [128/156] (650s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt) [386.390686] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt [386.394799] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [386.508082] [129/156] (650s left) perf_pmu (semaphore-wait-idle) [386.542915] Starting subtest: semaphore-wait-idle [386.545224] Starting dynamic subtest: rcs0 [387.056933] Dynamic subtest rcs0: SUCCESS (0.513s) [387.069636] Starting dynamic subtest: bcs0 [387.572276] Dynamic subtest bcs0: SUCCESS (0.511s) [387.585055] Starting dynamic subtest: vcs0 [388.086607] Dynamic subtest vcs0: SUCCESS (0.512s) [388.100568] Starting dynamic subtest: vcs1 [388.602760] Dynamic subtest vcs1: SUCCESS (0.512s) [388.616622] Starting dynamic subtest: vecs0 [389.116774] Dynamic subtest vecs0: SUCCESS (0.510s) [389.130366] Starting dynamic subtest: ccs0 [389.634441] Dynamic subtest ccs0: SUCCESS (0.514s) [389.637309] Subtest semaphore-wait-idle: SUCCESS (3.093s) [389.760598] [130/156] (646s left) kms_invalid_mode (bad-vsync-start) [389.862164] Starting subtest: bad-vsync-start [389.864529] Starting dynamic subtest: pipe-A-eDP-1 [390.150554] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s) [390.166117] Starting dynamic subtest: pipe-B-eDP-1 [390.167972] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [390.180022] Starting dynamic subtest: pipe-C-eDP-1 [390.182631] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [390.199334] Starting dynamic subtest: pipe-D-eDP-1 [390.201824] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [390.203907] Subtest bad-vsync-start: SUCCESS (0.292s) [391.022227] [131/156] (645s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip) [391.127842] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip [391.132297] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s) [391.249964] [132/156] (645s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt) [391.364357] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt [391.368760] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s) [391.466193] [133/156] (645s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [391.561896] Starting subtest: addfb25-yf-tiled-legacy [391.563916] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [391.659865] [134/156] (645s left) gem_ccs (ctrl-surf-copy) [391.712357] Starting subtest: ctrl-surf-copy [391.716676] Subtest ctrl-surf-copy: SKIP (0.000s) [391.822074] [135/156] (645s left) perf_pmu (busy-idle-no-semaphores) [391.862790] Starting subtest: busy-idle-no-semaphores [391.864758] Starting dynamic subtest: rcs0 [392.525092] Dynamic subtest rcs0: SUCCESS (0.662s) [392.538662] Starting dynamic subtest: bcs0 [393.179446] Dynamic subtest bcs0: SUCCESS (0.651s) [393.193349] Starting dynamic subtest: vcs0 [393.832498] Dynamic subtest vcs0: SUCCESS (0.650s) [393.845342] Starting dynamic subtest: vcs1 [395.875072] Dynamic subtest vcs1: SUCCESS (2.040s) [395.887814] Starting dynamic subtest: vecs0 [397.925452] Dynamic subtest vecs0: SUCCESS (2.048s) [397.939115] Starting dynamic subtest: ccs0 [399.907576] Dynamic subtest ccs0: SUCCESS (1.979s) [399.910396] Subtest busy-idle-no-semaphores: SUCCESS (8.046s) [400.023933] [136/156] (636s left) gem_exec_schedule (pi-common) [400.210558] Starting subtest: pi-common [400.212498] Starting dynamic subtest: rcs0 [400.242105] Dynamic subtest rcs0: SUCCESS (0.034s) [400.252322] Starting dynamic subtest: bcs0 [400.277083] Dynamic subtest bcs0: SUCCESS (0.034s) [400.287500] Starting dynamic subtest: vcs0 [400.310508] Dynamic subtest vcs0: SUCCESS (0.033s) [400.324276] Starting dynamic subtest: vcs1 [400.341388] Dynamic subtest vcs1: SUCCESS (0.030s) [400.352164] Starting dynamic subtest: vecs0 [400.374976] Dynamic subtest vecs0: SUCCESS (0.033s) [400.390347] Starting dynamic subtest: ccs0 [400.406215] Dynamic subtest ccs0: SUCCESS (0.030s) [400.408503] Subtest pi-common: SUCCESS (0.199s) [400.640696] [137/156] (636s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite) [400.747520] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite [407.882284] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.141s) [409.004865] [138/156] (628s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt) [409.114990] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt [416.426433] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.317s) [416.564771] [139/156] (620s left) kms_prime (basic-crc-vgem) [416.648301] Starting subtest: basic-crc-vgem [416.650121] Starting dynamic subtest: second-to-first [417.655363] Dynamic subtest second-to-first: SUCCESS (1.011s) [417.657403] Subtest basic-crc-vgem: SUCCESS (1.011s) [417.765192] [140/156] (619s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c) [417.827612] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s) [417.893162] [141/156] (619s left) api_intel_bb (purge-bb) [417.955181] Starting subtest: purge-bb [417.957093] Subtest purge-bb: SUCCESS (0.003s) [418.083181] [142/156] (619s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [418.185514] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu [418.189196] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [418.277120] [143/156] (619s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-cpu) [418.380978] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-cpu [418.384397] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [418.476983] [144/156] (618s left) gem_mmap_wc (close) [418.536102] Subtest close: SKIP (0.000s) [418.598712] [145/156] (618s left) gem_exec_schedule (u-independent) [418.670387] Starting subtest: u-independent [418.672093] Starting dynamic subtest: rcs0 [420.282385] Dynamic subtest rcs0: SUCCESS (1.618s) [420.295952] Starting dynamic subtest: vcs0 [422.271911] Dynamic subtest vcs0: SUCCESS (1.987s) [422.286458] Starting dynamic subtest: vcs1 [424.329062] Dynamic subtest vcs1: SUCCESS (2.055s) [424.343966] Starting dynamic subtest: vecs0 [426.313474] Dynamic subtest vecs0: SUCCESS (1.982s) [426.327416] Starting dynamic subtest: ccs0 [428.281878] Dynamic subtest ccs0: SUCCESS (1.966s) [428.285037] Subtest u-independent: SUCCESS (9.620s) [428.586116] [146/156] (608s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [428.686883] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip [429.500754] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.814s) [429.688543] [147/156] (607s left) kms_prop_blob (invalid-set-prop) [429.745707] Starting subtest: invalid-set-prop [429.748070] Subtest invalid-set-prop: SUCCESS (0.000s) [429.848900] [148/156] (607s left) gem_exec_flush (basic-wb-ro-before-default) [429.903573] Starting subtest: basic-wb-ro-before-default [435.385147] Subtest basic-wb-ro-before-default: SUCCESS (5.450s) [435.550550] [149/156] (601s left) gem_close_race (multigpu-basic-process) [435.611541] Starting subtest: multigpu-basic-process [435.615747] Subtest multigpu-basic-process: SKIP (0.000s) [435.849795] [150/156] (601s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0) [435.954574] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0 [436.971843] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.016s) [437.095566] [151/156] (600s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-blt) [437.187729] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-blt [437.190953] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s) [437.308327] [152/156] (600s left) gem_exec_capture (capture) [437.365212] Starting subtest: capture [437.367356] Starting dynamic subtest: rcs0-smem [438.202640] Dynamic subtest rcs0-smem: SUCCESS (0.833s) [438.215572] Starting dynamic subtest: bcs0-smem [439.025169] Dynamic subtest bcs0-smem: SUCCESS (0.822s) [439.037572] Starting dynamic subtest: vcs0-smem [439.854982] Dynamic subtest vcs0-smem: SUCCESS (0.828s) [439.868063] Starting dynamic subtest: vcs1-smem [441.167995] Dynamic subtest vcs1-smem: SUCCESS (1.316s) [441.181243] Starting dynamic subtest: vecs0-smem [442.059087] Dynamic subtest vecs0-smem: FAIL (0.824s) [442.071718] Starting dynamic subtest: ccs0-smem [443.312769] Dynamic subtest ccs0-smem: SUCCESS (1.319s) [443.315763] Subtest capture: FAIL (5.947s) [443.437766] [153/156] (594s left) kms_plane_scaling (planes-downscale-factor-0-5) [443.536080] Starting subtest: planes-downscale-factor-0-5 [443.538397] Starting dynamic subtest: pipe-A [443.557291] Dynamic subtest pipe-A: SKIP (0.018s) [443.568632] Starting dynamic subtest: pipe-B [443.592071] Dynamic subtest pipe-B: SKIP (0.032s) [443.603096] Starting dynamic subtest: pipe-C [443.624368] Dynamic subtest pipe-C: SKIP (0.032s) [443.637143] Starting dynamic subtest: pipe-D [443.655073] Dynamic subtest pipe-D: SKIP (0.031s) [443.659133] Subtest planes-downscale-factor-0-5: SKIP (0.116s) [443.782446] [154/156] (593s left) kms_psr (fbc-pr-primary-page-flip) [443.873114] Starting subtest: fbc-pr-primary-page-flip [443.877489] Subtest fbc-pr-primary-page-flip: SKIP (0.000s) [443.947729] [155/156] (593s left) kms_flip (2x-absolute-wf_vblank-interruptible) [444.038056] Starting subtest: 2x-absolute-wf_vblank-interruptible [444.048882] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.010s) [444.137628] [156/156] (593s left) kms_chamelium_edid (dp-edid-read) [444.514419] Subtest dp-edid-read: SKIP (0.000s) [445.371957] Closing watchdogs results: parsing output: 0/ for test: syncobj_timeline results: parsing output: 1/ for test: kms_plane results: parsing output: 2/ for test: gem_eio results: parsing output: 3/ for test: kms_draw_crc results: parsing output: 4/ for test: gem_ctx_isolation results: parsing output: 5/ for test: gem_spin_batch results: parsing output: 6/ for test: kms_frontbuffer_tracking results: parsing output: 7/ for test: api_intel_allocator results: parsing output: 8/ for test: gem_exec_balancer results: parsing output: 9/ for test: kms_atomic_transition results: parsing output: 10/ for test: gem_mmap_offset results: parsing output: 11/ for test: kms_big_fb results: parsing output: 12/ for test: kms_psr results: parsing output: 13/ for test: gem_exec_reloc results: parsing output: 14/ for test: kms_frontbuffer_tracking results: parsing output: 15/ for test: kms_rotation_crc results: parsing output: 16/ for test: i915_pm_rps results: parsing output: 17/ for test: kms_cursor_legacy results: parsing output: 18/ for test: kms_frontbuffer_tracking results: parsing output: 19/ for test: kms_frontbuffer_tracking results: parsing output: 20/ for test: kms_psr results: parsing output: 21/ for test: kms_frontbuffer_tracking results: parsing output: 22/ for test: kms_cursor_crc results: parsing output: 23/ for test: kms_frontbuffer_tracking results: parsing output: 24/ for test: kms_frontbuffer_tracking results: parsing output: 25/ for test: gem_mmap_wc results: parsing output: 26/ for test: syncobj_wait results: parsing output: 27/ for test: kms_big_fb results: parsing output: 28/ for test: kms_ccs results: parsing output: 29/ for test: kms_frontbuffer_tracking results: parsing output: 30/ for test: kms_hdmi_inject results: parsing output: 31/ for test: kms_psr results: parsing output: 32/ for test: gem_exec_schedule results: parsing output: 33/ for test: kms_ccs results: parsing output: 34/ for test: gem_render_copy results: parsing output: 35/ for test: kms_flip_scaled_crc results: parsing output: 36/ for test: kms_frontbuffer_tracking results: parsing output: 37/ for test: kms_frontbuffer_tracking results: parsing output: 38/ for test: kms_cdclk results: parsing output: 39/ for test: gem_mmap results: parsing output: 40/ for test: kms_flip results: parsing output: 41/ for test: kms_frontbuffer_tracking results: parsing output: 42/ for test: kms_big_fb results: parsing output: 43/ for test: kms_cursor_crc results: parsing output: 44/ for test: kms_frontbuffer_tracking results: parsing output: 45/ for test: kms_getfb results: parsing output: 46/ for test: syncobj_timeline results: parsing output: 47/ for test: syncobj_timeline results: parsing output: 48/ for test: kms_psr2_sf results: parsing output: 49/ for test: gem_exec_whisper results: parsing output: 50/ for test: kms_frontbuffer_tracking results: parsing output: 51/ for test: i915_pm_rps results: parsing output: 52/ for test: kms_frontbuffer_tracking results: parsing output: 53/ for test: kms_frontbuffer_tracking results: parsing output: 54/ for test: syncobj_wait results: parsing output: 55/ for test: kms_frontbuffer_tracking results: parsing output: 56/ for test: kms_frontbuffer_tracking results: parsing output: 57/ for test: kms_cursor_crc results: parsing output: 58/ for test: kms_frontbuffer_tracking results: parsing output: 59/ for test: kms_frontbuffer_tracking results: parsing output: 60/ for test: kms_flip results: parsing output: 61/ for test: kms_frontbuffer_tracking results: parsing output: 62/ for test: kms_frontbuffer_tracking results: parsing output: 63/ for test: gem_basic results: parsing output: 64/ for test: gem_partial_pwrite_pread results: parsing output: 65/ for test: kms_psr results: parsing output: 66/ for test: gem_ctx_persistence results: parsing output: 67/ for test: gem_render_copy results: parsing output: 68/ for test: kms_plane_alpha_blend results: parsing output: 69/ for test: kms_flip results: parsing output: 70/ for test: gem_ctx_param results: parsing output: 71/ for test: kms_frontbuffer_tracking results: parsing output: 72/ for test: gen9_exec_parse results: parsing output: 73/ for test: dumb_buffer results: parsing output: 74/ for test: kms_content_protection results: parsing output: 75/ for test: perf_pmu results: parsing output: 76/ for test: kms_frontbuffer_tracking results: parsing output: 77/ for test: kms_frontbuffer_tracking results: parsing output: 78/ for test: kms_chamelium_hpd results: parsing output: 79/ for test: kms_atomic_transition results: parsing output: 80/ for test: kms_frontbuffer_tracking results: parsing output: 81/ for test: syncobj_timeline results: parsing output: 82/ for test: kms_vblank results: parsing output: 83/ for test: gem_pread results: parsing output: 84/ for test: kms_frontbuffer_tracking results: parsing output: 85/ for test: gem_ctx_param results: parsing output: 86/ for test: kms_sequence results: parsing output: 87/ for test: kms_pm_backlight results: parsing output: 88/ for test: syncobj_timeline results: parsing output: 89/ for test: kms_atomic_transition results: parsing output: 90/ for test: kms_frontbuffer_tracking results: parsing output: 91/ for test: syncobj_wait results: parsing output: 92/ for test: kms_ccs results: parsing output: 93/ for test: kms_lease results: parsing output: 94/ for test: kms_frontbuffer_tracking results: parsing output: 95/ for test: gem_exec_params results: parsing output: 96/ for test: kms_flip_scaled_crc results: parsing output: 97/ for test: kms_frontbuffer_tracking results: parsing output: 98/ for test: syncobj_wait results: parsing output: 99/ for test: gem_pxp results: parsing output: 100/ for test: kms_frontbuffer_tracking results: parsing output: 101/ for test: perf results: parsing output: 102/ for test: kms_frontbuffer_tracking results: parsing output: 103/ for test: kms_psr results: parsing output: 104/ for test: kms_getfb results: parsing output: 105/ for test: kms_ccs results: parsing output: 106/ for test: drm_read results: parsing output: 107/ for test: gem_media_vme results: parsing output: 108/ for test: kms_frontbuffer_tracking results: parsing output: 109/ for test: kms_pm_rpm results: parsing output: 110/ for test: kms_color results: parsing output: 111/ for test: kms_big_fb results: parsing output: 112/ for test: prime_vgem results: parsing output: 113/ for test: gen9_exec_parse results: parsing output: 114/ for test: syncobj_wait results: parsing output: 115/ for test: gem_vm_create results: parsing output: 116/ for test: gem_lmem_swapping results: parsing output: 117/ for test: kms_cursor_crc results: parsing output: 118/ for test: gem_exec_fence results: parsing output: 119/ for test: kms_flip results: parsing output: 120/ for test: sysfs_heartbeat_interval results: parsing output: 121/ for test: kms_frontbuffer_tracking results: parsing output: 122/ for test: i915_hangman results: parsing output: 123/ for test: kms_frontbuffer_tracking results: parsing output: 124/ for test: kms_plane results: parsing output: 125/ for test: syncobj_wait results: parsing output: 126/ for test: kms_flip results: parsing output: 127/ for test: kms_frontbuffer_tracking results: parsing output: 128/ for test: perf_pmu results: parsing output: 129/ for test: kms_invalid_mode results: parsing output: 130/ for test: kms_big_fb results: parsing output: 131/ for test: kms_frontbuffer_tracking results: parsing output: 132/ for test: kms_addfb_basic results: parsing output: 133/ for test: gem_ccs results: parsing output: 134/ for test: perf_pmu results: parsing output: 135/ for test: gem_exec_schedule results: parsing output: 136/ for test: kms_frontbuffer_tracking results: parsing output: 137/ for test: kms_frontbuffer_tracking results: parsing output: 138/ for test: kms_prime results: parsing output: 139/ for test: kms_pipe_b_c_ivb results: parsing output: 140/ for test: api_intel_bb results: parsing output: 141/ for test: kms_frontbuffer_tracking results: parsing output: 142/ for test: kms_frontbuffer_tracking results: parsing output: 143/ for test: gem_mmap_wc results: parsing output: 144/ for test: gem_exec_schedule results: parsing output: 145/ for test: kms_big_fb results: parsing output: 146/ for test: kms_prop_blob results: parsing output: 147/ for test: gem_exec_flush results: parsing output: 148/ for test: gem_close_race results: parsing output: 149/ for test: kms_big_fb results: parsing output: 150/ for test: kms_frontbuffer_tracking results: parsing output: 151/ for test: gem_exec_capture results: parsing output: 152/ for test: kms_plane_scaling results: parsing output: 153/ for test: kms_psr results: parsing output: 154/ for test: kms_flip results: parsing output: 155/ for test: kms_chamelium_edid Done.