[58.531934] Initializing watchdogs
[58.531978]   /dev/watchdog0
[58.544983] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.549203] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.551502] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.554929] [001/132] (960s left) kms_plane_multiple (tiling-x)
[58.681131] Starting subtest: tiling-x
[58.683526] Starting dynamic subtest: pipe-A-eDP-1
[62.342588] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.667s)
[62.351197] Starting dynamic subtest: pipe-B-eDP-1
[66.771633] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.433s)
[66.780703] Starting dynamic subtest: pipe-C-eDP-1
[71.116176] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.346s)
[71.127266] Starting dynamic subtest: pipe-D-eDP-1
[75.442220] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.326s)
[75.445052] Subtest tiling-x: SUCCESS (16.777s)
[76.261472] [002/132] (942s left) gem_exec_balancer (parallel-keep-in-fence)
[76.533660] Starting subtest: parallel-keep-in-fence
[77.643281] Subtest parallel-keep-in-fence: SUCCESS (1.113s)
[77.763754] [003/132] (941s left) kms_setmode (basic-clone-single-crtc)
[77.821698] Starting subtest: basic-clone-single-crtc
[77.828177] Subtest basic-clone-single-crtc: SKIP (0.000s)
[77.914434] [004/132] (941s left) kms_selftest (drm_damage_helper)
[77.948569] Starting subtest: drm_damage_helper
[77.961046] Starting dynamic subtest: drm_test_damage_iter_no_damage
[78.062405] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.101s)
[78.069726] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[78.160149] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.097s)
[78.168063] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[78.255535] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.096s)
[78.264594] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[78.358444] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.102s)
[78.367092] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[78.462549] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.104s)
[78.471059] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[78.558068] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.095s)
[78.564385] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[78.673130] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.114s)
[78.681776] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[78.774778] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.102s)
[78.783742] Starting dynamic subtest: drm_test_damage_iter_single_damage
[78.879645] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.103s)
[78.886465] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[78.971709] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.091s)
[78.978988] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[79.079070] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.106s)
[79.087522] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[79.173658] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.095s)
[79.181776] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[79.277444] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.103s)
[79.285954] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[79.381385] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.103s)
[79.389738] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[79.498490] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.115s)
[79.510066] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[79.595711] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.098s)
[79.607136] Starting dynamic subtest: drm_test_damage_iter_damage
[79.689533] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.094s)
[79.698457] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[79.792493] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.102s)
[79.801454] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[79.889512] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.097s)
[79.898214] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[79.984200] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.094s)
[79.990768] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[80.084468] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.101s)
[80.086744] Subtest drm_damage_helper: SUCCESS (2.136s)
[80.242998] [005/132] (938s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[80.356070] Starting subtest: fbc-shrfb-scaledprimary
[83.354247] Subtest fbc-shrfb-scaledprimary: SUCCESS (3.002s)
[84.557232] [006/132] (934s left) kms_pm_rpm (fences)
[86.105482] Starting subtest: fences
[86.110378] Subtest fences: SKIP (0.000s)
[86.801443] [007/132] (932s left) gem_exec_schedule (pi-distinct-iova)
[86.988233] Starting subtest: pi-distinct-iova
[86.990375] Starting dynamic subtest: rcs0
[87.067908] Dynamic subtest rcs0: SUCCESS (0.082s)
[87.081215] Starting dynamic subtest: vcs0
[87.130985] Dynamic subtest vcs0: SUCCESS (0.062s)
[87.141067] Starting dynamic subtest: vcs1
[87.192585] Dynamic subtest vcs1: SUCCESS (0.062s)
[87.204594] Starting dynamic subtest: vecs0
[87.258581] Dynamic subtest vecs0: SUCCESS (0.064s)
[87.266952] Starting dynamic subtest: ccs0
[87.318645] Dynamic subtest ccs0: SUCCESS (0.062s)
[87.321145] Subtest pi-distinct-iova: SUCCESS (0.334s)
[89.016673] [008/132] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[89.114621] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[89.118861] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[89.211867] [009/132] (930s left) gem_render_tiled_blits (basic)
[89.275991] Subtest basic: SKIP (0.000s)
[89.350450] [010/132] (930s left) prime_mmap (test_invalid_sync_flags)
[89.423697] Starting subtest: test_invalid_sync_flags
[89.426205] Starting dynamic subtest: test_invalid_sync_flags-smem
[89.428118] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[89.430251] Subtest test_invalid_sync_flags: SUCCESS (0.000s)
[89.510725] [011/132] (929s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[89.612651] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[89.616656] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[89.695478] [012/132] (929s left) gem_exec_whisper (basic-forked-all)
[89.761954] Starting subtest: basic-forked-all
[102.957163] Subtest basic-forked-all: SUCCESS (13.194s)
[103.141131] [013/132] (916s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[103.239686] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[103.241956] Starting dynamic subtest: pipe-A-eDP-1
[103.335081] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[103.345421] Starting dynamic subtest: pipe-B-eDP-1
[104.462875] Dynamic subtest pipe-B-eDP-1: SKIP (1.126s)
[104.467504] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.227s)
[105.651251] [014/132] (913s left) kms_draw_crc (draw-method-pwrite)
[105.745361] Starting subtest: draw-method-pwrite
[105.747229] Starting dynamic subtest: xrgb8888-untiled
[106.343583] Dynamic subtest xrgb8888-untiled: SUCCESS (0.600s)
[106.352404] Starting dynamic subtest: rgb565-untiled
[106.787860] Dynamic subtest rgb565-untiled: SUCCESS (0.444s)
[106.795848] Starting dynamic subtest: xrgb2101010-untiled
[107.343066] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.554s)
[107.353705] Starting dynamic subtest: xbgr16161616f-untiled
[108.087864] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.744s)
[108.096825] Starting dynamic subtest: xrgb8888-xtiled
[109.868253] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.777s)
[109.877755] Starting dynamic subtest: rgb565-xtiled
[110.954596] Dynamic subtest rgb565-xtiled: SUCCESS (1.087s)
[110.964037] Starting dynamic subtest: xrgb2101010-xtiled
[112.754727] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.800s)
[112.762788] Starting dynamic subtest: xbgr16161616f-xtiled
[116.032295] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.277s)
[116.040964] Starting dynamic subtest: xrgb8888-4tiled
[117.121211] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.088s)
[117.128966] Starting dynamic subtest: rgb565-4tiled
[117.677376] Dynamic subtest rgb565-4tiled: SUCCESS (0.555s)
[117.687967] Starting dynamic subtest: xrgb2101010-4tiled
[118.765923] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.089s)
[118.774693] Starting dynamic subtest: xbgr16161616f-4tiled
[121.754856] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.988s)
[121.757564] Subtest draw-method-pwrite: SUCCESS (16.012s)
[121.868663] [015/132] (897s left) i915_suspend (basic-s3-without-i915)
[121.912118] Starting subtest: basic-s3-without-i915
[123.560498] Subtest basic-s3-without-i915: SKIP (1.643s)
[125.081657] [016/132] (894s left) kms_psr (psr2-sprite-plane-onoff)
[125.194160] Starting subtest: psr2-sprite-plane-onoff
[125.196421] Starting dynamic subtest: eDP-1
[126.992528] Dynamic subtest eDP-1: SUCCESS (1.797s)
[126.994914] Subtest psr2-sprite-plane-onoff: SUCCESS (1.798s)
[127.821047] [017/132] (891s left) kms_atomic (plane-invalid-params)
[127.919177] Starting subtest: plane-invalid-params
[127.921447] Starting dynamic subtest: pipe-A-eDP-1
[128.344568] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.424s)
[128.346713] Subtest plane-invalid-params: SUCCESS (0.425s)
[129.153099] [018/132] (890s left) syncobj_wait (wait-any-snapshot)
[129.210880] Starting subtest: wait-any-snapshot
[129.293530] Subtest wait-any-snapshot: SUCCESS (0.082s)
[129.378033] [019/132] (890s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[129.478898] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[129.481162] Starting dynamic subtest: pipe-A
[129.500535] Dynamic subtest pipe-A: SKIP (0.019s)
[129.508647] Starting dynamic subtest: pipe-B
[129.536978] Dynamic subtest pipe-B: SKIP (0.035s)
[129.546198] Starting dynamic subtest: pipe-C
[129.572237] Dynamic subtest pipe-C: SKIP (0.034s)
[129.583234] Starting dynamic subtest: pipe-D
[129.606308] Dynamic subtest pipe-D: SKIP (0.034s)
[129.610141] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.124s)
[129.715858] [020/132] (890s left) device_reset (cold-reset-bound)
[129.765721] Starting subtest: cold-reset-bound
[129.770589] Subtest cold-reset-bound: SKIP (0.000s)
[129.831820] [021/132] (889s left) kms_joiner (basic-max-non-joiner)
[129.925582] Starting subtest: basic-max-non-joiner
[129.929775] Subtest basic-max-non-joiner: SKIP (0.000s)
[129.987020] [022/132] (889s left) syncobj_wait (multi-wait-signaled)
[130.036885] Starting subtest: multi-wait-signaled
[130.039203] Subtest multi-wait-signaled: SUCCESS (0.000s)
[130.127851] [023/132] (889s left) gen9_exec_parse (bb-chained)
[130.182307] Subtest bb-chained: SKIP (0.000s)
[130.257812] [024/132] (889s left) gem_ctx_bad_destroy (invalid-ctx)
[130.317105] Starting subtest: invalid-ctx
[130.319079] Subtest invalid-ctx: SUCCESS (0.000s)
[130.399736] [025/132] (889s left) gem_ctx_param (invalid-set-ringsize)
[130.449325] Starting subtest: invalid-set-ringsize
[130.451320] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[130.521461] [026/132] (889s left) syncobj_timeline (single-wait-signaled)
[130.573514] Starting subtest: single-wait-signaled
[130.575521] Subtest single-wait-signaled: SUCCESS (0.000s)
[130.661879] [027/132] (889s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[130.757957] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[130.785443] Starting dynamic subtest: pipe-A-plane-0
[131.084852] Dynamic subtest pipe-A-plane-0: SUCCESS (0.300s)
[131.092547] Starting dynamic subtest: pipe-A-plane-3
[131.318259] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[132.441919] Starting dynamic subtest: pipe-B-plane-0
[132.735415] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[132.744168] Starting dynamic subtest: pipe-B-plane-3
[132.957690] Dynamic subtest pipe-B-plane-3: SUCCESS (0.222s)
[133.268727] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SUCCESS (2.510s)
[134.107711] [028/132] (885s left) kms_chamelium_audio (hdmi-audio-edid)
[134.507802] Subtest hdmi-audio-edid: SKIP (0.000s)
[135.321243] [029/132] (884s left) gem_fence_thrash (bo-write-verify-none)
[135.356694] Starting subtest: bo-write-verify-none
[135.381378] Subtest bo-write-verify-none: SKIP (0.024s)
[135.454441] [030/132] (884s left) kms_rotation_crc (bad-tiling)
[135.544799] Starting subtest: bad-tiling
[135.549038] Subtest bad-tiling: SKIP (0.001s)
[135.615327] [031/132] (884s left) kms_lease (lessee-list)
[135.702619] Starting subtest: lessee-list
[135.704785] Starting dynamic subtest: pipe-A-eDP-1
[135.707135] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[135.716714] Starting dynamic subtest: pipe-B-eDP-1
[135.718583] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[135.726155] Starting dynamic subtest: pipe-C-eDP-1
[135.727945] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[135.735216] Starting dynamic subtest: pipe-D-eDP-1
[135.737088] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[135.738864] Subtest lessee-list: SUCCESS (0.009s)
[135.836495] [032/132] (884s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[135.950450] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[135.954561] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[136.065367] [033/132] (884s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[136.153363] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[136.157334] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[136.248387] [034/132] (884s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[136.311136] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[136.312899] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[136.417744] [035/132] (883s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[136.507574] Starting subtest: fbc-psr2-sprite-mmap-cpu
[136.509461] Starting dynamic subtest: eDP-1
[136.512889] Dynamic subtest eDP-1: SKIP (0.000s)
[136.516382] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s)
[136.575465] [036/132] (883s left) perf (stress-open-close)
[136.688087] Starting subtest: stress-open-close
[136.690059] Starting dynamic subtest: 0-rcs0
[138.855740] Dynamic subtest 0-rcs0: SUCCESS (2.168s)
[138.865456] Starting dynamic subtest: 1-vcs0
[141.027829] Dynamic subtest 1-vcs0: SUCCESS (2.171s)
[141.029870] Subtest stress-open-close: SUCCESS (4.341s)
[141.071949] [037/132] (879s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[141.171960] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[141.175322] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[141.261110] [038/132] (879s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[141.356727] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[141.358699] Starting dynamic subtest: pipe-A-eDP-1
[142.144742] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.781s)
[142.153245] Starting dynamic subtest: pipe-B-eDP-1
[143.742119] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.594s)
[143.751243] Starting dynamic subtest: pipe-C-eDP-1
[145.253026] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.509s)
[145.263276] Starting dynamic subtest: pipe-D-eDP-1
[146.788602] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.535s)
[146.790491] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.423s)
[147.981616] [039/132] (872s left) gem_cs_tlb (engines)
[148.047975] Starting subtest: engines
[148.050458] Starting dynamic subtest: rcs0
[150.264571] Dynamic subtest rcs0: SUCCESS (2.152s)
[150.274945] Starting dynamic subtest: bcs0
[152.356073] Dynamic subtest bcs0: SUCCESS (2.153s)
[152.367193] Starting dynamic subtest: vcs0
[154.563434] Dynamic subtest vcs0: SUCCESS (2.153s)
[154.574547] Starting dynamic subtest: vcs1
[156.715993] Dynamic subtest vcs1: SUCCESS (2.151s)
[156.727139] Starting dynamic subtest: vecs0
[158.868324] Dynamic subtest vecs0: SUCCESS (2.151s)
[158.879416] Starting dynamic subtest: ccs0
[160.968792] Dynamic subtest ccs0: SUCCESS (2.151s)
[160.971203] Subtest engines: SUCCESS (12.918s)
[161.084975] [040/132] (859s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-wc)
[161.203710] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-wc
[167.879296] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.677s)
[167.990166] [041/132] (852s left) kms_cursor_crc (cursor-size-change)
[168.089133] Starting subtest: cursor-size-change
[168.091296] Starting dynamic subtest: pipe-A-eDP-1
[168.883379] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.796s)
[168.892751] Starting dynamic subtest: pipe-D-eDP-1
[170.377627] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.491s)
[170.380167] Subtest cursor-size-change: SUCCESS (2.290s)
[171.169278] [042/132] (849s left) gem_mmap_gtt (medium-copy)
[171.234408] Subtest medium-copy: SKIP (0.000s)
[171.290912] [043/132] (849s left) kms_vblank (wait-idle)
[171.384119] Starting subtest: wait-idle
[171.386414] Starting dynamic subtest: pipe-A-eDP-1
[173.151416] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.765s)
[173.164754] Starting dynamic subtest: pipe-D-eDP-1
[175.617418] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.466s)
[175.619833] Subtest wait-idle: SUCCESS (4.235s)
[176.433306] [044/132] (844s left) syncobj_wait (wait-all-delayed-signal)
[176.482979] Starting subtest: wait-all-delayed-signal
[176.586816] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[176.660826] [045/132] (844s left) gem_exec_schedule (deep)
[176.843713] Starting subtest: deep
[176.846033] Starting dynamic subtest: rcs0
[182.315700] Dynamic subtest rcs0: SKIP (5.469s)
[182.327828] Starting dynamic subtest: vcs0
[192.460049] Dynamic subtest vcs0: SUCCESS (10.146s)
[192.471368] Starting dynamic subtest: vcs1
[203.959073] Dynamic subtest vcs1: SUCCESS (11.498s)
[203.972767] Starting dynamic subtest: vecs0
[214.507138] Dynamic subtest vecs0: SUCCESS (10.547s)
[214.518817] Starting dynamic subtest: ccs0
[226.310866] Dynamic subtest ccs0: SUCCESS (11.802s)
[226.313385] Subtest deep: SUCCESS (49.467s)
[227.377263] [FACT gem_exec_schedule (deep)] new: kernel.kmod_is_loaded.vgem: true
[227.381204] [046/132] (793s left) gem_spin_batch (spin-all-new)
[227.438212] Starting subtest: spin-all-new
[227.461968] Subtest spin-all-new: SUCCESS (0.024s)
[228.929655] [047/132] (791s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[229.037934] Starting subtest: blocking-absolute-wf_vblank-interruptible
[229.040163] Starting dynamic subtest: A-eDP1
[237.824236] Dynamic subtest A-eDP1: SUCCESS (8.749s)
[237.833890] Starting dynamic subtest: B-eDP1
[246.552035] Dynamic subtest B-eDP1: SUCCESS (8.724s)
[246.562790] Starting dynamic subtest: C-eDP1
[255.284689] Dynamic subtest C-eDP1: SUCCESS (8.721s)
[255.295713] Starting dynamic subtest: D-eDP1
[264.020928] Dynamic subtest D-eDP1: SUCCESS (8.730s)
[264.023527] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.937s)
[265.184783] [048/132] (755s left) sw_sync (sync_multi_consumer_producer)
[265.225301] Starting subtest: sync_multi_consumer_producer
[265.533766] Subtest sync_multi_consumer_producer: SUCCESS (0.308s)
[265.606336] [049/132] (755s left) syncobj_wait (multi-wait-all-submitted-signaled)
[265.661374] Starting subtest: multi-wait-all-submitted-signaled
[265.760565] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.101s)
[265.836884] [050/132] (755s left) kms_content_protection (dp-mst-type-0)
[265.969802] Starting subtest: dp-mst-type-0
[265.974291] Subtest dp-mst-type-0: SKIP (0.000s)
[266.088188] [051/132] (754s left) kms_cursor_crc (cursor-offscreen-256x85)
[266.354837] Starting subtest: cursor-offscreen-256x85
[266.406122] Subtest cursor-offscreen-256x85: SKIP (0.044s)
[266.572918] [052/132] (754s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[266.665541] Starting subtest: psr-2p-pri-indfb-multidraw
[266.669353] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[266.756365] [053/132] (754s left) perf_pmu (rc6-suspend)
[266.794956] Starting subtest: rc6-suspend
[289.218311] Subtest rc6-suspend: SUCCESS (8.367s)
[290.062601] [054/132] (731s left) gem_caching (read-writes)
[290.143026] Subtest read-writes: SKIP (0.000s)
[290.207786] [055/132] (730s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[290.306417] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[291.071649] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.770s)
[291.219977] [056/132] (729s left) kms_chamelium_color (degamma)
[291.317735] Subtest degamma: SKIP (0.000s)
[291.364611] [057/132] (729s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[291.475122] Starting subtest: modeset-atomic-cursor-hotspot
[291.479419] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[291.618552] [058/132] (729s left) kms_prime (basic-crc-vgem)
[291.703531] Starting subtest: basic-crc-vgem
[291.705665] Starting dynamic subtest: second-to-first
[292.770067] Dynamic subtest second-to-first: SUCCESS (1.075s)
[292.771921] Subtest basic-crc-vgem: SUCCESS (1.077s)
[292.862823] [059/132] (728s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[292.992757] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[292.996453] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[293.060965] [060/132] (728s left) sysfs_heartbeat_interval (precise)
[293.116023] Starting subtest: precise
[293.118224] Starting dynamic subtest: vecs0
[300.964487] Dynamic subtest vecs0: SUCCESS (7.865s)
[300.976180] Starting dynamic subtest: ccs0
[302.121521] Dynamic subtest ccs0: SUCCESS (1.163s)
[302.130533] Starting dynamic subtest: vcs0
[309.918129] Dynamic subtest vcs0: SUCCESS (7.804s)
[309.929940] Starting dynamic subtest: bcs0
[311.067653] Dynamic subtest bcs0: SUCCESS (1.145s)
[311.077383] Starting dynamic subtest: rcs0
[312.185896] Dynamic subtest rcs0: SUCCESS (1.118s)
[312.194815] Starting dynamic subtest: vcs1
[318.999823] Dynamic subtest vcs1: SUCCESS (6.816s)
[319.002379] Subtest precise: SUCCESS (25.922s)
[319.074130] [061/132] (702s left) kms_psr (fbc-psr-cursor-blt)
[319.174300] Starting subtest: fbc-psr-cursor-blt
[319.176280] Starting dynamic subtest: eDP-1
[319.180384] Dynamic subtest eDP-1: SKIP (0.000s)
[319.184514] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[319.247116] [062/132] (702s left) kms_atomic_transition (plane-all-transition-fencing)
[319.342030] Starting subtest: plane-all-transition-fencing
[319.344281] Starting dynamic subtest: pipe-A-eDP-1
[321.834432] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[322.144678] Starting dynamic subtest: pipe-B-eDP-1
[325.353862] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.220s)
[325.665968] Subtest plane-all-transition-fencing: SUCCESS (6.326s)
[326.462160] [063/132] (694s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[326.576253] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[327.210797] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.637s)
[327.410345] [064/132] (694s left) gem_exec_fence (invalid-timeline-fence-array)
[327.955893] Starting subtest: invalid-timeline-fence-array
[327.958771] Subtest invalid-timeline-fence-array: SUCCESS (0.006s)
[329.061399] [065/132] (692s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[329.173991] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[329.178479] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[329.259238] [066/132] (692s left) syncobj_wait (single-wait-all-for-submit-submitted)
[329.324972] Starting subtest: single-wait-all-for-submit-submitted
[329.426728] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.102s)
[329.502757] [067/132] (692s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[329.601724] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[329.603608] Starting dynamic subtest: pipe-A-eDP-1
[330.014009] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.414s)
[330.025937] Starting dynamic subtest: pipe-B-eDP-1
[330.044996] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[330.054338] Starting dynamic subtest: pipe-C-eDP-1
[330.072006] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.003s)
[330.080694] Starting dynamic subtest: pipe-D-eDP-1
[330.099072] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[330.100904] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.429s)
[332.342039] [068/132] (689s left) gem_userptr_blits (sync-overlap)
[332.425018] Starting subtest: sync-overlap
[332.427342] Subtest sync-overlap: SUCCESS (0.000s)
[332.551213] [069/132] (689s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[332.608906] Subtest writes-after-reads: SKIP (0.000s)
[332.681186] [070/132] (689s left) gem_ctx_shared (exec-shared-gtt)
[332.746560] Starting subtest: exec-shared-gtt
[332.748509] Starting dynamic subtest: rcs0
[332.752477] Dynamic subtest rcs0: SUCCESS (0.009s)
[332.763022] Starting dynamic subtest: bcs0
[332.765245] Dynamic subtest bcs0: SUCCESS (0.006s)
[332.774112] Starting dynamic subtest: vcs0
[332.776504] Dynamic subtest vcs0: SUCCESS (0.007s)
[332.785449] Starting dynamic subtest: vcs1
[332.787324] Dynamic subtest vcs1: SUCCESS (0.007s)
[332.796034] Starting dynamic subtest: vecs0
[332.798175] Dynamic subtest vecs0: SUCCESS (0.007s)
[332.806941] Starting dynamic subtest: ccs0
[332.809138] Dynamic subtest ccs0: SUCCESS (0.005s)
[332.811308] Subtest exec-shared-gtt: SUCCESS (0.046s)
[332.973966] [071/132] (688s left) gem_ctx_persistence (heartbeat-hang)
[333.038270] Starting subtest: heartbeat-hang
[333.042710] Subtest heartbeat-hang: SKIP (0.000s)
[333.138874] [072/132] (688s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip)
[333.251274] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip
[333.255540] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[333.351120] [073/132] (688s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[333.448959] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[333.450886] Starting dynamic subtest: pipe-A-eDP-1
[339.310991] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.865s)
[339.320604] Starting dynamic subtest: pipe-B-eDP-1
[345.956901] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.646s)
[345.959103] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.515s)
[346.832224] [074/132] (675s left) vgem_basic (unload)
[346.880715] Starting subtest: unload
[375.923251] Subtest unload: SUCCESS (29.059s)
[376.022197] [075/132] (645s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[376.130506] Starting subtest: fbc-tiling-linear
[379.803552] Subtest fbc-tiling-linear: SUCCESS (3.678s)
[381.013799] [076/132] (640s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[381.114060] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[381.118387] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[381.225184] [077/132] (640s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[381.320873] Starting subtest: plane-lut1d-pre-ctm3x4
[381.516142] Starting dynamic subtest: pipe-A-plane-0
[381.863878] Dynamic subtest pipe-A-plane-0: FAIL (0.168s)
[381.884750] Starting dynamic subtest: pipe-A-plane-1
[382.075889] Dynamic subtest pipe-A-plane-1: FAIL (0.160s)
[382.096009] Starting dynamic subtest: pipe-A-plane-2
[382.308659] Dynamic subtest pipe-A-plane-2: FAIL (0.155s)
[383.250829] Starting dynamic subtest: pipe-B-plane-0
[383.705293] Dynamic subtest pipe-B-plane-0: FAIL (0.186s)
[383.717098] Starting dynamic subtest: pipe-B-plane-1
[384.030663] Dynamic subtest pipe-B-plane-1: FAIL (0.155s)
[384.041621] Starting dynamic subtest: pipe-B-plane-2
[384.326745] Dynamic subtest pipe-B-plane-2: FAIL (0.613s)
[385.455550] Starting dynamic subtest: pipe-C-plane-0
[385.973391] Dynamic subtest pipe-C-plane-0: FAIL (0.162s)
[385.983146] Starting dynamic subtest: pipe-C-plane-1
[386.371449] Dynamic subtest pipe-C-plane-1: FAIL (0.606s)
[386.382639] Starting dynamic subtest: pipe-C-plane-2
[386.739980] Dynamic subtest pipe-C-plane-2: FAIL (0.157s)
[387.575985] Starting dynamic subtest: pipe-D-plane-0
[388.190789] Dynamic subtest pipe-D-plane-0: FAIL (0.162s)
[388.202311] Starting dynamic subtest: pipe-D-plane-1
[388.659855] Dynamic subtest pipe-D-plane-1: FAIL (0.633s)
[388.670837] Starting dynamic subtest: pipe-D-plane-2
[389.107489] Dynamic subtest pipe-D-plane-2: FAIL (0.564s)
[389.231905] Subtest plane-lut1d-pre-ctm3x4: FAIL (7.893s)
[390.048639] [078/132] (632s left) kms_cursor_edge_walk (256x256-right-edge)
[390.137043] Starting subtest: 256x256-right-edge
[390.139338] Starting dynamic subtest: pipe-A-eDP-1
[393.626814] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.483s)
[393.636958] Starting dynamic subtest: pipe-D-eDP-1
[396.754846] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.123s)
[396.757111] Subtest 256x256-right-edge: SUCCESS (6.608s)
[397.550354] [079/132] (624s left) syncobj_wait (wait-all-for-submit-snapshot)
[397.610225] Starting subtest: wait-all-for-submit-snapshot
[397.690582] Subtest wait-all-for-submit-snapshot: SUCCESS (0.081s)
[397.767832] [080/132] (624s left) kms_addfb_basic (unused-pitches)
[397.835383] Starting subtest: unused-pitches
[397.837689] Subtest unused-pitches: SUCCESS (0.000s)
[397.985336] [081/132] (624s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[398.088591] Starting subtest: primary-yf-tiled-reflect-x-270
[398.093072] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[398.158888] [082/132] (624s left) i915_pm_rpm (system-suspend-execbuf)
[400.197357] Starting subtest: system-suspend-execbuf
[462.938189] Subtest system-suspend-execbuf: SUCCESS (48.041s)
[463.305327] [083/132] (558s left) kms_psr (psr-sprite-mmap-cpu)
[463.404058] Starting subtest: psr-sprite-mmap-cpu
[463.406359] Starting dynamic subtest: eDP-1
[465.214118] Dynamic subtest eDP-1: SUCCESS (1.808s)
[465.216268] Subtest psr-sprite-mmap-cpu: SUCCESS (1.810s)
[466.061247] [084/132] (556s left) fbdev (eof)
[466.116483] Starting subtest: eof
[466.118885] Subtest eof: SUCCESS (0.000s)
[466.184317] [085/132] (556s left) kms_getfb (getfb-handle-closed)
[466.274972] Starting subtest: getfb-handle-closed
[466.277120] Subtest getfb-handle-closed: SUCCESS (0.000s)
[466.382604] [086/132] (556s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[466.480253] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[466.484570] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[466.568070] [087/132] (555s left) gem_partial_pwrite_pread (reads)
[466.633228] Subtest reads: SKIP (0.000s)
[466.702396] [088/132] (555s left) gem_userptr_blits (create-destroy-sync)
[466.773955] Starting subtest: create-destroy-sync
[471.839719] Subtest create-destroy-sync: SUCCESS (5.013s)
[471.909574] [089/132] (550s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[472.008643] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[472.012697] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[472.106885] [090/132] (550s left) gem_pwrite (basic-self)
[472.170479] Subtest basic-self: SKIP (0.000s)
[472.237120] [091/132] (550s left) syncobj_wait (reset-during-wait-for-submit)
[472.296241] Starting subtest: reset-during-wait-for-submit
[472.399934] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[472.480343] [092/132] (550s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[472.574869] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[472.579297] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[472.667047] [093/132] (550s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[472.771033] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[472.773334] Starting dynamic subtest: pipe-A-eDP-1
[473.602684] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.825s)
[473.613300] Starting dynamic subtest: pipe-B-eDP-1
[475.163275] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.557s)
[475.173026] Starting dynamic subtest: pipe-C-eDP-1
[476.706516] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.544s)
[476.717166] Starting dynamic subtest: pipe-D-eDP-1
[478.194704] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.486s)
[478.196587] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.417s)
[479.381940] [094/132] (543s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[479.451467] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[479.556853] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.105s)
[479.631626] [095/132] (543s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[479.734950] Starting subtest: y-tiled-8bpp-rotate-270
[479.739343] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[479.826820] [096/132] (542s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[479.920378] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[479.922683] Starting dynamic subtest: pipe-A
[480.267716] Dynamic subtest pipe-A: SUCCESS (0.349s)
[480.278181] Starting dynamic subtest: pipe-B
[481.670425] Dynamic subtest pipe-B: SUCCESS (1.402s)
[481.687474] Starting dynamic subtest: pipe-C
[483.144888] Dynamic subtest pipe-C: SUCCESS (1.473s)
[483.161676] Starting dynamic subtest: pipe-D
[484.556645] Dynamic subtest pipe-D: SUCCESS (1.410s)
[484.558801] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.640s)
[485.724470] [097/132] (537s left) i915_drm_fdinfo (virtual-idle)
[485.776500] Starting subtest: virtual-idle
[488.833981] Subtest virtual-idle: SUCCESS (3.058s)
[488.912184] [098/132] (533s left) kms_async_flips (async-flip-dpms)
[489.006746] Starting subtest: async-flip-dpms
[489.383160] Starting dynamic subtest: pipe-A-eDP-1
[493.216251] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.836s)
[493.609238] Starting dynamic subtest: pipe-B-eDP-1
[496.477695] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.876s)
[496.886643] Starting dynamic subtest: pipe-C-eDP-1
[499.648491] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.773s)
[500.030057] Starting dynamic subtest: pipe-D-eDP-1
[502.806832] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.790s)
[502.809291] Subtest async-flip-dpms: SUCCESS (13.807s)
[503.993685] [099/132] (518s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[504.106854] Starting subtest: psr-1p-pri-indfb-multidraw
[526.810009] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.704s)
[526.923153] [100/132] (496s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[527.035046] Starting subtest: 4-tiled-64bpp-rotate-180
[528.626159] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.591s)
[528.794980] [101/132] (494s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[528.909142] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[528.912757] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[529.003809] [102/132] (494s left) gem_userptr_blits (mmap-offset-banned)
[529.092007] Starting subtest: mmap-offset-banned
[529.094107] Starting dynamic subtest: wb
[529.096070] Dynamic subtest wb: SUCCESS (0.000s)
[529.106613] Starting dynamic subtest: wc
[529.108458] Dynamic subtest wc: SUCCESS (0.000s)
[529.117293] Starting dynamic subtest: uc
[529.118932] Dynamic subtest uc: SUCCESS (0.000s)
[529.120620] Subtest mmap-offset-banned: SUCCESS (0.008s)
[529.243247] [103/132] (493s left) gem_exec_reloc (basic-cpu-gtt)
[529.302937] Subtest basic-cpu-gtt: SKIP (0.000s)
[529.375929] [104/132] (493s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[529.473027] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[537.119657] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.646s)
[537.270320] [105/132] (485s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[537.365473] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[537.369529] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[537.465928] [106/132] (485s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[537.584412] Starting subtest: basic-flip-before-cursor-legacy
[538.498427] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.914s)
[539.316756] [107/132] (483s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[539.414035] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[539.416308] Starting dynamic subtest: pipe-A-default-mode
[540.577916] Dynamic subtest pipe-A-default-mode: SKIP (1.162s)
[540.582445] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.164s)
[540.737023] [108/132] (482s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[540.856907] Starting subtest: protected-encrypted-src-copy-not-readible
[540.965626] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.111s)
[541.086549] [109/132] (482s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[541.195445] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[548.481358] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.288s)
[548.618344] [110/132] (474s left) perf (invalid-oa-exponent)
[548.726135] Starting subtest: invalid-oa-exponent
[548.738308] Subtest invalid-oa-exponent: SUCCESS (0.011s)
[548.782377] [111/132] (474s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[548.842426] Starting subtest: etime-multi-wait-all-for-submit-submitted
[548.948849] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.106s)
[549.037475] [112/132] (474s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[549.142280] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[549.146943] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[550.296795] [113/132] (473s left) syncobj_timeline (invalid-wait-illegal-handle)
[550.342215] Starting subtest: invalid-wait-illegal-handle
[550.344274] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[550.433694] [114/132] (473s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[550.532135] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[550.536459] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.000s)
[550.620599] [115/132] (472s left) i915_query (query-topology-matches-eu-total)
[550.669773] Starting subtest: query-topology-matches-eu-total
[550.672128] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[550.766444] [116/132] (472s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[551.145581] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[551.999117] [117/132] (471s left) i915_drm_fdinfo (most-busy-idle-check-all)
[552.042824] Starting subtest: most-busy-idle-check-all
[552.045258] Starting dynamic subtest: rcs0
[552.050090] Dynamic subtest rcs0: SKIP (0.004s)
[552.061447] Starting dynamic subtest: bcs0
[552.066071] Dynamic subtest bcs0: SKIP (0.000s)
[552.075387] Starting dynamic subtest: vcs0
[552.079584] Dynamic subtest vcs0: SKIP (0.000s)
[552.088633] Starting dynamic subtest: vcs1
[552.092847] Dynamic subtest vcs1: SKIP (0.000s)
[552.101625] Starting dynamic subtest: vecs0
[552.105829] Dynamic subtest vecs0: SKIP (0.000s)
[552.114610] Starting dynamic subtest: ccs0
[552.118806] Dynamic subtest ccs0: SKIP (0.000s)
[552.123047] Subtest most-busy-idle-check-all: SKIP (0.009s)
[552.160810] [118/132] (471s left) gem_exec_schedule (submit-early-slice)
[552.257129] Starting subtest: submit-early-slice
[552.259363] Starting dynamic subtest: rcs0
[552.289806] Dynamic subtest rcs0: SUCCESS (0.040s)
[552.302632] Starting dynamic subtest: bcs0
[552.329520] Dynamic subtest bcs0: SUCCESS (0.039s)
[552.339049] Starting dynamic subtest: vcs0
[552.366494] Dynamic subtest vcs0: SUCCESS (0.036s)
[552.376363] Starting dynamic subtest: vcs1
[552.402065] Dynamic subtest vcs1: SUCCESS (0.035s)
[552.413082] Starting dynamic subtest: vecs0
[552.443732] Dynamic subtest vecs0: SUCCESS (0.040s)
[552.453706] Starting dynamic subtest: ccs0
[552.483445] Dynamic subtest ccs0: SUCCESS (0.038s)
[552.485740] Subtest submit-early-slice: SUCCESS (0.234s)
[553.838483] [119/132] (469s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[553.938541] Starting subtest: psr-indfb-scaledprimary
[559.684263] Subtest psr-indfb-scaledprimary: SUCCESS (5.749s)
[559.812571] [120/132] (463s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[559.929349] Starting subtest: cursorA-vs-flipA-varying-size
[571.047080] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.116s)
[571.906365] [121/132] (451s left) gem_mmap_gtt (close-race)
[571.963276] Subtest close-race: SKIP (0.000s)
[572.030457] [122/132] (451s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[572.145306] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[572.149163] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[572.245858] [123/132] (451s left) perf_pmu (busy)
[572.289898] Starting subtest: busy
[572.292199] Starting dynamic subtest: rcs0
[572.854813] Dynamic subtest rcs0: SUCCESS (0.564s)
[572.872264] Starting dynamic subtest: bcs0
[573.421311] Dynamic subtest bcs0: SUCCESS (0.563s)
[573.433274] Starting dynamic subtest: vcs0
[574.752358] Dynamic subtest vcs0: SUCCESS (1.328s)
[574.766760] Starting dynamic subtest: vcs1
[576.800679] Dynamic subtest vcs1: SUCCESS (2.044s)
[576.813348] Starting dynamic subtest: vecs0
[578.784554] Dynamic subtest vecs0: SUCCESS (1.981s)
[578.797834] Starting dynamic subtest: ccs0
[579.347257] Dynamic subtest ccs0: SUCCESS (0.561s)
[579.349751] Subtest busy: SUCCESS (7.060s)
[579.459034] [124/132] (444s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[579.515837] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[579.520153] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[579.698320] [125/132] (444s left) api_intel_bb (object-noreloc-keep-cache-simple)
[579.762434] Starting subtest: object-noreloc-keep-cache-simple
[579.768844] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.009s)
[579.862379] [126/132] (444s left) api_intel_allocator (reserve-simple)
[579.910317] Starting subtest: reserve-simple
[579.914952] Subtest reserve-simple: SUCCESS (0.006s)
[580.054286] [127/132] (444s left) gem_mmap_offset (blt-coherency)
[580.108791] Starting subtest: blt-coherency
[580.117414] Subtest blt-coherency: SUCCESS (0.011s)
[580.259190] [128/132] (443s left) kms_psr (pr-sprite-mmap-cpu)
[580.349983] Starting subtest: pr-sprite-mmap-cpu
[580.354540] Subtest pr-sprite-mmap-cpu: SKIP (0.000s)
[580.419450] [129/132] (443s left) kms_cursor_crc (cursor-random-128x42)
[580.635223] Starting subtest: cursor-random-128x42
[580.680390] Subtest cursor-random-128x42: SKIP (0.042s)
[580.883381] [130/132] (443s left) gem_exec_whisper (basic-sync)
[580.956449] Starting subtest: basic-sync
[587.822645] Subtest basic-sync: SUCCESS (6.844s)
[588.000457] [131/132] (436s left) gem_exec_balancer (full-late)
[588.062740] Starting subtest: full-late
[592.607794] Subtest full-late: SUCCESS (4.546s)
[592.962980] [132/132] (431s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[593.072985] Starting subtest: disable-crc-after-crtc
[593.075169] Starting dynamic subtest: pipe-A-eDP-1
[594.790635] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.719s)
[594.801915] Starting dynamic subtest: pipe-B-eDP-1
[597.119632] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.326s)
[597.131065] Starting dynamic subtest: pipe-C-eDP-1
[599.358265] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.239s)
[599.369196] Starting dynamic subtest: pipe-D-eDP-1
[601.615208] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.256s)
[601.617546] Subtest disable-crc-after-crtc: SUCCESS (8.547s)
[602.456846] Closing watchdogs
results: parsing output: 0/ for test: kms_plane_multiple
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: kms_setmode
results: parsing output: 3/ for test: kms_selftest
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_pm_rpm
results: parsing output: 6/ for test: gem_exec_schedule
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: gem_render_tiled_blits
results: parsing output: 9/ for test: prime_mmap
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: gem_exec_whisper
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_draw_crc
results: parsing output: 14/ for test: i915_suspend
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: kms_atomic
results: parsing output: 17/ for test: syncobj_wait
results: parsing output: 18/ for test: kms_plane_scaling
results: parsing output: 19/ for test: device_reset
results: parsing output: 20/ for test: kms_joiner
results: parsing output: 21/ for test: syncobj_wait
results: parsing output: 22/ for test: gen9_exec_parse
results: parsing output: 23/ for test: gem_ctx_bad_destroy
results: parsing output: 24/ for test: gem_ctx_param
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_plane
results: parsing output: 27/ for test: kms_chamelium_audio
results: parsing output: 28/ for test: gem_fence_thrash
results: parsing output: 29/ for test: kms_rotation_crc
results: parsing output: 30/ for test: kms_lease
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: syncobj_timeline
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: perf
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_ccs
results: parsing output: 38/ for test: gem_cs_tlb
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_cursor_crc
results: parsing output: 41/ for test: gem_mmap_gtt
results: parsing output: 42/ for test: kms_vblank
results: parsing output: 43/ for test: syncobj_wait
results: parsing output: 44/ for test: gem_exec_schedule
results: parsing output: 45/ for test: gem_spin_batch
results: parsing output: 46/ for test: kms_flip
results: parsing output: 47/ for test: sw_sync
results: parsing output: 48/ for test: syncobj_wait
results: parsing output: 49/ for test: kms_content_protection
results: parsing output: 50/ for test: kms_cursor_crc
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: perf_pmu
results: parsing output: 53/ for test: gem_caching
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: kms_chamelium_color
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: kms_prime
results: parsing output: 58/ for test: kms_psr2_sf
results: parsing output: 59/ for test: sysfs_heartbeat_interval
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: kms_atomic_transition
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: gem_exec_fence
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: syncobj_wait
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: gem_userptr_blits
results: parsing output: 68/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 69/ for test: gem_ctx_shared
results: parsing output: 70/ for test: gem_ctx_persistence
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: kms_psr2_sf
results: parsing output: 73/ for test: vgem_basic
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_color_pipeline
results: parsing output: 77/ for test: kms_cursor_edge_walk
results: parsing output: 78/ for test: syncobj_wait
results: parsing output: 79/ for test: kms_addfb_basic
results: parsing output: 80/ for test: kms_rotation_crc
results: parsing output: 81/ for test: i915_pm_rpm
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: fbdev
results: parsing output: 84/ for test: kms_getfb
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_partial_pwrite_pread
results: parsing output: 87/ for test: gem_userptr_blits
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_pwrite
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_plane_scaling
results: parsing output: 96/ for test: i915_drm_fdinfo
results: parsing output: 97/ for test: kms_async_flips
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: kms_big_fb
results: parsing output: 101/ for test: gem_userptr_blits
results: parsing output: 102/ for test: gem_exec_reloc
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_cursor_legacy
results: parsing output: 106/ for test: kms_flip_scaled_crc
results: parsing output: 107/ for test: gem_pxp
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: perf
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: kms_ccs
results: parsing output: 112/ for test: syncobj_timeline
results: parsing output: 113/ for test: kms_flip_scaled_crc
results: parsing output: 114/ for test: i915_query
results: parsing output: 115/ for test: kms_chamelium_hpd
results: parsing output: 116/ for test: i915_drm_fdinfo
results: parsing output: 117/ for test: gem_exec_schedule
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_cursor_legacy
results: parsing output: 120/ for test: gem_mmap_gtt
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: perf_pmu
results: parsing output: 123/ for test: gem_render_copy
results: parsing output: 124/ for test: api_intel_bb
results: parsing output: 125/ for test: api_intel_allocator
results: parsing output: 126/ for test: gem_mmap_offset
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: kms_cursor_crc
results: parsing output: 129/ for test: gem_exec_whisper
results: parsing output: 130/ for test: gem_exec_balancer
results: parsing output: 131/ for test: kms_pipe_crc_basic
Done.