[57.727959] Initializing watchdogs
[57.728033]   /dev/watchdog0
[57.742664] [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]
[57.747501] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.749795] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.752862] [001/133] (960s left) perf_pmu (busy)
[57.818518] Starting subtest: busy
[57.820773] Starting dynamic subtest: rcs0
[58.412588] Dynamic subtest rcs0: SUCCESS (0.590s)
[58.422252] Starting dynamic subtest: bcs0
[58.979945] Dynamic subtest bcs0: SUCCESS (0.561s)
[58.990214] Starting dynamic subtest: vcs0
[60.886427] Dynamic subtest vcs0: SUCCESS (1.890s)
[60.895798] Starting dynamic subtest: vcs1
[62.935033] Dynamic subtest vcs1: SUCCESS (2.035s)
[62.942881] Starting dynamic subtest: vecs0
[64.918505] Dynamic subtest vecs0: SUCCESS (1.975s)
[64.928770] Starting dynamic subtest: ccs0
[65.482453] Dynamic subtest ccs0: SUCCESS (0.562s)
[65.485039] Subtest busy: SUCCESS (7.621s)
[65.578962] [002/133] (952s left) gem_unfence_active_buffers
[65.739711] [003/133] (952s left) syncobj_timeline (reset-multiple-signaled)
[65.808079] Starting subtest: reset-multiple-signaled
[65.810501] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[65.916035] [004/133] (952s left) gem_fence_thrash (bo-write-verify-none)
[65.948590] Starting subtest: bo-write-verify-none
[65.979914] Subtest bo-write-verify-none: SKIP (0.029s)
[66.037992] [005/133] (952s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[66.143600] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[73.228769] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (7.073s)
[73.385586] [006/133] (945s left) gem_ctx_persistence (heartbeat-close)
[73.460102] Starting subtest: heartbeat-close
[73.464872] Subtest heartbeat-close: SKIP (0.000s)
[73.571689] [007/133] (944s left) gem_ctx_param (root-set)
[73.630906] Starting subtest: root-set
[73.633265] Subtest root-set: SUCCESS (0.001s)
[73.738029] [008/133] (944s left) kms_getfb (getfb-handle-not-fb)
[73.828047] Starting subtest: getfb-handle-not-fb
[73.830416] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[73.947196] [009/133] (944s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[74.053624] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[74.058742] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[74.143657] [010/133] (944s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[74.377566] Starting subtest: cursor-rapid-movement-256x256
[74.380072] Starting dynamic subtest: pipe-A-eDP-1
[74.939771] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.556s)
[74.966532] Starting dynamic subtest: pipe-D-eDP-1
[76.117454] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.154s)
[76.119669] Subtest cursor-rapid-movement-256x256: SUCCESS (1.738s)
[76.972396] [011/133] (941s left) gem_ctx_engines (execute-one)
[77.032063] Starting subtest: execute-one
[79.401204] Subtest execute-one: SUCCESS (2.368s)
[79.934105] [012/133] (938s left) kms_color (ctm-green-to-red)
[80.024747] Starting subtest: ctm-green-to-red
[80.026666] Starting dynamic subtest: pipe-A-eDP-1
[80.734201] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s)
[80.743514] Starting dynamic subtest: pipe-B-eDP-1
[82.201592] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.465s)
[82.210378] Starting dynamic subtest: pipe-C-eDP-1
[83.530176] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.328s)
[83.538155] Starting dynamic subtest: pipe-D-eDP-1
[84.897877] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.367s)
[84.900316] Subtest ctm-green-to-red: SUCCESS (4.870s)
[85.707590] [013/133] (932s left) kms_invalid_mode (clock-too-high)
[85.808577] Starting subtest: clock-too-high
[85.810960] Starting dynamic subtest: pipe-A-eDP-1
[85.815253] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[85.824264] Starting dynamic subtest: pipe-B-eDP-1
[85.828667] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[85.835806] Starting dynamic subtest: pipe-C-eDP-1
[85.839888] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[85.846525] Starting dynamic subtest: pipe-D-eDP-1
[85.850628] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[85.854700] Subtest clock-too-high: SKIP (0.005s)
[85.887213] [014/133] (932s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[88.016546] Starting subtest: dpms-mode-unset-non-lpsp
[89.173986] Subtest dpms-mode-unset-non-lpsp: SKIP (1.156s)
[89.549704] [015/133] (929s left) kms_psr (psr-sprite-plane-onoff)
[89.653077] Starting subtest: psr-sprite-plane-onoff
[89.655470] Starting dynamic subtest: eDP-1
[91.490228] Dynamic subtest eDP-1: SUCCESS (1.835s)
[91.492533] Subtest psr-sprite-plane-onoff: SUCCESS (1.836s)
[92.297403] [016/133] (926s left) gem_spin_batch (resubmit)
[92.351923] Starting subtest: resubmit
[92.354185] Starting dynamic subtest: rcs0
[92.359589] Dynamic subtest rcs0: SUCCESS (0.007s)
[92.368434] Starting dynamic subtest: bcs0
[92.370580] Dynamic subtest bcs0: SUCCESS (0.002s)
[92.378179] Starting dynamic subtest: vcs0
[92.380306] Dynamic subtest vcs0: SUCCESS (0.004s)
[92.387128] Starting dynamic subtest: vcs1
[92.389254] Dynamic subtest vcs1: SUCCESS (0.003s)
[92.396120] Starting dynamic subtest: vecs0
[92.398382] Dynamic subtest vecs0: SUCCESS (0.002s)
[92.405596] Starting dynamic subtest: ccs0
[92.407862] Dynamic subtest ccs0: SUCCESS (0.003s)
[92.410095] Subtest resubmit: SUCCESS (0.024s)
[92.588424] [017/133] (926s left) gen9_exec_parse (batch-without-end)
[92.652264] Subtest batch-without-end: SKIP (0.000s)
[92.720965] [018/133] (926s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[92.779057] Starting subtest: single-wait-all-for-submit-unsubmitted
[92.882133] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.102s)
[92.959617] [019/133] (925s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[93.070302] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[93.072622] Starting dynamic subtest: pipe-A-eDP-1
[98.989560] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.919s)
[98.997454] Starting dynamic subtest: pipe-B-eDP-1
[105.645598] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.655s)
[105.647821] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.575s)
[106.519577] [020/133] (912s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[106.616616] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[106.992128] Starting dynamic subtest: pipe-A-eDP-1
[109.840829] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.788s)
[110.157340] Starting dynamic subtest: pipe-B-eDP-1
[112.957650] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.754s)
[113.291785] Starting dynamic subtest: pipe-C-eDP-1
[116.092881] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.757s)
[116.406049] Starting dynamic subtest: pipe-D-eDP-1
[119.223951] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.772s)
[119.226712] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.554s)
[120.368960] [021/133] (898s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[120.463104] Starting subtest: fbc-pr-sprite-mmap-cpu
[120.467367] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[120.542242] [022/133] (898s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[120.640362] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[128.477349] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.838s)
[128.622842] [023/133] (890s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[128.728077] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[128.732280] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[128.827346] [024/133] (890s left) tools_test (tools_test)
[128.876874] Starting subtest: tools_test
[128.935071] Subtest tools_test: SUCCESS (0.073s)
[128.996475] [025/133] (890s left) gem_exec_schedule (pi-ringfull)
[129.168610] Starting subtest: pi-ringfull
[129.171021] Starting dynamic subtest: rcs0
[129.228654] Dynamic subtest rcs0: SUCCESS (0.065s)
[129.241998] Starting dynamic subtest: bcs0
[129.256111] Dynamic subtest bcs0: SUCCESS (0.027s)
[129.267738] Starting dynamic subtest: vcs0
[129.283658] Dynamic subtest vcs0: SUCCESS (0.027s)
[129.294946] Starting dynamic subtest: vcs1
[129.311049] Dynamic subtest vcs1: SUCCESS (0.027s)
[129.322713] Starting dynamic subtest: vecs0
[129.338896] Dynamic subtest vecs0: SUCCESS (0.027s)
[129.350724] Starting dynamic subtest: ccs0
[129.367467] Dynamic subtest ccs0: SUCCESS (0.028s)
[129.369713] Subtest pi-ringfull: SUCCESS (0.204s)
[129.594907] [FACT gem_exec_schedule (pi-ringfull)] new: kernel.kmod_is_loaded.vgem: true
[129.598485] [026/133] (889s left) kms_async_flips (test-cursor-atomic)
[129.678204] Starting subtest: test-cursor-atomic
[130.061418] Starting dynamic subtest: pipe-A-eDP-1
[130.909323] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.849s)
[131.300908] Starting dynamic subtest: pipe-B-eDP-1
[132.047401] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.753s)
[132.434347] Starting dynamic subtest: pipe-C-eDP-1
[133.183546] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.756s)
[133.560971] Starting dynamic subtest: pipe-D-eDP-1
[134.321446] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.766s)
[134.323778] Subtest test-cursor-atomic: SUCCESS (4.645s)
[135.490470] [027/133] (883s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[135.604363] Starting subtest: cursorA-vs-flipB-varying-size
[135.608866] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[135.750540] [028/133] (883s left) i915_drm_fdinfo (busy-idle)
[135.791722] Starting subtest: busy-idle
[135.794096] Starting dynamic subtest: rcs0
[135.836330] Dynamic subtest rcs0: SKIP (0.042s)
[135.846662] Starting dynamic subtest: bcs0
[135.851207] Dynamic subtest bcs0: SKIP (0.001s)
[135.858712] Starting dynamic subtest: vcs0
[135.862752] Dynamic subtest vcs0: SKIP (0.000s)
[135.870146] Starting dynamic subtest: vcs1
[135.874613] Dynamic subtest vcs1: SKIP (0.000s)
[135.882272] Starting dynamic subtest: vecs0
[135.886573] Dynamic subtest vecs0: SKIP (0.000s)
[135.893771] Starting dynamic subtest: ccs0
[135.898165] Dynamic subtest ccs0: SKIP (0.000s)
[135.902510] Subtest busy-idle: SKIP (0.048s)
[135.937713] [029/133] (883s left) gem_exec_parallel (engines)
[136.003859] Starting subtest: engines
[136.006285] Starting dynamic subtest: basic
[137.880063] Dynamic subtest basic: SUCCESS (1.874s)
[137.888942] Starting dynamic subtest: contexts
[142.312044] Dynamic subtest contexts: SUCCESS (4.429s)
[142.322889] Starting dynamic subtest: fds
[146.054335] Dynamic subtest fds: SUCCESS (3.740s)
[146.065822] Starting dynamic subtest: userptr
[147.861906] Dynamic subtest userptr: SUCCESS (1.805s)
[147.865033] Subtest engines: SUCCESS (11.856s)
[148.054164] [030/133] (871s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[148.148035] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[148.150415] Starting dynamic subtest: pipe-A
[148.168462] Dynamic subtest pipe-A: SKIP (0.016s)
[148.181677] Starting dynamic subtest: pipe-B
[148.197606] Dynamic subtest pipe-B: SKIP (0.028s)
[148.209803] Starting dynamic subtest: pipe-C
[148.227923] Dynamic subtest pipe-C: SKIP (0.029s)
[148.237897] Starting dynamic subtest: pipe-D
[148.255491] Dynamic subtest pipe-D: SKIP (0.028s)
[148.259301] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.104s)
[148.374526] [031/133] (871s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[148.429131] Starting subtest: wait-all-for-submit-delayed-submit
[148.529864] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[148.619130] [032/133] (870s left) perf (invalid-create-userspace-config)
[148.762288] Starting subtest: invalid-create-userspace-config
[148.764592] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[148.801991] [033/133] (870s left) gem_exec_fence (keep-in-fence)
[148.973277] Starting subtest: keep-in-fence
[148.975860] Starting dynamic subtest: rcs0
[149.035450] Dynamic subtest rcs0: SUCCESS (0.063s)
[149.047631] Starting dynamic subtest: bcs0
[149.088704] Dynamic subtest bcs0: SUCCESS (0.051s)
[149.098674] Starting dynamic subtest: vcs0
[150.934253] Dynamic subtest vcs0: SUCCESS (1.842s)
[150.952941] Starting dynamic subtest: vcs1
[152.918330] Dynamic subtest vcs1: SUCCESS (1.982s)
[152.929551] Starting dynamic subtest: vecs0
[154.953061] Dynamic subtest vecs0: SUCCESS (1.982s)
[154.963442] Starting dynamic subtest: ccs0
[155.142253] Dynamic subtest ccs0: SUCCESS (0.239s)
[155.144726] Subtest keep-in-fence: SUCCESS (6.170s)
[156.973345] [034/133] (862s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[157.082129] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[164.307709] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.228s)
[164.444666] [035/133] (855s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[164.492313] Starting subtest: etime-single-wait-available-unsubmitted
[164.593190] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.101s)
[164.666081] [036/133] (854s left) kms_dsc (dsc-with-output-formats-with-bpc)
[164.758222] Starting subtest: dsc-with-output-formats-with-bpc
[164.814454] Subtest dsc-with-output-formats-with-bpc: SKIP (0.001s)
[164.850650] [037/133] (854s left) gem_exec_reloc (basic-range)
[164.906232] Subtest basic-range: SKIP (0.000s)
[164.974404] [038/133] (854s left) gem_lmem_swapping (heavy-verify-multi)
[165.016405] Subtest heavy-verify-multi: SKIP (0.000s)
[165.069116] [039/133] (854s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[165.154347] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[166.133072] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.978s)
[166.244146] [040/133] (853s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[166.347095] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[173.887217] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.545s)
[174.043829] [041/133] (845s left) gem_flink_basic (basic)
[174.100789] Starting subtest: basic
[174.103147] Subtest basic: SUCCESS (0.000s)
[174.184967] [042/133] (845s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[174.286302] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[174.288532] Starting dynamic subtest: pipe-A
[174.623939] Dynamic subtest pipe-A: SUCCESS (0.337s)
[174.636045] Starting dynamic subtest: pipe-B
[176.020859] Dynamic subtest pipe-B: SUCCESS (1.395s)
[176.028907] Starting dynamic subtest: pipe-C
[177.454078] Dynamic subtest pipe-C: SUCCESS (1.431s)
[177.462526] Starting dynamic subtest: pipe-D
[178.893969] Dynamic subtest pipe-D: SUCCESS (1.439s)
[178.896312] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.609s)
[180.057767] [043/133] (839s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[180.163549] Starting subtest: y-tiled-64bpp-rotate-180
[180.167790] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[180.279795] [044/133] (839s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[180.342761] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[180.347742] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[180.525509] [045/133] (839s left) gem_exec_params (invalid-ring2)
[180.596605] Starting subtest: invalid-ring2
[180.599045] Subtest invalid-ring2: SUCCESS (0.000s)
[180.702654] [046/133] (839s left) gem_exec_fence (basic-busy)
[180.880189] Starting subtest: basic-busy
[180.882636] Starting dynamic subtest: rcs0
[180.944332] Dynamic subtest rcs0: SUCCESS (0.064s)
[180.954736] Starting dynamic subtest: bcs0
[181.009521] Dynamic subtest bcs0: SUCCESS (0.063s)
[181.019089] Starting dynamic subtest: vcs0
[181.062549] Dynamic subtest vcs0: SUCCESS (0.051s)
[181.071862] Starting dynamic subtest: vcs1
[181.117481] Dynamic subtest vcs1: SUCCESS (0.053s)
[181.133804] Starting dynamic subtest: vecs0
[181.180742] Dynamic subtest vecs0: SUCCESS (0.061s)
[181.193073] Starting dynamic subtest: ccs0
[181.246602] Dynamic subtest ccs0: SUCCESS (0.063s)
[181.249087] Subtest basic-busy: SUCCESS (0.368s)
[183.029266] [047/133] (836s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[183.122962] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[183.125102] Starting dynamic subtest: pipe-A-eDP-1
[183.144606] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[183.153430] Starting dynamic subtest: pipe-B-eDP-1
[183.171951] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[183.179607] Starting dynamic subtest: pipe-C-eDP-1
[183.198309] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[183.205803] Starting dynamic subtest: pipe-D-eDP-1
[183.225123] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[183.227266] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[185.434596] [048/133] (834s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[185.504884] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[185.565460] [049/133] (834s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[185.666996] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[185.671085] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[185.776449] [050/133] (834s left) kms_vblank (query-forked-hang)
[185.872368] Starting subtest: query-forked-hang
[185.874688] Starting dynamic subtest: pipe-A-eDP-1
[213.668651] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.790s)
[213.681886] Starting dynamic subtest: pipe-D-eDP-1
[228.709528] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.038s)
[228.711702] Subtest query-forked-hang: SUCCESS (42.833s)
[229.502245] [051/133] (790s left) gem_exec_reloc (basic-write-wc)
[229.559936] Subtest basic-write-wc: SKIP (0.000s)
[229.641438] [052/133] (790s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[229.735510] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[229.739733] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[229.832021] [053/133] (790s left) kms_flip (basic-plain-flip)
[229.936587] Starting subtest: basic-plain-flip
[229.938764] Starting dynamic subtest: A-eDP1
[231.600454] Dynamic subtest A-eDP1: SUCCESS (1.663s)
[231.610586] Starting dynamic subtest: B-eDP1
[233.239663] Dynamic subtest B-eDP1: SUCCESS (1.638s)
[233.252942] Starting dynamic subtest: C-eDP1
[234.859300] Dynamic subtest C-eDP1: SUCCESS (1.617s)
[234.871153] Starting dynamic subtest: D-eDP1
[236.488521] Dynamic subtest D-eDP1: SUCCESS (1.626s)
[236.494896] Subtest basic-plain-flip: SUCCESS (6.558s)
[237.658084] [054/133] (782s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[237.747504] Starting subtest: flip-vs-dpms-off-vs-modeset
[237.749721] Starting dynamic subtest: A-eDP1
[241.394637] Dynamic subtest A-eDP1: SUCCESS (3.646s)
[241.403924] Starting dynamic subtest: B-eDP1
[244.576071] Dynamic subtest B-eDP1: SUCCESS (3.179s)
[244.584579] Starting dynamic subtest: C-eDP1
[247.686245] Dynamic subtest C-eDP1: SUCCESS (3.107s)
[247.695152] Starting dynamic subtest: D-eDP1
[250.798791] Dynamic subtest D-eDP1: SUCCESS (3.112s)
[250.800966] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.051s)
[251.612993] [055/133] (768s left) gem_exec_params (cliprects-invalid)
[251.668701] Starting subtest: cliprects-invalid
[251.671134] Subtest cliprects-invalid: SUCCESS (0.000s)
[251.773606] [056/133] (768s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[251.873428] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[251.877763] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[251.971875] [057/133] (768s left) kms_lease (lease-invalid-connector)
[252.065940] Starting subtest: lease-invalid-connector
[252.068216] Starting dynamic subtest: pipe-A-eDP-1
[252.070497] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[252.078209] Starting dynamic subtest: pipe-B-eDP-1
[252.080441] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[252.088028] Starting dynamic subtest: pipe-C-eDP-1
[252.089937] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[252.097584] Starting dynamic subtest: pipe-D-eDP-1
[252.099771] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[252.102022] Subtest lease-invalid-connector: SUCCESS (0.004s)
[252.138097] [058/133] (768s left) gem_ctx_shared (single-timeline)
[252.202667] Starting subtest: single-timeline
[252.222947] Subtest single-timeline: SUCCESS (0.014s)
[252.460857] [059/133] (768s left) gem_exec_params (rel-constants-invalid-ring)
[252.527037] Starting subtest: rel-constants-invalid-ring
[252.529655] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[252.620138] [060/133] (767s left) gem_exec_balancer (parallel-bb-first)
[252.884904] Starting subtest: parallel-bb-first
[253.803979] Subtest parallel-bb-first: SUCCESS (0.919s)
[254.978243] [061/133] (765s left) gem_pxp (reject-modify-context-protection-off-3)
[255.088861] Starting subtest: reject-modify-context-protection-off-3
[255.091591] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[255.216270] [062/133] (765s left) gem_userptr_blits (readonly-unsync)
[255.288348] Subtest readonly-unsync: SKIP (0.000s)
[255.407558] [063/133] (765s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[255.515604] Starting subtest: x-tiled-64bpp-rotate-90
[255.535598] Subtest x-tiled-64bpp-rotate-90: SKIP (0.020s)
[255.634417] [064/133] (765s left) i915_hangman (engine-error-state-capture)
[255.702180] Starting subtest: engine-error-state-capture
[255.704030] Starting dynamic subtest: rcs0
[258.868335] Dynamic subtest rcs0: SUCCESS (3.164s)
[258.878796] Starting dynamic subtest: bcs0
[264.883922] Dynamic subtest bcs0: SUCCESS (6.012s)
[264.893772] Starting dynamic subtest: vcs0
[270.836532] Dynamic subtest vcs0: SUCCESS (5.949s)
[270.847826] Starting dynamic subtest: vcs1
[275.828064] Dynamic subtest vcs1: SUCCESS (4.989s)
[275.839195] Starting dynamic subtest: vecs0
[280.820278] Dynamic subtest vecs0: SUCCESS (4.989s)
[280.831801] Starting dynamic subtest: ccs0
[285.877094] Dynamic subtest ccs0: SUCCESS (5.053s)
[285.880012] Subtest engine-error-state-capture: SUCCESS (30.174s)
[285.962685] [065/133] (734s left) api_intel_allocator (fork-simple-once)
[286.032657] Starting subtest: fork-simple-once
[286.051698] Subtest fork-simple-once: SUCCESS (0.020s)
[286.179528] [066/133] (734s left) kms_hdr (brightness-with-hdr)
[286.267648] Starting subtest: brightness-with-hdr
[286.273753] Subtest brightness-with-hdr: SKIP (0.000s)
[286.354464] [067/133] (734s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[286.461150] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[286.465238] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[286.556927] [068/133] (734s left) syncobj_wait (reset-signaled)
[286.625474] Starting subtest: reset-signaled
[286.627769] Subtest reset-signaled: SUCCESS (0.000s)
[286.737893] [069/133] (734s left) kms_psr (psr2-basic)
[286.833188] Starting subtest: psr2-basic
[286.835497] Starting dynamic subtest: eDP-1
[288.463402] Dynamic subtest eDP-1: SUCCESS (1.630s)
[288.465646] Subtest psr2-basic: SUCCESS (1.631s)
[289.276414] [070/133] (731s left) kms_cursor_crc (cursor-suspend)
[289.409088] Starting subtest: cursor-suspend
[289.411359] Starting dynamic subtest: pipe-A-eDP-1
[321.459318] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.257s)
[321.468918] Starting dynamic subtest: pipe-D-eDP-1
[355.508871] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.679s)
[355.511205] Subtest cursor-suspend: SUCCESS (6.939s)
[356.366672] [071/133] (664s left) device_reset (cold-reset-bound)
[356.416499] Starting subtest: cold-reset-bound
[356.421371] Subtest cold-reset-bound: SKIP (0.000s)
[356.501763] [072/133] (664s left) gem_busy (semaphore)
[356.696821] Subtest semaphore: SKIP (0.000s)
[356.938545] [073/133] (664s left) kms_panel_fitting (legacy)
[357.031519] Starting subtest: legacy
[357.033841] Starting dynamic subtest: pipe-A-eDP-1
[357.601695] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.554s)
[357.630448] Starting dynamic subtest: pipe-B-eDP-1
[359.256290] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.591s)
[359.284038] Starting dynamic subtest: pipe-C-eDP-1
[360.864304] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.553s)
[360.891009] Starting dynamic subtest: pipe-D-eDP-1
[362.389090] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.477s)
[362.391228] Subtest legacy: SUCCESS (5.236s)
[363.669408] [074/133] (657s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[363.767783] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[363.770300] Starting dynamic subtest: pipe-A-eDP-1
[363.841076] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[363.850563] Starting dynamic subtest: pipe-B-eDP-1
[364.947482] Dynamic subtest pipe-B-eDP-1: SKIP (1.091s)
[364.951857] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.170s)
[366.151763] [075/133] (655s left) kms_plane_cursor (viewport)
[366.238871] Starting subtest: viewport
[366.243030] Starting dynamic subtest: pipe-A-eDP-1-size-64
[368.702460] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.443s)
[368.763830] Starting dynamic subtest: pipe-A-eDP-1-size-128
[371.220636] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.448s)
[371.280122] Starting dynamic subtest: pipe-A-eDP-1-size-256
[373.732145] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.449s)
[374.084848] Starting dynamic subtest: pipe-B-eDP-1-size-64
[377.241237] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.158s)
[377.302553] Starting dynamic subtest: pipe-B-eDP-1-size-128
[379.726655] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.427s)
[379.787450] Starting dynamic subtest: pipe-B-eDP-1-size-256
[382.243422] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.460s)
[382.591796] Starting dynamic subtest: pipe-C-eDP-1-size-64
[385.426306] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.842s)
[385.467827] Starting dynamic subtest: pipe-C-eDP-1-size-128
[387.568048] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.113s)
[387.606409] Starting dynamic subtest: pipe-C-eDP-1-size-256
[389.701893] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.103s)
[390.028237] Starting dynamic subtest: pipe-D-eDP-1-size-64
[392.805336] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.787s)
[392.847608] Starting dynamic subtest: pipe-D-eDP-1-size-128
[394.960209] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.125s)
[394.998155] Starting dynamic subtest: pipe-D-eDP-1-size-256
[397.083115] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.091s)
[397.400558] Subtest viewport: SUCCESS (31.089s)
[398.238382] [076/133] (623s left) gem_exec_whisper (basic-fds)
[398.315707] Starting subtest: basic-fds
[404.209856] Subtest basic-fds: SUCCESS (5.891s)
[404.390218] [077/133] (616s left) kms_psr (psr-cursor-mmap-cpu)
[404.483029] Starting subtest: psr-cursor-mmap-cpu
[404.485379] Starting dynamic subtest: eDP-1
[406.220037] Dynamic subtest eDP-1: SUCCESS (1.735s)
[406.222296] Subtest psr-cursor-mmap-cpu: SUCCESS (1.736s)
[407.092190] [078/133] (614s left) gem_ctx_persistence (engines-persistence)
[407.161850] Starting subtest: engines-persistence
[407.164509] Starting dynamic subtest: rcs0
[407.973741] Dynamic subtest rcs0: SUCCESS (0.811s)
[407.987638] Starting dynamic subtest: bcs0
[408.738048] Dynamic subtest bcs0: SUCCESS (0.761s)
[408.749645] Starting dynamic subtest: vcs0
[409.515711] Dynamic subtest vcs0: SUCCESS (0.775s)
[409.527377] Starting dynamic subtest: vcs1
[410.275883] Dynamic subtest vcs1: SUCCESS (0.757s)
[410.287524] Starting dynamic subtest: vecs0
[411.051835] Dynamic subtest vecs0: SUCCESS (0.773s)
[411.063470] Starting dynamic subtest: ccs0
[411.814870] Dynamic subtest ccs0: SUCCESS (0.760s)
[411.817528] Subtest engines-persistence: SUCCESS (4.653s)
[411.894857] [079/133] (609s left) syncobj_eventfd (invalid-bad-pad)
[411.947015] Starting subtest: invalid-bad-pad
[411.949022] Subtest invalid-bad-pad: SUCCESS (0.000s)
[412.037454] [080/133] (609s left) kms_flip (absolute-wf_vblank)
[412.129802] Starting subtest: absolute-wf_vblank
[412.132072] Starting dynamic subtest: A-eDP1
[420.890152] Dynamic subtest A-eDP1: SUCCESS (8.721s)
[420.900980] Starting dynamic subtest: B-eDP1
[429.625928] Dynamic subtest B-eDP1: SUCCESS (8.731s)
[429.639094] Starting dynamic subtest: C-eDP1
[438.355851] Dynamic subtest C-eDP1: SUCCESS (8.714s)
[438.365383] Starting dynamic subtest: D-eDP1
[447.098167] Dynamic subtest D-eDP1: SUCCESS (8.735s)
[447.100305] Subtest absolute-wf_vblank: SUCCESS (34.913s)
[448.264487] [081/133] (573s left) gem_pxp (fail-invalid-protected-context)
[448.372273] Starting subtest: fail-invalid-protected-context
[448.374652] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[448.487873] [082/133] (573s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[448.580617] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[448.585002] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[449.696214] [083/133] (571s left) kms_big_fb (linear-16bpp-rotate-90)
[449.790790] Starting subtest: linear-16bpp-rotate-90
[449.812178] Subtest linear-16bpp-rotate-90: SKIP (0.018s)
[449.936392] [084/133] (571s left) gem_exec_reloc (basic-softpin)
[450.004117] Subtest basic-softpin: SKIP (0.000s)
[450.077713] [085/133] (571s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[450.169420] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[450.173684] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[450.270494] [086/133] (571s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[450.406695] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[450.411090] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[450.586174] [087/133] (571s left) prime_self_import (basic-llseek-size)
[450.630664] Starting subtest: basic-llseek-size
[450.646391] Subtest basic-llseek-size: SUCCESS (0.021s)
[450.742589] [088/133] (571s left) perf_pmu (semaphore-wait-idle)
[450.780514] Starting subtest: semaphore-wait-idle
[450.782405] Starting dynamic subtest: rcs0
[451.295382] Dynamic subtest rcs0: SUCCESS (0.513s)
[451.308859] Starting dynamic subtest: bcs0
[451.808847] Dynamic subtest bcs0: SUCCESS (0.510s)
[451.820180] Starting dynamic subtest: vcs0
[452.322080] Dynamic subtest vcs0: SUCCESS (0.510s)
[452.333191] Starting dynamic subtest: vcs1
[452.834187] Dynamic subtest vcs1: SUCCESS (0.510s)
[452.843712] Starting dynamic subtest: vecs0
[453.347586] Dynamic subtest vecs0: SUCCESS (0.510s)
[453.359000] Starting dynamic subtest: ccs0
[453.863198] Dynamic subtest ccs0: SUCCESS (0.513s)
[453.866018] Subtest semaphore-wait-idle: SUCCESS (3.084s)
[455.029896] [089/133] (566s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[455.128568] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[455.133003] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[455.215259] [090/133] (566s left) sw_sync (alloc_fence_invalid_timeline)
[455.254392] Starting subtest: alloc_fence_invalid_timeline
[455.256780] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[455.302983] [091/133] (566s left) gem_mmap_offset (mmap-boundaries)
[455.358023] Starting subtest: mmap-boundaries
[455.360205] Starting dynamic subtest: smem0
[455.639720] Dynamic subtest smem0: SUCCESS (0.283s)
[455.642203] Subtest mmap-boundaries: SUCCESS (0.284s)
[455.759294] [092/133] (566s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt)
[455.867985] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt
[455.872494] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[455.970643] [093/133] (566s left) gen3_render_mixed_blits
[456.086538] [094/133] (565s left) gem_fence_thrash (bo-write-verify-x)
[456.122518] Starting subtest: bo-write-verify-x
[456.141707] Subtest bo-write-verify-x: SKIP (0.021s)
[456.205570] [095/133] (565s left) gem_lmem_swapping (verify-random)
[456.248921] Subtest verify-random: SKIP (0.000s)
[456.293845] [096/133] (565s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[456.395684] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[456.400088] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[456.494273] [097/133] (565s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[456.595143] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[456.599282] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.000s)
[456.670091] [098/133] (565s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[456.761454] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[456.764803] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[456.868928] [099/133] (565s left) kms_cursor_crc (cursor-onscreen-256x256)
[457.091378] Starting subtest: cursor-onscreen-256x256
[457.095681] Starting dynamic subtest: pipe-A-eDP-1
[459.199521] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.103s)
[459.239249] Starting dynamic subtest: pipe-D-eDP-1
[461.947571] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.726s)
[461.949836] Subtest cursor-onscreen-256x256: SUCCESS (4.856s)
[462.752985] [100/133] (559s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[462.864038] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[462.908936] [101/133] (559s left) i915_query (query-regions-unallocated)
[462.965055] Starting subtest: query-regions-unallocated
[462.966933] Subtest query-regions-unallocated: SUCCESS (0.000s)
[463.080350] [102/133] (559s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[463.182580] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[463.186895] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[463.282290] [103/133] (559s left) kms_psr (fbc-psr-cursor-plane-move)
[463.371895] Starting subtest: fbc-psr-cursor-plane-move
[463.374042] Starting dynamic subtest: eDP-1
[463.377921] Dynamic subtest eDP-1: SKIP (0.000s)
[463.381791] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[463.443467] [104/133] (558s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[463.533201] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[463.537259] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[463.635380] [105/133] (558s left) gem_exec_schedule (submit-golden-slice)
[463.701675] Starting subtest: submit-golden-slice
[463.703590] Starting dynamic subtest: rcs0
[463.734317] Dynamic subtest rcs0: SUCCESS (0.036s)
[463.746762] Starting dynamic subtest: bcs0
[463.761192] Dynamic subtest bcs0: SUCCESS (0.027s)
[463.769949] Starting dynamic subtest: vcs0
[463.792472] Dynamic subtest vcs0: SUCCESS (0.030s)
[463.801395] Starting dynamic subtest: vcs1
[463.824229] Dynamic subtest vcs1: SUCCESS (0.031s)
[463.832801] Starting dynamic subtest: vecs0
[463.853720] Dynamic subtest vecs0: SUCCESS (0.029s)
[463.867654] Starting dynamic subtest: ccs0
[463.884851] Dynamic subtest ccs0: SUCCESS (0.030s)
[463.886796] Subtest submit-golden-slice: SUCCESS (0.188s)
[464.961180] [106/133] (557s left) kms_content_protection (suspend-resume)
[465.094693] Starting subtest: suspend-resume
[465.099066] Subtest suspend-resume: SKIP (0.000s)
[465.207360] [107/133] (557s left) api_intel_bb (render)
[465.274987] Starting subtest: render
[465.276839] Starting dynamic subtest: render-none-512
[465.374636] Dynamic subtest render-none-512: SUCCESS (0.101s)
[465.386943] Starting dynamic subtest: render-none-1024
[465.746345] Dynamic subtest render-none-1024: SUCCESS (0.369s)
[465.756476] Starting dynamic subtest: render-x-512
[465.829488] Dynamic subtest render-x-512: SUCCESS (0.083s)
[465.839552] Starting dynamic subtest: render-x-1024
[466.164025] Dynamic subtest render-x-1024: SUCCESS (0.333s)
[466.175632] Starting dynamic subtest: render-y-512
[466.247550] Dynamic subtest render-y-512: SUCCESS (0.084s)
[466.258429] Starting dynamic subtest: render-y-1024
[466.585993] Dynamic subtest render-y-1024: SUCCESS (0.336s)
[466.588507] Subtest render: SUCCESS (1.312s)
[466.698787] [108/133] (555s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[466.790982] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[466.793123] Starting dynamic subtest: pipe-A-eDP-1
[466.814761] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[466.826058] Starting dynamic subtest: pipe-B-eDP-1
[466.846549] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[466.855014] Starting dynamic subtest: pipe-C-eDP-1
[466.875631] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[466.883923] Starting dynamic subtest: pipe-D-eDP-1
[466.904642] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[466.908439] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[469.015087] [109/133] (553s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[469.119114] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[469.123462] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[470.243654] [110/133] (552s left) kms_rotation_crc (primary-rotation-180)
[470.340639] Starting subtest: primary-rotation-180
[477.311147] Subtest primary-rotation-180: SUCCESS (6.973s)
[478.502873] [111/133] (544s left) perf_pmu (init-wait)
[478.549764] Starting subtest: init-wait
[478.552310] Starting dynamic subtest: rcs0
[478.554978] Dynamic subtest rcs0: SUCCESS (0.001s)
[478.565221] Starting dynamic subtest: bcs0
[478.567477] Dynamic subtest bcs0: SUCCESS (0.000s)
[478.575994] Starting dynamic subtest: vcs0
[478.578260] Dynamic subtest vcs0: SUCCESS (0.001s)
[478.586736] Starting dynamic subtest: vcs1
[478.589052] Dynamic subtest vcs1: SUCCESS (0.001s)
[478.597230] Starting dynamic subtest: vecs0
[478.599464] Dynamic subtest vecs0: SUCCESS (0.001s)
[478.607980] Starting dynamic subtest: ccs0
[478.609922] Dynamic subtest ccs0: SUCCESS (0.000s)
[478.611933] Subtest init-wait: SUCCESS (0.008s)
[478.659823] [112/133] (544s left) gem_exec_reloc (basic-gtt-cpu-active)
[478.716004] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[478.782898] [113/133] (544s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[479.170112] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[480.016177] [114/133] (542s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[480.396178] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[481.247817] [115/133] (541s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[481.364411] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[481.368561] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[481.475458] [116/133] (541s left) i915_pm_rc6_residency (media-rc6-accuracy)
[481.530217] Starting subtest: media-rc6-accuracy
[481.534553] Subtest media-rc6-accuracy: SKIP (0.000s)
[481.645402] [117/133] (541s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[481.768445] Starting subtest: 2x-long-flip-vs-cursor-legacy
[481.773001] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[481.915709] [118/133] (541s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[482.001123] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[482.028588] Starting dynamic subtest: pipe-A-plane-0
[482.316807] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[482.325373] Starting dynamic subtest: pipe-A-plane-3
[482.550106] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[482.558600] Starting dynamic subtest: pipe-A-plane-5
[482.562196] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[483.661843] Starting dynamic subtest: pipe-B-plane-0
[483.943209] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[483.952159] Starting dynamic subtest: pipe-B-plane-3
[484.176362] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[484.187801] Starting dynamic subtest: pipe-B-plane-5
[484.192322] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[484.507161] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.504s)
[485.335135] [119/133] (537s left) syncobj_timeline (invalid-query-zero-handles)
[485.393117] Starting subtest: invalid-query-zero-handles
[485.395549] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[485.499708] [120/133] (537s left) kms_big_fb (4-tiled-addfb)
[485.610255] Starting subtest: 4-tiled-addfb
[485.612537] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[485.705043] [121/133] (537s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[485.801930] Starting subtest: 2x-plain-flip-ts-check-interruptible
[485.812862] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.010s)
[485.897472] [122/133] (537s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[485.990477] Starting subtest: pr-overlay-plane-update-continuous-sf
[485.994755] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[486.060830] [123/133] (537s left) kms_pipe_crc_basic (read-crc)
[486.157259] Starting subtest: read-crc
[486.159537] Starting dynamic subtest: pipe-A-eDP-1
[486.872843] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.716s)
[486.885208] Starting dynamic subtest: pipe-B-eDP-1
[488.217556] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.342s)
[488.227237] Starting dynamic subtest: pipe-C-eDP-1
[489.518299] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.298s)
[489.528164] Starting dynamic subtest: pipe-D-eDP-1
[490.851824] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.334s)
[490.854117] Subtest read-crc: SUCCESS (4.697s)
[491.681609] [124/133] (531s left) kms_chamelium_audio (dp-audio-edid)
[492.063387] Subtest dp-audio-edid: SKIP (0.000s)
[492.887599] [125/133] (530s left) kms_fbcon_fbt (fbc)
[492.965069] Starting subtest: fbc
[499.333843] Subtest fbc: SUCCESS (6.369s)
[500.412900] [126/133] (522s left) gem_userptr_blits (unsync-unmap)
[500.488926] Subtest unsync-unmap: SKIP (0.000s)
[500.625497] [127/133] (522s left) kms_properties (plane-properties-atomic)
[500.729165] Starting subtest: plane-properties-atomic
[500.730995] Starting dynamic subtest: pipe-A-eDP-1
[501.271435] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.542s)
[501.281702] Starting dynamic subtest: pipe-B-eDP-1
[502.592102] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.320s)
[502.602131] Starting dynamic subtest: pipe-C-eDP-1
[503.814191] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.218s)
[503.824165] Starting dynamic subtest: pipe-D-eDP-1
[505.058678] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.245s)
[505.061048] Subtest plane-properties-atomic: SUCCESS (4.331s)
[505.942796] [128/133] (517s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[506.038082] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[506.042380] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[506.128868] [129/133] (517s left) kms_atomic_interruptible (legacy-pageflip)
[506.229071] Starting subtest: legacy-pageflip
[506.231365] Starting dynamic subtest: pipe-A-eDP-1
[513.668417] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.435s)
[513.670307] Subtest legacy-pageflip: SUCCESS (7.437s)
[514.500549] [130/133] (508s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[514.610057] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[514.612340] Starting dynamic subtest: pipe-A-default-mode
[515.750808] Dynamic subtest pipe-A-default-mode: SKIP (1.139s)
[515.755174] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.140s)
[515.885143] [131/133] (507s left) sysfs_heartbeat_interval (idempotent)
[515.934796] Starting subtest: idempotent
[515.937089] Starting dynamic subtest: vecs0
[515.939419] Dynamic subtest vecs0: SUCCESS (0.001s)
[515.949811] Starting dynamic subtest: ccs0
[515.951730] Dynamic subtest ccs0: SUCCESS (0.001s)
[515.960560] Starting dynamic subtest: vcs0
[515.962442] Dynamic subtest vcs0: SUCCESS (0.000s)
[515.970743] Starting dynamic subtest: bcs0
[515.972635] Dynamic subtest bcs0: SUCCESS (0.001s)
[515.980745] Starting dynamic subtest: rcs0
[515.983041] Dynamic subtest rcs0: SUCCESS (0.001s)
[515.991447] Starting dynamic subtest: vcs1
[515.993648] Dynamic subtest vcs1: SUCCESS (0.001s)
[515.995842] Subtest idempotent: SUCCESS (0.010s)
[516.043763] [132/133] (507s left) kms_pm_rpm (system-suspend-modeset)
[518.072711] Starting subtest: system-suspend-modeset
[537.584525] Subtest system-suspend-modeset: SUCCESS (4.577s)
[537.978926] [133/133] (485s left) gem_vm_create (invalid-destroy)
[538.041891] Starting subtest: invalid-destroy
[538.044234] Subtest invalid-destroy: SUCCESS (0.002s)
[538.146221] Closing watchdogs
results: parsing output: 0/ for test: perf_pmu
results: parsing output: 1/ for test: gem_unfence_active_buffers
results: parsing output: 2/ for test: syncobj_timeline
results: parsing output: 3/ for test: gem_fence_thrash
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_ctx_persistence
results: parsing output: 6/ for test: gem_ctx_param
results: parsing output: 7/ for test: kms_getfb
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: kms_cursor_crc
results: parsing output: 10/ for test: gem_ctx_engines
results: parsing output: 11/ for test: kms_color
results: parsing output: 12/ for test: kms_invalid_mode
results: parsing output: 13/ for test: kms_pm_rpm
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: gem_spin_batch
results: parsing output: 16/ for test: gen9_exec_parse
results: parsing output: 17/ for test: syncobj_wait
results: parsing output: 18/ for test: kms_psr2_sf
results: parsing output: 19/ for test: kms_async_flips
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: tools_test
results: parsing output: 24/ for test: gem_exec_schedule
results: parsing output: 25/ for test: kms_async_flips
results: parsing output: 26/ for test: kms_cursor_legacy
results: parsing output: 27/ for test: i915_drm_fdinfo
results: parsing output: 28/ for test: gem_exec_parallel
results: parsing output: 29/ for test: kms_plane_scaling
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: perf
results: parsing output: 32/ for test: gem_exec_fence
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_dsc
results: parsing output: 36/ for test: gem_exec_reloc
results: parsing output: 37/ for test: gem_lmem_swapping
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gem_flink_basic
results: parsing output: 41/ for test: kms_plane_scaling
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: gem_render_copy
results: parsing output: 44/ for test: gem_exec_params
results: parsing output: 45/ for test: gem_exec_fence
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: gem_mmap_wc
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_vblank
results: parsing output: 50/ for test: gem_exec_reloc
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: gem_exec_params
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_lease
results: parsing output: 57/ for test: gem_ctx_shared
results: parsing output: 58/ for test: gem_exec_params
results: parsing output: 59/ for test: gem_exec_balancer
results: parsing output: 60/ for test: gem_pxp
results: parsing output: 61/ for test: gem_userptr_blits
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: i915_hangman
results: parsing output: 64/ for test: api_intel_allocator
results: parsing output: 65/ for test: kms_hdr
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: syncobj_wait
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_cursor_crc
results: parsing output: 70/ for test: device_reset
results: parsing output: 71/ for test: gem_busy
results: parsing output: 72/ for test: kms_panel_fitting
results: parsing output: 73/ for test: kms_psr2_sf
results: parsing output: 74/ for test: kms_plane_cursor
results: parsing output: 75/ for test: gem_exec_whisper
results: parsing output: 76/ for test: kms_psr
results: parsing output: 77/ for test: gem_ctx_persistence
results: parsing output: 78/ for test: syncobj_eventfd
results: parsing output: 79/ for test: kms_flip
results: parsing output: 80/ for test: gem_pxp
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: gem_exec_reloc
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_cursor_legacy
results: parsing output: 86/ for test: prime_self_import
results: parsing output: 87/ for test: perf_pmu
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: sw_sync
results: parsing output: 90/ for test: gem_mmap_offset
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gen3_render_mixed_blits
results: parsing output: 93/ for test: gem_fence_thrash
results: parsing output: 94/ for test: gem_lmem_swapping
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_cursor_crc
results: parsing output: 99/ for test: kms_tiled_display
results: parsing output: 100/ for test: i915_query
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: kms_content_protection
results: parsing output: 106/ for test: api_intel_bb
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: kms_ccs
results: parsing output: 109/ for test: kms_rotation_crc
results: parsing output: 110/ for test: perf_pmu
results: parsing output: 111/ for test: gem_exec_reloc
results: parsing output: 112/ for test: kms_chamelium_hpd
results: parsing output: 113/ for test: kms_chamelium_edid
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: i915_pm_rc6_residency
results: parsing output: 116/ for test: kms_cursor_legacy
results: parsing output: 117/ for test: kms_plane
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: kms_psr2_sf
results: parsing output: 122/ for test: kms_pipe_crc_basic
results: parsing output: 123/ for test: kms_chamelium_audio
results: parsing output: 124/ for test: kms_fbcon_fbt
results: parsing output: 125/ for test: gem_userptr_blits
results: parsing output: 126/ for test: kms_properties
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_atomic_interruptible
results: parsing output: 129/ for test: kms_flip_scaled_crc
results: parsing output: 130/ for test: sysfs_heartbeat_interval
results: parsing output: 131/ for test: kms_pm_rpm
results: parsing output: 132/ for test: gem_vm_create
Done.