[60.996670] Initializing watchdogs
[60.996734]   /dev/watchdog0
[61.020944] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[61.032113] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[61.036114] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.038555] [001/134] (960s left) kms_panel_fitting (atomic-fastset)
[61.232181] Starting subtest: atomic-fastset
[61.233996] Subtest atomic-fastset: SKIP (0.000s)
[61.359084] [002/134] (960s left) kms_psr (pr-cursor-mmap-gtt)
[61.547063] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[61.597204] [003/134] (960s left) kms_big_fb (linear-16bpp-rotate-270)
[61.789185] Starting subtest: linear-16bpp-rotate-270
[61.864091] Subtest linear-16bpp-rotate-270: SKIP (0.073s)
[62.090178] [004/134] (959s left) kms_rotation_crc (cursor-rotation-180)
[62.265086] Starting subtest: cursor-rotation-180
[63.074172] Subtest cursor-rotation-180: SUCCESS (0.810s)
[63.477267] [005/134] (958s left) gem_partial_pwrite_pread (write-snoop)
[63.565503] Subtest write-snoop: SKIP (0.000s)
[63.646282] [006/134] (958s left) syncobj_timeline (host-signal-ordered)
[63.716954] Starting subtest: host-signal-ordered
[63.717875] Subtest host-signal-ordered: SUCCESS (0.001s)
[63.890648] [007/134] (958s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[64.066778] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[64.067623] Starting dynamic subtest: pipe-A
[64.650589] Dynamic subtest pipe-A: SUCCESS (0.584s)
[64.668229] Starting dynamic subtest: pipe-B
[65.296817] Dynamic subtest pipe-B: SUCCESS (0.646s)
[65.306189] Starting dynamic subtest: pipe-C
[65.926954] Dynamic subtest pipe-C: SUCCESS (0.630s)
[65.938560] Starting dynamic subtest: pipe-D
[66.555216] Dynamic subtest pipe-D: SUCCESS (0.628s)
[66.556333] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (2.490s)
[66.878850] [008/134] (955s left) core_debugfs (read-all-entries)
[67.113283] Starting subtest: read-all-entries
[67.203187] Subtest read-all-entries: SUCCESS (0.092s)
[67.529609] [009/134] (954s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[67.824723] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[67.840171] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.014s)
[68.033875] [010/134] (954s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[68.466385] Starting subtest: cursor-rapid-movement-256x85
[68.470102] Starting dynamic subtest: pipe-A-DP-3
[68.947479] Dynamic subtest pipe-A-DP-3: SUCCESS (0.476s)
[68.989359] Starting dynamic subtest: pipe-D-DP-3
[69.385515] Dynamic subtest pipe-D-DP-3: SUCCESS (0.403s)
[69.386408] Subtest cursor-rapid-movement-256x85: SUCCESS (0.919s)
[69.754253] [011/134] (952s left) gem_ctx_persistence (engines-hostile-preempt)
[69.831037] Starting subtest: engines-hostile-preempt
[69.832161] Starting dynamic subtest: rcs0
[69.940395] Dynamic subtest rcs0: SUCCESS (0.110s)
[69.952032] Starting dynamic subtest: bcs0
[69.992840] Dynamic subtest bcs0: SUCCESS (0.052s)
[70.004526] Starting dynamic subtest: vcs0
[70.059628] Dynamic subtest vcs0: SUCCESS (0.066s)
[70.082223] Starting dynamic subtest: vcs1
[70.136446] Dynamic subtest vcs1: SUCCESS (0.076s)
[70.167885] Starting dynamic subtest: vecs0
[70.213463] Dynamic subtest vecs0: SUCCESS (0.077s)
[70.238470] Starting dynamic subtest: vecs1
[70.283104] Dynamic subtest vecs1: SUCCESS (0.069s)
[70.314071] Starting dynamic subtest: ccs0
[70.368255] Dynamic subtest ccs0: SUCCESS (0.084s)
[70.370222] Subtest engines-hostile-preempt: SUCCESS (0.538s)
[70.581866] [012/134] (951s left) i915_query (query-topology-known-pci-ids)
[70.655946] Starting subtest: query-topology-known-pci-ids
[70.657790] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[70.813318] [013/134] (951s left) gem_mmap_gtt (basic-read)
[70.895103] Subtest basic-read: SKIP (0.000s)
[70.973662] [014/134] (951s left) gem_pxp (reject-modify-context-protection-off-2)
[73.569316] Starting subtest: reject-modify-context-protection-off-2
[73.571538] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[73.713087] [015/134] (948s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[73.901565] Starting subtest: 4-tiled-16bpp-rotate-90
[73.955367] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.053s)
[74.179399] [016/134] (948s left) kms_properties (get_properties-sanity-non-atomic)
[74.366188] Starting subtest: get_properties-sanity-non-atomic
[74.368489] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[74.481841] [017/134] (948s left) kms_cursor_crc (cursor-offscreen-64x21)
[74.823772] Starting subtest: cursor-offscreen-64x21
[74.828260] Starting dynamic subtest: pipe-A-DP-3
[77.361152] Dynamic subtest pipe-A-DP-3: SUCCESS (2.533s)
[77.411865] Starting dynamic subtest: pipe-D-DP-3
[79.882544] Dynamic subtest pipe-D-DP-3: SUCCESS (2.478s)
[79.883784] Subtest cursor-offscreen-64x21: SUCCESS (5.059s)
[80.344804] [018/134] (942s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[80.527328] Starting subtest: fbc-rgb565-draw-mmap-gtt
[80.529052] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[80.715302] [019/134] (941s left) kms_getfb (getfb2-into-addfb2)
[80.892960] Starting subtest: getfb2-into-addfb2
[80.893909] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[81.085220] [020/134] (941s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[81.272075] Starting subtest: y-tiled-8bpp-rotate-270
[81.274014] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[81.465321] [021/134] (941s left) sysfs_defaults (readonly)
[81.540491] Starting subtest: readonly
[81.541397] Starting dynamic subtest: vecs0
[81.542270] Dynamic subtest vecs0: SUCCESS (0.000s)
[81.550541] Starting dynamic subtest: ccs0
[81.551534] Dynamic subtest ccs0: SUCCESS (0.000s)
[81.559714] Starting dynamic subtest: vcs0
[81.560848] Dynamic subtest vcs0: SUCCESS (0.000s)
[81.569500] Starting dynamic subtest: bcs0
[81.570556] Dynamic subtest bcs0: SUCCESS (0.000s)
[81.578780] Starting dynamic subtest: vecs1
[81.579904] Dynamic subtest vecs1: SUCCESS (0.000s)
[81.588140] Starting dynamic subtest: rcs0
[81.589360] Dynamic subtest rcs0: SUCCESS (0.000s)
[81.597445] Starting dynamic subtest: vcs1
[81.598616] Dynamic subtest vcs1: SUCCESS (0.000s)
[81.599601] Subtest readonly: SUCCESS (0.005s)
[81.645395] [022/134] (941s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[81.893497] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[82.024650] [023/134] (940s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[82.204717] Starting subtest: dsc-fractional-bpp-with-bpc
[82.216752] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[82.338229] [024/134] (940s left) gem_lmem_swapping (heavy-verify-multi)
[85.094008] Starting subtest: heavy-verify-multi
[85.095814] Starting dynamic subtest: lmem0
[98.080062] Dynamic subtest lmem0: SUCCESS (12.988s)
[98.081036] Subtest heavy-verify-multi: SUCCESS (12.988s)
[99.281566] [FACT gem_lmem_swapping (heavy-verify-multi)] deleted: kernel.kmod_is_loaded.i915: true
[99.283795] [025/134] (923s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[101.014037] Starting subtest: bad-pixel-format-4-tiled-mtl-mc-ccs
[101.014965] Starting dynamic subtest: pipe-A-DP-3
[101.023414] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[101.031505] Starting dynamic subtest: pipe-B-DP-3
[101.039931] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[101.048724] Starting dynamic subtest: pipe-C-DP-3
[101.056928] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[101.064918] Starting dynamic subtest: pipe-D-DP-3
[101.074436] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[101.076336] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SKIP (0.004s)
[101.549845] [FACT kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)] new: kernel.kmod_is_loaded.i915: true
[101.552142] [026/134] (921s left) kms_lease (possible-crtcs-filtering)
[101.729610] Starting subtest: possible-crtcs-filtering
[101.738196] Subtest possible-crtcs-filtering: SUCCESS (0.009s)
[101.919651] [027/134] (921s left) sw_sync (sync_random_merge)
[101.961772] Starting subtest: sync_random_merge
[102.025655] Subtest sync_random_merge: SUCCESS (0.064s)
[102.165938] [028/134] (921s left) perf_pmu (busy-accuracy-2)
[102.231961] Starting subtest: busy-accuracy-2
[102.232904] Starting dynamic subtest: rcs0
[105.306593] Dynamic subtest rcs0: SUCCESS (3.074s)
[105.316448] Starting dynamic subtest: bcs0
[108.370353] Dynamic subtest bcs0: SUCCESS (3.063s)
[108.379830] Starting dynamic subtest: vcs0
[111.438421] Dynamic subtest vcs0: SUCCESS (3.068s)
[111.448179] Starting dynamic subtest: vcs1
[114.506160] Dynamic subtest vcs1: SUCCESS (3.067s)
[114.515886] Starting dynamic subtest: vecs0
[117.573485] Dynamic subtest vecs0: SUCCESS (3.067s)
[117.583160] Starting dynamic subtest: vecs1
[120.640428] Dynamic subtest vecs1: SUCCESS (3.066s)
[120.649988] Starting dynamic subtest: ccs0
[123.705962] Dynamic subtest ccs0: SUCCESS (3.065s)
[123.706865] Subtest busy-accuracy-2: SUCCESS (21.473s)
[123.920756] [029/134] (899s left) gem_ppgtt (shrink-vs-evict-pinned)
[123.995587] Starting subtest: shrink-vs-evict-pinned
[129.945134] Subtest shrink-vs-evict-pinned: SUCCESS (5.949s)
[130.018136] [030/134] (893s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[130.198654] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[130.200282] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[130.364084] [031/134] (893s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[130.550565] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[130.552298] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[130.744100] [032/134] (892s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[130.923536] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[130.925200] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[131.122126] [033/134] (892s left) vgem_basic (sysfs)
[131.212952] Starting subtest: sysfs
[131.214098] Subtest sysfs: SUCCESS (0.000s)
[131.331341] [FACT vgem_basic (sysfs)] new: kernel.kmod_is_loaded.vgem: true
[131.333094] [034/134] (892s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[131.519155] Starting subtest: cursorB-vs-flipB-varying-size
[131.520944] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[131.645638] [035/134] (892s left) kms_ccs (crc-primary-basic-4-tiled-dg2-mc-ccs)
[131.838978] Starting subtest: crc-primary-basic-4-tiled-dg2-mc-ccs
[131.839931] Starting dynamic subtest: pipe-A-DP-3
[131.848286] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[131.857875] Starting dynamic subtest: pipe-B-DP-3
[131.866531] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[131.876477] Starting dynamic subtest: pipe-C-DP-3
[131.884793] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[131.894115] Starting dynamic subtest: pipe-D-DP-3
[131.904493] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[131.906528] Subtest crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.007s)
[132.307026] [036/134] (891s left) kms_pm_rpm (cursor)
[134.111196] Starting subtest: cursor
[134.114163] Subtest cursor: SKIP (0.000s)
[134.549942] [037/134] (889s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[134.734570] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[134.736388] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[135.061395] [038/134] (888s left) kms_flip (nonexisting-fb)
[135.247818] Starting subtest: nonexisting-fb
[135.251951] Starting dynamic subtest: A-DP3
[135.594546] Dynamic subtest A-DP3: SUCCESS (0.342s)
[135.635272] Starting dynamic subtest: B-DP3
[135.898532] Dynamic subtest B-DP3: SUCCESS (0.301s)
[135.924644] Starting dynamic subtest: C-DP3
[136.180585] Dynamic subtest C-DP3: SUCCESS (0.281s)
[136.195625] Starting dynamic subtest: D-DP3
[136.445415] Dynamic subtest D-DP3: SUCCESS (0.263s)
[136.449249] Subtest nonexisting-fb: SUCCESS (1.201s)
[136.860435] [039/134] (887s left) core_getversion (basic)
[136.924184] Starting subtest: basic
[136.926154] Subtest basic: SUCCESS (0.000s)
[137.048089] [040/134] (887s left) kms_properties (invalid-properties-atomic)
[137.234419] Starting subtest: invalid-properties-atomic
[137.699901] Subtest invalid-properties-atomic: SUCCESS (0.465s)
[137.808105] [041/134] (886s left) gem_exec_flush (basic-uc-rw-default)
[137.895257] Subtest basic-uc-rw-default: SKIP (0.000s)
[137.966743] [042/134] (886s left) i915_pm_rps (waitboost)
[138.051707] Starting subtest: waitboost
[141.653241] Subtest waitboost: SUCCESS (3.601s)
[141.841795] [043/134] (882s left) gem_exec_balancer (parallel-ordering)
[142.177764] Starting subtest: parallel-ordering
[143.808739] Subtest parallel-ordering: SUCCESS (1.631s)
[144.021624] [044/134] (880s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[144.213609] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[144.215392] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[144.375475] [045/134] (880s left) gem_tiled_swapping (non-threaded)
[144.461062] Subtest non-threaded: SKIP (0.000s)
[144.537636] [046/134] (880s left) gem_render_copy (linear)
[144.628493] Starting subtest: linear
[144.629432] Starting dynamic subtest: smem
[144.734728] Dynamic subtest smem: SUCCESS (0.106s)
[144.745008] Starting dynamic subtest: lmem0
[144.967812] Dynamic subtest lmem0: SUCCESS (0.232s)
[144.968955] Subtest linear: SUCCESS (0.339s)
[145.271849] [047/134] (879s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[145.353358] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[145.466161] [048/134] (879s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[145.658928] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[145.659848] Starting dynamic subtest: pipe-A
[147.658405] Dynamic subtest pipe-A: SUCCESS (1.999s)
[147.680514] Starting dynamic subtest: pipe-B
[149.790643] Dynamic subtest pipe-B: SUCCESS (2.131s)
[149.810029] Starting dynamic subtest: pipe-C
[150.105866] Dynamic subtest pipe-C: SUCCESS (0.314s)
[150.118449] Starting dynamic subtest: pipe-D
[150.401913] Dynamic subtest pipe-D: SUCCESS (0.295s)
[150.402974] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (4.743s)
[150.748132] [049/134] (874s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[150.939907] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[150.964234] Starting dynamic subtest: pipe-A-plane-0
[150.966143] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[150.976433] Starting dynamic subtest: pipe-A-plane-3
[150.978750] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[150.988586] Starting dynamic subtest: pipe-A-plane-5
[150.990270] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[151.195627] Starting dynamic subtest: pipe-B-plane-0
[151.197681] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[151.215199] Starting dynamic subtest: pipe-B-plane-3
[151.217204] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[151.229904] Starting dynamic subtest: pipe-B-plane-5
[151.231772] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[151.287281] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SKIP (0.345s)
[151.579317] [050/134] (873s left) perf (blocking)
[152.005505] Starting subtest: blocking
[152.006382] Starting dynamic subtest: 0-rcs0
[162.031254] Dynamic subtest 0-rcs0: SUCCESS (10.025s)
[162.032261] Subtest blocking: SUCCESS (10.026s)
[162.090011] [051/134] (862s left) gem_fence_thrash (bo-write-verify-threaded-none)
[162.136300] Starting subtest: bo-write-verify-threaded-none
[162.172164] Subtest bo-write-verify-threaded-none: SKIP (0.036s)
[162.271289] [052/134] (862s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[162.457905] Starting subtest: crc-primary-suspend-y-tiled-ccs
[162.458837] Starting dynamic subtest: pipe-A-DP-3
[162.462176] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[162.472256] Starting dynamic subtest: pipe-B-DP-3
[162.475697] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[162.485756] Starting dynamic subtest: pipe-C-DP-3
[162.489207] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[162.499234] Starting dynamic subtest: pipe-D-DP-3
[162.502678] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[162.504253] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.006s)
[162.919794] [053/134] (862s left) gem_eio (in-flight-1us)
[163.215273] Starting subtest: in-flight-1us
[168.318745] Subtest in-flight-1us: SUCCESS (5.103s)
[168.565952] [054/134] (856s left) kms_flip (flip-vs-expired-vblank)
[168.754274] Starting subtest: flip-vs-expired-vblank
[168.758598] Starting dynamic subtest: A-DP3
[176.615260] Dynamic subtest A-DP3: SUCCESS (7.856s)
[176.629352] Starting dynamic subtest: B-DP3
[184.381385] Dynamic subtest B-DP3: SUCCESS (7.765s)
[184.394557] Starting dynamic subtest: C-DP3
[192.158375] Dynamic subtest C-DP3: SUCCESS (7.776s)
[192.171258] Starting dynamic subtest: D-DP3
[199.931325] Dynamic subtest D-DP3: SUCCESS (7.772s)
[199.933481] Subtest flip-vs-expired-vblank: SUCCESS (31.179s)
[200.299916] [055/134] (824s left) gem_exec_schedule (preempt-hang)
[200.497272] Starting subtest: preempt-hang
[200.498210] Starting dynamic subtest: rcs0
[200.857999] Dynamic subtest rcs0: SUCCESS (0.360s)
[200.869556] Starting dynamic subtest: bcs0
[201.188392] Dynamic subtest bcs0: SUCCESS (0.329s)
[201.199907] Starting dynamic subtest: vcs0
[201.547365] Dynamic subtest vcs0: SUCCESS (0.359s)
[201.559218] Starting dynamic subtest: vcs1
[201.929008] Dynamic subtest vcs1: SUCCESS (0.366s)
[201.940134] Starting dynamic subtest: vecs0
[202.277230] Dynamic subtest vecs0: SUCCESS (0.345s)
[202.288681] Starting dynamic subtest: vecs1
[202.616153] Dynamic subtest vecs1: SUCCESS (0.333s)
[202.627527] Starting dynamic subtest: ccs0
[202.951572] Dynamic subtest ccs0: SUCCESS (0.358s)
[202.952436] Subtest preempt-hang: SUCCESS (2.454s)
[203.267785] [056/134] (821s left) kms_vblank (wait-forked-busy-hang)
[203.459169] Starting subtest: wait-forked-busy-hang
[203.460050] Starting dynamic subtest: pipe-A-DP-3
[210.185495] Dynamic subtest pipe-A-DP-3: SUCCESS (6.725s)
[210.199855] Starting dynamic subtest: pipe-D-DP-3
[216.194986] Dynamic subtest pipe-D-DP-3: SUCCESS (6.005s)
[216.195978] Subtest wait-forked-busy-hang: SUCCESS (12.737s)
[216.510478] [057/134] (808s left) kms_psr (psr-primary-blt)
[216.693199] Subtest psr-primary-blt: SKIP (0.000s)
[216.749447] [058/134] (808s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[216.952585] Starting subtest: x-tiled-32bpp-rotate-90
[217.027622] Subtest x-tiled-32bpp-rotate-90: SKIP (0.071s)
[217.294183] [059/134] (808s left) gem_ctx_persistence (legacy-engines-mixed)
[217.378365] Starting subtest: legacy-engines-mixed
[217.379347] Starting dynamic subtest: render
[217.487411] Dynamic subtest render: SUCCESS (0.108s)
[217.502175] Starting dynamic subtest: bsd1
[217.546291] Dynamic subtest bsd1: SUCCESS (0.057s)
[217.568471] Starting dynamic subtest: bsd2
[217.605612] Dynamic subtest bsd2: SUCCESS (0.057s)
[217.638639] Starting dynamic subtest: blt
[217.689849] Dynamic subtest blt: SUCCESS (0.081s)
[217.733433] Starting dynamic subtest: vebox
[217.780558] Dynamic subtest vebox: SUCCESS (0.087s)
[217.781817] Subtest legacy-engines-mixed: SUCCESS (0.403s)
[218.010343] [060/134] (807s left) gem_sync (basic-store-all)
[218.098984] Starting subtest: basic-store-all
[222.645668] Subtest basic-store-all: SUCCESS (4.547s)
[222.950209] [061/134] (802s left) gem_create (create-ext-placement-each)
[223.145807] Starting subtest: create-ext-placement-each
[223.146711] Subtest create-ext-placement-each: SUCCESS (0.000s)
[223.320711] [062/134] (802s left) kms_psr (fbc-pr-cursor-mmap-gtt)
[223.506280] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s)
[223.566446] [063/134] (802s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[223.755980] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[223.757775] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[223.925739] [064/134] (801s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[224.110951] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[224.113145] Starting dynamic subtest: pipe-A-DP-3
[224.188454] Dynamic subtest pipe-A-DP-3: SUCCESS (0.076s)
[224.297918] Starting dynamic subtest: pipe-B-DP-3
[224.500689] Dynamic subtest pipe-B-DP-3: SUCCESS (0.218s)
[224.559864] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (0.448s)
[224.846481] [065/134] (801s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[225.036194] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[225.266396] Starting dynamic subtest: pipe-A-DP-3-4
[227.540841] Dynamic subtest pipe-A-DP-3-4: SUCCESS (2.274s)
[227.552728] Starting dynamic subtest: pipe-A-DP-3-x
[229.722878] Dynamic subtest pipe-A-DP-3-x: SUCCESS (2.181s)
[229.945263] Starting dynamic subtest: pipe-B-DP-3-4
[232.169015] Dynamic subtest pipe-B-DP-3-4: SUCCESS (2.234s)
[232.180579] Starting dynamic subtest: pipe-B-DP-3-x
[234.334280] Dynamic subtest pipe-B-DP-3-x: SUCCESS (2.164s)
[234.527954] Starting dynamic subtest: pipe-C-DP-3-4
[236.763367] Dynamic subtest pipe-C-DP-3-4: SUCCESS (2.249s)
[236.774497] Starting dynamic subtest: pipe-C-DP-3-x
[238.924367] Dynamic subtest pipe-C-DP-3-x: SUCCESS (2.160s)
[239.107037] Starting dynamic subtest: pipe-D-DP-3-4
[241.336281] Dynamic subtest pipe-D-DP-3-4: SUCCESS (2.241s)
[241.347785] Starting dynamic subtest: pipe-D-DP-3-x
[243.501401] Dynamic subtest pipe-D-DP-3-x: SUCCESS (2.164s)
[243.502256] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (18.466s)
[243.979362] [066/134] (782s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[244.168934] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[244.170649] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[244.365082] [067/134] (781s left) kms_big_fb (yf-tiled-addfb)
[244.561182] Starting subtest: yf-tiled-addfb
[244.562999] Subtest yf-tiled-addfb: SKIP (0.000s)
[244.762254] [068/134] (781s left) gem_vm_create (invalid-create)
[244.851499] Starting subtest: invalid-create
[244.856472] Subtest invalid-create: SUCCESS (0.005s)
[245.000752] [069/134] (781s left) gem_exec_balancer (full-late)
[245.089362] Starting subtest: full-late
[247.051255] Subtest full-late: SUCCESS (1.963s)
[247.480779] [070/134] (778s left) syncobj_timeline (signal-point-0)
[247.561368] Starting subtest: signal-point-0
[247.562293] Subtest signal-point-0: SUCCESS (0.000s)
[247.746252] [071/134] (778s left) kms_addfb_basic (too-high)
[247.936547] Starting subtest: too-high
[247.937467] Subtest too-high: SUCCESS (0.001s)
[248.090879] [072/134] (778s left) gem_ctx_shared (exec-single-timeline)
[248.179527] Starting subtest: exec-single-timeline
[248.180425] Starting dynamic subtest: rcs0
[249.239055] Dynamic subtest rcs0: SUCCESS (1.058s)
[249.254164] Starting dynamic subtest: bcs0
[250.263134] Dynamic subtest bcs0: SUCCESS (1.019s)
[250.278534] Starting dynamic subtest: vcs0
[251.287351] Dynamic subtest vcs0: SUCCESS (1.019s)
[251.302787] Starting dynamic subtest: vcs1
[252.311177] Dynamic subtest vcs1: SUCCESS (1.019s)
[252.326131] Starting dynamic subtest: vecs0
[253.335226] Dynamic subtest vecs0: SUCCESS (1.019s)
[253.350811] Starting dynamic subtest: vecs1
[254.359049] Dynamic subtest vecs1: SUCCESS (1.019s)
[254.374169] Starting dynamic subtest: ccs0
[255.383214] Dynamic subtest ccs0: SUCCESS (1.019s)
[255.387559] Subtest exec-single-timeline: SUCCESS (7.205s)
[255.700865] [073/134] (770s left) prime_self_import (basic-with_fd_dup)
[255.748056] Starting subtest: basic-with_fd_dup
[255.795337] Subtest basic-with_fd_dup: SUCCESS (0.048s)
[255.947680] [074/134] (770s left) gem_exec_params (sol-reset-not-gen7)
[256.031801] Starting subtest: sol-reset-not-gen7
[256.034153] Subtest sol-reset-not-gen7: SUCCESS (0.002s)
[256.263627] [075/134] (770s left) kms_color (ctm-max)
[256.449408] Starting subtest: ctm-max
[256.450296] Starting dynamic subtest: pipe-A-DP-3
[257.149482] Dynamic subtest pipe-A-DP-3: SUCCESS (0.699s)
[257.174207] Starting dynamic subtest: pipe-B-DP-3
[257.751446] Dynamic subtest pipe-B-DP-3: SUCCESS (0.598s)
[257.772055] Starting dynamic subtest: pipe-C-DP-3
[258.350287] Dynamic subtest pipe-C-DP-3: SUCCESS (0.598s)
[258.368592] Starting dynamic subtest: pipe-D-DP-3
[258.970223] Dynamic subtest pipe-D-DP-3: SUCCESS (0.619s)
[258.971291] Subtest ctm-max: SUCCESS (2.523s)
[259.305213] [076/134] (767s left) kms_flip (blocking-absolute-wf_vblank)
[259.491065] Starting subtest: blocking-absolute-wf_vblank
[259.495759] Starting dynamic subtest: A-DP3
[267.348502] Dynamic subtest A-DP3: SUCCESS (7.852s)
[267.362583] Starting dynamic subtest: B-DP3
[275.121510] Dynamic subtest B-DP3: SUCCESS (7.771s)
[275.135211] Starting dynamic subtest: C-DP3
[282.894543] Dynamic subtest C-DP3: SUCCESS (7.771s)
[282.908373] Starting dynamic subtest: D-DP3
[290.665604] Dynamic subtest D-DP3: SUCCESS (7.770s)
[290.667961] Subtest blocking-absolute-wf_vblank: SUCCESS (31.176s)
[291.014942] [077/134] (735s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[291.215319] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[291.216224] Starting dynamic subtest: pipe-A-valid-mode
[293.098687] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.879s)
[293.100090] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (1.883s)
[293.401761] [078/134] (733s left) syncobj_timeline (single-wait-available-signaled)
[293.479370] Starting subtest: single-wait-available-signaled
[293.480278] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[293.603919] [079/134] (733s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[293.787421] Starting subtest: flip-vs-cursor-busy-crc-atomic
[294.550886] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.761s)
[294.864609] [080/134] (732s left) kms_vblank (invalid)
[295.050758] Starting subtest: invalid
[295.053018] Starting dynamic subtest: pipe-A-DP-3
[295.371044] Dynamic subtest pipe-A-DP-3: SUCCESS (0.317s)
[295.372111] Subtest invalid: SUCCESS (0.320s)
[295.678367] [081/134] (731s left) gem_spin_batch (resubmit-all)
[295.769403] Starting subtest: resubmit-all
[295.770288] Starting dynamic subtest: rcs0
[295.806989] Dynamic subtest rcs0: SUCCESS (0.038s)
[295.820271] Starting dynamic subtest: bcs0
[295.821207] Dynamic subtest bcs0: SUCCESS (0.003s)
[295.832826] Starting dynamic subtest: vcs0
[295.833713] Dynamic subtest vcs0: SUCCESS (0.003s)
[295.845160] Starting dynamic subtest: vcs1
[295.846240] Dynamic subtest vcs1: SUCCESS (0.003s)
[295.857611] Starting dynamic subtest: vecs0
[295.858653] Dynamic subtest vecs0: SUCCESS (0.002s)
[295.869648] Starting dynamic subtest: vecs1
[295.870829] Dynamic subtest vecs1: SUCCESS (0.003s)
[295.881766] Starting dynamic subtest: ccs0
[295.882895] Dynamic subtest ccs0: SUCCESS (0.003s)
[295.884084] Subtest resubmit-all: SUCCESS (0.063s)
[296.179960] [082/134] (731s left) kms_content_protection (srm)
[296.431381] Starting subtest: srm
[296.458438] Starting dynamic subtest: pipe-A-DP-3
[417.727379] Dynamic subtest pipe-A-DP-3: FAIL (121.107s)
[417.728480] Subtest srm: FAIL (121.238s)
[417.984537] [083/134] (609s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[418.174136] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[418.175918] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[418.328955] [084/134] (609s left) gem_set_tiling_vs_gtt
[418.475028] [085/134] (609s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[418.658962] Starting subtest: random-ccs-data-y-tiled-ccs
[418.661266] Starting dynamic subtest: pipe-A-DP-3
[418.670675] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[418.682287] Starting dynamic subtest: pipe-B-DP-3
[418.691780] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[418.703130] Starting dynamic subtest: pipe-C-DP-3
[418.712168] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[418.723608] Starting dynamic subtest: pipe-D-DP-3
[418.735640] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[418.737761] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.007s)
[419.160621] [086/134] (608s left) api_intel_allocator (fork-simple-stress-signal)
[419.236569] Starting subtest: fork-simple-stress-signal
[424.655985] Subtest fork-simple-stress-signal: SUCCESS (5.419s)
[424.886689] [087/134] (602s left) gem_exec_params (invalid-ring)
[424.975583] Starting subtest: invalid-ring
[424.976509] Subtest invalid-ring: SUCCESS (0.000s)
[425.150086] [088/134] (602s left) gen9_exec_parse (bb-oversize)
[425.232193] Subtest bb-oversize: SKIP (0.000s)
[425.375796] [089/134] (602s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[425.577217] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[425.579035] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[425.773172] [090/134] (602s left) kms_prime (basic-crc-hybrid)
[425.962477] Subtest basic-crc-hybrid: SKIP (0.000s)
[426.157451] [091/134] (601s left) kms_flip_tiling (flip-change-tiling)
[426.344642] Starting subtest: flip-change-tiling
[426.347915] Starting dynamic subtest: pipe-A-DP-3-4-to-4
[426.607925] Dynamic subtest pipe-A-DP-3-4-to-4: SUCCESS (0.261s)
[426.651861] Starting dynamic subtest: pipe-A-DP-3-4-to-x
[426.824477] Dynamic subtest pipe-A-DP-3-4-to-x: SUCCESS (0.212s)
[426.869882] Starting dynamic subtest: pipe-A-DP-3-4-to-linear
[427.040125] Dynamic subtest pipe-A-DP-3-4-to-linear: SUCCESS (0.212s)
[427.053446] Starting dynamic subtest: pipe-A-DP-3-x-to-4
[427.224490] Dynamic subtest pipe-A-DP-3-x-to-4: SUCCESS (0.182s)
[427.261152] Starting dynamic subtest: pipe-A-DP-3-x-to-x
[427.407869] Dynamic subtest pipe-A-DP-3-x-to-x: SUCCESS (0.178s)
[427.452209] Starting dynamic subtest: pipe-A-DP-3-x-to-linear
[427.624680] Dynamic subtest pipe-A-DP-3-x-to-linear: SUCCESS (0.212s)
[427.669572] Starting dynamic subtest: pipe-A-DP-3-linear-to-4
[427.841198] Dynamic subtest pipe-A-DP-3-linear-to-4: SUCCESS (0.212s)
[427.875094] Starting dynamic subtest: pipe-A-DP-3-linear-to-x
[428.041553] Dynamic subtest pipe-A-DP-3-linear-to-x: SUCCESS (0.195s)
[428.086509] Starting dynamic subtest: pipe-A-DP-3-linear-to-linear
[428.224312] Dynamic subtest pipe-A-DP-3-linear-to-linear: SUCCESS (0.178s)
[428.292776] Starting dynamic subtest: pipe-B-DP-3-4-to-4
[428.606248] Dynamic subtest pipe-B-DP-3-4-to-4: SUCCESS (0.330s)
[428.649201] Starting dynamic subtest: pipe-B-DP-3-4-to-x
[428.772017] Dynamic subtest pipe-B-DP-3-4-to-x: SUCCESS (0.162s)
[428.794714] Starting dynamic subtest: pipe-B-DP-3-4-to-linear
[428.921991] Dynamic subtest pipe-B-DP-3-4-to-linear: SUCCESS (0.148s)
[428.952180] Starting dynamic subtest: pipe-B-DP-3-x-to-4
[429.071850] Dynamic subtest pipe-B-DP-3-x-to-4: SUCCESS (0.148s)
[429.094297] Starting dynamic subtest: pipe-B-DP-3-x-to-x
[429.222074] Dynamic subtest pipe-B-DP-3-x-to-x: SUCCESS (0.148s)
[429.252470] Starting dynamic subtest: pipe-B-DP-3-x-to-linear
[429.388813] Dynamic subtest pipe-B-DP-3-x-to-linear: SUCCESS (0.164s)
[429.430019] Starting dynamic subtest: pipe-B-DP-3-linear-to-4
[429.555363] Dynamic subtest pipe-B-DP-3-linear-to-4: SUCCESS (0.164s)
[429.585146] Starting dynamic subtest: pipe-B-DP-3-linear-to-x
[429.705290] Dynamic subtest pipe-B-DP-3-linear-to-x: SUCCESS (0.148s)
[429.731324] Starting dynamic subtest: pipe-B-DP-3-linear-to-linear
[429.855434] Dynamic subtest pipe-B-DP-3-linear-to-linear: SUCCESS (0.148s)
[429.901541] Starting dynamic subtest: pipe-C-DP-3-4-to-4
[430.221258] Dynamic subtest pipe-C-DP-3-4-to-4: SUCCESS (0.330s)
[430.266321] Starting dynamic subtest: pipe-C-DP-3-4-to-x
[430.386774] Dynamic subtest pipe-C-DP-3-4-to-x: SUCCESS (0.161s)
[430.409322] Starting dynamic subtest: pipe-C-DP-3-4-to-linear
[430.536881] Dynamic subtest pipe-C-DP-3-4-to-linear: SUCCESS (0.148s)
[430.567134] Starting dynamic subtest: pipe-C-DP-3-x-to-4
[430.703773] Dynamic subtest pipe-C-DP-3-x-to-4: SUCCESS (0.164s)
[430.721393] Starting dynamic subtest: pipe-C-DP-3-x-to-x
[430.853837] Dynamic subtest pipe-C-DP-3-x-to-x: SUCCESS (0.147s)
[430.894755] Starting dynamic subtest: pipe-C-DP-3-x-to-linear
[431.020415] Dynamic subtest pipe-C-DP-3-x-to-linear: SUCCESS (0.164s)
[431.050563] Starting dynamic subtest: pipe-C-DP-3-linear-to-4
[431.187053] Dynamic subtest pipe-C-DP-3-linear-to-4: SUCCESS (0.164s)
[431.222391] Starting dynamic subtest: pipe-C-DP-3-linear-to-x
[431.353734] Dynamic subtest pipe-C-DP-3-linear-to-x: SUCCESS (0.164s)
[431.368597] Starting dynamic subtest: pipe-C-DP-3-linear-to-linear
[431.487111] Dynamic subtest pipe-C-DP-3-linear-to-linear: SUCCESS (0.131s)
[431.559369] Starting dynamic subtest: pipe-D-DP-3-4-to-4
[431.885102] Dynamic subtest pipe-D-DP-3-4-to-4: SUCCESS (0.358s)
[431.928688] Starting dynamic subtest: pipe-D-DP-3-4-to-x
[432.050830] Dynamic subtest pipe-D-DP-3-4-to-x: SUCCESS (0.162s)
[432.066883] Starting dynamic subtest: pipe-D-DP-3-4-to-linear
[432.184346] Dynamic subtest pipe-D-DP-3-4-to-linear: SUCCESS (0.132s)
[432.214959] Starting dynamic subtest: pipe-D-DP-3-x-to-4
[432.334258] Dynamic subtest pipe-D-DP-3-x-to-4: SUCCESS (0.148s)
[432.357408] Starting dynamic subtest: pipe-D-DP-3-x-to-x
[432.484326] Dynamic subtest pipe-D-DP-3-x-to-x: SUCCESS (0.148s)
[432.520100] Starting dynamic subtest: pipe-D-DP-3-x-to-linear
[432.651126] Dynamic subtest pipe-D-DP-3-x-to-linear: SUCCESS (0.164s)
[432.692684] Starting dynamic subtest: pipe-D-DP-3-linear-to-4
[432.817616] Dynamic subtest pipe-D-DP-3-linear-to-4: SUCCESS (0.164s)
[432.848260] Starting dynamic subtest: pipe-D-DP-3-linear-to-x
[432.967441] Dynamic subtest pipe-D-DP-3-linear-to-x: SUCCESS (0.148s)
[432.993541] Starting dynamic subtest: pipe-D-DP-3-linear-to-linear
[433.117553] Dynamic subtest pipe-D-DP-3-linear-to-linear: SUCCESS (0.148s)
[433.157711] Subtest flip-change-tiling: SUCCESS (6.806s)
[433.502045] [092/134] (594s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[433.693978] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[433.695637] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[433.883932] [093/134] (594s left) gem_pipe_control_store_loop (reused-buffer)
[433.969276] Starting subtest: reused-buffer
[436.146542] Subtest reused-buffer: SUCCESS (2.177s)
[436.349994] [094/134] (592s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[436.430972] Starting subtest: yf-tiled-ccs-to-y-tiled
[436.433371] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[436.728083] [095/134] (591s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[436.918018] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[436.919745] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[437.116505] [096/134] (591s left) syncobj_timeline (invalid-transfer-bad-pad)
[437.195455] Starting subtest: invalid-transfer-bad-pad
[437.196429] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[437.347475] [097/134] (591s left) kms_psr (fbc-psr-cursor-plane-move)
[437.536551] Subtest fbc-psr-cursor-plane-move: SKIP (0.000s)
[437.592005] [098/134] (591s left) kms_plane_multiple (2x-tiling-yf)
[437.780448] Starting subtest: 2x-tiling-yf
[437.782245] Subtest 2x-tiling-yf: SKIP (0.000s)
[437.952634] [099/134] (590s left) kms_flip (flip-vs-rmfb)
[438.139131] Starting subtest: flip-vs-rmfb
[438.143733] Starting dynamic subtest: A-DP3
[445.999630] Dynamic subtest A-DP3: SUCCESS (7.855s)
[446.041157] Starting dynamic subtest: B-DP3
[453.761857] Dynamic subtest B-DP3: SUCCESS (7.756s)
[453.798308] Starting dynamic subtest: C-DP3
[461.526838] Dynamic subtest C-DP3: SUCCESS (7.761s)
[461.566273] Starting dynamic subtest: D-DP3
[469.289977] Dynamic subtest D-DP3: SUCCESS (7.758s)
[469.296987] Subtest flip-vs-rmfb: SUCCESS (31.157s)
[469.655832] [100/134] (559s left) fbdev (write)
[469.718953] Starting subtest: write
[471.271090] Subtest write: SUCCESS (1.552s)
[471.368808] [101/134] (557s left) prime_vgem (basic-gtt)
[471.455989] Starting subtest: basic-gtt
[471.458024] Subtest basic-gtt: SKIP (0.000s)
[471.589668] [102/134] (557s left) i915_hangman (gt-engine-error)
[471.683321] Starting subtest: gt-engine-error
[471.684219] Starting dynamic subtest: rcs0
[472.531700] Dynamic subtest rcs0: SUCCESS (0.848s)
[472.547058] Starting dynamic subtest: bcs0
[473.374569] Dynamic subtest bcs0: SUCCESS (0.841s)
[473.401853] Starting dynamic subtest: vcs0
[474.204352] Dynamic subtest vcs0: SUCCESS (0.828s)
[474.218909] Starting dynamic subtest: vcs1
[474.959360] Dynamic subtest vcs1: SUCCESS (0.754s)
[474.973477] Starting dynamic subtest: vecs0
[475.765371] Dynamic subtest vecs0: SUCCESS (0.805s)
[475.779169] Starting dynamic subtest: vecs1
[476.510351] Dynamic subtest vecs1: SUCCESS (0.744s)
[476.524199] Starting dynamic subtest: ccs0
[477.339344] Dynamic subtest ccs0: SUCCESS (0.828s)
[477.340268] Subtest gt-engine-error: SUCCESS (5.657s)
[477.505433] [103/134] (551s left) sw_sync (sync_merge)
[477.553441] Starting subtest: sync_merge
[477.554333] Subtest sync_merge: SUCCESS (0.000s)
[477.681358] [104/134] (551s left) gem_exec_fence (submit)
[477.917781] Starting subtest: submit
[477.920301] Subtest submit: SKIP (0.000s)
[478.721764] [105/134] (550s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[478.915580] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[479.006955] [106/134] (550s left) gem_exec_params (rel-constants-invalid)
[479.090379] Starting subtest: rel-constants-invalid
[479.091305] Subtest rel-constants-invalid: SUCCESS (0.000s)
[479.287460] [107/134] (550s left) perf_pmu (most-busy-check-all)
[479.357798] Starting subtest: most-busy-check-all
[479.358750] Starting dynamic subtest: rcs0
[479.961924] Dynamic subtest rcs0: SUCCESS (0.603s)
[479.979395] Starting dynamic subtest: bcs0
[480.516832] Dynamic subtest bcs0: SUCCESS (0.552s)
[480.532527] Starting dynamic subtest: vcs0
[481.071814] Dynamic subtest vcs0: SUCCESS (0.554s)
[481.085579] Starting dynamic subtest: vcs1
[481.624807] Dynamic subtest vcs1: SUCCESS (0.552s)
[481.639048] Starting dynamic subtest: vecs0
[482.177515] Dynamic subtest vecs0: SUCCESS (0.551s)
[482.194495] Starting dynamic subtest: vecs1
[482.734772] Dynamic subtest vecs1: SUCCESS (0.556s)
[482.748512] Starting dynamic subtest: ccs0
[483.285849] Dynamic subtest ccs0: SUCCESS (0.550s)
[483.286730] Subtest most-busy-check-all: SUCCESS (3.929s)
[483.455733] [108/134] (545s left) kms_psr (psr-primary-mmap-cpu)
[483.641613] Subtest psr-primary-mmap-cpu: SKIP (0.000s)
[483.715522] [109/134] (545s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[483.917289] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[484.005178] [110/134] (545s left) gem_mmap_offset (clear-via-pagefault)
[484.080463] Starting subtest: clear-via-pagefault
[484.081443] Starting dynamic subtest: lmem0
[506.312296] Dynamic subtest lmem0: SUCCESS (22.229s)
[506.327354] Starting dynamic subtest: smem0
[532.452475] Dynamic subtest smem0: SUCCESS (26.138s)
[532.453418] Subtest clear-via-pagefault: SUCCESS (48.369s)
[532.703108] [111/134] (496s left) kms_big_fb (x-tiled-addfb-size-offset-overflow)
[532.897202] Starting subtest: x-tiled-addfb-size-offset-overflow
[532.898091] Subtest x-tiled-addfb-size-offset-overflow: SUCCESS (0.001s)
[533.053478] [112/134] (496s left) gem_lmem_swapping (random-engines)
[535.757418] Starting subtest: random-engines
[535.758444] Starting dynamic subtest: lmem0
[536.759997] Dynamic subtest lmem0: SUCCESS (1.004s)
[536.761008] Subtest random-engines: SUCCESS (1.005s)
[539.716582] [FACT gem_lmem_swapping (random-engines)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[539.717521] [FACT gem_lmem_swapping (random-engines)] deleted: kernel.kmod_is_loaded.i915: true
[539.719447] [113/134] (489s left) kms_pipe_crc_basic (suspend-read-crc)
[541.481330] Starting subtest: suspend-read-crc
[541.482225] Starting dynamic subtest: pipe-A-DP-3
[562.223244] Dynamic subtest pipe-A-DP-3: SUCCESS (5.128s)
[562.266501] Starting dynamic subtest: pipe-B-DP-3
[582.325755] Dynamic subtest pipe-B-DP-3: SUCCESS (4.938s)
[582.342238] Starting dynamic subtest: pipe-C-DP-3
[603.333342] Dynamic subtest pipe-C-DP-3: SUCCESS (4.617s)
[603.358819] Starting dynamic subtest: pipe-D-DP-3
[623.583070] Dynamic subtest pipe-D-DP-3: SUCCESS (4.608s)
[623.584108] Subtest suspend-read-crc: SUCCESS (19.311s)
[623.884479] [FACT kms_pipe_crc_basic (suspend-read-crc)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[623.886783] [FACT kms_pipe_crc_basic (suspend-read-crc)] new: kernel.kmod_is_loaded.i915: true
[623.889922] [114/134] (405s left) kms_cursor_edge_walk (128x128-top-edge)
[624.076601] Starting subtest: 128x128-top-edge
[624.079038] Starting dynamic subtest: pipe-A-DP-3
[627.553863] Dynamic subtest pipe-A-DP-3: SUCCESS (3.475s)
[627.572485] Starting dynamic subtest: pipe-D-DP-3
[630.881151] Dynamic subtest pipe-D-DP-3: SUCCESS (3.324s)
[630.882136] Subtest 128x128-top-edge: SUCCESS (6.804s)
[631.205138] [115/134] (398s left) gem_exec_schedule (timeslicing)
[631.301272] Starting subtest: timeslicing
[631.302264] Starting dynamic subtest: rcs0
[631.321464] Dynamic subtest rcs0: SUCCESS (0.021s)
[631.335012] Starting dynamic subtest: bcs0
[631.343311] Dynamic subtest bcs0: SUCCESS (0.021s)
[631.358573] Starting dynamic subtest: vcs0
[631.366481] Dynamic subtest vcs0: SUCCESS (0.022s)
[631.381357] Starting dynamic subtest: vcs1
[631.391178] Dynamic subtest vcs1: SUCCESS (0.023s)
[631.405964] Starting dynamic subtest: vecs0
[631.413989] Dynamic subtest vecs0: SUCCESS (0.022s)
[631.428755] Starting dynamic subtest: vecs1
[631.436861] Dynamic subtest vecs1: SUCCESS (0.022s)
[631.451574] Starting dynamic subtest: ccs0
[631.459424] Dynamic subtest ccs0: SUCCESS (0.021s)
[631.460338] Subtest timeslicing: SUCCESS (0.160s)
[631.950378] [116/134] (397s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[632.147268] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[632.149071] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[632.350895] [117/134] (397s left) gem_close (basic)
[632.434754] Starting subtest: basic
[632.435710] Subtest basic: SUCCESS (0.000s)
[632.619978] [118/134] (397s left) prime_busy (before-wait)
[632.704957] Starting subtest: before-wait
[632.710182] Starting dynamic subtest: rcs0
[632.757959] Dynamic subtest rcs0: SUCCESS (0.048s)
[632.772097] Starting dynamic subtest: bcs0
[632.812711] Dynamic subtest bcs0: SUCCESS (0.054s)
[632.833479] Starting dynamic subtest: vcs0
[632.853927] Dynamic subtest vcs0: SUCCESS (0.040s)
[632.875550] Starting dynamic subtest: vcs1
[632.893665] Dynamic subtest vcs1: SUCCESS (0.038s)
[632.915107] Starting dynamic subtest: vecs0
[632.934327] Dynamic subtest vecs0: SUCCESS (0.039s)
[632.952732] Starting dynamic subtest: vecs1
[632.974580] Dynamic subtest vecs1: SUCCESS (0.039s)
[632.990001] Starting dynamic subtest: ccs0
[633.013885] Dynamic subtest ccs0: SUCCESS (0.038s)
[633.129748] Subtest before-wait: SUCCESS (0.423s)
[633.288194] [119/134] (396s left) i915_pm_rps (fence-order)
[633.376285] Starting subtest: fence-order
[637.075223] Subtest fence-order: SUCCESS (3.699s)
[637.251017] [120/134] (392s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[637.492784] Starting subtest: fbcpsr-rgb101010-draw-blt
[637.494508] Subtest fbcpsr-rgb101010-draw-blt: SKIP (0.000s)
[637.617051] [121/134] (392s left) syncobj_timeline (32bits-limit)
[637.709244] Starting subtest: 32bits-limit
[637.710116] Subtest 32bits-limit: SUCCESS (0.001s)
[637.848810] [122/134] (392s left) kms_atomic (atomic-invalid-params)
[638.040201] Starting subtest: atomic-invalid-params
[638.041095] Starting dynamic subtest: pipe-A-DP-3
[638.538190] Dynamic subtest pipe-A-DP-3: SUCCESS (0.497s)
[638.539081] Subtest atomic-invalid-params: SUCCESS (0.499s)
[638.783707] [123/134] (391s left) kms_joiner (invalid-modeset-force-big-joiner)
[638.982525] Starting subtest: invalid-modeset-force-big-joiner
[638.983271] Starting dynamic subtest: big_joiner_on_last_pipe
[639.181316] Dynamic subtest big_joiner_on_last_pipe: SUCCESS (0.200s)
[639.182171] Subtest invalid-modeset-force-big-joiner: SUCCESS (0.201s)
[639.406686] [124/134] (390s left) gem_exec_whisper (basic-fds-priority)
[639.499038] Starting subtest: basic-fds-priority
[661.969993] Subtest basic-fds-priority: SUCCESS (22.469s)
[662.268151] [125/134] (368s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[662.528994] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[662.530750] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[662.722216] [126/134] (367s left) gen3_render_tiledy_blits
[662.869761] [127/134] (367s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[663.054466] Starting subtest: fb-8bpc-vs-panel-8bpc
[663.056772] Starting dynamic subtest: pipe-A-DP-3
[663.466501] Dynamic subtest pipe-A-DP-3: SUCCESS (0.407s)
[663.467913] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (0.411s)
[663.763907] [128/134] (366s left) kms_frontbuffer_tracking (psr-rgb101010-draw-render)
[663.955023] Starting subtest: psr-rgb101010-draw-render
[663.956799] Subtest psr-rgb101010-draw-render: SKIP (0.000s)
[664.173421] [129/134] (366s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[664.369945] Starting subtest: pipe-fbc-rte
[664.374787] Subtest pipe-fbc-rte: SKIP (0.000s)
[664.559578] [130/134] (366s left) kms_psr (fbc-psr2-primary-blt)
[664.747519] Subtest fbc-psr2-primary-blt: SKIP (0.000s)
[664.807643] [131/134] (366s left) i915_drm_fdinfo (most-busy-check-all)
[664.878688] Starting subtest: most-busy-check-all
[664.879656] Starting dynamic subtest: rcs0
[664.909715] Dynamic subtest rcs0: SKIP (0.030s)
[664.923414] Starting dynamic subtest: bcs0
[664.925727] Dynamic subtest bcs0: SKIP (0.000s)
[664.938539] Starting dynamic subtest: vcs0
[664.940829] Dynamic subtest vcs0: SKIP (0.000s)
[664.953473] Starting dynamic subtest: vcs1
[664.955729] Dynamic subtest vcs1: SKIP (0.000s)
[664.968161] Starting dynamic subtest: vecs0
[664.970576] Dynamic subtest vecs0: SKIP (0.000s)
[664.985666] Starting dynamic subtest: vecs1
[664.987947] Dynamic subtest vecs1: SKIP (0.000s)
[665.000354] Starting dynamic subtest: ccs0
[665.002559] Dynamic subtest ccs0: SKIP (0.000s)
[665.004808] Subtest most-busy-check-all: SKIP (0.039s)
[665.051986] [132/134] (365s left) gem_exec_params (invalid-fence-in-submit)
[665.177176] Starting subtest: invalid-fence-in-submit
[665.181618] Subtest invalid-fence-in-submit: SUCCESS (0.003s)
[665.377863] [133/134] (365s left) kms_chamelium_edid (hdmi-edid-stress-resolution-non-4k)
[665.640467] Subtest hdmi-edid-stress-resolution-non-4k: SKIP (0.000s)
[665.788053] [134/134] (365s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[665.978973] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[668.815500] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (2.837s)
[669.000186] Closing watchdogs
results: parsing output: 0/ for test: kms_panel_fitting
results: parsing output: 1/ for test: kms_psr
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: kms_rotation_crc
results: parsing output: 4/ for test: gem_partial_pwrite_pread
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: kms_plane_scaling
results: parsing output: 7/ for test: core_debugfs
results: parsing output: 8/ for test: kms_flip
results: parsing output: 9/ for test: kms_cursor_crc
results: parsing output: 10/ for test: gem_ctx_persistence
results: parsing output: 11/ for test: i915_query
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: gem_pxp
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: kms_properties
results: parsing output: 16/ for test: kms_cursor_crc
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_getfb
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: sysfs_defaults
results: parsing output: 21/ for test: kms_chamelium_hpd
results: parsing output: 22/ for test: kms_dsc
results: parsing output: 23/ for test: gem_lmem_swapping
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: kms_lease
results: parsing output: 26/ for test: sw_sync
results: parsing output: 27/ for test: perf_pmu
results: parsing output: 28/ for test: gem_ppgtt
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: vgem_basic
results: parsing output: 33/ for test: kms_cursor_legacy
results: parsing output: 34/ for test: kms_ccs
results: parsing output: 35/ for test: kms_pm_rpm
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: kms_flip
results: parsing output: 38/ for test: core_getversion
results: parsing output: 39/ for test: kms_properties
results: parsing output: 40/ for test: gem_exec_flush
results: parsing output: 41/ for test: i915_pm_rps
results: parsing output: 42/ for test: gem_exec_balancer
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_tiled_swapping
results: parsing output: 45/ for test: gem_render_copy
results: parsing output: 46/ for test: gem_exec_reloc
results: parsing output: 47/ for test: kms_plane_scaling
results: parsing output: 48/ for test: kms_plane
results: parsing output: 49/ for test: perf
results: parsing output: 50/ for test: gem_fence_thrash
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: gem_eio
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: gem_exec_schedule
results: parsing output: 55/ for test: kms_vblank
results: parsing output: 56/ for test: kms_psr
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: gem_ctx_persistence
results: parsing output: 59/ for test: gem_sync
results: parsing output: 60/ for test: gem_create
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_atomic_transition
results: parsing output: 64/ for test: kms_async_flips
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_big_fb
results: parsing output: 67/ for test: gem_vm_create
results: parsing output: 68/ for test: gem_exec_balancer
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: kms_addfb_basic
results: parsing output: 71/ for test: gem_ctx_shared
results: parsing output: 72/ for test: prime_self_import
results: parsing output: 73/ for test: gem_exec_params
results: parsing output: 74/ for test: kms_color
results: parsing output: 75/ for test: kms_flip
results: parsing output: 76/ for test: kms_flip_scaled_crc
results: parsing output: 77/ for test: syncobj_timeline
results: parsing output: 78/ for test: kms_cursor_legacy
results: parsing output: 79/ for test: kms_vblank
results: parsing output: 80/ for test: gem_spin_batch
results: parsing output: 81/ for test: kms_content_protection
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_set_tiling_vs_gtt
results: parsing output: 84/ for test: kms_ccs
results: parsing output: 85/ for test: api_intel_allocator
results: parsing output: 86/ for test: gem_exec_params
results: parsing output: 87/ for test: gen9_exec_parse
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_prime
results: parsing output: 90/ for test: kms_flip_tiling
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_pipe_control_store_loop
results: parsing output: 93/ for test: gem_render_copy
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: syncobj_timeline
results: parsing output: 96/ for test: kms_psr
results: parsing output: 97/ for test: kms_plane_multiple
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: fbdev
results: parsing output: 100/ for test: prime_vgem
results: parsing output: 101/ for test: i915_hangman
results: parsing output: 102/ for test: sw_sync
results: parsing output: 103/ for test: gem_exec_fence
results: parsing output: 104/ for test: kms_psr2_sf
results: parsing output: 105/ for test: gem_exec_params
results: parsing output: 106/ for test: perf_pmu
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: kms_psr2_sf
results: parsing output: 109/ for test: gem_mmap_offset
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: gem_lmem_swapping
results: parsing output: 112/ for test: kms_pipe_crc_basic
results: parsing output: 113/ for test: kms_cursor_edge_walk
results: parsing output: 114/ for test: gem_exec_schedule
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: gem_close
results: parsing output: 117/ for test: prime_busy
results: parsing output: 118/ for test: i915_pm_rps
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_atomic
results: parsing output: 122/ for test: kms_joiner
results: parsing output: 123/ for test: gem_exec_whisper
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gen3_render_tiledy_blits
results: parsing output: 126/ for test: kms_dither
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: i915_drm_fdinfo
results: parsing output: 131/ for test: gem_exec_params
results: parsing output: 132/ for test: kms_chamelium_edid
results: parsing output: 133/ for test: kms_frontbuffer_tracking
Done.