[59.950069] Initializing watchdogs
[59.950117]   /dev/watchdog0
[59.965357] [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.970061] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.972435] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.976085] [001/133] (960s left) i915_pm_rps (reset)
[60.072487] Starting subtest: reset
[68.505467] Subtest reset: SUCCESS (8.425s)
[68.613792] [002/133] (951s left) kms_fbcon_fbt (psr)
[68.665465] Starting subtest: psr
[76.248565] Subtest psr: SUCCESS (7.577s)
[76.325419] [003/133] (944s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[76.446317] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[76.461364] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.013s)
[76.533964] [004/133] (944s left) perf_pmu (busy)
[76.577793] Starting subtest: busy
[76.580063] Starting dynamic subtest: rcs0
[77.164962] Dynamic subtest rcs0: SUCCESS (0.588s)
[77.174466] Starting dynamic subtest: bcs0
[77.727440] Dynamic subtest bcs0: SUCCESS (0.562s)
[77.736946] Starting dynamic subtest: vcs0
[78.869658] Dynamic subtest vcs0: SUCCESS (1.139s)
[78.878774] Starting dynamic subtest: vcs1
[80.917315] Dynamic subtest vcs1: SUCCESS (2.046s)
[80.926308] Starting dynamic subtest: vecs0
[82.901458] Dynamic subtest vecs0: SUCCESS (1.982s)
[82.912507] Starting dynamic subtest: ccs0
[83.469240] Dynamic subtest ccs0: SUCCESS (0.568s)
[83.471883] Subtest busy: SUCCESS (6.891s)
[83.570802] [005/133] (937s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[83.632920] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[83.736644] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.103s)
[83.810877] [006/133] (936s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[83.862854] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[83.865215] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[83.958313] [007/133] (936s left) gem_exec_whisper (basic-fds-priority)
[84.031389] Starting subtest: basic-fds-priority
[107.528082] Subtest basic-fds-priority: SUCCESS (23.490s)
[107.706944] [008/133] (913s left) dumb_buffer (map-valid)
[107.762569] Starting subtest: map-valid
[107.764485] Subtest map-valid: SUCCESS (0.000s)
[107.850447] [009/133] (912s left) gem_spin_batch (legacy-resubmit-new)
[107.919764] Starting subtest: legacy-resubmit-new
[107.922076] Starting dynamic subtest: default
[107.931583] Dynamic subtest default: SUCCESS (0.012s)
[107.942280] Starting dynamic subtest: render
[107.944414] Dynamic subtest render: SUCCESS (0.004s)
[107.952697] Starting dynamic subtest: bsd
[107.954847] Dynamic subtest bsd: SUCCESS (0.007s)
[107.963335] Starting dynamic subtest: bsd1
[107.965510] Dynamic subtest bsd1: SUCCESS (0.004s)
[107.973858] Starting dynamic subtest: bsd2
[107.975958] Dynamic subtest bsd2: SUCCESS (0.009s)
[107.984584] Starting dynamic subtest: blt
[107.986765] Dynamic subtest blt: SUCCESS (0.006s)
[107.994913] Starting dynamic subtest: vebox
[107.997430] Dynamic subtest vebox: SUCCESS (0.006s)
[107.999694] Subtest legacy-resubmit-new: SUCCESS (0.049s)
[109.960157] [010/133] (910s left) kms_psr (fbc-psr2-primary-render)
[110.066598] Starting subtest: fbc-psr2-primary-render
[110.068905] Starting dynamic subtest: eDP-1
[110.073686] Dynamic subtest eDP-1: SKIP (0.000s)
[110.078193] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[110.140838] [011/133] (910s left) kms_flip (basic-flip-vs-dpms)
[110.242074] Starting subtest: basic-flip-vs-dpms
[110.244350] Starting dynamic subtest: A-eDP1
[112.878782] Dynamic subtest A-eDP1: SUCCESS (2.638s)
[112.888365] Starting dynamic subtest: B-eDP1
[115.051877] Dynamic subtest B-eDP1: SUCCESS (2.171s)
[115.061818] Starting dynamic subtest: C-eDP1
[117.121408] Dynamic subtest C-eDP1: SUCCESS (2.067s)
[117.131020] Starting dynamic subtest: D-eDP1
[119.294863] Dynamic subtest D-eDP1: SUCCESS (2.170s)
[119.297392] Subtest basic-flip-vs-dpms: SUCCESS (9.054s)
[120.096424] [012/133] (900s left) gem_eio (in-flight-contexts-1us)
[120.340477] Starting subtest: in-flight-contexts-1us
[131.937896] Subtest in-flight-contexts-1us: SUCCESS (11.597s)
[132.115272] [013/133] (888s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[132.218162] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[132.222205] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[132.309018] [014/133] (888s left) kms_lease (setcrtc-implicit-plane)
[132.397339] Starting subtest: setcrtc-implicit-plane
[132.399635] Starting dynamic subtest: pipe-A-eDP-1
[132.891855] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.493s)
[132.902948] Starting dynamic subtest: pipe-B-eDP-1
[134.035368] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.142s)
[134.045688] Starting dynamic subtest: pipe-C-eDP-1
[135.142497] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.105s)
[135.156698] Starting dynamic subtest: pipe-D-eDP-1
[136.281419] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.139s)
[136.283820] Subtest setcrtc-implicit-plane: SUCCESS (3.883s)
[137.127293] [015/133] (883s left) kms_atomic_interruptible (atomic-setmode)
[137.222283] Starting subtest: atomic-setmode
[137.224675] Starting dynamic subtest: pipe-A-eDP-1
[144.144944] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.922s)
[144.147456] Subtest atomic-setmode: SUCCESS (6.923s)
[145.007590] [016/133] (876s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[145.096859] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[145.101265] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[145.167525] [017/133] (875s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[145.262222] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[151.966500] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.705s)
[152.066261] [018/133] (869s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[152.188909] Starting subtest: cursorB-vs-flipB-toggle
[152.193265] Subtest cursorB-vs-flipB-toggle: SKIP (0.001s)
[152.334536] [019/133] (868s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[152.403265] Subtest tiled-to-tiled: SKIP (0.000s)
[152.463936] [020/133] (868s left) gen9_exec_parse (secure-batches)
[152.520356] Subtest secure-batches: SKIP (0.000s)
[152.583476] [021/133] (868s left) kms_chamelium_audio (dp-audio-edid)
[152.977205] Subtest dp-audio-edid: SKIP (0.000s)
[153.772269] [022/133] (867s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[153.868999] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[153.873402] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[153.937118] [023/133] (867s left) gem_exec_schedule (implicit-boths)
[154.002513] Starting subtest: implicit-boths
[154.004687] Starting dynamic subtest: rcs0
[154.110809] Dynamic subtest rcs0: SUCCESS (0.118s)
[154.122344] Starting dynamic subtest: vcs0
[154.195373] Dynamic subtest vcs0: SUCCESS (0.084s)
[154.206193] Starting dynamic subtest: vcs1
[154.277691] Dynamic subtest vcs1: SUCCESS (0.082s)
[154.289223] Starting dynamic subtest: vecs0
[154.362670] Dynamic subtest vecs0: SUCCESS (0.084s)
[154.374061] Starting dynamic subtest: ccs0
[154.454740] Dynamic subtest ccs0: SUCCESS (0.092s)
[154.457552] Subtest implicit-boths: SUCCESS (0.463s)
[156.009294] [024/133] (865s left) kms_plane_scaling (planes-downscale-factor-0-75)
[156.105807] Starting subtest: planes-downscale-factor-0-75
[156.108207] Starting dynamic subtest: pipe-A
[156.128958] Dynamic subtest pipe-A: SKIP (0.018s)
[156.141041] Starting dynamic subtest: pipe-B
[156.159639] Dynamic subtest pipe-B: SKIP (0.031s)
[156.169286] Starting dynamic subtest: pipe-C
[156.191992] Dynamic subtest pipe-C: SKIP (0.031s)
[156.200811] Starting dynamic subtest: pipe-D
[156.223163] Dynamic subtest pipe-D: SKIP (0.033s)
[156.227262] Subtest planes-downscale-factor-0-75: SKIP (0.114s)
[156.318230] [025/133] (865s left) kms_plane_alpha_blend (alpha-transparent-fb)
[156.419601] Starting subtest: alpha-transparent-fb
[156.700205] Starting dynamic subtest: pipe-A-eDP-1
[157.333107] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.630s)
[157.342764] Starting dynamic subtest: pipe-D-eDP-1
[158.684870] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.350s)
[158.687510] Subtest alpha-transparent-fb: SUCCESS (2.265s)
[159.498683] [026/133] (861s left) i915_drm_fdinfo (memory-info-resident)
[159.544287] Starting subtest: memory-info-resident
[159.546550] Starting dynamic subtest: smem0
[162.381199] Dynamic subtest smem0: SUCCESS (2.838s)
[162.383237] Subtest memory-info-resident: SUCCESS (2.839s)
[162.452676] [027/133] (859s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[162.506133] Starting subtest: multi-wait-all-for-submit-unsubmitted
[162.607844] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[162.686584] [028/133] (858s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[162.791142] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[162.795387] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[162.881108] [029/133] (858s left) kms_vblank (query-forked-hang)
[162.972324] Starting subtest: query-forked-hang
[162.974635] Starting dynamic subtest: pipe-A-eDP-1
[189.719446] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.744s)
[189.733297] Starting dynamic subtest: pipe-D-eDP-1
[204.696806] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.976s)
[204.699299] Subtest query-forked-hang: SUCCESS (41.723s)
[205.551202] [030/133] (816s left) kms_pm_rpm (system-suspend-idle)
[207.088090] Starting subtest: system-suspend-idle
[227.227114] Subtest system-suspend-idle: SUCCESS (4.827s)
[231.626941] [031/133] (794s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[231.748755] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[236.038462] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.273s)
[237.238006] [032/133] (788s left) gem_caching (writes)
[237.292742] Subtest writes: SKIP (0.000s)
[237.351590] [033/133] (788s left) gem_lmem_swapping (smem-oom)
[237.390344] Subtest smem-oom: SKIP (0.000s)
[237.430452] [034/133] (788s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[237.536322] Starting subtest: fbc-rgb565-draw-mmap-wc
[241.598362] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.056s)
[242.742189] [035/133] (783s left) gem_exercise_blt (fast-copy)
[242.804306] Starting subtest: fast-copy
[242.806753] Starting dynamic subtest: linear-smem-smem
[242.828068] Dynamic subtest linear-smem-smem: SUCCESS (0.024s)
[242.840184] Starting dynamic subtest: xmajor-smem-smem
[242.846903] Dynamic subtest xmajor-smem-smem: SUCCESS (0.018s)
[242.856390] Starting dynamic subtest: tile4-smem-smem
[242.865716] Dynamic subtest tile4-smem-smem: SUCCESS (0.018s)
[242.875236] Starting dynamic subtest: tile64-smem-smem
[242.883996] Dynamic subtest tile64-smem-smem: SUCCESS (0.018s)
[242.886493] Subtest fast-copy: SUCCESS (0.080s)
[243.020402] [036/133] (782s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[243.112181] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[243.116291] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[243.206126] [037/133] (782s left) gem_exec_schedule (preempt-user)
[243.281119] Starting subtest: preempt-user
[243.283055] Starting dynamic subtest: rcs0
[243.298355] Dynamic subtest rcs0: SUCCESS (0.025s)
[243.309240] Starting dynamic subtest: bcs0
[243.324657] Dynamic subtest bcs0: SUCCESS (0.026s)
[243.334167] Starting dynamic subtest: vcs0
[243.355120] Dynamic subtest vcs0: SUCCESS (0.030s)
[243.364753] Starting dynamic subtest: vcs1
[243.373907] Dynamic subtest vcs1: SUCCESS (0.018s)
[243.383423] Starting dynamic subtest: vecs0
[243.391883] Dynamic subtest vecs0: SUCCESS (0.017s)
[243.401551] Starting dynamic subtest: ccs0
[243.411493] Dynamic subtest ccs0: SUCCESS (0.019s)
[243.413607] Subtest preempt-user: SUCCESS (0.138s)
[245.297095] [038/133] (780s left) kms_properties (get_properties-sanity-non-atomic)
[245.390060] Starting subtest: get_properties-sanity-non-atomic
[245.392242] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[245.476899] [039/133] (780s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[245.575310] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[245.579548] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[245.671183] [040/133] (780s left) gem_ctx_persistence (idempotent)
[245.721078] Starting subtest: idempotent
[245.723317] Subtest idempotent: SUCCESS (0.000s)
[245.809680] [041/133] (780s left) kms_feature_discovery (display-3x)
[245.896124] Starting subtest: display-3x
[245.900250] Subtest display-3x: SKIP (0.000s)
[245.986272] [042/133] (780s left) kms_psr (fbc-pr-basic)
[246.075140] Starting subtest: fbc-pr-basic
[246.079354] Subtest fbc-pr-basic: SKIP (0.000s)
[246.147187] [043/133] (779s left) gem_exec_balancer (full-late-pulse)
[246.209417] Starting subtest: full-late-pulse
[251.081274] Subtest full-late-pulse: SUCCESS (4.866s)
[251.416960] [044/133] (774s left) gem_exec_schedule (preempt-hang)
[251.602571] Starting subtest: preempt-hang
[251.605164] Starting dynamic subtest: rcs0
[251.867246] Dynamic subtest rcs0: SUCCESS (0.265s)
[251.877664] Starting dynamic subtest: bcs0
[252.103859] Dynamic subtest bcs0: SUCCESS (0.234s)
[252.115192] Starting dynamic subtest: vcs0
[252.345001] Dynamic subtest vcs0: SUCCESS (0.240s)
[252.356306] Starting dynamic subtest: vcs1
[252.578069] Dynamic subtest vcs1: SUCCESS (0.232s)
[252.587950] Starting dynamic subtest: vecs0
[252.809970] Dynamic subtest vecs0: SUCCESS (0.231s)
[252.820702] Starting dynamic subtest: ccs0
[253.038513] Dynamic subtest ccs0: SUCCESS (0.228s)
[253.040443] Subtest preempt-hang: SUCCESS (1.435s)
[253.332462] [045/133] (772s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[253.427548] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[253.431902] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s)
[253.498199] [046/133] (772s left) kms_chamelium_frames (hdmi-aspect-ratio)
[253.875070] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[254.681485] [047/133] (771s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[254.775095] Starting subtest: x-tiled-8bpp-rotate-270
[254.795853] Subtest x-tiled-8bpp-rotate-270: SKIP (0.020s)
[254.901046] [048/133] (771s left) gem_exec_params (sol-reset-not-gen7)
[254.951506] Starting subtest: sol-reset-not-gen7
[254.956844] Subtest sol-reset-not-gen7: SUCCESS (0.003s)
[255.070425] [049/133] (771s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[255.183442] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[255.187598] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[255.273857] [050/133] (771s left) perf (invalid-oa-format-id)
[255.390611] Starting subtest: invalid-oa-format-id
[255.402704] Subtest invalid-oa-format-id: SUCCESS (0.013s)
[255.449684] [051/133] (771s left) kms_busy (extended-pageflip-hang-newfb)
[255.545747] Starting subtest: extended-pageflip-hang-newfb
[255.548053] Starting dynamic subtest: pipe-A
[282.050458] Dynamic subtest pipe-A: SUCCESS (26.500s)
[282.060691] Starting dynamic subtest: pipe-D
[297.087100] Dynamic subtest pipe-D: SUCCESS (15.028s)
[297.089423] Subtest extended-pageflip-hang-newfb: SUCCESS (41.532s)
[297.935997] [052/133] (728s left) gem_eio (context-create)
[298.180986] Starting subtest: context-create
[298.492756] Subtest context-create: SUCCESS (0.311s)
[298.602012] [053/133] (727s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[298.700094] Starting subtest: crc-primary-suspend-y-tiled-ccs
[298.702366] Starting dynamic subtest: pipe-A-eDP-1
[298.711206] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[298.722372] Starting dynamic subtest: pipe-B-eDP-1
[298.730845] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[298.740063] Starting dynamic subtest: pipe-C-eDP-1
[298.747937] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[298.757287] Starting dynamic subtest: pipe-D-eDP-1
[298.765481] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[298.769418] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.004s)
[300.909688] [054/133] (725s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[301.007002] Starting subtest: 4-tiled-8bpp-rotate-0
[302.244020] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.236s)
[302.372949] [055/133] (724s left) vgem_basic (second-client)
[302.495223] Starting subtest: second-client
[302.519918] Subtest second-client: SUCCESS (0.022s)
[302.572553] [FACT vgem_basic (second-client)] new: kernel.kmod_is_loaded.vgem: true
[302.575771] [056/133] (724s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[302.672347] Starting subtest: y-tiled-32bpp-rotate-0
[302.675871] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[302.765065] [057/133] (723s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[302.898489] Starting subtest: cursorA-vs-flipA-legacy
[314.031400] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.128s)
[314.831478] [058/133] (711s left) sysfs_heartbeat_interval (idempotent)
[314.900375] Starting subtest: idempotent
[314.902728] Starting dynamic subtest: vecs0
[314.905046] Dynamic subtest vecs0: SUCCESS (0.000s)
[314.919644] Starting dynamic subtest: ccs0
[314.921835] Dynamic subtest ccs0: SUCCESS (0.001s)
[314.931918] Starting dynamic subtest: vcs0
[314.933805] Dynamic subtest vcs0: SUCCESS (0.001s)
[314.942643] Starting dynamic subtest: bcs0
[314.944551] Dynamic subtest bcs0: SUCCESS (0.002s)
[314.953350] Starting dynamic subtest: rcs0
[314.955251] Dynamic subtest rcs0: SUCCESS (0.002s)
[314.964224] Starting dynamic subtest: vcs1
[314.966124] Dynamic subtest vcs1: SUCCESS (0.001s)
[314.967999] Subtest idempotent: SUCCESS (0.017s)
[315.032165] [059/133] (711s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[315.136308] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[318.830075] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.696s)
[320.038577] [060/133] (706s left) sriov_basic (bind-unbind-vf)
[320.096115] Starting subtest: bind-unbind-vf
[320.098539] Starting dynamic subtest: vf-1
[320.170713] Dynamic subtest vf-1: FAIL (0.018s)
[320.180809] Starting dynamic subtest: vf-2
[320.252332] Dynamic subtest vf-2: FAIL (0.004s)
[320.263567] Starting dynamic subtest: vf-3
[320.335518] Dynamic subtest vf-3: FAIL (0.004s)
[320.346124] Starting dynamic subtest: vf-4
[320.418089] Dynamic subtest vf-4: FAIL (0.004s)
[320.428987] Starting dynamic subtest: vf-5
[320.501195] Dynamic subtest vf-5: FAIL (0.004s)
[320.512237] Starting dynamic subtest: vf-6
[320.584516] Dynamic subtest vf-6: FAIL (0.004s)
[320.594832] Starting dynamic subtest: vf-7
[320.666519] Dynamic subtest vf-7: FAIL (0.004s)
[320.675840] Starting dynamic subtest: vf-random
[320.747755] Dynamic subtest vf-random: FAIL (0.004s)
[320.758464] Starting dynamic subtest: vf-last
[320.830241] Dynamic subtest vf-last: FAIL (0.507s)
[320.832547] Subtest bind-unbind-vf: FAIL (0.557s)
[320.872834] [061/133] (706s left) gem_userptr_blits (usage-restrictions)
[320.946414] Starting subtest: usage-restrictions
[320.948601] Subtest usage-restrictions: SUCCESS (0.000s)
[321.107049] [062/133] (705s left) gem_exec_reloc (basic-cpu-read-noreloc)
[321.163954] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[321.236189] [063/133] (705s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[321.338639] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[330.810037] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.470s)
[330.924618] [064/133] (696s left) gem_exec_suspend (basic-s3-devices)
[330.990433] Starting subtest: basic-S3-devices
[330.992820] Starting dynamic subtest: smem
[342.248854] Dynamic subtest smem: SUCCESS (11.259s)
[342.251822] Subtest basic-S3-devices: SUCCESS (11.261s)
[345.439928] [065/133] (684s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[345.546299] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[345.550262] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[345.666930] [066/133] (684s left) gem_ctx_param (basic-default)
[345.726373] Starting subtest: basic-default
[345.728823] Subtest basic-default: SUCCESS (0.000s)
[345.835298] [067/133] (684s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[345.955822] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[345.960124] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[346.055790] [068/133] (684s left) i915_hangman (gt-engine-error)
[346.124260] Starting subtest: gt-engine-error
[346.126230] Starting dynamic subtest: rcs0
[347.207368] Dynamic subtest rcs0: SUCCESS (1.083s)
[347.223096] Starting dynamic subtest: bcs0
[349.126538] Dynamic subtest bcs0: SUCCESS (1.916s)
[349.139767] Starting dynamic subtest: vcs0
[350.214081] Dynamic subtest vcs0: SUCCESS (1.085s)
[350.229392] Starting dynamic subtest: vcs1
[352.198615] Dynamic subtest vcs1: SUCCESS (1.981s)
[352.211460] Starting dynamic subtest: vecs0
[354.118731] Dynamic subtest vecs0: SUCCESS (1.918s)
[354.132800] Starting dynamic subtest: ccs0
[356.166821] Dynamic subtest ccs0: SUCCESS (2.045s)
[356.169842] Subtest gt-engine-error: SUCCESS (10.042s)
[356.250131] [069/133] (674s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[356.647813] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[357.436040] [070/133] (672s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[357.528154] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[357.911526] Starting dynamic subtest: pipe-A-eDP-1
[360.642520] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.729s)
[361.007989] Starting dynamic subtest: pipe-B-eDP-1
[363.769004] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.768s)
[364.148384] Starting dynamic subtest: pipe-C-eDP-1
[366.905022] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.763s)
[367.266642] Starting dynamic subtest: pipe-D-eDP-1
[370.040822] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.780s)
[370.043079] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.513s)
[371.227794] [071/133] (659s left) kms_selftest (drm_dp_mst_helper)
[371.271891] Starting subtest: drm_dp_mst_helper
[371.327034] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[371.449802] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.125s)
[371.459950] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[371.555472] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.105s)
[371.565424] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[371.659156] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.100s)
[371.661039] Subtest drm_dp_mst_helper: SUCCESS (0.385s)
[371.804465] [072/133] (658s left) kms_cursor_crc (cursor-size-hints)
[371.947137] Starting subtest: cursor-size-hints
[371.949449] Starting dynamic subtest: pipe-A-eDP-1
[372.565564] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.616s)
[372.575747] Starting dynamic subtest: pipe-D-eDP-1
[373.800956] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.235s)
[373.803103] Subtest cursor-size-hints: SUCCESS (1.854s)
[374.605545] [073/133] (655s left) kms_cursor_legacy (single-bo)
[374.725986] Starting subtest: single-bo
[374.728065] Starting dynamic subtest: pipe-A
[380.110965] Dynamic subtest pipe-A: SUCCESS (5.380s)
[380.123136] Starting dynamic subtest: pipe-B
[385.493957] Dynamic subtest pipe-B: SUCCESS (5.379s)
[385.506137] Starting dynamic subtest: pipe-C
[390.876959] Dynamic subtest pipe-C: SUCCESS (5.379s)
[390.888923] Starting dynamic subtest: pipe-D
[396.259490] Dynamic subtest pipe-D: SUCCESS (5.379s)
[396.271868] Starting dynamic subtest: all-pipes
[401.650550] Dynamic subtest all-pipes: SUCCESS (5.386s)
[401.652969] Subtest single-bo: SUCCESS (26.917s)
[401.799118] [074/133] (628s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[401.892869] Starting subtest: 2x-absolute-wf_vblank-interruptible
[401.905620] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[401.978287] [075/133] (628s left) gem_pxp (create-protected-buffer)
[402.100033] Starting subtest: create-protected-buffer
[402.102392] Subtest create-protected-buffer: SUCCESS (0.000s)
[402.205690] [076/133] (628s left) kms_big_fb (linear-32bpp-rotate-0)
[402.315079] Starting subtest: linear-32bpp-rotate-0
[403.827734] Subtest linear-32bpp-rotate-0: SUCCESS (1.512s)
[403.963433] [077/133] (626s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[404.063385] Starting subtest: bad-pixel-format-yf-tiled-ccs
[404.065300] Starting dynamic subtest: pipe-A-eDP-1
[404.084242] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[404.098613] Starting dynamic subtest: pipe-B-eDP-1
[404.116477] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[404.125838] Starting dynamic subtest: pipe-C-eDP-1
[404.143265] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[404.152305] Starting dynamic subtest: pipe-D-eDP-1
[404.169965] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[404.173480] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.005s)
[406.283471] [078/133] (624s left) prime_mmap_coherency (ioctl-errors)
[406.353456] Starting subtest: ioctl-errors
[422.059624] Subtest ioctl-errors: SUCCESS (15.706s)
[422.231470] [079/133] (608s left) core_setmaster (master-drop-set-shared-fd)
[422.364416] Starting subtest: master-drop-set-shared-fd
[422.374801] Subtest master-drop-set-shared-fd: SUCCESS (0.010s)
[422.416878] [080/133] (608s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[422.516850] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[422.518651] Starting dynamic subtest: pipe-A-eDP-1
[422.540645] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[422.553241] Starting dynamic subtest: pipe-B-eDP-1
[422.572550] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[422.581767] Starting dynamic subtest: pipe-C-eDP-1
[422.601519] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[422.613197] Starting dynamic subtest: pipe-D-eDP-1
[422.632501] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[422.636041] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[424.765040] [081/133] (606s left) syncobj_timeline (single-wait-all-available-submitted)
[424.838066] Starting subtest: single-wait-all-available-submitted
[424.840401] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[424.948799] [082/133] (605s left) kms_psr (psr2-primary-page-flip)
[425.045958] Starting subtest: psr2-primary-page-flip
[425.048113] Starting dynamic subtest: eDP-1
[426.744314] Dynamic subtest eDP-1: SUCCESS (1.696s)
[426.746575] Subtest psr2-primary-page-flip: SUCCESS (1.697s)
[427.628377] [083/133] (603s left) kms_psr (fbc-psr-sprite-plane-move)
[427.721896] Starting subtest: fbc-psr-sprite-plane-move
[427.724014] Starting dynamic subtest: eDP-1
[427.728035] Dynamic subtest eDP-1: SKIP (0.000s)
[427.731937] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[427.799991] [084/133] (603s left) api_intel_allocator (reserve-simple)
[427.860435] Starting subtest: reserve-simple
[427.862671] Subtest reserve-simple: SUCCESS (0.003s)
[427.996237] [085/133] (602s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[428.101874] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[428.105737] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[428.182176] [086/133] (602s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[428.277852] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[428.280033] Starting dynamic subtest: pipe-A-eDP-1
[428.300289] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.024s)
[428.310972] Starting dynamic subtest: pipe-B-eDP-1
[428.332059] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.022s)
[428.342443] Starting dynamic subtest: pipe-C-eDP-1
[428.362345] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.023s)
[428.372616] Starting dynamic subtest: pipe-D-eDP-1
[428.392830] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.024s)
[428.394963] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.099s)
[430.601629] [087/133] (600s left) kms_psr (psr-sprite-blt)
[430.696797] Starting subtest: psr-sprite-blt
[430.699050] Starting dynamic subtest: eDP-1
[432.432635] Dynamic subtest eDP-1: SUCCESS (1.734s)
[432.434914] Subtest psr-sprite-blt: SUCCESS (1.735s)
[433.269166] [088/133] (597s left) api_intel_bb (object-noreloc-purge-cache-simple)
[433.324929] Starting subtest: object-noreloc-purge-cache-simple
[433.329544] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s)
[433.425822] [089/133] (597s left) kms_cursor_crc (cursor-sliding-512x512)
[433.696605] Starting subtest: cursor-sliding-512x512
[433.701059] Subtest cursor-sliding-512x512: SKIP (0.001s)
[433.855290] [090/133] (597s left) gem_exec_fence (concurrent)
[434.032763] Starting subtest: concurrent
[434.037072] Subtest concurrent: SKIP (0.000s)
[436.301534] [091/133] (594s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[436.407448] Starting subtest: yf-tiled-8bpp-rotate-180
[436.411888] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[436.514166] [092/133] (594s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[436.606841] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[436.611265] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[436.694641] [093/133] (594s left) gem_ppgtt (blt-vs-render-ctx0)
[436.776278] Starting subtest: blt-vs-render-ctx0
[469.507846] Subtest blt-vs-render-ctx0: SUCCESS (32.720s)
[469.659116] [094/133] (561s left) gem_render_linear_blits (basic)
[469.746478] Starting subtest: basic
[469.779881] Subtest basic: SUCCESS (0.033s)
[469.868439] [095/133] (561s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[469.968609] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[477.340051] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.373s)
[477.454623] [096/133] (554s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[477.518756] Starting subtest: multi-wait-all-for-submit-available-signaled
[477.521530] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[477.607308] [097/133] (553s left) gem_tiled_pread_basic (basic)
[477.663960] Subtest basic: SKIP (0.000s)
[477.729170] [098/133] (553s left) i915_query (test-query-geometry-subslices)
[477.782830] Starting subtest: test-query-geometry-subslices
[477.785067] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[477.872481] [099/133] (553s left) kms_atomic (test-only)
[477.964361] Starting subtest: test-only
[478.042475] Starting dynamic subtest: pipe-A-eDP-1
[480.562838] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.520s)
[480.564994] Subtest test-only: SUCCESS (2.599s)
[481.427708] [100/133] (550s left) kms_psr (psr2-basic)
[481.525749] Starting subtest: psr2-basic
[481.528032] Starting dynamic subtest: eDP-1
[483.095937] Dynamic subtest eDP-1: SUCCESS (1.571s)
[483.098485] Subtest psr2-basic: SUCCESS (1.572s)
[483.908360] [101/133] (547s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[484.002913] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[484.816337] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.815s)
[484.968839] [102/133] (546s left) gem_exec_params (invalid-bsd2-flag-on-render)
[485.031347] Starting subtest: invalid-bsd2-flag-on-render
[485.033744] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[485.148928] [103/133] (546s left) gem_mmap_offset (bad-extensions)
[485.217203] Starting subtest: bad-extensions
[485.219435] Subtest bad-extensions: SUCCESS (0.000s)
[485.331056] [104/133] (546s left) gem_exec_fence (syncobj-invalid-wait)
[485.816797] Starting subtest: syncobj-invalid-wait
[485.819668] Subtest syncobj-invalid-wait: SUCCESS (0.001s)
[487.295075] [105/133] (544s left) gem_mmap_wc (copy)
[487.350458] Subtest copy: SKIP (0.000s)
[487.409626] [106/133] (544s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[487.504198] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[487.506628] Starting dynamic subtest: pipe-A
[487.616488] Dynamic subtest pipe-A: SKIP (0.107s)
[487.628036] Starting dynamic subtest: pipe-B
[488.767812] Dynamic subtest pipe-B: SKIP (1.150s)
[488.781495] Starting dynamic subtest: pipe-C
[489.982603] Dynamic subtest pipe-C: SUCCESS (1.219s)
[489.994999] Starting dynamic subtest: pipe-D
[491.128136] Dynamic subtest pipe-D: SUCCESS (1.145s)
[491.130413] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.625s)
[492.332068] [107/133] (539s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[492.396135] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[492.500826] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.105s)
[492.578995] [108/133] (539s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[492.674818] Starting subtest: primary-yf-tiled-reflect-x-270
[492.679392] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[492.746868] [109/133] (539s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[492.851438] Starting subtest: psr-rgb101010-draw-mmap-cpu
[499.894631] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (7.045s)
[499.984896] [110/133] (532s left) kms_frontbuffer_tracking (fbc-suspend)
[500.075560] Starting subtest: fbc-suspend
[538.482325] Subtest fbc-suspend: SUCCESS (8.313s)
[543.685145] [111/133] (492s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[543.791029] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[547.768688] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (3.960s)
[548.982263] [112/133] (487s left) gem_softpin (evict-active-interruptible)
[549.039300] Starting subtest: evict-active-interruptible
[549.045739] Subtest evict-active-interruptible: SUCCESS (0.005s)
[549.166023] [113/133] (487s left) perf_pmu (busy-idle-no-semaphores)
[549.212275] Starting subtest: busy-idle-no-semaphores
[549.214588] Starting dynamic subtest: rcs0
[549.877798] Dynamic subtest rcs0: SUCCESS (0.663s)
[549.891293] Starting dynamic subtest: bcs0
[550.532393] Dynamic subtest bcs0: SUCCESS (0.649s)
[550.545425] Starting dynamic subtest: vcs0
[551.186237] Dynamic subtest vcs0: SUCCESS (0.650s)
[551.202243] Starting dynamic subtest: vcs1
[552.931076] Dynamic subtest vcs1: SUCCESS (1.737s)
[552.944524] Starting dynamic subtest: vecs0
[554.915651] Dynamic subtest vecs0: SUCCESS (1.977s)
[554.928532] Starting dynamic subtest: ccs0
[556.961374] Dynamic subtest ccs0: SUCCESS (2.039s)
[556.964353] Subtest busy-idle-no-semaphores: SUCCESS (7.731s)
[557.081536] [114/133] (479s left) gem_mmap_gtt (basic-small-bo)
[557.139458] Subtest basic-small-bo: SKIP (0.000s)
[557.199676] [115/133] (479s left) gem_render_copy (y-tiled-to-vebox-linear)
[557.269662] Starting subtest: y-tiled-to-vebox-linear
[557.274295] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[557.464073] [116/133] (478s left) sw_sync (sync_multi_timeline_wait)
[557.501968] Starting subtest: sync_multi_timeline_wait
[557.504442] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[557.560832] [117/133] (478s left) kms_invalid_mode (zero-clock)
[557.657631] Starting subtest: zero-clock
[557.660068] Starting dynamic subtest: pipe-A-eDP-1
[557.958388] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.301s)
[557.969797] Starting dynamic subtest: pipe-B-eDP-1
[557.971738] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[557.982490] Starting dynamic subtest: pipe-C-eDP-1
[557.984943] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[557.995240] Starting dynamic subtest: pipe-D-eDP-1
[557.997465] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[557.999632] Subtest zero-clock: SUCCESS (0.307s)
[558.830756] [118/133] (477s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[558.919508] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[558.924007] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[559.007167] [119/133] (477s left) kms_pm_rpm (cursor)
[560.525245] Starting subtest: cursor
[560.530295] Subtest cursor: SKIP (0.000s)
[561.283107] [120/133] (475s left) kms_content_protection (uevent)
[561.418610] Starting subtest: uevent
[561.422862] Subtest uevent: SKIP (0.000s)
[561.525946] [121/133] (475s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[561.621038] Starting subtest: plane-ctm3x4-lut1d
[561.745930] Starting dynamic subtest: pipe-A-plane-0
[562.105067] Dynamic subtest pipe-A-plane-0: FAIL (0.193s)
[562.116365] Starting dynamic subtest: pipe-A-plane-1
[562.300439] Dynamic subtest pipe-A-plane-1: FAIL (0.170s)
[562.311029] Starting dynamic subtest: pipe-A-plane-2
[562.515577] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[563.467489] Starting dynamic subtest: pipe-B-plane-0
[563.891336] Dynamic subtest pipe-B-plane-0: FAIL (0.164s)
[563.909529] Starting dynamic subtest: pipe-B-plane-1
[564.220827] Dynamic subtest pipe-B-plane-1: FAIL (0.177s)
[564.232910] Starting dynamic subtest: pipe-B-plane-2
[564.524199] Dynamic subtest pipe-B-plane-2: FAIL (0.166s)
[565.184377] Starting dynamic subtest: pipe-C-plane-0
[565.670698] Dynamic subtest pipe-C-plane-0: FAIL (0.140s)
[565.682214] Starting dynamic subtest: pipe-C-plane-1
[566.074030] Dynamic subtest pipe-C-plane-1: FAIL (0.590s)
[566.086621] Starting dynamic subtest: pipe-C-plane-2
[566.440351] Dynamic subtest pipe-C-plane-2: FAIL (0.172s)
[567.327066] Starting dynamic subtest: pipe-D-plane-0
[567.933576] Dynamic subtest pipe-D-plane-0: FAIL (0.152s)
[567.945873] Starting dynamic subtest: pipe-D-plane-1
[568.402258] Dynamic subtest pipe-D-plane-1: FAIL (0.611s)
[568.414344] Starting dynamic subtest: pipe-D-plane-2
[568.854390] Dynamic subtest pipe-D-plane-2: FAIL (0.551s)
[568.942614] Subtest plane-ctm3x4-lut1d: FAIL (7.315s)
[569.818311] [122/133] (466s left) gem_flink_race (flink_name)
[569.856355] Starting subtest: flink_name
[575.260281] Subtest flink_name: SUCCESS (5.391s)
[575.373919] [123/133] (461s left) perf (sysctl-defaults)
[575.464196] Starting subtest: sysctl-defaults
[575.466542] Subtest sysctl-defaults: SUCCESS (0.000s)
[575.540287] [124/133] (461s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[575.642390] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[575.646363] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[575.758476] [125/133] (461s left) gem_ctx_isolation (clean)
[575.823603] Starting subtest: clean
[575.825518] Starting dynamic subtest: rcs0
[575.869159] Dynamic subtest rcs0: SUCCESS (0.048s)
[575.882219] Starting dynamic subtest: bcs0
[575.940684] Dynamic subtest bcs0: SUCCESS (0.070s)
[575.953013] Starting dynamic subtest: vcs0
[576.004894] Dynamic subtest vcs0: SUCCESS (0.063s)
[576.017048] Starting dynamic subtest: vcs1
[577.332036] Dynamic subtest vcs1: SUCCESS (1.326s)
[577.345175] Starting dynamic subtest: vecs0
[579.322167] Dynamic subtest vecs0: SUCCESS (1.989s)
[579.333559] Starting dynamic subtest: ccs0
[581.305092] Dynamic subtest ccs0: SUCCESS (1.982s)
[581.307635] Subtest clean: SUCCESS (5.485s)
[581.553153] [126/133] (455s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[581.610337] Starting subtest: yf-tiled-ccs-to-yf-tiled
[581.614801] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[581.800633] [127/133] (455s left) gem_create (create-ext-cpu-access-big)
[581.970363] Starting subtest: create-ext-cpu-access-big
[581.974620] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[582.046153] [128/133] (454s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[582.158363] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[588.701021] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.545s)
[588.815799] [129/133] (448s left) i915_query (query-garbage)
[588.875525] Starting subtest: query-garbage
[588.877774] Subtest query-garbage: SUCCESS (0.000s)
[588.971155] [130/133] (448s left) gem_close_race (basic-threads)
[589.027363] Starting subtest: basic-threads
[590.394007] Subtest basic-threads: SUCCESS (1.366s)
[590.580058] [131/133] (446s left) syncobj_wait (single-wait-all-for-submit-signaled)
[590.645062] Starting subtest: single-wait-all-for-submit-signaled
[590.647539] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[590.731426] [132/133] (446s left) kms_cursor_crc (cursor-offscreen-128x42)
[590.946250] Starting subtest: cursor-offscreen-128x42
[590.996767] Subtest cursor-offscreen-128x42: SKIP (0.047s)
[591.191882] [133/133] (445s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[591.293110] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[591.296934] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[591.363286] Closing watchdogs
results: parsing output: 0/ for test: i915_pm_rps
results: parsing output: 1/ for test: kms_fbcon_fbt
results: parsing output: 2/ for test: kms_flip
results: parsing output: 3/ for test: perf_pmu
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: gem_exec_whisper
results: parsing output: 7/ for test: dumb_buffer
results: parsing output: 8/ for test: gem_spin_batch
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: kms_flip
results: parsing output: 11/ for test: gem_eio
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_lease
results: parsing output: 14/ for test: kms_atomic_interruptible
results: parsing output: 15/ for test: kms_plane
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_cursor_legacy
results: parsing output: 18/ for test: gem_set_tiling_vs_blt
results: parsing output: 19/ for test: gen9_exec_parse
results: parsing output: 20/ for test: kms_chamelium_audio
results: parsing output: 21/ for test: kms_plane
results: parsing output: 22/ for test: gem_exec_schedule
results: parsing output: 23/ for test: kms_plane_scaling
results: parsing output: 24/ for test: kms_plane_alpha_blend
results: parsing output: 25/ for test: i915_drm_fdinfo
results: parsing output: 26/ for test: syncobj_wait
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_vblank
results: parsing output: 29/ for test: kms_pm_rpm
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_caching
results: parsing output: 32/ for test: gem_lmem_swapping
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_exercise_blt
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_exec_schedule
results: parsing output: 37/ for test: kms_properties
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: gem_ctx_persistence
results: parsing output: 40/ for test: kms_feature_discovery
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: gem_exec_balancer
results: parsing output: 43/ for test: gem_exec_schedule
results: parsing output: 44/ for test: kms_flip_scaled_crc
results: parsing output: 45/ for test: kms_chamelium_frames
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: gem_exec_params
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: perf
results: parsing output: 50/ for test: kms_busy
results: parsing output: 51/ for test: gem_eio
results: parsing output: 52/ for test: kms_ccs
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: vgem_basic
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: sysfs_heartbeat_interval
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: sriov_basic
results: parsing output: 60/ for test: gem_userptr_blits
results: parsing output: 61/ for test: gem_exec_reloc
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_exec_suspend
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gem_ctx_param
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: i915_hangman
results: parsing output: 68/ for test: kms_chamelium_hpd
results: parsing output: 69/ for test: kms_async_flips
results: parsing output: 70/ for test: kms_selftest
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: kms_cursor_legacy
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: gem_pxp
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_ccs
results: parsing output: 77/ for test: prime_mmap_coherency
results: parsing output: 78/ for test: core_setmaster
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: kms_psr
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: api_intel_allocator
results: parsing output: 84/ for test: kms_psr2_sf
results: parsing output: 85/ for test: kms_ccs
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: api_intel_bb
results: parsing output: 88/ for test: kms_cursor_crc
results: parsing output: 89/ for test: gem_exec_fence
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_ppgtt
results: parsing output: 93/ for test: gem_render_linear_blits
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: syncobj_timeline
results: parsing output: 96/ for test: gem_tiled_pread_basic
results: parsing output: 97/ for test: i915_query
results: parsing output: 98/ for test: kms_atomic
results: parsing output: 99/ for test: kms_psr
results: parsing output: 100/ for test: kms_big_fb
results: parsing output: 101/ for test: gem_exec_params
results: parsing output: 102/ for test: gem_mmap_offset
results: parsing output: 103/ for test: gem_exec_fence
results: parsing output: 104/ for test: gem_mmap_wc
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: syncobj_timeline
results: parsing output: 107/ for test: kms_rotation_crc
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_softpin
results: parsing output: 112/ for test: perf_pmu
results: parsing output: 113/ for test: gem_mmap_gtt
results: parsing output: 114/ for test: gem_render_copy
results: parsing output: 115/ for test: sw_sync
results: parsing output: 116/ for test: kms_invalid_mode
results: parsing output: 117/ for test: kms_flip_scaled_crc
results: parsing output: 118/ for test: kms_pm_rpm
results: parsing output: 119/ for test: kms_content_protection
results: parsing output: 120/ for test: kms_color_pipeline
results: parsing output: 121/ for test: gem_flink_race
results: parsing output: 122/ for test: perf
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_ctx_isolation
results: parsing output: 125/ for test: gem_render_copy
results: parsing output: 126/ for test: gem_create
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: i915_query
results: parsing output: 129/ for test: gem_close_race
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: kms_cursor_crc
results: parsing output: 132/ for test: kms_psr2_sf
Done.