[59.795409] Initializing watchdogs
[59.795460]   /dev/watchdog0
[59.810291] [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.814911] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.817552] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.820938] [001/155] (960s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt)
[59.957346] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt
[59.961545] Subtest fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[60.065192] [002/155] (960s left) perf_pmu (rc6-suspend)
[60.113627] Starting subtest: rc6-suspend
[82.368157] Subtest rc6-suspend: SUCCESS (8.448s)
[83.259377] [003/155] (937s left) kms_psr (psr-cursor-plane-onoff)
[83.368786] Starting subtest: psr-cursor-plane-onoff
[83.370951] Starting dynamic subtest: eDP-1
[85.365023] Dynamic subtest eDP-1: SUCCESS (2.006s)
[85.367249] Subtest psr-cursor-plane-onoff: SUCCESS (2.007s)
[86.212707] [004/155] (934s left) gem_userptr_blits (coherency-unsync)
[86.344187] Subtest coherency-unsync: SKIP (0.000s)
[86.869109] [005/155] (933s left) gen7_exec_parse (basic-allocation)
[86.957175] Subtest basic-allocation: SKIP (0.000s)
[87.172726] [006/155] (933s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-pwrite)
[87.367648] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-pwrite
[87.372351] Subtest hdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[87.574000] [007/155] (933s left) i915_hangman (engine-error-state-capture)
[87.648744] Starting subtest: engine-error-state-capture
[87.651087] Starting dynamic subtest: rcs0
[93.127745] Dynamic subtest rcs0: SUCCESS (5.495s)
[93.138247] Starting dynamic subtest: bcs0
[99.145774] Dynamic subtest bcs0: SUCCESS (6.027s)
[99.157156] Starting dynamic subtest: vcs0
[105.098442] Dynamic subtest vcs0: SUCCESS (5.958s)
[105.110504] Starting dynamic subtest: vcs1
[111.047673] Dynamic subtest vcs1: SUCCESS (5.951s)
[111.062010] Starting dynamic subtest: vecs0
[116.105649] Dynamic subtest vecs0: SUCCESS (5.055s)
[116.118047] Starting dynamic subtest: ccs0
[122.121647] Dynamic subtest ccs0: SUCCESS (6.014s)
[122.124655] Subtest engine-error-state-capture: SUCCESS (34.505s)
[122.201810] [008/155] (898s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[122.308597] Starting subtest: yf-tiled-8bpp-rotate-90
[122.312928] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[122.405315] [009/155] (898s left) gem_exec_flush (basic-wb-ro-default)
[122.471543] Starting subtest: basic-wb-ro-default
[127.956933] Subtest basic-wb-ro-default: SUCCESS (5.456s)
[128.114551] [010/155] (892s left) kms_color (ctm-signed)
[128.210395] Starting subtest: ctm-signed
[128.212921] Starting dynamic subtest: pipe-A-eDP-1
[130.347619] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.137s)
[130.357315] Starting dynamic subtest: pipe-B-eDP-1
[133.199167] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.853s)
[133.209434] Starting dynamic subtest: pipe-C-eDP-1
[135.942454] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.742s)
[135.952102] Starting dynamic subtest: pipe-D-eDP-1
[138.623016] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.683s)
[138.625625] Subtest ctm-signed: SUCCESS (10.416s)
[139.446562] [011/155] (881s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[139.545949] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[139.547895] Starting dynamic subtest: pipe-A-eDP-1
[140.294348] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.742s)
[140.304884] Starting dynamic subtest: pipe-B-eDP-1
[142.087988] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.791s)
[142.097237] Starting dynamic subtest: pipe-C-eDP-1
[143.918936] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.832s)
[143.927510] Starting dynamic subtest: pipe-D-eDP-1
[145.759966] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.840s)
[145.762059] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.209s)
[146.965528] [012/155] (873s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[147.058119] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc
[147.060510] Starting dynamic subtest: pipe-A-eDP-1
[147.118666] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[147.129057] Starting dynamic subtest: pipe-B-eDP-1
[147.187474] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[147.197601] Starting dynamic subtest: pipe-C-eDP-1
[147.256267] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[147.266277] Starting dynamic subtest: pipe-D-eDP-1
[147.322638] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[147.326722] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.007s)
[149.352156] [013/155] (871s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[149.450866] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[149.453089] Starting dynamic subtest: pipe-A-eDP-1
[149.473712] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.024s)
[149.483775] Starting dynamic subtest: pipe-B-eDP-1
[149.503386] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.021s)
[149.512740] Starting dynamic subtest: pipe-C-eDP-1
[149.532185] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.022s)
[149.543801] Starting dynamic subtest: pipe-D-eDP-1
[149.563999] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s)
[149.566274] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.093s)
[151.814998] [014/155] (869s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[151.874164] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[151.878825] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[152.077098] [015/155] (868s left) syncobj_wait (invalid-wait-illegal-handle)
[152.137397] Starting subtest: invalid-wait-illegal-handle
[152.139388] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[152.252028] [016/155] (868s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[152.297447] Starting subtest: multi-wait-all-for-submit-submitted
[152.404520] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.107s)
[152.474375] [017/155] (868s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[152.583798] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu
[152.587968] Subtest fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[152.688183] [018/155] (868s left) gem_exec_balancer (sequential)
[152.745476] Starting subtest: sequential
[155.859745] Subtest sequential: SUCCESS (3.116s)
[156.208597] [019/155] (864s left) gem_pread (exhaustion)
[156.264155] Subtest exhaustion: SKIP (0.000s)
[156.333078] [020/155] (864s left) gem_workarounds (reset)
[156.396142] Starting subtest: reset
[156.608959] Subtest reset: SUCCESS (0.212s)
[156.728908] [021/155] (864s left) kms_psr (fbc-psr2-basic)
[156.844617] Starting subtest: fbc-psr2-basic
[156.846904] Starting dynamic subtest: eDP-1
[156.852044] Dynamic subtest eDP-1: SKIP (0.000s)
[156.857291] Subtest fbc-psr2-basic: SKIP (0.001s)
[156.913689] [022/155] (864s left) api_intel_allocator (alloc-simple)
[156.963918] Starting subtest: alloc-simple
[156.965831] Subtest alloc-simple: SUCCESS (0.004s)
[157.074960] [023/155] (864s left) kms_cursor_crc (cursor-random-64x21)
[157.276808] Starting subtest: cursor-random-64x21
[157.325655] Subtest cursor-random-64x21: SKIP (0.044s)
[157.588150] [024/155] (863s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-onoff)
[157.687987] Starting subtest: hdr-2p-scndscrn-spr-indfb-onoff
[157.691506] Subtest hdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[157.781633] [025/155] (863s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-pwrite)
[157.883806] Starting subtest: psr-1p-offscreen-pri-indfb-draw-pwrite
[165.361025] Subtest psr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.483s)
[165.473862] [026/155] (855s left) kms_cursor_edge_walk (128x128-top-edge)
[165.574436] Starting subtest: 128x128-top-edge
[165.576822] Starting dynamic subtest: pipe-A-eDP-1
[169.007537] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.435s)
[169.018275] Starting dynamic subtest: pipe-D-eDP-1
[172.157492] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.149s)
[172.159806] Subtest 128x128-top-edge: SUCCESS (6.586s)
[172.965546] [027/155] (848s left) kms_psr (pr-sprite-mmap-cpu)
[173.060331] Starting subtest: pr-sprite-mmap-cpu
[173.064874] Subtest pr-sprite-mmap-cpu: SKIP (0.000s)
[173.132399] [028/155] (848s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[173.229296] Starting subtest: x-tiled-32bpp-rotate-270
[173.248940] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[173.373119] [029/155] (848s left) prime_vgem (sync)
[173.475728] Starting subtest: sync
[173.478187] Starting dynamic subtest: rcs0
[173.517639] Dynamic subtest rcs0: SUCCESS (0.042s)
[173.527307] Starting dynamic subtest: bcs0
[173.580861] Dynamic subtest bcs0: SUCCESS (0.063s)
[173.590303] Starting dynamic subtest: vcs0
[173.628549] Dynamic subtest vcs0: SUCCESS (0.047s)
[173.638137] Starting dynamic subtest: vcs1
[173.673033] Dynamic subtest vcs1: SUCCESS (0.044s)
[173.682673] Starting dynamic subtest: vecs0
[173.721488] Dynamic subtest vecs0: SUCCESS (0.047s)
[173.730936] Starting dynamic subtest: ccs0
[173.771797] Dynamic subtest ccs0: SUCCESS (0.049s)
[173.774180] Subtest sync: SUCCESS (0.296s)
[173.885807] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[173.888593] [030/155] (847s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[173.987258] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[173.989269] Starting dynamic subtest: pipe-A-eDP-1
[174.083662] Dynamic subtest pipe-A-eDP-1: SKIP (0.101s)
[174.095803] Starting dynamic subtest: pipe-B-eDP-1
[175.237833] Dynamic subtest pipe-B-eDP-1: SKIP (1.151s)
[175.242412] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.254s)
[176.522729] [031/155] (845s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[176.618980] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[176.623342] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.000s)
[176.692452] [032/155] (844s left) kms_cursor_legacy (forked-move)
[176.802523] Starting subtest: forked-move
[176.804456] Starting dynamic subtest: pipe-A
[182.334242] Dynamic subtest pipe-A: SUCCESS (5.474s)
[182.348048] Starting dynamic subtest: pipe-B
[187.820294] Dynamic subtest pipe-B: SUCCESS (5.482s)
[187.833037] Starting dynamic subtest: pipe-C
[193.299614] Dynamic subtest pipe-C: SUCCESS (5.477s)
[193.311317] Starting dynamic subtest: pipe-D
[198.772016] Dynamic subtest pipe-D: SUCCESS (5.473s)
[198.791690] Starting dynamic subtest: all-pipes
[204.304341] Dynamic subtest all-pipes: SUCCESS (5.529s)
[204.306401] Subtest forked-move: SUCCESS (27.441s)
[204.402835] [033/155] (817s left) gem_exec_schedule (pi-common)
[204.584690] Starting subtest: pi-common
[204.586810] Starting dynamic subtest: rcs0
[204.611651] Dynamic subtest rcs0: SUCCESS (0.030s)
[204.627520] Starting dynamic subtest: bcs0
[204.639078] Dynamic subtest bcs0: SUCCESS (0.027s)
[204.653363] Starting dynamic subtest: vcs0
[204.665804] Dynamic subtest vcs0: SUCCESS (0.026s)
[204.680328] Starting dynamic subtest: vcs1
[204.691646] Dynamic subtest vcs1: SUCCESS (0.025s)
[204.706295] Starting dynamic subtest: vecs0
[204.718277] Dynamic subtest vecs0: SUCCESS (0.026s)
[204.732402] Starting dynamic subtest: ccs0
[204.744362] Dynamic subtest ccs0: SUCCESS (0.026s)
[204.746646] Subtest pi-common: SUCCESS (0.163s)
[204.980055] [034/155] (816s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[205.039035] Starting subtest: multi-wait-all-for-submit-unsubmitted
[205.140138] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[205.221477] [035/155] (816s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[205.318358] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[205.322828] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[205.420442] [036/155] (816s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[205.482297] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[205.554237] [037/155] (816s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[205.647523] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[205.652031] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[206.755322] [038/155] (815s left) gem_ccs (ctrl-surf-copy-new-ctx)
[206.824978] Starting subtest: ctrl-surf-copy-new-ctx
[206.829869] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[206.942828] [039/155] (814s left) gem_userptr_blits (unsync-unmap)
[207.003506] Subtest unsync-unmap: SKIP (0.000s)
[207.142658] [040/155] (814s left) kms_psr (fbc-psr-primary-page-flip)
[207.234163] Starting subtest: fbc-psr-primary-page-flip
[207.236290] Starting dynamic subtest: eDP-1
[207.240292] Dynamic subtest eDP-1: SKIP (0.000s)
[207.244272] Subtest fbc-psr-primary-page-flip: SKIP (0.001s)
[207.307424] [041/155] (814s left) kms_psr (fbc-pr-cursor-blt)
[207.394245] Starting subtest: fbc-pr-cursor-blt
[207.398285] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[207.465810] [042/155] (814s left) gem_close (basic)
[207.540925] Starting subtest: basic
[207.543140] Subtest basic: SUCCESS (0.000s)
[207.640650] [043/155] (814s left) syncobj_wait (invalid-signal-illegal-handle)
[207.687628] Starting subtest: invalid-signal-illegal-handle
[207.689290] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[207.788513] [044/155] (814s left) kms_content_protection (legacy)
[207.929759] Starting subtest: legacy
[207.933500] Subtest legacy: SKIP (0.000s)
[208.044966] [045/155] (814s left) kms_pm_rpm (modeset-lpsp-stress)
[210.279928] Starting subtest: modeset-lpsp-stress
[226.242629] Subtest modeset-lpsp-stress: SUCCESS (15.964s)
[226.329202] [046/155] (795s left) vgem_basic (bad-fence)
[226.381615] Starting subtest: bad-fence
[226.383878] Subtest bad-fence: SUCCESS (0.000s)
[226.455963] [047/155] (795s left) gem_flink_basic (flink-lifetime)
[226.512071] Starting subtest: flink-lifetime
[226.514014] Subtest flink-lifetime: SUCCESS (0.003s)
[226.629489] [048/155] (795s left) gem_render_copy_redux (interruptible)
[226.692174] Starting subtest: interruptible
[226.994823] Subtest interruptible: SUCCESS (0.303s)
[227.098806] [049/155] (795s left) kms_addfb_basic (small-bo)
[227.185911] Starting subtest: small-bo
[227.188018] Subtest small-bo: SUCCESS (0.000s)
[227.284067] [050/155] (795s left) kms_big_fb (linear-16bpp-rotate-90)
[227.381775] Starting subtest: linear-16bpp-rotate-90
[227.404467] Subtest linear-16bpp-rotate-90: SKIP (0.022s)
[227.511331] [051/155] (794s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[227.604329] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[227.606434] Starting dynamic subtest: pipe-A-default-mode
[228.761426] Dynamic subtest pipe-A-default-mode: SKIP (1.153s)
[228.765807] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.155s)
[228.909131] [052/155] (793s left) gem_exec_reloc (basic-write-wc-noreloc)
[228.968812] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[229.041341] [053/155] (793s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-pwrite)
[229.153241] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-pwrite
[229.157425] Subtest hdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[229.244285] [054/155] (793s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[229.346522] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[229.350744] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[229.437171] [055/155] (793s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[229.485161] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[229.487553] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.001s)
[229.618276] [056/155] (792s left) kms_flip (plain-flip-ts-check)
[229.723283] Starting subtest: plain-flip-ts-check
[229.725019] Starting dynamic subtest: A-eDP1
[238.660101] Dynamic subtest A-eDP1: SUCCESS (8.905s)
[238.670316] Starting dynamic subtest: B-eDP1
[247.495393] Dynamic subtest B-eDP1: SUCCESS (8.835s)
[247.507546] Starting dynamic subtest: C-eDP1
[256.420669] Dynamic subtest C-eDP1: SUCCESS (8.913s)
[256.433157] Starting dynamic subtest: D-eDP1
[265.248355] Dynamic subtest D-eDP1: SUCCESS (8.828s)
[265.250495] Subtest plain-flip-ts-check: SUCCESS (35.493s)
[266.388615] [057/155] (756s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[266.441895] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[266.444295] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[266.539382] [058/155] (756s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[266.640777] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[266.645268] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[266.730316] [059/155] (755s left) kms_lease (lease-invalid-crtc)
[266.817139] Starting subtest: lease-invalid-crtc
[266.819496] Starting dynamic subtest: pipe-A-eDP-1
[266.821826] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[266.832018] Starting dynamic subtest: pipe-B-eDP-1
[266.834280] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[266.844979] Starting dynamic subtest: pipe-C-eDP-1
[266.846952] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[266.856834] Starting dynamic subtest: pipe-D-eDP-1
[266.858720] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[266.860605] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[266.901541] [060/155] (755s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-blt)
[267.004445] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-blt
[267.008895] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[267.121369] [061/155] (755s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[267.223982] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[267.228145] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[267.329174] [062/155] (755s left) gem_exercise_blt (fast-copy-emit)
[267.395543] Starting subtest: fast-copy-emit
[267.397567] Starting dynamic subtest: linear-smem-smem-emit
[267.418830] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.023s)
[267.432806] Starting dynamic subtest: xmajor-smem-smem-emit
[267.436846] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.017s)
[267.445952] Starting dynamic subtest: tile4-smem-smem-emit
[267.453674] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.016s)
[267.464373] Starting dynamic subtest: tile64-smem-smem-emit
[267.470583] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.017s)
[267.472661] Subtest fast-copy-emit: SUCCESS (0.076s)
[267.575674] [063/155] (755s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-plflip-blt)
[267.678963] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-plflip-blt
[267.682274] Subtest fbcpsrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[267.780379] [064/155] (755s left) kms_plane_multiple (tiling-none)
[267.885739] Starting subtest: tiling-none
[267.887363] Starting dynamic subtest: pipe-A-eDP-1
[271.729760] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.844s)
[271.740744] Starting dynamic subtest: pipe-B-eDP-1
[276.270486] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.542s)
[276.282669] Starting dynamic subtest: pipe-C-eDP-1
[280.904278] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.632s)
[280.914335] Starting dynamic subtest: pipe-D-eDP-1
[285.345723] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.441s)
[285.347886] Subtest tiling-none: SUCCESS (17.465s)
[286.170066] [065/155] (736s left) gem_create (busy-create)
[286.234829] Starting subtest: busy-create
[286.237014] Starting dynamic subtest: smem0
[320.111627] Dynamic subtest smem0: SUCCESS (33.875s)
[320.114195] Subtest busy-create: SUCCESS (33.877s)
[320.297690] [066/155] (702s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[320.402092] Starting subtest: plane-downscale-factor-0-5-with-rotation
[320.404893] Starting dynamic subtest: pipe-A
[320.422550] Dynamic subtest pipe-A: SKIP (0.017s)
[320.433649] Starting dynamic subtest: pipe-B
[320.455331] Dynamic subtest pipe-B: SKIP (0.032s)
[320.465502] Starting dynamic subtest: pipe-C
[320.489590] Dynamic subtest pipe-C: SKIP (0.032s)
[320.499805] Starting dynamic subtest: pipe-D
[320.520725] Dynamic subtest pipe-D: SKIP (0.032s)
[320.525224] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.116s)
[320.613015] [067/155] (702s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-render)
[320.714660] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-render
[320.719339] Subtest psrhdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[320.814076] [068/155] (702s left) i915_drm_fdinfo (all-busy-idle-check-all)
[320.857082] Starting subtest: all-busy-idle-check-all
[320.865293] Subtest all-busy-idle-check-all: SKIP (0.004s)
[320.923741] [069/155] (702s left) kms_cursor_crc (cursor-alpha-transparent)
[321.055282] Starting subtest: cursor-alpha-transparent
[321.057133] Starting dynamic subtest: pipe-A-eDP-1
[321.689762] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.634s)
[321.702086] Starting dynamic subtest: pipe-D-eDP-1
[322.950716] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.258s)
[322.952886] Subtest cursor-alpha-transparent: SUCCESS (1.895s)
[323.842630] [070/155] (699s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[323.953708] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[323.957898] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[324.062974] [071/155] (699s left) kms_frontbuffer_tracking (fbchdr-shrfb-scaledprimary)
[324.161264] Starting subtest: fbchdr-shrfb-scaledprimary
[324.165550] Subtest fbchdr-shrfb-scaledprimary: SKIP (0.000s)
[324.246073] [072/155] (699s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[324.338704] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[324.343277] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[324.437743] [073/155] (698s left) gem_ctx_persistence (legacy-engines-queued)
[324.500534] Starting subtest: legacy-engines-queued
[324.502958] Starting dynamic subtest: render
[324.572162] Dynamic subtest render: SUCCESS (0.071s)
[324.586596] Starting dynamic subtest: bsd1
[326.152581] Dynamic subtest bsd1: SUCCESS (1.574s)
[326.166239] Starting dynamic subtest: bsd2
[328.134238] Dynamic subtest bsd2: SUCCESS (1.977s)
[328.149417] Starting dynamic subtest: blt
[328.186828] Dynamic subtest blt: SUCCESS (0.051s)
[328.197971] Starting dynamic subtest: vebox
[330.120656] Dynamic subtest vebox: SUCCESS (1.930s)
[330.123886] Subtest legacy-engines-queued: SUCCESS (5.619s)
[330.203705] [074/155] (693s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[330.337595] Starting subtest: cursorA-vs-flipB-legacy
[330.342484] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[330.479300] [075/155] (692s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[330.592293] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[330.596552] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[330.709218] [076/155] (692s left) kms_chamelium_frames (dp-crc-fast)
[331.109308] Subtest dp-crc-fast: SKIP (0.000s)
[331.943834] [077/155] (691s left) i915_pm_rps (thresholds-park)
[332.012216] Starting subtest: thresholds-park
[332.017434] Subtest thresholds-park: SKIP (0.001s)
[332.129380] [078/155] (691s left) drm_read (short-buffer-block)
[332.267179] Starting subtest: short-buffer-block
[332.269677] Subtest short-buffer-block: SUCCESS (0.000s)
[332.383516] [079/155] (691s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[332.484980] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[332.489514] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[332.575630] [080/155] (691s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[332.631434] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[332.698503] [081/155] (690s left) kms_frontbuffer_tracking (fbcpsrhdr-indfb-scaledprimary)
[332.791781] Starting subtest: fbcpsrhdr-indfb-scaledprimary
[332.795725] Subtest fbcpsrhdr-indfb-scaledprimary: SKIP (0.000s)
[332.892663] [082/155] (690s left) gem_partial_pwrite_pread (reads)
[332.951909] Subtest reads: SKIP (0.000s)
[333.018242] [083/155] (690s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[333.112938] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[333.114831] Starting dynamic subtest: pipe-A-eDP-1
[333.134286] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[333.144487] Starting dynamic subtest: pipe-B-eDP-1
[333.161913] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[333.171245] Starting dynamic subtest: pipe-C-eDP-1
[333.189074] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[333.199657] Starting dynamic subtest: pipe-D-eDP-1
[333.217717] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[333.221359] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[335.420271] [084/155] (688s left) perf_pmu (busy-idle-check-all)
[335.464614] Starting subtest: busy-idle-check-all
[335.466887] Starting dynamic subtest: rcs0
[336.117922] Dynamic subtest rcs0: SUCCESS (0.653s)
[336.131971] Starting dynamic subtest: bcs0
[336.772927] Dynamic subtest bcs0: SUCCESS (0.652s)
[336.785523] Starting dynamic subtest: vcs0
[338.152605] Dynamic subtest vcs0: SUCCESS (1.376s)
[338.165075] Starting dynamic subtest: vcs1
[340.138165] Dynamic subtest vcs1: SUCCESS (1.983s)
[340.154935] Starting dynamic subtest: vecs0
[342.185452] Dynamic subtest vecs0: SUCCESS (2.044s)
[342.199127] Starting dynamic subtest: ccs0
[342.842757] Dynamic subtest ccs0: SUCCESS (0.656s)
[342.845314] Subtest busy-idle-check-all: SUCCESS (7.378s)
[342.938537] [085/155] (680s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[343.047144] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[343.051435] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[343.151946] [086/155] (680s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[343.266609] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[344.345958] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.080s)
[344.469686] [087/155] (679s left) gem_mmap_offset (oob-read)
[344.518694] Starting subtest: oob-read
[344.520712] Subtest oob-read: SUCCESS (0.000s)
[344.640763] [088/155] (679s left) kms_plane_cursor (primary)
[344.731668] Starting subtest: primary
[344.735192] Starting dynamic subtest: pipe-A-eDP-1-size-64
[347.394052] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.663s)
[347.419742] Starting dynamic subtest: pipe-A-eDP-1-size-128
[350.040099] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.628s)
[350.066291] Starting dynamic subtest: pipe-A-eDP-1-size-256
[352.652249] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.594s)
[352.969145] Starting dynamic subtest: pipe-B-eDP-1-size-64
[356.264395] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.304s)
[356.288424] Starting dynamic subtest: pipe-B-eDP-1-size-128
[358.874378] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.594s)
[358.899388] Starting dynamic subtest: pipe-B-eDP-1-size-256
[361.519017] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.627s)
[361.834695] Starting dynamic subtest: pipe-C-eDP-1-size-64
[364.788932] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.967s)
[364.817442] Starting dynamic subtest: pipe-C-eDP-1-size-128
[367.078174] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.269s)
[367.105291] Starting dynamic subtest: pipe-C-eDP-1-size-256
[369.367188] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.270s)
[369.691267] Starting dynamic subtest: pipe-D-eDP-1-size-64
[372.725315] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.049s)
[372.753522] Starting dynamic subtest: pipe-D-eDP-1-size-128
[375.002699] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.259s)
[375.030194] Starting dynamic subtest: pipe-D-eDP-1-size-256
[377.224545] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.203s)
[377.539247] Subtest primary: SUCCESS (32.806s)
[378.348940] [089/155] (645s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[378.458965] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[382.167818] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.714s)
[383.396759] [090/155] (640s left) gem_render_copy (yf-tiled)
[383.454743] Starting subtest: yf-tiled
[383.459150] Subtest yf-tiled: SKIP (0.000s)
[383.651037] [091/155] (640s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[383.741272] Starting subtest: 4-tiled-16bpp-rotate-270
[383.760744] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.019s)
[383.877049] [092/155] (640s left) kms_plane (pixel-format-yf-tiled-ccs-modifier-source-clamping)
[383.977449] Starting subtest: pixel-format-yf-tiled-ccs-modifier-source-clamping
[383.981647] Subtest pixel-format-yf-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[384.063343] [093/155] (640s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-fullscreen)
[384.163106] Starting subtest: fbchdr-2p-primscrn-spr-indfb-fullscreen
[384.167460] Subtest fbchdr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[384.269304] [094/155] (639s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[384.379619] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[384.383785] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[384.502635] [095/155] (639s left) gem_ctx_persistence (engines-cleanup)
[384.558416] Starting subtest: engines-cleanup
[384.560566] Starting dynamic subtest: rcs0
[384.624648] Dynamic subtest rcs0: SUCCESS (0.069s)
[384.637190] Starting dynamic subtest: bcs0
[384.666769] Dynamic subtest bcs0: SUCCESS (0.040s)
[384.680999] Starting dynamic subtest: vcs0
[384.706181] Dynamic subtest vcs0: SUCCESS (0.038s)
[384.719773] Starting dynamic subtest: vcs1
[384.756763] Dynamic subtest vcs1: SUCCESS (0.048s)
[384.769969] Starting dynamic subtest: vecs0
[384.786159] Dynamic subtest vecs0: SUCCESS (0.028s)
[384.795645] Starting dynamic subtest: ccs0
[384.877738] Dynamic subtest ccs0: SUCCESS (0.090s)
[384.879969] Subtest engines-cleanup: SUCCESS (0.324s)
[384.958032] [096/155] (639s left) kms_invalid_mode (int-max-clock)
[385.043768] Starting subtest: int-max-clock
[385.045716] Starting dynamic subtest: pipe-A-eDP-1
[385.328661] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[385.338758] Starting dynamic subtest: pipe-B-eDP-1
[385.340654] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[385.350714] Starting dynamic subtest: pipe-C-eDP-1
[385.352999] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[385.362596] Starting dynamic subtest: pipe-D-eDP-1
[385.364942] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[385.367296] Subtest int-max-clock: SUCCESS (0.290s)
[386.188686] [097/155] (638s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[386.290433] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[386.294946] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[386.392142] [098/155] (637s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[386.492913] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[386.497952] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.000s)
[386.568010] [099/155] (637s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[386.666471] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[387.749743] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.082s)
[387.871768] [100/155] (636s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[387.971303] Starting subtest: 2x-flip-vs-modeset-vs-hang
[387.974838] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.002s)
[388.100154] [101/155] (636s left) kms_pm_backlight (brightness-with-dpms)
[388.196626] Starting subtest: brightness-with-dpms
[388.249386] Starting dynamic subtest: eDP-1
[390.991978] Dynamic subtest eDP-1: SUCCESS (2.742s)
[390.994376] Subtest brightness-with-dpms: SUCCESS (2.794s)
[391.859678] [102/155] (632s left) kms_addfb_basic (no-handle)
[391.910347] Starting subtest: no-handle
[391.912511] Subtest no-handle: SUCCESS (0.000s)
[392.051451] [103/155] (632s left) gem_mmap_gtt (basic-small-bo-tiledy)
[392.108568] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[392.168025] [104/155] (632s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[392.217803] Starting subtest: etime-multi-wait-all-available-unsubmitted
[392.325908] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.106s)
[392.417046] [105/155] (632s left) syncobj_timeline (multi-wait-available-submitted)
[392.464468] Starting subtest: multi-wait-available-submitted
[392.466767] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[392.557111] [106/155] (632s left) dmabuf_sync_file (export-multiwait)
[392.600816] Starting subtest: export-multiwait
[392.603032] Subtest export-multiwait: SUCCESS (0.001s)
[392.654509] [107/155] (632s left) gem_exec_reloc (basic-cpu-read)
[392.708042] Subtest basic-cpu-read: SKIP (0.000s)
[392.782368] [108/155] (632s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-pwrite)
[392.875375] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-pwrite
[392.879324] Subtest fbchdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[392.969558] [109/155] (631s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[393.073060] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[393.077465] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[393.155072] [110/155] (631s left) i915_pm_rps (engine-order)
[393.229988] Starting subtest: engine-order
[398.490157] Subtest engine-order: SUCCESS (5.258s)
[398.607503] [111/155] (626s left) gem_userptr_blits (coherency-sync)
[398.684711] Starting subtest: coherency-sync
[399.173988] Subtest coherency-sync: SKIP (0.450s)
[399.276558] [112/155] (625s left) gem_exec_suspend (basic-s0)
[399.394583] Starting subtest: basic-S0
[399.396593] Starting dynamic subtest: smem
[420.027211] Dynamic subtest smem: SUCCESS (6.728s)
[420.029959] Subtest basic-S0: SUCCESS (6.731s)
[420.226272] [113/155] (604s left) gem_busy (close-race)
[420.299161] Starting subtest: close-race
[444.027348] Subtest close-race: SUCCESS (23.817s)
[444.405452] [114/155] (580s left) gen9_exec_parse (bb-start-cmd)
[444.512532] Subtest bb-start-cmd: SKIP (0.000s)
[444.589515] [115/155] (580s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-render)
[444.696673] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-render
[444.700840] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[444.819529] [116/155] (580s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[444.913584] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[452.026937] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.125s)
[452.130884] [117/155] (573s left) gem_softpin (safe-alignment)
[452.191997] Starting subtest: safe-alignment
[452.213984] Subtest safe-alignment: SUCCESS (0.022s)
[454.118808] [118/155] (571s left) vgem_basic (debugfs)
[454.176771] Starting subtest: debugfs
[454.179082] Subtest debugfs: SUCCESS (0.000s)
[454.233298] [119/155] (571s left) kms_getfb (getfb2-accept-nv12)
[454.324620] Starting subtest: getfb2-accept-nv12
[454.327044] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[454.447777] [120/155] (570s left) kms_vblank (ts-continuation-idle)
[454.541061] Starting subtest: ts-continuation-idle
[454.543334] Starting dynamic subtest: pipe-A-eDP-1
[454.945769] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.404s)
[454.963952] Starting dynamic subtest: pipe-D-eDP-1
[456.035955] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.089s)
[456.038247] Subtest ts-continuation-idle: SUCCESS (1.497s)
[456.904541] [121/155] (568s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-blt)
[456.999737] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-blt
[457.004211] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[457.097004] [122/155] (568s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[457.489799] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[458.319852] [123/155] (567s left) gen3_render_mixed_blits
[458.444155] [124/155] (567s left) syncobj_timeline (single-wait-all-signaled)
[458.501543] Starting subtest: single-wait-all-signaled
[458.503916] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[458.625475] [125/155] (566s left) gem_pread (snoop)
[458.680401] Subtest snoop: SKIP (0.000s)
[458.744620] [126/155] (566s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[458.852623] Starting subtest: psr-rgb565-draw-mmap-wc
[465.743862] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.896s)
[465.860006] [127/155] (559s left) kms_selftest (drm_format_helper)
[465.906512] Starting subtest: drm_format_helper
[465.967545] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[466.060985] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.093s)
[466.072657] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[466.155002] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.093s)
[466.167515] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[466.267377] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.112s)
[466.277966] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[466.376132] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.109s)
[466.388771] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[466.486186] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.108s)
[466.498307] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[466.585084] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.097s)
[466.597519] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[466.687759] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.100s)
[466.698057] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[466.809753] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.123s)
[466.822397] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[466.903928] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.093s)
[466.916285] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[467.007932] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.103s)
[467.020150] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[467.127915] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.119s)
[467.138626] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[467.229060] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.099s)
[467.241966] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[467.343304] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.113s)
[467.356706] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[467.444170] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.099s)
[467.456745] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[467.543390] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.097s)
[467.555528] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[467.666087] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.121s)
[467.683206] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[467.780423] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.113s)
[467.782354] Subtest drm_format_helper: SUCCESS (1.873s)
[467.963423] [128/155] (557s left) gem_exec_flush (basic-uc-ro-default)
[468.025439] Starting subtest: basic-uc-ro-default
[473.512897] Subtest basic-uc-ro-default: SUCCESS (5.465s)
[473.683138] [129/155] (552s left) kms_flip (2x-plain-flip-fb-recreate)
[473.778777] Starting subtest: 2x-plain-flip-fb-recreate
[473.788697] Subtest 2x-plain-flip-fb-recreate: SKIP (0.011s)
[473.867971] [130/155] (551s left) gem_exec_fence (syncobj-invalid-flags)
[474.273135] Starting subtest: syncobj-invalid-flags
[474.275641] Subtest syncobj-invalid-flags: SUCCESS (0.001s)
[476.168132] [131/155] (549s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[476.274899] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[476.279357] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[476.371859] [132/155] (549s left) kms_dsc (dsc-with-output-formats)
[476.465952] Starting subtest: dsc-with-output-formats
[476.487361] Subtest dsc-with-output-formats: SKIP (0.000s)
[476.556077] [133/155] (549s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[476.651649] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[476.665883] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.012s)
[476.742428] [134/155] (549s left) kms_frontbuffer_tracking (psrhdr-modesetfrombusy)
[476.844205] Starting subtest: psrhdr-modesetfrombusy
[476.848373] Subtest psrhdr-modesetfrombusy: SKIP (0.000s)
[476.952113] [135/155] (549s left) kms_pm_rpm (system-suspend-modeset)
[479.312773] Starting subtest: system-suspend-modeset
[499.690175] Subtest system-suspend-modeset: SUCCESS (4.646s)
[500.089422] [136/155] (525s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[500.201068] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[500.205507] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[500.314128] [137/155] (525s left) gem_exec_balancer (noheartbeat)
[500.575493] Starting subtest: noheartbeat
[500.579943] Subtest noheartbeat: SKIP (0.000s)
[500.650778] [138/155] (525s left) kms_frontbuffer_tracking (fbcpsrhdr-shrfb-scaledprimary)
[500.756772] Starting subtest: fbcpsrhdr-shrfb-scaledprimary
[500.760976] Subtest fbcpsrhdr-shrfb-scaledprimary: SKIP (0.000s)
[500.873316] [139/155] (525s left) gem_exec_schedule (submit-late-slice)
[500.949877] Starting subtest: submit-late-slice
[500.951903] Starting dynamic subtest: rcs0
[500.981646] Dynamic subtest rcs0: SUCCESS (0.040s)
[500.995903] Starting dynamic subtest: bcs0
[501.015851] Dynamic subtest bcs0: SUCCESS (0.034s)
[501.026900] Starting dynamic subtest: vcs0
[501.043952] Dynamic subtest vcs0: SUCCESS (0.028s)
[501.055026] Starting dynamic subtest: vcs1
[501.074491] Dynamic subtest vcs1: SUCCESS (0.029s)
[501.085437] Starting dynamic subtest: vecs0
[501.100924] Dynamic subtest vecs0: SUCCESS (0.027s)
[501.112188] Starting dynamic subtest: ccs0
[501.134407] Dynamic subtest ccs0: SUCCESS (0.033s)
[501.136765] Subtest submit-late-slice: SUCCESS (0.195s)
[502.898841] [140/155] (523s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt)
[502.997458] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt
[503.001513] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[503.097901] [141/155] (523s left) kms_psr (psr-suspend)
[503.199045] Starting subtest: psr-suspend
[503.201330] Starting dynamic subtest: eDP-1
[521.719803] Dynamic subtest eDP-1: SUCCESS (3.072s)
[521.722235] Subtest psr-suspend: SUCCESS (3.073s)
[522.573049] [142/155] (503s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-plflip-blt)
[522.692513] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-plflip-blt
[522.696827] Subtest fbcpsrhdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[522.815312] [143/155] (503s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[522.910227] Starting subtest: primary-x-tiled-reflect-x-0
[529.823880] Subtest primary-x-tiled-reflect-x-0: SUCCESS (6.960s)
[530.987553] [144/155] (495s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[531.102364] Starting subtest: addfb25-yf-tiled-legacy
[531.104650] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[531.207439] [145/155] (495s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[531.310101] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[531.314205] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[531.404471] [146/155] (495s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-render)
[531.501958] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-render
[531.506115] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[531.599800] [147/155] (494s left) api_intel_bb (destroy-bb)
[531.662597] Starting subtest: destroy-bb
[531.668294] Subtest destroy-bb: SUCCESS (0.008s)
[531.814334] [148/155] (494s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[531.921827] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[531.925147] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[532.023184] [149/155] (494s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[532.131119] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[539.289281] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.178s)
[539.440339] [150/155] (487s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf)
[539.552642] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf
[539.554961] Starting dynamic subtest: pipe-A-eDP-1
[550.231690] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.694s)
[550.244501] Starting dynamic subtest: pipe-B-eDP-1
[561.715934] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.482s)
[561.718170] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.179s)
[562.564152] [151/155] (464s left) kms_hdmi_inject (inject-audio)
[562.929344] Starting subtest: inject-audio
[563.224654] Subtest inject-audio: SUCCESS (0.299s)
[563.836778] [152/155] (462s left) gem_exec_fence (syncobj-timeline-wait)
[564.385772] Starting subtest: syncobj-timeline-wait
[565.314710] Subtest syncobj-timeline-wait: SUCCESS (0.929s)
[567.365454] [153/155] (459s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[567.467507] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[567.471841] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[567.574826] [154/155] (459s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[567.667598] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[567.671692] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[567.760312] [155/155] (459s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[567.851392] Starting subtest: plane-use-after-nonblocking-unbind
[567.853717] Starting dynamic subtest: pipe-A-eDP-1
[567.911995] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[568.222251] Starting dynamic subtest: pipe-B-eDP-1
[569.075248] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.863s)
[569.388644] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.539s)
[570.195573] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: perf_pmu
results: parsing output: 2/ for test: kms_psr
results: parsing output: 3/ for test: gem_userptr_blits
results: parsing output: 4/ for test: gen7_exec_parse
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: i915_hangman
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: gem_exec_flush
results: parsing output: 9/ for test: kms_color
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: gem_render_copy
results: parsing output: 14/ for test: syncobj_wait
results: parsing output: 15/ for test: syncobj_wait
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_exec_balancer
results: parsing output: 18/ for test: gem_pread
results: parsing output: 19/ for test: gem_workarounds
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: api_intel_allocator
results: parsing output: 22/ for test: kms_cursor_crc
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_cursor_edge_walk
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: prime_vgem
results: parsing output: 29/ for test: kms_psr2_sf
results: parsing output: 30/ for test: kms_flip_scaled_crc
results: parsing output: 31/ for test: kms_cursor_legacy
results: parsing output: 32/ for test: gem_exec_schedule
results: parsing output: 33/ for test: syncobj_wait
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_mmap_gtt
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: gem_ccs
results: parsing output: 38/ for test: gem_userptr_blits
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: gem_close
results: parsing output: 42/ for test: syncobj_wait
results: parsing output: 43/ for test: kms_content_protection
results: parsing output: 44/ for test: kms_pm_rpm
results: parsing output: 45/ for test: vgem_basic
results: parsing output: 46/ for test: gem_flink_basic
results: parsing output: 47/ for test: gem_render_copy_redux
results: parsing output: 48/ for test: kms_addfb_basic
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: kms_flip_scaled_crc
results: parsing output: 51/ for test: gem_exec_reloc
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: syncobj_timeline
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: syncobj_timeline
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_lease
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_exercise_blt
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_plane_multiple
results: parsing output: 64/ for test: gem_create
results: parsing output: 65/ for test: kms_plane_scaling
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: i915_drm_fdinfo
results: parsing output: 68/ for test: kms_cursor_crc
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: gem_ctx_persistence
results: parsing output: 73/ for test: kms_cursor_legacy
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_chamelium_frames
results: parsing output: 76/ for test: i915_pm_rps
results: parsing output: 77/ for test: drm_read
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: gem_exec_reloc
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_partial_pwrite_pread
results: parsing output: 82/ for test: kms_ccs
results: parsing output: 83/ for test: perf_pmu
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: gem_mmap_offset
results: parsing output: 87/ for test: kms_plane_cursor
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_render_copy
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_plane
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_ctx_persistence
results: parsing output: 95/ for test: kms_invalid_mode
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_flip_scaled_crc
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_pm_backlight
results: parsing output: 101/ for test: kms_addfb_basic
results: parsing output: 102/ for test: gem_mmap_gtt
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: dmabuf_sync_file
results: parsing output: 106/ for test: gem_exec_reloc
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: i915_pm_rps
results: parsing output: 110/ for test: gem_userptr_blits
results: parsing output: 111/ for test: gem_exec_suspend
results: parsing output: 112/ for test: gem_busy
results: parsing output: 113/ for test: gen9_exec_parse
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: gem_softpin
results: parsing output: 117/ for test: vgem_basic
results: parsing output: 118/ for test: kms_getfb
results: parsing output: 119/ for test: kms_vblank
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_chamelium_hpd
results: parsing output: 122/ for test: gen3_render_mixed_blits
results: parsing output: 123/ for test: syncobj_timeline
results: parsing output: 124/ for test: gem_pread
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_selftest
results: parsing output: 127/ for test: gem_exec_flush
results: parsing output: 128/ for test: kms_flip
results: parsing output: 129/ for test: gem_exec_fence
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_dsc
results: parsing output: 132/ for test: kms_flip
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_pm_rpm
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: gem_exec_balancer
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_exec_schedule
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_psr
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: kms_rotation_crc
results: parsing output: 143/ for test: kms_addfb_basic
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: api_intel_bb
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_psr2_sf
results: parsing output: 150/ for test: kms_hdmi_inject
results: parsing output: 151/ for test: gem_exec_fence
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_atomic_transition
Done.