[55.666120] Initializing watchdogs
[55.666207]   /dev/watchdog0
[55.682759] [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]
[55.687777] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[55.689933] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[55.693156] [001/156] (960s left) gem_userptr_blits (readonly-pwrite-unsync)
[55.791671] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[55.920486] [002/156] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[56.028066] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[62.595720] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.622s)
[62.731704] [003/156] (953s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-blt)
[62.840469] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-blt
[62.844710] Subtest fbchdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[62.935096] [004/156] (953s left) kms_pm_dc (dc5-psr)
[64.043282] Starting subtest: dc5-psr
[66.938058] Subtest dc5-psr: SUCCESS (2.904s)
[67.762030] [005/156] (948s left) kms_psr (pr-primary-mmap-cpu)
[67.859460] Starting subtest: pr-primary-mmap-cpu
[67.863756] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[67.942759] [006/156] (948s left) kms_plane_multiple (tiling-y)
[68.036957] Starting subtest: tiling-y
[68.041256] Subtest tiling-y: SKIP (0.000s)
[68.116910] [007/156] (948s left) gem_userptr_blits (dmabuf-sync)
[68.177607] Starting subtest: dmabuf-sync
[68.181620] Subtest dmabuf-sync: SKIP (0.002s)
[68.328014] [008/156] (948s left) syncobj_timeline (etime-single-wait-submitted)
[68.375629] Starting subtest: etime-single-wait-submitted
[68.481197] Subtest etime-single-wait-submitted: SUCCESS (0.104s)
[68.564993] [009/156] (947s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[68.666751] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[68.670706] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[68.774404] [010/156] (947s left) kms_frontbuffer_tracking (psr-rgb101010-draw-render)
[68.868506] Starting subtest: psr-rgb101010-draw-render
[76.195080] Subtest psr-rgb101010-draw-render: SUCCESS (7.340s)
[76.308602] [011/156] (940s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[76.393293] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[76.395683] Starting dynamic subtest: pipe-A-eDP-1
[76.891812] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.470s)
[76.899828] Starting dynamic subtest: pipe-B-eDP-1
[78.412559] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.521s)
[78.420811] Starting dynamic subtest: pipe-C-eDP-1
[79.818796] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.403s)
[79.829268] Starting dynamic subtest: pipe-D-eDP-1
[81.225069] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.412s)
[81.227298] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.809s)
[82.399760] [012/156] (934s left) kms_getfb (getfb2-handle-protection)
[82.491325] Starting subtest: getfb2-handle-protection
[82.509274] Subtest getfb2-handle-protection: SUCCESS (0.019s)
[82.635401] [013/156] (934s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[82.747581] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[83.560042] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.814s)
[83.755386] [014/156] (932s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-msflip-blt)
[83.860788] Starting subtest: psrhdr-1p-primscrn-indfb-msflip-blt
[83.865245] Subtest psrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[83.967668] [015/156] (932s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[84.017128] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[84.021565] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[84.196786] [016/156] (932s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[84.287253] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[84.291841] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[84.384305] [017/156] (932s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[84.479865] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc
[84.484118] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[84.576817] [018/156] (932s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-pwrite)
[84.676584] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-pwrite
[84.680603] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[84.785884] [019/156] (932s left) gem_exec_flush (basic-wb-ro-before-default)
[84.846601] Starting subtest: basic-wb-ro-before-default
[90.296938] Subtest basic-wb-ro-before-default: SUCCESS (5.451s)
[90.481896] [020/156] (926s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[90.593611] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[90.597858] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[90.701469] [021/156] (926s left) kms_cursor_crc (cursor-random-64x64)
[90.870620] Starting subtest: cursor-random-64x64
[90.873083] Starting dynamic subtest: pipe-A-eDP-1
[94.196305] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.319s)
[94.221859] Starting dynamic subtest: pipe-D-eDP-1
[98.182327] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.966s)
[98.184658] Subtest cursor-random-64x64: SUCCESS (7.312s)
[99.055537] [022/156] (917s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[99.111803] Starting subtest: invalid-single-wait-all-unsubmitted
[99.113741] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[99.213745] [023/156] (917s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[99.307779] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[99.312292] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[100.471383] [024/156] (916s left) kms_pipe_crc_basic (read-crc)
[100.565147] Starting subtest: read-crc
[100.567353] Starting dynamic subtest: pipe-A-eDP-1
[101.274707] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.711s)
[101.285570] Starting dynamic subtest: pipe-B-eDP-1
[102.665215] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.388s)
[102.674834] Starting dynamic subtest: pipe-C-eDP-1
[104.045473] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.379s)
[104.053968] Starting dynamic subtest: pipe-D-eDP-1
[105.385295] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.338s)
[105.387858] Subtest read-crc: SUCCESS (4.820s)
[106.237947] [025/156] (910s left) kms_frontbuffer_tracking (hdr-modesetfrombusy)
[106.329092] Starting subtest: hdr-modesetfrombusy
[106.333672] Subtest hdr-modesetfrombusy: SKIP (0.000s)
[106.429007] [026/156] (910s left) kms_big_fb (x-tiled-addfb)
[106.518273] Starting subtest: x-tiled-addfb
[106.520417] Subtest x-tiled-addfb: SUCCESS (0.000s)
[106.597727] [027/156] (910s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[106.690711] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[106.693219] Starting dynamic subtest: pipe-A-default-mode
[107.889714] Dynamic subtest pipe-A-default-mode: SKIP (1.195s)
[107.894109] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.196s)
[108.053403] [028/156] (909s left) gem_mmap_wc (copy)
[108.114208] Subtest copy: SKIP (0.000s)
[108.178638] [029/156] (909s left) gem_ctx_persistence (userptr)
[108.235120] Starting subtest: userptr
[108.469891] Subtest userptr: SUCCESS (0.234s)
[108.549827] [030/156] (908s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-pgflip-blt)
[108.649631] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-pgflip-blt
[108.653990] Subtest fbcpsrhdr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[108.739342] [031/156] (908s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[108.865073] Starting subtest: 2x-long-cursor-vs-flip-legacy
[108.869424] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.001s)
[109.029075] [032/156] (908s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[109.141715] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[115.982952] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.846s)
[116.130526] [033/156] (901s left) kms_feature_discovery (psr1)
[116.222130] Starting subtest: psr1
[116.224079] Subtest psr1: SUCCESS (0.000s)
[116.294447] [034/156] (901s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt)
[116.406582] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt
[116.410760] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[116.499503] [035/156] (901s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[116.546969] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[116.549208] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[116.636710] [036/156] (900s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[116.730073] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu
[116.734069] Subtest fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[116.807586] [037/156] (900s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[116.916625] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[116.920070] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[117.000272] [038/156] (900s left) kms_atomic (plane-primary-legacy)
[117.093906] Starting subtest: plane-primary-legacy
[117.095811] Starting dynamic subtest: pipe-A-eDP-1
[117.528035] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.433s)
[117.530432] Subtest plane-primary-legacy: SUCCESS (0.434s)
[118.420123] [039/156] (899s left) gem_eio (wait-immediate)
[118.653031] Starting subtest: wait-immediate
[119.015422] Subtest wait-immediate: SUCCESS (0.364s)
[119.138220] [040/156] (898s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[119.184462] Starting subtest: invalid-multi-wait-all-unsubmitted
[119.186790] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[119.286593] [041/156] (898s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[119.336917] Starting subtest: invalid-single-wait-all-unsubmitted
[119.339059] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[119.435628] [042/156] (898s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-blt)
[119.528597] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-blt
[119.532260] Subtest fbchdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[119.629594] [043/156] (898s left) kms_plane_scaling (intel-max-src-size)
[119.715923] Starting subtest: intel-max-src-size
[119.721416] Subtest intel-max-src-size: SKIP (0.000s)
[119.793156] [044/156] (898s left) i915_pm_rps (engine-order)
[119.854226] Starting subtest: engine-order
[125.133103] Subtest engine-order: SUCCESS (5.278s)
[125.270256] [045/156] (892s left) kms_flip (2x-plain-flip-fb-recreate)
[125.366437] Starting subtest: 2x-plain-flip-fb-recreate
[125.377628] Subtest 2x-plain-flip-fb-recreate: SKIP (0.010s)
[125.452700] [046/156] (892s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[125.520603] Starting subtest: y-tiled-to-vebox-y-tiled
[125.524952] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[125.704442] [047/156] (892s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[125.800338] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[125.802271] Starting dynamic subtest: pipe-A-eDP-1
[127.020033] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.221s)
[127.030924] Starting dynamic subtest: pipe-B-eDP-1
[129.310538] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.289s)
[129.319657] Starting dynamic subtest: pipe-C-eDP-1
[131.662069] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.350s)
[131.671439] Starting dynamic subtest: pipe-D-eDP-1
[133.971372] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.308s)
[133.973498] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (8.174s)
[135.139212] [048/156] (882s left) gem_exec_fence (syncobj-timeline-wait)
[135.705672] Starting subtest: syncobj-timeline-wait
[137.277459] Subtest syncobj-timeline-wait: SUCCESS (1.574s)
[139.358869] [FACT gem_exec_fence (syncobj-timeline-wait)] new: kernel.kmod_is_loaded.vgem: true
[139.362242] [049/156] (878s left) kms_invalid_mode (int-max-clock)
[139.458829] Starting subtest: int-max-clock
[139.461105] Starting dynamic subtest: pipe-A-eDP-1
[139.752546] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.292s)
[139.765075] Starting dynamic subtest: pipe-B-eDP-1
[139.767026] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[139.776434] Starting dynamic subtest: pipe-C-eDP-1
[139.778705] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[139.787697] Starting dynamic subtest: pipe-D-eDP-1
[139.789994] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[139.792320] Subtest int-max-clock: SUCCESS (0.298s)
[140.570308] [050/156] (877s left) kms_addfb_basic (invalid-set-prop)
[140.630644] Starting subtest: invalid-set-prop
[140.633095] Subtest invalid-set-prop: SUCCESS (0.000s)
[140.762987] [051/156] (877s left) kms_cursor_edge_walk (256x256-right-edge)
[140.852701] Starting subtest: 256x256-right-edge
[140.855229] Starting dynamic subtest: pipe-A-eDP-1
[144.334392] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.483s)
[144.343786] Starting dynamic subtest: pipe-D-eDP-1
[147.459486] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.122s)
[147.461422] Subtest 256x256-right-edge: SUCCESS (6.607s)
[148.284861] [052/156] (869s left) gem_sync (basic-store-all)
[148.361146] Starting subtest: basic-store-all
[152.594228] Subtest basic-store-all: SUCCESS (4.243s)
[152.780132] [053/156] (865s left) kms_flip (wf_vblank-ts-check)
[152.873300] Starting subtest: wf_vblank-ts-check
[152.875651] Starting dynamic subtest: A-eDP1
[161.808681] Dynamic subtest A-eDP1: SUCCESS (8.933s)
[161.819622] Starting dynamic subtest: B-eDP1
[170.743064] Dynamic subtest B-eDP1: SUCCESS (8.932s)
[170.754459] Starting dynamic subtest: C-eDP1
[179.653549] Dynamic subtest C-eDP1: SUCCESS (8.909s)
[179.664902] Starting dynamic subtest: D-eDP1
[188.586973] Dynamic subtest D-eDP1: SUCCESS (8.933s)
[188.591676] Subtest wf_vblank-ts-check: SUCCESS (35.718s)
[189.820946] [054/156] (828s left) gem_userptr_blits (probe)
[189.897119] Starting subtest: probe
[189.976646] Subtest probe: SUCCESS (0.079s)
[190.115304] [055/156] (828s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[190.201769] Starting subtest: fbc-pr-cursor-mmap-cpu
[190.206224] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[190.273618] [056/156] (828s left) prime_vgem (fence-read-hang)
[190.337394] Subtest fence-read-hang: SKIP (0.000s)
[190.422261] [057/156] (827s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[190.514187] Starting subtest: basic-modeset-with-all-modifiers-formats
[190.899046] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[191.701593] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.803s)
[191.710786] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[191.723703] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[191.735244] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[191.745741] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[191.755151] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[191.901880] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.155s)
[191.912384] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[191.923686] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[191.933131] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[191.946679] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[191.956147] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[191.968270] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[191.977758] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[191.990529] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[192.001870] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[192.012603] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[192.021813] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[192.035113] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[192.044396] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[192.057194] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[192.066628] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[192.079452] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.021s)
[192.090413] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[192.235024] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s)
[192.245219] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[192.257138] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.021s)
[192.266497] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[192.279485] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[192.288798] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[192.301313] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.020s)
[192.311503] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[192.323849] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.021s)
[192.333118] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[192.346068] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.021s)
[192.361574] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[192.368376] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[192.379980] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[192.390615] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.021s)
[192.399850] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[192.412592] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[192.422374] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[192.568765] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.155s)
[192.579759] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[192.591102] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.021s)
[192.602074] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[192.613246] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[192.622205] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[192.634988] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.021s)
[192.643967] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[192.658432] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[192.667342] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[192.679691] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.021s)
[192.688128] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[192.701479] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.021s)
[192.710027] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[192.723858] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[192.732669] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[192.746041] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[193.125973] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.614s)
[193.985778] [058/156] (824s left) kms_content_protection (atomic-dpms)
[194.133686] Starting subtest: atomic-dpms
[194.138127] Subtest atomic-dpms: SKIP (0.000s)
[194.260753] [059/156] (824s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[194.358812] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[194.363171] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[194.435152] [060/156] (824s left) kms_ccs (crc-primary-basic-4-tiled-dg2-mc-ccs)
[194.520078] Starting subtest: crc-primary-basic-4-tiled-dg2-mc-ccs
[194.522276] Starting dynamic subtest: pipe-A-eDP-1
[194.544406] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[194.555761] Starting dynamic subtest: pipe-B-eDP-1
[194.576774] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[194.585861] Starting dynamic subtest: pipe-C-eDP-1
[194.607358] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[194.616363] Starting dynamic subtest: pipe-D-eDP-1
[194.638699] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[194.642810] Subtest crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[196.842715] [061/156] (821s left) gem_lmem_swapping (parallel-random-engines)
[196.887037] Subtest parallel-random-engines: SKIP (0.000s)
[196.931811] [062/156] (821s left) perf_pmu (busy-start)
[196.973620] Starting subtest: busy-start
[196.975746] Starting dynamic subtest: rcs0
[199.525741] Dynamic subtest rcs0: SUCCESS (2.551s)
[199.537705] Starting dynamic subtest: bcs0
[202.076752] Dynamic subtest bcs0: SUCCESS (2.548s)
[202.088782] Starting dynamic subtest: vcs0
[205.313779] Dynamic subtest vcs0: SUCCESS (3.234s)
[205.325540] Starting dynamic subtest: vcs1
[209.282700] Dynamic subtest vcs1: SUCCESS (3.966s)
[209.296440] Starting dynamic subtest: vecs0
[213.314501] Dynamic subtest vecs0: SUCCESS (4.029s)
[213.325946] Starting dynamic subtest: ccs0
[215.864221] Dynamic subtest ccs0: SUCCESS (2.549s)
[215.866677] Subtest busy-start: SUCCESS (18.892s)
[215.973540] [063/156] (802s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[216.071696] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-wc
[216.075954] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[216.178166] [064/156] (802s left) gem_ccs (suspend-resume)
[216.236641] Starting subtest: suspend-resume
[216.241040] Subtest suspend-resume: SKIP (0.000s)
[216.345407] [065/156] (802s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[216.446073] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[216.450397] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[216.553695] [066/156] (802s left) gen7_exec_parse (batch-without-end)
[216.604664] Subtest batch-without-end: SKIP (0.000s)
[216.675466] [067/156] (802s left) kms_dp_linktrain_fallback (dp-fallback)
[216.772209] Starting subtest: dp-fallback
[216.778476] Subtest dp-fallback: SKIP (0.000s)
[216.846235] [068/156] (802s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[216.933884] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[216.937436] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[217.028494] [069/156] (801s left) api_intel_allocator (simple-allocator)
[217.078638] Starting subtest: simple-allocator
[217.080355] Starting dynamic subtest: basic
[217.082086] Dynamic subtest basic: SUCCESS (0.006s)
[217.093670] Starting dynamic subtest: parallel-one
[217.270679] Dynamic subtest parallel-one: SUCCESS (0.185s)
[217.281375] Starting dynamic subtest: print
[217.283717] Dynamic subtest print: SUCCESS (0.000s)
[217.293029] Starting dynamic subtest: reuse
[217.295936] Dynamic subtest reuse: SUCCESS (0.000s)
[217.304952] Starting dynamic subtest: reserve
[217.307411] Dynamic subtest reserve: SUCCESS (0.000s)
[217.316418] Starting dynamic subtest: fork-reopen-allocator
[217.318725] Dynamic subtest fork-reopen-allocator: SUCCESS (0.015s)
[217.320878] Subtest simple-allocator: SUCCESS (0.215s)
[217.413477] [070/156] (801s left) syncobj_basic (create-signaled)
[217.464324] Starting subtest: create-signaled
[217.466682] Subtest create-signaled: SUCCESS (0.000s)
[217.560763] [071/156] (801s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[217.652320] Starting subtest: fbc-psr-cursor-mmap-cpu
[217.654244] Starting dynamic subtest: eDP-1
[217.658400] Dynamic subtest eDP-1: SKIP (0.000s)
[217.662489] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[217.738931] [072/156] (801s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[217.838074] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[218.506648] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.670s)
[218.668691] [073/156] (800s left) prime_vgem (basic-read)
[218.743802] Starting subtest: basic-read
[218.754605] Subtest basic-read: SKIP (0.012s)
[218.890334] [074/156] (800s left) kms_flip (2x-flip-vs-dpms)
[218.985204] Starting subtest: 2x-flip-vs-dpms
[218.993500] Subtest 2x-flip-vs-dpms: SKIP (0.010s)
[219.076273] [075/156] (800s left) syncobj_timeline (single-wait-available-submitted)
[219.134691] Starting subtest: single-wait-available-submitted
[219.136556] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[219.232799] [076/156] (799s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[219.337211] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[219.341557] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[219.427442] [077/156] (799s left) gem_eio (in-flight-1us)
[219.642432] Starting subtest: in-flight-1us
[227.940159] Subtest in-flight-1us: SUCCESS (8.298s)
[228.120978] [078/156] (791s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[228.227576] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[228.231835] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[228.346842] [079/156] (790s left) i915_pm_freq_api (freq-reset)
[228.417055] Starting subtest: freq-reset
[228.419248] Starting dynamic subtest: gt0
[228.600411] Dynamic subtest gt0: SUCCESS (0.184s)
[228.612309] Starting dynamic subtest: gt1
[228.757704] Dynamic subtest gt1: SUCCESS (0.155s)
[228.760335] Subtest freq-reset: SUCCESS (0.342s)
[228.831706] [080/156] (790s left) gem_pwrite (basic-random)
[228.885399] Subtest basic-random: SKIP (0.000s)
[228.942182] [081/156] (790s left) gem_busy (semaphore)
[229.123300] Subtest semaphore: SKIP (0.000s)
[229.347900] [082/156] (790s left) kms_vrr (seamless-rr-switch-virtual)
[229.447228] Starting subtest: seamless-rr-switch-virtual
[229.453779] Subtest seamless-rr-switch-virtual: SKIP (0.000s)
[229.520188] [083/156] (789s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[229.629442] Starting subtest: y-tiled-64bpp-rotate-0
[229.633733] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[229.727666] [084/156] (789s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[229.831566] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[229.835809] Subtest psrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[229.925748] [085/156] (789s left) kms_psr (fbc-psr2-sprite-blt)
[230.013162] Starting subtest: fbc-psr2-sprite-blt
[230.015062] Starting dynamic subtest: eDP-1
[230.019079] Dynamic subtest eDP-1: SKIP (0.000s)
[230.023079] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[230.102536] [086/156] (789s left) gem_exec_fence (submit67)
[230.284692] Starting subtest: submit67
[230.289536] Subtest submit67: SKIP (0.000s)
[232.376299] [087/156] (787s left) gem_lmem_swapping (smem-oom)
[232.418705] Subtest smem-oom: SKIP (0.000s)
[232.460879] [088/156] (787s left) kms_psr (psr-cursor-render)
[232.551844] Starting subtest: psr-cursor-render
[232.554061] Starting dynamic subtest: eDP-1
[234.235196] Dynamic subtest eDP-1: SUCCESS (1.684s)
[234.237809] Subtest psr-cursor-render: SUCCESS (1.685s)
[235.115857] [089/156] (784s left) gem_exec_schedule (semaphore-user)
[235.187805] Starting subtest: semaphore-user
[235.241180] Subtest semaphore-user: SUCCESS (0.060s)
[236.330206] [090/156] (783s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-render)
[236.428069] Starting subtest: fbchdr-rgb101010-draw-render
[236.432483] Subtest fbchdr-rgb101010-draw-render: SKIP (0.000s)
[236.536422] [091/156] (783s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[236.632311] Starting subtest: 2x-flip-vs-rmfb-interruptible
[236.644074] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.010s)
[236.731517] [092/156] (783s left) gem_exec_suspend (basic-s0)
[236.789918] Starting subtest: basic-S0
[236.792342] Starting dynamic subtest: smem
[257.546069] Dynamic subtest smem: SUCCESS (7.462s)
[257.549353] Subtest basic-S0: SUCCESS (7.464s)
[257.741762] [093/156] (762s left) gem_exec_params (invalid-ring2)
[257.819978] Starting subtest: invalid-ring2
[257.822292] Subtest invalid-ring2: SUCCESS (0.000s)
[257.923954] [094/156] (762s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[258.016582] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[258.021224] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[258.141834] [095/156] (761s left) gem_close_race (basic-process)
[258.202326] Starting subtest: basic-process
[258.253954] Subtest basic-process: SUCCESS (0.052s)
[258.441748] [096/156] (761s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-move)
[258.541833] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-move
[258.546399] Subtest psrhdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[258.645005] [097/156] (761s left) kms_prime (basic-crc-hybrid)
[258.729742] Subtest basic-crc-hybrid: SKIP (0.000s)
[258.831438] [098/156] (761s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[258.936362] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[258.940351] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[259.036732] [099/156] (761s left) vgem_basic (bad-flag)
[259.086760] Starting subtest: bad-flag
[259.088826] Subtest bad-flag: SUCCESS (0.000s)
[259.140371] [100/156] (761s left) gem_exec_schedule (pi-userfault)
[259.313241] Starting subtest: pi-userfault
[259.315302] Starting dynamic subtest: rcs0
[259.322153] Dynamic subtest rcs0: SUCCESS (0.014s)
[259.337175] Starting dynamic subtest: bcs0
[259.339404] Dynamic subtest bcs0: SUCCESS (0.010s)
[259.349536] Starting dynamic subtest: vcs0
[259.351778] Dynamic subtest vcs0: SUCCESS (0.006s)
[259.364495] Starting dynamic subtest: vcs1
[259.366354] Dynamic subtest vcs1: SUCCESS (0.008s)
[259.377917] Starting dynamic subtest: vecs0
[259.380034] Dynamic subtest vecs0: SUCCESS (0.008s)
[259.389702] Starting dynamic subtest: ccs0
[259.391784] Dynamic subtest ccs0: SUCCESS (0.007s)
[259.393866] Subtest pi-userfault: SUCCESS (0.060s)
[259.553059] [101/156] (760s left) i915_query (engine-info-invalid)
[259.609571] Starting subtest: engine-info-invalid
[259.611916] Subtest engine-info-invalid: SUCCESS (0.000s)
[259.724598] [102/156] (760s left) gem_eio (context-create)
[259.946120] Starting subtest: context-create
[260.280090] Subtest context-create: SUCCESS (0.336s)
[260.445990] [103/156] (759s left) gem_workarounds (reset-context)
[260.515252] Starting subtest: reset-context
[260.694913] Subtest reset-context: SUCCESS (0.182s)
[260.816958] [104/156] (759s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[260.913838] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[268.215592] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.357s)
[268.375855] [105/156] (752s left) gem_spin_batch (spin-each)
[268.431576] Starting subtest: spin-each
[271.705492] Subtest spin-each: SUCCESS (3.277s)
[273.690062] [106/156] (746s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[273.805395] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[273.809886] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[273.895416] [107/156] (746s left) kms_chamelium_hpd (common-hpd-after-suspend)
[274.282719] Subtest common-hpd-after-suspend: SKIP (0.000s)
[275.098620] [108/156] (745s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[275.223058] Starting subtest: cursorB-vs-flipB-legacy
[275.227484] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[275.365373] [109/156] (745s left) kms_cursor_crc (cursor-onscreen-64x21)
[275.569825] Starting subtest: cursor-onscreen-64x21
[275.622410] Subtest cursor-onscreen-64x21: SKIP (0.045s)
[275.882144] [110/156] (744s left) sw_sync (sync_busy_fork_unixsocket)
[275.918497] Starting subtest: sync_busy_fork_unixsocket
[275.925754] Subtest sync_busy_fork_unixsocket: SUCCESS (0.008s)
[276.016158] [111/156] (744s left) prime_self_import (basic-with_fd_dup)
[276.047719] Starting subtest: basic-with_fd_dup
[276.063507] Subtest basic-with_fd_dup: SUCCESS (0.015s)
[276.168772] [112/156] (744s left) kms_frontbuffer_tracking (fbchdr-farfromfence-mmap-gtt)
[276.263943] Starting subtest: fbchdr-farfromfence-mmap-gtt
[276.267355] Subtest fbchdr-farfromfence-mmap-gtt: SKIP (0.000s)
[276.353586] [113/156] (744s left) gem_mmap_offset (close-race)
[276.411140] Starting subtest: close-race
[296.520544] Subtest close-race: SUCCESS (20.119s)
[296.686181] [114/156] (724s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[296.795925] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[297.453198] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.659s)
[297.615233] [115/156] (723s left) kms_plane (pixel-format-yf-tiled-modifier)
[297.706147] Starting subtest: pixel-format-yf-tiled-modifier
[297.710561] Subtest pixel-format-yf-tiled-modifier: SKIP (0.000s)
[297.781491] [116/156] (723s left) kms_vrr (lobf)
[297.874742] Starting subtest: lobf
[297.879192] Subtest lobf: SKIP (0.000s)
[297.960363] [117/156] (722s left) core_debugfs (read-all-entries)
[298.017973] Starting subtest: read-all-entries
[298.114448] Subtest read-all-entries: SUCCESS (0.096s)
[298.179749] [118/156] (722s left) prime_vgem (sync)
[298.255388] Starting subtest: sync
[298.257731] Starting dynamic subtest: rcs0
[298.317928] Dynamic subtest rcs0: SUCCESS (0.062s)
[298.331052] Starting dynamic subtest: bcs0
[298.385412] Dynamic subtest bcs0: SUCCESS (0.067s)
[298.395261] Starting dynamic subtest: vcs0
[298.431253] Dynamic subtest vcs0: SUCCESS (0.045s)
[298.442219] Starting dynamic subtest: vcs1
[298.479490] Dynamic subtest vcs1: SUCCESS (0.047s)
[298.489479] Starting dynamic subtest: vecs0
[298.525445] Dynamic subtest vecs0: SUCCESS (0.045s)
[298.535761] Starting dynamic subtest: ccs0
[298.571041] Dynamic subtest ccs0: SUCCESS (0.045s)
[298.573043] Subtest sync: SUCCESS (0.316s)
[298.703355] [119/156] (722s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-pwrite)
[298.800546] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-pwrite
[298.804031] Subtest fbchdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[298.900997] [120/156] (722s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[298.997235] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[299.000848] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[299.087725] [121/156] (722s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[299.192587] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[299.196159] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[299.296523] [122/156] (721s left) gem_exec_suspend (basic-s3)
[299.351159] Starting subtest: basic-S3
[299.352969] Starting dynamic subtest: smem
[319.553272] Dynamic subtest smem: SUCCESS (6.285s)
[319.556531] Subtest basic-S3: SUCCESS (6.287s)
[319.735744] [123/156] (701s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[319.852569] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[324.346421] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.554s)
[325.502415] [124/156] (695s left) kms_color (ctm-0-75)
[325.597805] Starting subtest: ctm-0-75
[325.599741] Starting dynamic subtest: pipe-A-eDP-1
[330.494738] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.924s)
[330.505985] Starting dynamic subtest: pipe-B-eDP-1
[336.208106] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.731s)
[336.219444] Starting dynamic subtest: pipe-C-eDP-1
[341.552254] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.352s)
[341.563456] Starting dynamic subtest: pipe-D-eDP-1
[346.890354] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.343s)
[346.892730] Subtest ctm-0-75: SUCCESS (21.354s)
[347.710194] [125/156] (673s left) kms_psr2_sf (pr-overlay-primary-update-sf-dmg-area)
[347.821157] Starting subtest: pr-overlay-primary-update-sf-dmg-area
[347.826009] Subtest pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[347.891900] [126/156] (673s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[347.942869] Starting subtest: multi-wait-available-unsubmitted-submitted
[347.945058] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[348.040838] [127/156] (673s left) dmabuf_sync_file (export-multiwait)
[348.081138] Starting subtest: export-multiwait
[348.083527] Subtest export-multiwait: SUCCESS (0.001s)
[348.151661] [128/156] (673s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[348.258035] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[348.262415] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[348.333524] [129/156] (673s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[348.421109] Starting subtest: bad-pixel-format-4-tiled-mtl-mc-ccs
[348.423441] Starting dynamic subtest: pipe-A-eDP-1
[348.444052] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[348.456942] Starting dynamic subtest: pipe-B-eDP-1
[348.475887] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[348.486492] Starting dynamic subtest: pipe-C-eDP-1
[348.505430] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[348.516443] Starting dynamic subtest: pipe-D-eDP-1
[348.534963] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[348.536988] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SUCCESS (0.009s)
[350.760684] [130/156] (670s left) gem_exec_parallel (basic)
[350.814646] Starting subtest: basic
[350.817162] Starting dynamic subtest: rcs0
[352.277009] Dynamic subtest rcs0: SUCCESS (1.461s)
[352.289196] Starting dynamic subtest: bcs0
[353.778572] Dynamic subtest bcs0: SUCCESS (1.499s)
[353.791942] Starting dynamic subtest: vcs0
[355.534363] Dynamic subtest vcs0: SUCCESS (1.750s)
[355.549264] Starting dynamic subtest: vcs1
[357.568210] Dynamic subtest vcs1: SUCCESS (2.031s)
[357.581660] Starting dynamic subtest: vecs0
[359.552899] Dynamic subtest vecs0: SUCCESS (1.981s)
[359.564709] Starting dynamic subtest: ccs0
[361.062440] Dynamic subtest ccs0: SUCCESS (1.508s)
[361.064876] Subtest basic: SUCCESS (10.244s)
[361.263729] [131/156] (660s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[361.382552] Starting subtest: cursorB-vs-flipA-atomic
[361.387126] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[361.542132] [132/156] (660s left) gem_exec_balancer (full-pulse)
[361.612053] Starting subtest: full-pulse
[366.488891] Subtest full-pulse: SUCCESS (4.876s)
[366.833753] [133/156] (654s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-cpu)
[366.941760] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-cpu
[366.946117] Subtest fbcpsrhdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[367.057728] [134/156] (654s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[367.151900] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[369.667183] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (2.520s)
[370.881612] [135/156] (650s left) kms_chamelium_frames (dp-crc-fast)
[371.286467] Subtest dp-crc-fast: SKIP (0.000s)
[372.087332] [136/156] (649s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[372.177064] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[372.189882] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[372.266691] [137/156] (649s left) gem_softpin (invalid)
[372.322987] Starting subtest: invalid
[372.328128] Subtest invalid: SUCCESS (0.006s)
[372.457119] [138/156] (649s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-plflip-blt)
[372.547764] Starting subtest: hdr-1p-primscrn-shrfb-plflip-blt
[372.551755] Subtest hdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[372.672906] [139/156] (649s left) gem_ctx_persistence (engines-hang)
[372.724047] Starting subtest: engines-hang
[372.726189] Starting dynamic subtest: rcs0
[372.850080] Dynamic subtest rcs0: SUCCESS (0.125s)
[372.864401] Starting dynamic subtest: bcs0
[372.952423] Dynamic subtest bcs0: SUCCESS (0.100s)
[372.965564] Starting dynamic subtest: vcs0
[373.037120] Dynamic subtest vcs0: SUCCESS (0.083s)
[373.049449] Starting dynamic subtest: vcs1
[373.126534] Dynamic subtest vcs1: SUCCESS (0.087s)
[373.139555] Starting dynamic subtest: vecs0
[373.221634] Dynamic subtest vecs0: SUCCESS (0.092s)
[373.234241] Starting dynamic subtest: ccs0
[373.314411] Dynamic subtest ccs0: SUCCESS (0.091s)
[373.316679] Subtest engines-hang: SUCCESS (0.592s)
[373.398548] [140/156] (648s left) syncobj_wait (wait-all-complex)
[373.457723] Starting subtest: wait-all-complex
[373.670461] Subtest wait-all-complex: SUCCESS (0.213s)
[373.749702] [141/156] (648s left) kms_atomic_interruptible (legacy-pageflip)
[373.842627] Starting subtest: legacy-pageflip
[373.844971] Starting dynamic subtest: pipe-A-eDP-1
[381.224019] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.382s)
[381.226303] Subtest legacy-pageflip: SUCCESS (7.383s)
[382.071230] [142/156] (639s left) syncobj_wait (invalid-single-wait-unsubmitted)
[382.131988] Starting subtest: invalid-single-wait-unsubmitted
[382.134005] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[382.244003] [143/156] (639s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[382.332985] Starting subtest: compare-crc-sanitycheck-nv12
[382.335274] Starting dynamic subtest: pipe-A-eDP-1
[383.037713] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.705s)
[383.051026] Starting dynamic subtest: pipe-B-eDP-1
[384.347962] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.308s)
[384.359080] Starting dynamic subtest: pipe-C-eDP-1
[385.647401] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.296s)
[385.657908] Starting dynamic subtest: pipe-D-eDP-1
[386.879779] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.234s)
[386.882230] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.550s)
[387.697362] [144/156] (634s left) prime_mmap (test_errors)
[387.751357] Starting subtest: test_errors
[387.753299] Starting dynamic subtest: test_errors-smem
[387.845631] Dynamic subtest test_errors-smem: SUCCESS (0.096s)
[387.848465] Subtest test_errors: SUCCESS (0.096s)
[387.940825] [145/156] (634s left) syncobj_eventfd (invalid-illegal-handle)
[387.988407] Starting subtest: invalid-illegal-handle
[387.990694] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[388.113678] [146/156] (634s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt)
[388.215148] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt
[388.219074] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[388.315227] [147/156] (633s left) kms_psr (psr-sprite-mmap-cpu)
[388.413882] Starting subtest: psr-sprite-mmap-cpu
[388.416212] Starting dynamic subtest: eDP-1
[390.186411] Dynamic subtest eDP-1: SUCCESS (1.773s)
[390.188796] Subtest psr-sprite-mmap-cpu: SUCCESS (1.774s)
[391.070254] [148/156] (631s left) kms_sequence (get-forked)
[391.174033] Starting subtest: get-forked
[391.176019] Starting dynamic subtest: pipe-A-eDP-1
[393.049444] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.877s)
[393.061021] Starting dynamic subtest: pipe-B-eDP-1
[395.577082] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.527s)
[395.587723] Starting dynamic subtest: pipe-C-eDP-1
[398.102855] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.524s)
[398.114616] Starting dynamic subtest: pipe-D-eDP-1
[400.661620] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.558s)
[400.663909] Subtest get-forked: SUCCESS (9.491s)
[401.485596] [149/156] (620s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[401.763941] Starting subtest: parallel-dmabuf-import-out-fence
[402.834810] Subtest parallel-dmabuf-import-out-fence: SUCCESS (1.071s)
[402.935295] [150/156] (619s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[403.021872] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[403.023998] Starting dynamic subtest: pipe-A
[403.085084] Dynamic subtest pipe-A: SUCCESS (0.065s)
[403.097633] Starting dynamic subtest: pipe-B
[404.217228] Dynamic subtest pipe-B: SUCCESS (1.131s)
[404.230754] Starting dynamic subtest: pipe-C
[405.339542] Dynamic subtest pipe-C: SUCCESS (1.121s)
[405.351111] Starting dynamic subtest: pipe-D
[406.458380] Dynamic subtest pipe-D: SUCCESS (1.118s)
[406.460793] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.440s)
[407.610055] [151/156] (614s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[407.715305] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[414.592917] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.883s)
[414.720827] [152/156] (607s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[414.828098] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[422.293055] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.466s)
[422.455917] [153/156] (600s left) i915_drm_fdinfo (all-busy-check-all)
[422.498941] Starting subtest: all-busy-check-all
[422.503414] Subtest all-busy-check-all: SKIP (0.004s)
[422.576249] [154/156] (599s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[422.702520] Starting subtest: fbc-psr2-cursor-plane-update-sf
[422.704747] Starting dynamic subtest: pipe-A-eDP-1
[422.777385] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[422.790800] Starting dynamic subtest: pipe-B-eDP-1
[423.942430] Dynamic subtest pipe-B-eDP-1: SKIP (1.163s)
[423.947130] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.244s)
[425.267419] [155/156] (597s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-linear)
[425.369596] Starting subtest: fbcpsrhdr-tiling-linear
[425.373626] Subtest fbcpsrhdr-tiling-linear: SKIP (0.000s)
[425.478878] [156/156] (597s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[425.576891] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[425.581023] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[425.655475] Closing watchdogs
results: parsing output: 0/ for test: gem_userptr_blits
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_pm_dc
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: kms_plane_multiple
results: parsing output: 6/ for test: gem_userptr_blits
results: parsing output: 7/ for test: syncobj_timeline
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: kms_getfb
results: parsing output: 12/ for test: kms_big_fb
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: gem_render_copy
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_exec_flush
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_cursor_crc
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: kms_ccs
results: parsing output: 23/ for test: kms_pipe_crc_basic
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: kms_flip_scaled_crc
results: parsing output: 27/ for test: gem_mmap_wc
results: parsing output: 28/ for test: gem_ctx_persistence
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_cursor_legacy
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_feature_discovery
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_atomic
results: parsing output: 38/ for test: gem_eio
results: parsing output: 39/ for test: syncobj_wait
results: parsing output: 40/ for test: syncobj_wait
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_plane_scaling
results: parsing output: 43/ for test: i915_pm_rps
results: parsing output: 44/ for test: kms_flip
results: parsing output: 45/ for test: gem_render_copy
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: gem_exec_fence
results: parsing output: 48/ for test: kms_invalid_mode
results: parsing output: 49/ for test: kms_addfb_basic
results: parsing output: 50/ for test: kms_cursor_edge_walk
results: parsing output: 51/ for test: gem_sync
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: gem_userptr_blits
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: prime_vgem
results: parsing output: 56/ for test: kms_async_flips
results: parsing output: 57/ for test: kms_content_protection
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: gem_lmem_swapping
results: parsing output: 61/ for test: perf_pmu
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_ccs
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gen7_exec_parse
results: parsing output: 66/ for test: kms_dp_linktrain_fallback
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: api_intel_allocator
results: parsing output: 69/ for test: syncobj_basic
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: prime_vgem
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_eio
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: i915_pm_freq_api
results: parsing output: 79/ for test: gem_pwrite
results: parsing output: 80/ for test: gem_busy
results: parsing output: 81/ for test: kms_vrr
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_psr
results: parsing output: 85/ for test: gem_exec_fence
results: parsing output: 86/ for test: gem_lmem_swapping
results: parsing output: 87/ for test: kms_psr
results: parsing output: 88/ for test: gem_exec_schedule
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_flip
results: parsing output: 91/ for test: gem_exec_suspend
results: parsing output: 92/ for test: gem_exec_params
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: gem_close_race
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_prime
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: vgem_basic
results: parsing output: 99/ for test: gem_exec_schedule
results: parsing output: 100/ for test: i915_query
results: parsing output: 101/ for test: gem_eio
results: parsing output: 102/ for test: gem_workarounds
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_spin_batch
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_chamelium_hpd
results: parsing output: 107/ for test: kms_cursor_legacy
results: parsing output: 108/ for test: kms_cursor_crc
results: parsing output: 109/ for test: sw_sync
results: parsing output: 110/ for test: prime_self_import
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gem_mmap_offset
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: kms_plane
results: parsing output: 115/ for test: kms_vrr
results: parsing output: 116/ for test: core_debugfs
results: parsing output: 117/ for test: prime_vgem
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_exec_suspend
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_color
results: parsing output: 124/ for test: kms_psr2_sf
results: parsing output: 125/ for test: syncobj_timeline
results: parsing output: 126/ for test: dmabuf_sync_file
results: parsing output: 127/ for test: kms_flip_scaled_crc
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: gem_exec_parallel
results: parsing output: 130/ for test: kms_cursor_legacy
results: parsing output: 131/ for test: gem_exec_balancer
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_chamelium_frames
results: parsing output: 135/ for test: kms_flip
results: parsing output: 136/ for test: gem_softpin
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_ctx_persistence
results: parsing output: 139/ for test: syncobj_wait
results: parsing output: 140/ for test: kms_atomic_interruptible
results: parsing output: 141/ for test: syncobj_wait
results: parsing output: 142/ for test: kms_pipe_crc_basic
results: parsing output: 143/ for test: prime_mmap
results: parsing output: 144/ for test: syncobj_eventfd
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_psr
results: parsing output: 147/ for test: kms_sequence
results: parsing output: 148/ for test: gem_exec_balancer
results: parsing output: 149/ for test: kms_plane_scaling
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: i915_drm_fdinfo
results: parsing output: 153/ for test: kms_psr2_sf
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.