[59.354582] Initializing watchdogs
[59.354632]   /dev/watchdog0
[59.368874] [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.374136] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.376310] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.378306] [001/131] (960s left) gen9_exec_parse (bb-chained)
[59.443932] Subtest bb-chained: SKIP (0.000s)
[59.526326] [002/131] (960s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[59.603336] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[59.604354] Starting dynamic subtest: pipe-A-eDP-1
[59.613886] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[59.619847] Starting dynamic subtest: pipe-B-eDP-1
[59.629143] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[59.639650] Starting dynamic subtest: pipe-C-eDP-1
[59.647922] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[59.653585] Starting dynamic subtest: pipe-D-eDP-1
[59.662292] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[59.664040] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[61.887415] [003/131] (958s left) kms_properties (crtc-properties-atomic)
[61.971643] Starting subtest: crtc-properties-atomic
[61.972730] Starting dynamic subtest: pipe-A-eDP-1
[62.426224] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.454s)
[62.432670] Starting dynamic subtest: pipe-B-eDP-1
[63.545721] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.120s)
[63.552819] Starting dynamic subtest: pipe-C-eDP-1
[64.662766] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.116s)
[64.669394] Starting dynamic subtest: pipe-D-eDP-1
[65.793998] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.130s)
[65.794904] Subtest crtc-properties-atomic: SUCCESS (3.823s)
[66.601583] [004/131] (953s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[66.697265] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[66.699073] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[66.811804] [005/131] (953s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[66.904968] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[66.906077] Starting dynamic subtest: pipe-A-default-mode
[67.195050] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[67.196938] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.288s)
[68.047150] [006/131] (951s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[68.143400] Starting subtest: psr-1p-primscrn-cur-indfb-move
[75.441460] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.298s)
[75.604603] [007/131] (944s left) kms_psr (psr2-primary-mmap-cpu)
[75.700796] Starting subtest: psr2-primary-mmap-cpu
[75.701810] Starting dynamic subtest: eDP-1
[77.442867] Dynamic subtest eDP-1: SUCCESS (1.742s)
[77.443820] Subtest psr2-primary-mmap-cpu: SUCCESS (1.742s)
[78.335709] [008/131] (941s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[78.434618] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[78.436470] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[78.521134] [009/131] (941s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[78.604576] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[78.606739] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[78.700689] [010/131] (941s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[78.810694] Starting subtest: 4-tiled-addfb-size-offset-overflow
[78.811727] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[78.925434] [011/131] (941s left) gem_eio (wait-immediate)
[79.091810] Starting subtest: wait-immediate
[79.329211] Subtest wait-immediate: SUCCESS (0.238s)
[79.443773] [012/131] (940s left) kms_psr2_sf (pr-overlay-primary-update-sf-dmg-area)
[84.521237] Starting subtest: pr-overlay-primary-update-sf-dmg-area
[84.523953] Subtest pr-overlay-primary-update-sf-dmg-area: SKIP (0.003s)
[85.379150] [013/131] (934s left) gem_exec_alignment (many)
[85.438092] Starting subtest: many
[107.131564] Subtest many: SUCCESS (21.691s)
[109.524108] [014/131] (910s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[109.650679] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[114.237890] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (4.587s)
[115.360252] [015/131] (904s left) kms_psr (fbc-psr-cursor-blt)
[115.449408] Starting subtest: fbc-psr-cursor-blt
[115.450370] Starting dynamic subtest: eDP-1
[115.452144] Dynamic subtest eDP-1: SKIP (0.000s)
[115.453879] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[115.521650] [016/131] (904s left) gem_fence_thrash (bo-write-verify-threaded-y)
[115.548955] Starting subtest: bo-write-verify-threaded-y
[115.577135] Subtest bo-write-verify-threaded-y: SKIP (0.028s)
[115.631420] [017/131] (904s left) fbdev (unaligned-read)
[115.661369] Starting subtest: unaligned-read
[115.676997] Subtest unaligned-read: SUCCESS (0.016s)
[115.744006] [018/131] (904s left) kms_lease (lease-unleased-crtc)
[115.838091] Starting subtest: lease-unleased-crtc
[115.839283] Starting dynamic subtest: pipe-A-eDP-1
[116.139330] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.300s)
[116.148170] Starting dynamic subtest: pipe-B-eDP-1
[116.903785] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.760s)
[116.912054] Starting dynamic subtest: pipe-C-eDP-1
[116.912909] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[116.919506] Starting dynamic subtest: pipe-D-eDP-1
[116.920349] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[116.921178] Subtest lease-unleased-crtc: SUCCESS (1.074s)
[117.300683] [019/131] (903s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[117.404375] Starting subtest: x-tiled-16bpp-rotate-0
[118.785434] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.381s)
[118.920728] [020/131] (901s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[123.953834] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[123.956749] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.003s)
[124.831966] [021/131] (895s left) gem_exec_balancer (bonded-false-hang)
[125.083487] Starting subtest: bonded-false-hang
[125.085242] Subtest bonded-false-hang: SKIP (0.000s)
[125.209958] [022/131] (895s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[125.303532] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[131.912872] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.608s)
[132.077266] [023/131] (888s left) gem_exec_params (mmapped)
[132.131933] Starting subtest: mmapped
[132.136053] Subtest mmapped: SUCCESS (0.004s)
[132.280413] [024/131] (888s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[132.375844] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[139.421569] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.046s)
[140.555145] [025/131] (880s left) gem_mmap_gtt (cpuset-big-copy)
[140.603314] Subtest cpuset-big-copy: SKIP (0.000s)
[140.656578] [026/131] (879s left) kms_psr (fbc-psr2-sprite-blt)
[140.741377] Starting subtest: fbc-psr2-sprite-blt
[140.742414] Starting dynamic subtest: eDP-1
[140.744279] Dynamic subtest eDP-1: SKIP (0.000s)
[140.745953] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[140.829870] [027/131] (879s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[140.931504] Starting subtest: flip-primary-invalidate-overlay
[152.521139] Subtest flip-primary-invalidate-overlay: SUCCESS (11.589s)
[152.648697] [028/131] (868s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[152.747314] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[152.748953] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[152.857284] [029/131] (867s left) kms_getfb (getfb-handle-protection)
[152.958552] Starting subtest: getfb-handle-protection
[152.974681] Subtest getfb-handle-protection: SUCCESS (0.017s)
[153.121038] [030/131] (867s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[153.174810] Starting subtest: multi-wait-all-for-submit-submitted
[153.281929] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.105s)
[153.359930] [031/131] (867s left) kms_flip (2x-plain-flip-ts-check)
[153.445455] Starting subtest: 2x-plain-flip-ts-check
[153.455346] Subtest 2x-plain-flip-ts-check: SKIP (0.011s)
[153.553065] [032/131] (867s left) gem_pxp (reject-modify-context-protection-on)
[153.671426] Starting subtest: reject-modify-context-protection-on
[153.672549] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[153.797648] [033/131] (867s left) kms_chamelium_audio (dp-audio-edid)
[154.171421] Subtest dp-audio-edid: SKIP (0.000s)
[154.940632] [034/131] (865s left) sysfs_timeslice_duration (timeout)
[154.998824] Starting subtest: timeout
[154.999897] Starting dynamic subtest: vecs0
[164.906276] Dynamic subtest vecs0: SUCCESS (9.905s)
[164.916154] Starting dynamic subtest: ccs0
[166.353605] Dynamic subtest ccs0: SUCCESS (1.445s)
[166.362721] Starting dynamic subtest: vcs0
[175.910701] Dynamic subtest vcs0: SUCCESS (9.555s)
[175.919069] Starting dynamic subtest: bcs0
[176.583652] Dynamic subtest bcs0: SUCCESS (0.672s)
[176.592514] Starting dynamic subtest: rcs0
[177.956425] Dynamic subtest rcs0: SUCCESS (1.372s)
[177.966881] Starting dynamic subtest: vcs1
[187.936394] Dynamic subtest vcs1: SUCCESS (9.975s)
[187.938410] Subtest timeout: SUCCESS (32.934s)
[188.025741] [035/131] (832s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[188.077245] Starting subtest: invalid-bsd2-flag-on-vebox
[188.078295] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[188.215603] [036/131] (832s left) gem_exercise_blt (fast-copy-emit)
[188.268108] Starting subtest: fast-copy-emit
[188.269104] Starting dynamic subtest: linear-smem-smem-emit
[188.295922] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.027s)
[188.305063] Starting dynamic subtest: xmajor-smem-smem-emit
[188.316972] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.020s)
[188.323751] Starting dynamic subtest: tile4-smem-smem-emit
[188.337194] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.020s)
[188.343856] Starting dynamic subtest: tile64-smem-smem-emit
[188.358463] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.021s)
[188.359436] Subtest fast-copy-emit: SUCCESS (0.091s)
[188.514675] [037/131] (832s left) kms_plane_multiple (2x-tiling-4)
[188.604447] Starting subtest: 2x-tiling-4
[188.606230] Subtest 2x-tiling-4: SKIP (0.000s)
[188.694273] [038/131] (832s left) dmabuf_sync_file (export-before-signal)
[188.784906] Starting subtest: export-before-signal
[188.785827] Subtest export-before-signal: SUCCESS (0.001s)
[188.845931] [FACT dmabuf_sync_file (export-before-signal)] new: kernel.kmod_is_loaded.vgem: true
[188.847706] [039/131] (832s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[188.933280] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[188.934149] Starting dynamic subtest: pipe-A-eDP-1
[188.958593] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[188.966030] Starting dynamic subtest: pipe-B-eDP-1
[188.989548] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[188.996624] Starting dynamic subtest: pipe-C-eDP-1
[189.018884] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[189.025451] Starting dynamic subtest: pipe-D-eDP-1
[189.050823] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[189.052808] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.008s)
[191.146595] [040/131] (829s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[191.240284] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[191.241901] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[191.342471] [041/131] (829s left) i915_pm_rpm (gem-pread)
[193.066288] Starting subtest: gem-pread
[195.246384] Subtest gem-pread: SUCCESS (2.182s)
[195.340341] [042/131] (825s left) prime_vgem (fence-wait)
[195.418702] Starting subtest: fence-wait
[195.419774] Starting dynamic subtest: rcs0
[196.478744] Dynamic subtest rcs0: SUCCESS (1.057s)
[196.485789] Starting dynamic subtest: bcs0
[197.551695] Dynamic subtest bcs0: SUCCESS (1.070s)
[197.566761] Starting dynamic subtest: vcs0
[198.599706] Dynamic subtest vcs0: SUCCESS (1.046s)
[198.607322] Starting dynamic subtest: vcs1
[199.641418] Dynamic subtest vcs1: SUCCESS (1.040s)
[199.649788] Starting dynamic subtest: vecs0
[200.682616] Dynamic subtest vecs0: SUCCESS (1.039s)
[200.692678] Starting dynamic subtest: ccs0
[201.726513] Dynamic subtest ccs0: SUCCESS (1.043s)
[201.728688] Subtest fence-wait: SUCCESS (6.308s)
[201.862801] [043/131] (819s left) syncobj_timeline (invalid-wait-bad-flags)
[201.919379] Starting subtest: invalid-wait-bad-flags
[201.920387] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[202.047894] [044/131] (819s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[202.130871] Starting subtest: fbcpsr-rgb565-draw-render
[209.130967] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.001s)
[209.303763] [045/131] (811s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[209.359845] Starting subtest: wait-all-for-submit-delayed-submit
[209.465428] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[209.546740] [046/131] (811s left) gem_exec_flush (basic-wb-rw-default)
[209.599141] Starting subtest: basic-wb-rw-default
[215.051486] Subtest basic-wb-rw-default: SUCCESS (5.452s)
[215.259172] [047/131] (805s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[215.343634] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[215.345353] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[215.458396] [048/131] (805s left) kms_pipe_crc_basic (suspend-read-crc)
[215.550322] Starting subtest: suspend-read-crc
[215.551254] Starting dynamic subtest: pipe-A-eDP-1
[233.625374] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.056s)
[233.633757] Starting dynamic subtest: pipe-B-eDP-1
[252.387397] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.192s)
[252.397983] Starting dynamic subtest: pipe-C-eDP-1
[270.280665] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.012s)
[270.289355] Starting dynamic subtest: pipe-D-eDP-1
[288.280544] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.079s)
[288.281485] Subtest suspend-read-crc: SUCCESS (12.345s)
[295.147788] [049/131] (732s left) gem_exec_reloc (basic-gtt-wc)
[295.208389] Subtest basic-gtt-wc: SKIP (0.000s)
[295.276273] [050/131] (732s left) gem_watchdog (default-physical)
[295.346769] Starting subtest: default-physical
[297.125052] Subtest default-physical: SUCCESS (1.774s)
[297.220895] [051/131] (730s left) gem_fence_thrash (bo-write-verify-threaded-x)
[297.249229] Starting subtest: bo-write-verify-threaded-x
[297.261653] Subtest bo-write-verify-threaded-x: SKIP (0.011s)
[297.321630] [052/131] (730s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[297.413556] Starting subtest: fbc-rgb565-draw-blt
[301.663989] Subtest fbc-rgb565-draw-blt: SUCCESS (4.243s)
[302.850450] [053/131] (724s left) kms_universal_plane (disable-primary-vs-flip)
[302.938186] Starting subtest: disable-primary-vs-flip
[302.939169] Starting dynamic subtest: pipe-A-eDP-1
[304.497776] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.558s)
[304.509994] Starting dynamic subtest: pipe-B-eDP-1
[306.679117] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.176s)
[306.687395] Starting dynamic subtest: pipe-C-eDP-1
[308.934781] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.254s)
[308.943678] Starting dynamic subtest: pipe-D-eDP-1
[311.087483] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.149s)
[311.088554] Subtest disable-primary-vs-flip: SUCCESS (8.143s)
[311.911430] [054/131] (715s left) kms_cursor_edge_walk (128x128-top-edge)
[312.000916] Starting subtest: 128x128-top-edge
[312.001974] Starting dynamic subtest: pipe-A-eDP-1
[315.454662] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.451s)
[315.462926] Starting dynamic subtest: pipe-D-eDP-1
[318.566942] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.110s)
[318.567782] Subtest 128x128-top-edge: SUCCESS (6.564s)
[319.381844] [055/131] (708s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[319.491353] Starting subtest: flip-vs-cursor-busy-crc-legacy
[320.274816] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.781s)
[321.132002] [056/131] (706s left) kms_flip (modeset-vs-vblank-race-interruptible)
[321.223296] Starting subtest: modeset-vs-vblank-race-interruptible
[321.225544] Starting dynamic subtest: A-eDP1
[325.932883] Dynamic subtest A-eDP1: SUCCESS (4.705s)
[325.942748] Starting dynamic subtest: B-eDP1
[330.604756] Dynamic subtest B-eDP1: SUCCESS (4.668s)
[330.616945] Starting dynamic subtest: C-eDP1
[335.132569] Dynamic subtest C-eDP1: SUCCESS (4.524s)
[335.141854] Starting dynamic subtest: D-eDP1
[339.686647] Dynamic subtest D-eDP1: SUCCESS (4.552s)
[339.689175] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.459s)
[340.873904] [057/131] (686s left) perf_pmu (busy-start)
[340.914231] Starting subtest: busy-start
[340.915385] Starting dynamic subtest: rcs0
[343.467233] Dynamic subtest rcs0: SUCCESS (2.551s)
[343.479759] Starting dynamic subtest: bcs0
[346.017913] Dynamic subtest bcs0: SUCCESS (2.547s)
[346.030796] Starting dynamic subtest: vcs0
[349.305262] Dynamic subtest vcs0: SUCCESS (3.282s)
[349.315986] Starting dynamic subtest: vcs1
[353.272303] Dynamic subtest vcs1: SUCCESS (3.963s)
[353.283147] Starting dynamic subtest: vecs0
[357.304154] Dynamic subtest vecs0: SUCCESS (4.028s)
[357.314990] Starting dynamic subtest: ccs0
[359.854118] Dynamic subtest ccs0: SUCCESS (2.549s)
[359.857922] Subtest busy-start: SUCCESS (18.936s)
[359.981951] [058/131] (667s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[360.084773] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[366.527380] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.441s)
[366.696151] [059/131] (660s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[366.795588] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[366.796525] Starting dynamic subtest: pipe-A-eDP-1
[383.924641] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.804s)
[383.933613] Starting dynamic subtest: pipe-B-eDP-1
[401.850356] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.769s)
[401.859735] Starting dynamic subtest: pipe-C-eDP-1
[419.824241] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.705s)
[419.835078] Starting dynamic subtest: pipe-D-eDP-1
[437.821737] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.634s)
[437.822901] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (9.917s)
[445.053440] [060/131] (588s left) kms_flip (2x-flip-vs-expired-vblank)
[445.153471] Starting subtest: 2x-flip-vs-expired-vblank
[445.165313] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s)
[445.271426] [061/131] (588s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[445.374400] Starting subtest: basic-flip-before-cursor-atomic
[446.306777] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.931s)
[447.186423] [062/131] (586s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[447.271454] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[447.273228] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[447.385132] [063/131] (586s left) gem_ctx_param (basic)
[447.439306] Starting subtest: basic
[447.440552] Subtest basic: SUCCESS (0.001s)
[447.558667] [064/131] (586s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[447.642267] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[447.643382] Starting dynamic subtest: pipe-A-default-mode
[447.950352] Dynamic subtest pipe-A-default-mode: SKIP (0.306s)
[447.952869] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.308s)
[448.811210] [065/131] (585s left) gem_create (create-ext-placement-each)
[448.982434] Starting subtest: create-ext-placement-each
[448.984044] Subtest create-ext-placement-each: SUCCESS (0.000s)
[449.083360] [066/131] (584s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[449.451934] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[450.230346] [067/131] (583s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[450.313839] Starting subtest: extended-pageflip-modeset-hang-oldfb
[450.315046] Starting dynamic subtest: pipe-A
[458.225936] Dynamic subtest pipe-A: SUCCESS (7.905s)
[458.234765] Starting dynamic subtest: pipe-D
[466.880731] Dynamic subtest pipe-D: SUCCESS (8.648s)
[466.882471] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.557s)
[467.718199] [068/131] (566s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[467.774318] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[467.775698] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[467.888923] [069/131] (566s left) api_intel_bb (add-remove-objects)
[467.938414] Starting subtest: add-remove-objects
[467.946792] Subtest add-remove-objects: SUCCESS (0.010s)
[468.089096] [070/131] (565s left) gem_exec_fence (keep-in-fence)
[468.247775] Starting subtest: keep-in-fence
[468.249521] Starting dynamic subtest: rcs0
[468.332055] Dynamic subtest rcs0: SUCCESS (0.084s)
[468.342442] Starting dynamic subtest: bcs0
[468.386155] Dynamic subtest bcs0: SUCCESS (0.053s)
[468.397985] Starting dynamic subtest: vcs0
[470.381421] Dynamic subtest vcs0: SUCCESS (1.989s)
[470.392381] Starting dynamic subtest: vcs1
[472.427063] Dynamic subtest vcs1: SUCCESS (2.044s)
[472.437889] Starting dynamic subtest: vecs0
[474.412059] Dynamic subtest vecs0: SUCCESS (1.982s)
[474.424181] Starting dynamic subtest: ccs0
[474.654960] Dynamic subtest ccs0: SUCCESS (0.241s)
[474.656679] Subtest keep-in-fence: SUCCESS (6.406s)
[476.503413] [071/131] (557s left) api_intel_bb (destroy-bb)
[476.555282] Starting subtest: destroy-bb
[476.571632] Subtest destroy-bb: SUCCESS (0.017s)
[476.714892] [072/131] (557s left) kms_psr (fbc-pr-cursor-render)
[476.798818] Starting subtest: fbc-pr-cursor-render
[476.801232] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[476.878740] [073/131] (557s left) gem_softpin (safe-alignment)
[476.930070] Starting subtest: safe-alignment
[476.956172] Subtest safe-alignment: SUCCESS (0.026s)
[478.438227] [074/131] (555s left) i915_hangman (gt-engine-error)
[478.491474] Starting subtest: gt-engine-error
[478.492537] Starting dynamic subtest: rcs0
[480.395268] Dynamic subtest rcs0: SUCCESS (1.901s)
[480.407319] Starting dynamic subtest: bcs0
[482.377530] Dynamic subtest bcs0: SUCCESS (1.981s)
[482.387017] Starting dynamic subtest: vcs0
[484.361621] Dynamic subtest vcs0: SUCCESS (1.981s)
[484.372557] Starting dynamic subtest: vcs1
[486.345474] Dynamic subtest vcs1: SUCCESS (1.981s)
[486.357156] Starting dynamic subtest: vecs0
[488.393833] Dynamic subtest vecs0: SUCCESS (2.046s)
[488.405476] Starting dynamic subtest: ccs0
[490.376632] Dynamic subtest ccs0: SUCCESS (1.981s)
[490.378295] Subtest gt-engine-error: SUCCESS (11.888s)
[490.476857] [075/131] (543s left) kms_cursor_crc (cursor-onscreen-512x512)
[490.732715] Starting subtest: cursor-onscreen-512x512
[490.735354] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[490.924938] [076/131] (543s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[491.019437] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[491.020646] Starting dynamic subtest: pipe-A
[491.082846] Dynamic subtest pipe-A: SUCCESS (0.065s)
[491.093277] Starting dynamic subtest: pipe-B
[492.242732] Dynamic subtest pipe-B: SUCCESS (1.159s)
[492.252063] Starting dynamic subtest: pipe-C
[493.354058] Dynamic subtest pipe-C: SUCCESS (1.111s)
[493.362903] Starting dynamic subtest: pipe-D
[494.488413] Dynamic subtest pipe-D: SUCCESS (1.133s)
[494.489600] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.472s)
[495.655515] [077/131] (538s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[495.765444] Starting subtest: flip-vs-cursor-busy-crc-atomic
[496.580058] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.815s)
[497.385376] [078/131] (536s left) kms_dp_link_training (uhbr-sst)
[497.466580] Starting subtest: uhbr-sst
[497.470156] Subtest uhbr-sst: SKIP (0.000s)
[497.550363] [079/131] (536s left) perf (blocking-parameterized)
[497.670326] Starting subtest: blocking-parameterized
[517.694027] Subtest blocking-parameterized: SUCCESS (20.018s)
[517.738790] [080/131] (516s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[517.830250] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[517.832450] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[517.948011] [081/131] (516s left) vgem_basic (sysfs)
[517.993366] Starting subtest: sysfs
[517.994350] Subtest sysfs: SUCCESS (0.000s)
[518.056741] [082/131] (516s left) gem_ctx_persistence (engines-hostile)
[518.115308] Starting subtest: engines-hostile
[518.116372] Starting dynamic subtest: rcs0
[518.262008] Dynamic subtest rcs0: SUCCESS (0.148s)
[518.275651] Starting dynamic subtest: bcs0
[518.383085] Dynamic subtest bcs0: SUCCESS (0.119s)
[518.394441] Starting dynamic subtest: vcs0
[518.494065] Dynamic subtest vcs0: SUCCESS (0.109s)
[518.506265] Starting dynamic subtest: vcs1
[518.604966] Dynamic subtest vcs1: SUCCESS (0.108s)
[518.617481] Starting dynamic subtest: vecs0
[518.726890] Dynamic subtest vecs0: SUCCESS (0.119s)
[518.741608] Starting dynamic subtest: ccs0
[518.848798] Dynamic subtest ccs0: SUCCESS (0.119s)
[518.850268] Subtest engines-hostile: SUCCESS (0.736s)
[518.936932] [083/131] (515s left) i915_suspend (fence-restore-untiled)
[518.975339] Starting subtest: fence-restore-untiled
[518.977593] Subtest fence-restore-untiled: SKIP (0.000s)
[519.083918] [084/131] (515s left) syncobj_wait (invalid-reset-illegal-handle)
[519.125442] Starting subtest: invalid-reset-illegal-handle
[519.126623] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[519.236950] [085/131] (515s left) gem_readwrite (write-bad-handle)
[519.278512] Subtest write-bad-handle: SKIP (0.000s)
[519.352926] [086/131] (515s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[519.409766] Subtest no-spare-fences-busy: SKIP (0.000s)
[519.483963] [087/131] (515s left) gem_ctx_persistence (heartbeat-close)
[519.536435] Starting subtest: heartbeat-close
[519.538653] Subtest heartbeat-close: SKIP (0.000s)
[519.662908] [088/131] (515s left) kms_cursor_crc (cursor-onscreen-256x256)
[519.936026] Starting subtest: cursor-onscreen-256x256
[519.942362] Starting dynamic subtest: pipe-A-eDP-1
[522.102037] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.160s)
[522.130795] Starting dynamic subtest: pipe-D-eDP-1
[524.879520] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.754s)
[524.880677] Subtest cursor-onscreen-256x256: SUCCESS (4.942s)
[525.763041] [089/131] (508s left) gem_ringfill (engines-basic)
[531.374653] Starting subtest: engines-basic
[531.376037] Starting dynamic subtest: rcs0
[531.446263] Dynamic subtest rcs0: SUCCESS (0.071s)
[531.457839] Starting dynamic subtest: bcs0
[531.493444] Dynamic subtest bcs0: SUCCESS (0.045s)
[531.503898] Starting dynamic subtest: vcs0
[531.538596] Dynamic subtest vcs0: SUCCESS (0.043s)
[531.550078] Starting dynamic subtest: vcs1
[531.584854] Dynamic subtest vcs1: SUCCESS (0.044s)
[531.594570] Starting dynamic subtest: vecs0
[531.630054] Dynamic subtest vecs0: SUCCESS (0.044s)
[531.639811] Starting dynamic subtest: ccs0
[531.676734] Dynamic subtest ccs0: SUCCESS (0.045s)
[531.678291] Subtest engines-basic: SUCCESS (0.303s)
[531.760091] [090/131] (502s left) kms_pm_rpm (cursor)
[533.583808] Starting subtest: cursor
[533.586910] Subtest cursor: SKIP (0.000s)
[534.005398] [091/131] (500s left) gem_pread (bench)
[534.061702] Subtest bench: SKIP (0.000s)
[534.129308] [092/131] (500s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[534.228865] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[537.805013] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.575s)
[538.994207] [093/131] (495s left) gem_exec_schedule (submit-golden-slice)
[539.057361] Starting subtest: submit-golden-slice
[539.058442] Starting dynamic subtest: rcs0
[539.098838] Dynamic subtest rcs0: SUCCESS (0.041s)
[539.108346] Starting dynamic subtest: bcs0
[539.129020] Dynamic subtest bcs0: SUCCESS (0.030s)
[539.137965] Starting dynamic subtest: vcs0
[539.163564] Dynamic subtest vcs0: SUCCESS (0.034s)
[539.172525] Starting dynamic subtest: vcs1
[539.196407] Dynamic subtest vcs1: SUCCESS (0.032s)
[539.205046] Starting dynamic subtest: vecs0
[539.226586] Dynamic subtest vecs0: SUCCESS (0.029s)
[539.235342] Starting dynamic subtest: ccs0
[539.258188] Dynamic subtest ccs0: SUCCESS (0.031s)
[539.259332] Subtest submit-golden-slice: SUCCESS (0.201s)
[540.523356] [094/131] (494s left) gem_exec_schedule (preempt-contexts)
[540.589378] Starting subtest: preempt-contexts
[540.590679] Starting dynamic subtest: rcs0
[540.651469] Dynamic subtest rcs0: SUCCESS (0.062s)
[540.659967] Starting dynamic subtest: bcs0
[540.711970] Dynamic subtest bcs0: SUCCESS (0.060s)
[540.720822] Starting dynamic subtest: vcs0
[540.756363] Dynamic subtest vcs0: SUCCESS (0.044s)
[540.765280] Starting dynamic subtest: vcs1
[540.806538] Dynamic subtest vcs1: SUCCESS (0.049s)
[540.815424] Starting dynamic subtest: vecs0
[540.847652] Dynamic subtest vecs0: SUCCESS (0.041s)
[540.863739] Starting dynamic subtest: ccs0
[540.899717] Dynamic subtest ccs0: SUCCESS (0.051s)
[540.900852] Subtest preempt-contexts: SUCCESS (0.311s)
[542.500835] [095/131] (492s left) kms_frontbuffer_tracking (psr-suspend)
[542.607395] Starting subtest: psr-suspend
[580.939953] Subtest psr-suspend: SUCCESS (8.322s)
[587.074541] [096/131] (453s left) gem_exec_capture (capture-invisible)
[587.126809] Starting subtest: capture-invisible
[587.127671] Starting dynamic subtest: smem0
[587.129359] Dynamic subtest smem0: SKIP (0.000s)
[587.130987] Subtest capture-invisible: SKIP (0.001s)
[587.226440] [097/131] (453s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[587.313841] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[587.314878] Starting dynamic subtest: pipe-A-eDP-1
[587.336344] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.023s)
[587.346456] Starting dynamic subtest: pipe-B-eDP-1
[587.359383] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.022s)
[587.369143] Starting dynamic subtest: pipe-C-eDP-1
[587.382101] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.022s)
[587.391733] Starting dynamic subtest: pipe-D-eDP-1
[587.404272] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.021s)
[587.405174] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.092s)
[589.650066] [098/131] (451s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0)
[589.736390] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0
[590.705731] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.968s)
[590.848195] [099/131] (450s left) kms_big_fb (linear-64bpp-rotate-90)
[590.947648] Starting subtest: linear-64bpp-rotate-90
[590.967957] Subtest linear-64bpp-rotate-90: SKIP (0.020s)
[591.133843] [100/131] (449s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[591.220767] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[597.721226] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.495s)
[597.862066] [101/131] (443s left) kms_content_protection (dp-mst-suspend-resume)
[597.997571] Starting subtest: dp-mst-suspend-resume
[597.999702] Subtest dp-mst-suspend-resume: SKIP (0.000s)
[598.139198] [102/131] (443s left) kms_atomic (plane-primary-legacy)
[598.234731] Starting subtest: plane-primary-legacy
[598.235692] Starting dynamic subtest: pipe-A-eDP-1
[598.679982] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.445s)
[598.680856] Subtest plane-primary-legacy: SUCCESS (0.446s)
[599.490305] [103/131] (441s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[599.536445] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[599.537542] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[599.649316] [104/131] (441s left) gem_userptr_blits (unsync-unmap-after-close)
[599.696174] Subtest unsync-unmap-after-close: SKIP (0.000s)
[599.843677] [105/131] (441s left) drm_read (empty-nonblock)
[599.987282] Starting subtest: empty-nonblock
[599.988369] Subtest empty-nonblock: SUCCESS (0.000s)
[600.152900] [106/131] (441s left) gem_eio (suspend)
[600.392795] Starting subtest: suspend
[612.331270] Subtest suspend: SUCCESS (11.932s)
[619.496875] [107/131] (428s left) kms_psr (pr-primary-render)
[619.591080] Starting subtest: pr-primary-render
[619.592809] Subtest pr-primary-render: SKIP (0.000s)
[619.672356] [108/131] (428s left) kms_vblank (ts-continuation-suspend)
[619.756228] Starting subtest: ts-continuation-suspend
[619.757148] Starting dynamic subtest: pipe-A-eDP-1
[637.026367] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.919s)
[637.044014] Starting dynamic subtest: pipe-D-eDP-1
[654.961072] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.506s)
[654.962015] Subtest ts-continuation-suspend: SUCCESS (4.430s)
[660.831158] [109/131] (392s left) kms_flip (2x-nonexisting-fb-interruptible)
[660.933150] Starting subtest: 2x-nonexisting-fb-interruptible
[660.944997] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.011s)
[661.074336] [110/131] (392s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[661.168585] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[667.865384] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.696s)
[668.047526] [111/131] (385s left) kms_invalid_mode (uint-max-clock)
[668.136172] Starting subtest: uint-max-clock
[668.137065] Starting dynamic subtest: pipe-A-eDP-1
[668.418333] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.282s)
[668.427239] Starting dynamic subtest: pipe-B-eDP-1
[668.428162] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[668.436247] Starting dynamic subtest: pipe-C-eDP-1
[668.437153] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[668.445425] Starting dynamic subtest: pipe-D-eDP-1
[668.446353] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[668.447287] Subtest uint-max-clock: SUCCESS (0.288s)
[669.281220] [112/131] (384s left) kms_properties (get_properties-sanity-non-atomic)
[669.367450] Starting subtest: get_properties-sanity-non-atomic
[669.368315] Subtest get_properties-sanity-non-atomic: SUCCESS (0.001s)
[669.461071] [113/131] (384s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[671.376534] Starting subtest: modeset-non-lpsp-stress-no-wait
[671.424847] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.051s)
[671.761122] [114/131] (381s left) kms_flip (flip-vs-absolute-wf_vblank)
[671.861466] Starting subtest: flip-vs-absolute-wf_vblank
[671.863898] Starting dynamic subtest: A-eDP1
[680.801831] Dynamic subtest A-eDP1: SUCCESS (8.935s)
[680.812844] Starting dynamic subtest: B-eDP1
[689.739238] Dynamic subtest B-eDP1: SUCCESS (8.930s)
[689.749636] Starting dynamic subtest: C-eDP1
[698.665296] Dynamic subtest C-eDP1: SUCCESS (8.919s)
[698.676021] Starting dynamic subtest: D-eDP1
[707.599058] Dynamic subtest D-eDP1: SUCCESS (8.928s)
[707.601656] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.720s)
[708.838464] [115/131] (344s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[708.896603] Starting subtest: basic-batch-kernel-default-cmd
[708.898397] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[709.096398] [116/131] (344s left) gem_exec_whisper (basic-fds-all)
[709.156394] Starting subtest: basic-fds-all
[720.231925] Subtest basic-fds-all: SUCCESS (11.073s)
[720.422889] [117/131] (333s left) kms_properties (invalid-properties-atomic)
[720.515913] Starting subtest: invalid-properties-atomic
[720.824793] Subtest invalid-properties-atomic: SUCCESS (0.307s)
[720.917031] [118/131] (332s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[721.001456] Starting subtest: plane-scaler-unity-scaling-with-rotation
[721.002423] Starting dynamic subtest: pipe-A
[721.229741] Dynamic subtest pipe-A: SUCCESS (0.229s)
[721.239644] Starting dynamic subtest: pipe-B
[722.505982] Dynamic subtest pipe-B: SUCCESS (1.276s)
[722.514764] Starting dynamic subtest: pipe-C
[723.833481] Dynamic subtest pipe-C: SUCCESS (1.327s)
[723.842663] Starting dynamic subtest: pipe-D
[725.156356] Dynamic subtest pipe-D: SUCCESS (1.322s)
[725.157329] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.157s)
[726.358917] [119/131] (327s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[726.401400] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[726.509244] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.105s)
[726.601885] [120/131] (327s left) syncobj_basic (bad-pad-handle-to-fd)
[726.654138] Starting subtest: bad-pad-handle-to-fd
[726.654964] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[726.754198] [121/131] (327s left) kms_chamelium_hpd (dp-hpd)
[727.129225] Subtest dp-hpd: SKIP (0.000s)
[727.947949] [122/131] (325s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[728.045674] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[728.419253] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[729.383773] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: FAIL (0.746s)
[729.393309] Starting dynamic subtest: pipe-A-eDP-1-4
[729.551358] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.198s)
[729.559936] Starting dynamic subtest: pipe-A-eDP-1-4
[729.634200] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.199s)
[729.643851] Starting dynamic subtest: pipe-A-eDP-1-x
[729.906876] Dynamic subtest pipe-A-eDP-1-x: FAIL (0.188s)
[730.125651] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[731.129513] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: FAIL (0.931s)
[731.139130] Starting dynamic subtest: pipe-B-eDP-1-4
[731.376978] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.174s)
[731.386457] Starting dynamic subtest: pipe-B-eDP-1-4
[731.455040] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.008s)
[731.463540] Starting dynamic subtest: pipe-B-eDP-1-x
[731.610537] Dynamic subtest pipe-B-eDP-1-x: FAIL (0.168s)
[731.774655] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[734.514499] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.745s)
[734.524155] Starting dynamic subtest: pipe-C-eDP-1-4
[736.678548] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.163s)
[736.690102] Starting dynamic subtest: pipe-C-eDP-1-4
[738.689627] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[738.698216] Starting dynamic subtest: pipe-C-eDP-1-x
[740.868618] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[741.238794] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[744.050309] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.817s)
[744.062130] Starting dynamic subtest: pipe-D-eDP-1-4
[746.224406] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[746.235830] Starting dynamic subtest: pipe-D-eDP-1-4
[748.234834] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[748.246604] Starting dynamic subtest: pipe-D-eDP-1-x
[750.427307] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.190s)
[750.428695] Subtest async-flip-with-page-flip-events-tiled-atomic: FAIL (22.376s)
[751.651483] [123/131] (302s left) i915_drm_fdinfo (most-busy-check-all)
[751.684616] Starting subtest: most-busy-check-all
[751.685591] Starting dynamic subtest: rcs0
[751.688472] Dynamic subtest rcs0: SKIP (0.004s)
[751.698034] Starting dynamic subtest: bcs0
[751.699744] Dynamic subtest bcs0: SKIP (0.000s)
[751.708289] Starting dynamic subtest: vcs0
[751.710046] Dynamic subtest vcs0: SKIP (0.000s)
[751.718251] Starting dynamic subtest: vcs1
[751.719994] Dynamic subtest vcs1: SKIP (0.000s)
[751.728131] Starting dynamic subtest: vecs0
[751.729820] Dynamic subtest vecs0: SKIP (0.000s)
[751.738085] Starting dynamic subtest: ccs0
[751.739816] Dynamic subtest ccs0: SKIP (0.001s)
[751.741684] Subtest most-busy-check-all: SKIP (0.010s)
[751.769565] [124/131] (302s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[751.891171] Starting subtest: fb-8bpc-vs-panel-6bpc
[751.892170] Starting dynamic subtest: pipe-A-eDP-1
[753.277445] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.386s)
[753.278393] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.388s)
[754.097681] [125/131] (299s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[754.216389] Starting subtest: cursorB-vs-flipA-atomic-transitions
[754.219213] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.002s)
[754.391198] [126/131] (299s left) gem_set_tiling_vs_pwrite
[754.518889] [127/131] (299s left) gem_mmap (bad-object)
[754.572652] Subtest bad-object: SKIP (0.000s)
[754.628641] [128/131] (299s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[754.720775] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[754.722463] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[754.824424] [129/131] (299s left) syncobj_wait (multi-wait-for-submit-submitted)
[754.880520] Starting subtest: multi-wait-for-submit-submitted
[754.988843] Subtest multi-wait-for-submit-submitted: SUCCESS (0.106s)
[755.073146] [130/131] (299s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[755.166029] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[755.167870] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[755.275400] [131/131] (298s left) gem_mmap_offset (isolation)
[755.335954] Starting subtest: isolation
[755.343465] Subtest isolation: SUCCESS (0.005s)
[755.454506] Closing watchdogs
results: parsing output: 0/ for test: gen9_exec_parse
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: kms_properties
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_psr
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: gem_eio
results: parsing output: 11/ for test: kms_psr2_sf
results: parsing output: 12/ for test: gem_exec_alignment
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: gem_fence_thrash
results: parsing output: 16/ for test: fbdev
results: parsing output: 17/ for test: kms_lease
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: kms_psr2_sf
results: parsing output: 20/ for test: gem_exec_balancer
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_exec_params
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_mmap_gtt
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: kms_psr_stress_test
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_getfb
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: gem_pxp
results: parsing output: 32/ for test: kms_chamelium_audio
results: parsing output: 33/ for test: sysfs_timeslice_duration
results: parsing output: 34/ for test: gem_exec_params
results: parsing output: 35/ for test: gem_exercise_blt
results: parsing output: 36/ for test: kms_plane_multiple
results: parsing output: 37/ for test: dmabuf_sync_file
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: i915_pm_rpm
results: parsing output: 41/ for test: prime_vgem
results: parsing output: 42/ for test: syncobj_timeline
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: gem_exec_flush
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_pipe_crc_basic
results: parsing output: 48/ for test: gem_exec_reloc
results: parsing output: 49/ for test: gem_watchdog
results: parsing output: 50/ for test: gem_fence_thrash
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_universal_plane
results: parsing output: 53/ for test: kms_cursor_edge_walk
results: parsing output: 54/ for test: kms_cursor_legacy
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: perf_pmu
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_ccs
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: kms_cursor_legacy
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_ctx_param
results: parsing output: 63/ for test: kms_flip_scaled_crc
results: parsing output: 64/ for test: gem_create
results: parsing output: 65/ for test: kms_chamelium_hpd
results: parsing output: 66/ for test: kms_busy
results: parsing output: 67/ for test: syncobj_timeline
results: parsing output: 68/ for test: api_intel_bb
results: parsing output: 69/ for test: gem_exec_fence
results: parsing output: 70/ for test: api_intel_bb
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: gem_softpin
results: parsing output: 73/ for test: i915_hangman
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: kms_plane_scaling
results: parsing output: 76/ for test: kms_cursor_legacy
results: parsing output: 77/ for test: kms_dp_link_training
results: parsing output: 78/ for test: perf
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: vgem_basic
results: parsing output: 81/ for test: gem_ctx_persistence
results: parsing output: 82/ for test: i915_suspend
results: parsing output: 83/ for test: syncobj_wait
results: parsing output: 84/ for test: gem_readwrite
results: parsing output: 85/ for test: gem_fenced_exec_thrash
results: parsing output: 86/ for test: gem_ctx_persistence
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: gem_ringfill
results: parsing output: 89/ for test: kms_pm_rpm
results: parsing output: 90/ for test: gem_pread
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_exec_schedule
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_capture
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_content_protection
results: parsing output: 101/ for test: kms_atomic
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: gem_userptr_blits
results: parsing output: 104/ for test: drm_read
results: parsing output: 105/ for test: gem_eio
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: kms_vblank
results: parsing output: 108/ for test: kms_flip
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_invalid_mode
results: parsing output: 111/ for test: kms_properties
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: kms_flip
results: parsing output: 114/ for test: gem_exec_flush
results: parsing output: 115/ for test: gem_exec_whisper
results: parsing output: 116/ for test: kms_properties
results: parsing output: 117/ for test: kms_plane_scaling
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: syncobj_basic
results: parsing output: 120/ for test: kms_chamelium_hpd
results: parsing output: 121/ for test: kms_async_flips
results: parsing output: 122/ for test: i915_drm_fdinfo
results: parsing output: 123/ for test: kms_dither
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 126/ for test: gem_mmap
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_mmap_offset
Done.