[58.830747] Initializing watchdogs
[58.830852]   /dev/watchdog0
[58.846514] [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]
[58.851088] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.853778] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.857204] [001/156] (960s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-fullscreen)
[59.001256] Starting subtest: fbchdr-2p-primscrn-spr-indfb-fullscreen
[59.005743] Subtest fbchdr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[59.106553] [002/156] (960s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled)
[59.177547] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[59.179912] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[59.288764] [003/156] (960s left) gem_set_tiling_vs_pwrite
[59.412671] [004/156] (960s left) gem_softpin (zero)
[59.467031] Starting subtest: zero
[59.474161] Subtest zero: SUCCESS (0.005s)
[59.618460] [005/156] (959s left) gem_exec_schedule (smoketest)
[59.794582] Starting subtest: smoketest
[59.796593] Starting dynamic subtest: rcs0
[65.446985] Dynamic subtest rcs0: SUCCESS (5.501s)
[65.456966] Starting dynamic subtest: bcs0
[70.947987] Dynamic subtest bcs0: SUCCESS (5.494s)
[70.957606] Starting dynamic subtest: vcs0
[76.445597] Dynamic subtest vcs0: SUCCESS (5.481s)
[76.455343] Starting dynamic subtest: vcs1
[81.937025] Dynamic subtest vcs1: SUCCESS (5.495s)
[81.946256] Starting dynamic subtest: vecs0
[87.425778] Dynamic subtest vecs0: SUCCESS (5.483s)
[87.436867] Starting dynamic subtest: ccs0
[92.919102] Dynamic subtest ccs0: SUCCESS (5.489s)
[92.921837] Subtest smoketest: SUCCESS (32.946s)
[93.000235] [006/156] (926s left) vgem_basic (debugfs)
[93.111695] Starting subtest: debugfs
[93.113799] Subtest debugfs: SUCCESS (0.000s)
[93.161825] [FACT vgem_basic (debugfs)] new: kernel.kmod_is_loaded.vgem: true
[93.165142] [007/156] (926s left) i915_pm_rps (fence-order)
[93.220658] Starting subtest: fence-order
[96.146075] Subtest fence-order: SUCCESS (2.922s)
[96.279839] [008/156] (923s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[96.609695] Starting subtest: cursor-rapid-movement-max-size
[96.616248] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[96.751141] [009/156] (922s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[96.808406] Starting subtest: etime-single-wait-available-unsubmitted
[96.914056] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.105s)
[96.992066] [010/156] (922s left) kms_vblank (query-forked)
[97.076058] Starting subtest: query-forked
[97.078094] Starting dynamic subtest: pipe-A-eDP-1
[98.925672] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.850s)
[98.940851] Starting dynamic subtest: pipe-D-eDP-1
[101.468082] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.539s)
[101.470475] Subtest query-forked: SUCCESS (4.392s)
[102.328724] [011/156] (917s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[102.435472] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[102.439897] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[102.521229] [012/156] (917s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[102.654090] Starting subtest: short-flip-after-cursor-atomic-transitions
[103.635891] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.980s)
[104.448908] [013/156] (915s left) kms_cursor_edge_walk (256x256-right-edge)
[104.545695] Starting subtest: 256x256-right-edge
[104.548320] Starting dynamic subtest: pipe-A-eDP-1
[108.022561] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.476s)
[108.030985] Starting dynamic subtest: pipe-D-eDP-1
[111.251146] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.228s)
[111.253292] Subtest 256x256-right-edge: SUCCESS (6.706s)
[112.059929] [014/156] (907s left) kms_flip (2x-flip-vs-panning-vs-hang)
[112.162268] Starting subtest: 2x-flip-vs-panning-vs-hang
[112.166306] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.002s)
[112.262893] [015/156] (907s left) gem_basic (multigpu-create-close)
[112.318368] Starting subtest: multigpu-create-close
[112.322634] Subtest multigpu-create-close: SKIP (0.000s)
[112.423748] [016/156] (907s left) syncobj_wait (wait-all-snapshot)
[112.482839] Starting subtest: wait-all-snapshot
[112.563739] Subtest wait-all-snapshot: SUCCESS (0.081s)
[112.649851] [017/156] (907s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[112.754580] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-cpu
[112.758765] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[112.866342] [018/156] (907s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[112.964724] Starting subtest: yf-tiled-32bpp-rotate-90
[112.969204] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[113.064596] [019/156] (907s left) kms_psr (fbc-psr2-cursor-blt)
[113.152078] Starting subtest: fbc-psr2-cursor-blt
[113.153595] Starting dynamic subtest: eDP-1
[113.156896] Dynamic subtest eDP-1: SKIP (0.000s)
[113.160286] Subtest fbc-psr2-cursor-blt: SKIP (0.000s)
[113.223225] [020/156] (906s left) gem_exec_schedule (independent)
[113.290963] Starting subtest: independent
[113.292953] Starting dynamic subtest: rcs0
[115.334589] Dynamic subtest rcs0: SUCCESS (2.046s)
[115.346526] Starting dynamic subtest: vcs0
[117.329145] Dynamic subtest vcs0: SUCCESS (1.994s)
[117.339726] Starting dynamic subtest: vcs1
[119.249453] Dynamic subtest vcs1: SUCCESS (1.919s)
[119.259829] Starting dynamic subtest: vecs0
[121.300256] Dynamic subtest vecs0: SUCCESS (2.050s)
[121.310828] Starting dynamic subtest: ccs0
[123.333763] Dynamic subtest ccs0: SUCCESS (2.032s)
[123.336836] Subtest independent: SUCCESS (10.045s)
[123.634557] [021/156] (896s left) i915_hangman (engine-engine-hang)
[123.694852] Starting subtest: engine-engine-hang
[123.696931] Starting dynamic subtest: rcs0
[125.286438] Dynamic subtest rcs0: SUCCESS (1.591s)
[125.296758] Starting dynamic subtest: bcs0
[127.268683] Dynamic subtest bcs0: SUCCESS (1.981s)
[127.280244] Starting dynamic subtest: vcs0
[129.253263] Dynamic subtest vcs0: SUCCESS (1.983s)
[129.265056] Starting dynamic subtest: vcs1
[131.237034] Dynamic subtest vcs1: SUCCESS (1.982s)
[131.248805] Starting dynamic subtest: vecs0
[133.284499] Dynamic subtest vecs0: SUCCESS (2.046s)
[133.295234] Starting dynamic subtest: ccs0
[135.269220] Dynamic subtest ccs0: SUCCESS (1.983s)
[135.271710] Subtest engine-engine-hang: SUCCESS (11.574s)
[135.363167] [022/156] (884s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[135.467911] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[135.472179] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[135.550027] [023/156] (884s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite)
[135.652211] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite
[135.656182] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[135.747983] [024/156] (884s left) kms_properties (get_properties-sanity-atomic)
[135.842758] Starting subtest: get_properties-sanity-atomic
[135.845152] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[135.915013] [025/156] (884s left) kms_atomic (plane-invalid-params-fence)
[136.013699] Starting subtest: plane-invalid-params-fence
[136.015521] Starting dynamic subtest: pipe-A-eDP-1
[136.372079] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.357s)
[136.374030] Subtest plane-invalid-params-fence: SUCCESS (0.358s)
[137.188432] [026/156] (883s left) syncobj_timeline (invalid-wait-bad-flags)
[137.244973] Starting subtest: invalid-wait-bad-flags
[137.247483] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[137.337160] [027/156] (883s left) kms_cursor_crc (cursor-onscreen-32x32)
[137.473437] Starting subtest: cursor-onscreen-32x32
[137.521956] Subtest cursor-onscreen-32x32: SKIP (0.046s)
[137.810877] [028/156] (882s left) prime_vgem (fence-write-hang)
[137.881873] Subtest fence-write-hang: SKIP (0.000s)
[137.974331] [029/156] (882s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[138.087835] Starting subtest: cursor-vs-flip-atomic
[167.837940] Subtest cursor-vs-flip-atomic: SUCCESS (29.748s)
[168.727727] [030/156] (851s left) gem_render_tiled_blits (basic)
[168.797270] Subtest basic: SKIP (0.000s)
[168.856799] [031/156] (851s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[168.905500] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[169.010855] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.104s)
[169.111119] [032/156] (851s left) gem_pxp (create-regular-buffer)
[169.228722] Starting subtest: create-regular-buffer
[169.230691] Subtest create-regular-buffer: SUCCESS (0.000s)
[169.331519] [033/156] (851s left) gem_exec_schedule (wide)
[169.513408] Starting subtest: wide
[169.515992] Starting dynamic subtest: rcs0
[179.766230] Dynamic subtest rcs0: SUCCESS (10.200s)
[179.778270] Starting dynamic subtest: vcs0
[190.198659] Dynamic subtest vcs0: SUCCESS (10.481s)
[190.210054] Starting dynamic subtest: vcs1
[200.376437] Dynamic subtest vcs1: SUCCESS (10.177s)
[200.385902] Starting dynamic subtest: vecs0
[210.565784] Dynamic subtest vecs0: SUCCESS (10.189s)
[210.576917] Starting dynamic subtest: ccs0
[225.371412] Dynamic subtest ccs0: SUCCESS (14.753s)
[225.374053] Subtest wide: SUCCESS (55.803s)
[228.300433] [034/156] (792s left) kms_hdr (brightness-with-hdr)
[228.392803] Starting subtest: brightness-with-hdr
[228.395160] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[228.399267] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[228.409019] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[228.413563] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[228.418183] Subtest brightness-with-hdr: SKIP (0.002s)
[228.466351] [035/156] (792s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[228.520733] Starting subtest: single-wait-for-submit-unsubmitted
[228.626107] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.104s)
[228.711905] [036/156] (792s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-render)
[228.818514] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-render
[228.822526] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[228.914336] [037/156] (791s left) vgem_basic (dmabuf-fence)
[228.955735] Starting subtest: dmabuf-fence
[228.957955] Subtest dmabuf-fence: SUCCESS (0.001s)
[229.026269] [038/156] (791s left) gen3_mixed_blits
[229.139858] [039/156] (791s left) gem_create (create-ext-placement-all)
[229.299418] Starting subtest: create-ext-placement-all
[229.302217] Subtest create-ext-placement-all: SUCCESS (0.000s)
[229.420918] [040/156] (791s left) gen9_exec_parse (batch-without-end)
[229.488311] Subtest batch-without-end: SKIP (0.000s)
[229.571184] [041/156] (791s left) kms_addfb_basic (legacy-format)
[229.622152] Starting subtest: legacy-format
[229.648212] Subtest legacy-format: SUCCESS (0.024s)
[229.778361] [042/156] (791s left) kms_frontbuffer_tracking (hdr-suspend)
[229.885072] Starting subtest: hdr-suspend
[229.888834] Subtest hdr-suspend: SKIP (0.000s)
[230.015682] [043/156] (791s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[230.126920] Starting subtest: cursorA-vs-flipB-atomic
[230.131297] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[230.280267] [044/156] (790s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-mmap-gtt)
[230.372659] Starting subtest: psrhdr-rgb565-draw-mmap-gtt
[230.376138] Subtest psrhdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[230.486561] [045/156] (790s left) gem_ctx_persistence (engines-persistence)
[230.545508] Starting subtest: engines-persistence
[230.547627] Starting dynamic subtest: rcs0
[231.366886] Dynamic subtest rcs0: SUCCESS (0.820s)
[231.378552] Starting dynamic subtest: bcs0
[232.131774] Dynamic subtest bcs0: SUCCESS (0.764s)
[232.143750] Starting dynamic subtest: vcs0
[232.899835] Dynamic subtest vcs0: SUCCESS (0.767s)
[232.911074] Starting dynamic subtest: vcs1
[233.661227] Dynamic subtest vcs1: SUCCESS (0.760s)
[233.673558] Starting dynamic subtest: vecs0
[234.437811] Dynamic subtest vecs0: SUCCESS (0.775s)
[234.451171] Starting dynamic subtest: ccs0
[235.178902] Dynamic subtest ccs0: SUCCESS (0.739s)
[235.181400] Subtest engines-persistence: SUCCESS (4.633s)
[235.314279] [046/156] (785s left) i915_drm_fdinfo (most-busy-check-all)
[235.356301] Starting subtest: most-busy-check-all
[235.358722] Starting dynamic subtest: rcs0
[235.365496] Dynamic subtest rcs0: SKIP (0.007s)
[235.375085] Starting dynamic subtest: bcs0
[235.379173] Dynamic subtest bcs0: SKIP (0.000s)
[235.387723] Starting dynamic subtest: vcs0
[235.392069] Dynamic subtest vcs0: SKIP (0.000s)
[235.400184] Starting dynamic subtest: vcs1
[235.404077] Dynamic subtest vcs1: SKIP (0.000s)
[235.412417] Starting dynamic subtest: vecs0
[235.416380] Dynamic subtest vecs0: SKIP (0.000s)
[235.425076] Starting dynamic subtest: ccs0
[235.429287] Dynamic subtest ccs0: SKIP (0.000s)
[235.433498] Subtest most-busy-check-all: SKIP (0.011s)
[235.470472] [047/156] (785s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[235.606098] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[235.610090] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[235.720351] [048/156] (785s left) kms_content_protection (legacy-hdcp14)
[235.854996] Starting subtest: legacy-hdcp14
[235.859508] Subtest legacy-hdcp14: SKIP (0.000s)
[235.958401] [049/156] (785s left) kms_chamelium_edid (dp-edid-resolution-list)
[237.160591] Subtest dp-edid-resolution-list: SKIP (0.000s)
[237.490128] [050/156] (783s left) kms_dsc (dsc-with-output-formats)
[237.589855] Starting subtest: dsc-with-output-formats
[237.611127] Subtest dsc-with-output-formats: SKIP (0.001s)
[237.680171] [051/156] (783s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[237.782317] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[237.798355] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[237.903237] [052/156] (783s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[237.995453] Starting subtest: hdr-rgb565-draw-blt
[237.999473] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[238.103743] [053/156] (783s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[238.203251] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[238.207620] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[238.269846] [054/156] (783s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[238.370953] Starting subtest: compare-crc-sanitycheck-xr24
[238.373911] Starting dynamic subtest: pipe-A-eDP-1
[239.044220] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.672s)
[239.054469] Starting dynamic subtest: pipe-B-eDP-1
[240.338666] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.293s)
[240.349245] Starting dynamic subtest: pipe-C-eDP-1
[241.604869] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.266s)
[241.615131] Starting dynamic subtest: pipe-D-eDP-1
[242.816174] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.211s)
[242.818485] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.446s)
[243.644684] [055/156] (777s left) kms_psr (fbc-pr-cursor-plane-onoff)
[243.735212] Starting subtest: fbc-pr-cursor-plane-onoff
[243.739798] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[243.807169] [056/156] (777s left) kms_vblank (wait-forked-hang)
[243.909355] Starting subtest: wait-forked-hang
[243.911629] Starting dynamic subtest: pipe-A-eDP-1
[270.086485] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.174s)
[270.100438] Starting dynamic subtest: pipe-D-eDP-1
[294.094663] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.007s)
[294.096616] Subtest wait-forked-hang: SUCCESS (50.184s)
[294.934171] [057/156] (726s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-plflip-blt)
[295.036881] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-plflip-blt
[295.041247] Subtest fbcpsrhdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[295.136067] [058/156] (726s left) gem_exec_fence (parallel)
[295.319172] Starting subtest: parallel
[295.321114] Starting dynamic subtest: rcs0
[297.468207] Dynamic subtest rcs0: SUCCESS (2.152s)
[297.478322] Starting dynamic subtest: bcs0
[299.617309] Dynamic subtest bcs0: SUCCESS (2.148s)
[299.628374] Starting dynamic subtest: vcs0
[301.766966] Dynamic subtest vcs0: SUCCESS (2.149s)
[301.775813] Starting dynamic subtest: vcs1
[303.917989] Dynamic subtest vcs1: SUCCESS (2.150s)
[303.929675] Starting dynamic subtest: vecs0
[306.074539] Dynamic subtest vecs0: SUCCESS (2.156s)
[306.085732] Starting dynamic subtest: ccs0
[308.223471] Dynamic subtest ccs0: SUCCESS (2.148s)
[308.225489] Subtest parallel: SUCCESS (12.907s)
[309.339890] [059/156] (712s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[309.447389] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[309.451458] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[309.557819] [060/156] (712s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[309.651089] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[309.655498] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[309.748231] [061/156] (712s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[309.840456] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[309.852989] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[309.927059] [062/156] (711s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-move)
[310.025760] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-move
[310.029821] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[310.120254] [063/156] (711s left) gem_exec_balancer (full)
[310.172510] Starting subtest: full
[315.200642] Subtest full: SUCCESS (5.028s)
[315.487452] [064/156] (706s left) kms_chamelium_hpd (common-hpd-after-suspend)
[315.875806] Subtest common-hpd-after-suspend: SKIP (0.000s)
[316.717582] [065/156] (705s left) kms_plane (plane-position-hole)
[316.808125] Starting subtest: plane-position-hole
[317.338056] Starting dynamic subtest: pipe-A-plane-1
[318.637440] Dynamic subtest pipe-A-plane-1: SUCCESS (1.299s)
[318.648035] Starting dynamic subtest: pipe-A-plane-2
[319.965048] Dynamic subtest pipe-A-plane-2: SUCCESS (1.325s)
[319.975368] Starting dynamic subtest: pipe-A-plane-3
[321.307142] Dynamic subtest pipe-A-plane-3: SUCCESS (1.342s)
[321.316383] Starting dynamic subtest: pipe-A-plane-4
[322.623161] Dynamic subtest pipe-A-plane-4: SUCCESS (1.317s)
[322.632509] Starting dynamic subtest: pipe-A-plane-5
[322.647528] Dynamic subtest pipe-A-plane-5: SUCCESS (0.023s)
[323.851242] Starting dynamic subtest: pipe-B-plane-1
[325.195723] Dynamic subtest pipe-B-plane-1: SUCCESS (1.354s)
[325.206346] Starting dynamic subtest: pipe-B-plane-2
[326.487186] Dynamic subtest pipe-B-plane-2: SUCCESS (1.289s)
[326.497605] Starting dynamic subtest: pipe-B-plane-3
[327.851962] Dynamic subtest pipe-B-plane-3: SUCCESS (1.363s)
[327.862274] Starting dynamic subtest: pipe-B-plane-4
[329.165925] Dynamic subtest pipe-B-plane-4: SUCCESS (1.312s)
[329.175596] Starting dynamic subtest: pipe-B-plane-5
[329.192432] Dynamic subtest pipe-B-plane-5: SUCCESS (0.026s)
[329.194702] Subtest plane-position-hole: SUCCESS (12.385s)
[330.051497] [066/156] (692s left) gem_ctx_create (basic-files)
[330.120166] Starting subtest: basic-files
[332.128610] Subtest basic-files: SUCCESS (2.008s)
[333.435079] [067/156] (688s left) perf_pmu (most-busy-idle-check-all)
[333.470806] Starting subtest: most-busy-idle-check-all
[333.472758] Starting dynamic subtest: rcs0
[335.304034] Dynamic subtest rcs0: SUCCESS (1.831s)
[335.317755] Starting dynamic subtest: bcs0
[337.286738] Dynamic subtest bcs0: SUCCESS (1.980s)
[337.299207] Starting dynamic subtest: vcs0
[339.270632] Dynamic subtest vcs0: SUCCESS (1.981s)
[339.283310] Starting dynamic subtest: vcs1
[341.318815] Dynamic subtest vcs1: SUCCESS (2.045s)
[341.331644] Starting dynamic subtest: vecs0
[343.302973] Dynamic subtest vecs0: SUCCESS (1.980s)
[343.315457] Starting dynamic subtest: ccs0
[345.286380] Dynamic subtest ccs0: SUCCESS (1.981s)
[345.289255] Subtest most-busy-idle-check-all: SUCCESS (11.817s)
[345.398352] [068/156] (676s left) device_reset (unbind-reset-rebind)
[345.446087] Starting subtest: unbind-reset-rebind
[350.458234] Subtest unbind-reset-rebind: SUCCESS (5.007s)
[350.505821] [069/156] (671s left) gem_exec_schedule (fairslice)
[350.587505] Starting subtest: fairslice
[350.589487] Starting dynamic subtest: rcs0
[352.608206] Dynamic subtest rcs0: SUCCESS (2.025s)
[352.623559] Starting dynamic subtest: bcs0
[354.628304] Dynamic subtest bcs0: SUCCESS (2.019s)
[354.637721] Starting dynamic subtest: vcs0
[356.652557] Dynamic subtest vcs0: SUCCESS (2.021s)
[356.664288] Starting dynamic subtest: vcs1
[358.722109] Dynamic subtest vcs1: SUCCESS (2.019s)
[358.734033] Starting dynamic subtest: vecs0
[360.693558] Dynamic subtest vecs0: SUCCESS (2.021s)
[360.706154] Starting dynamic subtest: ccs0
[362.717985] Dynamic subtest ccs0: SUCCESS (2.023s)
[362.720321] Subtest fairslice: SUCCESS (12.135s)
[363.013814] [070/156] (659s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[363.119388] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[363.123462] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[363.227657] [071/156] (659s left) gem_pxp (reject-modify-context-protection-on)
[363.351065] Starting subtest: reject-modify-context-protection-on
[363.353555] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[363.455281] [072/156] (658s left) kms_panel_fitting (atomic-fastset)
[363.540144] Starting subtest: atomic-fastset
[363.542429] Starting dynamic subtest: pipe-A-eDP-1
[363.700413] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.162s)
[363.712413] Starting dynamic subtest: pipe-B-eDP-1
[364.943869] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.241s)
[364.956216] Starting dynamic subtest: pipe-C-eDP-1
[366.156251] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.211s)
[366.170067] Starting dynamic subtest: pipe-D-eDP-1
[367.392101] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.235s)
[367.394367] Subtest atomic-fastset: SUCCESS (3.855s)
[368.641273] [073/156] (653s left) perf_pmu (busy-no-semaphores)
[368.682886] Starting subtest: busy-no-semaphores
[368.684907] Starting dynamic subtest: rcs0
[369.251386] Dynamic subtest rcs0: SUCCESS (0.569s)
[369.262837] Starting dynamic subtest: bcs0
[369.799094] Dynamic subtest bcs0: SUCCESS (0.545s)
[369.810547] Starting dynamic subtest: vcs0
[370.352044] Dynamic subtest vcs0: SUCCESS (0.549s)
[370.365188] Starting dynamic subtest: vcs1
[371.850451] Dynamic subtest vcs1: SUCCESS (1.494s)
[371.862919] Starting dynamic subtest: vecs0
[373.836107] Dynamic subtest vecs0: SUCCESS (1.982s)
[373.849291] Starting dynamic subtest: ccs0
[375.817734] Dynamic subtest ccs0: SUCCESS (1.978s)
[375.820696] Subtest busy-no-semaphores: SUCCESS (7.138s)
[375.936680] [074/156] (646s left) kms_psr (psr2-sprite-plane-onoff)
[376.033094] Starting subtest: psr2-sprite-plane-onoff
[376.035308] Starting dynamic subtest: eDP-1
[377.767147] Dynamic subtest eDP-1: SUCCESS (1.733s)
[377.769429] Subtest psr2-sprite-plane-onoff: SUCCESS (1.734s)
[378.613140] [075/156] (643s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[378.713656] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[378.715961] Starting dynamic subtest: pipe-A-eDP-1
[378.775584] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[378.785797] Starting dynamic subtest: pipe-B-eDP-1
[378.843422] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[378.856204] Starting dynamic subtest: pipe-C-eDP-1
[378.913394] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[378.924136] Starting dynamic subtest: pipe-D-eDP-1
[378.983061] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[378.987208] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.009s)
[380.991086] [076/156] (641s left) kms_frontbuffer_tracking (fbchdr-1p-shrfb-fliptrack-mmap-gtt)
[381.103629] Starting subtest: fbchdr-1p-shrfb-fliptrack-mmap-gtt
[381.107635] Subtest fbchdr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[381.204526] [077/156] (641s left) gem_mmap_gtt (medium-copy)
[381.263992] Subtest medium-copy: SKIP (0.000s)
[381.323704] [078/156] (641s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[381.433864] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[381.438077] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[381.531659] [079/156] (641s left) api_intel_bb (object-reloc-purge-cache)
[381.586040] Starting subtest: object-reloc-purge-cache
[381.593220] Subtest object-reloc-purge-cache: SKIP (0.004s)
[381.697902] [080/156] (640s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[381.795975] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[386.484578] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.694s)
[387.706446] [081/156] (634s left) kms_psr (fbc-psr-sprite-render)
[387.807684] Starting subtest: fbc-psr-sprite-render
[387.809968] Starting dynamic subtest: eDP-1
[387.813973] Dynamic subtest eDP-1: SKIP (0.000s)
[387.818166] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[387.892479] [082/156] (634s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[387.987026] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[387.991651] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[388.061042] [083/156] (634s left) gem_exec_parallel (basic)
[388.124052] Starting subtest: basic
[388.126452] Starting dynamic subtest: rcs0
[389.571811] Dynamic subtest rcs0: SUCCESS (1.447s)
[389.583917] Starting dynamic subtest: bcs0
[391.046059] Dynamic subtest bcs0: SUCCESS (1.473s)
[391.056084] Starting dynamic subtest: vcs0
[394.246554] Dynamic subtest vcs0: SUCCESS (3.196s)
[394.259108] Starting dynamic subtest: vcs1
[396.230882] Dynamic subtest vcs1: SUCCESS (1.981s)
[396.243288] Starting dynamic subtest: vecs0
[398.277561] Dynamic subtest vecs0: SUCCESS (2.043s)
[398.290835] Starting dynamic subtest: ccs0
[399.785029] Dynamic subtest ccs0: SUCCESS (1.506s)
[399.787530] Subtest basic: SUCCESS (11.662s)
[399.974758] [084/156] (622s left) kms_plane_alpha_blend (constant-alpha-mid)
[400.073055] Starting subtest: constant-alpha-mid
[400.354766] Starting dynamic subtest: pipe-A-eDP-1
[401.390217] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.034s)
[401.406759] Starting dynamic subtest: pipe-D-eDP-1
[402.976953] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.585s)
[402.979430] Subtest constant-alpha-mid: SUCCESS (2.905s)
[403.851276] [085/156] (619s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[403.959965] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[405.095201] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.134s)
[405.215033] [086/156] (617s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[405.323771] Starting subtest: x-tiled-64bpp-rotate-180
[406.936798] Subtest x-tiled-64bpp-rotate-180: SUCCESS (1.613s)
[407.151430] [087/156] (615s left) gem_ctx_persistence (heartbeat-many)
[407.205484] Starting subtest: heartbeat-many
[407.210183] Subtest heartbeat-many: SKIP (0.000s)
[407.308111] [088/156] (615s left) prime_self_import (export-vs-gem_close-race)
[407.337368] Starting subtest: export-vs-gem_close-race
[412.463334] Subtest export-vs-gem_close-race: SUCCESS (5.116s)
[412.537951] [089/156] (610s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[412.812754] Starting subtest: parallel-dmabuf-import-out-fence
[414.209368] Subtest parallel-dmabuf-import-out-fence: SUCCESS (1.396s)
[414.313562] [090/156] (608s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[414.411630] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[414.415776] Subtest fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[414.510312] [091/156] (608s left) kms_flip_event_leak (basic)
[414.606027] Starting subtest: basic
[414.608131] Starting dynamic subtest: pipe-A-eDP-1
[415.024201] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.418s)
[415.039593] Starting dynamic subtest: pipe-B-eDP-1
[416.178112] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.152s)
[416.188690] Starting dynamic subtest: pipe-C-eDP-1
[417.291218] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.112s)
[417.301689] Starting dynamic subtest: pipe-D-eDP-1
[418.425267] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.133s)
[418.427646] Subtest basic: SUCCESS (3.822s)
[419.301615] [092/156] (603s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[419.410739] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-pwrite
[419.414954] Subtest hdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[419.506242] [093/156] (603s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[419.552820] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[419.657663] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.103s)
[419.735237] [094/156] (603s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[419.834157] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[427.179853] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.351s)
[427.312534] [095/156] (595s left) gem_userptr_blits (dmabuf-unsync)
[427.375605] Subtest dmabuf-unsync: SKIP (0.000s)
[427.514235] [096/156] (595s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[427.611320] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[427.636642] Starting dynamic subtest: pipe-A-plane-0
[427.925341] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[427.938042] Starting dynamic subtest: pipe-A-plane-3
[428.160139] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[428.171222] Starting dynamic subtest: pipe-A-plane-5
[428.175265] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[429.318989] Starting dynamic subtest: pipe-B-plane-0
[429.610490] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[429.623725] Starting dynamic subtest: pipe-B-plane-3
[429.844571] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[429.855028] Starting dynamic subtest: pipe-B-plane-5
[429.859616] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[430.166415] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.557s)
[431.003988] [097/156] (592s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[431.097636] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[431.101889] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[431.191063] [098/156] (592s left) syncobj_wait (wait-all-interrupted)
[431.244198] Starting subtest: wait-all-interrupted
[431.354034] Subtest wait-all-interrupted: SUCCESS (0.109s)
[431.444079] [099/156] (592s left) gem_exec_reloc (basic-write-cpu-noreloc)
[431.496862] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[431.574854] [100/156] (591s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[431.678589] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[439.095431] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.422s)
[439.186894] [101/156] (584s left) gem_ctx_bad_destroy (invalid-pad)
[439.246991] Starting subtest: invalid-pad
[439.249528] Subtest invalid-pad: SUCCESS (0.000s)
[439.319999] [102/156] (584s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[439.424762] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[439.430202] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[439.529866] [103/156] (584s left) kms_chamelium_audio (hdmi-audio)
[439.914059] Subtest hdmi-audio: SKIP (0.000s)
[440.755787] [104/156] (582s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[440.857966] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[441.968470] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: FAIL (0.515s)
[442.010842] [105/156] (581s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[442.130999] Starting subtest: 2x-cursor-vs-flip-atomic
[442.134911] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[442.275301] [106/156] (581s left) gem_exec_suspend (basic-s4-devices)
[442.336455] Starting subtest: basic-S4-devices
[442.338732] Starting dynamic subtest: smem
[455.302629] Dynamic subtest smem: SUCCESS (12.963s)
[455.305618] Subtest basic-S4-devices: SUCCESS (12.965s)
[461.511753] [107/156] (568s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-fullscreen)
[461.652763] Starting subtest: psrhdr-2p-primscrn-spr-indfb-fullscreen
[461.656803] Subtest psrhdr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[461.749556] [108/156] (568s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[461.857074] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[461.861387] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[461.962583] [109/156] (567s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[462.058299] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[462.062255] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[462.169080] [110/156] (567s left) kms_busy (extended-pageflip-hang-oldfb)
[462.267074] Starting subtest: extended-pageflip-hang-oldfb
[462.269339] Starting dynamic subtest: pipe-A
[462.674974] Dynamic subtest pipe-A: SUCCESS (0.407s)
[462.686855] Starting dynamic subtest: pipe-D
[463.861313] Dynamic subtest pipe-D: SUCCESS (1.184s)
[463.863667] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.596s)
[464.685394] [111/156] (565s left) gem_exec_whisper (basic-queues-priority)
[464.742650] Starting subtest: basic-queues-priority
[489.299209] Subtest basic-queues-priority: SUCCESS (24.502s)
[489.436322] [112/156] (540s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt)
[489.542498] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt
[489.546680] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[489.643904] [113/156] (540s left) kms_plane_scaling (planes-upscale-factor-0-25)
[489.741265] Starting subtest: planes-upscale-factor-0-25
[489.743579] Starting dynamic subtest: pipe-A
[489.802252] Dynamic subtest pipe-A: SUCCESS (0.063s)
[489.813672] Starting dynamic subtest: pipe-B
[491.012905] Dynamic subtest pipe-B: SUCCESS (1.210s)
[491.024613] Starting dynamic subtest: pipe-C
[492.140225] Dynamic subtest pipe-C: SUCCESS (1.126s)
[492.151968] Starting dynamic subtest: pipe-D
[493.273805] Dynamic subtest pipe-D: SUCCESS (1.133s)
[493.276188] Subtest planes-upscale-factor-0-25: SUCCESS (3.535s)
[494.423683] [114/156] (535s left) syncobj_timeline (wait-delayed-signal)
[494.474704] Starting subtest: wait-delayed-signal
[494.576462] Subtest wait-delayed-signal: SUCCESS (0.102s)
[494.666915] [115/156] (535s left) intel_hwmon (hwmon-read)
[494.727059] Subtest hwmon-read: SKIP (0.000s)
[494.795235] [116/156] (535s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[494.883507] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[494.886087] Starting dynamic subtest: pipe-A-default-mode
[496.094601] Dynamic subtest pipe-A-default-mode: SKIP (1.209s)
[496.098689] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.210s)
[496.238650] [117/156] (534s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[496.353680] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[496.357621] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[496.447945] [118/156] (533s left) kms_lease (cursor-implicit-plane)
[496.549519] Starting subtest: cursor-implicit-plane
[496.551799] Starting dynamic subtest: pipe-A-eDP-1
[496.996429] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.450s)
[497.014553] Starting dynamic subtest: pipe-B-eDP-1
[498.189609] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.191s)
[498.201451] Starting dynamic subtest: pipe-C-eDP-1
[499.302390] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.110s)
[499.313796] Starting dynamic subtest: pipe-D-eDP-1
[500.427627] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.123s)
[500.429835] Subtest cursor-implicit-plane: SUCCESS (3.880s)
[501.284996] [119/156] (529s left) syncobj_wait (invalid-signal-bad-pad)
[501.345580] Starting subtest: invalid-signal-bad-pad
[501.347888] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[501.474732] [120/156] (528s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[501.532741] Subtest tiled-to-untiled: SKIP (0.000s)
[501.616418] [121/156] (528s left) gem_ctx_param (root-set)
[501.671131] Starting subtest: root-set
[501.673409] Subtest root-set: SUCCESS (0.001s)
[501.779595] [122/156] (528s left) gem_mmap_offset (perf)
[501.836554] Starting subtest: perf
[501.838430] Starting dynamic subtest: smem0
[502.194313] Dynamic subtest smem0: SUCCESS (0.356s)
[502.196775] Subtest perf: SUCCESS (0.357s)
[502.319144] [123/156] (528s left) prime_vgem (fence-wait)
[502.396283] Starting subtest: fence-wait
[502.398691] Starting dynamic subtest: rcs0
[503.440532] Dynamic subtest rcs0: SUCCESS (1.042s)
[503.453926] Starting dynamic subtest: bcs0
[504.507294] Dynamic subtest bcs0: SUCCESS (1.065s)
[504.521969] Starting dynamic subtest: vcs0
[505.547258] Dynamic subtest vcs0: SUCCESS (1.039s)
[505.566766] Starting dynamic subtest: vcs1
[506.591669] Dynamic subtest vcs1: SUCCESS (1.043s)
[506.605802] Starting dynamic subtest: vecs0
[507.635543] Dynamic subtest vecs0: SUCCESS (1.041s)
[507.649703] Starting dynamic subtest: ccs0
[508.679021] Dynamic subtest ccs0: SUCCESS (1.041s)
[508.681927] Subtest fence-wait: SUCCESS (6.283s)
[508.796672] [124/156] (521s left) kms_psr (pr-no-drrs)
[508.893255] Starting subtest: pr-no-drrs
[508.897782] Subtest pr-no-drrs: SKIP (0.000s)
[508.967904] [125/156] (521s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-onoff)
[509.061192] Starting subtest: hdr-2p-primscrn-spr-indfb-onoff
[509.065164] Subtest hdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[509.174816] [126/156] (521s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-render)
[509.270243] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-render
[509.274200] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[509.377383] [127/156] (521s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[509.478088] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[509.480397] Starting dynamic subtest: pipe-A-eDP-1
[509.551748] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[509.566076] Starting dynamic subtest: pipe-B-eDP-1
[510.663759] Dynamic subtest pipe-B-eDP-1: SKIP (1.108s)
[510.668728] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.189s)
[511.934804] [128/156] (518s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[512.023144] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[512.025288] Starting dynamic subtest: pipe-A-eDP-1
[512.046204] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.023s)
[512.061774] Starting dynamic subtest: pipe-B-eDP-1
[512.081265] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.024s)
[512.092589] Starting dynamic subtest: pipe-C-eDP-1
[512.112065] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.022s)
[512.123525] Starting dynamic subtest: pipe-D-eDP-1
[512.143816] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s)
[512.146132] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.099s)
[514.405362] [129/156] (516s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-move)
[514.503217] Starting subtest: psrhdr-1p-primscrn-spr-indfb-move
[514.507226] Subtest psrhdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[514.611327] [130/156] (516s left) syncobj_timeline (multi-wait-submitted-signaled)
[514.663992] Starting subtest: multi-wait-submitted-signaled
[514.666223] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[514.786080] [131/156] (516s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[514.889181] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[514.891481] Starting dynamic subtest: pipe-A-eDP-1
[514.900740] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[514.913391] Starting dynamic subtest: pipe-B-eDP-1
[514.922447] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[514.935774] Starting dynamic subtest: pipe-C-eDP-1
[514.944637] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[514.958198] Starting dynamic subtest: pipe-D-eDP-1
[514.966627] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[514.970721] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[517.173040] [132/156] (513s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[517.283799] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[517.287996] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[517.368644] [133/156] (513s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-msflip-blt)
[517.477666] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-msflip-blt
[517.481916] Subtest fbcpsrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[517.566309] [134/156] (513s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[517.666320] Starting subtest: y-tiled-8bpp-rotate-90
[517.670631] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[517.767894] [135/156] (513s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[517.868412] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[517.872582] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[517.956090] [136/156] (513s left) kms_plane_multiple (2x-tiling-yf)
[518.051530] Starting subtest: 2x-tiling-yf
[518.055398] Subtest 2x-tiling-yf: SKIP (0.000s)
[518.138366] [137/156] (512s left) kms_pm_rpm (system-suspend-idle)
[520.468627] Starting subtest: system-suspend-idle
[539.799225] Subtest system-suspend-idle: SUCCESS (4.275s)
[540.198097] [138/156] (490s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[540.305082] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[547.295610] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (6.901s)
[547.445350] [139/156] (483s left) gem_exec_reloc (basic-range)
[547.509990] Subtest basic-range: SKIP (0.000s)
[547.582795] [140/156] (483s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[547.638136] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[547.642304] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[547.818770] [141/156] (483s left) gem_userptr_blits (vma-merge)
[547.901165] Starting subtest: vma-merge
[547.945544] Subtest vma-merge: SUCCESS (0.044s)
[548.093025] [142/156] (483s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[548.161505] Starting subtest: map-fixed-invalidate-overlap
[548.165957] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[548.311603] [143/156] (483s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-blt)
[548.415450] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-blt
[548.419014] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[548.523805] [144/156] (482s left) kms_dp_link_training (uhbr-mst)
[548.620716] Starting subtest: uhbr-mst
[548.628048] Subtest uhbr-mst: SKIP (0.000s)
[548.708583] [145/156] (482s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[548.802797] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[548.804431] Starting dynamic subtest: pipe-A-eDP-1
[549.241967] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.438s)
[549.256767] Starting dynamic subtest: pipe-B-eDP-1
[549.273684] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[549.285983] Starting dynamic subtest: pipe-C-eDP-1
[549.302233] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[549.312932] Starting dynamic subtest: pipe-D-eDP-1
[549.328705] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[549.330501] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.453s)
[551.600045] [146/156] (479s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[551.704159] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[556.201330] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (4.486s)
[557.350539] [147/156] (474s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[557.458418] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[557.462539] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[557.567172] [148/156] (474s left) syncobj_basic (bad-handle-to-fd)
[557.616028] Starting subtest: bad-handle-to-fd
[557.618370] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[557.717373] [149/156] (473s left) kms_pm_rpm (modeset-lpsp)
[559.551979] Starting subtest: modeset-lpsp
[561.006105] Subtest modeset-lpsp: SUCCESS (1.455s)
[561.106188] [150/156] (470s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[561.174629] Subtest writes-after-reads: SKIP (0.000s)
[561.248764] [151/156] (470s left) gem_mmap_gtt (fault-concurrent-x)
[561.299447] Subtest fault-concurrent-X: SKIP (0.000s)
[561.375700] [152/156] (470s left) kms_selftest (drm_dp_mst_helper)
[561.412262] Starting subtest: drm_dp_mst_helper
[561.423123] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[561.526580] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.105s)
[561.538364] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[561.633952] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.106s)
[561.645261] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[561.734244] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.099s)
[561.736470] Subtest drm_dp_mst_helper: SUCCESS (0.323s)
[561.913307] [153/156] (469s left) kms_color (ctm-max)
[562.002415] Starting subtest: ctm-max
[562.004897] Starting dynamic subtest: pipe-A-eDP-1
[562.716133] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.713s)
[562.729135] Starting dynamic subtest: pipe-B-eDP-1
[564.176514] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.456s)
[564.188057] Starting dynamic subtest: pipe-C-eDP-1
[565.555301] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.375s)
[565.567070] Starting dynamic subtest: pipe-D-eDP-1
[566.892777] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.335s)
[566.894841] Subtest ctm-max: SUCCESS (4.884s)
[567.709151] [154/156] (464s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-move)
[567.819401] Starting subtest: hdr-2p-primscrn-cur-indfb-move
[567.823766] Subtest hdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[567.934999] [155/156] (464s left) gem_exec_balancer (bonded-chain)
[567.999279] Starting subtest: bonded-chain
[575.042629] Subtest bonded-chain: SUCCESS (7.041s)
[575.368174] [156/156] (456s left) vgem_basic (bad-pad)
[575.409352] Starting subtest: bad-pad
[575.411491] Subtest bad-pad: SUCCESS (0.000s)
[575.463687] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 3/ for test: gem_softpin
results: parsing output: 4/ for test: gem_exec_schedule
results: parsing output: 5/ for test: vgem_basic
results: parsing output: 6/ for test: i915_pm_rps
results: parsing output: 7/ for test: kms_cursor_crc
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: kms_vblank
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_cursor_legacy
results: parsing output: 12/ for test: kms_cursor_edge_walk
results: parsing output: 13/ for test: kms_flip
results: parsing output: 14/ for test: gem_basic
results: parsing output: 15/ for test: syncobj_wait
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: gem_exec_schedule
results: parsing output: 20/ for test: i915_hangman
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_properties
results: parsing output: 24/ for test: kms_atomic
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_cursor_crc
results: parsing output: 27/ for test: prime_vgem
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: gem_render_tiled_blits
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: gem_pxp
results: parsing output: 32/ for test: gem_exec_schedule
results: parsing output: 33/ for test: kms_hdr
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: vgem_basic
results: parsing output: 37/ for test: gen3_mixed_blits
results: parsing output: 38/ for test: gem_create
results: parsing output: 39/ for test: gen9_exec_parse
results: parsing output: 40/ for test: kms_addfb_basic
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_cursor_legacy
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_ctx_persistence
results: parsing output: 45/ for test: i915_drm_fdinfo
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_content_protection
results: parsing output: 48/ for test: kms_chamelium_edid
results: parsing output: 49/ for test: kms_dsc
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_psr2_sf
results: parsing output: 53/ for test: kms_pipe_crc_basic
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: kms_vblank
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_exec_fence
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: gem_exec_balancer
results: parsing output: 63/ for test: kms_chamelium_hpd
results: parsing output: 64/ for test: kms_plane
results: parsing output: 65/ for test: gem_ctx_create
results: parsing output: 66/ for test: perf_pmu
results: parsing output: 67/ for test: device_reset
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_pxp
results: parsing output: 71/ for test: kms_panel_fitting
results: parsing output: 72/ for test: perf_pmu
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_mmap_gtt
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: api_intel_bb
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_psr
results: parsing output: 81/ for test: kms_flip_scaled_crc
results: parsing output: 82/ for test: gem_exec_parallel
results: parsing output: 83/ for test: kms_plane_alpha_blend
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: gem_ctx_persistence
results: parsing output: 87/ for test: prime_self_import
results: parsing output: 88/ for test: gem_exec_balancer
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_flip_event_leak
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: syncobj_wait
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_userptr_blits
results: parsing output: 95/ for test: kms_plane
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: syncobj_wait
results: parsing output: 98/ for test: gem_exec_reloc
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_ctx_bad_destroy
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_chamelium_audio
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: kms_cursor_legacy
results: parsing output: 105/ for test: gem_exec_suspend
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_busy
results: parsing output: 110/ for test: gem_exec_whisper
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_plane_scaling
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: intel_hwmon
results: parsing output: 115/ for test: kms_flip_scaled_crc
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_lease
results: parsing output: 118/ for test: syncobj_wait
results: parsing output: 119/ for test: gem_set_tiling_vs_blt
results: parsing output: 120/ for test: gem_ctx_param
results: parsing output: 121/ for test: gem_mmap_offset
results: parsing output: 122/ for test: prime_vgem
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_psr2_sf
results: parsing output: 127/ for test: kms_ccs
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: syncobj_timeline
results: parsing output: 130/ for test: kms_ccs
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_big_fb
results: parsing output: 134/ for test: kms_big_fb
results: parsing output: 135/ for test: kms_plane_multiple
results: parsing output: 136/ for test: kms_pm_rpm
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_exec_reloc
results: parsing output: 139/ for test: gem_render_copy
results: parsing output: 140/ for test: gem_userptr_blits
results: parsing output: 141/ for test: gem_userptr_blits
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_dp_link_training
results: parsing output: 144/ for test: kms_ccs
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: syncobj_basic
results: parsing output: 148/ for test: kms_pm_rpm
results: parsing output: 149/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 150/ for test: gem_mmap_gtt
results: parsing output: 151/ for test: kms_selftest
results: parsing output: 152/ for test: kms_color
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: gem_exec_balancer
results: parsing output: 155/ for test: vgem_basic
Done.