[93.479944] Initializing watchdogs
[93.480021]   /dev/watchdog0
[93.496158] [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]
[93.500608] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[93.502940] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[93.506253] [001/154] (960s left) kms_psr (fbc-pr-dpms)
[93.627889] Starting subtest: fbc-pr-dpms
[93.632365] Subtest fbc-pr-dpms: SKIP (0.000s)
[93.719251] [002/154] (960s left) kms_ccs (crc-sprite-planes-basic-yf-tiled-ccs)
[93.808824] Starting subtest: crc-sprite-planes-basic-yf-tiled-ccs
[93.811134] Starting dynamic subtest: pipe-A-eDP-1
[93.869066] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[93.876571] Starting dynamic subtest: pipe-B-eDP-1
[93.933700] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[93.942517] Starting dynamic subtest: pipe-C-eDP-1
[94.001240] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[94.010185] Starting dynamic subtest: pipe-D-eDP-1
[94.066759] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[94.070855] Subtest crc-sprite-planes-basic-yf-tiled-ccs: SKIP (0.008s)
[96.095974] [003/154] (957s left) gem_ctx_bad_destroy (invalid-pad)
[96.144552] Starting subtest: invalid-pad
[96.146820] Subtest invalid-pad: SUCCESS (0.000s)
[96.220274] [004/154] (957s left) gen3_render_tiledx_blits
[96.324220] [005/154] (957s left) kms_content_protection (atomic-dpms)
[96.468086] Starting subtest: atomic-dpms
[96.472734] Subtest atomic-dpms: SKIP (0.000s)
[96.596326] [006/154] (957s left) gem_mmap_gtt (flink-race)
[96.661154] Subtest flink-race: SKIP (0.000s)
[96.717071] [007/154] (957s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[96.818735] Starting subtest: extended-modeset-hang-newfb-with-reset
[96.821030] Starting dynamic subtest: pipe-A
[110.768092] Dynamic subtest pipe-A: SUCCESS (13.946s)
[110.778785] Starting dynamic subtest: pipe-D
[125.096596] Dynamic subtest pipe-D: SUCCESS (14.326s)
[125.099257] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (28.276s)
[125.904834] [008/154] (928s left) syncobj_wait (multi-wait-submitted)
[125.968023] Starting subtest: multi-wait-submitted
[126.080064] Subtest multi-wait-submitted: SUCCESS (0.110s)
[126.166836] [009/154] (928s left) kms_getfb (getfb2-handle-protection)
[126.267091] Starting subtest: getfb2-handle-protection
[126.287303] Subtest getfb2-handle-protection: SUCCESS (0.021s)
[126.409699] [010/154] (927s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[126.502420] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[126.507224] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[127.629879] [011/154] (926s left) gem_wait (write-wait)
[127.702621] Starting subtest: write-wait
[127.705261] Starting dynamic subtest: all-engines
[128.844891] Dynamic subtest all-engines: SUCCESS (1.142s)
[128.860987] Starting dynamic subtest: rcs0
[129.899937] Dynamic subtest rcs0: SUCCESS (1.054s)
[129.917110] Starting dynamic subtest: bcs0
[130.958291] Dynamic subtest bcs0: SUCCESS (1.058s)
[130.975074] Starting dynamic subtest: vcs0
[132.012466] Dynamic subtest vcs0: SUCCESS (1.053s)
[132.030297] Starting dynamic subtest: vcs1
[133.066643] Dynamic subtest vcs1: SUCCESS (1.053s)
[133.083277] Starting dynamic subtest: vecs0
[134.120185] Dynamic subtest vecs0: SUCCESS (1.053s)
[134.137831] Starting dynamic subtest: ccs0
[135.177270] Dynamic subtest ccs0: SUCCESS (1.056s)
[135.179698] Subtest write-wait: SUCCESS (7.475s)
[135.385270] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[135.388982] [012/154] (919s left) prime_vgem (coherency-blt)
[135.472566] Starting subtest: coherency-blt
[137.612511] Subtest coherency-blt: SUCCESS (2.085s)
[137.670228] [013/154] (916s left) kms_vblank (query-forked)
[137.770101] Starting subtest: query-forked
[137.772089] Starting dynamic subtest: pipe-A-eDP-1
[139.596145] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.825s)
[139.611055] Starting dynamic subtest: pipe-D-eDP-1
[142.144305] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.548s)
[142.146538] Subtest query-forked: SUCCESS (4.375s)
[143.002552] [014/154] (911s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[143.097629] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[143.101794] Subtest hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[143.204781] [015/154] (911s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[143.304157] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[143.308468] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[143.416351] [016/154] (911s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[143.503647] Starting subtest: multiplane-rotation-cropping-top
[160.984947] Subtest multiplane-rotation-cropping-top: SUCCESS (17.482s)
[161.100185] [017/154] (893s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[161.199937] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[162.290502] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.090s)
[162.414557] [018/154] (892s left) gem_create (create-clear)
[162.460790] Starting subtest: create-clear
[162.463596] Starting dynamic subtest: smem0
[195.254533] Dynamic subtest smem0: SUCCESS (32.791s)
[195.256494] Subtest create-clear: SUCCESS (32.791s)
[195.443152] [019/154] (859s left) kms_chamelium_hpd (vga-hpd)
[195.837634] Subtest vga-hpd: SKIP (0.000s)
[196.614029] [020/154] (858s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-msflip-blt)
[196.718482] Starting subtest: psrhdr-2p-scndscrn-shrfb-msflip-blt
[196.722883] Subtest psrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[196.814739] [021/154] (857s left) kms_big_fb (linear-8bpp-rotate-90)
[196.915408] Starting subtest: linear-8bpp-rotate-90
[196.935296] Subtest linear-8bpp-rotate-90: SKIP (0.019s)
[197.039333] [022/154] (857s left) gem_lmem_swapping (verify)
[197.079595] Subtest verify: SKIP (0.000s)
[197.123933] [023/154] (857s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[197.214947] Starting subtest: y-tiled-64bpp-rotate-180
[197.219119] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[197.323730] [024/154] (857s left) kms_dsc (dsc-with-output-formats)
[197.411985] Starting subtest: dsc-with-output-formats
[197.433327] Subtest dsc-with-output-formats: SKIP (0.001s)
[197.487740] [025/154] (857s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[197.580843] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[197.584594] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[197.681019] [026/154] (857s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[197.782809] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[197.786127] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[197.864702] [027/154] (857s left) api_intel_bb (purge-bb)
[197.912144] Starting subtest: purge-bb
[197.913980] Subtest purge-bb: SUCCESS (0.003s)
[198.039390] [028/154] (857s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[198.126302] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[198.129629] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[198.212946] [029/154] (856s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-shrfb-plflip-blt)
[198.319275] Starting subtest: fbchdr-1p-primscrn-shrfb-plflip-blt
[198.322874] Subtest fbchdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[198.434732] [030/154] (856s left) kms_flip (plain-flip-ts-check)
[198.537553] Starting subtest: plain-flip-ts-check
[198.539364] Starting dynamic subtest: A-eDP1
[207.456270] Dynamic subtest A-eDP1: SUCCESS (8.883s)
[207.465896] Starting dynamic subtest: B-eDP1
[216.293910] Dynamic subtest B-eDP1: SUCCESS (8.835s)
[216.303412] Starting dynamic subtest: C-eDP1
[225.125603] Dynamic subtest C-eDP1: SUCCESS (8.820s)
[225.134648] Starting dynamic subtest: D-eDP1
[233.963224] Dynamic subtest D-eDP1: SUCCESS (8.834s)
[233.965307] Subtest plain-flip-ts-check: SUCCESS (35.382s)
[235.115560] [031/154] (820s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[235.219377] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[242.772461] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.559s)
[242.899117] [032/154] (812s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[242.993429] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[242.995390] Starting dynamic subtest: pipe-A-eDP-1
[245.219565] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.228s)
[245.228691] Starting dynamic subtest: pipe-B-eDP-1
[247.672195] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.451s)
[247.683022] Starting dynamic subtest: pipe-C-eDP-1
[249.828263] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.153s)
[249.837633] Starting dynamic subtest: pipe-D-eDP-1
[251.962413] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.133s)
[251.964318] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.972s)
[253.217018] [033/154] (802s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[253.320422] Starting subtest: basic-max-pipe-crc-check
[253.324742] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[253.368148] [034/154] (801s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-plflip-blt)
[253.469633] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-plflip-blt
[253.473816] Subtest fbcpsrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[253.573852] [035/154] (801s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-onoff)
[253.678238] Starting subtest: hdr-2p-primscrn-spr-indfb-onoff
[253.682571] Subtest hdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[253.801155] [036/154] (801s left) gem_softpin (allocator-basic-reserve)
[253.860125] Starting subtest: allocator-basic-reserve
[253.925082] Subtest allocator-basic-reserve: SUCCESS (0.064s)
[254.065027] [037/154] (801s left) gem_exec_whisper (basic-queues-priority)
[254.128093] Starting subtest: basic-queues-priority
[278.377459] Subtest basic-queues-priority: SUCCESS (24.197s)
[278.511310] [038/154] (777s left) gem_ctx_persistence (legacy-engines-hostile)
[278.569927] Starting subtest: legacy-engines-hostile
[278.572352] Starting dynamic subtest: render
[278.647787] Dynamic subtest render: SUCCESS (0.077s)
[278.663099] Starting dynamic subtest: bsd1
[278.759897] Dynamic subtest bsd1: SUCCESS (0.109s)
[278.771982] Starting dynamic subtest: bsd2
[278.855553] Dynamic subtest bsd2: SUCCESS (0.093s)
[278.868163] Starting dynamic subtest: blt
[278.976499] Dynamic subtest blt: SUCCESS (0.118s)
[278.986738] Starting dynamic subtest: vebox
[279.088045] Dynamic subtest vebox: SUCCESS (0.109s)
[279.090629] Subtest legacy-engines-hostile: SUCCESS (0.519s)
[279.167230] [039/154] (776s left) gem_exec_gttfill (basic)
[279.229214] Starting subtest: basic
[282.562541] Subtest basic: SUCCESS (3.332s)
[282.757989] [040/154] (772s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[282.920120] Starting subtest: x-tiled-16bpp-rotate-0
[284.321611] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.401s)
[284.430384] [041/154] (771s left) kms_frontbuffer_tracking (fbchdr-farfromfence-mmap-gtt)
[284.548903] Starting subtest: fbchdr-farfromfence-mmap-gtt
[284.552365] Subtest fbchdr-farfromfence-mmap-gtt: SKIP (0.000s)
[284.647193] [042/154] (771s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[284.744327] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[284.747861] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[284.829127] [043/154] (770s left) gem_partial_pwrite_pread (writes-after-reads)
[284.892071] Subtest writes-after-reads: SKIP (0.000s)
[284.963863] [044/154] (770s left) gem_ctx_shared (q-in-order)
[285.022969] Starting subtest: Q-in-order
[285.025103] Starting dynamic subtest: rcs0
[285.125251] Dynamic subtest rcs0: SUCCESS (0.102s)
[285.138475] Starting dynamic subtest: vcs0
[285.225486] Dynamic subtest vcs0: SUCCESS (0.099s)
[285.244214] Starting dynamic subtest: vcs1
[285.312605] Dynamic subtest vcs1: SUCCESS (0.086s)
[285.329089] Starting dynamic subtest: vecs0
[285.393259] Dynamic subtest vecs0: SUCCESS (0.079s)
[285.408620] Starting dynamic subtest: ccs0
[285.480219] Dynamic subtest ccs0: SUCCESS (0.084s)
[285.482582] Subtest Q-in-order: SUCCESS (0.457s)
[286.393250] [045/154] (769s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[286.496724] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[290.237098] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.747s)
[291.428388] [046/154] (764s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render)
[291.522855] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render
[291.527259] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[291.606422] [047/154] (764s left) kms_psr (pr-basic)
[291.703481] Starting subtest: pr-basic
[291.707418] Subtest pr-basic: SKIP (0.000s)
[291.776311] [048/154] (764s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[291.875376] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[295.622711] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.753s)
[296.829878] [049/154] (759s left) kms_cursor_crc (cursor-offscreen-64x21)
[297.042710] Starting subtest: cursor-offscreen-64x21
[297.092764] Subtest cursor-offscreen-64x21: SKIP (0.044s)
[297.348408] [050/154] (758s left) kms_flip (2x-flip-vs-modeset)
[297.444836] Starting subtest: 2x-flip-vs-modeset
[297.454626] Subtest 2x-flip-vs-modeset: SKIP (0.011s)
[297.534433] [051/154] (758s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[297.643524] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-gtt
[297.647414] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[297.751141] [052/154] (758s left) gen9_exec_parse (bb-start-param)
[297.811924] Subtest bb-start-param: SKIP (0.000s)
[297.887962] [053/154] (758s left) vgem_slow (nohang)
[297.928614] Starting subtest: nohang
[308.275488] Subtest nohang: SUCCESS (10.294s)
[308.317792] [054/154] (747s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[308.424933] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[308.429259] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[308.533545] [055/154] (747s left) core_sysfs (read-all-entries)
[308.586387] Starting subtest: read-all-entries
[308.588788] Subtest read-all-entries: SUCCESS (0.003s)
[308.692126] [056/154] (747s left) gem_exec_params (invalid-fence-in)
[308.747575] Starting subtest: invalid-fence-in
[308.749783] Subtest invalid-fence-in: SUCCESS (0.000s)
[308.845828] [057/154] (747s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[308.972408] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[308.976802] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[309.133660] [058/154] (747s left) kms_async_flips (async-flip-hang)
[309.230596] Starting subtest: async-flip-hang
[309.598997] Starting dynamic subtest: pipe-A-eDP-1
[324.810575] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.208s)
[325.179336] Starting dynamic subtest: pipe-B-eDP-1
[340.805762] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.631s)
[341.201508] Starting dynamic subtest: pipe-C-eDP-1
[356.804836] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.603s)
[357.186658] Starting dynamic subtest: pipe-D-eDP-1
[372.798877] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.619s)
[372.801414] Subtest async-flip-hang: SUCCESS (63.564s)
[373.993214] [059/154] (682s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-onoff)
[374.094017] Starting subtest: psrhdr-1p-primscrn-cur-indfb-onoff
[374.098054] Subtest psrhdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[374.183843] [060/154] (682s left) gem_mmap_offset (ptrace)
[374.233738] Starting subtest: ptrace
[374.235640] Starting dynamic subtest: smem0-wb
[374.257942] Dynamic subtest smem0-wb: SUCCESS (0.022s)
[374.267729] Starting dynamic subtest: smem0-wc
[374.277214] Dynamic subtest smem0-wc: SUCCESS (0.018s)
[374.286965] Starting dynamic subtest: smem0-uc
[374.300173] Dynamic subtest smem0-uc: SUCCESS (0.022s)
[374.302349] Subtest ptrace: SUCCESS (0.069s)
[374.428995] [061/154] (682s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[374.527717] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[374.531962] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[374.641737] [062/154] (681s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[374.698651] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[374.703210] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[374.878504] [063/154] (681s left) gem_exec_reloc (basic-cpu-read)
[374.940052] Subtest basic-cpu-read: SKIP (0.000s)
[375.015730] [064/154] (681s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[375.112615] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[375.116733] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[375.215167] [065/154] (681s left) gem_exec_schedule (preempt-contexts)
[375.294975] Starting subtest: preempt-contexts
[375.297344] Starting dynamic subtest: rcs0
[375.341813] Dynamic subtest rcs0: SUCCESS (0.055s)
[375.352866] Starting dynamic subtest: bcs0
[375.396184] Dynamic subtest bcs0: SUCCESS (0.054s)
[375.405834] Starting dynamic subtest: vcs0
[375.447680] Dynamic subtest vcs0: SUCCESS (0.051s)
[375.457734] Starting dynamic subtest: vcs1
[375.500551] Dynamic subtest vcs1: SUCCESS (0.052s)
[375.510741] Starting dynamic subtest: vecs0
[375.544880] Dynamic subtest vecs0: SUCCESS (0.042s)
[375.557506] Starting dynamic subtest: ccs0
[375.595136] Dynamic subtest ccs0: SUCCESS (0.051s)
[375.597508] Subtest preempt-contexts: SUCCESS (0.309s)
[377.435389] [066/154] (679s left) syncobj_timeline (reset-during-wait-for-submit)
[377.490261] Starting subtest: reset-during-wait-for-submit
[377.594556] Subtest reset-during-wait-for-submit: SUCCESS (0.104s)
[377.685412] [067/154] (679s left) kms_cursor_legacy (forked-move)
[377.809512] Starting subtest: forked-move
[377.811420] Starting dynamic subtest: pipe-A
[383.330753] Dynamic subtest pipe-A: SUCCESS (5.457s)
[383.341525] Starting dynamic subtest: pipe-B
[388.797878] Dynamic subtest pipe-B: SUCCESS (5.475s)
[388.808929] Starting dynamic subtest: pipe-C
[394.277597] Dynamic subtest pipe-C: SUCCESS (5.475s)
[394.289468] Starting dynamic subtest: pipe-D
[399.759606] Dynamic subtest pipe-D: SUCCESS (5.480s)
[399.776959] Starting dynamic subtest: all-pipes
[405.267986] Dynamic subtest all-pipes: SUCCESS (5.505s)
[405.270205] Subtest forked-move: SUCCESS (27.403s)
[405.376991] [068/154] (651s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[405.486383] Starting subtest: primary-yf-tiled-reflect-x-270
[405.490790] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[405.574219] [069/154] (651s left) gem_exec_big (single)
[405.625543] Starting subtest: single
[407.792142] Subtest single: SUCCESS (2.164s)
[408.005396] [070/154] (648s left) gem_exec_whisper (basic-forked-all)
[408.109715] Starting subtest: basic-forked-all
[422.157221] Subtest basic-forked-all: SUCCESS (14.047s)
[422.343633] [071/154] (634s left) i915_pm_rpm (gem-execbuf)
[424.532380] Starting subtest: gem-execbuf
[424.535489] Starting dynamic subtest: smem0
[426.317927] Dynamic subtest smem0: SUCCESS (1.782s)
[426.320351] Subtest gem-execbuf: SUCCESS (1.784s)
[426.428642] [072/154] (630s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-onoff)
[426.528800] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-onoff
[426.533103] Subtest fbchdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[426.640189] [073/154] (630s left) gem_tiled_pread_pwrite
[426.761344] [074/154] (630s left) perf_pmu (init-busy)
[426.799563] Starting subtest: init-busy
[426.801984] Starting dynamic subtest: rcs0
[426.804309] Dynamic subtest rcs0: SUCCESS (0.001s)
[426.815987] Starting dynamic subtest: bcs0
[426.818178] Dynamic subtest bcs0: SUCCESS (0.000s)
[426.828155] Starting dynamic subtest: vcs0
[426.830294] Dynamic subtest vcs0: SUCCESS (0.000s)
[426.844977] Starting dynamic subtest: vcs1
[426.846913] Dynamic subtest vcs1: SUCCESS (0.001s)
[426.859803] Starting dynamic subtest: vecs0
[426.862329] Dynamic subtest vecs0: SUCCESS (0.000s)
[426.873196] Starting dynamic subtest: ccs0
[426.875108] Dynamic subtest ccs0: SUCCESS (0.000s)
[426.876961] Subtest init-busy: SUCCESS (0.008s)
[426.919414] [075/154] (630s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[427.014589] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[427.018704] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[427.102024] [076/154] (630s left) gem_mmap_wc (fault-concurrent)
[427.154748] Subtest fault-concurrent: SKIP (0.000s)
[427.218940] [077/154] (629s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[427.316289] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt
[427.319790] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[427.404985] [078/154] (629s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[427.505686] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[427.509119] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[427.598113] [079/154] (629s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-blt)
[427.690693] Starting subtest: fbchdr-rgb101010-draw-blt
[427.694577] Subtest fbchdr-rgb101010-draw-blt: SKIP (0.000s)
[427.776164] [080/154] (629s left) kms_invalid_mode (clock-too-high)
[427.876474] Starting subtest: clock-too-high
[427.878454] Starting dynamic subtest: pipe-A-eDP-1
[428.170492] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.295s)
[428.181297] Starting dynamic subtest: pipe-B-eDP-1
[428.185642] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[428.195518] Starting dynamic subtest: pipe-C-eDP-1
[428.200236] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[428.212024] Starting dynamic subtest: pipe-D-eDP-1
[428.216463] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[428.218766] Subtest clock-too-high: SUCCESS (0.303s)
[429.016326] [081/154] (628s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[429.114926] Starting subtest: fbc-rgb101010-draw-render
[431.634489] Subtest fbc-rgb101010-draw-render: SUCCESS (2.525s)
[432.814351] [082/154] (624s left) gem_mmap_wc (write-read-distinct)
[432.869203] Subtest write-read-distinct: SKIP (0.000s)
[432.932875] [083/154] (624s left) perf (global-sseu-config-invalid)
[433.108231] Starting subtest: global-sseu-config-invalid
[433.112531] Subtest global-sseu-config-invalid: SKIP (0.000s)
[433.151231] [084/154] (624s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-move)
[433.247428] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-move
[433.251689] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[433.344419] [085/154] (624s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[433.436057] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[433.440382] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[433.540865] [086/154] (624s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[433.638131] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[433.642609] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[433.729515] [087/154] (623s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[433.992239] Starting subtest: cursor-rapid-movement-256x85
[434.046805] Subtest cursor-rapid-movement-256x85: SKIP (0.049s)
[434.241137] [088/154] (623s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[434.329520] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[434.331542] Starting dynamic subtest: pipe-A
[434.350372] Dynamic subtest pipe-A: SKIP (0.018s)
[434.360795] Starting dynamic subtest: pipe-B
[434.383726] Dynamic subtest pipe-B: SKIP (0.032s)
[434.393745] Starting dynamic subtest: pipe-C
[434.415569] Dynamic subtest pipe-C: SKIP (0.032s)
[434.425204] Starting dynamic subtest: pipe-D
[434.447194] Dynamic subtest pipe-D: SKIP (0.031s)
[434.450630] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.116s)
[434.544646] [089/154] (623s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[434.637753] Starting subtest: fbc-psr2-primary-mmap-gtt
[434.639702] Starting dynamic subtest: eDP-1
[434.643257] Dynamic subtest eDP-1: SKIP (0.000s)
[434.646797] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[434.713530] [090/154] (623s left) sw_sync (sync_busy_fork_unixsocket)
[434.750098] Starting subtest: sync_busy_fork_unixsocket
[434.756762] Subtest sync_busy_fork_unixsocket: SUCCESS (0.008s)
[434.813792] [091/154] (622s left) gem_exec_flush (basic-uc-rw-default)
[434.869867] Starting subtest: basic-uc-rw-default
[440.355869] Subtest basic-uc-rw-default: SUCCESS (5.453s)
[440.511334] [092/154] (617s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-pwrite)
[440.609604] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-pwrite
[440.613790] Subtest fbchdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[440.702641] [093/154] (617s left) kms_color (deep-color)
[440.806601] Starting subtest: deep-color
[441.872924] Starting dynamic subtest: pipe-A-eDP-1-gamma
[442.612911] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.738s)
[442.623677] Starting dynamic subtest: pipe-A-eDP-1-degamma
[444.082512] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.466s)
[444.093706] Starting dynamic subtest: pipe-A-eDP-1-ctm
[445.501756] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.421s)
[446.276043] Starting dynamic subtest: pipe-B-eDP-1-gamma
[446.980491] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.714s)
[446.991746] Starting dynamic subtest: pipe-B-eDP-1-degamma
[448.467549] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.485s)
[448.478062] Starting dynamic subtest: pipe-B-eDP-1-ctm
[449.898475] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.428s)
[450.670080] Starting dynamic subtest: pipe-C-eDP-1-gamma
[451.361796] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.702s)
[451.372504] Starting dynamic subtest: pipe-C-eDP-1-degamma
[452.825353] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.460s)
[452.836566] Starting dynamic subtest: pipe-C-eDP-1-ctm
[454.259575] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.436s)
[455.070882] Starting dynamic subtest: pipe-D-eDP-1-gamma
[455.770043] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.707s)
[455.780790] Starting dynamic subtest: pipe-D-eDP-1-degamma
[457.236869] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.467s)
[457.249939] Starting dynamic subtest: pipe-D-eDP-1-ctm
[458.669988] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.433s)
[458.672444] Subtest deep-color: SUCCESS (17.865s)
[459.532681] [094/154] (598s left) kms_pm_backlight (fade)
[459.634315] Starting subtest: fade
[459.682083] Starting dynamic subtest: eDP-1
[463.457259] Dynamic subtest eDP-1: SUCCESS (3.773s)
[463.460036] Subtest fade: SUCCESS (3.825s)
[463.852065] [095/154] (594s left) gem_exec_reloc (basic-gtt-active)
[463.931401] Subtest basic-gtt-active: SKIP (0.000s)
[464.023699] [096/154] (593s left) gem_mmap (basic)
[464.082982] Subtest basic: SKIP (0.000s)
[464.145972] [097/154] (593s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[464.211207] Starting subtest: single-wait-for-submit-available-signaled
[464.213739] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[464.319226] [098/154] (593s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[464.426959] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[464.431161] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[464.536971] [099/154] (593s left) gem_exec_reloc (basic-wc-gtt)
[464.593016] Subtest basic-wc-gtt: SKIP (0.000s)
[464.672092] [100/154] (593s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[464.724210] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[464.830736] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.106s)
[464.926750] [101/154] (593s left) gem_exec_fence (syncobj-unused-fence)
[465.373985] Starting subtest: syncobj-unused-fence
[465.377452] Subtest syncobj-unused-fence: SUCCESS (0.007s)
[467.387641] [102/154] (590s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-cpu)
[467.495127] Starting subtest: hdr-rgb101010-draw-mmap-cpu
[467.499135] Subtest hdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[467.593796] [103/154] (590s left) kms_cursor_edge_walk (64x64-left-edge)
[467.687206] Starting subtest: 64x64-left-edge
[467.689496] Starting dynamic subtest: pipe-A-eDP-1
[471.122039] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.435s)
[471.133456] Starting dynamic subtest: pipe-D-eDP-1
[474.256843] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.133s)
[474.259200] Subtest 64x64-left-edge: SUCCESS (6.570s)
[475.082289] [104/154] (583s left) syncobj_timeline (invalid-query-zero-handles)
[475.130610] Starting subtest: invalid-query-zero-handles
[475.132533] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[475.241193] [105/154] (583s left) gem_bad_reloc (negative-reloc-lut)
[475.296375] Subtest negative-reloc-lut: SKIP (0.000s)
[475.375081] [106/154] (583s left) kms_addfb_basic (invalid-set-prop)
[475.432247] Starting subtest: invalid-set-prop
[475.434244] Subtest invalid-set-prop: SUCCESS (0.000s)
[475.577239] [107/154] (582s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[475.678096] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[498.996800] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (23.324s)
[499.134018] [108/154] (559s left) gem_softpin (noreloc-interruptible)
[499.204385] Starting subtest: noreloc-interruptible
[504.665835] Subtest noreloc-interruptible: SUCCESS (5.408s)
[504.735241] [109/154] (553s left) gem_exec_balancer (parallel-bb-first)
[504.992500] Starting subtest: parallel-bb-first
[505.969647] Subtest parallel-bb-first: SUCCESS (0.976s)
[506.394565] [110/154] (552s left) kms_async_flips (test-cursor)
[506.486768] Starting subtest: test-cursor
[506.865203] Starting dynamic subtest: pipe-A-eDP-1
[507.686756] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.823s)
[508.063333] Starting dynamic subtest: pipe-B-eDP-1
[508.817438] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.764s)
[509.209529] Starting dynamic subtest: pipe-C-eDP-1
[509.962900] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.765s)
[510.330673] Starting dynamic subtest: pipe-D-eDP-1
[511.088789] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.767s)
[511.091083] Subtest test-cursor: SUCCESS (4.604s)
[512.258831] [111/154] (546s left) gem_pxp (reject-modify-context-protection-off-3)
[512.377567] Starting subtest: reject-modify-context-protection-off-3
[512.380246] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[512.479643] [112/154] (546s left) syncobj_timeline (host-signal-ordered)
[512.542094] Starting subtest: host-signal-ordered
[512.544672] Subtest host-signal-ordered: SUCCESS (0.000s)
[512.646819] [113/154] (546s left) kms_plane_multiple (tiling-y)
[512.745967] Starting subtest: tiling-y
[512.750136] Subtest tiling-y: SKIP (0.000s)
[512.819069] [114/154] (545s left) kms_flip (flip-vs-suspend)
[512.902204] Starting subtest: flip-vs-suspend
[512.904431] Starting dynamic subtest: A-eDP1
[629.527182] Dynamic subtest A-eDP1: SUCCESS (12.618s)
[629.529984] Subtest flip-vs-suspend: SUCCESS (12.621s)
[640.669102] [115/154] (429s left) i915_drm_fdinfo (context-close-stress)
[640.719728] Starting subtest: context-close-stress
[651.514808] Subtest context-close-stress: SUCCESS (10.746s)
[651.563774] [116/154] (418s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[651.852821] Starting subtest: cursorA-vs-flipA-varying-size
[663.025331] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.172s)
[663.906505] [117/154] (406s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[664.020371] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[664.822271] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.804s)
[664.999768] [118/154] (404s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-plflip-blt)
[665.100619] Starting subtest: hdr-1p-primscrn-shrfb-plflip-blt
[665.104749] Subtest hdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[665.197771] [119/154] (404s left) kms_psr (pr-cursor-render)
[665.297440] Starting subtest: pr-cursor-render
[665.301597] Subtest pr-cursor-render: SKIP (0.000s)
[665.372082] [120/154] (404s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[665.477412] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[672.579822] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.108s)
[672.718694] [121/154] (397s left) kms_psr (psr2-sprite-plane-move)
[672.817633] Starting subtest: psr2-sprite-plane-move
[672.819884] Starting dynamic subtest: eDP-1
[674.573496] Dynamic subtest eDP-1: SUCCESS (1.754s)
[674.575999] Subtest psr2-sprite-plane-move: SUCCESS (1.755s)
[675.428558] [122/154] (394s left) kms_plane_lowres (tiling-none)
[675.519539] Starting subtest: tiling-none
[675.521773] Starting dynamic subtest: pipe-A-eDP-1
[675.526390] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[675.828247] Starting dynamic subtest: pipe-B-eDP-1
[675.833780] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[675.847087] Starting dynamic subtest: pipe-C-eDP-1
[675.851709] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[675.862653] Starting dynamic subtest: pipe-D-eDP-1
[675.867169] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[675.871579] Subtest tiling-none: SKIP (0.307s)
[676.700815] [123/154] (393s left) api_intel_allocator (standalone)
[676.761990] Starting subtest: standalone
[676.777850] Subtest standalone: SUCCESS (0.018s)
[676.915614] [124/154] (393s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[677.011342] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[677.013519] Starting dynamic subtest: pipe-A-eDP-1
[677.454205] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.443s)
[677.467652] Starting dynamic subtest: pipe-B-eDP-1
[677.488915] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[677.502358] Starting dynamic subtest: pipe-C-eDP-1
[677.521270] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[677.532374] Starting dynamic subtest: pipe-D-eDP-1
[677.551425] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.005s)
[677.552957] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.466s)
[679.729340] [125/154] (390s left) perf (sysctl-defaults)
[679.797504] Starting subtest: sysctl-defaults
[679.799989] Subtest sysctl-defaults: SUCCESS (0.000s)
[679.862337] [126/154] (390s left) gem_fence_thrash (bo-write-verify-threaded-none)
[679.897663] Starting subtest: bo-write-verify-threaded-none
[679.924556] Subtest bo-write-verify-threaded-none: SKIP (0.025s)
[679.990442] [127/154] (390s left) i915_query (query-topology-coherent-slice-mask)
[680.056793] Starting subtest: query-topology-coherent-slice-mask
[680.061137] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[680.169137] [128/154] (390s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[680.276734] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[680.278992] Starting dynamic subtest: pipe-A-eDP-1
[680.355431] Dynamic subtest pipe-A-eDP-1: SKIP (0.080s)
[680.369570] Starting dynamic subtest: pipe-B-eDP-1
[681.508778] Dynamic subtest pipe-B-eDP-1: SKIP (1.150s)
[681.513533] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.234s)
[682.800829] [129/154] (387s left) kms_pm_rpm (system-suspend-modeset)
[684.527112] Starting subtest: system-suspend-modeset
[707.336280] Subtest system-suspend-modeset: SUCCESS (7.297s)
[712.121154] [130/154] (362s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[712.231611] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[712.235546] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[712.343221] [131/154] (362s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[712.451262] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[714.941747] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (2.490s)
[716.141765] [132/154] (358s left) kms_chamelium_edid (hdmi-edid-stress-resolution-non-4k)
[716.522665] Subtest hdmi-edid-stress-resolution-non-4k: SKIP (0.000s)
[717.337495] [133/154] (357s left) kms_chamelium_color (ctm-0-75)
[717.429886] Subtest ctm-0-75: SKIP (0.000s)
[717.475016] [134/154] (357s left) gem_exec_schedule (semaphore-codependency)
[717.555992] Starting subtest: semaphore-codependency
[717.561284] Subtest semaphore-codependency: SUCCESS (0.013s)
[717.919474] [135/154] (356s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[718.476731] Starting subtest: syncobj-timeline-multiple-ext-nodes
[718.479615] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.003s)
[719.945265] [136/154] (354s left) perf (oa-exponents)
[720.030666] Starting subtest: oa-exponents
[720.033033] Starting dynamic subtest: 0-rcs0
[721.821832] Dynamic subtest 0-rcs0: SUCCESS (1.790s)
[721.835456] Starting dynamic subtest: 1-vcs0
[723.678468] Dynamic subtest 1-vcs0: SUCCESS (1.853s)
[723.680962] Subtest oa-exponents: SUCCESS (3.646s)
[723.729985] [137/154] (351s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[723.827036] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[727.890209] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (4.066s)
[729.087087] [138/154] (345s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[729.138951] Starting subtest: rel-constants-invalid-rel-gen5
[729.141247] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[729.243447] [139/154] (345s left) i915_fb_tiling (basic-x-tiling)
[729.291204] Subtest basic-x-tiling: SKIP (0.000s)
[729.356833] [140/154] (345s left) gem_userptr_blits (unsync-unmap-cycles)
[729.429541] Subtest unsync-unmap-cycles: SKIP (0.000s)
[729.558764] [141/154] (345s left) kms_vblank (ts-continuation-suspend)
[729.650309] Starting subtest: ts-continuation-suspend
[729.652668] Starting dynamic subtest: pipe-A-eDP-1
[746.787123] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.932s)
[746.805885] Starting dynamic subtest: pipe-D-eDP-1
[764.807325] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.597s)
[764.809961] Subtest ts-continuation-suspend: SUCCESS (4.532s)
[776.668081] [142/154] (309s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-plflip-blt)
[776.780974] Starting subtest: psrhdr-2p-scndscrn-indfb-plflip-blt
[776.785404] Subtest psrhdr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[776.876321] [143/154] (309s left) perf (oa-formats)
[776.949760] Starting subtest: oa-formats
[776.952140] Starting dynamic subtest: 0-rcs0
[776.962986] Dynamic subtest 0-rcs0: SUCCESS (0.013s)
[776.975039] Starting dynamic subtest: 1-vecs0
[776.986930] Dynamic subtest 1-vecs0: SUCCESS (0.022s)
[776.989287] Subtest oa-formats: SUCCESS (0.038s)
[777.032136] [144/154] (309s left) i915_selftest (live)
[780.390537] Starting subtest: live
[780.395629] Starting dynamic subtest: sanitycheck
[784.848160] Dynamic subtest sanitycheck: SUCCESS (4.457s)
[784.858320] Starting dynamic subtest: uncore
[789.249688] Dynamic subtest uncore: SUCCESS (4.394s)
[789.259210] Starting dynamic subtest: workarounds
[807.490432] Dynamic subtest workarounds: FAIL (18.091s)
[807.502050] Starting dynamic subtest: gt_engines
[811.950624] Dynamic subtest gt_engines: SUCCESS (4.597s)
[811.962964] Starting dynamic subtest: gt_timelines
[825.985849] Dynamic subtest gt_timelines: SUCCESS (14.035s)
[825.996735] Starting dynamic subtest: gt_contexts
[830.403933] Dynamic subtest gt_contexts: SUCCESS (4.417s)
[830.416116] Starting dynamic subtest: gt_lrc
[836.546637] Dynamic subtest gt_lrc: SUCCESS (6.140s)
[836.556350] Starting dynamic subtest: gt_mocs
[839.543385] Dynamic subtest gt_mocs: SUCCESS (2.993s)
[839.554012] Starting dynamic subtest: gt_pm
[846.550116] Dynamic subtest gt_pm: SUCCESS (7.002s)
[846.561345] Starting dynamic subtest: gt_heartbeat
[850.941577] Dynamic subtest gt_heartbeat: SUCCESS (4.389s)
[850.953192] Starting dynamic subtest: gt_tlb
[860.946525] Dynamic subtest gt_tlb: SUCCESS (9.999s)
[860.957814] Starting dynamic subtest: requests
[874.561172] Dynamic subtest requests: SUCCESS (13.610s)
[874.572772] Starting dynamic subtest: migrate
[882.308730] Dynamic subtest migrate: SUCCESS (7.748s)
[882.320068] Starting dynamic subtest: active
[885.052510] Dynamic subtest active: SUCCESS (2.742s)
[885.061964] Starting dynamic subtest: objects
[889.957712] Dynamic subtest objects: SUCCESS (4.903s)
[889.969048] Starting dynamic subtest: mman
[894.812722] Dynamic subtest mman: SUCCESS (4.853s)
[894.824312] Starting dynamic subtest: dmabuf
[899.185620] Dynamic subtest dmabuf: SUCCESS (4.370s)
[899.197166] Starting dynamic subtest: vma
[903.514600] Dynamic subtest vma: SUCCESS (4.327s)
[903.526044] Starting dynamic subtest: coherency
[908.674805] Dynamic subtest coherency: SUCCESS (5.158s)
[908.686217] Starting dynamic subtest: gtt
[920.806287] Dynamic subtest gtt: SUCCESS (12.129s)
[920.818196] Starting dynamic subtest: gem
[929.238975] Dynamic subtest gem: SUCCESS (8.429s)
[929.250638] Starting dynamic subtest: evict
[934.702892] Dynamic subtest evict: SUCCESS (5.461s)
[934.717834] Starting dynamic subtest: hugepages
[946.093161] Dynamic subtest hugepages: SUCCESS (11.387s)
[946.103521] Starting dynamic subtest: gem_contexts
[973.542668] Dynamic subtest gem_contexts: SUCCESS (27.440s)
[973.553159] Starting dynamic subtest: client
[977.947503] Dynamic subtest client: SUCCESS (4.403s)
[977.963374] Starting dynamic subtest: gem_migrate
[982.294918] Dynamic subtest gem_migrate: SUCCESS (4.344s)
[982.306433] Starting dynamic subtest: reset
[985.121800] Dynamic subtest reset: SUCCESS (2.824s)
[985.133061] Starting dynamic subtest: memory_region
[989.506323] Dynamic subtest memory_region: SUCCESS (4.382s)
[989.517455] Starting dynamic subtest: hangcheck
[1003.473090] Dynamic subtest hangcheck: SUCCESS (13.964s)
[1003.484809] Starting dynamic subtest: execlists
[1007.858788] Dynamic subtest execlists: SUCCESS (4.385s)
[1007.869562] Starting dynamic subtest: ring_submission
[1012.292317] Dynamic subtest ring_submission: SUCCESS (4.432s)
[1012.302942] Starting dynamic subtest: perf
[1016.716908] Dynamic subtest perf: SUCCESS (4.423s)
[1016.728458] Starting dynamic subtest: slpc
[1031.353343] Dynamic subtest slpc: SUCCESS (14.637s)
[1031.364764] Starting dynamic subtest: guc
[1035.348338] Dynamic subtest guc: SUCCESS (3.994s)
[1035.359687] Starting dynamic subtest: guc_multi_lrc
[1039.757855] Dynamic subtest guc_multi_lrc: SUCCESS (4.409s)
[1039.772945] Starting dynamic subtest: guc_hang
[1044.761335] Dynamic subtest guc_hang: SUCCESS (5.001s)
[1044.772966] Starting dynamic subtest: late_gt_pm
[1049.139211] Dynamic subtest late_gt_pm: SUCCESS (4.376s)
[1049.141971] Subtest live: FAIL (268.714s)
[1049.170937] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[1049.171624] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[1049.175389] [145/154] ( 37s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[1050.777216] Starting subtest: fbcpsr-tiling-linear
[1057.463925] Subtest fbcpsr-tiling-linear: SUCCESS (6.694s)
[1057.597888] [FACT kms_frontbuffer_tracking (fbcpsr-tiling-linear)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[1057.599209] [FACT kms_frontbuffer_tracking (fbcpsr-tiling-linear)] new: kernel.kmod_is_loaded.i915: true
[1057.603392] [146/154] ( 28s left) gem_ctx_isolation (dirty-create)
[1057.671810] Starting subtest: dirty-create
[1057.674166] Starting dynamic subtest: rcs0
[1057.881329] Dynamic subtest rcs0: SUCCESS (0.209s)
[1057.896111] Starting dynamic subtest: bcs0
[1058.107770] Dynamic subtest bcs0: SUCCESS (0.225s)
[1058.122694] Starting dynamic subtest: vcs0
[1058.309247] Dynamic subtest vcs0: SUCCESS (0.201s)
[1058.321213] Starting dynamic subtest: vcs1
[1059.686548] Dynamic subtest vcs1: SUCCESS (1.376s)
[1059.699755] Starting dynamic subtest: vecs0
[1061.654421] Dynamic subtest vecs0: SUCCESS (1.967s)
[1061.666507] Starting dynamic subtest: ccs0
[1063.626934] Dynamic subtest ccs0: SUCCESS (1.972s)
[1063.629353] Subtest dirty-create: SUCCESS (5.955s)
[1063.890798] [147/154] ( 22s left) gem_close (basic)
[1063.950788] Starting subtest: basic
[1063.952945] Subtest basic: SUCCESS (0.000s)
[1064.069257] [148/154] ( 22s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-pwrite)
[1064.170971] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-pwrite
[1064.175029] Subtest psrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[1064.279939] [149/154] ( 22s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[1064.367523] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[1064.371705] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[1064.461387] [150/154] ( 22s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[1064.563504] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[1071.431669] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.871s)
[1071.558327] [151/154] ( 14s left) syncobj_wait (single-wait-submitted)
[1071.625954] Starting subtest: single-wait-submitted
[1071.734546] Subtest single-wait-submitted: SUCCESS (0.108s)
[1071.837967] [152/154] ( 14s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[1071.938216] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[1071.942498] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[1072.020237] [153/154] ( 14s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[1072.122885] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[1076.483750] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.369s)
[1077.680819] [154/154] (  8s left) syncobj_wait (invalid-signal-illegal-handle)
[1077.738066] Starting subtest: invalid-signal-illegal-handle
[1077.740517] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[1077.857910] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: gem_ctx_bad_destroy
results: parsing output: 3/ for test: gen3_render_tiledx_blits
results: parsing output: 4/ for test: kms_content_protection
results: parsing output: 5/ for test: gem_mmap_gtt
results: parsing output: 6/ for test: kms_busy
results: parsing output: 7/ for test: syncobj_wait
results: parsing output: 8/ for test: kms_getfb
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: gem_wait
results: parsing output: 11/ for test: prime_vgem
results: parsing output: 12/ for test: kms_vblank
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_rotation_crc
results: parsing output: 16/ for test: kms_big_fb
results: parsing output: 17/ for test: gem_create
results: parsing output: 18/ for test: kms_chamelium_hpd
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: gem_lmem_swapping
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: kms_dsc
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_psr2_sf
results: parsing output: 26/ for test: api_intel_bb
results: parsing output: 27/ for test: kms_flip_scaled_crc
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_flip
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: kms_multipipe_modeset
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_softpin
results: parsing output: 36/ for test: gem_exec_whisper
results: parsing output: 37/ for test: gem_ctx_persistence
results: parsing output: 38/ for test: gem_exec_gttfill
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_partial_pwrite_pread
results: parsing output: 43/ for test: gem_ctx_shared
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_cursor_crc
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gen9_exec_parse
results: parsing output: 52/ for test: vgem_slow
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: core_sysfs
results: parsing output: 55/ for test: gem_exec_params
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: kms_async_flips
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_mmap_offset
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_render_copy
results: parsing output: 62/ for test: gem_exec_reloc
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_exec_schedule
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: kms_cursor_legacy
results: parsing output: 67/ for test: kms_rotation_crc
results: parsing output: 68/ for test: gem_exec_big
results: parsing output: 69/ for test: gem_exec_whisper
results: parsing output: 70/ for test: i915_pm_rpm
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: gem_tiled_pread_pwrite
results: parsing output: 73/ for test: perf_pmu
results: parsing output: 74/ for test: kms_plane
results: parsing output: 75/ for test: gem_mmap_wc
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_invalid_mode
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_mmap_wc
results: parsing output: 82/ for test: perf
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: kms_plane_scaling
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: sw_sync
results: parsing output: 90/ for test: gem_exec_flush
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_color
results: parsing output: 93/ for test: kms_pm_backlight
results: parsing output: 94/ for test: gem_exec_reloc
results: parsing output: 95/ for test: gem_mmap
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gem_exec_reloc
results: parsing output: 99/ for test: syncobj_timeline
results: parsing output: 100/ for test: gem_exec_fence
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_cursor_edge_walk
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: gem_bad_reloc
results: parsing output: 105/ for test: kms_addfb_basic
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_softpin
results: parsing output: 108/ for test: gem_exec_balancer
results: parsing output: 109/ for test: kms_async_flips
results: parsing output: 110/ for test: gem_pxp
results: parsing output: 111/ for test: syncobj_timeline
results: parsing output: 112/ for test: kms_plane_multiple
results: parsing output: 113/ for test: kms_flip
results: parsing output: 114/ for test: i915_drm_fdinfo
results: parsing output: 115/ for test: kms_cursor_legacy
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_psr
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_psr
results: parsing output: 121/ for test: kms_plane_lowres
results: parsing output: 122/ for test: api_intel_allocator
results: parsing output: 123/ for test: kms_ccs
results: parsing output: 124/ for test: perf
results: parsing output: 125/ for test: gem_fence_thrash
results: parsing output: 126/ for test: i915_query
results: parsing output: 127/ for test: kms_psr2_sf
results: parsing output: 128/ for test: kms_pm_rpm
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_chamelium_edid
results: parsing output: 132/ for test: kms_chamelium_color
results: parsing output: 133/ for test: gem_exec_schedule
results: parsing output: 134/ for test: gem_exec_fence
results: parsing output: 135/ for test: perf
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: gem_exec_params
results: parsing output: 138/ for test: i915_fb_tiling
results: parsing output: 139/ for test: gem_userptr_blits
results: parsing output: 140/ for test: kms_vblank
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: perf
results: parsing output: 143/ for test: i915_selftest
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: gem_ctx_isolation
results: parsing output: 146/ for test: gem_close
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_frontbuffer_tracking
results: parsing output: 150/ for test: syncobj_wait
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: syncobj_wait
Done.