[59.195922] Initializing watchdogs
[59.195974]   /dev/watchdog0
[59.211407] [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.215846] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.218239] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.221955] [001/154] (960s left) kms_psr (fbc-pr-dpms)
[59.345844] Starting subtest: fbc-pr-dpms
[59.350153] Subtest fbc-pr-dpms: SKIP (0.000s)
[59.428713] [002/154] (960s left) kms_ccs (crc-sprite-planes-basic-yf-tiled-ccs)
[59.523890] Starting subtest: crc-sprite-planes-basic-yf-tiled-ccs
[59.526232] Starting dynamic subtest: pipe-A-eDP-1
[59.585140] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[59.592396] Starting dynamic subtest: pipe-B-eDP-1
[59.650926] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[59.659907] Starting dynamic subtest: pipe-C-eDP-1
[59.717240] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[59.725631] Starting dynamic subtest: pipe-D-eDP-1
[59.783431] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[59.787734] Subtest crc-sprite-planes-basic-yf-tiled-ccs: SKIP (0.007s)
[61.855952] [003/154] (957s left) gem_ctx_bad_destroy (invalid-pad)
[61.908715] Starting subtest: invalid-pad
[61.911240] Subtest invalid-pad: SUCCESS (0.000s)
[61.975735] [004/154] (957s left) gen3_render_tiledx_blits
[62.109029] [005/154] (957s left) kms_content_protection (atomic-dpms)
[62.259937] Starting subtest: atomic-dpms
[62.264961] Subtest atomic-dpms: SKIP (0.000s)
[62.388337] [006/154] (957s left) gem_mmap_gtt (flink-race)
[62.443966] Subtest flink-race: SKIP (0.000s)
[62.504230] [007/154] (957s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[62.609109] Starting subtest: extended-modeset-hang-newfb-with-reset
[62.611432] Starting dynamic subtest: pipe-A
[76.697044] Dynamic subtest pipe-A: SUCCESS (14.060s)
[76.706745] Starting dynamic subtest: pipe-D
[92.288087] Dynamic subtest pipe-D: SUCCESS (15.585s)
[92.290445] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (29.648s)
[93.079168] [008/154] (926s left) syncobj_wait (multi-wait-submitted)
[93.138367] Starting subtest: multi-wait-submitted
[93.246090] Subtest multi-wait-submitted: SUCCESS (0.108s)
[93.317318] [009/154] (926s left) kms_getfb (getfb2-handle-protection)
[93.421108] Starting subtest: getfb2-handle-protection
[93.436969] Subtest getfb2-handle-protection: SUCCESS (0.017s)
[93.566444] [010/154] (926s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[93.648856] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[93.653510] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[94.817694] [011/154] (925s left) gem_wait (write-wait)
[94.872924] Starting subtest: write-wait
[94.875279] Starting dynamic subtest: all-engines
[95.986158] Dynamic subtest all-engines: SUCCESS (1.112s)
[96.003021] Starting dynamic subtest: rcs0
[97.042966] Dynamic subtest rcs0: SUCCESS (1.055s)
[97.058443] Starting dynamic subtest: bcs0
[98.100055] Dynamic subtest bcs0: SUCCESS (1.056s)
[98.115791] Starting dynamic subtest: vcs0
[99.155099] Dynamic subtest vcs0: SUCCESS (1.054s)
[99.170685] Starting dynamic subtest: vcs1
[100.209581] Dynamic subtest vcs1: SUCCESS (1.053s)
[100.225261] Starting dynamic subtest: vecs0
[101.263292] Dynamic subtest vecs0: SUCCESS (1.052s)
[101.279150] Starting dynamic subtest: ccs0
[102.319236] Dynamic subtest ccs0: SUCCESS (1.055s)
[102.321755] Subtest write-wait: SUCCESS (7.446s)
[102.525087] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[102.528225] [012/154] (917s left) prime_vgem (coherency-blt)
[102.592170] Starting subtest: coherency-blt
[104.510977] Subtest coherency-blt: SUCCESS (1.917s)
[104.621163] [013/154] (915s left) kms_vblank (query-forked)
[104.720381] Starting subtest: query-forked
[104.722662] Starting dynamic subtest: pipe-A-eDP-1
[106.547093] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.824s)
[106.561802] Starting dynamic subtest: pipe-D-eDP-1
[109.075903] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.525s)
[109.078419] Subtest query-forked: SUCCESS (4.353s)
[109.892711] [014/154] (910s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[109.999702] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[110.004209] Subtest hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[110.092358] [015/154] (910s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[110.180461] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[110.184593] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[110.299373] [016/154] (909s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[110.387293] Starting subtest: multiplane-rotation-cropping-top
[127.835692] Subtest multiplane-rotation-cropping-top: SUCCESS (17.449s)
[127.950050] [017/154] (892s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[128.058528] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[129.070332] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.012s)
[129.207203] [018/154] (891s left) gem_create (create-clear)
[129.264460] Starting subtest: create-clear
[129.266562] Starting dynamic subtest: smem0
[162.263419] Dynamic subtest smem0: SUCCESS (32.994s)
[162.265408] Subtest create-clear: SUCCESS (32.995s)
[162.451424] [019/154] (857s left) kms_chamelium_hpd (vga-hpd)
[162.850426] Subtest vga-hpd: SKIP (0.000s)
[163.705970] [020/154] (856s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-msflip-blt)
[163.809059] Starting subtest: psrhdr-2p-scndscrn-shrfb-msflip-blt
[163.813765] Subtest psrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[163.905657] [021/154] (856s left) kms_big_fb (linear-8bpp-rotate-90)
[163.997395] Starting subtest: linear-8bpp-rotate-90
[164.021779] Subtest linear-8bpp-rotate-90: SKIP (0.024s)
[164.135115] [022/154] (856s left) gem_lmem_swapping (verify)
[164.174765] Subtest verify: SKIP (0.000s)
[164.219283] [023/154] (856s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[164.319705] Starting subtest: y-tiled-64bpp-rotate-180
[164.323927] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[164.431789] [024/154] (856s left) kms_dsc (dsc-with-output-formats)
[164.525914] Starting subtest: dsc-with-output-formats
[164.547994] Subtest dsc-with-output-formats: SKIP (0.001s)
[164.600597] [025/154] (856s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[164.701480] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[164.704903] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[164.804239] [026/154] (855s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[164.903545] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[164.906985] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[164.983937] [027/154] (855s left) api_intel_bb (purge-bb)
[165.027031] Starting subtest: purge-bb
[165.031398] Subtest purge-bb: SUCCESS (0.005s)
[165.168837] [028/154] (855s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[165.252271] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[165.255707] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[165.336315] [029/154] (855s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-shrfb-plflip-blt)
[165.427072] Starting subtest: fbchdr-1p-primscrn-shrfb-plflip-blt
[165.430542] Subtest fbchdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[165.533130] [030/154] (855s left) kms_flip (plain-flip-ts-check)
[165.626349] Starting subtest: plain-flip-ts-check
[165.628365] Starting dynamic subtest: A-eDP1
[174.557867] Dynamic subtest A-eDP1: SUCCESS (8.899s)
[174.569289] Starting dynamic subtest: B-eDP1
[183.391567] Dynamic subtest B-eDP1: SUCCESS (8.831s)
[183.401769] Starting dynamic subtest: C-eDP1
[192.214075] Dynamic subtest C-eDP1: SUCCESS (8.811s)
[192.226620] Starting dynamic subtest: D-eDP1
[201.044833] Dynamic subtest D-eDP1: SUCCESS (8.825s)
[201.047486] Subtest plain-flip-ts-check: SUCCESS (35.377s)
[202.192419] [031/154] (818s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[202.297765] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[209.601314] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.309s)
[209.735180] [032/154] (811s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[209.836381] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[209.838768] Starting dynamic subtest: pipe-A-eDP-1
[211.971462] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.133s)
[211.980931] Starting dynamic subtest: pipe-B-eDP-1
[214.170473] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.199s)
[214.179175] Starting dynamic subtest: pipe-C-eDP-1
[216.262463] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.088s)
[216.274998] Starting dynamic subtest: pipe-D-eDP-1
[218.290896] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.028s)
[218.293296] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.454s)
[219.536383] [033/154] (801s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[219.643312] Starting subtest: basic-max-pipe-crc-check
[219.647451] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[219.689917] [034/154] (801s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-plflip-blt)
[219.786927] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-plflip-blt
[219.792671] Subtest fbcpsrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[219.891021] [035/154] (801s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-onoff)
[219.998017] Starting subtest: hdr-2p-primscrn-spr-indfb-onoff
[220.002240] Subtest hdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[220.082761] [036/154] (801s left) gem_softpin (allocator-basic-reserve)
[220.153934] Starting subtest: allocator-basic-reserve
[220.218823] Subtest allocator-basic-reserve: SUCCESS (0.065s)
[220.368624] [037/154] (800s left) gem_exec_whisper (basic-queues-priority)
[220.421536] Starting subtest: basic-queues-priority
[243.487606] Subtest basic-queues-priority: SUCCESS (23.064s)
[243.673827] [038/154] (777s left) gem_ctx_persistence (legacy-engines-hostile)
[243.733143] Starting subtest: legacy-engines-hostile
[243.735777] Starting dynamic subtest: render
[243.829667] Dynamic subtest render: SUCCESS (0.096s)
[243.842902] Starting dynamic subtest: bsd1
[243.926796] Dynamic subtest bsd1: SUCCESS (0.094s)
[243.938990] Starting dynamic subtest: bsd2
[244.041517] Dynamic subtest bsd2: SUCCESS (0.112s)
[244.054842] Starting dynamic subtest: blt
[244.154990] Dynamic subtest blt: SUCCESS (0.110s)
[244.165846] Starting dynamic subtest: vebox
[244.263974] Dynamic subtest vebox: SUCCESS (0.106s)
[244.266633] Subtest legacy-engines-hostile: SUCCESS (0.532s)
[244.356775] [039/154] (776s left) gem_exec_gttfill (basic)
[244.427401] Starting subtest: basic
[247.747386] Subtest basic: SUCCESS (3.320s)
[247.938102] [040/154] (773s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[248.095754] Starting subtest: x-tiled-16bpp-rotate-0
[249.520725] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.425s)
[249.657710] [041/154] (771s left) kms_frontbuffer_tracking (fbchdr-farfromfence-mmap-gtt)
[249.776128] Starting subtest: fbchdr-farfromfence-mmap-gtt
[249.779468] Subtest fbchdr-farfromfence-mmap-gtt: SKIP (0.000s)
[249.887266] [042/154] (771s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[249.990517] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[249.994203] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[250.099031] [043/154] (771s left) gem_partial_pwrite_pread (writes-after-reads)
[250.158177] Subtest writes-after-reads: SKIP (0.000s)
[250.239403] [044/154] (771s left) gem_ctx_shared (q-in-order)
[250.300383] Starting subtest: Q-in-order
[250.302500] Starting dynamic subtest: rcs0
[250.403390] Dynamic subtest rcs0: SUCCESS (0.102s)
[250.413578] Starting dynamic subtest: vcs0
[250.494721] Dynamic subtest vcs0: SUCCESS (0.091s)
[250.508545] Starting dynamic subtest: vcs1
[250.577586] Dynamic subtest vcs1: SUCCESS (0.080s)
[250.595047] Starting dynamic subtest: vecs0
[250.653936] Dynamic subtest vecs0: SUCCESS (0.077s)
[250.671310] Starting dynamic subtest: ccs0
[250.734875] Dynamic subtest ccs0: SUCCESS (0.080s)
[250.737386] Subtest Q-in-order: SUCCESS (0.434s)
[252.379540] [045/154] (769s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[252.482061] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[256.369412] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.892s)
[257.560910] [046/154] (763s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render)
[257.666131] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render
[257.670230] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[257.763538] [047/154] (763s left) kms_psr (pr-basic)
[257.867741] Starting subtest: pr-basic
[257.872217] Subtest pr-basic: SKIP (0.000s)
[257.941350] [048/154] (763s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[258.048744] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[261.744920] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.703s)
[262.959832] [049/154] (758s left) kms_cursor_crc (cursor-offscreen-64x21)
[263.154617] Starting subtest: cursor-offscreen-64x21
[263.203868] Subtest cursor-offscreen-64x21: SKIP (0.042s)
[263.450168] [050/154] (758s left) kms_flip (2x-flip-vs-modeset)
[263.546081] Starting subtest: 2x-flip-vs-modeset
[263.555565] Subtest 2x-flip-vs-modeset: SKIP (0.011s)
[263.676205] [051/154] (758s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[263.775478] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-gtt
[263.779967] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[263.867553] [052/154] (757s left) gen9_exec_parse (bb-start-param)
[263.926627] Subtest bb-start-param: SKIP (0.000s)
[263.996116] [053/154] (757s left) vgem_slow (nohang)
[264.030970] Starting subtest: nohang
[274.454831] Subtest nohang: SUCCESS (10.369s)
[274.496351] [054/154] (747s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[274.601629] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[274.605764] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[274.691934] [055/154] (747s left) core_sysfs (read-all-entries)
[274.750545] Starting subtest: read-all-entries
[274.752468] Subtest read-all-entries: SUCCESS (0.004s)
[274.880796] [056/154] (747s left) gem_exec_params (invalid-fence-in)
[274.946567] Starting subtest: invalid-fence-in
[274.948873] Subtest invalid-fence-in: SUCCESS (0.000s)
[275.072348] [057/154] (746s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[275.201482] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[275.206048] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[275.349591] [058/154] (746s left) kms_async_flips (async-flip-hang)
[275.441367] Starting subtest: async-flip-hang
[275.827312] Starting dynamic subtest: pipe-A-eDP-1
[290.791759] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.951s)
[291.168297] Starting dynamic subtest: pipe-B-eDP-1
[306.782693] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.618s)
[307.153047] Starting dynamic subtest: pipe-C-eDP-1
[322.777139] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.633s)
[323.159384] Starting dynamic subtest: pipe-D-eDP-1
[338.779541] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.631s)
[338.781971] Subtest async-flip-hang: SUCCESS (63.334s)
[340.015112] [059/154] (682s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-onoff)
[340.112582] Starting subtest: psrhdr-1p-primscrn-cur-indfb-onoff
[340.116977] Subtest psrhdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[340.215859] [060/154] (681s left) gem_mmap_offset (ptrace)
[340.264928] Starting subtest: ptrace
[340.267322] Starting dynamic subtest: smem0-wb
[340.278070] Dynamic subtest smem0-wb: SUCCESS (0.012s)
[340.288942] Starting dynamic subtest: smem0-wc
[340.296869] Dynamic subtest smem0-wc: SUCCESS (0.018s)
[340.306259] Starting dynamic subtest: smem0-uc
[340.319038] Dynamic subtest smem0-uc: SUCCESS (0.021s)
[340.321425] Subtest ptrace: SUCCESS (0.057s)
[340.443864] [061/154] (681s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[340.546553] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[340.550641] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[340.637201] [062/154] (681s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[340.693319] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[340.697729] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[340.876309] [063/154] (681s left) gem_exec_reloc (basic-cpu-read)
[340.937433] Subtest basic-cpu-read: SKIP (0.000s)
[341.014497] [064/154] (681s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[341.105063] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[341.109452] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[341.191659] [065/154] (681s left) gem_exec_schedule (preempt-contexts)
[341.283905] Starting subtest: preempt-contexts
[341.286115] Starting dynamic subtest: rcs0
[341.341687] Dynamic subtest rcs0: SUCCESS (0.063s)
[341.350916] Starting dynamic subtest: bcs0
[341.391243] Dynamic subtest bcs0: SUCCESS (0.050s)
[341.400513] Starting dynamic subtest: vcs0
[341.439575] Dynamic subtest vcs0: SUCCESS (0.047s)
[341.449326] Starting dynamic subtest: vcs1
[341.487372] Dynamic subtest vcs1: SUCCESS (0.046s)
[341.497159] Starting dynamic subtest: vecs0
[341.536239] Dynamic subtest vecs0: SUCCESS (0.049s)
[341.545710] Starting dynamic subtest: ccs0
[341.587612] Dynamic subtest ccs0: SUCCESS (0.050s)
[341.589779] Subtest preempt-contexts: SUCCESS (0.310s)
[343.395643] [066/154] (678s left) syncobj_timeline (reset-during-wait-for-submit)
[343.455016] Starting subtest: reset-during-wait-for-submit
[343.559049] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[343.636379] [067/154] (678s left) kms_cursor_legacy (forked-move)
[343.755481] Starting subtest: forked-move
[343.757689] Starting dynamic subtest: pipe-A
[349.270380] Dynamic subtest pipe-A: SUCCESS (5.457s)
[349.281768] Starting dynamic subtest: pipe-B
[354.750561] Dynamic subtest pipe-B: SUCCESS (5.479s)
[354.763178] Starting dynamic subtest: pipe-C
[360.234093] Dynamic subtest pipe-C: SUCCESS (5.481s)
[360.248215] Starting dynamic subtest: pipe-D
[365.713218] Dynamic subtest pipe-D: SUCCESS (5.476s)
[365.724524] Starting dynamic subtest: all-pipes
[371.204265] Dynamic subtest all-pipes: SUCCESS (5.489s)
[371.206682] Subtest forked-move: SUCCESS (27.394s)
[371.309589] [068/154] (651s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[371.421980] Starting subtest: primary-yf-tiled-reflect-x-270
[371.426353] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[371.511391] [069/154] (650s left) gem_exec_big (single)
[371.568755] Starting subtest: single
[373.764052] Subtest single: SUCCESS (2.195s)
[373.958787] [070/154] (648s left) gem_exec_whisper (basic-forked-all)
[374.058319] Starting subtest: basic-forked-all
[387.268593] Subtest basic-forked-all: SUCCESS (13.207s)
[387.453398] [071/154] (635s left) i915_pm_rpm (gem-execbuf)
[389.434260] Starting subtest: gem-execbuf
[389.436663] Starting dynamic subtest: smem0
[391.192212] Dynamic subtest smem0: SUCCESS (1.757s)
[391.194393] Subtest gem-execbuf: SUCCESS (1.759s)
[391.311126] [072/154] (631s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-onoff)
[391.425786] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-onoff
[391.429999] Subtest fbchdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[391.521973] [073/154] (631s left) gem_tiled_pread_pwrite
[391.660217] [074/154] (631s left) perf_pmu (init-busy)
[391.711332] Starting subtest: init-busy
[391.713634] Starting dynamic subtest: rcs0
[391.716081] Dynamic subtest rcs0: SUCCESS (0.001s)
[391.725668] Starting dynamic subtest: bcs0
[391.727887] Dynamic subtest bcs0: SUCCESS (0.000s)
[391.737309] Starting dynamic subtest: vcs0
[391.739488] Dynamic subtest vcs0: SUCCESS (0.001s)
[391.748749] Starting dynamic subtest: vcs1
[391.750993] Dynamic subtest vcs1: SUCCESS (0.001s)
[391.760254] Starting dynamic subtest: vecs0
[391.762453] Dynamic subtest vecs0: SUCCESS (0.001s)
[391.772062] Starting dynamic subtest: ccs0
[391.773934] Dynamic subtest ccs0: SUCCESS (0.000s)
[391.775870] Subtest init-busy: SUCCESS (0.008s)
[391.825400] [075/154] (630s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[391.923099] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[391.927087] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[392.006879] [076/154] (630s left) gem_mmap_wc (fault-concurrent)
[392.060414] Subtest fault-concurrent: SKIP (0.000s)
[392.122111] [077/154] (630s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[392.212335] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt
[392.215756] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[392.324576] [078/154] (630s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[392.423976] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[392.427709] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[392.523088] [079/154] (630s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-blt)
[392.631286] Starting subtest: fbchdr-rgb101010-draw-blt
[392.634659] Subtest fbchdr-rgb101010-draw-blt: SKIP (0.000s)
[392.755116] [080/154] (630s left) kms_invalid_mode (clock-too-high)
[392.842476] Starting subtest: clock-too-high
[392.844438] Starting dynamic subtest: pipe-A-eDP-1
[393.137965] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.296s)
[393.148952] Starting dynamic subtest: pipe-B-eDP-1
[393.154020] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[393.165900] Starting dynamic subtest: pipe-C-eDP-1
[393.170681] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[393.182672] Starting dynamic subtest: pipe-D-eDP-1
[393.186745] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[393.188954] Subtest clock-too-high: SUCCESS (0.301s)
[393.993865] [081/154] (628s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[394.096432] Starting subtest: fbc-rgb101010-draw-render
[396.611473] Subtest fbc-rgb101010-draw-render: SUCCESS (2.520s)
[397.812768] [082/154] (625s left) gem_mmap_wc (write-read-distinct)
[397.870900] Subtest write-read-distinct: SKIP (0.000s)
[397.936895] [083/154] (625s left) perf (global-sseu-config-invalid)
[398.090935] Starting subtest: global-sseu-config-invalid
[398.095186] Subtest global-sseu-config-invalid: SKIP (0.000s)
[398.132368] [084/154] (624s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-move)
[398.238145] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-move
[398.242333] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[398.354749] [085/154] (624s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[398.467745] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[398.472191] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[398.580897] [086/154] (624s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[398.675704] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[398.679714] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[398.786694] [087/154] (624s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[399.044442] Starting subtest: cursor-rapid-movement-256x85
[399.088575] Subtest cursor-rapid-movement-256x85: SKIP (0.042s)
[399.272494] [088/154] (623s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[399.364037] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[399.365881] Starting dynamic subtest: pipe-A
[399.385231] Dynamic subtest pipe-A: SKIP (0.017s)
[399.396431] Starting dynamic subtest: pipe-B
[399.416846] Dynamic subtest pipe-B: SKIP (0.032s)
[399.426161] Starting dynamic subtest: pipe-C
[399.447698] Dynamic subtest pipe-C: SKIP (0.031s)
[399.456781] Starting dynamic subtest: pipe-D
[399.481082] Dynamic subtest pipe-D: SKIP (0.032s)
[399.484133] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.114s)
[399.571752] [089/154] (623s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[399.663375] Starting subtest: fbc-psr2-primary-mmap-gtt
[399.665351] Starting dynamic subtest: eDP-1
[399.668851] Dynamic subtest eDP-1: SKIP (0.000s)
[399.672322] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[399.736488] [090/154] (623s left) sw_sync (sync_busy_fork_unixsocket)
[399.770426] Starting subtest: sync_busy_fork_unixsocket
[399.775056] Subtest sync_busy_fork_unixsocket: SUCCESS (0.008s)
[399.834983] [091/154] (623s left) gem_exec_flush (basic-uc-rw-default)
[399.892985] Starting subtest: basic-uc-rw-default
[405.383468] Subtest basic-uc-rw-default: SUCCESS (5.456s)
[405.535867] [092/154] (617s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-pwrite)
[405.635492] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-pwrite
[405.639603] Subtest fbchdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[405.731340] [093/154] (617s left) kms_color (deep-color)
[405.821339] Starting subtest: deep-color
[406.871077] Starting dynamic subtest: pipe-A-eDP-1-gamma
[407.586826] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.715s)
[407.597813] Starting dynamic subtest: pipe-A-eDP-1-degamma
[409.049573] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.462s)
[409.060087] Starting dynamic subtest: pipe-A-eDP-1-ctm
[410.508961] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.460s)
[411.270715] Starting dynamic subtest: pipe-B-eDP-1-gamma
[411.971443] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.707s)
[411.987378] Starting dynamic subtest: pipe-B-eDP-1-degamma
[413.473969] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.501s)
[413.485796] Starting dynamic subtest: pipe-B-eDP-1-ctm
[414.914688] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.439s)
[415.668922] Starting dynamic subtest: pipe-C-eDP-1-gamma
[416.354602] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.693s)
[416.364170] Starting dynamic subtest: pipe-C-eDP-1-degamma
[417.822935] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.466s)
[417.838507] Starting dynamic subtest: pipe-C-eDP-1-ctm
[419.256610] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.436s)
[420.067057] Starting dynamic subtest: pipe-D-eDP-1-gamma
[420.748042] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.691s)
[420.758645] Starting dynamic subtest: pipe-D-eDP-1-degamma
[422.133284] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.383s)
[422.143756] Starting dynamic subtest: pipe-D-eDP-1-ctm
[423.559274] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.425s)
[423.561627] Subtest deep-color: SUCCESS (17.738s)
[424.419819] [094/154] (599s left) kms_pm_backlight (fade)
[424.520376] Starting subtest: fade
[424.567243] Starting dynamic subtest: eDP-1
[427.578269] Dynamic subtest eDP-1: SUCCESS (3.007s)
[427.580688] Subtest fade: SUCCESS (3.057s)
[428.458621] [095/154] (595s left) gem_exec_reloc (basic-gtt-active)
[428.532086] Subtest basic-gtt-active: SKIP (0.000s)
[428.611396] [096/154] (594s left) gem_mmap (basic)
[428.660646] Subtest basic: SKIP (0.000s)
[428.719427] [097/154] (594s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[428.781531] Starting subtest: single-wait-for-submit-available-signaled
[428.783782] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[428.878275] [098/154] (594s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[428.967971] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[428.972260] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[429.068847] [099/154] (594s left) gem_exec_reloc (basic-wc-gtt)
[429.118827] Subtest basic-wc-gtt: SKIP (0.000s)
[429.196665] [100/154] (594s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[429.258060] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[429.367108] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.107s)
[429.442086] [101/154] (594s left) gem_exec_fence (syncobj-unused-fence)
[429.876064] Starting subtest: syncobj-unused-fence
[429.878531] Subtest syncobj-unused-fence: SUCCESS (0.005s)
[431.386745] [102/154] (592s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-cpu)
[431.483563] Starting subtest: hdr-rgb101010-draw-mmap-cpu
[431.487780] Subtest hdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[431.595718] [103/154] (592s left) kms_cursor_edge_walk (64x64-left-edge)
[431.693030] Starting subtest: 64x64-left-edge
[431.695372] Starting dynamic subtest: pipe-A-eDP-1
[435.134964] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.442s)
[435.148692] Starting dynamic subtest: pipe-D-eDP-1
[438.268642] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.132s)
[438.270924] Subtest 64x64-left-edge: SUCCESS (6.578s)
[439.086398] [104/154] (584s left) syncobj_timeline (invalid-query-zero-handles)
[439.145672] Starting subtest: invalid-query-zero-handles
[439.147597] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[439.256931] [105/154] (584s left) gem_bad_reloc (negative-reloc-lut)
[439.303241] Subtest negative-reloc-lut: SKIP (0.000s)
[439.370881] [106/154] (584s left) kms_addfb_basic (invalid-set-prop)
[439.419503] Starting subtest: invalid-set-prop
[439.421427] Subtest invalid-set-prop: SUCCESS (0.000s)
[439.554481] [107/154] (584s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[439.640458] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[462.376209] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (22.740s)
[462.520468] [108/154] (561s left) gem_softpin (noreloc-interruptible)
[462.575549] Starting subtest: noreloc-interruptible
[468.029994] Subtest noreloc-interruptible: SUCCESS (5.403s)
[468.093390] [109/154] (556s left) gem_exec_balancer (parallel-bb-first)
[468.340863] Starting subtest: parallel-bb-first
[469.290727] Subtest parallel-bb-first: SUCCESS (0.950s)
[470.332403] [110/154] (553s left) kms_async_flips (test-cursor)
[470.428861] Starting subtest: test-cursor
[470.801053] Starting dynamic subtest: pipe-A-eDP-1
[471.589124] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.791s)
[471.974252] Starting dynamic subtest: pipe-B-eDP-1
[472.730347] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.762s)
[473.115748] Starting dynamic subtest: pipe-C-eDP-1
[473.862811] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.758s)
[474.242648] Starting dynamic subtest: pipe-D-eDP-1
[475.001574] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.768s)
[475.003877] Subtest test-cursor: SUCCESS (4.575s)
[476.182945] [111/154] (548s left) gem_pxp (reject-modify-context-protection-off-3)
[476.315177] Starting subtest: reject-modify-context-protection-off-3
[476.317493] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[476.419202] [112/154] (547s left) syncobj_timeline (host-signal-ordered)
[476.474578] Starting subtest: host-signal-ordered
[476.476821] Subtest host-signal-ordered: SUCCESS (0.000s)
[476.573272] [113/154] (547s left) kms_plane_multiple (tiling-y)
[476.675379] Starting subtest: tiling-y
[476.679830] Subtest tiling-y: SKIP (0.000s)
[476.747136] [114/154] (547s left) kms_flip (flip-vs-suspend)
[476.837874] Starting subtest: flip-vs-suspend
[476.840210] Starting dynamic subtest: A-eDP1
[622.281457] Dynamic subtest A-eDP1: SUCCESS (12.131s)
[622.286732] Subtest flip-vs-suspend: SUCCESS (12.134s)
[622.443699] [115/154] (401s left) i915_drm_fdinfo (context-close-stress)
[622.495329] Starting subtest: context-close-stress
[633.410551] Subtest context-close-stress: SUCCESS (10.747s)
[633.654426] [116/154] (390s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[633.860124] Starting subtest: cursorA-vs-flipA-varying-size
[645.016756] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.125s)
[645.916201] [117/154] (378s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[646.021507] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[646.868845] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.848s)
[647.023447] [118/154] (377s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-plflip-blt)
[647.131262] Starting subtest: hdr-1p-primscrn-shrfb-plflip-blt
[647.136274] Subtest hdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[647.238803] [119/154] (377s left) kms_psr (pr-cursor-render)
[647.338990] Starting subtest: pr-cursor-render
[647.343632] Subtest pr-cursor-render: SKIP (0.000s)
[647.415628] [120/154] (377s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[647.505353] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[654.524841] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.018s)
[654.650552] [121/154] (369s left) kms_psr (psr2-sprite-plane-move)
[654.757641] Starting subtest: psr2-sprite-plane-move
[654.760303] Starting dynamic subtest: eDP-1
[656.566399] Dynamic subtest eDP-1: SUCCESS (1.806s)
[656.569198] Subtest psr2-sprite-plane-move: SUCCESS (1.807s)
[657.403277] [122/154] (367s left) kms_plane_lowres (tiling-none)
[657.496577] Starting subtest: tiling-none
[657.499077] Starting dynamic subtest: pipe-A-eDP-1
[657.504310] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[657.793307] Starting dynamic subtest: pipe-B-eDP-1
[657.798078] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[657.808990] Starting dynamic subtest: pipe-C-eDP-1
[657.814293] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[657.825438] Starting dynamic subtest: pipe-D-eDP-1
[657.830367] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[657.835377] Subtest tiling-none: SKIP (0.294s)
[658.634964] [123/154] (366s left) api_intel_allocator (standalone)
[658.688469] Starting subtest: standalone
[658.704359] Subtest standalone: SUCCESS (0.018s)
[658.814257] [124/154] (365s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[658.897850] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[658.900253] Starting dynamic subtest: pipe-A-eDP-1
[659.319873] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.422s)
[659.334569] Starting dynamic subtest: pipe-B-eDP-1
[659.358574] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[659.377065] Starting dynamic subtest: pipe-C-eDP-1
[659.399700] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.003s)
[659.411319] Starting dynamic subtest: pipe-D-eDP-1
[659.434383] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[659.437014] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.436s)
[661.604735] [125/154] (363s left) perf (sysctl-defaults)
[661.670600] Starting subtest: sysctl-defaults
[661.673433] Subtest sysctl-defaults: SUCCESS (0.000s)
[661.748478] [126/154] (363s left) gem_fence_thrash (bo-write-verify-threaded-none)
[661.784824] Starting subtest: bo-write-verify-threaded-none
[661.802958] Subtest bo-write-verify-threaded-none: SKIP (0.015s)
[661.864876] [127/154] (363s left) i915_query (query-topology-coherent-slice-mask)
[661.915390] Starting subtest: query-topology-coherent-slice-mask
[661.920779] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[662.005529] [128/154] (362s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[662.105124] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[662.107589] Starting dynamic subtest: pipe-A-eDP-1
[662.176951] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[662.188912] Starting dynamic subtest: pipe-B-eDP-1
[663.373401] Dynamic subtest pipe-B-eDP-1: SKIP (1.191s)
[663.379451] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.270s)
[664.559367] [129/154] (360s left) kms_pm_rpm (system-suspend-modeset)
[666.790927] Starting subtest: system-suspend-modeset
[686.516699] Subtest system-suspend-modeset: SUCCESS (4.295s)
[686.923151] [130/154] (338s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[687.026669] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[687.031806] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[687.128939] [131/154] (337s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[687.233815] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[689.737734] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (2.478s)
[690.864823] [132/154] (334s left) kms_chamelium_edid (hdmi-edid-stress-resolution-non-4k)
[691.246506] Subtest hdmi-edid-stress-resolution-non-4k: SKIP (0.000s)
[692.076237] [133/154] (333s left) kms_chamelium_color (ctm-0-75)
[692.168470] Subtest ctm-0-75: SKIP (0.000s)
[692.218632] [134/154] (332s left) gem_exec_schedule (semaphore-codependency)
[692.304755] Starting subtest: semaphore-codependency
[692.310570] Subtest semaphore-codependency: SUCCESS (0.014s)
[692.651989] [135/154] (332s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[693.211804] Starting subtest: syncobj-timeline-multiple-ext-nodes
[693.218440] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.004s)
[695.098043] [136/154] (330s left) perf (oa-exponents)
[695.189004] Starting subtest: oa-exponents
[695.191767] Starting dynamic subtest: 0-rcs0
[697.006181] Dynamic subtest 0-rcs0: SUCCESS (1.807s)
[697.019388] Starting dynamic subtest: 1-vcs1
[698.841762] Dynamic subtest 1-vcs1: SUCCESS (1.827s)
[698.844660] Subtest oa-exponents: SUCCESS (3.636s)
[698.896505] [137/154] (326s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[699.005350] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[702.984979] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (3.975s)
[704.162761] [138/154] (321s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[704.219700] Starting subtest: rel-constants-invalid-rel-gen5
[704.221682] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[704.309686] [139/154] (321s left) i915_fb_tiling (basic-x-tiling)
[704.365259] Subtest basic-x-tiling: SKIP (0.000s)
[704.436091] [140/154] (321s left) gem_userptr_blits (unsync-unmap-cycles)
[704.503065] Subtest unsync-unmap-cycles: SKIP (0.000s)
[704.621537] [141/154] (320s left) kms_vblank (ts-continuation-suspend)
[704.710700] Starting subtest: ts-continuation-suspend
[704.713349] Starting dynamic subtest: pipe-A-eDP-1
[721.529645] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.547s)
[721.547509] Starting dynamic subtest: pipe-D-eDP-1
[738.549876] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.183s)
[738.551871] Subtest ts-continuation-suspend: SUCCESS (3.734s)
[739.404660] [142/154] (286s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-plflip-blt)
[739.509531] Starting subtest: psrhdr-2p-scndscrn-indfb-plflip-blt
[739.514214] Subtest psrhdr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[739.600725] [143/154] (285s left) perf (oa-formats)
[739.699047] Starting subtest: oa-formats
[739.701485] Starting dynamic subtest: 0-rcs0
[739.711298] Dynamic subtest 0-rcs0: SUCCESS (0.012s)
[739.727043] Starting dynamic subtest: 1-vcs0
[739.734732] Dynamic subtest 1-vcs0: SUCCESS (0.022s)
[739.737056] Subtest oa-formats: SUCCESS (0.036s)
[739.777399] [144/154] (285s left) i915_selftest (live)
[743.198918] Starting subtest: live
[743.201577] Starting dynamic subtest: sanitycheck
[747.579202] Dynamic subtest sanitycheck: SUCCESS (4.346s)
[747.590355] Starting dynamic subtest: uncore
[751.935654] Dynamic subtest uncore: SUCCESS (4.335s)
[751.946126] Starting dynamic subtest: workarounds
[755.066048] Dynamic subtest workarounds: SUCCESS (3.120s)
[755.075726] Starting dynamic subtest: gt_engines
[759.661634] Dynamic subtest gt_engines: SUCCESS (4.584s)
[759.672575] Starting dynamic subtest: gt_timelines
[775.998030] Dynamic subtest gt_timelines: SUCCESS (16.327s)
[776.009620] Starting dynamic subtest: gt_contexts
[780.350649] Dynamic subtest gt_contexts: SUCCESS (4.353s)
[780.362026] Starting dynamic subtest: gt_lrc
[786.514759] Dynamic subtest gt_lrc: SUCCESS (6.163s)
[786.525575] Starting dynamic subtest: gt_mocs
[789.509254] Dynamic subtest gt_mocs: SUCCESS (2.993s)
[789.520224] Starting dynamic subtest: gt_pm
[796.275564] Dynamic subtest gt_pm: SUCCESS (6.764s)
[796.285280] Starting dynamic subtest: gt_heartbeat
[800.642536] Dynamic subtest gt_heartbeat: SUCCESS (4.364s)
[800.653043] Starting dynamic subtest: gt_tlb
[810.536200] Dynamic subtest gt_tlb: SUCCESS (9.889s)
[810.545728] Starting dynamic subtest: requests
[824.066795] Dynamic subtest requests: SUCCESS (13.523s)
[824.078003] Starting dynamic subtest: migrate
[831.800782] Dynamic subtest migrate: SUCCESS (7.730s)
[831.813526] Starting dynamic subtest: active
[836.141842] Dynamic subtest active: SUCCESS (4.340s)
[836.152748] Starting dynamic subtest: objects
[841.028049] Dynamic subtest objects: SUCCESS (4.886s)
[841.038976] Starting dynamic subtest: mman
[845.794156] Dynamic subtest mman: SUCCESS (4.766s)
[845.803770] Starting dynamic subtest: dmabuf
[850.196762] Dynamic subtest dmabuf: SUCCESS (4.401s)
[850.207448] Starting dynamic subtest: vma
[854.537906] Dynamic subtest vma: SUCCESS (4.339s)
[854.548984] Starting dynamic subtest: coherency
[859.496619] Dynamic subtest coherency: SUCCESS (4.958s)
[859.508098] Starting dynamic subtest: gtt
[871.626958] Dynamic subtest gtt: SUCCESS (12.128s)
[871.636469] Starting dynamic subtest: gem
[879.824435] Dynamic subtest gem: SUCCESS (8.194s)
[879.835548] Starting dynamic subtest: evict
[885.301110] Dynamic subtest evict: SUCCESS (5.474s)
[885.311799] Starting dynamic subtest: hugepages
[896.723296] Dynamic subtest hugepages: SUCCESS (11.419s)
[896.733917] Starting dynamic subtest: gem_contexts
[922.774289] Dynamic subtest gem_contexts: SUCCESS (26.044s)
[922.784774] Starting dynamic subtest: client
[927.776718] Dynamic subtest client: SUCCESS (4.999s)
[927.788409] Starting dynamic subtest: gem_migrate
[932.162837] Dynamic subtest gem_migrate: SUCCESS (4.383s)
[932.174382] Starting dynamic subtest: reset
[936.534757] Dynamic subtest reset: SUCCESS (4.370s)
[936.545457] Starting dynamic subtest: memory_region
[940.956994] Dynamic subtest memory_region: SUCCESS (4.417s)
[940.968546] Starting dynamic subtest: hangcheck
[956.680539] Dynamic subtest hangcheck: SUCCESS (15.718s)
[956.689397] Starting dynamic subtest: execlists
[960.988476] Dynamic subtest execlists: SUCCESS (4.305s)
[960.999827] Starting dynamic subtest: ring_submission
[965.354429] Dynamic subtest ring_submission: SUCCESS (4.363s)
[965.364853] Starting dynamic subtest: perf
[969.724149] Dynamic subtest perf: SUCCESS (4.369s)
[969.736295] Starting dynamic subtest: slpc
[984.379373] Dynamic subtest slpc: SUCCESS (14.655s)
[984.390154] Starting dynamic subtest: guc
[988.329247] Dynamic subtest guc: SUCCESS (3.948s)
[988.340919] Starting dynamic subtest: guc_multi_lrc
[992.742602] Dynamic subtest guc_multi_lrc: SUCCESS (4.411s)
[992.752469] Starting dynamic subtest: guc_hang
[996.273944] Dynamic subtest guc_hang: SUCCESS (3.529s)
[996.285887] Starting dynamic subtest: late_gt_pm
[1000.620588] Dynamic subtest late_gt_pm: SUCCESS (4.345s)
[1000.623234] Subtest live: SUCCESS (257.323s)
[1000.651433] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[1000.652135] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[1000.655572] [145/154] ( 25s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[1002.196675] Starting subtest: fbcpsr-tiling-linear
[1008.930403] Subtest fbcpsr-tiling-linear: SUCCESS (6.739s)
[1009.070638] [FACT kms_frontbuffer_tracking (fbcpsr-tiling-linear)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[1009.071825] [FACT kms_frontbuffer_tracking (fbcpsr-tiling-linear)] new: kernel.kmod_is_loaded.i915: true
[1009.075157] [146/154] ( 16s left) gem_ctx_isolation (dirty-create)
[1009.145502] Starting subtest: dirty-create
[1009.147048] Starting dynamic subtest: rcs0
[1009.351955] Dynamic subtest rcs0: SUCCESS (0.208s)
[1009.363470] Starting dynamic subtest: bcs0
[1009.587745] Dynamic subtest bcs0: SUCCESS (0.234s)
[1009.601567] Starting dynamic subtest: vcs0
[1009.826072] Dynamic subtest vcs0: SUCCESS (0.237s)
[1009.836051] Starting dynamic subtest: vcs1
[1011.214030] Dynamic subtest vcs1: SUCCESS (1.384s)
[1011.225619] Starting dynamic subtest: vecs0
[1013.259314] Dynamic subtest vecs0: SUCCESS (2.047s)
[1013.270297] Starting dynamic subtest: ccs0
[1015.243803] Dynamic subtest ccs0: SUCCESS (1.984s)
[1015.246152] Subtest dirty-create: SUCCESS (6.100s)
[1015.472397] [147/154] ( 10s left) gem_close (basic)
[1015.540757] Starting subtest: basic
[1015.543170] Subtest basic: SUCCESS (0.000s)
[1015.661653] [148/154] ( 10s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-pwrite)
[1015.750512] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-pwrite
[1015.754716] Subtest psrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[1015.831940] [149/154] (  9s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[1015.928515] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[1015.932335] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[1016.020788] [150/154] (  9s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[1016.117740] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[1023.020841] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.907s)
[1023.132329] [151/154] (  2s left) syncobj_wait (single-wait-submitted)
[1023.188520] Starting subtest: single-wait-submitted
[1023.297281] Subtest single-wait-submitted: SUCCESS (0.108s)
[1023.375645] [152/154] (  2s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[1023.482537] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[1023.486484] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[1023.579547] [153/154] (  2s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[1023.684011] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[1027.974046] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.296s)
[1029.080762] Overall timeout time exceeded, stopping.
[1029.097293] 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
results: no output, setting notrun
Done.