[59.098892] Initializing watchdogs
[59.098947]   /dev/watchdog0
[59.114262] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.119569] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.121810] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.125043] [001/130] (960s left) kms_psr (fbc-pr-sprite-plane-onoff)
[59.244915] Starting subtest: fbc-pr-sprite-plane-onoff
[59.249104] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[59.312438] [002/130] (960s left) kms_cursor_legacy (single-bo)
[59.429769] Starting subtest: single-bo
[59.432058] Starting dynamic subtest: pipe-A
[64.814405] Dynamic subtest pipe-A: SUCCESS (5.380s)
[64.822802] Starting dynamic subtest: pipe-B
[70.195824] Dynamic subtest pipe-B: SUCCESS (5.381s)
[70.204523] Starting dynamic subtest: pipe-C
[75.576253] Dynamic subtest pipe-C: SUCCESS (5.379s)
[75.585432] Starting dynamic subtest: pipe-D
[80.957454] Dynamic subtest pipe-D: SUCCESS (5.380s)
[80.967617] Starting dynamic subtest: all-pipes
[86.346371] Dynamic subtest all-pipes: SUCCESS (5.388s)
[86.348583] Subtest single-bo: SUCCESS (26.913s)
[86.484719] [003/130] (933s left) kms_psr (psr-sprite-render)
[86.579285] Starting subtest: psr-sprite-render
[86.581686] Starting dynamic subtest: eDP-1
[88.340403] Dynamic subtest eDP-1: SUCCESS (1.763s)
[88.342856] Subtest psr-sprite-render: SUCCESS (1.763s)
[89.188903] [004/130] (930s left) gem_userptr_blits (access-control)
[89.268882] Starting subtest: access-control
[89.273505] Subtest access-control: SKIP (0.000s)
[89.391039] [005/130] (930s left) kms_cursor_crc (cursor-random-256x85)
[89.652035] Starting subtest: cursor-random-256x85
[89.706084] Subtest cursor-random-256x85: SKIP (0.047s)
[89.888649] [006/130] (929s left) gem_mmap_offset (mmap-boundaries)
[89.950698] Starting subtest: mmap-boundaries
[89.952715] Starting dynamic subtest: smem0
[90.232191] Dynamic subtest smem0: SUCCESS (0.282s)
[90.234704] Subtest mmap-boundaries: SUCCESS (0.282s)
[90.347784] [007/130] (929s left) kms_plane (pixel-format-4-tiled-modifier)
[90.449253] Starting subtest: pixel-format-4-tiled-modifier
[90.474855] Starting dynamic subtest: pipe-A-plane-0
[91.109601] Dynamic subtest pipe-A-plane-0: SUCCESS (0.634s)
[91.117548] Starting dynamic subtest: pipe-A-plane-3
[91.529708] Dynamic subtest pipe-A-plane-3: SUCCESS (0.421s)
[91.538453] Starting dynamic subtest: pipe-A-plane-5
[91.541605] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[92.678085] Starting dynamic subtest: pipe-B-plane-0
[93.292960] Dynamic subtest pipe-B-plane-0: SUCCESS (0.622s)
[93.302849] Starting dynamic subtest: pipe-B-plane-3
[93.748081] Dynamic subtest pipe-B-plane-3: SUCCESS (0.455s)
[93.755638] Starting dynamic subtest: pipe-B-plane-5
[93.759065] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[94.070395] Subtest pixel-format-4-tiled-modifier: SUCCESS (3.623s)
[94.871110] [008/130] (925s left) kms_big_fb (linear-8bpp-rotate-270)
[94.985153] Starting subtest: linear-8bpp-rotate-270
[95.005241] Subtest linear-8bpp-rotate-270: SKIP (0.020s)
[95.108947] [009/130] (924s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[95.168723] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[95.238856] [010/130] (924s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[95.338536] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[95.340880] Starting dynamic subtest: pipe-A-eDP-1
[95.363507] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[95.372709] Starting dynamic subtest: pipe-B-eDP-1
[95.394208] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[95.401620] Starting dynamic subtest: pipe-C-eDP-1
[95.423177] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[95.430530] Starting dynamic subtest: pipe-D-eDP-1
[95.452248] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[95.456266] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[97.633946] [011/130] (922s left) gem_exec_balancer (indices)
[97.686625] Starting subtest: indices
[99.893758] Subtest indices: SUCCESS (2.204s)
[100.222716] [012/130] (919s left) gem_render_copy (yf-tiled-ccs-to-linear)
[100.277477] Starting subtest: yf-tiled-ccs-to-linear
[100.282102] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[100.460235] [013/130] (919s left) i915_pm_rps (reset)
[100.527931] Starting subtest: reset
[108.995339] Subtest reset: SUCCESS (8.464s)
[109.108302] [014/130] (911s left) perf (polling)
[109.220070] Starting subtest: polling
[109.222224] Starting dynamic subtest: 0-rcs0
[119.246894] Dynamic subtest 0-rcs0: SUCCESS (10.027s)
[119.254864] Starting dynamic subtest: 1-vecs0
[129.267816] Dynamic subtest 1-vecs0: SUCCESS (10.021s)
[129.269822] Subtest polling: SUCCESS (20.048s)
[129.315971] [015/130] (890s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[129.417737] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[129.420056] Starting dynamic subtest: pipe-A-eDP-1
[129.732639] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[129.742692] Starting dynamic subtest: pipe-B-eDP-1
[131.012763] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.277s)
[131.023015] Starting dynamic subtest: pipe-C-eDP-1
[132.217220] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.202s)
[132.227224] Starting dynamic subtest: pipe-D-eDP-1
[133.473325] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.261s)
[133.475579] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.028s)
[134.668911] [016/130] (885s left) perf_pmu (interrupts)
[134.722801] Starting subtest: interrupts
[135.858389] Subtest interrupts: SUCCESS (1.134s)
[135.977630] [FACT perf_pmu (interrupts)] new: kernel.kmod_is_loaded.vgem: true
[135.981216] [017/130] (884s left) fbdev (unaligned-write)
[136.026688] Starting subtest: unaligned-write
[136.130810] Subtest unaligned-write: SUCCESS (0.102s)
[136.195060] [018/130] (884s left) perf (gen12-mi-rpc)
[136.306329] Starting subtest: gen12-mi-rpc
[136.308785] Starting dynamic subtest: rcs0
[136.323955] Dynamic subtest rcs0: SUCCESS (0.016s)
[136.326298] Subtest gen12-mi-rpc: SUCCESS (0.016s)
[136.372080] [019/130] (883s left) gem_pwrite_snooped
[136.528929] [020/130] (883s left) kms_psr (fbc-psr-cursor-blt)
[136.625819] Starting subtest: fbc-psr-cursor-blt
[136.628169] Starting dynamic subtest: eDP-1
[136.633315] Dynamic subtest eDP-1: SKIP (0.000s)
[136.637836] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[136.700070] [021/130] (883s left) syncobj_timeline (reset-multiple-signaled)
[136.755091] Starting subtest: reset-multiple-signaled
[136.757574] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[136.886912] [022/130] (883s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[136.951690] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[136.954062] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[137.084791] [023/130] (883s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[137.204424] Starting subtest: basic-flip-before-cursor-atomic
[138.145576] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.938s)
[138.998467] [024/130] (881s left) gem_exec_schedule (preempt-other-chain)
[139.064682] Starting subtest: preempt-other-chain
[139.066626] Starting dynamic subtest: rcs0
[139.146925] Dynamic subtest rcs0: SUCCESS (0.089s)
[139.158310] Starting dynamic subtest: bcs0
[139.233571] Dynamic subtest bcs0: SUCCESS (0.085s)
[139.246114] Starting dynamic subtest: vcs0
[139.321238] Dynamic subtest vcs0: SUCCESS (0.089s)
[139.332969] Starting dynamic subtest: vcs1
[139.403552] Dynamic subtest vcs1: SUCCESS (0.082s)
[139.417858] Starting dynamic subtest: vecs0
[139.483693] Dynamic subtest vecs0: SUCCESS (0.080s)
[139.494711] Starting dynamic subtest: ccs0
[139.575715] Dynamic subtest ccs0: SUCCESS (0.091s)
[139.578352] Subtest preempt-other-chain: SUCCESS (0.519s)
[140.963621] [025/130] (879s left) gem_exec_capture (capture-invisible)
[141.023096] Starting subtest: capture-invisible
[141.025466] Starting dynamic subtest: smem0
[141.029922] Dynamic subtest smem0: SKIP (0.000s)
[141.034351] Subtest capture-invisible: SKIP (0.000s)
[141.124526] [026/130] (879s left) i915_drm_fdinfo (idle)
[141.173869] Starting subtest: idle
[141.176071] Starting dynamic subtest: rcs0
[141.713751] Dynamic subtest rcs0: SUCCESS (0.541s)
[141.724436] Starting dynamic subtest: bcs0
[142.249873] Dynamic subtest bcs0: SUCCESS (0.533s)
[142.261382] Starting dynamic subtest: vcs0
[142.754918] Dynamic subtest vcs0: SUCCESS (0.504s)
[142.766710] Starting dynamic subtest: vcs1
[143.259993] Dynamic subtest vcs1: SUCCESS (0.504s)
[143.271634] Starting dynamic subtest: vecs0
[143.764430] Dynamic subtest vecs0: SUCCESS (0.503s)
[143.775801] Starting dynamic subtest: ccs0
[144.268777] Dynamic subtest ccs0: SUCCESS (0.504s)
[144.271632] Subtest idle: SUCCESS (3.095s)
[144.347086] [027/130] (876s left) i915_selftest (live)
[147.558220] Starting subtest: live
[147.560399] Starting dynamic subtest: sanitycheck
[151.848185] Dynamic subtest sanitycheck: SUCCESS (4.289s)
[151.857548] Starting dynamic subtest: uncore
[156.113483] Dynamic subtest uncore: SUCCESS (4.265s)
[156.127506] Starting dynamic subtest: workarounds
[173.888148] Dynamic subtest workarounds: FAIL (17.641s)
[173.897262] Starting dynamic subtest: gt_engines
[178.247025] Dynamic subtest gt_engines: SUCCESS (4.489s)
[178.256107] Starting dynamic subtest: gt_timelines
[194.905070] Dynamic subtest gt_timelines: SUCCESS (16.655s)
[194.912839] Starting dynamic subtest: gt_contexts
[199.207965] Dynamic subtest gt_contexts: SUCCESS (4.302s)
[199.215168] Starting dynamic subtest: gt_lrc
[203.490658] Dynamic subtest gt_lrc: SUCCESS (4.281s)
[203.498856] Starting dynamic subtest: gt_mocs
[206.503219] Dynamic subtest gt_mocs: SUCCESS (3.010s)
[206.513042] Starting dynamic subtest: gt_pm
[213.154417] Dynamic subtest gt_pm: SUCCESS (6.649s)
[213.163890] Starting dynamic subtest: gt_heartbeat
[217.438840] Dynamic subtest gt_heartbeat: SUCCESS (4.283s)
[217.447970] Starting dynamic subtest: gt_tlb
[226.765941] Dynamic subtest gt_tlb: SUCCESS (9.326s)
[226.774977] Starting dynamic subtest: requests
[238.235420] Dynamic subtest requests: SUCCESS (11.469s)
[238.244455] Starting dynamic subtest: migrate
[244.054046] Dynamic subtest migrate: SUCCESS (5.817s)
[244.063267] Starting dynamic subtest: active
[248.368718] Dynamic subtest active: SUCCESS (4.314s)
[248.378299] Starting dynamic subtest: objects
[253.062064] Dynamic subtest objects: SUCCESS (4.692s)
[253.070926] Starting dynamic subtest: mman
[257.841518] Dynamic subtest mman: SUCCESS (4.778s)
[257.855313] Starting dynamic subtest: dmabuf
[262.168911] Dynamic subtest dmabuf: SUCCESS (4.326s)
[262.178424] Starting dynamic subtest: vma
[264.842435] Dynamic subtest vma: SUCCESS (2.671s)
[264.851845] Starting dynamic subtest: coherency
[268.737794] Dynamic subtest coherency: SUCCESS (3.893s)
[268.747746] Starting dynamic subtest: gtt
[278.749458] Dynamic subtest gtt: SUCCESS (10.009s)
[278.758266] Starting dynamic subtest: gem
[286.503069] Dynamic subtest gem: SUCCESS (7.752s)
[286.512467] Starting dynamic subtest: evict
[292.022571] Dynamic subtest evict: SUCCESS (5.518s)
[292.035870] Starting dynamic subtest: hugepages
[302.657439] Dynamic subtest hugepages: SUCCESS (10.632s)
[302.666710] Starting dynamic subtest: gem_contexts
[331.462503] Dynamic subtest gem_contexts: SUCCESS (28.802s)
[331.471671] Starting dynamic subtest: client
[335.833059] Dynamic subtest client: SUCCESS (4.370s)
[335.842755] Starting dynamic subtest: gem_migrate
[340.130217] Dynamic subtest gem_migrate: SUCCESS (4.295s)
[340.139521] Starting dynamic subtest: reset
[342.872459] Dynamic subtest reset: SUCCESS (2.741s)
[342.882000] Starting dynamic subtest: memory_region
[347.149906] Dynamic subtest memory_region: SUCCESS (4.276s)
[347.159495] Starting dynamic subtest: hangcheck
[360.405504] Dynamic subtest hangcheck: SUCCESS (13.254s)
[360.415377] Starting dynamic subtest: execlists
[364.778737] Dynamic subtest execlists: SUCCESS (4.372s)
[364.788220] Starting dynamic subtest: ring_submission
[367.461969] Dynamic subtest ring_submission: SUCCESS (2.680s)
[367.471548] Starting dynamic subtest: perf
[371.749714] Dynamic subtest perf: SUCCESS (4.285s)
[371.759439] Starting dynamic subtest: slpc
[385.791953] Dynamic subtest slpc: SUCCESS (14.041s)
[385.801325] Starting dynamic subtest: guc
[389.707034] Dynamic subtest guc: SUCCESS (3.912s)
[389.715811] Starting dynamic subtest: guc_multi_lrc
[394.032990] Dynamic subtest guc_multi_lrc: SUCCESS (4.324s)
[394.042706] Starting dynamic subtest: guc_hang
[398.924357] Dynamic subtest guc_hang: SUCCESS (4.890s)
[398.933816] Starting dynamic subtest: late_gt_pm
[401.659816] Dynamic subtest late_gt_pm: SUCCESS (2.733s)
[401.662813] Subtest live: FAIL (254.093s)
[401.693327] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[401.694041] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[401.697149] [028/130] (619s left) kms_rotation_crc (sprite-rotation-270)
[403.263310] Starting subtest: sprite-rotation-270
[403.267332] Subtest sprite-rotation-270: SKIP (0.001s)
[403.376496] [FACT kms_rotation_crc (sprite-rotation-270)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[403.377907] [FACT kms_rotation_crc (sprite-rotation-270)] new: kernel.kmod_is_loaded.i915: true
[403.381890] [029/130] (617s left) perf (whitelisted-registers-userspace-config)
[403.519884] Starting subtest: whitelisted-registers-userspace-config
[403.522137] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[403.565894] [030/130] (617s left) kms_invalid_mode (bad-vsync-end)
[403.658254] Starting subtest: bad-vsync-end
[403.660576] Starting dynamic subtest: pipe-A-eDP-1
[403.946481] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[403.957856] Starting dynamic subtest: pipe-B-eDP-1
[403.959825] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[403.966878] Starting dynamic subtest: pipe-C-eDP-1
[403.968744] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[403.976037] Starting dynamic subtest: pipe-D-eDP-1
[403.977931] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[403.979869] Subtest bad-vsync-end: SUCCESS (0.297s)
[404.822177] [031/130] (616s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[404.916738] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[404.921115] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[405.013889] [032/130] (615s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[405.111160] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[414.536231] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.426s)
[414.650576] [033/130] (606s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[414.711788] Starting subtest: etime-multi-wait-all-for-submit-submitted
[414.817659] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.104s)
[414.906968] [034/130] (606s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[415.005757] Starting subtest: fbcpsr-slowdraw
[440.222895] Subtest fbcpsr-slowdraw: SUCCESS (25.219s)
[440.379467] [035/130] (580s left) kms_draw_crc (draw-method-blt)
[440.483633] Starting subtest: draw-method-blt
[440.485786] Starting dynamic subtest: xrgb8888-untiled
[440.726648] Dynamic subtest xrgb8888-untiled: SUCCESS (0.244s)
[440.738701] Starting dynamic subtest: rgb565-untiled
[440.960022] Dynamic subtest rgb565-untiled: SUCCESS (0.231s)
[440.968775] Starting dynamic subtest: xrgb2101010-untiled
[441.181752] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[441.190300] Starting dynamic subtest: xbgr16161616f-untiled
[441.415171] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s)
[441.426408] Starting dynamic subtest: xrgb8888-xtiled
[441.526507] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.110s)
[441.537489] Starting dynamic subtest: rgb565-xtiled
[441.648486] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[441.659598] Starting dynamic subtest: xrgb2101010-xtiled
[441.759650] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[441.768284] Starting dynamic subtest: xbgr16161616f-xtiled
[441.870668] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[441.879065] Starting dynamic subtest: xrgb8888-4tiled
[441.981818] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.110s)
[441.990241] Starting dynamic subtest: rgb565-4tiled
[442.081815] Dynamic subtest rgb565-4tiled: SUCCESS (0.099s)
[442.090127] Starting dynamic subtest: xrgb2101010-4tiled
[442.193059] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.111s)
[442.201786] Starting dynamic subtest: xbgr16161616f-4tiled
[442.318108] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.121s)
[442.319701] Subtest draw-method-blt: SUCCESS (1.834s)
[442.419649] [036/130] (578s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[442.532389] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[442.535823] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[442.630008] [037/130] (578s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[442.724272] Starting subtest: fbcpsr-2p-rte
[442.727661] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[442.825420] [038/130] (578s left) kms_chamelium_frames (hdmi-frame-dump)
[443.224774] Subtest hdmi-frame-dump: SKIP (0.000s)
[444.031528] [039/130] (577s left) gem_mmap_wc (read)
[444.082628] Subtest read: SKIP (0.000s)
[444.150488] [040/130] (577s left) prime_vgem (fence-flip-hang)
[444.226852] Subtest fence-flip-hang: SKIP (0.000s)
[444.320328] [041/130] (576s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[444.402652] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[444.406975] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[444.487528] [042/130] (576s left) gem_busy (close-race)
[444.551651] Starting subtest: close-race
[469.909824] Subtest close-race: SUCCESS (25.355s)
[470.294605] [043/130] (551s left) kms_atomic (plane-cursor-legacy)
[470.432268] Starting subtest: plane-cursor-legacy
[470.434659] Starting dynamic subtest: pipe-A-eDP-1
[470.826941] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.395s)
[470.829519] Subtest plane-cursor-legacy: SUCCESS (0.396s)
[471.687140] [044/130] (549s left) gem_exec_fence (nb-await)
[471.868318] Starting subtest: nb-await
[471.870923] Starting dynamic subtest: rcs0
[471.935085] Dynamic subtest rcs0: SUCCESS (0.067s)
[471.947094] Starting dynamic subtest: bcs0
[471.994781] Dynamic subtest bcs0: SUCCESS (0.056s)
[472.006654] Starting dynamic subtest: vcs0
[472.054832] Dynamic subtest vcs0: SUCCESS (0.058s)
[472.066614] Starting dynamic subtest: vcs1
[472.113426] Dynamic subtest vcs1: SUCCESS (0.056s)
[472.125025] Starting dynamic subtest: vecs0
[472.172288] Dynamic subtest vecs0: SUCCESS (0.057s)
[472.184096] Starting dynamic subtest: ccs0
[472.230987] Dynamic subtest ccs0: SUCCESS (0.057s)
[472.233645] Subtest nb-await: SUCCESS (0.365s)
[474.037269] [045/130] (547s left) gem_ctx_engines (none)
[474.101048] Starting subtest: none
[474.103574] Subtest none: SUCCESS (0.001s)
[474.292434] [046/130] (547s left) kms_psr (pr-primary-mmap-gtt)
[474.397068] Starting subtest: pr-primary-mmap-gtt
[474.401576] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[474.475983] [047/130] (547s left) gem_mmap_wc (invalid-flags)
[474.539829] Subtest invalid-flags: SKIP (0.000s)
[474.600868] [048/130] (546s left) gem_mmap_wc (write-prefaulted)
[474.655736] Subtest write-prefaulted: SKIP (0.000s)
[474.710063] [049/130] (546s left) drm_read (short-buffer-block)
[474.849281] Starting subtest: short-buffer-block
[474.851649] Subtest short-buffer-block: SUCCESS (0.000s)
[475.000435] [050/130] (546s left) gem_ctx_persistence (file)
[475.057330] Starting subtest: file
[475.168552] Subtest file: SUCCESS (0.113s)
[475.280837] [051/130] (546s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[475.383271] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[475.385170] Starting dynamic subtest: pipe-A
[476.433955] Dynamic subtest pipe-A: SUCCESS (1.053s)
[476.442742] Starting dynamic subtest: pipe-B
[478.591910] Dynamic subtest pipe-B: SUCCESS (2.157s)
[478.600945] Starting dynamic subtest: pipe-C
[479.793034] Dynamic subtest pipe-C: SUCCESS (1.199s)
[479.804951] Starting dynamic subtest: pipe-D
[481.024360] Dynamic subtest pipe-D: SUCCESS (1.230s)
[481.026687] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.644s)
[482.226474] [052/130] (539s left) core_getclient (basic)
[482.282539] Starting subtest: basic
[482.284500] Subtest basic: SUCCESS (0.000s)
[482.379781] [053/130] (539s left) vgem_basic (bad-handle)
[482.427977] Starting subtest: bad-handle
[482.430404] Subtest bad-handle: SUCCESS (0.000s)
[482.486393] [054/130] (539s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[482.605335] Starting subtest: 2x-long-flip-vs-cursor-legacy
[482.609713] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[482.758569] [055/130] (539s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[482.873507] Starting subtest: basic-busy-flip-before-cursor-varying-size
[482.878071] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[483.022089] [056/130] (538s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[483.125381] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[489.957380] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.836s)
[490.106600] [057/130] (531s left) perf_pmu (rc6)
[490.152765] Starting subtest: rc6
[490.155101] Starting dynamic subtest: gt0
[496.521614] Dynamic subtest gt0: SUCCESS (6.367s)
[496.533745] Starting dynamic subtest: runtime-pm-gt0
[504.295502] Dynamic subtest runtime-pm-gt0: SUCCESS (7.771s)
[504.308031] Starting dynamic subtest: runtime-pm-long-gt0
[516.779595] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.482s)
[516.791947] Starting dynamic subtest: other-idle-gt0
[523.122542] Dynamic subtest other-idle-gt0: SUCCESS (6.341s)
[523.132448] Starting dynamic subtest: gt1
[529.465373] Dynamic subtest gt1: SUCCESS (6.339s)
[529.478183] Starting dynamic subtest: runtime-pm-gt1
[536.913634] Dynamic subtest runtime-pm-gt1: SUCCESS (7.444s)
[536.926226] Starting dynamic subtest: runtime-pm-long-gt1
[549.350861] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.434s)
[549.362076] Starting dynamic subtest: other-idle-gt1
[555.694944] Dynamic subtest other-idle-gt1: SUCCESS (6.341s)
[555.697883] Subtest rc6: SUCCESS (65.539s)
[556.860183] [058/130] (465s left) gem_mmap (bad-offset)
[556.916923] Subtest bad-offset: SKIP (0.000s)
[556.980018] [059/130] (465s left) kms_chamelium_color (ctm-0-25)
[557.084693] Subtest ctm-0-25: SKIP (0.000s)
[557.140693] [060/130] (464s left) kms_lease (implicit-plane-lease)
[557.234581] Starting subtest: implicit-plane-lease
[557.236646] Subtest implicit-plane-lease: SUCCESS (0.003s)
[557.321168] [061/130] (464s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[557.414490] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[557.417934] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[557.496734] [062/130] (464s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[557.593043] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[557.605939] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[557.660760] [063/130] (464s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[557.766189] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[557.767952] Starting dynamic subtest: pipe-A-eDP-1
[557.853202] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[557.863259] Starting dynamic subtest: pipe-B-eDP-1
[559.050394] Dynamic subtest pipe-B-eDP-1: SKIP (1.194s)
[559.054762] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.285s)
[560.220361] [064/130] (462s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[560.342443] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[578.007183] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (17.662s)
[578.882292] [065/130] (443s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[578.940671] Starting subtest: rel-constants-invalid-rel-gen5
[578.942998] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[579.024447] [066/130] (443s left) gem_exec_fence (basic-wait-all)
[579.092022] Starting subtest: basic-wait-all
[579.174507] Subtest basic-wait-all: SUCCESS (0.083s)
[580.954041] [067/130] (441s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[581.042911] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[581.055031] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[581.117909] [068/130] (441s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[581.224822] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[581.227148] Starting dynamic subtest: pipe-A-eDP-1
[581.250072] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[581.261706] Starting dynamic subtest: pipe-B-eDP-1
[581.283166] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[581.291938] Starting dynamic subtest: pipe-C-eDP-1
[581.313412] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[581.322410] Starting dynamic subtest: pipe-D-eDP-1
[581.344794] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[581.349199] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[583.553655] [069/130] (438s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[583.618107] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[583.620652] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[583.669774] [070/130] (438s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[583.772680] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[583.776966] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[583.821977] [071/130] (438s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[583.922035] Starting subtest: x-tiled-32bpp-rotate-180
[585.289820] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.369s)
[585.414978] [072/130] (437s left) gem_exec_reloc (basic-write-cpu)
[585.472968] Subtest basic-write-cpu: SKIP (0.000s)
[585.549362] [073/130] (437s left) kms_flip (2x-flip-vs-panning)
[585.647750] Starting subtest: 2x-flip-vs-panning
[585.661593] Subtest 2x-flip-vs-panning: SKIP (0.013s)
[585.720070] [074/130] (436s left) core_sysfs (read-all-entries)
[585.774538] Starting subtest: read-all-entries
[585.783245] Subtest read-all-entries: SUCCESS (0.011s)
[585.838486] [075/130] (436s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[585.932094] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[586.768767] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.837s)
[586.899998] [076/130] (435s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[586.993588] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[588.018750] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.024s)
[588.121573] [077/130] (434s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[588.216292] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[588.219928] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[588.293820] [078/130] (434s left) gem_exec_schedule (deep)
[588.479878] Starting subtest: deep
[588.482660] Starting dynamic subtest: rcs0
[593.943451] Dynamic subtest rcs0: SKIP (5.460s)
[593.955768] Starting dynamic subtest: vcs0
[604.241893] Dynamic subtest vcs0: SUCCESS (10.297s)
[604.257395] Starting dynamic subtest: vcs1
[616.286688] Dynamic subtest vcs1: SUCCESS (12.044s)
[616.302967] Starting dynamic subtest: vecs0
[627.439073] Dynamic subtest vecs0: SUCCESS (11.152s)
[627.452625] Starting dynamic subtest: ccs0
[639.443644] Dynamic subtest ccs0: SUCCESS (12.003s)
[639.446330] Subtest deep: SUCCESS (50.961s)
[640.797778] [079/130] (382s left) syncobj_timeline (etime-single-wait-submitted)
[640.857759] Starting subtest: etime-single-wait-submitted
[640.962223] Subtest etime-single-wait-submitted: SUCCESS (0.104s)
[641.018448] [080/130] (381s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[641.124593] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[641.128808] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[641.211474] [081/130] (381s left) api_intel_bb (blit-reloc-purge-cache)
[641.290991] Starting subtest: blit-reloc-purge-cache
[641.295606] Subtest blit-reloc-purge-cache: SKIP (0.001s)
[641.383200] [082/130] (381s left) syncobj_timeline (signal)
[641.451515] Starting subtest: signal
[641.453921] Subtest signal: SUCCESS (0.000s)
[641.540603] [083/130] (381s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[641.651840] Starting subtest: psr2-cursor-plane-move-continuous-sf
[641.654055] Starting dynamic subtest: pipe-A-eDP-1
[652.005302] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.358s)
[652.016234] Starting dynamic subtest: pipe-B-eDP-1
[663.066743] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.059s)
[663.069195] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.420s)
[663.916301] [084/130] (359s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[664.024466] Starting subtest: y-tiled-32bpp-rotate-180
[664.028819] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[664.094329] [085/130] (359s left) kms_pm_dc (dc9-dpms)
[665.185221] Starting subtest: dc9-dpms
[666.623816] Subtest dc9-dpms: SUCCESS (1.440s)
[666.679253] [086/130] (356s left) gem_exercise_blt (fast-copy-emit)
[666.738271] Starting subtest: fast-copy-emit
[666.740737] Starting dynamic subtest: linear-smem-smem-emit
[666.760845] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.022s)
[666.772679] Starting dynamic subtest: xmajor-smem-smem-emit
[666.779312] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.018s)
[666.788826] Starting dynamic subtest: tile4-smem-smem-emit
[666.795821] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.016s)
[666.805308] Starting dynamic subtest: tile64-smem-smem-emit
[666.813915] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.017s)
[666.816307] Subtest fast-copy-emit: SUCCESS (0.076s)
[666.924409] [087/130] (356s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[666.985016] Starting subtest: y-tiled-to-vebox-x-tiled
[666.989465] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[667.147319] [088/130] (356s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[667.253492] Starting subtest: cursorB-vs-flipB-varying-size
[667.258084] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[667.377049] [089/130] (355s left) perf_pmu (idle)
[667.423893] Starting subtest: idle
[667.426168] Starting dynamic subtest: rcs0
[667.961257] Dynamic subtest rcs0: SUCCESS (0.535s)
[667.973571] Starting dynamic subtest: bcs0
[668.477684] Dynamic subtest bcs0: SUCCESS (0.516s)
[668.488088] Starting dynamic subtest: vcs0
[669.004499] Dynamic subtest vcs0: SUCCESS (0.524s)
[669.015196] Starting dynamic subtest: vcs1
[669.524414] Dynamic subtest vcs1: SUCCESS (0.517s)
[669.536949] Starting dynamic subtest: vecs0
[670.040730] Dynamic subtest vecs0: SUCCESS (0.514s)
[670.053350] Starting dynamic subtest: ccs0
[670.556772] Dynamic subtest ccs0: SUCCESS (0.513s)
[670.559741] Subtest idle: SUCCESS (3.135s)
[670.643632] [090/130] (352s left) gem_userptr_blits (stress-purge)
[670.724354] Starting subtest: stress-purge
[676.106753] Subtest stress-purge: SUCCESS (5.385s)
[676.203491] [091/130] (347s left) kms_plane_multiple (2x-tiling-yf)
[676.329996] Starting subtest: 2x-tiling-yf
[676.334050] Subtest 2x-tiling-yf: SKIP (0.000s)
[676.380012] [092/130] (347s left) kms_psr (psr2-cursor-plane-move)
[676.470647] Starting subtest: psr2-cursor-plane-move
[676.472641] Starting dynamic subtest: eDP-1
[678.155423] Dynamic subtest eDP-1: SUCCESS (1.684s)
[678.157635] Subtest psr2-cursor-plane-move: SUCCESS (1.685s)
[679.015547] [093/130] (344s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[679.073226] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[679.075497] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[679.123759] [094/130] (344s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[679.215916] Starting subtest: nonblocking-crc-frame-sequence
[679.217822] Starting dynamic subtest: pipe-A-eDP-1
[679.963300] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.746s)
[679.974284] Starting dynamic subtest: pipe-B-eDP-1
[681.330611] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.366s)
[681.342057] Starting dynamic subtest: pipe-C-eDP-1
[682.731099] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.400s)
[682.741394] Starting dynamic subtest: pipe-D-eDP-1
[684.070820] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.337s)
[684.073074] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.855s)
[684.852596] [095/130] (338s left) gem_exec_reloc (basic-cpu-read)
[684.917019] Subtest basic-cpu-read: SKIP (0.000s)
[684.986527] [096/130] (338s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[685.074647] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[685.076921] Starting dynamic subtest: pipe-A
[686.235205] Dynamic subtest pipe-A: SUCCESS (1.162s)
[686.244309] Starting dynamic subtest: pipe-B
[688.451165] Dynamic subtest pipe-B: SUCCESS (2.214s)
[688.467360] Starting dynamic subtest: pipe-C
[689.676028] Dynamic subtest pipe-C: SUCCESS (1.224s)
[689.686809] Starting dynamic subtest: pipe-D
[690.829766] Dynamic subtest pipe-D: SUCCESS (1.152s)
[690.832258] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.757s)
[692.008175] [097/130] (331s left) kms_chamelium_hpd (vga-hpd)
[692.390833] Subtest vga-hpd: SKIP (0.000s)
[693.231177] [098/130] (330s left) gem_exec_schedule (preempt-queue-contexts-chain)
[693.296448] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[693.567486] [099/130] (330s left) kms_cursor_crc (cursor-onscreen-64x21)
[693.980052] Starting subtest: cursor-onscreen-64x21
[694.030454] Subtest cursor-onscreen-64x21: SKIP (0.045s)
[694.437151] [100/130] (329s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[694.544185] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[694.609930] [101/130] (329s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[694.706156] Starting subtest: yf-tiled-64bpp-rotate-0
[694.710492] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[694.779276] [102/130] (329s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[694.873883] Starting subtest: fbcpsr-indfb-scaledprimary
[700.629352] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.757s)
[700.736887] [103/130] (323s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[700.834687] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[700.838767] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[700.904081] [104/130] (323s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[700.998154] Starting subtest: async-flip-with-page-flip-events-linear
[701.393086] Starting dynamic subtest: pipe-A-eDP-1
[704.148989] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.755s)
[704.512817] Starting dynamic subtest: pipe-B-eDP-1
[707.282944] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.776s)
[707.644716] Starting dynamic subtest: pipe-C-eDP-1
[710.417996] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.780s)
[710.790168] Starting dynamic subtest: pipe-D-eDP-1
[713.544336] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.760s)
[713.546858] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.547s)
[714.716862] [105/130] (309s left) gem_mmap_gtt (basic-write)
[714.773566] Subtest basic-write: SKIP (0.000s)
[714.842240] [106/130] (309s left) kms_scaling_modes (scaling-mode-none)
[714.928663] Starting subtest: scaling-mode-none
[714.930779] Starting dynamic subtest: pipe-A-eDP-1
[715.022771] Dynamic subtest pipe-A-eDP-1: SKIP (0.093s)
[715.033491] Starting dynamic subtest: pipe-B-eDP-1
[715.057886] Dynamic subtest pipe-B-eDP-1: SKIP (0.032s)
[715.067631] Starting dynamic subtest: pipe-C-eDP-1
[715.089327] Dynamic subtest pipe-C-eDP-1: SKIP (0.030s)
[715.100813] Starting dynamic subtest: pipe-D-eDP-1
[715.120402] Dynamic subtest pipe-D-eDP-1: SKIP (0.030s)
[715.124586] Subtest scaling-mode-none: SKIP (0.192s)
[715.205978] [107/130] (308s left) kms_vblank (ts-continuation-modeset-hang)
[715.289179] Starting subtest: ts-continuation-modeset-hang
[715.291417] Starting dynamic subtest: pipe-A-eDP-1
[741.716083] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.425s)
[741.730507] Starting dynamic subtest: pipe-D-eDP-1
[756.702934] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.982s)
[756.705273] Subtest ts-continuation-modeset-hang: SUCCESS (41.411s)
[757.545815] [108/130] (266s left) kms_flip_event_leak (basic)
[757.635595] Starting subtest: basic
[757.637680] Starting dynamic subtest: pipe-A-eDP-1
[758.054351] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.419s)
[758.065876] Starting dynamic subtest: pipe-B-eDP-1
[759.189209] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.133s)
[759.206685] Starting dynamic subtest: pipe-C-eDP-1
[760.295088] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.103s)
[760.313896] Starting dynamic subtest: pipe-D-eDP-1
[761.429135] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[761.431323] Subtest basic: SUCCESS (3.792s)
[762.277480] [109/130] (261s left) gem_tiled_partial_pwrite_pread (writes)
[762.340841] Subtest writes: SKIP (0.000s)
[762.415491] [110/130] (261s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[762.500463] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[762.503046] Starting dynamic subtest: pipe-A-eDP-1
[796.686858] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.831s)
[796.697991] Starting dynamic subtest: pipe-B-eDP-1
[831.606976] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.500s)
[831.617064] Starting dynamic subtest: pipe-C-eDP-1
[866.600223] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.456s)
[866.611116] Starting dynamic subtest: pipe-D-eDP-1
[901.601782] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.440s)
[901.604647] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (17.232s)
[908.820341] [111/130] (121s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[908.957598] Starting subtest: cursorB-vs-flipA-atomic-transitions
[908.963065] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[909.083629] [112/130] (121s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[909.181072] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[913.908668] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.727s)
[915.028519] [113/130] (115s left) kms_color (legacy-gamma)
[915.121709] Starting subtest: legacy-gamma
[915.124195] Starting dynamic subtest: pipe-A-eDP-1
[915.841579] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.721s)
[915.853634] Starting dynamic subtest: pipe-B-eDP-1
[917.270508] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.428s)
[917.281806] Starting dynamic subtest: pipe-C-eDP-1
[918.649515] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.377s)
[918.661463] Starting dynamic subtest: pipe-D-eDP-1
[919.973652] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.320s)
[919.976109] Subtest legacy-gamma: SUCCESS (4.849s)
[920.770027] [114/130] (109s left) gem_userptr_blits (forbidden-operations)
[920.832245] Starting subtest: forbidden-operations
[920.836672] Subtest forbidden-operations: SKIP (0.000s)
[920.933102] [115/130] (109s left) gem_ctx_param (invalid-get-engines)
[920.984906] Starting subtest: invalid-get-engines
[920.987648] Subtest invalid-get-engines: SUCCESS (0.001s)
[921.062026] [116/130] (109s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[921.158493] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[921.164048] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[921.231324] [117/130] (109s left) syncobj_wait (single-wait-all-submitted)
[921.299953] Starting subtest: single-wait-all-submitted
[921.406585] Subtest single-wait-all-submitted: SUCCESS (0.108s)
[921.466386] [118/130] (109s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[921.569747] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[930.260491] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.690s)
[930.352900] [119/130] (100s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[930.446023] Starting subtest: psr-rgb565-draw-mmap-gtt
[930.451348] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[930.512411] [120/130] (100s left) kms_content_protection (dp-mst-type-1)
[930.652505] Starting subtest: dp-mst-type-1
[930.657796] Subtest dp-mst-type-1: SKIP (0.000s)
[930.750474] [121/130] (100s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[930.848882] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[930.854273] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[930.922003] [122/130] ( 99s left) gem_exec_schedule (wide)
[931.103443] Starting subtest: wide
[931.105992] Starting dynamic subtest: rcs0
[942.729030] Dynamic subtest rcs0: SUCCESS (11.628s)
[942.739953] Starting dynamic subtest: vcs0
[956.448199] Dynamic subtest vcs0: SUCCESS (13.718s)
[956.460673] Starting dynamic subtest: vcs1
[968.520790] Dynamic subtest vcs1: SUCCESS (12.069s)
[968.537382] Starting dynamic subtest: vecs0
[980.048196] Dynamic subtest vecs0: SUCCESS (11.525s)
[980.061801] Starting dynamic subtest: ccs0
[995.100055] Dynamic subtest ccs0: SUCCESS (15.048s)
[995.102425] Subtest wide: SUCCESS (63.992s)
[996.276810] [123/130] ( 34s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[996.394808] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[996.399413] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[996.470279] [124/130] ( 34s left) prime_self_import (basic-llseek-bad)
[996.510550] Starting subtest: basic-llseek-bad
[996.529577] Subtest basic-llseek-bad: SUCCESS (0.020s)
[996.613932] [125/130] ( 34s left) gem_softpin (zero)
[996.678292] Starting subtest: zero
[996.682556] Subtest zero: SUCCESS (0.004s)
[996.782509] [126/130] ( 34s left) i915_getparams_basic (basic-subslice-total)
[996.834637] Starting subtest: basic-subslice-total
[996.840215] Subtest basic-subslice-total: SUCCESS (0.000s)
[996.902986] [127/130] ( 34s left) gem_exec_params (dr4-dirt)
[996.971787] Starting subtest: DR4-dirt
[996.974605] Subtest DR4-dirt: SUCCESS (0.000s)
[997.037473] [128/130] ( 34s left) prime_busy (hang)
[997.092593] Starting subtest: hang
[997.094925] Starting dynamic subtest: rcs0
[1023.293369] Dynamic subtest rcs0: SUCCESS (26.201s)
[1023.306782] Starting dynamic subtest: bcs0
[1030.448798] Dynamic subtest bcs0: SUCCESS (7.154s)
[1030.459679] Starting dynamic subtest: vcs0
[1037.422716] Dynamic subtest vcs0: SUCCESS (6.976s)
[1037.433587] Starting dynamic subtest: vcs1
[1044.397211] Dynamic subtest vcs1: SUCCESS (6.972s)
[1044.407390] Starting dynamic subtest: vecs0
[1051.435626] Dynamic subtest vecs0: SUCCESS (7.037s)
[1051.447285] Starting dynamic subtest: ccs0
[1065.275089] Dynamic subtest ccs0: SUCCESS (13.835s)
[1065.277769] Subtest hang: SUCCESS (68.183s)
[1065.346937] Overall timeout time exceeded, stopping.
[1065.363648] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: kms_cursor_legacy
results: parsing output: 2/ for test: kms_psr
results: parsing output: 3/ for test: gem_userptr_blits
results: parsing output: 4/ for test: kms_cursor_crc
results: parsing output: 5/ for test: gem_mmap_offset
results: parsing output: 6/ for test: kms_plane
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: gem_exec_reloc
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: gem_exec_balancer
results: parsing output: 11/ for test: gem_render_copy
results: parsing output: 12/ for test: i915_pm_rps
results: parsing output: 13/ for test: perf
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: perf_pmu
results: parsing output: 16/ for test: fbdev
results: parsing output: 17/ for test: perf
results: parsing output: 18/ for test: gem_pwrite_snooped
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: syncobj_timeline
results: parsing output: 21/ for test: syncobj_wait
results: parsing output: 22/ for test: kms_cursor_legacy
results: parsing output: 23/ for test: gem_exec_schedule
results: parsing output: 24/ for test: gem_exec_capture
results: parsing output: 25/ for test: i915_drm_fdinfo
results: parsing output: 26/ for test: i915_selftest
results: parsing output: 27/ for test: kms_rotation_crc
results: parsing output: 28/ for test: perf
results: parsing output: 29/ for test: kms_invalid_mode
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: syncobj_timeline
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_draw_crc
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_chamelium_frames
results: parsing output: 38/ for test: gem_mmap_wc
results: parsing output: 39/ for test: prime_vgem
results: parsing output: 40/ for test: kms_flip_scaled_crc
results: parsing output: 41/ for test: gem_busy
results: parsing output: 42/ for test: kms_atomic
results: parsing output: 43/ for test: gem_exec_fence
results: parsing output: 44/ for test: gem_ctx_engines
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: gem_mmap_wc
results: parsing output: 47/ for test: gem_mmap_wc
results: parsing output: 48/ for test: drm_read
results: parsing output: 49/ for test: gem_ctx_persistence
results: parsing output: 50/ for test: kms_plane_scaling
results: parsing output: 51/ for test: core_getclient
results: parsing output: 52/ for test: vgem_basic
results: parsing output: 53/ for test: kms_cursor_legacy
results: parsing output: 54/ for test: kms_cursor_legacy
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: perf_pmu
results: parsing output: 57/ for test: gem_mmap
results: parsing output: 58/ for test: kms_chamelium_color
results: parsing output: 59/ for test: kms_lease
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: kms_cursor_legacy
results: parsing output: 64/ for test: gem_exec_params
results: parsing output: 65/ for test: gem_exec_fence
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: kms_ccs
results: parsing output: 68/ for test: syncobj_wait
results: parsing output: 69/ for test: kms_psr2_sf
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: gem_exec_reloc
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: core_sysfs
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_exec_schedule
results: parsing output: 78/ for test: syncobj_timeline
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: api_intel_bb
results: parsing output: 81/ for test: syncobj_timeline
results: parsing output: 82/ for test: kms_psr2_sf
results: parsing output: 83/ for test: kms_big_fb
results: parsing output: 84/ for test: kms_pm_dc
results: parsing output: 85/ for test: gem_exercise_blt
results: parsing output: 86/ for test: gem_render_copy
results: parsing output: 87/ for test: kms_cursor_legacy
results: parsing output: 88/ for test: perf_pmu
results: parsing output: 89/ for test: gem_userptr_blits
results: parsing output: 90/ for test: kms_plane_multiple
results: parsing output: 91/ for test: kms_psr
results: parsing output: 92/ for test: syncobj_timeline
results: parsing output: 93/ for test: kms_pipe_crc_basic
results: parsing output: 94/ for test: gem_exec_reloc
results: parsing output: 95/ for test: kms_plane_scaling
results: parsing output: 96/ for test: kms_chamelium_hpd
results: parsing output: 97/ for test: gem_exec_schedule
results: parsing output: 98/ for test: kms_cursor_crc
results: parsing output: 99/ for test: gem_fenced_exec_thrash
results: parsing output: 100/ for test: kms_big_fb
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_async_flips
results: parsing output: 104/ for test: gem_mmap_gtt
results: parsing output: 105/ for test: kms_scaling_modes
results: parsing output: 106/ for test: kms_vblank
results: parsing output: 107/ for test: kms_flip_event_leak
results: parsing output: 108/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: kms_cursor_legacy
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_color
results: parsing output: 113/ for test: gem_userptr_blits
results: parsing output: 114/ for test: gem_ctx_param
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: syncobj_wait
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_content_protection
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_exec_schedule
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: prime_self_import
results: parsing output: 124/ for test: gem_softpin
results: parsing output: 125/ for test: i915_getparams_basic
results: parsing output: 126/ for test: gem_exec_params
results: parsing output: 127/ for test: prime_busy
results: parsing output: 128/ for test: gem_exec_flush
results: no output, setting notrun
results: parsing output: 129/ for test: gen9_exec_parse
results: no output, setting notrun
Done.