[57.811773] Initializing watchdogs
[57.811868]   /dev/watchdog0
[57.827490] [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]
[57.832015] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.834306] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.837758] [001/133] (960s left) kms_plane (pixel-format-y-tiled-modifier-source-clamping)
[57.965888] Starting subtest: pixel-format-y-tiled-modifier-source-clamping
[57.970070] Subtest pixel-format-y-tiled-modifier-source-clamping: SKIP (0.000s)
[58.063724] [002/133] (960s left) gem_mmap_gtt (fault-concurrent-y)
[58.127157] Subtest fault-concurrent-Y: SKIP (0.000s)
[58.180022] [003/133] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[58.283633] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[58.287922] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[58.393472] [004/133] (960s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[58.801244] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[59.625095] [005/133] (958s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[59.719465] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[59.721558] Starting dynamic subtest: pipe-A-eDP-1
[61.082871] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.364s)
[61.373462] Starting dynamic subtest: pipe-B-eDP-1
[62.416186] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.051s)
[62.681716] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.964s)
[63.527347] [006/133] (955s left) gem_mmap_wc (read)
[63.594089] Subtest read: SKIP (0.000s)
[63.661300] [007/133] (954s left) kms_dsc (dsc-basic)
[63.760098] Starting subtest: dsc-basic
[63.773020] Subtest dsc-basic: SKIP (0.000s)
[63.828566] [008/133] (954s left) kms_addfb_basic (unused-offsets)
[63.880059] Starting subtest: unused-offsets
[63.882485] Subtest unused-offsets: SUCCESS (0.000s)
[64.022228] [009/133] (954s left) gem_pxp (create-valid-protected-context)
[64.156995] Starting subtest: create-valid-protected-context
[64.159495] Subtest create-valid-protected-context: SUCCESS (0.002s)
[64.282200] [010/133] (954s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[64.376369] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[64.378717] Starting dynamic subtest: pipe-A-eDP-1
[65.349065] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.967s)
[65.358296] Starting dynamic subtest: pipe-B-eDP-1
[66.996063] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.645s)
[67.004218] Starting dynamic subtest: pipe-C-eDP-1
[68.579459] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.582s)
[68.588657] Starting dynamic subtest: pipe-D-eDP-1
[70.102606] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.523s)
[70.104726] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.720s)
[71.250971] [011/133] (947s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[71.351587] Starting subtest: fbcpsr-stridechange
[77.603202] Subtest fbcpsr-stridechange: SUCCESS (6.255s)
[77.725172] [012/133] (941s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[77.821047] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[77.825698] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[77.916582] [013/133] (940s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[78.025369] Starting subtest: psr2-plane-move-sf-dmg-area
[78.027989] Starting dynamic subtest: pipe-A-eDP-1
[82.531543] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.511s)
[82.540191] Starting dynamic subtest: pipe-B-eDP-1
[87.830805] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.298s)
[87.833144] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (9.811s)
[88.704691] [014/133] (930s left) kms_cursor_edge_walk (64x64-top-edge)
[88.799201] Starting subtest: 64x64-top-edge
[88.801615] Starting dynamic subtest: pipe-A-eDP-1
[92.268907] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.470s)
[92.277295] Starting dynamic subtest: pipe-D-eDP-1
[95.399486] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.129s)
[95.401694] Subtest 64x64-top-edge: SUCCESS (6.601s)
[96.208349] [015/133] (922s left) vgem_basic (dmabuf-mmap)
[96.285126] Starting subtest: dmabuf-mmap
[96.293986] Subtest dmabuf-mmap: SUCCESS (0.011s)
[96.356878] [FACT vgem_basic (dmabuf-mmap)] new: kernel.kmod_is_loaded.vgem: true
[96.360094] [016/133] (922s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[96.457378] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[96.459652] Starting dynamic subtest: pipe-A
[96.521975] Dynamic subtest pipe-A: SUCCESS (0.067s)
[96.532183] Starting dynamic subtest: pipe-B
[97.639915] Dynamic subtest pipe-B: SUCCESS (1.117s)
[97.650886] Starting dynamic subtest: pipe-C
[98.755314] Dynamic subtest pipe-C: SUCCESS (1.115s)
[98.763308] Starting dynamic subtest: pipe-D
[99.900558] Dynamic subtest pipe-D: SUCCESS (1.144s)
[99.902892] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.446s)
[101.048029] [017/133] (917s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[101.149946] Starting subtest: bad-rotation-90-yf-tiled-ccs
[101.152354] Starting dynamic subtest: pipe-A-eDP-1
[101.176078] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[101.186354] Starting dynamic subtest: pipe-B-eDP-1
[101.208608] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[101.216788] Starting dynamic subtest: pipe-C-eDP-1
[101.238973] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[101.246603] Starting dynamic subtest: pipe-D-eDP-1
[101.268572] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[101.273051] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.003s)
[103.417087] [018/133] (915s left) gem_ctx_exec (basic)
[103.471048] Starting subtest: basic
[103.476560] Subtest basic: SUCCESS (0.007s)
[103.596759] [019/133] (915s left) gem_exec_schedule (pi-shared-iova)
[103.790495] Starting subtest: pi-shared-iova
[103.792579] Starting dynamic subtest: rcs0
[103.867630] Dynamic subtest rcs0: SUCCESS (0.078s)
[103.880218] Starting dynamic subtest: vcs0
[103.928967] Dynamic subtest vcs0: SUCCESS (0.061s)
[103.939576] Starting dynamic subtest: vcs1
[103.986016] Dynamic subtest vcs1: SUCCESS (0.056s)
[103.995506] Starting dynamic subtest: vecs0
[104.038812] Dynamic subtest vecs0: SUCCESS (0.053s)
[104.049865] Starting dynamic subtest: ccs0
[104.094105] Dynamic subtest ccs0: SUCCESS (0.055s)
[104.096627] Subtest pi-shared-iova: SUCCESS (0.307s)
[105.387211] [020/133] (913s left) device_reset (unbind-reset-rebind)
[105.440567] Starting subtest: unbind-reset-rebind
[110.310902] Subtest unbind-reset-rebind: SUCCESS (4.866s)
[110.353290] [021/133] (908s left) syncobj_timeline (wait-all-snapshot)
[110.418695] Starting subtest: wait-all-snapshot
[110.499504] Subtest wait-all-snapshot: SUCCESS (0.082s)
[110.574707] [022/133] (908s left) gem_mmap_gtt (medium-copy-xy)
[110.635788] Subtest medium-copy-XY: SKIP (0.000s)
[110.703881] [023/133] (908s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[110.756758] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[110.759025] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[110.854137] [024/133] (908s left) perf_pmu (busy-check-all)
[110.896818] Starting subtest: busy-check-all
[110.898953] Starting dynamic subtest: rcs0
[111.452059] Dynamic subtest rcs0: SUCCESS (0.554s)
[111.467078] Starting dynamic subtest: bcs0
[112.003850] Dynamic subtest bcs0: SUCCESS (0.549s)
[112.019768] Starting dynamic subtest: vcs0
[113.293974] Dynamic subtest vcs0: SUCCESS (1.287s)
[113.309673] Starting dynamic subtest: vcs1
[115.275892] Dynamic subtest vcs1: SUCCESS (1.979s)
[115.288844] Starting dynamic subtest: vecs0
[117.323682] Dynamic subtest vecs0: SUCCESS (2.046s)
[117.336762] Starting dynamic subtest: ccs0
[117.879491] Dynamic subtest ccs0: SUCCESS (0.556s)
[117.882018] Subtest busy-check-all: SUCCESS (6.984s)
[117.985944] [025/133] (901s left) syncobj_timeline (invalid-query-illegal-handle)
[118.037761] Starting subtest: invalid-query-illegal-handle
[118.040122] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[118.132959] [026/133] (901s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions)
[118.256445] Starting subtest: cursorA-vs-flipA-atomic-transitions
[129.457970] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.201s)
[130.309580] [027/133] (889s left) gem_tiled_swapping (non-threaded)
[130.380322] Subtest non-threaded: SKIP (0.000s)
[130.449850] [028/133] (889s left) kms_plane_multiple (tiling-x)
[130.549784] Starting subtest: tiling-x
[130.552041] Starting dynamic subtest: pipe-A-eDP-1
[134.354190] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.802s)
[134.364570] Starting dynamic subtest: pipe-B-eDP-1
[138.903454] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.549s)
[138.912998] Starting dynamic subtest: pipe-C-eDP-1
[143.235971] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.331s)
[143.248754] Starting dynamic subtest: pipe-D-eDP-1
[147.576693] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.341s)
[147.579058] Subtest tiling-x: SUCCESS (17.029s)
[148.399844] [029/133] (871s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[148.508211] Starting subtest: yf-tiled-16bpp-rotate-270
[148.512076] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[148.609026] [030/133] (870s left) kms_psr (pr-cursor-mmap-cpu)
[148.703079] Starting subtest: pr-cursor-mmap-cpu
[148.707554] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[148.777210] [031/133] (870s left) gem_ctx_param (invalid-get-ringsize)
[148.824924] Starting subtest: invalid-get-ringsize
[148.827223] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[148.927398] [032/133] (870s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[149.026199] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[155.700525] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.676s)
[155.843474] [033/133] (863s left) gem_exec_whisper (basic-fds-all)
[155.906544] Starting subtest: basic-fds-all
[167.189813] Subtest basic-fds-all: SUCCESS (11.282s)
[167.374803] [034/133] (852s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[167.488157] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[167.492407] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[167.598859] [035/133] (852s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[167.650067] Starting subtest: invalid-multi-wait-unsubmitted
[167.652396] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[167.741358] [036/133] (852s left) gem_mmap_offset (close-race)
[167.798721] Starting subtest: close-race
[187.961180] Subtest close-race: SUCCESS (20.110s)
[188.048509] [037/133] (831s left) kms_psr (psr-cursor-render)
[188.157174] Starting subtest: psr-cursor-render
[188.159595] Starting dynamic subtest: eDP-1
[189.848709] Dynamic subtest eDP-1: SUCCESS (1.691s)
[189.851426] Subtest psr-cursor-render: SUCCESS (1.692s)
[190.722415] [038/133] (829s left) perf_pmu (interrupts)
[190.764476] Starting subtest: interrupts
[191.845094] Subtest interrupts: SUCCESS (1.080s)
[191.968192] [039/133] (827s left) api_intel_allocator (alloc-simple)
[192.016216] Starting subtest: alloc-simple
[192.020589] Subtest alloc-simple: SUCCESS (0.005s)
[192.186469] [040/133] (827s left) kms_psr (psr-primary-blt)
[192.274883] Starting subtest: psr-primary-blt
[192.277296] Starting dynamic subtest: eDP-1
[193.997428] Dynamic subtest eDP-1: SUCCESS (1.721s)
[193.999825] Subtest psr-primary-blt: SUCCESS (1.722s)
[194.874954] [041/133] (825s left) kms_cursor_crc (cursor-random-512x170)
[195.167794] Starting subtest: cursor-random-512x170
[195.172333] Subtest cursor-random-512x170: SKIP (0.001s)
[195.307733] [042/133] (824s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[195.360873] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[195.462098] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.103s)
[195.552422] [043/133] (824s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[197.503313] Starting subtest: gem-execbuf-stress-pc8
[197.547615] Subtest gem-execbuf-stress-pc8: SKIP (0.042s)
[197.886709] [044/133] (822s left) gem_reg_read (bad-register)
[197.938206] Starting subtest: bad-register
[197.940540] Subtest bad-register: SUCCESS (0.000s)
[198.039406] [045/133] (822s left) gem_mmap_gtt (basic-small-copy-odd)
[198.089331] Subtest basic-small-copy-odd: SKIP (0.000s)
[198.150032] [046/133] (822s left) i915_hangman (gt-engine-hang)
[198.217055] Starting subtest: gt-engine-hang
[198.219245] Starting dynamic subtest: rcs0
[199.277390] Dynamic subtest rcs0: SUCCESS (1.058s)
[199.290710] Starting dynamic subtest: bcs0
[201.256682] Dynamic subtest bcs0: SUCCESS (1.977s)
[201.270424] Starting dynamic subtest: vcs0
[203.241462] Dynamic subtest vcs0: SUCCESS (1.981s)
[203.255699] Starting dynamic subtest: vcs1
[205.289939] Dynamic subtest vcs1: SUCCESS (2.046s)
[205.305724] Starting dynamic subtest: vecs0
[207.272104] Dynamic subtest vecs0: SUCCESS (1.979s)
[207.284625] Starting dynamic subtest: ccs0
[209.256857] Dynamic subtest ccs0: SUCCESS (1.981s)
[209.260748] Subtest gt-engine-hang: SUCCESS (11.040s)
[209.407168] [047/133] (810s left) fbdev (pan)
[209.445625] Starting subtest: pan
[209.475565] Subtest pan: SUCCESS (0.032s)
[209.547903] [048/133] (810s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[209.654656] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[210.752664] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: FAIL (0.500s)
[210.794569] [049/133] (809s left) prime_self_import (basic-with_one_bo)
[210.828614] Starting subtest: basic-with_one_bo
[210.847528] Subtest basic-with_one_bo: SUCCESS (0.020s)
[210.994088] [050/133] (809s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[211.092526] Starting subtest: 2x-flip-vs-modeset-vs-hang
[211.096249] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.002s)
[211.203917] [051/133] (809s left) kms_flip (2x-flip-vs-suspend)
[211.297361] Starting subtest: 2x-flip-vs-suspend
[211.301328] Subtest 2x-flip-vs-suspend: SKIP (0.002s)
[211.406936] [052/133] (809s left) i915_query (query-garbage)
[211.466068] Starting subtest: query-garbage
[211.468282] Subtest query-garbage: SUCCESS (0.000s)
[211.571417] [053/133] (808s left) kms_pm_dc (dc5-retention-flops)
[212.678979] Starting subtest: dc5-retention-flops
[212.683302] Subtest dc5-retention-flops: SKIP (0.000s)
[212.758063] [054/133] (807s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[212.864771] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[212.869264] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[212.971138] [055/133] (807s left) kms_atomic_interruptible (legacy-dpms)
[213.064578] Starting subtest: legacy-dpms
[213.066853] Starting dynamic subtest: pipe-A-eDP-1
[220.790462] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.727s)
[220.792664] Subtest legacy-dpms: SUCCESS (7.728s)
[221.633230] [056/133] (799s left) gem_basic (create-close)
[221.698071] Starting subtest: create-close
[221.702422] Subtest create-close: SUCCESS (0.000s)
[221.810774] [057/133] (798s left) sysfs_timeslice_duration (idempotent)
[221.865678] Starting subtest: idempotent
[221.868042] Starting dynamic subtest: vecs0
[221.870323] Dynamic subtest vecs0: SUCCESS (0.000s)
[221.881017] Starting dynamic subtest: ccs0
[221.882914] Dynamic subtest ccs0: SUCCESS (0.000s)
[221.892624] Starting dynamic subtest: vcs0
[221.894538] Dynamic subtest vcs0: SUCCESS (0.000s)
[221.904319] Starting dynamic subtest: bcs0
[221.906208] Dynamic subtest bcs0: SUCCESS (0.000s)
[221.915921] Starting dynamic subtest: rcs0
[221.917893] Dynamic subtest rcs0: SUCCESS (0.000s)
[221.927955] Starting dynamic subtest: vcs1
[221.930068] Dynamic subtest vcs1: SUCCESS (0.000s)
[221.931968] Subtest idempotent: SUCCESS (0.006s)
[222.002425] [058/133] (798s left) kms_cursor_crc (cursor-offscreen-128x128)
[222.199478] Starting subtest: cursor-offscreen-128x128
[222.204480] Starting dynamic subtest: pipe-A-eDP-1
[224.148628] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.942s)
[224.178907] Starting dynamic subtest: pipe-D-eDP-1
[226.731319] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.559s)
[226.733587] Subtest cursor-offscreen-128x128: SUCCESS (4.530s)
[227.575109] [059/133] (793s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[227.669415] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[227.673606] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[227.766368] [060/133] (793s left) gem_pxp (regular-baseline-src-copy-readible)
[227.880942] Starting subtest: regular-baseline-src-copy-readible
[227.938802] Subtest regular-baseline-src-copy-readible: SUCCESS (0.061s)
[228.066071] [061/133] (792s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[228.161755] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[228.166267] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[228.239626] [062/133] (792s left) gem_exec_schedule (fairslice)
[228.309254] Starting subtest: fairslice
[228.311108] Starting dynamic subtest: rcs0
[230.324755] Dynamic subtest rcs0: SUCCESS (2.022s)
[230.336221] Starting dynamic subtest: bcs0
[232.350644] Dynamic subtest bcs0: SUCCESS (2.024s)
[232.362510] Starting dynamic subtest: vcs0
[234.371074] Dynamic subtest vcs0: SUCCESS (2.020s)
[234.382919] Starting dynamic subtest: vcs1
[236.390545] Dynamic subtest vcs1: SUCCESS (2.019s)
[236.403581] Starting dynamic subtest: vecs0
[238.459437] Dynamic subtest vecs0: SUCCESS (2.014s)
[238.471252] Starting dynamic subtest: ccs0
[240.430457] Dynamic subtest ccs0: SUCCESS (2.022s)
[240.432472] Subtest fairslice: SUCCESS (12.128s)
[240.748293] [063/133] (780s left) kms_frontbuffer_tracking (psr-2p-rte)
[240.873584] Starting subtest: psr-2p-rte
[240.878049] Subtest psr-2p-rte: SKIP (0.000s)
[240.999967] [064/133] (780s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[241.104898] Starting subtest: plane-lut1d-pre-ctm3x4
[241.251087] Starting dynamic subtest: pipe-A-plane-0
[241.417688] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[241.430737] Starting dynamic subtest: pipe-A-plane-1
[241.575079] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[241.585555] Starting dynamic subtest: pipe-A-plane-2
[241.761461] Dynamic subtest pipe-A-plane-2: SUCCESS (0.188s)
[242.999947] Starting dynamic subtest: pipe-B-plane-0
[243.190381] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[243.201650] Starting dynamic subtest: pipe-B-plane-1
[243.379073] Dynamic subtest pipe-B-plane-1: SUCCESS (0.188s)
[243.390753] Starting dynamic subtest: pipe-B-plane-2
[243.568284] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s)
[244.800117] Starting dynamic subtest: pipe-C-plane-0
[244.957908] Dynamic subtest pipe-C-plane-0: SUCCESS (0.166s)
[244.969429] Starting dynamic subtest: pipe-C-plane-1
[245.116302] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[245.126671] Starting dynamic subtest: pipe-C-plane-2
[245.269112] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[246.445257] Starting dynamic subtest: pipe-D-plane-0
[246.600794] Dynamic subtest pipe-D-plane-0: SUCCESS (0.167s)
[246.616798] Starting dynamic subtest: pipe-D-plane-1
[246.767040] Dynamic subtest pipe-D-plane-1: SUCCESS (0.164s)
[246.778164] Starting dynamic subtest: pipe-D-plane-2
[246.911510] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[247.198908] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (6.093s)
[248.006915] [065/133] (773s left) gem_exec_balancer (semaphore)
[248.061487] Starting subtest: semaphore
[249.264433] Subtest semaphore: SUCCESS (1.201s)
[249.587522] [066/133] (771s left) kms_content_protection (dp-mst-type-0)
[249.741740] Starting subtest: dp-mst-type-0
[249.747056] Subtest dp-mst-type-0: SKIP (0.000s)
[249.868387] [067/133] (771s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[249.973774] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[249.978028] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[250.062910] [068/133] (771s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[250.177203] Starting subtest: 2x-flip-vs-cursor-atomic
[250.181967] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[250.338120] [069/133] (770s left) kms_lease (lease-invalid-crtc)
[250.427811] Starting subtest: lease-invalid-crtc
[250.430079] Starting dynamic subtest: pipe-A-eDP-1
[250.432206] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[250.442484] Starting dynamic subtest: pipe-B-eDP-1
[250.444730] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[250.455210] Starting dynamic subtest: pipe-C-eDP-1
[250.457071] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[250.466739] Starting dynamic subtest: pipe-D-eDP-1
[250.468893] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[250.470738] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[250.508145] [070/133] (770s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[250.630207] Starting subtest: cursor-vs-flip-atomic-transitions
[281.081218] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.447s)
[281.929439] [071/133] (739s left) gem_render_copy (yf-tiled-to-vebox-y-tiled)
[281.990948] Starting subtest: yf-tiled-to-vebox-y-tiled
[281.995409] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s)
[282.188322] [072/133] (739s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[282.279257] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[282.281671] Starting dynamic subtest: pipe-A-default-mode
[283.404466] Dynamic subtest pipe-A-default-mode: SKIP (1.124s)
[283.408699] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.125s)
[283.549429] [073/133] (737s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[283.598389] Starting subtest: etime-single-wait-available-unsubmitted
[283.703609] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.102s)
[283.780924] [074/133] (737s left) kms_chamelium_color (ctm-0-50)
[283.880435] Subtest ctm-0-50: SKIP (0.000s)
[283.927518] [075/133] (737s left) kms_invalid_mode (overflow-vrefresh)
[284.016935] Starting subtest: overflow-vrefresh
[284.020106] Starting dynamic subtest: pipe-A-eDP-1
[284.316236] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.296s)
[284.329319] Starting dynamic subtest: pipe-B-eDP-1
[284.331306] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[284.341745] Starting dynamic subtest: pipe-C-eDP-1
[284.344160] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[284.355802] Starting dynamic subtest: pipe-D-eDP-1
[284.358086] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[284.360258] Subtest overflow-vrefresh: SUCCESS (0.302s)
[285.124504] [076/133] (736s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[285.224828] Starting subtest: psr-1p-primscrn-spr-indfb-move
[292.660191] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (7.438s)
[292.811930] [077/133] (728s left) gem_create (hog-create)
[292.863357] Starting subtest: hog-create
[292.865606] Starting dynamic subtest: smem0
[326.289298] Dynamic subtest smem0: SUCCESS (33.421s)
[326.292335] Subtest hog-create: SUCCESS (33.424s)
[326.478258] [078/133] (695s left) gem_unref_active_buffers
[326.704428] [079/133] (694s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[326.816936] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[326.821271] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[326.902336] [080/133] (694s left) kms_big_fb (y-tiled-addfb-size-overflow)
[327.011347] Starting subtest: y-tiled-addfb-size-overflow
[327.015638] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[327.119779] [081/133] (694s left) gem_exec_reloc (basic-write-cpu-active)
[327.175951] Subtest basic-write-cpu-active: SKIP (0.000s)
[327.247126] [082/133] (694s left) kms_pm_rpm (legacy-planes-dpms)
[329.475492] Starting subtest: legacy-planes-dpms
[329.478108] Starting dynamic subtest: plane-65
[337.822574] Dynamic subtest plane-65: SUCCESS (8.350s)
[337.835692] Starting dynamic subtest: plane-95
[346.172556] Dynamic subtest plane-95: SUCCESS (8.347s)
[346.186329] Starting dynamic subtest: plane-125
[354.476348] Dynamic subtest plane-125: SUCCESS (8.300s)
[354.488483] Starting dynamic subtest: plane-135
[362.802865] Dynamic subtest plane-135: SUCCESS (8.323s)
[362.806029] Subtest legacy-planes-dpms: SUCCESS (33.331s)
[363.198447] [083/133] (658s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[363.303329] Starting subtest: disable-crc-after-crtc
[363.305333] Starting dynamic subtest: pipe-A-eDP-1
[365.022871] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.718s)
[365.034251] Starting dynamic subtest: pipe-B-eDP-1
[367.372657] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.350s)
[367.383284] Starting dynamic subtest: pipe-C-eDP-1
[369.615438] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.240s)
[369.626972] Starting dynamic subtest: pipe-D-eDP-1
[371.885038] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.269s)
[371.887265] Subtest disable-crc-after-crtc: SUCCESS (8.583s)
[372.729189] [084/133] (649s left) prime_udl
[372.817843] [085/133] (649s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[372.922706] Starting subtest: fbcpsr-modesetfrombusy
[379.110942] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.192s)
[379.262256] [086/133] (642s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[379.360512] Starting subtest: x-tiled-8bpp-rotate-270
[379.381937] Subtest x-tiled-8bpp-rotate-270: SKIP (0.021s)
[379.487867] [087/133] (642s left) gem_exec_fence (syncobj-repeat)
[379.949607] Starting subtest: syncobj-repeat
[380.140795] Subtest syncobj-repeat: SUCCESS (0.193s)
[381.345666] [088/133] (640s left) gem_softpin (zero)
[381.405702] Starting subtest: zero
[381.410443] Subtest zero: SUCCESS (0.004s)
[381.537428] [089/133] (640s left) gem_ctx_persistence (heartbeat-hostile)
[381.605022] Starting subtest: heartbeat-hostile
[381.609641] Subtest heartbeat-hostile: SKIP (0.000s)
[381.716086] [090/133] (640s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[381.810669] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[381.815164] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[381.920899] [091/133] (640s left) kms_chamelium_frames (hdmi-crc-multiple)
[382.309165] Subtest hdmi-crc-multiple: SKIP (0.000s)
[383.089522] [092/133] (639s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[383.178087] Starting subtest: async-flip-with-page-flip-events-linear
[383.563496] Starting dynamic subtest: pipe-A-eDP-1
[386.302036] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.737s)
[386.663940] Starting dynamic subtest: pipe-B-eDP-1
[389.484187] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.775s)
[389.808891] Starting dynamic subtest: pipe-C-eDP-1
[392.560281] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.758s)
[392.921396] Starting dynamic subtest: pipe-D-eDP-1
[395.689688] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.776s)
[395.692842] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.512s)
[396.895033] [093/133] (625s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[396.998777] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[397.003274] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[397.068892] [094/133] (625s left) gem_exec_params (secure-non-master)
[397.125766] Starting subtest: secure-non-master
[397.130213] Subtest secure-non-master: SKIP (0.000s)
[397.251158] [095/133] (625s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt)
[397.361789] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt
[397.366206] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[397.465144] [096/133] (624s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[397.571664] Starting subtest: fbc-rgb101010-draw-render
[402.187964] Subtest fbc-rgb101010-draw-render: SUCCESS (4.617s)
[403.382337] [097/133] (619s left) api_intel_bb (reset-flags)
[403.444449] Starting subtest: reset-flags
[403.451953] Subtest reset-flags: SUCCESS (0.008s)
[403.574704] [098/133] (618s left) syncobj_wait (single-wait-submitted)
[403.627833] Starting subtest: single-wait-submitted
[403.735196] Subtest single-wait-submitted: SUCCESS (0.106s)
[403.825537] [099/133] (618s left) i915_query (engine-info)
[403.875068] Starting subtest: engine-info
[403.877295] Subtest engine-info: SUCCESS (0.004s)
[403.999917] [100/133] (618s left) perf_pmu (all-busy-check-all)
[404.035668] Starting subtest: all-busy-check-all
[405.325091] Subtest all-busy-check-all: SUCCESS (1.290s)
[405.443333] [101/133] (617s left) syncobj_basic (bad-flags-fd-to-handle)
[405.491254] Starting subtest: bad-flags-fd-to-handle
[405.493420] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[405.592999] [102/133] (617s left) gem_exec_schedule (u-submit-golden-slice)
[405.663436] Starting subtest: u-submit-golden-slice
[405.665607] Starting dynamic subtest: rcs0
[405.692973] Dynamic subtest rcs0: SUCCESS (0.036s)
[405.703700] Starting dynamic subtest: bcs0
[405.724452] Dynamic subtest bcs0: SUCCESS (0.030s)
[405.741422] Starting dynamic subtest: vcs0
[405.757882] Dynamic subtest vcs0: SUCCESS (0.033s)
[405.769909] Starting dynamic subtest: vcs1
[405.789398] Dynamic subtest vcs1: SUCCESS (0.031s)
[405.803587] Starting dynamic subtest: vecs0
[405.816766] Dynamic subtest vecs0: SUCCESS (0.027s)
[405.828033] Starting dynamic subtest: ccs0
[405.850710] Dynamic subtest ccs0: SUCCESS (0.033s)
[405.853094] Subtest u-submit-golden-slice: SUCCESS (0.195s)
[407.403988] [103/133] (615s left) i915_drm_fdinfo (memory-info-shared)
[407.444504] Starting subtest: memory-info-shared
[407.446849] Starting dynamic subtest: smem0
[407.498688] Dynamic subtest smem0: SUCCESS (0.055s)
[407.501252] Subtest memory-info-shared: SUCCESS (0.055s)
[407.594762] [104/133] (615s left) gem_mmap_wc (write-read-distinct)
[407.651075] Subtest write-read-distinct: SKIP (0.000s)
[407.714690] [105/133] (615s left) kms_selftest (drm_damage_helper)
[407.748111] Starting subtest: drm_damage_helper
[407.763551] Starting dynamic subtest: drm_test_damage_iter_no_damage
[407.869062] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.106s)
[407.879437] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[407.960090] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.091s)
[407.971436] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[408.071499] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.110s)
[408.083259] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[408.160783] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.090s)
[408.171071] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[408.256420] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.094s)
[408.266922] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[408.341431] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.082s)
[408.351907] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[408.435619] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.093s)
[408.446159] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[408.531263] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.095s)
[408.542973] Starting dynamic subtest: drm_test_damage_iter_single_damage
[408.640225] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.108s)
[408.651831] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[408.767631] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.125s)
[408.777882] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[408.864568] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.098s)
[408.876546] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[408.962340] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.097s)
[408.974602] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[409.074311] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.108s)
[409.084956] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[409.162061] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.086s)
[409.174425] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[409.263656] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.103s)
[409.273955] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[409.375871] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.111s)
[409.386231] Starting dynamic subtest: drm_test_damage_iter_damage
[409.479592] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.101s)
[409.491095] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[409.570457] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.091s)
[409.580673] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[409.671703] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.099s)
[409.682477] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[409.766276] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.094s)
[409.776360] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[409.851237] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.085s)
[409.852944] Subtest drm_damage_helper: SUCCESS (2.104s)
[410.024887] [106/133] (612s left) prime_mmap (test_correct_cpu_write)
[410.079024] Starting subtest: test_correct_cpu_write
[410.081528] Starting dynamic subtest: test_correct_cpu_write-smem
[410.083721] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[410.085979] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[410.197519] [107/133] (612s left) gen9_exec_parse (secure-batches)
[410.258771] Subtest secure-batches: SKIP (0.000s)
[410.343360] [108/133] (612s left) kms_atomic (plane-immutable-zpos)
[410.440328] Starting subtest: plane-immutable-zpos
[410.442548] Starting dynamic subtest: pipe-A-eDP-1
[411.076895] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.635s)
[411.079185] Subtest plane-immutable-zpos: SUCCESS (0.637s)
[411.892806] [109/133] (611s left) gem_exec_nop (basic-parallel)
[411.966667] Starting subtest: basic-parallel
[427.339659] Subtest basic-parallel: SUCCESS (15.383s)
[427.572750] [110/133] (595s left) kms_psr (fbc-psr-sprite-render)
[427.681086] Starting subtest: fbc-psr-sprite-render
[427.683467] Starting dynamic subtest: eDP-1
[427.688037] Dynamic subtest eDP-1: SKIP (0.000s)
[427.692545] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[427.771265] [111/133] (595s left) gem_fenced_exec_thrash (2-spare-fences)
[427.815848] Subtest 2-spare-fences: SKIP (0.000s)
[427.894286] [112/133] (595s left) perf_pmu (idle)
[427.934234] Starting subtest: idle
[427.936527] Starting dynamic subtest: rcs0
[428.482340] Dynamic subtest rcs0: SUCCESS (0.546s)
[428.496175] Starting dynamic subtest: bcs0
[429.001956] Dynamic subtest bcs0: SUCCESS (0.517s)
[429.015953] Starting dynamic subtest: vcs0
[429.519995] Dynamic subtest vcs0: SUCCESS (0.515s)
[429.532973] Starting dynamic subtest: vcs1
[430.039323] Dynamic subtest vcs1: SUCCESS (0.517s)
[430.053079] Starting dynamic subtest: vecs0
[430.563093] Dynamic subtest vecs0: SUCCESS (0.521s)
[430.577100] Starting dynamic subtest: ccs0
[431.080945] Dynamic subtest ccs0: SUCCESS (0.515s)
[431.084066] Subtest idle: SUCCESS (3.147s)
[431.186336] [113/133] (591s left) kms_vblank (wait-busy-hang)
[431.286983] Starting subtest: wait-busy-hang
[431.289266] Starting dynamic subtest: pipe-A-eDP-1
[458.066871] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.777s)
[458.085259] Starting dynamic subtest: pipe-D-eDP-1
[473.092162] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.024s)
[473.094471] Subtest wait-busy-hang: SUCCESS (41.807s)
[473.949980] [114/133] (549s left) gem_exec_balancer (noheartbeat)
[474.215390] Starting subtest: noheartbeat
[474.219999] Subtest noheartbeat: SKIP (0.000s)
[474.328257] [115/133] (548s left) kms_hdr (brightness-with-hdr)
[474.422045] Starting subtest: brightness-with-hdr
[474.428625] Subtest brightness-with-hdr: SKIP (0.000s)
[474.494950] [116/133] (548s left) kms_flip (blocking-wf_vblank)
[474.587653] Starting subtest: blocking-wf_vblank
[474.590025] Starting dynamic subtest: A-eDP1
[483.529786] Dynamic subtest A-eDP1: SUCCESS (8.910s)
[483.540864] Starting dynamic subtest: B-eDP1
[492.451782] Dynamic subtest B-eDP1: SUCCESS (8.921s)
[492.465431] Starting dynamic subtest: C-eDP1
[501.341986] Dynamic subtest C-eDP1: SUCCESS (8.922s)
[501.356533] Starting dynamic subtest: D-eDP1
[510.271384] Dynamic subtest D-eDP1: SUCCESS (8.925s)
[510.273756] Subtest blocking-wf_vblank: SUCCESS (35.688s)
[511.513853] [117/133] (511s left) kms_plane (pixel-format-linear-modifier-source-clamping)
[511.605160] Starting subtest: pixel-format-linear-modifier-source-clamping
[511.631947] Starting dynamic subtest: pipe-A-plane-0
[512.209336] Dynamic subtest pipe-A-plane-0: SUCCESS (0.578s)
[512.220897] Starting dynamic subtest: pipe-A-plane-3
[512.653483] Dynamic subtest pipe-A-plane-3: SUCCESS (0.444s)
[513.809420] Starting dynamic subtest: pipe-B-plane-0
[514.399561] Dynamic subtest pipe-B-plane-0: SUCCESS (0.600s)
[514.410486] Starting dynamic subtest: pipe-B-plane-3
[514.832331] Dynamic subtest pipe-B-plane-3: SUCCESS (0.433s)
[515.162509] Subtest pixel-format-linear-modifier-source-clamping: SUCCESS (3.557s)
[516.001203] [118/133] (507s left) gem_lmem_swapping (heavy-verify-multi)
[516.045647] Subtest heavy-verify-multi: SKIP (0.000s)
[516.088051] [119/133] (507s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[516.183534] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[520.743646] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.563s)
[521.896513] [120/133] (501s left) kms_big_fb (4-tiled-addfb-size-overflow)
[521.993668] Starting subtest: 4-tiled-addfb-size-overflow
[521.995998] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[522.092091] [121/133] (501s left) gem_vm_create (invalid-create)
[522.143495] Starting subtest: invalid-create
[522.148721] Subtest invalid-create: SUCCESS (0.002s)
[522.257247] [122/133] (501s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[522.344098] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[522.346351] Starting dynamic subtest: pipe-A-eDP-1
[522.370893] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[522.383554] Starting dynamic subtest: pipe-B-eDP-1
[522.407395] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[522.419475] Starting dynamic subtest: pipe-C-eDP-1
[522.443724] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[522.455360] Starting dynamic subtest: pipe-D-eDP-1
[522.479519] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[522.483585] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[524.654406] [123/133] (498s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[524.761745] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[531.858439] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.102s)
[531.984271] [124/133] (491s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[532.072867] Starting subtest: fbc-psr2-cursor-mmap-cpu
[532.075111] Starting dynamic subtest: eDP-1
[532.079324] Dynamic subtest eDP-1: SKIP (0.000s)
[532.083540] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.001s)
[532.147284] [125/133] (491s left) syncobj_basic (bad-create-flags)
[532.197637] Starting subtest: bad-create-flags
[532.199634] Subtest bad-create-flags: SUCCESS (0.000s)
[532.312177] [126/133] (491s left) perf (enable-disable)
[532.451600] Starting subtest: enable-disable
[532.453930] Starting dynamic subtest: 0-rcs0
[534.721581] Dynamic subtest 0-rcs0: SUCCESS (2.269s)
[534.734246] Starting dynamic subtest: 1-vcs0
[539.263986] Dynamic subtest 1-vcs0: SUCCESS (4.487s)
[539.266564] Subtest enable-disable: SUCCESS (6.759s)
[539.308100] [127/133] (484s left) gem_ctx_shared (q-smoketest)
[539.381765] Starting subtest: Q-smoketest
[539.383987] Starting dynamic subtest: rcs0
[544.996746] Dynamic subtest rcs0: SUCCESS (5.513s)
[545.010609] Starting dynamic subtest: bcs0
[550.485057] Dynamic subtest bcs0: SUCCESS (5.485s)
[550.498084] Starting dynamic subtest: vcs0
[555.963072] Dynamic subtest vcs0: SUCCESS (5.480s)
[555.976255] Starting dynamic subtest: vcs1
[561.389026] Dynamic subtest vcs1: SUCCESS (5.475s)
[561.401961] Starting dynamic subtest: vecs0
[566.942747] Dynamic subtest vecs0: SUCCESS (5.499s)
[566.955904] Starting dynamic subtest: ccs0
[572.393344] Dynamic subtest ccs0: SUCCESS (5.502s)
[572.395346] Subtest Q-smoketest: SUCCESS (32.961s)
[572.551915] [128/133] (451s left) gem_exec_flush (basic-wb-rw-before-default)
[572.627036] Starting subtest: basic-wb-rw-before-default
[578.118329] Subtest basic-wb-rw-before-default: SUCCESS (5.464s)
[578.276620] [129/133] (445s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[578.381253] Starting subtest: psr-rgb565-draw-mmap-wc
[585.305396] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.925s)
[585.408338] [130/133] (438s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[585.500199] Starting subtest: crc-primary-basic-yf-tiled-ccs
[585.502491] Starting dynamic subtest: pipe-A-eDP-1
[585.525726] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[585.538802] Starting dynamic subtest: pipe-B-eDP-1
[585.560775] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[585.572545] Starting dynamic subtest: pipe-C-eDP-1
[585.594438] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[585.605538] Starting dynamic subtest: pipe-D-eDP-1
[585.628373] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[585.632408] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.006s)
[587.783053] [131/133] (436s left) kms_cursor_legacy (forked-move)
[587.902446] Starting subtest: forked-move
[587.904370] Starting dynamic subtest: pipe-A
[593.421184] Dynamic subtest pipe-A: SUCCESS (5.462s)
[593.433567] Starting dynamic subtest: pipe-B
[598.883987] Dynamic subtest pipe-B: SUCCESS (5.462s)
[598.900667] Starting dynamic subtest: pipe-C
[604.369858] Dynamic subtest pipe-C: SUCCESS (5.483s)
[604.383389] Starting dynamic subtest: pipe-D
[609.843273] Dynamic subtest pipe-D: SUCCESS (5.469s)
[609.862907] Starting dynamic subtest: all-pipes
[615.297767] Dynamic subtest all-pipes: SUCCESS (5.503s)
[615.300319] Subtest forked-move: SUCCESS (27.391s)
[615.450300] [132/133] (408s left) prime_vgem (fence-wait)
[615.535306] Starting subtest: fence-wait
[615.537461] Starting dynamic subtest: rcs0
[616.590988] Dynamic subtest rcs0: SUCCESS (1.054s)
[616.603704] Starting dynamic subtest: bcs0
[617.661599] Dynamic subtest bcs0: SUCCESS (1.070s)
[617.674360] Starting dynamic subtest: vcs0
[618.703312] Dynamic subtest vcs0: SUCCESS (1.041s)
[618.719233] Starting dynamic subtest: vcs1
[619.745478] Dynamic subtest vcs1: SUCCESS (1.040s)
[619.759451] Starting dynamic subtest: vecs0
[620.787391] Dynamic subtest vecs0: SUCCESS (1.040s)
[620.801071] Starting dynamic subtest: ccs0
[621.831263] Dynamic subtest ccs0: SUCCESS (1.041s)
[621.833919] Subtest fence-wait: SUCCESS (6.296s)
[621.956250] [133/133] (402s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[622.056528] Starting subtest: 2x-absolute-wf_vblank-interruptible
[622.069222] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.012s)
[622.151898] Closing watchdogs
results: parsing output: 0/ for test: kms_plane
results: parsing output: 1/ for test: gem_mmap_gtt
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_chamelium_hpd
results: parsing output: 4/ for test: kms_atomic_transition
results: parsing output: 5/ for test: gem_mmap_wc
results: parsing output: 6/ for test: kms_dsc
results: parsing output: 7/ for test: kms_addfb_basic
results: parsing output: 8/ for test: gem_pxp
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_cursor_edge_walk
results: parsing output: 14/ for test: vgem_basic
results: parsing output: 15/ for test: kms_plane_scaling
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: gem_ctx_exec
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: device_reset
results: parsing output: 20/ for test: syncobj_timeline
results: parsing output: 21/ for test: gem_mmap_gtt
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: perf_pmu
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: kms_cursor_legacy
results: parsing output: 26/ for test: gem_tiled_swapping
results: parsing output: 27/ for test: kms_plane_multiple
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_ctx_param
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_exec_whisper
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: gem_mmap_offset
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: perf_pmu
results: parsing output: 38/ for test: api_intel_allocator
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: kms_cursor_crc
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: i915_pm_rpm
results: parsing output: 43/ for test: gem_reg_read
results: parsing output: 44/ for test: gem_mmap_gtt
results: parsing output: 45/ for test: i915_hangman
results: parsing output: 46/ for test: fbdev
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: prime_self_import
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: i915_query
results: parsing output: 52/ for test: kms_pm_dc
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_atomic_interruptible
results: parsing output: 55/ for test: gem_basic
results: parsing output: 56/ for test: sysfs_timeslice_duration
results: parsing output: 57/ for test: kms_cursor_crc
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_pxp
results: parsing output: 60/ for test: kms_psr2_sf
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_color_pipeline
results: parsing output: 64/ for test: gem_exec_balancer
results: parsing output: 65/ for test: kms_content_protection
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_cursor_legacy
results: parsing output: 68/ for test: kms_lease
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: gem_render_copy
results: parsing output: 71/ for test: kms_flip_scaled_crc
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: kms_chamelium_color
results: parsing output: 74/ for test: kms_invalid_mode
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_create
results: parsing output: 77/ for test: gem_unref_active_buffers
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: gem_exec_reloc
results: parsing output: 81/ for test: kms_pm_rpm
results: parsing output: 82/ for test: kms_pipe_crc_basic
results: parsing output: 83/ for test: prime_udl
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: gem_exec_fence
results: parsing output: 87/ for test: gem_softpin
results: parsing output: 88/ for test: gem_ctx_persistence
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_chamelium_frames
results: parsing output: 91/ for test: kms_async_flips
results: parsing output: 92/ for test: kms_psr2_sf
results: parsing output: 93/ for test: gem_exec_params
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: api_intel_bb
results: parsing output: 97/ for test: syncobj_wait
results: parsing output: 98/ for test: i915_query
results: parsing output: 99/ for test: perf_pmu
results: parsing output: 100/ for test: syncobj_basic
results: parsing output: 101/ for test: gem_exec_schedule
results: parsing output: 102/ for test: i915_drm_fdinfo
results: parsing output: 103/ for test: gem_mmap_wc
results: parsing output: 104/ for test: kms_selftest
results: parsing output: 105/ for test: prime_mmap
results: parsing output: 106/ for test: gen9_exec_parse
results: parsing output: 107/ for test: kms_atomic
results: parsing output: 108/ for test: gem_exec_nop
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: gem_fenced_exec_thrash
results: parsing output: 111/ for test: perf_pmu
results: parsing output: 112/ for test: kms_vblank
results: parsing output: 113/ for test: gem_exec_balancer
results: parsing output: 114/ for test: kms_hdr
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: kms_plane
results: parsing output: 117/ for test: gem_lmem_swapping
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: gem_vm_create
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: syncobj_basic
results: parsing output: 125/ for test: perf
results: parsing output: 126/ for test: gem_ctx_shared
results: parsing output: 127/ for test: gem_exec_flush
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_ccs
results: parsing output: 130/ for test: kms_cursor_legacy
results: parsing output: 131/ for test: prime_vgem
results: parsing output: 132/ for test: kms_flip
Done.