[59.435152] Initializing watchdogs
[59.435199]   /dev/watchdog0
[59.450187] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.454843] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.457080] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.460222] [001/132] (960s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[59.584106] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[59.586306] Starting dynamic subtest: pipe-A-eDP-1
[59.608920] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[59.616069] Starting dynamic subtest: pipe-B-eDP-1
[59.637209] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[59.643937] Starting dynamic subtest: pipe-C-eDP-1
[59.665125] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[59.671783] Starting dynamic subtest: pipe-D-eDP-1
[59.693317] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[59.697686] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[61.893345] [002/132] (958s left) gem_ctx_engines (invalid-engines)
[61.967512] Starting subtest: invalid-engines
[61.977104] Subtest invalid-engines: SUCCESS (0.010s)
[62.195040] [003/132] (957s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[62.298165] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[63.302221] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.013s)
[63.411800] [004/132] (956s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[63.505810] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[63.508309] Starting dynamic subtest: pipe-A-eDP-1
[63.531907] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[63.541631] Starting dynamic subtest: pipe-B-eDP-1
[63.563586] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[63.571162] Starting dynamic subtest: pipe-C-eDP-1
[63.591845] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[63.598926] Starting dynamic subtest: pipe-D-eDP-1
[63.618604] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[63.622254] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[65.809789] [005/132] (954s left) kms_big_fb (linear-16bpp-rotate-0)
[65.910428] Starting subtest: linear-16bpp-rotate-0
[67.326237] Subtest linear-16bpp-rotate-0: SUCCESS (1.422s)
[67.447359] [006/132] (952s left) i915_selftest (live)
[69.132110] Starting subtest: live
[69.134331] Starting dynamic subtest: sanitycheck
[73.863030] Dynamic subtest sanitycheck: SUCCESS (4.746s)
[73.871964] Starting dynamic subtest: uncore
[78.170245] Dynamic subtest uncore: SUCCESS (4.313s)
[78.178295] Starting dynamic subtest: workarounds
[82.781033] Dynamic subtest workarounds: SUCCESS (4.613s)
[82.789576] Starting dynamic subtest: gt_engines
[85.875226] Dynamic subtest gt_engines: SUCCESS (3.093s)
[85.884127] Starting dynamic subtest: gt_timelines
[102.204794] Dynamic subtest gt_timelines: SUCCESS (16.318s)
[102.213764] Starting dynamic subtest: gt_contexts
[106.530894] Dynamic subtest gt_contexts: SUCCESS (4.324s)
[106.540204] Starting dynamic subtest: gt_lrc
[112.554762] Dynamic subtest gt_lrc: SUCCESS (6.022s)
[112.563595] Starting dynamic subtest: gt_mocs
[116.992223] Dynamic subtest gt_mocs: SUCCESS (4.435s)
[117.001500] Starting dynamic subtest: gt_pm
[121.773988] Dynamic subtest gt_pm: SUCCESS (4.779s)
[121.782618] Starting dynamic subtest: gt_heartbeat
[126.164062] Dynamic subtest gt_heartbeat: SUCCESS (4.388s)
[126.173033] Starting dynamic subtest: gt_tlb
[135.459821] Dynamic subtest gt_tlb: SUCCESS (9.293s)
[135.468103] Starting dynamic subtest: requests
[148.959614] Dynamic subtest requests: SUCCESS (13.498s)
[148.968343] Starting dynamic subtest: migrate
[156.631013] Dynamic subtest migrate: SUCCESS (7.673s)
[156.640067] Starting dynamic subtest: active
[160.969666] Dynamic subtest active: SUCCESS (4.338s)
[160.978123] Starting dynamic subtest: objects
[165.665557] Dynamic subtest objects: SUCCESS (4.697s)
[165.673497] Starting dynamic subtest: mman
[168.960032] Dynamic subtest mman: SUCCESS (3.294s)
[168.969288] Starting dynamic subtest: dmabuf
[173.292344] Dynamic subtest dmabuf: SUCCESS (4.332s)
[173.306699] Starting dynamic subtest: vma
[177.619419] Dynamic subtest vma: SUCCESS (4.326s)
[177.628791] Starting dynamic subtest: coherency
[182.668985] Dynamic subtest coherency: SUCCESS (5.049s)
[182.678661] Starting dynamic subtest: gtt
[192.475630] Dynamic subtest gtt: SUCCESS (9.807s)
[192.484946] Starting dynamic subtest: gem
[199.939826] Dynamic subtest gem: SUCCESS (7.462s)
[199.949523] Starting dynamic subtest: evict
[205.538675] Dynamic subtest evict: SUCCESS (5.598s)
[205.548093] Starting dynamic subtest: hugepages
[214.315022] Dynamic subtest hugepages: SUCCESS (8.775s)
[214.323693] Starting dynamic subtest: gem_contexts
[241.272085] Dynamic subtest gem_contexts: SUCCESS (26.955s)
[241.281386] Starting dynamic subtest: client
[245.600698] Dynamic subtest client: SUCCESS (4.328s)
[245.609289] Starting dynamic subtest: gem_migrate
[249.907999] Dynamic subtest gem_migrate: SUCCESS (4.306s)
[249.916810] Starting dynamic subtest: reset
[252.683773] Dynamic subtest reset: SUCCESS (2.775s)
[252.692899] Starting dynamic subtest: memory_region
[256.965055] Dynamic subtest memory_region: SUCCESS (4.279s)
[256.974904] Starting dynamic subtest: hangcheck
[269.820889] Dynamic subtest hangcheck: SUCCESS (12.855s)
[269.830047] Starting dynamic subtest: execlists
[272.499574] Dynamic subtest execlists: SUCCESS (2.676s)
[272.508325] Starting dynamic subtest: ring_submission
[276.836414] Dynamic subtest ring_submission: SUCCESS (4.335s)
[276.850466] Starting dynamic subtest: perf
[281.214084] Dynamic subtest perf: SUCCESS (4.375s)
[281.223479] Starting dynamic subtest: slpc
[293.270997] Dynamic subtest slpc: SUCCESS (12.052s)
[293.288327] Starting dynamic subtest: guc
[298.689550] Dynamic subtest guc: SUCCESS (5.416s)
[298.697663] Starting dynamic subtest: guc_multi_lrc
[303.075813] Dynamic subtest guc_multi_lrc: SUCCESS (4.385s)
[303.084828] Starting dynamic subtest: guc_hang
[307.965262] Dynamic subtest guc_hang: SUCCESS (4.886s)
[307.979010] Starting dynamic subtest: late_gt_pm
[312.285744] Dynamic subtest late_gt_pm: SUCCESS (4.318s)
[312.288805] Subtest live: SUCCESS (243.159s)
[312.325735] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[312.328887] [007/132] (707s left) gem_close_race (basic-threads)
[313.879167] Starting subtest: basic-threads
[315.325062] Subtest basic-threads: SUCCESS (1.448s)
[315.482487] [FACT gem_close_race (basic-threads)] new: kernel.kmod_is_loaded.i915: true
[315.486502] [008/132] (704s left) drm_read (short-buffer-nonblock)
[315.660783] Starting subtest: short-buffer-nonblock
[315.663425] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[315.770494] [009/132] (704s left) kms_flip (flip-vs-modeset-vs-hang)
[315.859553] Starting subtest: flip-vs-modeset-vs-hang
[315.861790] Starting dynamic subtest: A-eDP1
[366.745526] Dynamic subtest A-eDP1: SUCCESS (50.880s)
[366.753801] Starting dynamic subtest: D-eDP1
[416.726962] Dynamic subtest D-eDP1: SUCCESS (49.975s)
[416.729994] Subtest flip-vs-modeset-vs-hang: SUCCESS (100.861s)
[417.073652] [010/132] (603s left) kms_universal_plane (universal-plane-pageflip-windowed)
[417.168164] Starting subtest: universal-plane-pageflip-windowed
[417.170348] Starting dynamic subtest: pipe-A-eDP-1
[417.770500] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.601s)
[417.779581] Starting dynamic subtest: pipe-B-eDP-1
[419.015859] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.246s)
[419.025057] Starting dynamic subtest: pipe-C-eDP-1
[420.226669] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.207s)
[420.236083] Starting dynamic subtest: pipe-D-eDP-1
[421.460448] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.232s)
[421.462332] Subtest universal-plane-pageflip-windowed: SUCCESS (4.292s)
[422.345169] [011/132] (598s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[422.415255] Starting subtest: mixed-tiled-to-y-tiled-ccs
[422.419762] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[422.595760] [012/132] (597s left) prime_vgem (fence-write-hang)
[422.726265] Subtest fence-write-hang: SKIP (0.000s)
[422.839317] [FACT prime_vgem (fence-write-hang)] new: kernel.kmod_is_loaded.vgem: true
[422.843825] [013/132] (597s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[422.942794] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[422.947174] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[423.049690] [014/132] (597s left) kms_dsc (dsc-with-bpc-formats)
[423.150732] Starting subtest: dsc-with-bpc-formats
[423.259737] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[423.300588] [015/132] (597s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[423.402563] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[423.406568] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[423.483874] [016/132] (597s left) kms_pm_rpm (cursor)
[425.424332] Starting subtest: cursor
[425.429764] Subtest cursor: SKIP (0.000s)
[425.792949] [017/132] (594s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[425.902482] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[425.906627] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[425.981499] [018/132] (594s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[426.054240] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[426.113888] [019/132] (594s left) kms_invalid_mode (bad-hsync-start)
[426.211346] Starting subtest: bad-hsync-start
[426.213563] Starting dynamic subtest: pipe-A-eDP-1
[426.497380] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[426.505404] Starting dynamic subtest: pipe-B-eDP-1
[426.507557] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[426.515240] Starting dynamic subtest: pipe-C-eDP-1
[426.517391] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[426.525165] Starting dynamic subtest: pipe-D-eDP-1
[426.527352] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[426.529637] Subtest bad-hsync-start: SUCCESS (0.293s)
[427.312028] [020/132] (593s left) kms_lease (possible-crtcs-filtering)
[427.399072] Starting subtest: possible-crtcs-filtering
[427.406089] Subtest possible-crtcs-filtering: SUCCESS (0.009s)
[427.516574] [021/132] (593s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[427.620130] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[431.786694] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.169s)
[432.922861] [022/132] (587s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[433.023074] Starting subtest: y-tiled-16bpp-rotate-90
[433.027351] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[433.112287] [023/132] (587s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[433.209062] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[433.213260] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[433.302835] [024/132] (587s left) gem_ccs (ctrl-surf-copy)
[433.355372] Starting subtest: ctrl-surf-copy
[433.359740] Subtest ctrl-surf-copy: SKIP (0.000s)
[433.449901] [025/132] (587s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[433.544774] Starting subtest: plane-upscale-20x20-with-rotation
[433.546943] Starting dynamic subtest: pipe-A
[433.770072] Dynamic subtest pipe-A: SUCCESS (0.227s)
[433.778208] Starting dynamic subtest: pipe-B
[435.094440] Dynamic subtest pipe-B: SUCCESS (1.322s)
[435.102764] Starting dynamic subtest: pipe-C
[436.428228] Dynamic subtest pipe-C: SUCCESS (1.332s)
[436.437043] Starting dynamic subtest: pipe-D
[437.772522] Dynamic subtest pipe-D: SUCCESS (1.343s)
[437.774721] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.230s)
[438.976255] [026/132] (582s left) kms_cursor_legacy (flip-vs-cursor-toggle)
[439.114320] Starting subtest: flip-vs-cursor-toggle
[448.235536] Subtest flip-vs-cursor-toggle: SUCCESS (9.121s)
[449.091656] [027/132] (571s left) core_auth (getclient-simple)
[449.130039] Starting subtest: getclient-simple
[449.149809] Subtest getclient-simple: SUCCESS (0.021s)
[449.252968] [028/132] (571s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[449.348823] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[449.353229] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[449.447764] [029/132] (571s left) gem_exec_schedule (deep)
[449.627960] Starting subtest: deep
[449.630359] Starting dynamic subtest: rcs0
[454.703035] Dynamic subtest rcs0: SKIP (5.071s)
[454.714727] Starting dynamic subtest: vcs0
[464.951725] Dynamic subtest vcs0: SUCCESS (10.250s)
[464.962652] Starting dynamic subtest: vcs1
[475.831034] Dynamic subtest vcs1: SUCCESS (10.877s)
[475.841436] Starting dynamic subtest: vecs0
[486.716530] Dynamic subtest vecs0: SUCCESS (10.882s)
[486.729610] Starting dynamic subtest: ccs0
[498.848470] Dynamic subtest ccs0: SUCCESS (12.130s)
[498.855912] Subtest deep: SUCCESS (49.217s)
[500.825810] [030/132] (520s left) gem_tiled_pread_basic (basic)
[500.890641] Subtest basic: SKIP (0.000s)
[500.956205] [031/132] (520s left) i915_query (query-topology-unsupported)
[501.011335] Starting subtest: query-topology-unsupported
[501.015485] Subtest query-topology-unsupported: SKIP (0.000s)
[501.114278] [032/132] (520s left) gem_exec_whisper (basic-fds)
[501.182132] Starting subtest: basic-fds
[509.215067] Subtest basic-fds: SUCCESS (8.028s)
[509.428629] [033/132] (511s left) kms_plane_scaling (invalid-parameters)
[509.537895] Starting subtest: invalid-parameters
[509.540129] Starting dynamic subtest: less-than-1-height-src
[509.550760] Dynamic subtest less-than-1-height-src: SUCCESS (0.014s)
[509.560333] Starting dynamic subtest: less-than-1-width-src
[509.565616] Dynamic subtest less-than-1-width-src: SUCCESS (0.013s)
[509.567906] Subtest invalid-parameters: SUCCESS (0.028s)
[509.670075] [034/132] (511s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[509.770720] Starting subtest: fbcpsr-stridechange
[516.070889] Subtest fbcpsr-stridechange: SUCCESS (6.304s)
[516.196660] [035/132] (505s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[516.248619] Starting subtest: invalid-single-wait-unsubmitted
[516.250547] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[516.340493] [036/132] (505s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[516.435986] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[520.882581] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.450s)
[522.040585] [037/132] (499s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[522.137185] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[522.139486] Starting dynamic subtest: pipe-A-eDP-1
[522.198598] Dynamic subtest pipe-A-eDP-1: SKIP (0.002s)
[522.209071] Starting dynamic subtest: pipe-B-eDP-1
[522.266793] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[522.277196] Starting dynamic subtest: pipe-C-eDP-1
[522.335739] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[522.346066] Starting dynamic subtest: pipe-D-eDP-1
[522.403853] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[522.407909] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.010s)
[524.390873] [038/132] (497s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[524.453213] Starting subtest: y-tiled-to-vebox-x-tiled
[524.457214] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[524.637596] [039/132] (496s left) perf_pmu (semaphore-busy)
[524.679494] Starting subtest: semaphore-busy
[524.681768] Starting dynamic subtest: rcs0
[526.326475] Dynamic subtest rcs0: SUCCESS (1.644s)
[526.336662] Starting dynamic subtest: bcs0
[527.958238] Dynamic subtest bcs0: SUCCESS (1.630s)
[527.969823] Starting dynamic subtest: vcs0
[531.832861] Dynamic subtest vcs0: SUCCESS (3.871s)
[531.844626] Starting dynamic subtest: vcs1
[537.784675] Dynamic subtest vcs1: SUCCESS (5.949s)
[537.796075] Starting dynamic subtest: vecs0
[543.800398] Dynamic subtest vecs0: SUCCESS (6.013s)
[543.810972] Starting dynamic subtest: ccs0
[546.876274] Dynamic subtest ccs0: SUCCESS (3.073s)
[546.878502] Subtest semaphore-busy: SUCCESS (22.197s)
[547.000640] [040/132] (474s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[547.118804] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[550.964292] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (3.850s)
[552.104333] [041/132] (469s left) kms_hdmi_inject (inject-4k)
[552.452956] Starting subtest: inject-4k
[552.767294] Subtest inject-4k: SUCCESS (0.316s)
[553.356486] [042/132] (468s left) kms_cursor_crc (cursor-random-512x512)
[553.642594] Starting subtest: cursor-random-512x512
[553.647853] Subtest cursor-random-512x512: SKIP (0.001s)
[553.813423] [043/132] (467s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[553.907191] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[553.909431] Starting dynamic subtest: pipe-A-eDP-1
[553.934430] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[553.946197] Starting dynamic subtest: pipe-B-eDP-1
[553.970153] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[553.978761] Starting dynamic subtest: pipe-C-eDP-1
[554.001801] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[554.010294] Starting dynamic subtest: pipe-D-eDP-1
[554.035217] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[554.039974] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[556.118965] [044/132] (465s left) gem_exec_create (legacy)
[556.187978] Starting subtest: legacy
[556.190215] Starting dynamic subtest: smem
[560.255629] Dynamic subtest smem: SUCCESS (4.067s)
[560.258601] Subtest legacy: SUCCESS (4.070s)
[560.456349] [045/132] (461s left) gem_eio (throttle)
[560.706310] Starting subtest: throttle
[561.091342] Subtest throttle: SUCCESS (0.384s)
[561.234319] [046/132] (460s left) i915_pm_rpm (system-suspend)
[563.377446] Starting subtest: system-suspend
[581.603279] Subtest system-suspend: SUCCESS (2.966s)
[581.987141] [047/132] (439s left) kms_color_pipeline (plane-ctm3x4)
[582.092867] Starting subtest: plane-ctm3x4
[582.223318] Starting dynamic subtest: pipe-A-plane-0
[582.363009] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[582.374367] Starting dynamic subtest: pipe-A-plane-1
[582.481936] Dynamic subtest pipe-A-plane-1: SUCCESS (0.121s)
[582.498920] Starting dynamic subtest: pipe-A-plane-2
[582.644008] Dynamic subtest pipe-A-plane-2: SUCCESS (0.165s)
[583.832436] Starting dynamic subtest: pipe-B-plane-0
[583.954633] Dynamic subtest pipe-B-plane-0: SUCCESS (0.133s)
[583.964764] Starting dynamic subtest: pipe-B-plane-1
[584.073361] Dynamic subtest pipe-B-plane-1: SUCCESS (0.121s)
[584.083992] Starting dynamic subtest: pipe-B-plane-2
[584.181918] Dynamic subtest pipe-B-plane-2: SUCCESS (0.110s)
[585.326593] Starting dynamic subtest: pipe-C-plane-0
[585.432532] Dynamic subtest pipe-C-plane-0: SUCCESS (0.122s)
[585.443032] Starting dynamic subtest: pipe-C-plane-1
[585.541724] Dynamic subtest pipe-C-plane-1: SUCCESS (0.110s)
[585.552074] Starting dynamic subtest: pipe-C-plane-2
[585.661665] Dynamic subtest pipe-C-plane-2: SUCCESS (0.122s)
[586.885831] Starting dynamic subtest: pipe-D-plane-0
[586.997687] Dynamic subtest pipe-D-plane-0: SUCCESS (0.122s)
[587.008063] Starting dynamic subtest: pipe-D-plane-1
[587.107321] Dynamic subtest pipe-D-plane-1: SUCCESS (0.110s)
[587.117862] Starting dynamic subtest: pipe-D-plane-2
[587.227457] Dynamic subtest pipe-D-plane-2: SUCCESS (0.121s)
[587.510125] Subtest plane-ctm3x4: SUCCESS (5.538s)
[588.309328] [048/132] (433s left) gem_mmap_gtt (basic-small-copy-xy)
[588.365796] Subtest basic-small-copy-XY: SKIP (0.000s)
[588.424636] [049/132] (433s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[588.481613] Starting subtest: y-tiled-ccs-to-y-tiled
[588.486765] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[588.665604] [050/132] (433s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[588.757786] Starting subtest: nonblocking-crc-frame-sequence
[588.759938] Starting dynamic subtest: pipe-A-eDP-1
[589.487150] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.737s)
[589.497877] Starting dynamic subtest: pipe-B-eDP-1
[590.893488] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.423s)
[590.903599] Starting dynamic subtest: pipe-C-eDP-1
[592.294938] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.411s)
[592.306707] Starting dynamic subtest: pipe-D-eDP-1
[593.674912] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.388s)
[593.677995] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.964s)
[594.544121] [051/132] (427s left) perf_pmu (invalid-open)
[594.585844] Starting subtest: invalid-open
[594.588764] Subtest invalid-open: SUCCESS (0.000s)
[594.714457] [052/132] (427s left) gem_exec_balancer (invalid-bonds)
[594.777977] Starting subtest: invalid-bonds
[594.783770] Subtest invalid-bonds: SKIP (0.000s)
[595.099083] [053/132] (427s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[595.679808] Starting subtest: syncobj-backward-timeline-chain-engines
[595.797535] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.119s)
[596.686125] [054/132] (425s left) perf (gen12-oa-tlb-invalidate)
[596.808982] Starting subtest: gen12-oa-tlb-invalidate
[596.811713] Starting dynamic subtest: 0-rcs0
[606.834069] Dynamic subtest 0-rcs0: SUCCESS (10.053s)
[606.845500] Starting dynamic subtest: 1-vecs0
[616.877402] Dynamic subtest 1-vecs0: SUCCESS (10.047s)
[616.880049] Subtest gen12-oa-tlb-invalidate: SUCCESS (20.103s)
[616.931830] [055/132] (405s left) sysfs_timeslice_duration (duration)
[617.021599] Starting subtest: duration
[617.024291] Starting dynamic subtest: vecs0
[630.530834] Dynamic subtest vecs0: SUCCESS (13.510s)
[630.544753] Starting dynamic subtest: ccs0
[643.673483] Dynamic subtest ccs0: SUCCESS (13.144s)
[643.687020] Starting dynamic subtest: vcs0
[658.498745] Dynamic subtest vcs0: SUCCESS (14.826s)
[658.511643] Starting dynamic subtest: bcs0
[671.643693] Dynamic subtest bcs0: SUCCESS (13.148s)
[671.657732] Starting dynamic subtest: rcs0
[684.800392] Dynamic subtest rcs0: SUCCESS (13.152s)
[684.813924] Starting dynamic subtest: vcs1
[698.498008] Dynamic subtest vcs1: SUCCESS (13.690s)
[698.500910] Subtest duration: SUCCESS (81.487s)
[698.579844] [056/132] (323s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[698.694806] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[698.699877] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[698.793386] [057/132] (323s left) kms_cursor_crc (cursor-onscreen-128x128)
[699.024409] Starting subtest: cursor-onscreen-128x128
[699.033950] Starting dynamic subtest: pipe-A-eDP-1
[701.191034] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.158s)
[701.222690] Starting dynamic subtest: pipe-D-eDP-1
[704.061983] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.847s)
[704.064133] Subtest cursor-onscreen-128x128: SUCCESS (5.035s)
[704.875184] [058/132] (317s left) gem_eio (in-flight-internal-1us)
[705.091020] Starting subtest: in-flight-internal-1us
[706.593968] Subtest in-flight-internal-1us: SUCCESS (1.503s)
[706.715072] [059/132] (315s left) gem_pread (self)
[706.782572] Subtest self: SKIP (0.000s)
[706.846650] [060/132] (315s left) kms_scaling_modes (scaling-mode-full-aspect)
[706.940027] Starting subtest: scaling-mode-full-aspect
[706.942750] Starting dynamic subtest: pipe-A-eDP-1
[707.037833] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.099s)
[707.052016] Starting dynamic subtest: pipe-B-eDP-1
[708.169284] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.128s)
[708.181180] Starting dynamic subtest: pipe-C-eDP-1
[709.339744] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.169s)
[709.350838] Starting dynamic subtest: pipe-D-eDP-1
[710.469379] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s)
[710.472050] Subtest scaling-mode-full-aspect: SUCCESS (3.532s)
[711.773218] [061/132] (310s left) kms_chamelium_hpd (vga-hpd)
[712.163951] Subtest vga-hpd: SKIP (0.000s)
[712.950500] [062/132] (309s left) kms_frontbuffer_tracking (basic)
[713.055012] Starting subtest: basic
[716.252725] Subtest basic: SUCCESS (3.203s)
[716.363521] [063/132] (306s left) kms_getfb (getfb2-handle-closed)
[716.457783] Starting subtest: getfb2-handle-closed
[716.459905] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[716.572815] [064/132] (306s left) gem_exec_schedule (pi-common)
[716.761510] Starting subtest: pi-common
[716.763987] Starting dynamic subtest: rcs0
[716.791042] Dynamic subtest rcs0: SUCCESS (0.032s)
[716.801936] Starting dynamic subtest: bcs0
[716.823000] Dynamic subtest bcs0: SUCCESS (0.031s)
[716.836711] Starting dynamic subtest: vcs0
[716.854874] Dynamic subtest vcs0: SUCCESS (0.031s)
[716.870023] Starting dynamic subtest: vcs1
[716.884906] Dynamic subtest vcs1: SUCCESS (0.029s)
[716.900017] Starting dynamic subtest: vecs0
[716.912760] Dynamic subtest vecs0: SUCCESS (0.027s)
[716.928538] Starting dynamic subtest: ccs0
[716.941151] Dynamic subtest ccs0: SUCCESS (0.027s)
[716.944005] Subtest pi-common: SUCCESS (0.183s)
[717.167007] [065/132] (305s left) gem_lmem_swapping (heavy-multi)
[717.223834] Subtest heavy-multi: SKIP (0.000s)
[717.270172] [066/132] (305s left) gem_mmap_offset (mmap-boundaries)
[717.336016] Starting subtest: mmap-boundaries
[717.338314] Starting dynamic subtest: smem0
[717.620699] Dynamic subtest smem0: SUCCESS (0.282s)
[717.623646] Subtest mmap-boundaries: SUCCESS (0.283s)
[717.744151] [067/132] (305s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[717.840045] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[717.844594] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[717.946167] [068/132] (304s left) gem_exec_schedule (u-submit-early-slice)
[718.028704] Starting subtest: u-submit-early-slice
[718.031161] Starting dynamic subtest: rcs0
[718.069742] Dynamic subtest rcs0: SUCCESS (0.050s)
[718.081267] Starting dynamic subtest: bcs0
[718.119689] Dynamic subtest bcs0: SUCCESS (0.049s)
[718.130190] Starting dynamic subtest: vcs0
[718.167093] Dynamic subtest vcs0: SUCCESS (0.047s)
[718.178331] Starting dynamic subtest: vcs1
[718.206691] Dynamic subtest vcs1: SUCCESS (0.039s)
[718.217518] Starting dynamic subtest: vecs0
[718.251019] Dynamic subtest vecs0: SUCCESS (0.043s)
[718.267678] Starting dynamic subtest: ccs0
[718.299899] Dynamic subtest ccs0: SUCCESS (0.048s)
[718.302709] Subtest u-submit-early-slice: SUCCESS (0.281s)
[719.702741] [069/132] (303s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[719.803978] Starting subtest: fbcpsr-shrfb-scaledprimary
[725.541552] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.741s)
[725.656441] [070/132] (297s left) gem_userptr_blits (forbidden-operations)
[725.732386] Starting subtest: forbidden-operations
[725.737369] Subtest forbidden-operations: SKIP (0.000s)
[725.841430] [071/132] (297s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[725.931744] Starting subtest: bad-rotation-90-y-tiled-ccs
[725.934011] Starting dynamic subtest: pipe-A-eDP-1
[725.957011] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[725.968989] Starting dynamic subtest: pipe-B-eDP-1
[725.994093] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[726.004648] Starting dynamic subtest: pipe-C-eDP-1
[726.028801] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[726.039025] Starting dynamic subtest: pipe-D-eDP-1
[726.064712] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[726.069620] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.004s)
[728.177886] [072/132] (294s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[728.270600] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[728.961125] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.692s)
[729.139569] [073/132] (293s left) kms_dp_aux_dev (basic)
[729.191045] Starting subtest: basic
[729.419787] Subtest basic: SUCCESS (0.226s)
[729.492322] [074/132] (293s left) prime_self_import (basic-with_one_bo_two_files)
[729.534267] Starting subtest: basic-with_one_bo_two_files
[729.550830] Subtest basic-with_one_bo_two_files: SUCCESS (0.016s)
[729.644052] [075/132] (293s left) gem_sync (basic-many-each)
[729.718035] Starting subtest: basic-many-each
[732.032757] Subtest basic-many-each: SUCCESS (2.332s)
[732.224434] [076/132] (291s left) kms_psr (fbc-psr2-cursor-blt)
[732.321811] Starting subtest: fbc-psr2-cursor-blt
[732.324798] Starting dynamic subtest: eDP-1
[732.330810] Dynamic subtest eDP-1: SKIP (0.000s)
[732.337323] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[732.398837] [077/132] (290s left) gem_mmap_wc (read-write)
[732.464903] Subtest read-write: SKIP (0.000s)
[732.526825] [078/132] (290s left) kms_plane_lowres (tiling-none)
[732.617171] Starting subtest: tiling-none
[732.619494] Starting dynamic subtest: pipe-A-eDP-1
[732.624059] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[732.915272] Starting dynamic subtest: pipe-B-eDP-1
[732.920637] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[732.931537] Starting dynamic subtest: pipe-C-eDP-1
[732.936740] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[732.947891] Starting dynamic subtest: pipe-D-eDP-1
[732.953750] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[732.958907] Subtest tiling-none: SKIP (0.295s)
[733.760461] [079/132] (289s left) vgem_basic (sysfs)
[733.801870] Starting subtest: sysfs
[733.804593] Subtest sysfs: SUCCESS (0.000s)
[733.858653] [080/132] (289s left) kms_cursor_edge_walk (64x64-top-edge)
[733.946821] Starting subtest: 64x64-top-edge
[733.949611] Starting dynamic subtest: pipe-A-eDP-1
[737.388821] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.443s)
[737.400121] Starting dynamic subtest: pipe-D-eDP-1
[740.582144] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.192s)
[740.585083] Subtest 64x64-top-edge: SUCCESS (6.639s)
[741.458284] [081/132] (281s left) kms_async_flips (invalid-async-flip-atomic)
[741.551732] Starting subtest: invalid-async-flip-atomic
[741.925875] Starting dynamic subtest: pipe-A-eDP-1
[742.764027] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.839s)
[743.125904] Starting dynamic subtest: pipe-B-eDP-1
[743.955240] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.839s)
[744.328686] Starting dynamic subtest: pipe-C-eDP-1
[745.160880] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.842s)
[745.522793] Starting dynamic subtest: pipe-D-eDP-1
[746.346023] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.834s)
[746.348875] Subtest invalid-async-flip-atomic: SUCCESS (4.799s)
[747.570436] [082/132] (275s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[747.663784] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[747.668808] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[747.752325] [083/132] (275s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[747.854741] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[747.859927] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[747.953725] [084/132] (275s left) gem_ctx_persistence (engines-mixed-process)
[748.010893] Starting subtest: engines-mixed-process
[748.013828] Starting dynamic subtest: rcs0
[749.548503] Dynamic subtest rcs0: SUCCESS (1.538s)
[749.561245] Starting dynamic subtest: bcs0
[751.537921] Dynamic subtest bcs0: SUCCESS (1.988s)
[751.551583] Starting dynamic subtest: vcs0
[753.585807] Dynamic subtest vcs0: SUCCESS (2.043s)
[753.597982] Starting dynamic subtest: vcs1
[755.569628] Dynamic subtest vcs1: SUCCESS (1.987s)
[755.582871] Starting dynamic subtest: vecs0
[757.559791] Dynamic subtest vecs0: SUCCESS (1.989s)
[757.578401] Starting dynamic subtest: ccs0
[759.575858] Dynamic subtest ccs0: SUCCESS (2.011s)
[759.578780] Subtest engines-mixed-process: SUCCESS (11.565s)
[759.659044] [085/132] (263s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[759.758143] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[759.761013] Starting dynamic subtest: pipe-A-default-mode
[762.451120] Dynamic subtest pipe-A-default-mode: SUCCESS (2.694s)
[762.453904] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.695s)
[763.274557] [086/132] (260s left) kms_addfb_basic (invalid-get-prop-any)
[763.331768] Starting subtest: invalid-get-prop-any
[763.334065] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[763.484176] [087/132] (260s left) kms_vblank (wait-forked-busy-hang)
[763.575807] Starting subtest: wait-forked-busy-hang
[763.577921] Starting dynamic subtest: pipe-A-eDP-1
[790.299193] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.730s)
[790.319540] Starting dynamic subtest: pipe-D-eDP-1
[805.351338] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.054s)
[805.354016] Subtest wait-forked-busy-hang: SUCCESS (41.789s)
[806.174893] [088/132] (217s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[806.264753] Starting subtest: addfb25-y-tiled-legacy
[806.266843] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[806.370505] [089/132] (217s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[806.464819] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[813.705875] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.242s)
[813.845180] [090/132] (210s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[813.909036] Starting subtest: invalid-bsd2-flag-on-vebox
[813.911982] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[814.040148] [091/132] (209s left) syncobj_timeline (reset-signaled)
[814.085419] Starting subtest: reset-signaled
[814.087899] Subtest reset-signaled: SUCCESS (0.001s)
[814.181736] [092/132] (209s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[814.296677] Starting subtest: cursorA-vs-flipA-atomic
[825.445834] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.144s)
[826.257473] [093/132] (197s left) gem_mmap_wc (read)
[826.315382] Subtest read: SKIP (0.000s)
[826.372462] [094/132] (197s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[826.474936] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[826.477696] Starting dynamic subtest: A-eDP1
[835.362705] Dynamic subtest A-eDP1: SUCCESS (8.884s)
[835.376023] Starting dynamic subtest: B-eDP1
[844.267586] Dynamic subtest B-eDP1: SUCCESS (8.900s)
[844.281170] Starting dynamic subtest: C-eDP1
[853.151021] Dynamic subtest C-eDP1: SUCCESS (8.879s)
[853.162021] Starting dynamic subtest: D-eDP1
[862.049030] Dynamic subtest D-eDP1: SUCCESS (8.895s)
[862.054696] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.574s)
[863.257002] [095/132] (160s left) gem_madvise (dontneed-before-exec)
[863.300419] Starting subtest: dontneed-before-exec
[863.327808] Subtest dontneed-before-exec: SKIP (0.028s)
[863.395309] [096/132] (160s left) gen9_exec_parse (bb-start-out)
[863.452738] Subtest bb-start-out: SKIP (0.000s)
[863.544642] [097/132] (160s left) perf (oa-formats)
[863.641728] Starting subtest: oa-formats
[863.644578] Starting dynamic subtest: 0-rcs0
[863.654172] Dynamic subtest 0-rcs0: SUCCESS (0.013s)
[863.665824] Starting dynamic subtest: 1-vcs0
[863.678660] Dynamic subtest 1-vcs0: SUCCESS (0.023s)
[863.681217] Subtest oa-formats: SUCCESS (0.038s)
[863.731350] [098/132] (160s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[863.830835] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[863.835728] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[863.926329] [099/132] (160s left) syncobj_timeline (wait-all-interrupted)
[863.989903] Starting subtest: wait-all-interrupted
[864.097434] Subtest wait-all-interrupted: SUCCESS (0.108s)
[864.181521] [100/132] (160s left) kms_pm_rpm (system-suspend-modeset)
[865.721208] Starting subtest: system-suspend-modeset
[885.053801] Subtest system-suspend-modeset: SUCCESS (4.229s)
[885.430781] [101/132] (138s left) i915_drm_fdinfo (virtual-busy-all)
[885.488321] Starting subtest: virtual-busy-all
[885.494302] Subtest virtual-busy-all: SKIP (0.000s)
[885.570169] [102/132] (138s left) kms_content_protection (atomic)
[885.717800] Starting subtest: atomic
[885.723371] Subtest atomic: SKIP (0.000s)
[885.838794] [103/132] (138s left) kms_addfb_basic (invalid-get-prop)
[885.894155] Starting subtest: invalid-get-prop
[885.896638] Subtest invalid-get-prop: SUCCESS (0.000s)
[886.069880] [104/132] (138s left) kms_chamelium_edid (dp-edid-read)
[886.458965] Subtest dp-edid-read: SKIP (0.000s)
[887.259914] [105/132] (137s left) gem_ctx_persistence (file)
[887.309028] Starting subtest: file
[887.415448] Subtest file: SUCCESS (0.106s)
[887.526792] [106/132] (137s left) gem_spin_batch (spin-all)
[887.581449] Starting subtest: spin-all
[887.598011] Subtest spin-all: SUCCESS (0.016s)
[888.708358] [107/132] (135s left) kms_psr (pr-cursor-render)
[888.804873] Starting subtest: pr-cursor-render
[888.809438] Subtest pr-cursor-render: SKIP (0.000s)
[888.889706] [108/132] (135s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[888.942256] Starting subtest: etime-multi-wait-all-available-unsubmitted
[889.050043] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.107s)
[889.154270] [109/132] (135s left) gem_mmap_gtt (bad-object)
[889.218329] Subtest bad-object: SKIP (0.000s)
[889.276176] [110/132] (135s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[889.381698] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[889.386322] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[889.480198] [111/132] (135s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[889.574845] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[889.577443] Starting dynamic subtest: pipe-A-eDP-1
[889.679740] Dynamic subtest pipe-A-eDP-1: SKIP (0.110s)
[889.691152] Starting dynamic subtest: pipe-B-eDP-1
[890.812561] Dynamic subtest pipe-B-eDP-1: SKIP (1.127s)
[890.818139] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.241s)
[892.075365] [112/132] (132s left) dmabuf_sync_file (export-basic)
[892.126670] Starting subtest: export-basic
[892.129225] Subtest export-basic: SUCCESS (0.001s)
[892.185967] [113/132] (132s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[892.247343] Starting subtest: etime-single-wait-all-for-submit-submitted
[892.353172] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.106s)
[892.433718] [114/132] (132s left) kms_psr (psr2-suspend)
[892.542471] Starting subtest: psr2-suspend
[892.545293] Starting dynamic subtest: eDP-1
[910.297579] Dynamic subtest eDP-1: SUCCESS (3.539s)
[910.300274] Subtest psr2-suspend: SUCCESS (3.541s)
[911.149119] [115/132] (113s left) gem_exec_reloc (basic-wc-cpu-active)
[911.223313] Subtest basic-wc-cpu-active: SKIP (0.000s)
[911.310065] [116/132] (113s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[911.412105] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[911.414828] Starting dynamic subtest: A-eDP1
[914.986037] Dynamic subtest A-eDP1: SUCCESS (3.561s)
[914.999360] Starting dynamic subtest: B-eDP1
[918.307306] Dynamic subtest B-eDP1: SUCCESS (3.311s)
[918.319746] Starting dynamic subtest: C-eDP1
[921.608092] Dynamic subtest C-eDP1: SUCCESS (3.296s)
[921.620997] Starting dynamic subtest: D-eDP1
[924.923673] Dynamic subtest D-eDP1: SUCCESS (3.310s)
[924.926297] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.486s)
[925.783173] [117/132] ( 99s left) gem_workarounds (suspend-resume-context)
[925.847035] Starting subtest: suspend-resume-context
[941.908545] Subtest suspend-resume-context: SUCCESS (1.185s)
[942.075935] [118/132] ( 83s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[942.200891] Starting subtest: yf-tiled-16bpp-rotate-0
[942.205043] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[942.303041] [119/132] ( 82s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[942.402389] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[942.406723] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[942.490304] [120/132] ( 82s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[942.607364] Starting subtest: cursorA-vs-flipA-varying-size
[953.771216] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.137s)
[954.577445] [121/132] ( 70s left) kms_flip (basic-flip-vs-modeset)
[954.674319] Starting subtest: basic-flip-vs-modeset
[954.676596] Starting dynamic subtest: A-eDP1
[957.199911] Dynamic subtest A-eDP1: SUCCESS (2.521s)
[957.212141] Starting dynamic subtest: B-eDP1
[959.416587] Dynamic subtest B-eDP1: SUCCESS (2.211s)
[959.428022] Starting dynamic subtest: C-eDP1
[961.603917] Dynamic subtest C-eDP1: SUCCESS (2.183s)
[961.618679] Starting dynamic subtest: D-eDP1
[963.806066] Dynamic subtest D-eDP1: SUCCESS (2.203s)
[963.808534] Subtest basic-flip-vs-modeset: SUCCESS (9.126s)
[964.684740] [122/132] ( 60s left) kms_plane (pixel-format-linear-modifier)
[964.793500] Starting subtest: pixel-format-linear-modifier
[964.822360] Starting dynamic subtest: pipe-A-plane-0
[968.445912] Dynamic subtest pipe-A-plane-0: SUCCESS (3.622s)
[968.458868] Starting dynamic subtest: pipe-A-plane-3
[970.146882] Dynamic subtest pipe-A-plane-3: SUCCESS (1.698s)
[970.160247] Starting dynamic subtest: pipe-A-plane-5
[970.358061] Dynamic subtest pipe-A-plane-5: SUCCESS (0.209s)
[971.441832] Starting dynamic subtest: pipe-B-plane-0
[975.120082] Dynamic subtest pipe-B-plane-0: SUCCESS (3.689s)
[975.131125] Starting dynamic subtest: pipe-B-plane-3
[976.830607] Dynamic subtest pipe-B-plane-3: SUCCESS (1.710s)
[976.841537] Starting dynamic subtest: pipe-B-plane-5
[977.041323] Dynamic subtest pipe-B-plane-5: SUCCESS (0.210s)
[977.370946] Subtest pixel-format-linear-modifier: SUCCESS (12.575s)
[978.182463] [123/132] ( 47s left) gem_exec_reloc (basic-gtt-active)
[978.239348] Subtest basic-gtt-active: SKIP (0.000s)
[978.298154] [124/132] ( 47s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[978.394623] Starting subtest: fbc-indfb-scaledprimary
[981.285743] Subtest fbc-indfb-scaledprimary: SUCCESS (2.895s)
[982.491717] [125/132] ( 42s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[982.608196] Starting subtest: psr2-cursor-plane-update-sf
[982.610374] Starting dynamic subtest: pipe-A-eDP-1
[983.172374] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.567s)
[983.184602] Starting dynamic subtest: pipe-B-eDP-1
[984.429585] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.258s)
[984.431980] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.828s)
[985.281764] [126/132] ( 40s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[985.372917] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[985.376925] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s)
[985.446957] [127/132] ( 40s left) i915_module_load (reload-no-display)
[985.481332] Starting subtest: reload-no-display
[991.479985] Subtest reload-no-display: SUCCESS (5.997s)
[991.510980] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[991.511743] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[991.515007] [128/132] ( 34s left) gem_mmap_wc (read-write-distinct)
[993.057518] Subtest read-write-distinct: SKIP (0.000s)
[993.122492] [FACT gem_mmap_wc (read-write-distinct)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[993.123870] [FACT gem_mmap_wc (read-write-distinct)] new: kernel.kmod_is_loaded.i915: true
[993.127860] [129/132] ( 32s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[993.225177] Starting subtest: fbc-psr2-sprite-plane-onoff
[993.227525] Starting dynamic subtest: eDP-1
[993.231927] Dynamic subtest eDP-1: SKIP (0.000s)
[993.236333] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[993.313304] [130/132] ( 32s left) kms_force_connector_basic (force-edid)
[993.390589] Starting subtest: force-edid
[993.757152] Subtest force-edid: SUCCESS (0.368s)
[993.893408] [131/132] ( 31s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[994.008011] Starting subtest: 2x-cursor-vs-flip-atomic
[994.011804] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[994.149437] [132/132] ( 31s left) kms_prop_blob (invalid-get-prop-any)
[994.212128] Starting subtest: invalid-get-prop-any
[994.213948] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[994.309234] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: gem_ctx_engines
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: i915_selftest
results: parsing output: 6/ for test: gem_close_race
results: parsing output: 7/ for test: drm_read
results: parsing output: 8/ for test: kms_flip
results: parsing output: 9/ for test: kms_universal_plane
results: parsing output: 10/ for test: gem_render_copy
results: parsing output: 11/ for test: prime_vgem
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_dsc
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_pm_rpm
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_pipe_b_c_ivb
results: parsing output: 18/ for test: kms_invalid_mode
results: parsing output: 19/ for test: kms_lease
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: gem_ccs
results: parsing output: 24/ for test: kms_plane_scaling
results: parsing output: 25/ for test: kms_cursor_legacy
results: parsing output: 26/ for test: core_auth
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: gem_exec_schedule
results: parsing output: 29/ for test: gem_tiled_pread_basic
results: parsing output: 30/ for test: i915_query
results: parsing output: 31/ for test: gem_exec_whisper
results: parsing output: 32/ for test: kms_plane_scaling
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: gem_render_copy
results: parsing output: 38/ for test: perf_pmu
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_hdmi_inject
results: parsing output: 41/ for test: kms_cursor_crc
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: gem_exec_create
results: parsing output: 44/ for test: gem_eio
results: parsing output: 45/ for test: i915_pm_rpm
results: parsing output: 46/ for test: kms_color_pipeline
results: parsing output: 47/ for test: gem_mmap_gtt
results: parsing output: 48/ for test: gem_render_copy
results: parsing output: 49/ for test: kms_pipe_crc_basic
results: parsing output: 50/ for test: perf_pmu
results: parsing output: 51/ for test: gem_exec_balancer
results: parsing output: 52/ for test: gem_exec_fence
results: parsing output: 53/ for test: perf
results: parsing output: 54/ for test: sysfs_timeslice_duration
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_cursor_crc
results: parsing output: 57/ for test: gem_eio
results: parsing output: 58/ for test: gem_pread
results: parsing output: 59/ for test: kms_scaling_modes
results: parsing output: 60/ for test: kms_chamelium_hpd
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_getfb
results: parsing output: 63/ for test: gem_exec_schedule
results: parsing output: 64/ for test: gem_lmem_swapping
results: parsing output: 65/ for test: gem_mmap_offset
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_exec_schedule
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_userptr_blits
results: parsing output: 70/ for test: kms_ccs
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: kms_dp_aux_dev
results: parsing output: 73/ for test: prime_self_import
results: parsing output: 74/ for test: gem_sync
results: parsing output: 75/ for test: kms_psr
results: parsing output: 76/ for test: gem_mmap_wc
results: parsing output: 77/ for test: kms_plane_lowres
results: parsing output: 78/ for test: vgem_basic
results: parsing output: 79/ for test: kms_cursor_edge_walk
results: parsing output: 80/ for test: kms_async_flips
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_ctx_persistence
results: parsing output: 84/ for test: kms_flip_scaled_crc
results: parsing output: 85/ for test: kms_addfb_basic
results: parsing output: 86/ for test: kms_vblank
results: parsing output: 87/ for test: kms_addfb_basic
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_exec_params
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: gem_mmap_wc
results: parsing output: 93/ for test: kms_flip
results: parsing output: 94/ for test: gem_madvise
results: parsing output: 95/ for test: gen9_exec_parse
results: parsing output: 96/ for test: perf
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: syncobj_timeline
results: parsing output: 99/ for test: kms_pm_rpm
results: parsing output: 100/ for test: i915_drm_fdinfo
results: parsing output: 101/ for test: kms_content_protection
results: parsing output: 102/ for test: kms_addfb_basic
results: parsing output: 103/ for test: kms_chamelium_edid
results: parsing output: 104/ for test: gem_ctx_persistence
results: parsing output: 105/ for test: gem_spin_batch
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: syncobj_timeline
results: parsing output: 108/ for test: gem_mmap_gtt
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_psr2_sf
results: parsing output: 111/ for test: dmabuf_sync_file
results: parsing output: 112/ for test: syncobj_timeline
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: gem_exec_reloc
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: gem_workarounds
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: kms_cursor_legacy
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: kms_plane
results: parsing output: 122/ for test: gem_exec_reloc
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_psr2_sf
results: parsing output: 125/ for test: kms_flip_scaled_crc
results: parsing output: 126/ for test: i915_module_load
results: parsing output: 127/ for test: gem_mmap_wc
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: kms_force_connector_basic
results: parsing output: 130/ for test: kms_cursor_legacy
results: parsing output: 131/ for test: kms_prop_blob
Done.