[59.188662] Initializing watchdogs
[59.188710]   /dev/watchdog0
[59.202278] [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]
[59.206512] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.209428] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.212787] [001/133] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[59.340528] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[59.344571] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[59.436922] [002/133] (960s left) kms_async_flips (test-cursor-atomic)
[59.529961] Starting subtest: test-cursor-atomic
[59.904871] Starting dynamic subtest: pipe-A-eDP-1
[60.655672] Dynamic subtest pipe-A-eDP-1: SKIP (0.747s)
[61.030490] Starting dynamic subtest: pipe-B-eDP-1
[61.786828] Dynamic subtest pipe-B-eDP-1: SKIP (0.759s)
[62.161795] Starting dynamic subtest: pipe-C-eDP-1
[62.914222] Dynamic subtest pipe-C-eDP-1: SKIP (0.754s)
[63.282108] Starting dynamic subtest: pipe-D-eDP-1
[64.053046] Dynamic subtest pipe-D-eDP-1: SKIP (0.773s)
[64.057503] Subtest test-cursor-atomic: SKIP (4.519s)
[65.242269] [003/133] (954s left) kms_cursor_crc (cursor-dpms)
[65.363494] Starting subtest: cursor-dpms
[65.366293] Starting dynamic subtest: pipe-A-eDP-1
[68.397909] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.030s)
[68.410724] Starting dynamic subtest: pipe-D-eDP-1
[72.007318] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.607s)
[72.009289] Subtest cursor-dpms: SUCCESS (6.640s)
[72.865192] [004/133] (946s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[72.953135] Starting subtest: bad-pixel-format-4-tiled-mtl-mc-ccs
[72.955532] Starting dynamic subtest: pipe-A-eDP-1
[72.976697] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[72.986093] Starting dynamic subtest: pipe-B-eDP-1
[73.005663] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[73.012860] Starting dynamic subtest: pipe-C-eDP-1
[73.031822] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[73.039619] Starting dynamic subtest: pipe-D-eDP-1
[73.058558] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[73.060742] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SUCCESS (0.005s)
[75.232206] [005/133] (944s left) kms_psr (psr2-cursor-plane-move)
[75.328927] Starting subtest: psr2-cursor-plane-move
[75.331250] Starting dynamic subtest: eDP-1
[77.010546] Dynamic subtest eDP-1: SUCCESS (1.680s)
[77.012456] Subtest psr2-cursor-plane-move: SUCCESS (1.681s)
[77.891383] [006/133] (942s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[78.014142] Starting subtest: cursorA-vs-flipA-toggle
[89.142232] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.125s)
[90.027720] [007/133] (929s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[90.143211] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[90.901067] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.759s)
[91.059034] [008/133] (928s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[91.151899] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[92.154200] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.001s)
[92.263371] [009/133] (927s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[92.314278] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[92.419306] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[92.506237] [010/133] (927s left) kms_vrr (flipline)
[92.617128] Starting subtest: flipline
[92.623944] Subtest flipline: SKIP (0.000s)
[92.683500] [011/133] (927s left) syncobj_wait (multi-wait-all-submitted-signaled)
[92.743114] Starting subtest: multi-wait-all-submitted-signaled
[92.850821] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.107s)
[92.920960] [012/133] (927s left) kms_flip (wf_vblank-ts-check)
[93.017403] Starting subtest: wf_vblank-ts-check
[93.019850] Starting dynamic subtest: A-eDP1
[102.003099] Dynamic subtest A-eDP1: SUCCESS (8.954s)
[102.012620] Starting dynamic subtest: B-eDP1
[110.934768] Dynamic subtest B-eDP1: SUCCESS (8.929s)
[110.942910] Starting dynamic subtest: C-eDP1
[119.857173] Dynamic subtest C-eDP1: SUCCESS (8.911s)
[119.866626] Starting dynamic subtest: D-eDP1
[128.793915] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[128.796505] Subtest wf_vblank-ts-check: SUCCESS (35.736s)
[129.973105] [013/133] (890s left) gem_eio (in-flight-1us)
[130.187260] Starting subtest: in-flight-1us
[138.437282] Subtest in-flight-1us: SUCCESS (8.249s)
[138.566235] [FACT gem_eio (in-flight-1us)] new: kernel.kmod_is_loaded.vgem: true
[138.569771] [014/133] (881s left) syncobj_wait (invalid-wait-illegal-handle)
[138.625296] Starting subtest: invalid-wait-illegal-handle
[138.627626] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[138.714872] [015/133] (881s left) gem_ctx_isolation (preservation-reset)
[138.875885] Starting subtest: preservation-reset
[138.878178] Starting dynamic subtest: rcs0
[139.127178] Dynamic subtest rcs0: SUCCESS (0.254s)
[139.141940] Starting dynamic subtest: bcs0
[139.428409] Dynamic subtest bcs0: SUCCESS (0.301s)
[139.440340] Starting dynamic subtest: vcs0
[139.724332] Dynamic subtest vcs0: SUCCESS (0.295s)
[139.732413] Starting dynamic subtest: vcs1
[141.152807] Dynamic subtest vcs1: SUCCESS (1.427s)
[141.162267] Starting dynamic subtest: vecs0
[143.217507] Dynamic subtest vecs0: SUCCESS (2.064s)
[143.226913] Starting dynamic subtest: ccs0
[145.205960] Dynamic subtest ccs0: SUCCESS (1.988s)
[145.208533] Subtest preservation-reset: SUCCESS (6.335s)
[145.340849] [016/133] (874s left) kms_color (invalid-ctm-matrix-sizes)
[145.444930] Starting subtest: invalid-ctm-matrix-sizes
[145.447279] Starting dynamic subtest: pipe-A
[145.449517] Dynamic subtest pipe-A: SUCCESS (0.002s)
[145.458511] Starting dynamic subtest: pipe-B
[145.460706] Dynamic subtest pipe-B: SUCCESS (0.003s)
[145.468231] Starting dynamic subtest: pipe-C
[145.470478] Dynamic subtest pipe-C: SUCCESS (0.003s)
[145.477550] Starting dynamic subtest: pipe-D
[145.479407] Dynamic subtest pipe-D: SUCCESS (0.004s)
[145.481713] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.013s)
[145.524841] [017/133] (874s left) gem_spin_batch (legacy-resubmit)
[145.584767] Starting subtest: legacy-resubmit
[145.586996] Starting dynamic subtest: default
[145.589223] Dynamic subtest default: SUCCESS (0.006s)
[145.597861] Starting dynamic subtest: render
[145.600155] Dynamic subtest render: SUCCESS (0.001s)
[145.607902] Starting dynamic subtest: bsd
[145.609798] Dynamic subtest bsd: SUCCESS (0.003s)
[145.616769] Starting dynamic subtest: bsd1
[145.618584] Dynamic subtest bsd1: SUCCESS (0.003s)
[145.625520] Starting dynamic subtest: bsd2
[145.627403] Dynamic subtest bsd2: SUCCESS (0.001s)
[145.634297] Starting dynamic subtest: blt
[145.636211] Dynamic subtest blt: SUCCESS (0.003s)
[145.643094] Starting dynamic subtest: vebox
[145.644947] Dynamic subtest vebox: SUCCESS (0.003s)
[145.646879] Subtest legacy-resubmit: SUCCESS (0.024s)
[145.834424] [018/133] (874s left) syncobj_timeline (wait-all-for-submit-snapshot)
[145.890544] Starting subtest: wait-all-for-submit-snapshot
[145.971029] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[146.045462] [019/133] (874s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[146.147027] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[146.149025] Starting dynamic subtest: pipe-A-eDP-1
[146.167596] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[146.176925] Starting dynamic subtest: pipe-B-eDP-1
[146.193517] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[146.200384] Starting dynamic subtest: pipe-C-eDP-1
[146.217998] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[146.227773] Starting dynamic subtest: pipe-D-eDP-1
[146.245165] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[146.252400] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.003s)
[148.349337] [020/133] (872s left) syncobj_timeline (device-signal-unordered)
[148.403942] Starting subtest: device-signal-unordered
[148.406294] Subtest device-signal-unordered: SUCCESS (0.000s)
[148.496110] [021/133] (871s left) kms_cursor_edge_walk (128x128-right-edge)
[148.593961] Starting subtest: 128x128-right-edge
[148.596158] Starting dynamic subtest: pipe-A-eDP-1
[152.016492] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.421s)
[152.025060] Starting dynamic subtest: pipe-D-eDP-1
[155.132044] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.114s)
[155.134116] Subtest 128x128-right-edge: SUCCESS (6.538s)
[155.953284] [022/133] (864s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[156.061383] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[156.065655] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[156.152783] [023/133] (864s left) kms_getfb (getfb2-into-addfb2)
[156.258165] Starting subtest: getfb2-into-addfb2
[156.260405] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[156.366895] [024/133] (864s left) gem_exec_reloc (basic-gtt-read-active)
[156.430344] Subtest basic-gtt-read-active: SKIP (0.000s)
[156.494368] [025/133] (864s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[156.594413] Starting subtest: cursorB-vs-flipA-atomic
[156.599043] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[156.734185] [026/133] (863s left) kms_getfb (getfb-handle-valid)
[156.825997] Starting subtest: getfb-handle-valid
[156.828318] Subtest getfb-handle-valid: SUCCESS (0.000s)
[156.932069] [027/133] (863s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[157.025356] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[157.028907] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[157.136806] [028/133] (863s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[157.670858] Starting subtest: syncobj-timeline-invalid-wait
[157.673813] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.003s)
[159.015563] [029/133] (861s left) kms_chamelium_hpd (vga-hpd-fast)
[159.411361] Subtest vga-hpd-fast: SKIP (0.000s)
[160.261296] [030/133] (860s left) gem_exec_whisper (basic-fds-priority)
[160.330160] Starting subtest: basic-fds-priority
[184.510940] Subtest basic-fds-priority: SUCCESS (24.127s)
[184.642084] [031/133] (836s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[184.738656] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[184.740812] Starting dynamic subtest: pipe-A-eDP-1
[184.760874] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[184.771942] Starting dynamic subtest: pipe-B-eDP-1
[184.789980] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[184.797818] Starting dynamic subtest: pipe-C-eDP-1
[184.817540] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[184.825649] Starting dynamic subtest: pipe-D-eDP-1
[184.844692] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[184.846586] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.007s)
[187.039750] [032/133] (833s left) gem_exec_await (wide-contexts)
[187.262169] Starting subtest: wide-contexts
[210.845080] Subtest wide-contexts: SUCCESS (23.567s)
[211.050323] [033/133] (809s left) gem_wait (write-wait)
[211.170449] Starting subtest: write-wait
[211.172851] Starting dynamic subtest: all-engines
[212.292526] Dynamic subtest all-engines: SUCCESS (1.122s)
[212.308261] Starting dynamic subtest: rcs0
[213.348799] Dynamic subtest rcs0: SUCCESS (1.055s)
[213.365385] Starting dynamic subtest: bcs0
[214.406859] Dynamic subtest bcs0: SUCCESS (1.056s)
[214.422533] Starting dynamic subtest: vcs0
[215.460830] Dynamic subtest vcs0: SUCCESS (1.052s)
[215.476372] Starting dynamic subtest: vcs1
[216.514066] Dynamic subtest vcs1: SUCCESS (1.051s)
[216.529736] Starting dynamic subtest: vecs0
[217.568509] Dynamic subtest vecs0: SUCCESS (1.052s)
[217.584677] Starting dynamic subtest: ccs0
[218.626108] Dynamic subtest ccs0: SUCCESS (1.055s)
[218.628617] Subtest write-wait: SUCCESS (7.457s)
[218.819990] [034/133] (802s left) gem_mmap_gtt (close-race)
[218.872584] Subtest close-race: SKIP (0.000s)
[218.930076] [035/133] (802s left) gem_exec_create (legacy)
[219.002738] Starting subtest: legacy
[219.005205] Starting dynamic subtest: smem
[222.871639] Dynamic subtest smem: SUCCESS (3.867s)
[222.874172] Subtest legacy: SUCCESS (3.869s)
[223.068900] [036/133] (798s left) gem_pxp (display-protected-crc)
[223.193708] Starting subtest: display-protected-crc
[224.872336] Subtest display-protected-crc: SUCCESS (1.682s)
[225.000743] [037/133] (796s left) tools_test (sysfs_l3_parity)
[225.052019] Starting subtest: sysfs_l3_parity
[225.137065] Subtest sysfs_l3_parity: SKIP (0.095s)
[225.191026] [038/133] (795s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[225.280252] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[231.931421] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.653s)
[232.036828] [039/133] (789s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[232.137086] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[232.141712] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[232.226181] [040/133] (789s left) perf_pmu (busy-accuracy-98)
[232.269683] Starting subtest: busy-accuracy-98
[232.271799] Starting dynamic subtest: rcs0
[235.309099] Dynamic subtest rcs0: SUCCESS (3.037s)
[235.317920] Starting dynamic subtest: bcs0
[238.363714] Dynamic subtest bcs0: SUCCESS (3.052s)
[238.372954] Starting dynamic subtest: vcs0
[241.459030] Dynamic subtest vcs0: SUCCESS (3.044s)
[241.477772] Starting dynamic subtest: vcs1
[244.499754] Dynamic subtest vcs1: SUCCESS (3.039s)
[244.510778] Starting dynamic subtest: vecs0
[247.545090] Dynamic subtest vecs0: SUCCESS (3.043s)
[247.554765] Starting dynamic subtest: ccs0
[250.600883] Dynamic subtest ccs0: SUCCESS (3.053s)
[250.603185] Subtest busy-accuracy-98: SUCCESS (18.280s)
[250.663823] [041/133] (770s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[250.740256] Starting subtest: single-wait-for-submit-unsubmitted
[250.842766] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[250.920287] [042/133] (770s left) dumb_buffer (create-clear)
[250.974873] Starting subtest: create-clear
[284.957202] Subtest create-clear: SUCCESS (33.928s)
[285.021905] [043/133] (736s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[285.130579] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[285.134789] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[285.214934] [044/133] (736s left) gem_render_copy (yf-tiled-to-vebox-linear)
[285.284657] Starting subtest: yf-tiled-to-vebox-linear
[285.288944] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[285.467722] [045/133] (735s left) gem_mmap_gtt (zero-extend)
[285.521217] Subtest zero-extend: SKIP (0.000s)
[285.574063] [046/133] (735s left) kms_flip (dpms-off-confusion-interruptible)
[285.662044] Starting subtest: dpms-off-confusion-interruptible
[285.664343] Starting dynamic subtest: A-eDP1
[294.376746] Dynamic subtest A-eDP1: SUCCESS (8.679s)
[294.389157] Starting dynamic subtest: B-eDP1
[303.011198] Dynamic subtest B-eDP1: SUCCESS (8.632s)
[303.022350] Starting dynamic subtest: C-eDP1
[311.637887] Dynamic subtest C-eDP1: SUCCESS (8.613s)
[311.649886] Starting dynamic subtest: D-eDP1
[320.274310] Dynamic subtest D-eDP1: SUCCESS (8.629s)
[320.276867] Subtest dpms-off-confusion-interruptible: SUCCESS (34.567s)
[321.402688] [047/133] (700s left) kms_lease (invalid-create-leases)
[321.495595] Starting subtest: invalid-create-leases
[321.498092] Subtest invalid-create-leases: SUCCESS (0.005s)
[321.599970] [048/133] (699s left) gem_pxp (protected-raw-src-copy-not-readible)
[321.663949] Starting subtest: protected-raw-src-copy-not-readible
[321.722204] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.060s)
[321.834900] [049/133] (699s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[321.928572] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[321.932591] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[322.023310] [050/133] (699s left) kms_psr (fbc-pr-primary-mmap-cpu)
[322.122700] Starting subtest: fbc-pr-primary-mmap-cpu
[322.126695] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[322.193351] [051/133] (699s left) i915_pm_rps (thresholds-park)
[322.258741] Starting subtest: thresholds-park
[322.262877] Subtest thresholds-park: SKIP (0.001s)
[322.361956] [052/133] (699s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[322.458540] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[322.462090] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[322.526739] [053/133] (699s left) kms_async_flips (crc)
[322.608074] Starting subtest: crc
[322.979983] Starting dynamic subtest: pipe-A-eDP-1
[325.938738] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.937s)
[326.297999] Starting dynamic subtest: pipe-B-eDP-1
[329.258085] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.952s)
[329.617587] Starting dynamic subtest: pipe-C-eDP-1
[332.570066] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.931s)
[332.905833] Starting dynamic subtest: pipe-D-eDP-1
[335.815548] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.887s)
[335.817927] Subtest crc: SUCCESS (13.175s)
[336.944856] [054/133] (684s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[337.046105] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[340.635595] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.592s)
[341.759714] [055/133] (680s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[341.857191] Starting subtest: coverage-vs-premult-vs-constant
[342.144402] Starting dynamic subtest: pipe-A-eDP-1
[342.702046] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.555s)
[342.720778] Starting dynamic subtest: pipe-D-eDP-1
[343.978749] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.276s)
[343.981183] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.124s)
[344.849286] [056/133] (677s left) kms_properties (crtc-properties-legacy)
[344.941714] Starting subtest: crtc-properties-legacy
[344.944075] Starting dynamic subtest: pipe-A-eDP-1
[345.372310] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.432s)
[345.381593] Starting dynamic subtest: pipe-B-eDP-1
[346.502910] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.128s)
[346.512635] Starting dynamic subtest: pipe-C-eDP-1
[347.631210] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.126s)
[347.641757] Starting dynamic subtest: pipe-D-eDP-1
[348.754564] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.124s)
[348.756916] Subtest crtc-properties-legacy: SUCCESS (3.815s)
[349.574159] [057/133] (672s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[349.677061] Starting subtest: fbc-psr2-sprite-mmap-cpu
[349.679166] Starting dynamic subtest: eDP-1
[349.683091] Dynamic subtest eDP-1: SKIP (0.000s)
[349.686974] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s)
[349.747968] [058/133] (672s left) gem_ccs (block-multicopy-compressed)
[349.815264] Starting subtest: block-multicopy-compressed
[349.819272] Subtest block-multicopy-compressed: SKIP (0.000s)
[349.907572] [059/133] (672s left) gem_exec_reloc (basic-scanout)
[349.965366] Subtest basic-scanout: SKIP (0.000s)
[350.039733] [060/133] (672s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[350.090880] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[350.093111] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[350.178848] [061/133] (671s left) kms_plane_scaling (planes-upscale-factor-0-25)
[350.266790] Starting subtest: planes-upscale-factor-0-25
[350.269017] Starting dynamic subtest: pipe-A
[350.327926] Dynamic subtest pipe-A: SUCCESS (0.062s)
[350.338999] Starting dynamic subtest: pipe-B
[351.501737] Dynamic subtest pipe-B: SUCCESS (1.173s)
[351.512557] Starting dynamic subtest: pipe-C
[352.613841] Dynamic subtest pipe-C: SUCCESS (1.111s)
[352.623070] Starting dynamic subtest: pipe-D
[353.766891] Dynamic subtest pipe-D: SUCCESS (1.152s)
[353.769076] Subtest planes-upscale-factor-0-25: SUCCESS (3.502s)
[354.917412] [062/133] (667s left) gem_workarounds (suspend-resume)
[354.977903] Starting subtest: suspend-resume
[371.187511] Subtest suspend-resume: SUCCESS (2.159s)
[371.339124] [063/133] (650s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[371.437636] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[376.299302] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.846s)
[377.470494] [064/133] (644s left) kms_psr (psr-sprite-plane-move)
[377.572855] Starting subtest: psr-sprite-plane-move
[377.575191] Starting dynamic subtest: eDP-1
[379.303046] Dynamic subtest eDP-1: SUCCESS (1.726s)
[379.305467] Subtest psr-sprite-plane-move: SUCCESS (1.727s)
[380.141910] [065/133] (642s left) syncobj_timeline (invalid-signal-zero-handles)
[380.195361] Starting subtest: invalid-signal-zero-handles
[380.197268] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[380.294030] [066/133] (642s left) kms_big_fb (4-tiled-addfb-size-overflow)
[380.387081] Starting subtest: 4-tiled-addfb-size-overflow
[380.388991] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[380.473592] [067/133] (641s left) gem_eio (in-flight-contexts-immediate)
[380.706651] Starting subtest: in-flight-contexts-immediate
[392.025566] Subtest in-flight-contexts-immediate: SUCCESS (11.303s)
[392.173779] [068/133] (630s left) syncobj_basic (bad-handle-to-fd)
[392.226601] Starting subtest: bad-handle-to-fd
[392.228844] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[392.317151] [069/133] (630s left) kms_invalid_mode (zero-clock)
[392.427013] Starting subtest: zero-clock
[392.429337] Starting dynamic subtest: pipe-A-eDP-1
[392.712664] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[392.733050] Starting dynamic subtest: pipe-B-eDP-1
[392.735593] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[392.748606] Starting dynamic subtest: pipe-C-eDP-1
[392.751012] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[392.761024] Starting dynamic subtest: pipe-D-eDP-1
[392.763452] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[392.765856] Subtest zero-clock: SUCCESS (0.294s)
[393.575664] [070/133] (628s left) api_intel_bb (add-remove-objects)
[393.628745] Starting subtest: add-remove-objects
[393.633580] Subtest add-remove-objects: SUCCESS (0.008s)
[393.770422] [071/133] (628s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[393.878096] Starting subtest: psr2-cursor-plane-move-continuous-sf
[393.880316] Starting dynamic subtest: pipe-A-eDP-1
[404.225908] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.349s)
[404.236827] Starting dynamic subtest: pipe-B-eDP-1
[415.344136] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.119s)
[415.346017] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.471s)
[416.128637] [072/133] (606s left) gem_ctx_persistence (legacy-engines-persistence)
[416.198735] Starting subtest: legacy-engines-persistence
[416.200997] Starting dynamic subtest: render
[417.013889] Dynamic subtest render: SUCCESS (0.814s)
[417.026481] Starting dynamic subtest: bsd1
[417.786871] Dynamic subtest bsd1: SUCCESS (0.770s)
[417.799747] Starting dynamic subtest: bsd2
[418.552649] Dynamic subtest bsd2: SUCCESS (0.763s)
[418.567704] Starting dynamic subtest: blt
[419.302456] Dynamic subtest blt: SUCCESS (0.747s)
[419.314500] Starting dynamic subtest: vebox
[420.081587] Dynamic subtest vebox: SUCCESS (0.776s)
[420.084120] Subtest legacy-engines-persistence: SUCCESS (3.884s)
[420.166748] [073/133] (602s left) gem_exec_reloc (basic-write-cpu-active)
[420.226660] Subtest basic-write-cpu-active: SKIP (0.000s)
[420.294637] [074/133] (602s left) gem_readwrite (read-write)
[420.346066] Subtest read-write: SKIP (0.000s)
[420.402027] [075/133] (602s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[420.505281] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[427.095578] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.592s)
[427.235482] [076/133] (595s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[427.336769] Starting subtest: y-tiled-16bpp-rotate-0
[427.341418] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[427.434424] [077/133] (595s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[427.535282] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[427.559737] Starting dynamic subtest: pipe-A-plane-0
[427.848732] Dynamic subtest pipe-A-plane-0: SUCCESS (0.288s)
[427.860822] Starting dynamic subtest: pipe-A-plane-3
[428.081206] Dynamic subtest pipe-A-plane-3: SUCCESS (0.231s)
[428.094996] Starting dynamic subtest: pipe-A-plane-5
[428.099284] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[429.209924] Starting dynamic subtest: pipe-B-plane-0
[429.490279] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[429.501019] Starting dynamic subtest: pipe-B-plane-3
[429.723576] Dynamic subtest pipe-B-plane-3: SUCCESS (0.231s)
[429.736958] Starting dynamic subtest: pipe-B-plane-5
[429.741509] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[430.037354] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.504s)
[430.896287] [078/133] (591s left) api_intel_bb (intel-bb-blit-none)
[430.954988] Starting subtest: intel-bb-blit-none
[434.964154] Subtest intel-bb-blit-none: SUCCESS (4.007s)
[435.089484] [079/133] (587s left) kms_cursor_crc (cursor-sliding-128x42)
[435.318334] Starting subtest: cursor-sliding-128x42
[435.369726] Subtest cursor-sliding-128x42: SKIP (0.046s)
[435.594914] [080/133] (587s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[435.694812] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[435.698744] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[435.803561] [081/133] (587s left) prime_busy (before)
[435.863206] Starting subtest: before
[435.867774] Starting dynamic subtest: rcs0
[435.906480] Dynamic subtest rcs0: SUCCESS (0.040s)
[435.917009] Starting dynamic subtest: bcs0
[435.957216] Dynamic subtest bcs0: SUCCESS (0.050s)
[435.967161] Starting dynamic subtest: vcs0
[435.997689] Dynamic subtest vcs0: SUCCESS (0.040s)
[436.007496] Starting dynamic subtest: vcs1
[436.038738] Dynamic subtest vcs1: SUCCESS (0.040s)
[436.048325] Starting dynamic subtest: vecs0
[436.080117] Dynamic subtest vecs0: SUCCESS (0.040s)
[436.090010] Starting dynamic subtest: ccs0
[436.120421] Dynamic subtest ccs0: SUCCESS (0.040s)
[436.228831] Subtest before: SUCCESS (0.364s)
[436.304787] [082/133] (586s left) gem_exec_schedule (u-semaphore-resolve)
[436.372814] Starting subtest: u-semaphore-resolve
[436.408150] Subtest u-semaphore-resolve: SUCCESS (0.043s)
[436.766042] [083/133] (586s left) gem_exec_balancer (full-late-pulse)
[436.832950] Starting subtest: full-late-pulse
[441.829205] Subtest full-late-pulse: SUCCESS (4.995s)
[442.144508] [084/133] (580s left) kms_tiled_display (basic-test-pattern)
[442.261068] Subtest basic-test-pattern: SKIP (0.000s)
[442.309488] [085/133] (580s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[442.410682] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[442.414586] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[442.496427] [086/133] (580s left) gem_eio (wait-wedge-1us)
[442.703946] Starting subtest: wait-wedge-1us
[444.023902] Subtest wait-wedge-1us: SUCCESS (1.319s)
[444.167585] [087/133] (579s left) gem_close_race (basic-threads)
[444.229345] Starting subtest: basic-threads
[445.656383] Subtest basic-threads: SUCCESS (1.429s)
[445.862293] [088/133] (577s left) kms_content_protection (dp-mst-type-0-hdcp14)
[445.999548] Starting subtest: dp-mst-type-0-hdcp14
[446.003814] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[446.126646] [089/133] (577s left) gem_exec_whisper (basic-queues-all)
[446.188051] Starting subtest: basic-queues-all
[457.037488] Subtest basic-queues-all: SUCCESS (10.843s)
[457.221983] [090/133] (566s left) gem_ctx_engines (none)
[457.284052] Starting subtest: none
[457.286040] Subtest none: SUCCESS (0.001s)
[457.484918] [091/133] (565s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[457.591600] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[457.596212] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[457.709934] [092/133] (565s left) gem_exec_params (no-vebox)
[457.767598] Starting subtest: no-vebox
[457.769952] Subtest no-vebox: SUCCESS (0.002s)
[458.986986] [093/133] (564s left) kms_flip (bo-too-big-interruptible)
[459.081020] Starting subtest: bo-too-big-interruptible
[459.083407] Starting dynamic subtest: A-eDP1
[460.351256] Dynamic subtest A-eDP1: SUCCESS (1.269s)
[460.363232] Starting dynamic subtest: B-eDP1
[461.587739] Dynamic subtest B-eDP1: SUCCESS (1.234s)
[461.597714] Starting dynamic subtest: C-eDP1
[462.838669] Dynamic subtest C-eDP1: SUCCESS (1.249s)
[462.851000] Starting dynamic subtest: D-eDP1
[464.160427] Dynamic subtest D-eDP1: SUCCESS (1.320s)
[464.165514] Subtest bo-too-big-interruptible: SUCCESS (5.083s)
[465.380659] [094/133] (558s left) gem_exec_flush (basic-uc-set-default)
[465.431460] Starting subtest: basic-uc-set-default
[470.914526] Subtest basic-uc-set-default: SUCCESS (5.456s)
[471.073089] [095/133] (552s left) kms_rmfb (close-fd)
[471.173676] Starting subtest: close-fd
[471.175838] Starting dynamic subtest: pipe-A-eDP-1
[471.283138] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.109s)
[471.295729] Starting dynamic subtest: pipe-B-eDP-1
[472.436205] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.151s)
[472.447951] Starting dynamic subtest: pipe-C-eDP-1
[473.569903] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.135s)
[473.581154] Starting dynamic subtest: pipe-D-eDP-1
[474.718074] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.144s)
[474.720253] Subtest close-fd: SUCCESS (3.545s)
[475.938148] [096/133] (547s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[476.054430] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[476.058348] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[476.171732] [097/133] (547s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[476.258228] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[476.262567] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[477.352354] [098/133] (546s left) perf_pmu (init-wait)
[477.399194] Starting subtest: init-wait
[477.401780] Starting dynamic subtest: rcs0
[477.404064] Dynamic subtest rcs0: SUCCESS (0.002s)
[477.416174] Starting dynamic subtest: bcs0
[477.418379] Dynamic subtest bcs0: SUCCESS (0.000s)
[477.428075] Starting dynamic subtest: vcs0
[477.430284] Dynamic subtest vcs0: SUCCESS (0.000s)
[477.439518] Starting dynamic subtest: vcs1
[477.441719] Dynamic subtest vcs1: SUCCESS (0.000s)
[477.450917] Starting dynamic subtest: vecs0
[477.452798] Dynamic subtest vecs0: SUCCESS (0.000s)
[477.462159] Starting dynamic subtest: ccs0
[477.464248] Dynamic subtest ccs0: SUCCESS (0.000s)
[477.466439] Subtest init-wait: SUCCESS (0.008s)
[477.507171] [099/133] (546s left) gem_mmap_gtt (hang)
[477.563426] Subtest hang: SKIP (0.000s)
[477.630858] [100/133] (546s left) i915_pm_rpm (gem-execbuf)
[479.160974] Starting subtest: gem-execbuf
[479.164039] Starting dynamic subtest: smem0
[480.870002] Dynamic subtest smem0: SUCCESS (1.709s)
[480.872255] Subtest gem-execbuf: SUCCESS (1.711s)
[480.964363] [101/133] (542s left) syncobj_wait (multi-wait-submitted)
[481.019290] Starting subtest: multi-wait-submitted
[481.125706] Subtest multi-wait-submitted: SUCCESS (0.106s)
[481.223261] [102/133] (542s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[481.319898] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[481.322248] Starting dynamic subtest: pipe-A-default-mode
[484.091982] Dynamic subtest pipe-A-default-mode: SUCCESS (2.772s)
[484.094565] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.773s)
[484.928460] [103/133] (538s left) kms_flip_event_leak (basic)
[485.024773] Starting subtest: basic
[485.026910] Starting dynamic subtest: pipe-A-eDP-1
[485.446567] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.423s)
[485.457162] Starting dynamic subtest: pipe-B-eDP-1
[486.555176] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.107s)
[486.569962] Starting dynamic subtest: pipe-C-eDP-1
[487.658506] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.100s)
[487.669284] Starting dynamic subtest: pipe-D-eDP-1
[488.796844] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[488.799037] Subtest basic: SUCCESS (3.773s)
[489.655028] [104/133] (534s left) kms_psr (pr-sprite-render)
[489.744605] Starting subtest: pr-sprite-render
[489.748967] Subtest pr-sprite-render: SKIP (0.000s)
[489.816892] [105/133] (534s left) vgem_basic (bad-flag)
[489.858533] Starting subtest: bad-flag
[489.860425] Subtest bad-flag: SUCCESS (0.000s)
[489.920959] [106/133] (534s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[490.024104] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[490.028382] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[490.114372] [107/133] (533s left) kms_draw_crc (draw-method-mmap-cpu)
[490.204688] Starting subtest: draw-method-mmap-cpu
[490.206925] Starting dynamic subtest: xrgb8888-untiled
[490.470552] Dynamic subtest xrgb8888-untiled: SUCCESS (0.267s)
[490.481040] Starting dynamic subtest: rgb565-untiled
[490.692377] Dynamic subtest rgb565-untiled: SUCCESS (0.220s)
[490.701868] Starting dynamic subtest: xrgb2101010-untiled
[490.914693] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[490.924545] Starting dynamic subtest: xbgr16161616f-untiled
[491.147995] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.232s)
[491.158337] Starting dynamic subtest: xrgb8888-xtiled
[491.283029] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[491.292863] Starting dynamic subtest: rgb565-xtiled
[491.404161] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[491.414471] Starting dynamic subtest: xrgb2101010-xtiled
[491.525685] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.120s)
[491.538276] Starting dynamic subtest: xbgr16161616f-xtiled
[491.647937] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.120s)
[491.657965] Starting dynamic subtest: xrgb8888-4tiled
[491.792667] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[491.804840] Starting dynamic subtest: rgb565-4tiled
[491.936646] Dynamic subtest rgb565-4tiled: SUCCESS (0.143s)
[491.947085] Starting dynamic subtest: xrgb2101010-4tiled
[492.059172] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.122s)
[492.069315] Starting dynamic subtest: xbgr16161616f-4tiled
[492.192345] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.132s)
[492.194350] Subtest draw-method-mmap-cpu: SUCCESS (1.990s)
[492.290146] [108/133] (531s left) gem_tiled_pread_pwrite
[492.410084] [109/133] (531s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[492.505097] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[499.702756] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.200s)
[499.829442] [110/133] (524s left) kms_pm_dc (dc5-psr)
[500.933781] Starting subtest: dc5-psr
[503.911294] Subtest dc5-psr: SUCCESS (2.978s)
[504.724051] [111/133] (519s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[504.825369] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[504.829792] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[504.948668] [112/133] (519s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[505.045546] Starting subtest: primary-y-tiled-reflect-x-180
[505.109410] Subtest primary-y-tiled-reflect-x-180: SKIP (0.062s)
[505.224254] [113/133] (519s left) kms_chamelium_edid (hdmi-edid-read)
[505.599208] Subtest hdmi-edid-read: SKIP (0.000s)
[506.420591] [114/133] (517s left) kms_psr (fbc-psr-cursor-mmap-gtt)
[506.514673] Starting subtest: fbc-psr-cursor-mmap-gtt
[506.516832] Starting dynamic subtest: eDP-1
[506.520836] Dynamic subtest eDP-1: SKIP (0.000s)
[506.524789] Subtest fbc-psr-cursor-mmap-gtt: SKIP (0.001s)
[506.588999] [115/133] (517s left) kms_chamelium_color (ctm-0-25)
[506.670823] Subtest ctm-0-25: SKIP (0.000s)
[506.718725] [116/133] (517s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[508.240014] Starting subtest: modeset-lpsp-stress-no-wait
[518.330944] Subtest modeset-lpsp-stress-no-wait: SUCCESS (10.093s)
[518.409477] [117/133] (506s left) gem_ctx_shared (q-out-order)
[518.484373] Starting subtest: Q-out-order
[518.487017] Starting dynamic subtest: rcs0
[518.593960] Dynamic subtest rcs0: SUCCESS (0.109s)
[518.614240] Starting dynamic subtest: vcs0
[518.680147] Dynamic subtest vcs0: SUCCESS (0.086s)
[518.695347] Starting dynamic subtest: vcs1
[518.766147] Dynamic subtest vcs1: SUCCESS (0.085s)
[518.783083] Starting dynamic subtest: vecs0
[518.863124] Dynamic subtest vecs0: SUCCESS (0.096s)
[518.881706] Starting dynamic subtest: ccs0
[518.956956] Dynamic subtest ccs0: SUCCESS (0.091s)
[518.959683] Subtest Q-out-order: SUCCESS (0.472s)
[520.053972] [118/133] (504s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[520.149946] Starting subtest: fbcpsr-slowdraw
[545.312985] Subtest fbcpsr-slowdraw: SUCCESS (25.160s)
[545.449705] [119/133] (479s left) kms_flip (nonexisting-fb)
[545.538711] Starting subtest: nonexisting-fb
[545.541053] Starting dynamic subtest: A-eDP1
[546.707006] Dynamic subtest A-eDP1: SUCCESS (1.167s)
[546.717694] Starting dynamic subtest: B-eDP1
[547.840213] Dynamic subtest B-eDP1: SUCCESS (1.132s)
[547.857991] Starting dynamic subtest: C-eDP1
[548.970925] Dynamic subtest C-eDP1: SUCCESS (1.129s)
[548.985103] Starting dynamic subtest: D-eDP1
[550.104554] Dynamic subtest D-eDP1: SUCCESS (1.131s)
[550.106771] Subtest nonexisting-fb: SUCCESS (4.568s)
[551.288772] [120/133] (473s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[551.380218] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[551.382466] Starting dynamic subtest: pipe-A-eDP-1
[551.405641] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[551.418480] Starting dynamic subtest: pipe-B-eDP-1
[551.439419] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[551.450071] Starting dynamic subtest: pipe-C-eDP-1
[551.470698] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[551.480077] Starting dynamic subtest: pipe-D-eDP-1
[551.502063] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[551.506205] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[553.658277] [121/133] (471s left) kms_plane_lowres (tiling-x)
[553.751009] Starting subtest: tiling-x
[553.753292] Starting dynamic subtest: pipe-A-eDP-1
[553.757816] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[554.054054] Starting dynamic subtest: pipe-B-eDP-1
[554.058460] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[554.068139] Starting dynamic subtest: pipe-C-eDP-1
[554.072887] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[554.084573] Starting dynamic subtest: pipe-D-eDP-1
[554.088918] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[554.093238] Subtest tiling-x: SKIP (0.297s)
[554.886743] [122/133] (469s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[554.980961] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[561.576817] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.595s)
[561.722789] [123/133] (463s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[561.836766] Starting subtest: yf-tiled-16bpp-rotate-180
[561.841204] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[561.934373] [124/133] (462s left) perf (invalid-create-userspace-config)
[562.077521] Starting subtest: invalid-create-userspace-config
[562.079455] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[562.120431] [125/133] (462s left) gen9_exec_parse (basic-rejected-ctx-param)
[562.181244] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[562.260714] [126/133] (462s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[562.316497] Starting subtest: multi-wait-all-for-submit-signaled
[562.318497] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[562.409727] [127/133] (462s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[562.467847] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[562.574243] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[562.651420] [128/133] (462s left) kms_plane_scaling (intel-max-src-size)
[562.748072] Starting subtest: intel-max-src-size
[562.754392] Subtest intel-max-src-size: SKIP (0.000s)
[562.846846] [129/133] (462s left) i915_drm_fdinfo (memory-info-idle)
[562.894670] Starting subtest: memory-info-idle
[562.896576] Starting dynamic subtest: smem0
[562.946436] Dynamic subtest smem0: SUCCESS (0.054s)
[562.948704] Subtest memory-info-idle: SUCCESS (0.055s)
[563.023611] [130/133] (462s left) kms_dp_linktrain_fallback (dsc-fallback)
[563.118927] Starting subtest: dsc-fallback
[563.124943] Subtest dsc-fallback: SKIP (0.000s)
[563.189586] [131/133] (461s left) gem_exec_schedule (preempt-other-chain)
[563.261231] Starting subtest: preempt-other-chain
[563.263504] Starting dynamic subtest: rcs0
[563.333954] Dynamic subtest rcs0: SUCCESS (0.080s)
[563.349384] Starting dynamic subtest: bcs0
[563.413256] Dynamic subtest bcs0: SUCCESS (0.078s)
[563.427162] Starting dynamic subtest: vcs0
[563.493131] Dynamic subtest vcs0: SUCCESS (0.079s)
[563.508422] Starting dynamic subtest: vcs1
[563.580527] Dynamic subtest vcs1: SUCCESS (0.088s)
[563.594652] Starting dynamic subtest: vecs0
[563.659703] Dynamic subtest vecs0: SUCCESS (0.079s)
[563.673788] Starting dynamic subtest: ccs0
[563.741201] Dynamic subtest ccs0: SUCCESS (0.081s)
[563.743427] Subtest preempt-other-chain: SUCCESS (0.488s)
[565.065925] [132/133] (460s left) sw_sync (alloc_timeline)
[565.104959] Starting subtest: alloc_timeline
[565.107340] Subtest alloc_timeline: SUCCESS (0.000s)
[565.168510] [133/133] (460s left) i915_fb_tiling (basic-x-tiling)
[565.241125] Subtest basic-x-tiling: SKIP (0.000s)
[565.318236] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_async_flips
results: parsing output: 2/ for test: kms_cursor_crc
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: kms_cursor_legacy
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: kms_vrr
results: parsing output: 10/ for test: syncobj_wait
results: parsing output: 11/ for test: kms_flip
results: parsing output: 12/ for test: gem_eio
results: parsing output: 13/ for test: syncobj_wait
results: parsing output: 14/ for test: gem_ctx_isolation
results: parsing output: 15/ for test: kms_color
results: parsing output: 16/ for test: gem_spin_batch
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_ccs
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: kms_cursor_edge_walk
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_getfb
results: parsing output: 23/ for test: gem_exec_reloc
results: parsing output: 24/ for test: kms_cursor_legacy
results: parsing output: 25/ for test: kms_getfb
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_exec_fence
results: parsing output: 28/ for test: kms_chamelium_hpd
results: parsing output: 29/ for test: gem_exec_whisper
results: parsing output: 30/ for test: kms_ccs
results: parsing output: 31/ for test: gem_exec_await
results: parsing output: 32/ for test: gem_wait
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: gem_exec_create
results: parsing output: 35/ for test: gem_pxp
results: parsing output: 36/ for test: tools_test
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: perf_pmu
results: parsing output: 40/ for test: syncobj_wait
results: parsing output: 41/ for test: dumb_buffer
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_render_copy
results: parsing output: 44/ for test: gem_mmap_gtt
results: parsing output: 45/ for test: kms_flip
results: parsing output: 46/ for test: kms_lease
results: parsing output: 47/ for test: gem_pxp
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: i915_pm_rps
results: parsing output: 51/ for test: kms_psr2_sf
results: parsing output: 52/ for test: kms_async_flips
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_plane_alpha_blend
results: parsing output: 55/ for test: kms_properties
results: parsing output: 56/ for test: kms_psr
results: parsing output: 57/ for test: gem_ccs
results: parsing output: 58/ for test: gem_exec_reloc
results: parsing output: 59/ for test: syncobj_wait
results: parsing output: 60/ for test: kms_plane_scaling
results: parsing output: 61/ for test: gem_workarounds
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: gem_eio
results: parsing output: 67/ for test: syncobj_basic
results: parsing output: 68/ for test: kms_invalid_mode
results: parsing output: 69/ for test: api_intel_bb
results: parsing output: 70/ for test: kms_psr2_sf
results: parsing output: 71/ for test: gem_ctx_persistence
results: parsing output: 72/ for test: gem_exec_reloc
results: parsing output: 73/ for test: gem_readwrite
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_plane
results: parsing output: 77/ for test: api_intel_bb
results: parsing output: 78/ for test: kms_cursor_crc
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: prime_busy
results: parsing output: 81/ for test: gem_exec_schedule
results: parsing output: 82/ for test: gem_exec_balancer
results: parsing output: 83/ for test: kms_tiled_display
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_eio
results: parsing output: 86/ for test: gem_close_race
results: parsing output: 87/ for test: kms_content_protection
results: parsing output: 88/ for test: gem_exec_whisper
results: parsing output: 89/ for test: gem_ctx_engines
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: gem_exec_params
results: parsing output: 92/ for test: kms_flip
results: parsing output: 93/ for test: gem_exec_flush
results: parsing output: 94/ for test: kms_rmfb
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: perf_pmu
results: parsing output: 98/ for test: gem_mmap_gtt
results: parsing output: 99/ for test: i915_pm_rpm
results: parsing output: 100/ for test: syncobj_wait
results: parsing output: 101/ for test: kms_flip_scaled_crc
results: parsing output: 102/ for test: kms_flip_event_leak
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: vgem_basic
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_draw_crc
results: parsing output: 107/ for test: gem_tiled_pread_pwrite
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_pm_dc
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_rotation_crc
results: parsing output: 112/ for test: kms_chamelium_edid
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: kms_chamelium_color
results: parsing output: 115/ for test: kms_pm_rpm
results: parsing output: 116/ for test: gem_ctx_shared
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_flip
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: kms_plane_lowres
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: perf
results: parsing output: 124/ for test: gen9_exec_parse
results: parsing output: 125/ for test: syncobj_timeline
results: parsing output: 126/ for test: syncobj_wait
results: parsing output: 127/ for test: kms_plane_scaling
results: parsing output: 128/ for test: i915_drm_fdinfo
results: parsing output: 129/ for test: kms_dp_linktrain_fallback
results: parsing output: 130/ for test: gem_exec_schedule
results: parsing output: 131/ for test: sw_sync
results: parsing output: 132/ for test: i915_fb_tiling
Done.