[60.652954] Initializing watchdogs
[60.653029]   /dev/watchdog0
[60.673421] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[60.684204] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[60.688093] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.690418] [001/132] (960s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[60.874944] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[60.876704] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[61.045602] [002/132] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[61.232510] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[61.234193] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[61.432367] [003/132] (959s left) gem_ctx_shared (q-independent)
[61.520138] Starting subtest: Q-independent
[61.521061] Starting dynamic subtest: rcs0
[61.762933] Dynamic subtest rcs0: SUCCESS (0.241s)
[61.771069] Starting dynamic subtest: bcs0
[61.917441] Dynamic subtest bcs0: SUCCESS (0.154s)
[61.925544] Starting dynamic subtest: vcs0
[62.077819] Dynamic subtest vcs0: SUCCESS (0.159s)
[62.085952] Starting dynamic subtest: vcs1
[62.238477] Dynamic subtest vcs1: SUCCESS (0.160s)
[62.246523] Starting dynamic subtest: vecs0
[62.397362] Dynamic subtest vecs0: SUCCESS (0.158s)
[62.405349] Starting dynamic subtest: vecs1
[62.557849] Dynamic subtest vecs1: SUCCESS (0.160s)
[62.566344] Starting dynamic subtest: ccs0
[62.720379] Dynamic subtest ccs0: SUCCESS (0.162s)
[62.721359] Subtest Q-independent: SUCCESS (1.195s)
[63.088666] [004/132] (958s left) gem_ccs (block-multicopy-inplace)
[63.166386] Starting subtest: block-multicopy-inplace
[63.167339] Starting dynamic subtest: linear-compressed-compfmt0-smem-lmem0-multicopy
[63.214265] Dynamic subtest linear-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.047s)
[63.222456] Starting dynamic subtest: linear-compressed-compfmt0-lmem0-lmem0-multicopy
[63.303947] Dynamic subtest linear-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.089s)
[63.311609] Starting dynamic subtest: xmajor-compressed-compfmt0-smem-lmem0-multicopy
[63.318368] Dynamic subtest xmajor-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.014s)
[63.326173] Starting dynamic subtest: xmajor-compressed-compfmt0-lmem0-lmem0-multicopy
[63.409241] Dynamic subtest xmajor-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.090s)
[63.417135] Starting dynamic subtest: tile4-compressed-compfmt0-smem-lmem0-multicopy
[63.423862] Dynamic subtest tile4-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.014s)
[63.431606] Starting dynamic subtest: tile4-compressed-compfmt0-lmem0-lmem0-multicopy
[63.520910] Dynamic subtest tile4-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.096s)
[63.528689] Starting dynamic subtest: tile64-compressed-compfmt0-smem-lmem0-multicopy
[63.536413] Dynamic subtest tile64-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.015s)
[63.544260] Starting dynamic subtest: tile64-compressed-compfmt0-lmem0-lmem0-multicopy
[63.630560] Dynamic subtest tile64-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.094s)
[63.631460] Subtest block-multicopy-inplace: SUCCESS (0.463s)
[63.807915] [005/132] (957s left) perf (buffer-fill)
[64.231207] Starting subtest: buffer-fill
[64.232122] Starting dynamic subtest: 0-rcs0
[66.397115] Dynamic subtest 0-rcs0: SUCCESS (2.159s)
[66.398033] Subtest buffer-fill: SUCCESS (2.159s)
[66.450500] [006/132] (955s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[66.719559] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[66.721275] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[66.935659] [007/132] (954s left) kms_psr (fbc-psr2-primary-blt)
[67.308887] Subtest fbc-psr2-primary-blt: SKIP (0.000s)
[67.395129] [008/132] (954s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[67.676221] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[67.677256] Starting dynamic subtest: pipe-A-valid-mode
[67.756172] Dynamic subtest pipe-A-valid-mode: SKIP (0.078s)
[67.757965] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SKIP (0.080s)
[67.956024] [009/132] (953s left) gem_flink_basic (bad-open)
[68.031923] Starting subtest: bad-open
[68.033159] Subtest bad-open: SUCCESS (0.000s)
[68.198514] [010/132] (953s left) drm_read (short-buffer-nonblock)
[68.476015] Starting subtest: short-buffer-nonblock
[68.476936] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[68.719446] [011/132] (953s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[68.903263] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[68.904946] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[69.103706] [012/132] (952s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[69.295217] Starting subtest: y-tiled-16bpp-rotate-90
[69.296939] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[69.490675] [013/132] (952s left) syncobj_wait (wait-all-for-submit-complex)
[69.574607] Starting subtest: wait-all-for-submit-complex
[69.792844] Subtest wait-all-for-submit-complex: SUCCESS (0.217s)
[69.923951] [014/132] (952s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[70.120400] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[73.084172] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (2.960s)
[73.325809] [015/132] (948s left) kms_psr (psr2-primary-render)
[73.515336] Subtest psr2-primary-render: SKIP (0.000s)
[73.579854] [016/132] (948s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[73.660950] Starting subtest: multi-wait-all-for-submit-available-signaled
[73.661860] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[73.862220] [017/132] (948s left) gem_exec_capture (pi)
[73.942117] Starting subtest: pi
[73.943036] Starting dynamic subtest: rcs0
[79.791452] Dynamic subtest rcs0: SUCCESS (5.843s)
[79.800155] Starting dynamic subtest: bcs0
[85.626900] Dynamic subtest bcs0: SUCCESS (5.830s)
[85.635643] Starting dynamic subtest: vcs0
[91.503228] Dynamic subtest vcs0: SUCCESS (5.871s)
[91.511577] Starting dynamic subtest: vcs1
[97.231988] Dynamic subtest vcs1: SUCCESS (5.727s)
[97.240831] Starting dynamic subtest: vecs0
[102.971474] Dynamic subtest vecs0: SUCCESS (5.737s)
[102.980241] Starting dynamic subtest: vecs1
[108.637598] Dynamic subtest vecs1: SUCCESS (5.664s)
[108.647390] Starting dynamic subtest: ccs0
[114.356488] Dynamic subtest ccs0: SUCCESS (5.717s)
[114.357430] Subtest pi: SUCCESS (40.392s)
[114.522047] [018/132] (907s left) i915_pm_rpm (gem-evict-pwrite)
[117.006818] Starting subtest: gem-evict-pwrite
[117.010112] Subtest gem-evict-pwrite: SKIP (0.000s)
[117.435769] [019/132] (904s left) gem_mmap_gtt (basic-wc)
[117.506142] Subtest basic-wc: SKIP (0.000s)
[117.569655] [020/132] (904s left) kms_invalid_mode (bad-hsync-start)
[117.746395] Starting subtest: bad-hsync-start
[117.747274] Starting dynamic subtest: pipe-A-DP-3
[117.822574] Dynamic subtest pipe-A-DP-3: SUCCESS (0.076s)
[117.834851] Starting dynamic subtest: pipe-B-DP-3
[117.836066] Dynamic subtest pipe-B-DP-3: SUCCESS (0.001s)
[117.846992] Starting dynamic subtest: pipe-C-DP-3
[117.848132] Dynamic subtest pipe-C-DP-3: SUCCESS (0.001s)
[117.858813] Starting dynamic subtest: pipe-D-DP-3
[117.859769] Dynamic subtest pipe-D-DP-3: SUCCESS (0.001s)
[117.860652] Subtest bad-hsync-start: SUCCESS (0.083s)
[118.104421] [021/132] (904s left) kms_flip (absolute-wf_vblank-interruptible)
[118.291546] Starting subtest: absolute-wf_vblank-interruptible
[118.296010] Starting dynamic subtest: A-DP3
[126.117742] Dynamic subtest A-DP3: SUCCESS (7.820s)
[126.128578] Starting dynamic subtest: B-DP3
[133.871795] Dynamic subtest B-DP3: SUCCESS (7.752s)
[133.881492] Starting dynamic subtest: C-DP3
[141.624723] Dynamic subtest C-DP3: SUCCESS (7.751s)
[141.635155] Starting dynamic subtest: D-DP3
[149.535253] Dynamic subtest D-DP3: SUCCESS (7.911s)
[149.537148] Subtest absolute-wf_vblank-interruptible: SUCCESS (31.242s)
[149.895279] [022/132] (872s left) gem_exec_params (secure-non-master)
[149.975046] Starting subtest: secure-non-master
[149.977191] Subtest secure-non-master: SKIP (0.000s)
[150.163043] [023/132] (872s left) api_intel_allocator (open-vm)
[150.239934] Starting subtest: open-vm
[150.265808] Subtest open-vm: SUCCESS (0.026s)
[150.479598] [024/132] (872s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[150.666564] Starting subtest: basic-modeset-with-all-modifiers-formats
[150.901062] Starting dynamic subtest: pipe-A-DP-3-4-RGB565
[151.159842] Dynamic subtest pipe-A-DP-3-4-RGB565: SUCCESS (0.259s)
[151.171798] Starting dynamic subtest: pipe-A-DP-3-4-XRGB8888
[151.193379] Dynamic subtest pipe-A-DP-3-4-XRGB8888: SUCCESS (0.033s)
[151.208059] Starting dynamic subtest: pipe-A-DP-3-4-XRGB2101010
[151.227127] Dynamic subtest pipe-A-DP-3-4-XRGB2101010: SUCCESS (0.033s)
[151.241772] Starting dynamic subtest: pipe-A-DP-3-4-XRGB16161616F
[151.267737] Dynamic subtest pipe-A-DP-3-4-XRGB16161616F: SUCCESS (0.032s)
[151.289079] Starting dynamic subtest: pipe-A-DP-3-4-YUYV
[151.293389] Dynamic subtest pipe-A-DP-3-4-YUYV: SUCCESS (0.033s)
[151.314112] Starting dynamic subtest: pipe-A-DP-3-4-Y210
[151.326511] Dynamic subtest pipe-A-DP-3-4-Y210: SUCCESS (0.033s)
[151.339935] Starting dynamic subtest: pipe-A-DP-3-4-XYUV8888
[151.359880] Dynamic subtest pipe-A-DP-3-4-XYUV8888: SUCCESS (0.033s)
[151.373422] Starting dynamic subtest: pipe-A-DP-3-4-XV30
[151.393206] Dynamic subtest pipe-A-DP-3-4-XV30: SUCCESS (0.033s)
[151.406744] Starting dynamic subtest: pipe-A-DP-3-4-XV36
[151.426497] Dynamic subtest pipe-A-DP-3-4-XV36: SUCCESS (0.033s)
[151.439983] Starting dynamic subtest: pipe-A-DP-3-x-RGB565
[151.609933] Dynamic subtest pipe-A-DP-3-x-RGB565: SUCCESS (0.183s)
[151.618999] Starting dynamic subtest: pipe-A-DP-3-x-XRGB8888
[151.643097] Dynamic subtest pipe-A-DP-3-x-XRGB8888: SUCCESS (0.033s)
[151.654808] Starting dynamic subtest: pipe-A-DP-3-x-XRGB2101010
[151.676611] Dynamic subtest pipe-A-DP-3-x-XRGB2101010: SUCCESS (0.033s)
[151.693073] Starting dynamic subtest: pipe-A-DP-3-x-XRGB16161616F
[151.709819] Dynamic subtest pipe-A-DP-3-x-XRGB16161616F: SUCCESS (0.033s)
[151.726407] Starting dynamic subtest: pipe-A-DP-3-x-YUYV
[151.743408] Dynamic subtest pipe-A-DP-3-x-YUYV: SUCCESS (0.033s)
[151.760382] Starting dynamic subtest: pipe-A-DP-3-x-Y210
[151.776850] Dynamic subtest pipe-A-DP-3-x-Y210: SUCCESS (0.033s)
[151.793678] Starting dynamic subtest: pipe-A-DP-3-x-XYUV8888
[151.810119] Dynamic subtest pipe-A-DP-3-x-XYUV8888: SUCCESS (0.033s)
[151.826926] Starting dynamic subtest: pipe-A-DP-3-x-XV30
[151.843118] Dynamic subtest pipe-A-DP-3-x-XV30: SUCCESS (0.032s)
[151.859847] Starting dynamic subtest: pipe-A-DP-3-x-XV36
[151.876446] Dynamic subtest pipe-A-DP-3-x-XV36: SUCCESS (0.033s)
[151.893469] Starting dynamic subtest: pipe-A-DP-3-linear-RGB565
[152.060929] Dynamic subtest pipe-A-DP-3-linear-RGB565: SUCCESS (0.184s)
[152.070067] Starting dynamic subtest: pipe-A-DP-3-linear-XRGB8888
[152.095224] Dynamic subtest pipe-A-DP-3-linear-XRGB8888: SUCCESS (0.034s)
[152.111581] Starting dynamic subtest: pipe-A-DP-3-linear-XRGB2101010
[152.128505] Dynamic subtest pipe-A-DP-3-linear-XRGB2101010: SUCCESS (0.033s)
[152.145306] Starting dynamic subtest: pipe-A-DP-3-linear-XRGB16161616F
[152.161873] Dynamic subtest pipe-A-DP-3-linear-XRGB16161616F: SUCCESS (0.033s)
[152.178832] Starting dynamic subtest: pipe-A-DP-3-linear-YUYV
[152.195209] Dynamic subtest pipe-A-DP-3-linear-YUYV: SUCCESS (0.033s)
[152.212030] Starting dynamic subtest: pipe-A-DP-3-linear-Y210
[152.228512] Dynamic subtest pipe-A-DP-3-linear-Y210: SUCCESS (0.033s)
[152.245272] Starting dynamic subtest: pipe-A-DP-3-linear-XYUV8888
[152.261838] Dynamic subtest pipe-A-DP-3-linear-XYUV8888: SUCCESS (0.033s)
[152.278598] Starting dynamic subtest: pipe-A-DP-3-linear-XV30
[152.295151] Dynamic subtest pipe-A-DP-3-linear-XV30: SUCCESS (0.033s)
[152.311936] Starting dynamic subtest: pipe-A-DP-3-linear-XV36
[152.328668] Dynamic subtest pipe-A-DP-3-linear-XV36: SUCCESS (0.033s)
[152.603497] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (1.937s)
[152.957970] [025/132] (869s left) gem_exec_parallel (userptr)
[153.038438] Starting subtest: userptr
[153.039373] Starting dynamic subtest: rcs0
[154.546214] Dynamic subtest rcs0: SUCCESS (1.508s)
[154.557172] Starting dynamic subtest: bcs0
[156.007391] Dynamic subtest bcs0: SUCCESS (1.461s)
[156.017145] Starting dynamic subtest: vcs0
[157.509508] Dynamic subtest vcs0: SUCCESS (1.502s)
[157.519795] Starting dynamic subtest: vcs1
[159.019225] Dynamic subtest vcs1: SUCCESS (1.509s)
[159.029384] Starting dynamic subtest: vecs0
[160.497194] Dynamic subtest vecs0: SUCCESS (1.478s)
[160.507609] Starting dynamic subtest: vecs1
[161.936490] Dynamic subtest vecs1: SUCCESS (1.439s)
[161.945521] Starting dynamic subtest: ccs0
[163.398170] Dynamic subtest ccs0: SUCCESS (1.461s)
[163.399115] Subtest userptr: SUCCESS (10.360s)
[163.722939] [026/132] (859s left) gem_mmap_gtt (bad-object)
[163.795006] Subtest bad-object: SKIP (0.000s)
[163.868983] [027/132] (859s left) gem_spin_batch (resubmit-all)
[163.946590] Starting subtest: resubmit-all
[163.947484] Starting dynamic subtest: rcs0
[163.981107] Dynamic subtest rcs0: SUCCESS (0.034s)
[163.990551] Starting dynamic subtest: bcs0
[163.991459] Dynamic subtest bcs0: SUCCESS (0.002s)
[164.001225] Starting dynamic subtest: vcs0
[164.002337] Dynamic subtest vcs0: SUCCESS (0.003s)
[164.011606] Starting dynamic subtest: vcs1
[164.012800] Dynamic subtest vcs1: SUCCESS (0.002s)
[164.021787] Starting dynamic subtest: vecs0
[164.022912] Dynamic subtest vecs0: SUCCESS (0.002s)
[164.031762] Starting dynamic subtest: vecs1
[164.032894] Dynamic subtest vecs1: SUCCESS (0.002s)
[164.041668] Starting dynamic subtest: ccs0
[164.042726] Dynamic subtest ccs0: SUCCESS (0.002s)
[164.043740] Subtest resubmit-all: SUCCESS (0.050s)
[164.304764] [028/132] (858s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[164.575625] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[164.729398] [029/132] (858s left) template (b)
[164.801017] Starting subtest: B
[164.801947] Subtest B: SUCCESS (0.000s)
[165.003143] [030/132] (858s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[165.081436] Starting subtest: yf-tiled-ccs-to-x-tiled
[165.083475] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[165.318993] [031/132] (857s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[165.496477] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[165.497424] Starting dynamic subtest: pipe-A-DP-3
[165.505860] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[165.515054] Starting dynamic subtest: pipe-B-DP-3
[165.523512] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[165.532502] Starting dynamic subtest: pipe-C-DP-3
[165.540725] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[165.549527] Starting dynamic subtest: pipe-D-DP-3
[165.559497] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[165.561296] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SKIP (0.006s)
[165.985130] [032/132] (857s left) gem_close_race (basic-threads)
[166.096105] Starting subtest: basic-threads
[167.310651] Subtest basic-threads: SUCCESS (1.215s)
[167.644657] [033/132] (855s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[167.823589] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[167.824436] Starting dynamic subtest: pipe-A-DP-3
[167.833140] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[167.842168] Starting dynamic subtest: pipe-B-DP-3
[167.850544] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[167.859461] Starting dynamic subtest: pipe-C-DP-3
[167.867597] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[167.876455] Starting dynamic subtest: pipe-D-DP-3
[167.886461] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[167.888342] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[168.306083] [034/132] (855s left) gen3_render_linear_blits
[168.455569] [035/132] (855s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[168.642789] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (0.000s)
[168.686408] [036/132] (854s left) syncobj_basic (bad-create-flags)
[168.756167] Starting subtest: bad-create-flags
[168.757168] Subtest bad-create-flags: SUCCESS (0.000s)
[168.942591] [037/132] (854s left) prime_vgem (wait)
[169.123234] Starting subtest: wait
[169.124492] Starting dynamic subtest: rcs0
[169.178277] Dynamic subtest rcs0: SUCCESS (0.056s)
[169.188015] Starting dynamic subtest: bcs0
[169.243429] Dynamic subtest bcs0: SUCCESS (0.065s)
[169.253192] Starting dynamic subtest: vcs0
[169.286588] Dynamic subtest vcs0: SUCCESS (0.043s)
[169.312477] Starting dynamic subtest: vcs1
[169.333040] Dynamic subtest vcs1: SUCCESS (0.045s)
[169.358123] Starting dynamic subtest: vecs0
[169.376038] Dynamic subtest vecs0: SUCCESS (0.042s)
[169.390843] Starting dynamic subtest: vecs1
[169.419360] Dynamic subtest vecs1: SUCCESS (0.043s)
[169.435952] Starting dynamic subtest: ccs0
[169.463093] Dynamic subtest ccs0: SUCCESS (0.043s)
[169.464150] Subtest wait: SUCCESS (0.341s)
[169.691567] [FACT prime_vgem (wait)] new: kernel.kmod_is_loaded.vgem: true
[169.693975] [038/132] (853s left) kms_psr (fbc-pr-cursor-blt)
[169.882008] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[169.939430] [039/132] (853s left) gem_exec_schedule (u-fairslice)
[170.029432] Starting subtest: u-fairslice
[170.030337] Starting dynamic subtest: rcs0
[172.082257] Dynamic subtest rcs0: SUCCESS (2.052s)
[172.092251] Starting dynamic subtest: bcs0
[174.101898] Dynamic subtest bcs0: SUCCESS (2.019s)
[174.111498] Starting dynamic subtest: vcs0
[176.122226] Dynamic subtest vcs0: SUCCESS (2.020s)
[176.132098] Starting dynamic subtest: vcs1
[178.142332] Dynamic subtest vcs1: SUCCESS (2.020s)
[178.152323] Starting dynamic subtest: vecs0
[180.161997] Dynamic subtest vecs0: SUCCESS (2.019s)
[180.171426] Starting dynamic subtest: vecs1
[182.183391] Dynamic subtest vecs1: SUCCESS (2.020s)
[182.193149] Starting dynamic subtest: ccs0
[184.201603] Dynamic subtest ccs0: SUCCESS (2.019s)
[184.202561] Subtest u-fairslice: SUCCESS (14.171s)
[184.596962] [040/132] (839s left) gem_ctx_persistence (many-contexts)
[184.684251] Starting subtest: many-contexts
[222.386295] Subtest many-contexts: SUCCESS (37.699s)
[222.550753] [041/132] (801s left) perf_pmu (pmu-read)
[222.621789] Starting subtest: pmu-read
[222.625888] Subtest pmu-read: SUCCESS (0.004s)
[222.788841] [042/132] (801s left) perf_pmu (busy-accuracy-2)
[222.861221] Starting subtest: busy-accuracy-2
[222.862156] Starting dynamic subtest: rcs0
[225.923919] Dynamic subtest rcs0: SUCCESS (3.061s)
[225.934824] Starting dynamic subtest: bcs0
[228.990064] Dynamic subtest bcs0: SUCCESS (3.065s)
[229.000624] Starting dynamic subtest: vcs0
[232.057923] Dynamic subtest vcs0: SUCCESS (3.067s)
[232.068472] Starting dynamic subtest: vcs1
[235.124786] Dynamic subtest vcs1: SUCCESS (3.066s)
[235.135453] Starting dynamic subtest: vecs0
[238.191815] Dynamic subtest vecs0: SUCCESS (3.066s)
[238.202450] Starting dynamic subtest: vecs1
[241.259897] Dynamic subtest vecs1: SUCCESS (3.067s)
[241.270914] Starting dynamic subtest: ccs0
[244.322423] Dynamic subtest ccs0: SUCCESS (3.062s)
[244.323327] Subtest busy-accuracy-2: SUCCESS (21.459s)
[244.519075] [043/132] (779s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[244.716380] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[244.718055] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[244.921752] [044/132] (779s left) gem_ctx_param (vm)
[245.002428] Starting subtest: vm
[245.088679] Subtest vm: SUCCESS (0.086s)
[245.268950] [045/132] (778s left) vgem_basic (bad-pad)
[245.337577] Starting subtest: bad-pad
[245.338562] Subtest bad-pad: SUCCESS (0.000s)
[245.482241] [046/132] (778s left) gem_set_tiling_vs_pwrite
[245.631174] [047/132] (778s left) gem_fence_thrash (bo-write-verify-none)
[245.678177] Starting subtest: bo-write-verify-none
[245.710893] Subtest bo-write-verify-none: SKIP (0.032s)
[245.782054] [048/132] (778s left) syncobj_basic (illegal-fd-to-handle)
[245.856079] Starting subtest: illegal-fd-to-handle
[245.857042] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[246.055496] [049/132] (778s left) gen9_exec_parse (bb-start-far)
[246.141320] Subtest bb-start-far: SKIP (0.000s)
[246.215763] [050/132] (778s left) gem_mmap_offset (ptrace)
[246.291986] Starting subtest: ptrace
[246.295689] Starting dynamic subtest: lmem0-fixed
[246.408977] Dynamic subtest lmem0-fixed: SUCCESS (0.113s)
[246.421394] Starting dynamic subtest: smem0-fixed
[246.427253] Dynamic subtest smem0-fixed: SUCCESS (0.015s)
[246.428133] Subtest ptrace: SUCCESS (0.136s)
[246.636556] [051/132] (777s left) kms_plane (pixel-format)
[246.823720] Starting subtest: pixel-format
[246.853363] Starting dynamic subtest: pipe-A-plane-0
[253.987580] Dynamic subtest pipe-A-plane-0: SUCCESS (7.134s)
[253.998930] Starting dynamic subtest: pipe-A-plane-3
[257.738584] Dynamic subtest pipe-A-plane-3: SUCCESS (3.750s)
[257.752723] Starting dynamic subtest: pipe-A-plane-5
[257.955256] Dynamic subtest pipe-A-plane-5: SUCCESS (0.215s)
[258.183290] Starting dynamic subtest: pipe-B-plane-0
[264.816931] Dynamic subtest pipe-B-plane-0: SUCCESS (6.650s)
[264.830924] Starting dynamic subtest: pipe-B-plane-3
[268.750345] Dynamic subtest pipe-B-plane-3: SUCCESS (3.932s)
[268.761221] Starting dynamic subtest: pipe-B-plane-5
[268.983803] Dynamic subtest pipe-B-plane-5: SUCCESS (0.233s)
[269.090855] Subtest pixel-format: SUCCESS (22.265s)
[269.442126] [052/132] (755s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[269.640520] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[269.700855] [053/132] (754s left) gem_ctx_param (invalid-param-set)
[269.780553] Starting subtest: invalid-param-set
[269.781434] Subtest invalid-param-set: SUCCESS (0.000s)
[269.967908] [054/132] (754s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[270.168428] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[270.170195] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[270.373110] [055/132] (754s left) kms_psr (psr-sprite-plane-move)
[270.560096] Subtest psr-sprite-plane-move: SKIP (0.000s)
[270.617953] [056/132] (754s left) gem_exec_reloc (basic-gtt-active)
[270.703577] Subtest basic-gtt-active: SKIP (0.000s)
[270.838972] [057/132] (754s left) gem_exec_schedule (preempt-other-chain)
[270.931083] Starting subtest: preempt-other-chain
[270.932053] Starting dynamic subtest: rcs0
[271.581455] Dynamic subtest rcs0: SUCCESS (0.643s)
[271.599084] Starting dynamic subtest: bcs0
[272.193475] Dynamic subtest bcs0: SUCCESS (0.618s)
[272.203510] Starting dynamic subtest: vcs0
[272.803746] Dynamic subtest vcs0: SUCCESS (0.610s)
[272.814230] Starting dynamic subtest: vcs1
[273.453498] Dynamic subtest vcs1: SUCCESS (0.649s)
[273.463758] Starting dynamic subtest: vecs0
[274.222793] Dynamic subtest vecs0: SUCCESS (0.769s)
[274.233378] Starting dynamic subtest: vecs1
[274.834748] Dynamic subtest vecs1: SUCCESS (0.611s)
[274.845563] Starting dynamic subtest: ccs0
[275.457048] Dynamic subtest ccs0: SUCCESS (0.622s)
[275.458228] Subtest preempt-other-chain: SUCCESS (4.526s)
[275.886790] [058/132] (749s left) kms_big_fb (x-tiled-addfb-size-overflow)
[276.086278] Starting subtest: x-tiled-addfb-size-overflow
[276.087234] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.001s)
[276.284694] [059/132] (748s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[276.473646] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[276.475315] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[276.660751] [060/132] (748s left) syncobj_timeline (reset-during-wait-for-submit)
[276.748391] Starting subtest: reset-during-wait-for-submit
[276.849815] Subtest reset-during-wait-for-submit: SUCCESS (0.101s)
[276.988654] [061/132] (748s left) gem_render_copy (y-tiled)
[277.074478] Starting subtest: y-tiled
[277.076607] Subtest y-tiled: SKIP (0.000s)
[277.321622] [062/132] (747s left) syncobj_timeline (wait-for-submit-delayed-submit)
[277.403292] Starting subtest: wait-for-submit-delayed-submit
[277.507407] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[277.648370] [063/132] (747s left) kms_rotation_crc (primary-rotation-180)
[277.836945] Starting subtest: primary-rotation-180
[288.188904] Subtest primary-rotation-180: SUCCESS (10.352s)
[288.600808] [064/132] (736s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render)
[288.794414] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render
[288.796122] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[289.001192] [065/132] (736s left) gem_softpin (noreloc-interruptible)
[289.089528] Starting subtest: noreloc-interruptible
[294.535456] Subtest noreloc-interruptible: SUCCESS (5.446s)
[294.728880] [066/132] (730s left) gem_exec_await (wide-all)
[295.037425] Starting subtest: wide-all
[317.096134] Subtest wide-all: SUCCESS (22.059s)
[317.399122] [067/132] (708s left) kms_vblank (ts-continuation-dpms-suspend)
[317.617158] Starting subtest: ts-continuation-dpms-suspend
[317.618085] Starting dynamic subtest: pipe-A-DP-3
[336.917814] Dynamic subtest pipe-A-DP-3: SUCCESS (4.233s)
[336.933520] Starting dynamic subtest: pipe-D-DP-3
[355.701348] Dynamic subtest pipe-D-DP-3: SUCCESS (3.929s)
[355.702269] Subtest ts-continuation-dpms-suspend: SUCCESS (8.166s)
[355.981841] [068/132] (669s left) gem_reg_read (timestamp-moving)
[356.069511] Starting subtest: timestamp-moving
[357.071368] Subtest timestamp-moving: SUCCESS (1.001s)
[357.203313] [069/132] (668s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[357.397971] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[357.399731] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[357.610922] [070/132] (668s left) i915_drm_fdinfo (virtual-busy-idle)
[357.684773] Starting subtest: virtual-busy-idle
[357.686524] Subtest virtual-busy-idle: SKIP (0.000s)
[357.850205] [071/132] (668s left) gem_exec_suspend (basic-s0)
[357.941183] Starting subtest: basic-S0
[357.942147] Starting dynamic subtest: smem
[379.483496] Dynamic subtest smem: SUCCESS (7.174s)
[379.496403] Starting dynamic subtest: lmem0
[400.470718] Dynamic subtest lmem0: SUCCESS (7.145s)
[400.471775] Subtest basic-S0: SUCCESS (14.320s)
[400.781383] [072/132] (625s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[400.970561] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[403.187652] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (2.217s)
[403.417407] [073/132] (622s left) gem_exec_whisper (basic-sync)
[403.510040] Starting subtest: basic-sync
[409.429234] Subtest basic-sync: SUCCESS (5.918s)
[409.720985] [074/132] (616s left) kms_feature_discovery (display-2x)
[409.908195] Starting subtest: display-2x
[409.909905] Subtest display-2x: SKIP (0.000s)
[410.068914] [075/132] (616s left) i915_drm_fdinfo (memory-info-shared)
[410.139068] Starting subtest: memory-info-shared
[410.140026] Starting dynamic subtest: lmem0
[410.208307] Dynamic subtest lmem0: SUCCESS (0.069s)
[410.221780] Starting dynamic subtest: smem0
[410.270132] Dynamic subtest smem0: SUCCESS (0.061s)
[410.271228] Subtest memory-info-shared: SUCCESS (0.132s)
[410.411278] [076/132] (615s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[410.607906] Starting subtest: plane-lut1d-pre-ctm3x4
[410.634593] Starting dynamic subtest: pipe-A-DP-3
[411.034310] Dynamic subtest pipe-A-DP-3: SUCCESS (0.400s)
[411.237289] Starting dynamic subtest: pipe-B-DP-3
[411.590142] Dynamic subtest pipe-B-DP-3: SUCCESS (0.365s)
[411.793258] Starting dynamic subtest: pipe-C-DP-3
[412.127728] Dynamic subtest pipe-C-DP-3: SUCCESS (0.348s)
[412.346795] Starting dynamic subtest: pipe-D-DP-3
[412.683490] Dynamic subtest pipe-D-DP-3: SUCCESS (0.348s)
[412.790721] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (2.183s)
[413.105056] [077/132] (613s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[413.301785] Starting subtest: primary-x-tiled-reflect-x-0
[423.456561] Subtest primary-x-tiled-reflect-x-0: SUCCESS (10.153s)
[423.861493] [078/132] (602s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[424.059594] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[424.061322] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[424.253956] [079/132] (602s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[424.443049] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[424.444093] Starting dynamic subtest: pipe-A-valid-mode
[424.526959] Dynamic subtest pipe-A-valid-mode: SKIP (0.082s)
[424.528831] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.084s)
[424.784954] [080/132] (601s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[424.985487] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[425.714693] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.729s)
[425.983509] [081/132] (600s left) gem_exec_fence (syncobj-timeline-chain-engines)
[426.642153] Starting subtest: syncobj-timeline-chain-engines
[426.815582] Subtest syncobj-timeline-chain-engines: SUCCESS (0.175s)
[427.216690] [082/132] (599s left) kms_chamelium_color (ctm-negative)
[427.409078] Subtest ctm-negative: SKIP (0.000s)
[427.478978] [083/132] (599s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[427.672574] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[427.674315] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[427.886719] [084/132] (599s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[427.976121] Starting subtest: invalid-multi-wait-all-unsubmitted
[427.977111] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[428.113732] [085/132] (598s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[428.311697] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[429.432845] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (1.120s)
[429.702394] [086/132] (597s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[429.893692] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[429.895477] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[430.102718] [087/132] (597s left) kms_pm_dc (dc3co-vpb-simulation)
[431.293890] Starting subtest: dc3co-vpb-simulation
[431.295796] Subtest dc3co-vpb-simulation: SKIP (0.000s)
[431.447200] [088/132] (595s left) gem_mmap_gtt (big-bo)
[431.525791] Subtest big-bo: SKIP (0.000s)
[431.596809] [089/132] (595s left) gem_mmap_offset (clear)
[431.676926] Starting subtest: clear
[431.677986] Starting dynamic subtest: lmem0
[459.154817] Dynamic subtest lmem0: SUCCESS (27.469s)
[459.175252] Starting dynamic subtest: smem0
[484.462983] Dynamic subtest smem0: SUCCESS (25.307s)
[484.464232] Subtest clear: SUCCESS (52.778s)
[484.769757] [090/132] (542s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[485.031892] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[486.192467] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (1.160s)
[486.475935] [091/132] (541s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[486.672645] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[486.674297] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[486.877798] [092/132] (540s left) kms_properties (crtc-properties-legacy)
[487.069906] Starting subtest: crtc-properties-legacy
[487.070831] Starting dynamic subtest: pipe-A-DP-3
[487.402026] Dynamic subtest pipe-A-DP-3: SUCCESS (0.331s)
[487.421283] Starting dynamic subtest: pipe-B-DP-3
[487.745792] Dynamic subtest pipe-B-DP-3: SUCCESS (0.343s)
[487.761742] Starting dynamic subtest: pipe-C-DP-3
[488.101701] Dynamic subtest pipe-C-DP-3: SUCCESS (0.353s)
[488.116076] Starting dynamic subtest: pipe-D-DP-3
[488.454560] Dynamic subtest pipe-D-DP-3: SUCCESS (0.351s)
[488.455495] Subtest crtc-properties-legacy: SUCCESS (1.386s)
[488.798831] [093/132] (538s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[488.992808] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[488.994556] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[489.194273] [094/132] (538s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[489.381913] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[489.382819] Starting dynamic subtest: pipe-A-DP-3
[489.391864] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[489.404651] Starting dynamic subtest: pipe-B-DP-3
[489.413615] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[489.426119] Starting dynamic subtest: pipe-C-DP-3
[489.435358] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[489.448463] Starting dynamic subtest: pipe-D-DP-3
[489.459280] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[489.461299] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[489.870746] [095/132] (538s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[489.958249] Starting subtest: etime-multi-wait-all-submitted-signaled
[490.064004] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.105s)
[490.258121] [096/132] (537s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[490.448806] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[490.462818] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.015s)
[490.653748] [097/132] (537s left) api_intel_bb (render-ccs)
[490.734068] Starting subtest: render-ccs
[490.830545] Subtest render-ccs: SUCCESS (0.096s)
[491.021008] [098/132] (537s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[491.107557] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[491.109824] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[491.406140] [099/132] (536s left) kms_flip (flip-vs-wf_vblank-interruptible)
[491.599131] Starting subtest: flip-vs-wf_vblank-interruptible
[491.603964] Starting dynamic subtest: A-DP3
[492.714120] Dynamic subtest A-DP3: SUCCESS (1.110s)
[492.731002] Starting dynamic subtest: B-DP3
[493.748159] Dynamic subtest B-DP3: SUCCESS (1.032s)
[493.765971] Starting dynamic subtest: C-DP3
[494.776372] Dynamic subtest C-DP3: SUCCESS (1.027s)
[494.791341] Starting dynamic subtest: D-DP3
[495.980794] Dynamic subtest D-DP3: SUCCESS (1.203s)
[495.983148] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (4.383s)
[496.333414] [100/132] (532s left) kms_cursor_crc (cursor-offscreen-64x21)
[496.683898] Starting subtest: cursor-offscreen-64x21
[496.688897] Starting dynamic subtest: pipe-A-DP-3
[499.241478] Dynamic subtest pipe-A-DP-3: SUCCESS (2.551s)
[499.293058] Starting dynamic subtest: pipe-D-DP-3
[501.771518] Dynamic subtest pipe-D-DP-3: SUCCESS (2.490s)
[501.772494] Subtest cursor-offscreen-64x21: SUCCESS (5.087s)
[502.203033] [101/132] (526s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[502.392044] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[502.393758] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[502.549599] [102/132] (525s left) gem_softpin (softpin)
[502.640754] Starting subtest: softpin
[534.885652] Subtest softpin: SUCCESS (32.244s)
[535.081234] [103/132] (493s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[535.275080] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[535.275946] Starting dynamic subtest: pipe-A-DP-3
[535.284149] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[535.297122] Starting dynamic subtest: pipe-B-DP-3
[535.305499] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[535.318193] Starting dynamic subtest: pipe-C-DP-3
[535.326446] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[535.339428] Starting dynamic subtest: pipe-D-DP-3
[535.350193] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[535.352205] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[535.757812] [104/132] (492s left) gem_ctx_engines (invalid-engines)
[535.847138] Starting subtest: invalid-engines
[535.884370] Subtest invalid-engines: SUCCESS (0.037s)
[536.228023] [105/132] (492s left) kms_atomic (atomic-invalid-params)
[536.421199] Starting subtest: atomic-invalid-params
[536.423782] Starting dynamic subtest: pipe-A-DP-3
[537.001644] Dynamic subtest pipe-A-DP-3: SUCCESS (0.578s)
[537.002607] Subtest atomic-invalid-params: SUCCESS (0.580s)
[537.354343] [106/132] (491s left) perf (i915-ref-count)
[537.519268] Starting subtest: i915-ref-count
[537.652787] Subtest i915-ref-count: SUCCESS (0.133s)
[537.802522] [107/132] (491s left) sw_sync (timeline_closed_signaled)
[537.851468] Starting subtest: timeline_closed_signaled
[537.852344] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[538.001203] [108/132] (490s left) kms_cursor_crc (cursor-sliding-128x128)
[538.368580] Starting subtest: cursor-sliding-128x128
[538.372976] Starting dynamic subtest: pipe-A-DP-3
[542.701773] Dynamic subtest pipe-A-DP-3: SUCCESS (4.327s)
[542.754753] Starting dynamic subtest: pipe-D-DP-3
[547.082642] Dynamic subtest pipe-D-DP-3: SUCCESS (4.339s)
[547.083576] Subtest cursor-sliding-128x128: SUCCESS (8.713s)
[547.538173] [109/132] (481s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[547.616799] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[547.697077] [110/132] (481s left) kms_content_protection (atomic-hdcp14)
[547.949966] Starting subtest: atomic-hdcp14
[547.982848] Starting dynamic subtest: pipe-A-DP-3
[668.787894] Dynamic subtest pipe-A-DP-3: FAIL (120.639s)
[668.789096] Subtest atomic-hdcp14: FAIL (120.776s)
[669.044550] [111/132] (360s left) kms_atomic_interruptible (universal-setplane-primary)
[669.234071] Starting subtest: universal-setplane-primary
[669.234925] Starting dynamic subtest: pipe-A-DP-3
[675.566796] Dynamic subtest pipe-A-DP-3: SUCCESS (6.330s)
[675.567781] Subtest universal-setplane-primary: SUCCESS (6.333s)
[675.858359] [112/132] (353s left) syncobj_wait (invalid-wait-illegal-handle)
[675.946093] Starting subtest: invalid-wait-illegal-handle
[675.947084] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[676.120266] [113/132] (353s left) kms_vrr (seamless-rr-switch-vrr)
[676.311216] Starting subtest: seamless-rr-switch-vrr
[676.313794] Subtest seamless-rr-switch-vrr: SKIP (0.000s)
[676.473222] [114/132] (352s left) gem_userptr_blits (dmabuf-unsync)
[676.563536] Subtest dmabuf-unsync: SKIP (0.000s)
[676.792239] [115/132] (352s left) i915_suspend (basic-s3-without-i915)
[676.840045] Starting subtest: basic-s3-without-i915
[699.010510] Subtest basic-s3-without-i915: SUCCESS (6.130s)
[699.129235] [116/132] (330s left) kms_atomic_transition (plane-toggle-modeset-transition)
[699.322097] Starting subtest: plane-toggle-modeset-transition
[699.322878] Starting dynamic subtest: pipe-A-DP-3
[701.086372] Dynamic subtest pipe-A-DP-3: SUCCESS (1.763s)
[701.100339] Starting dynamic subtest: pipe-B-DP-3
[702.590015] Dynamic subtest pipe-B-DP-3: SUCCESS (1.500s)
[702.595779] Subtest plane-toggle-modeset-transition: SUCCESS (3.273s)
[702.890397] [117/132] (326s left) kms_busy (extended-pageflip-hang-newfb)
[703.103458] Starting subtest: extended-pageflip-hang-newfb
[703.105842] Starting dynamic subtest: pipe-A
[711.000093] Dynamic subtest pipe-A: SUCCESS (7.894s)
[711.021884] Starting dynamic subtest: pipe-D
[718.881826] Dynamic subtest pipe-D: SUCCESS (7.874s)
[718.884180] Subtest extended-pageflip-hang-newfb: SUCCESS (15.779s)
[719.197966] [118/132] (310s left) core_getstats (basic)
[719.278838] Starting subtest: basic
[719.280033] Subtest basic: SUCCESS (0.000s)
[719.431137] [119/132] (310s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[719.623104] Starting subtest: psr-farfromfence-mmap-gtt
[719.624796] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[719.814321] [120/132] (310s left) perf (unprivileged-single-ctx-counters)
[720.247294] Starting subtest: unprivileged-single-ctx-counters
[720.249013] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[720.330330] [121/132] (309s left) gem_exec_balancer (hog)
[720.420218] Starting subtest: hog
[720.422499] Subtest hog: SKIP (0.000s)
[720.900463] [122/132] (309s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[721.098250] Starting subtest: fbcpsr-rgb101010-draw-blt
[721.100054] Subtest fbcpsr-rgb101010-draw-blt: SKIP (0.000s)
[721.296935] [123/132] (308s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[721.486324] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[721.487056] Starting dynamic subtest: pipe-A
[721.578152] Dynamic subtest pipe-A: SUCCESS (0.092s)
[721.596269] Starting dynamic subtest: pipe-B
[721.791470] Dynamic subtest pipe-B: SUCCESS (0.212s)
[721.808274] Starting dynamic subtest: pipe-C
[721.987747] Dynamic subtest pipe-C: SUCCESS (0.195s)
[722.010168] Starting dynamic subtest: pipe-D
[722.200646] Dynamic subtest pipe-D: SUCCESS (0.211s)
[722.201596] Subtest planes-downscale-factor-0-5-upscale-20x20: SUCCESS (0.716s)
[722.581724] [124/132] (307s left) kms_plane_alpha_blend (constant-alpha-max)
[722.776590] Starting subtest: constant-alpha-max
[723.044269] Starting dynamic subtest: pipe-A-DP-3
[724.005639] Dynamic subtest pipe-A-DP-3: SUCCESS (0.961s)
[724.023379] Starting dynamic subtest: pipe-D-DP-3
[724.973409] Dynamic subtest pipe-D-DP-3: SUCCESS (0.965s)
[724.974261] Subtest constant-alpha-max: SUCCESS (2.197s)
[725.253874] [125/132] (305s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[725.448014] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[725.449737] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[725.665710] [126/132] (304s left) kms_pm_rpm (basic-rte)
[725.714049] Starting subtest: basic-rte
[727.820671] Subtest basic-rte: SUCCESS (2.107s)
[728.539385] [127/132] (301s left) kms_legacy_colorkey (invalid-plane)
[728.725846] Starting subtest: invalid-plane
[728.726779] Starting dynamic subtest: zero-id
[728.727662] Dynamic subtest zero-id: SUCCESS (0.000s)
[728.740546] Starting dynamic subtest: outrange-id
[728.741675] Dynamic subtest outrange-id: SUCCESS (0.000s)
[728.742729] Subtest invalid-plane: SUCCESS (0.002s)
[728.908596] [128/132] (301s left) gem_vm_create (invalid-destroy)
[728.998181] Starting subtest: invalid-destroy
[729.003305] Subtest invalid-destroy: SUCCESS (0.005s)
[729.162227] [129/132] (301s left) kms_psr (pr-cursor-render)
[729.350026] Subtest pr-cursor-render: SKIP (0.000s)
[729.408963] [130/132] (301s left) kms_selftest (drm_plane_helper)
[729.464270] Starting subtest: drm_plane_helper
[729.473371] Starting dynamic subtest: drm_test_check_plane_state
[729.588761] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.112s)
[729.628061] Starting dynamic subtest: drm_test_check_invalid_plane_state
[729.679765] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.088s)
[729.681152] Subtest drm_plane_helper: SUCCESS (0.215s)
[729.929988] [131/132] (300s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[730.205233] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[730.359993] [132/132] (300s left) kms_prop_blob (blob-prop-core)
[730.440476] Starting subtest: blob-prop-core
[730.441337] Subtest blob-prop-core: SUCCESS (0.000s)
[730.630216] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_legacy
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_ctx_shared
results: parsing output: 3/ for test: gem_ccs
results: parsing output: 4/ for test: perf
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_psr
results: parsing output: 7/ for test: kms_flip_scaled_crc
results: parsing output: 8/ for test: gem_flink_basic
results: parsing output: 9/ for test: drm_read
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_big_fb
results: parsing output: 12/ for test: syncobj_wait
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: gem_exec_capture
results: parsing output: 17/ for test: i915_pm_rpm
results: parsing output: 18/ for test: gem_mmap_gtt
results: parsing output: 19/ for test: kms_invalid_mode
results: parsing output: 20/ for test: kms_flip
results: parsing output: 21/ for test: gem_exec_params
results: parsing output: 22/ for test: api_intel_allocator
results: parsing output: 23/ for test: kms_async_flips
results: parsing output: 24/ for test: gem_exec_parallel
results: parsing output: 25/ for test: gem_mmap_gtt
results: parsing output: 26/ for test: gem_spin_batch
results: parsing output: 27/ for test: kms_chamelium_hpd
results: parsing output: 28/ for test: template
results: parsing output: 29/ for test: gem_render_copy
results: parsing output: 30/ for test: kms_ccs
results: parsing output: 31/ for test: gem_close_race
results: parsing output: 32/ for test: kms_ccs
results: parsing output: 33/ for test: gen3_render_linear_blits
results: parsing output: 34/ for test: kms_psr2_sf
results: parsing output: 35/ for test: syncobj_basic
results: parsing output: 36/ for test: prime_vgem
results: parsing output: 37/ for test: kms_psr
results: parsing output: 38/ for test: gem_exec_schedule
results: parsing output: 39/ for test: gem_ctx_persistence
results: parsing output: 40/ for test: perf_pmu
results: parsing output: 41/ for test: perf_pmu
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_ctx_param
results: parsing output: 44/ for test: vgem_basic
results: parsing output: 45/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 46/ for test: gem_fence_thrash
results: parsing output: 47/ for test: syncobj_basic
results: parsing output: 48/ for test: gen9_exec_parse
results: parsing output: 49/ for test: gem_mmap_offset
results: parsing output: 50/ for test: kms_plane
results: parsing output: 51/ for test: kms_psr2_sf
results: parsing output: 52/ for test: gem_ctx_param
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: gem_exec_schedule
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: gem_render_copy
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: kms_rotation_crc
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_softpin
results: parsing output: 65/ for test: gem_exec_await
results: parsing output: 66/ for test: kms_vblank
results: parsing output: 67/ for test: gem_reg_read
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: i915_drm_fdinfo
results: parsing output: 70/ for test: gem_exec_suspend
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: gem_exec_whisper
results: parsing output: 73/ for test: kms_feature_discovery
results: parsing output: 74/ for test: i915_drm_fdinfo
results: parsing output: 75/ for test: kms_color_pipeline
results: parsing output: 76/ for test: kms_rotation_crc
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_flip_scaled_crc
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: gem_exec_fence
results: parsing output: 81/ for test: kms_chamelium_color
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_pm_dc
results: parsing output: 87/ for test: gem_mmap_gtt
results: parsing output: 88/ for test: gem_mmap_offset
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_properties
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_ccs
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: kms_flip
results: parsing output: 96/ for test: api_intel_bb
results: parsing output: 97/ for test: gem_render_copy
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: kms_cursor_crc
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: gem_softpin
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: gem_ctx_engines
results: parsing output: 104/ for test: kms_atomic
results: parsing output: 105/ for test: perf
results: parsing output: 106/ for test: sw_sync
results: parsing output: 107/ for test: kms_cursor_crc
results: parsing output: 108/ for test: gem_mmap_gtt
results: parsing output: 109/ for test: kms_content_protection
results: parsing output: 110/ for test: kms_atomic_interruptible
results: parsing output: 111/ for test: syncobj_wait
results: parsing output: 112/ for test: kms_vrr
results: parsing output: 113/ for test: gem_userptr_blits
results: parsing output: 114/ for test: i915_suspend
results: parsing output: 115/ for test: kms_atomic_transition
results: parsing output: 116/ for test: kms_busy
results: parsing output: 117/ for test: core_getstats
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: perf
results: parsing output: 120/ for test: gem_exec_balancer
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_plane_scaling
results: parsing output: 123/ for test: kms_plane_alpha_blend
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_pm_rpm
results: parsing output: 126/ for test: kms_legacy_colorkey
results: parsing output: 127/ for test: gem_vm_create
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: kms_selftest
results: parsing output: 130/ for test: kms_chamelium_hpd
results: parsing output: 131/ for test: kms_prop_blob
Done.