[59.982046] Initializing watchdogs
[59.982113]   /dev/watchdog0
[59.995966] [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]
[60.000131] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.002567] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.005749] [001/132] (960s left) gem_exec_reloc (basic-write-read)
[60.085210] Subtest basic-write-read: SKIP (0.000s)
[60.142218] [002/132] (960s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[60.187998] Starting subtest: invalid-multi-wait-unsubmitted
[60.190455] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[60.299188] [003/132] (960s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[60.390277] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[60.392596] Starting dynamic subtest: pipe-A-eDP-1
[60.401702] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[60.409815] Starting dynamic subtest: pipe-B-eDP-1
[60.418758] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[60.426599] Starting dynamic subtest: pipe-C-eDP-1
[60.435173] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[60.441258] Starting dynamic subtest: pipe-D-eDP-1
[60.449498] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[60.453549] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[62.672640] [004/132] (957s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[62.735922] Starting subtest: y-tiled-ccs-to-yf-tiled
[62.740581] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[62.912744] [005/132] (957s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[63.017417] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[63.021780] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[63.097363] [006/132] (957s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[63.195491] Starting subtest: 4-tiled-64bpp-rotate-180
[65.054617] Subtest 4-tiled-64bpp-rotate-180: FAIL (1.266s)
[65.089677] [007/132] (955s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[65.188871] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[65.192619] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[65.283646] [008/132] (955s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[65.368558] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[65.372301] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.000s)
[65.435596] [009/132] (955s left) gem_exec_balancer (fairslice)
[65.494711] Starting subtest: fairslice
[68.592533] Subtest fairslice: SUCCESS (3.094s)
[68.971735] [010/132] (951s left) kms_invalid_mode (bad-htotal)
[69.074659] Starting subtest: bad-htotal
[69.077215] Starting dynamic subtest: pipe-A-eDP-1
[69.358397] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[69.367190] Starting dynamic subtest: pipe-B-eDP-1
[69.369112] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[69.384331] Starting dynamic subtest: pipe-C-eDP-1
[69.386895] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[69.398520] Starting dynamic subtest: pipe-D-eDP-1
[69.400826] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[69.402989] Subtest bad-htotal: SUCCESS (0.287s)
[70.196540] [011/132] (950s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[70.303819] Starting subtest: yf-tiled-32bpp-rotate-180
[70.308279] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[70.406007] [012/132] (950s left) kms_content_protection (atomic-hdcp14)
[70.552649] Starting subtest: atomic-hdcp14
[70.556740] Subtest atomic-hdcp14: SKIP (0.000s)
[70.678145] [013/132] (950s left) i915_selftest (mock)
[72.315747] Starting subtest: mock
[72.318194] Starting dynamic subtest: sanitycheck
[72.991225] Dynamic subtest sanitycheck: SUCCESS (0.678s)
[72.998590] Starting dynamic subtest: shmem
[73.656256] Dynamic subtest shmem: SUCCESS (0.664s)
[73.664950] Starting dynamic subtest: fence
[81.048389] Dynamic subtest fence: SUCCESS (7.389s)
[81.056939] Starting dynamic subtest: scatterlist
[82.704901] Dynamic subtest scatterlist: SUCCESS (1.656s)
[82.713202] Starting dynamic subtest: syncmap
[85.917900] Dynamic subtest syncmap: SUCCESS (3.211s)
[85.926743] Starting dynamic subtest: uncore
[86.590528] Dynamic subtest uncore: SUCCESS (0.672s)
[86.603175] Starting dynamic subtest: ring
[87.272272] Dynamic subtest ring: SUCCESS (0.681s)
[87.280551] Starting dynamic subtest: engine
[87.940293] Dynamic subtest engine: SUCCESS (0.667s)
[87.948892] Starting dynamic subtest: timelines
[91.790106] Dynamic subtest timelines: SUCCESS (3.848s)
[91.798448] Starting dynamic subtest: requests
[95.484315] Dynamic subtest requests: SUCCESS (3.693s)
[95.492946] Starting dynamic subtest: objects
[96.204414] Dynamic subtest objects: SUCCESS (0.720s)
[96.216827] Starting dynamic subtest: phys
[96.957931] Dynamic subtest phys: SUCCESS (0.753s)
[96.963877] Starting dynamic subtest: dmabuf
[97.681859] Dynamic subtest dmabuf: SUCCESS (0.720s)
[97.690518] Starting dynamic subtest: vma
[103.344525] Dynamic subtest vma: SUCCESS (5.664s)
[103.356196] Starting dynamic subtest: evict
[104.756054] Dynamic subtest evict: SUCCESS (1.411s)
[104.764664] Starting dynamic subtest: gtt
[108.055693] Dynamic subtest gtt: SUCCESS (3.298s)
[108.064766] Starting dynamic subtest: hugepages
[108.910653] Dynamic subtest hugepages: SUCCESS (0.856s)
[108.917374] Starting dynamic subtest: memory_region
[111.408038] Dynamic subtest memory_region: SUCCESS (2.497s)
[111.410608] Subtest mock: SUCCESS (39.092s)
[111.433632] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[111.436576] [014/132] (909s left) fbdev (unaligned-read)
[112.922061] Starting subtest: unaligned-read
[112.940156] Subtest unaligned-read: SUCCESS (0.017s)
[112.978828] [FACT fbdev (unaligned-read)] new: kernel.kmod_is_loaded.i915: true
[112.982350] [015/132] (908s left) perf_pmu (busy-accuracy-50)
[113.040607] Starting subtest: busy-accuracy-50
[113.042490] Starting dynamic subtest: rcs0
[114.581269] Dynamic subtest rcs0: SUCCESS (1.540s)
[114.590824] Starting dynamic subtest: bcs0
[116.129465] Dynamic subtest bcs0: SUCCESS (1.547s)
[116.139411] Starting dynamic subtest: vcs0
[117.671629] Dynamic subtest vcs0: SUCCESS (1.541s)
[117.681501] Starting dynamic subtest: vcs1
[119.213097] Dynamic subtest vcs1: SUCCESS (1.540s)
[119.222992] Starting dynamic subtest: vecs0
[120.756238] Dynamic subtest vecs0: SUCCESS (1.542s)
[120.765737] Starting dynamic subtest: ccs0
[122.301369] Dynamic subtest ccs0: SUCCESS (1.545s)
[122.303819] Subtest busy-accuracy-50: SUCCESS (9.261s)
[122.448924] [016/132] (898s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[122.556602] Starting subtest: plane-upscale-factor-0-25-with-rotation
[122.558891] Starting dynamic subtest: pipe-A
[122.792661] Dynamic subtest pipe-A: SUCCESS (0.238s)
[122.802618] Starting dynamic subtest: pipe-B
[124.062063] Dynamic subtest pipe-B: SUCCESS (1.269s)
[124.071110] Starting dynamic subtest: pipe-C
[125.611955] Dynamic subtest pipe-C: SUCCESS (1.549s)
[125.621475] Starting dynamic subtest: pipe-D
[126.942375] Dynamic subtest pipe-D: SUCCESS (1.330s)
[126.944679] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.387s)
[128.117239] [017/132] (892s left) gem_userptr_blits (sync-unmap-cycles)
[128.184963] Starting subtest: sync-unmap-cycles
[133.557517] Subtest sync-unmap-cycles: SUCCESS (5.373s)
[133.683101] [018/132] (887s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[133.789881] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[133.793485] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[133.875478] [019/132] (887s left) gem_ctx_param (basic-default)
[133.927419] Starting subtest: basic-default
[133.929547] Subtest basic-default: SUCCESS (0.000s)
[134.002604] [020/132] (887s left) syncobj_basic (bad-pad-handle-to-fd)
[134.057630] Starting subtest: bad-pad-handle-to-fd
[134.059463] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[134.171580] [021/132] (887s left) perf_pmu (render-node-busy-idle)
[134.236574] Starting subtest: render-node-busy-idle
[134.238798] Starting dynamic subtest: rcs0
[134.920587] Dynamic subtest rcs0: SUCCESS (0.684s)
[134.931050] Starting dynamic subtest: bcs0
[135.583260] Dynamic subtest bcs0: SUCCESS (0.661s)
[135.594082] Starting dynamic subtest: vcs0
[136.918308] Dynamic subtest vcs0: SUCCESS (1.332s)
[136.929300] Starting dynamic subtest: vcs1
[138.901206] Dynamic subtest vcs1: SUCCESS (1.982s)
[138.911956] Starting dynamic subtest: vecs0
[140.886045] Dynamic subtest vecs0: SUCCESS (1.984s)
[140.897494] Starting dynamic subtest: ccs0
[141.548481] Dynamic subtest ccs0: SUCCESS (0.663s)
[141.550999] Subtest render-node-busy-idle: SUCCESS (7.312s)
[141.621238] [022/132] (879s left) kms_cursor_edge_walk (128x128-left-edge)
[141.715718] Starting subtest: 128x128-left-edge
[141.718088] Starting dynamic subtest: pipe-A-eDP-1
[145.188926] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.473s)
[145.196607] Starting dynamic subtest: pipe-D-eDP-1
[148.319412] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.129s)
[148.321663] Subtest 128x128-left-edge: SUCCESS (6.604s)
[149.126456] [023/132] (872s left) kms_addfb_basic (unused-modifier)
[149.185848] Starting subtest: unused-modifier
[149.188087] Subtest unused-modifier: SUCCESS (0.000s)
[149.315867] [024/132] (872s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[149.415404] Starting subtest: fbc-farfromfence-mmap-gtt
[149.419477] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[149.512053] [025/132] (871s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[149.615624] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[150.383532] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.770s)
[150.557791] [026/132] (870s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[150.613933] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[150.618323] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[150.815329] [027/132] (870s left) kms_lease (lease-revoke)
[150.921176] Starting subtest: lease-revoke
[150.923524] Starting dynamic subtest: pipe-A-eDP-1
[150.950073] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.029s)
[150.960292] Starting dynamic subtest: pipe-B-eDP-1
[150.977842] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.027s)
[150.985854] Starting dynamic subtest: pipe-C-eDP-1
[151.003848] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.027s)
[151.012083] Starting dynamic subtest: pipe-D-eDP-1
[151.032951] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.028s)
[151.035217] Subtest lease-revoke: SUCCESS (0.115s)
[151.153030] [028/132] (870s left) kms_properties (colorop-properties-atomic)
[151.259389] Starting subtest: colorop-properties-atomic
[151.261764] Starting dynamic subtest: pipe-A-eDP-1
[151.614466] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.355s)
[151.624251] Starting dynamic subtest: pipe-B-eDP-1
[152.675851] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.062s)
[152.686660] Starting dynamic subtest: pipe-C-eDP-1
[153.705212] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.028s)
[153.715878] Starting dynamic subtest: pipe-D-eDP-1
[154.758544] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.050s)
[154.760633] Subtest colorop-properties-atomic: SUCCESS (3.499s)
[155.555944] [029/132] (866s left) gem_exec_whisper (basic-forked-all)
[155.625112] Starting subtest: basic-forked-all
[168.855239] Subtest basic-forked-all: SUCCESS (13.226s)
[169.035490] [030/132] (852s left) syncobj_timeline (signal-array)
[169.102828] Starting subtest: signal-array
[169.105174] Subtest signal-array: SUCCESS (0.000s)
[169.212270] [031/132] (852s left) kms_flip (2x-busy-flip)
[169.302891] Starting subtest: 2x-busy-flip
[169.311459] Subtest 2x-busy-flip: SKIP (0.010s)
[169.381629] [032/132] (852s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[169.478573] Starting subtest: fbc-rgb565-draw-mmap-gtt
[169.482788] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[169.583220] [033/132] (852s left) kms_psr (psr2-primary-render)
[169.685117] Starting subtest: psr2-primary-render
[169.687318] Starting dynamic subtest: eDP-1
[171.441780] Dynamic subtest eDP-1: SUCCESS (1.757s)
[171.444303] Subtest psr2-primary-render: SUCCESS (1.757s)
[172.311664] [034/132] (849s left) gem_exec_reloc (basic-wc-cpu)
[172.375863] Subtest basic-wc-cpu: SKIP (0.000s)
[172.443338] [035/132] (849s left) kms_cursor_legacy (flip-vs-cursor-toggle)
[172.560259] Starting subtest: flip-vs-cursor-toggle
[181.088348] Subtest flip-vs-cursor-toggle: SUCCESS (8.528s)
[181.945272] [036/132] (839s left) kms_getfb (getfb-reject-nv12)
[182.052832] Starting subtest: getfb-reject-nv12
[182.055837] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[182.154395] [037/132] (839s left) kms_sequence (get-forked-busy)
[182.259665] Starting subtest: get-forked-busy
[182.261910] Starting dynamic subtest: pipe-A-eDP-1
[184.195330] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.936s)
[184.204938] Starting dynamic subtest: pipe-B-eDP-1
[186.763999] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.567s)
[186.773788] Starting dynamic subtest: pipe-C-eDP-1
[189.373878] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.609s)
[189.386050] Starting dynamic subtest: pipe-D-eDP-1
[191.906866] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.531s)
[191.909257] Subtest get-forked-busy: SUCCESS (9.647s)
[192.712932] [038/132] (829s left) gen7_exec_parse (basic-rejected)
[192.773083] Subtest basic-rejected: SKIP (0.000s)
[192.838300] [039/132] (829s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[192.972529] Starting subtest: cursorA-vs-flipA-varying-size
[204.110705] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.138s)
[204.941959] [040/132] (817s left) syncobj_timeline (etime-single-wait-for-submit-available-unsubmitted)
[204.997251] Starting subtest: etime-single-wait-for-submit-available-unsubmitted
[205.105460] Subtest etime-single-wait-for-submit-available-unsubmitted: SUCCESS (0.108s)
[205.178292] [041/132] (816s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[205.312785] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[216.510540] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.197s)
[217.373480] [042/132] (804s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[217.700613] Starting subtest: cursor-rapid-movement-max-size
[217.707535] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[217.820339] [043/132] (804s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[217.906075] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[217.932983] Starting dynamic subtest: pipe-A-plane-0
[218.576825] Dynamic subtest pipe-A-plane-0: SUCCESS (0.644s)
[218.589242] Starting dynamic subtest: pipe-A-plane-3
[218.978848] Dynamic subtest pipe-A-plane-3: SUCCESS (0.398s)
[220.059078] Starting dynamic subtest: pipe-B-plane-0
[220.697592] Dynamic subtest pipe-B-plane-0: SUCCESS (0.644s)
[220.706144] Starting dynamic subtest: pipe-B-plane-3
[221.141776] Dynamic subtest pipe-B-plane-3: SUCCESS (0.444s)
[221.454931] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.547s)
[222.322254] [044/132] (799s left) i915_drm_fdinfo (virtual-busy-all)
[222.366554] Starting subtest: virtual-busy-all
[222.371328] Subtest virtual-busy-all: SKIP (0.000s)
[222.434183] [045/132] (799s left) syncobj_basic (bad-fd-to-handle)
[222.493210] Starting subtest: bad-fd-to-handle
[222.495597] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[222.604137] [046/132] (799s left) sriov_basic (enable-vfs-bind-unbind-each)
[222.656795] Starting subtest: enable-vfs-bind-unbind-each
[222.658791] Starting dynamic subtest: numvfs-1
[222.733216] Dynamic subtest numvfs-1: FAIL (0.004s)
[222.740810] Starting dynamic subtest: numvfs-2
[222.813595] Dynamic subtest numvfs-2: FAIL (0.003s)
[222.823741] Starting dynamic subtest: numvfs-3
[222.897111] Dynamic subtest numvfs-3: FAIL (0.003s)
[222.906526] Starting dynamic subtest: numvfs-4
[222.978896] Dynamic subtest numvfs-4: FAIL (0.003s)
[222.987622] Starting dynamic subtest: numvfs-5
[223.056903] Dynamic subtest numvfs-5: FAIL (0.004s)
[223.065086] Starting dynamic subtest: numvfs-6
[223.134404] Dynamic subtest numvfs-6: FAIL (0.003s)
[223.142249] Starting dynamic subtest: numvfs-7
[223.211145] Dynamic subtest numvfs-7: FAIL (0.003s)
[223.220353] Starting dynamic subtest: numvfs-random
[223.284066] Dynamic subtest numvfs-random: FAIL (0.003s)
[223.285883] Subtest enable-vfs-bind-unbind-each: FAIL (0.034s)
[223.318405] [047/132] (798s left) kms_chamelium_hpd (dp-hpd)
[223.723816] Subtest dp-hpd: SKIP (0.000s)
[224.535732] [048/132] (797s left) gem_exec_whisper (basic-queues-priority)
[224.597460] Starting subtest: basic-queues-priority
[248.947747] Subtest basic-queues-priority: SUCCESS (24.348s)
[249.126089] [049/132] (773s left) api_intel_allocator (reserve-simple)
[249.173468] Starting subtest: reserve-simple
[249.175934] Subtest reserve-simple: SUCCESS (0.005s)
[249.282132] [050/132] (773s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[249.378128] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs
[249.380399] Starting dynamic subtest: pipe-A-eDP-1
[249.400538] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[249.409050] Starting dynamic subtest: pipe-B-eDP-1
[249.428267] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[249.437765] Starting dynamic subtest: pipe-C-eDP-1
[249.456431] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[249.464490] Starting dynamic subtest: pipe-D-eDP-1
[249.482990] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[249.485072] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.008s)
[251.541897] [051/132] (770s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[251.657856] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[251.661994] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[251.752990] [052/132] (770s left) gem_exec_fence (basic-await)
[251.931035] Starting subtest: basic-await
[251.933118] Starting dynamic subtest: rcs0
[252.003774] Dynamic subtest rcs0: SUCCESS (0.075s)
[252.018399] Starting dynamic subtest: bcs0
[252.079531] Dynamic subtest bcs0: SUCCESS (0.074s)
[252.088404] Starting dynamic subtest: vcs0
[252.154764] Dynamic subtest vcs0: SUCCESS (0.073s)
[252.166537] Starting dynamic subtest: vcs1
[252.227510] Dynamic subtest vcs1: SUCCESS (0.071s)
[252.238914] Starting dynamic subtest: vecs0
[252.307469] Dynamic subtest vecs0: SUCCESS (0.077s)
[252.317407] Starting dynamic subtest: ccs0
[252.380657] Dynamic subtest ccs0: SUCCESS (0.071s)
[252.383099] Subtest basic-await: SUCCESS (0.453s)
[253.984855] [FACT gem_exec_fence (basic-await)] new: kernel.kmod_is_loaded.vgem: true
[253.988284] [053/132] (768s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[254.087279] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[261.034854] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (6.950s)
[261.145321] [054/132] (761s left) kms_psr (pr-suspend)
[261.246601] Starting subtest: pr-suspend
[261.250977] Subtest pr-suspend: SKIP (0.000s)
[261.315367] [055/132] (761s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[261.405164] Starting subtest: multiplane-rotation-cropping-bottom
[278.873528] Subtest multiplane-rotation-cropping-bottom: SUCCESS (17.471s)
[279.002174] [056/132] (743s left) kms_vblank (ts-continuation-dpms-suspend)
[279.101783] Starting subtest: ts-continuation-dpms-suspend
[279.103572] Starting dynamic subtest: pipe-A-eDP-1
[295.811281] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.995s)
[295.825885] Starting dynamic subtest: pipe-D-eDP-1
[313.811085] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.515s)
[313.813537] Subtest ts-continuation-dpms-suspend: SUCCESS (4.514s)
[314.622516] [057/132] (708s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[314.732264] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[321.301372] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.541s)
[321.418810] [058/132] (701s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[321.514192] Starting subtest: compare-crc-sanitycheck-nv12
[321.516546] Starting dynamic subtest: pipe-A-eDP-1
[322.225660] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.708s)
[322.235802] Starting dynamic subtest: pipe-B-eDP-1
[323.467295] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.235s)
[323.478487] Starting dynamic subtest: pipe-C-eDP-1
[324.703930] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.233s)
[324.713264] Starting dynamic subtest: pipe-D-eDP-1
[325.877473] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.170s)
[325.879790] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.354s)
[326.731656] [059/132] (695s left) gem_media_fill (media-fill)
[326.788230] Subtest media-fill: SKIP (0.000s)
[326.912839] [060/132] (695s left) gem_pread (self)
[326.967431] Subtest self: SKIP (0.000s)
[327.022619] [061/132] (695s left) kms_color_pipeline (plane-ctm3x4)
[327.111113] Starting subtest: plane-ctm3x4
[327.235002] Starting dynamic subtest: pipe-A-plane-0
[327.356579] Dynamic subtest pipe-A-plane-0: SUCCESS (0.122s)
[327.365602] Starting dynamic subtest: pipe-A-plane-1
[327.490533] Dynamic subtest pipe-A-plane-1: SUCCESS (0.132s)
[327.500244] Starting dynamic subtest: pipe-A-plane-2
[327.602062] Dynamic subtest pipe-A-plane-2: SUCCESS (0.110s)
[328.834409] Starting dynamic subtest: pipe-B-plane-0
[328.947929] Dynamic subtest pipe-B-plane-0: SUCCESS (0.122s)
[328.957178] Starting dynamic subtest: pipe-B-plane-1
[329.112933] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[329.126946] Starting dynamic subtest: pipe-B-plane-2
[329.235043] Dynamic subtest pipe-B-plane-2: SUCCESS (0.119s)
[330.493799] Starting dynamic subtest: pipe-C-plane-0
[330.620830] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[330.631351] Starting dynamic subtest: pipe-C-plane-1
[330.731495] Dynamic subtest pipe-C-plane-1: SUCCESS (0.111s)
[330.740961] Starting dynamic subtest: pipe-C-plane-2
[330.842781] Dynamic subtest pipe-C-plane-2: SUCCESS (0.110s)
[332.010745] Starting dynamic subtest: pipe-D-plane-0
[332.113968] Dynamic subtest pipe-D-plane-0: SUCCESS (0.111s)
[332.124070] Starting dynamic subtest: pipe-D-plane-1
[332.225334] Dynamic subtest pipe-D-plane-1: SUCCESS (0.110s)
[332.234933] Starting dynamic subtest: pipe-D-plane-2
[332.337198] Dynamic subtest pipe-D-plane-2: SUCCESS (0.111s)
[332.648684] Subtest plane-ctm3x4: SUCCESS (5.530s)
[333.492429] [062/132] (689s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[333.599418] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[333.603540] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[333.700858] [063/132] (689s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[333.793888] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[333.796212] Starting dynamic subtest: pipe-A
[334.177357] Dynamic subtest pipe-A: SUCCESS (0.385s)
[334.188728] Starting dynamic subtest: pipe-B
[335.618677] Dynamic subtest pipe-B: SUCCESS (1.439s)
[335.629700] Starting dynamic subtest: pipe-C
[337.013958] Dynamic subtest pipe-C: SUCCESS (1.392s)
[337.023042] Starting dynamic subtest: pipe-D
[338.456251] Dynamic subtest pipe-D: SUCCESS (1.440s)
[338.458536] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.661s)
[339.620703] [064/132] (683s left) gem_exec_schedule (preemptive-hang)
[339.789182] Starting subtest: preemptive-hang
[339.792038] Starting dynamic subtest: rcs0
[349.217202] Dynamic subtest rcs0: SUCCESS (9.427s)
[349.232654] Starting dynamic subtest: bcs0
[358.175681] Dynamic subtest bcs0: SUCCESS (8.960s)
[358.186710] Starting dynamic subtest: vcs0
[367.200574] Dynamic subtest vcs0: SUCCESS (9.021s)
[367.211955] Starting dynamic subtest: vcs1
[376.222410] Dynamic subtest vcs1: SUCCESS (9.015s)
[376.234867] Starting dynamic subtest: vecs0
[385.177279] Dynamic subtest vecs0: SUCCESS (8.952s)
[385.189563] Starting dynamic subtest: ccs0
[394.211374] Dynamic subtest ccs0: SUCCESS (9.029s)
[394.213683] Subtest preemptive-hang: SUCCESS (54.413s)
[394.457453] [065/132] (628s left) gem_ctx_persistence (engines-hang)
[394.522160] Starting subtest: engines-hang
[394.524504] Starting dynamic subtest: rcs0
[394.596373] Dynamic subtest rcs0: SUCCESS (0.073s)
[394.608963] Starting dynamic subtest: bcs0
[394.695839] Dynamic subtest bcs0: SUCCESS (0.098s)
[394.707778] Starting dynamic subtest: vcs0
[394.789771] Dynamic subtest vcs0: SUCCESS (0.092s)
[394.801583] Starting dynamic subtest: vcs1
[394.884573] Dynamic subtest vcs1: SUCCESS (0.092s)
[394.896288] Starting dynamic subtest: vecs0
[394.972656] Dynamic subtest vecs0: SUCCESS (0.086s)
[394.992168] Starting dynamic subtest: ccs0
[395.066921] Dynamic subtest ccs0: SUCCESS (0.092s)
[395.069432] Subtest engines-hang: SUCCESS (0.546s)
[395.150147] [066/132] (627s left) gem_exec_schedule (deep)
[395.337014] Starting subtest: deep
[395.339692] Starting dynamic subtest: rcs0
[401.404042] Dynamic subtest rcs0: SKIP (6.062s)
[401.423654] Starting dynamic subtest: vcs0
[411.570746] Dynamic subtest vcs0: SUCCESS (10.167s)
[411.583525] Starting dynamic subtest: vcs1
[422.634004] Dynamic subtest vcs1: SUCCESS (11.066s)
[422.646920] Starting dynamic subtest: vecs0
[433.464899] Dynamic subtest vecs0: SUCCESS (10.831s)
[433.476892] Starting dynamic subtest: ccs0
[445.657721] Dynamic subtest ccs0: SUCCESS (12.191s)
[445.660248] Subtest deep: SUCCESS (50.324s)
[447.543933] [067/132] (575s left) gem_wait (busy)
[447.614103] Starting subtest: busy
[447.616281] Starting dynamic subtest: all-engines
[449.079262] Dynamic subtest all-engines: SUCCESS (1.466s)
[449.092392] Starting dynamic subtest: rcs0
[449.622714] Dynamic subtest rcs0: SUCCESS (0.542s)
[449.636624] Starting dynamic subtest: bcs0
[450.163144] Dynamic subtest bcs0: SUCCESS (0.540s)
[450.172495] Starting dynamic subtest: vcs0
[452.087424] Dynamic subtest vcs0: SUCCESS (1.921s)
[452.103930] Starting dynamic subtest: vcs1
[454.071024] Dynamic subtest vcs1: SUCCESS (1.981s)
[454.082899] Starting dynamic subtest: vecs0
[456.055431] Dynamic subtest vecs0: SUCCESS (1.981s)
[456.067511] Starting dynamic subtest: ccs0
[456.596630] Dynamic subtest ccs0: SUCCESS (0.541s)
[456.601660] Subtest busy: SUCCESS (8.986s)
[456.795090] [068/132] (566s left) gem_madvise (dontneed-before-mmap)
[456.832367] Starting subtest: dontneed-before-mmap
[456.848061] Subtest dontneed-before-mmap: SUCCESS (0.019s)
[456.959154] [069/132] (566s left) gem_exec_flush (basic-uc-rw-default)
[457.023877] Starting subtest: basic-uc-rw-default
[462.489002] Subtest basic-uc-rw-default: SUCCESS (5.464s)
[462.678140] [070/132] (560s left) gem_mmap_gtt (basic)
[462.739772] Subtest basic: SKIP (0.000s)
[462.800422] [071/132] (560s left) kms_tv_load_detect (load-detect)
[462.856153] Subtest load-detect: SKIP (0.000s)
[462.921336] [072/132] (560s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[463.031203] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[463.035483] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[463.129745] [073/132] (560s left) gem_wait (invalid-buf)
[463.195609] Starting subtest: invalid-buf
[463.197829] Subtest invalid-buf: SUCCESS (0.000s)
[463.397758] [074/132] (559s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[463.485286] Starting subtest: bad-aux-stride-y-tiled-ccs
[463.487584] Starting dynamic subtest: pipe-A-eDP-1
[463.509925] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[463.520866] Starting dynamic subtest: pipe-B-eDP-1
[463.542370] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[463.552652] Starting dynamic subtest: pipe-C-eDP-1
[463.573680] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[463.584306] Starting dynamic subtest: pipe-D-eDP-1
[463.605816] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[463.610099] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[465.770765] [075/132] (557s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[465.869939] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[465.874186] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[465.994335] [076/132] (557s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[466.082645] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[466.086795] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[466.187875] [077/132] (557s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[466.286702] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[472.838285] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.556s)
[472.958693] [078/132] (550s left) kms_properties (invalid-properties-legacy)
[473.054710] Starting subtest: invalid-properties-legacy
[473.155372] Subtest invalid-properties-legacy: SUCCESS (0.101s)
[473.228420] [079/132] (550s left) gem_mmap_gtt (basic-read-write-distinct)
[473.276494] Subtest basic-read-write-distinct: SKIP (0.000s)
[473.332305] [080/132] (550s left) kms_display_modes (extended-mode-basic)
[473.416925] Starting subtest: extended-mode-basic
[473.421382] Subtest extended-mode-basic: SKIP (0.000s)
[473.487004] [081/132] (550s left) kms_flip (blocking-absolute-wf_vblank)
[473.576581] Starting subtest: blocking-absolute-wf_vblank
[473.578552] Starting dynamic subtest: A-eDP1
[482.347685] Dynamic subtest A-eDP1: SUCCESS (8.771s)
[482.363929] Starting dynamic subtest: B-eDP1
[491.080257] Dynamic subtest B-eDP1: SUCCESS (8.728s)
[491.099049] Starting dynamic subtest: C-eDP1
[499.800003] Dynamic subtest C-eDP1: SUCCESS (8.715s)
[499.820222] Starting dynamic subtest: D-eDP1
[508.533456] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[508.539046] Subtest blocking-absolute-wf_vblank: SUCCESS (34.958s)
[509.751012] [082/132] (513s left) kms_psr2_su (page_flip-xrgb8888)
[509.803045] Subtest page_flip-XRGB8888: SKIP (0.000s)
[509.872089] [083/132] (513s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[509.930042] Starting subtest: multi-wait-for-submit-available-submitted
[509.932371] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.001s)
[510.049865] [084/132] (513s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[510.141410] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[510.152267] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[510.230771] [085/132] (513s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[510.317132] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[510.321509] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[510.389569] [086/132] (513s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[510.478680] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[510.481026] Starting dynamic subtest: pipe-A-eDP-1
[510.506615] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[510.518296] Starting dynamic subtest: pipe-B-eDP-1
[510.542416] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[510.552393] Starting dynamic subtest: pipe-C-eDP-1
[510.576628] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[510.588472] Starting dynamic subtest: pipe-D-eDP-1
[510.612732] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[510.616864] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[512.612569] [087/132] (511s left) kms_chamelium_frames (hdmi-frame-dump)
[513.000737] Subtest hdmi-frame-dump: SKIP (0.000s)
[513.825227] [088/132] (510s left) gem_mmap_offset (mmap-unmap)
[513.886646] Starting subtest: mmap-unmap
[513.889392] Starting dynamic subtest: smem0
[536.455827] Dynamic subtest smem0: SUCCESS (22.563s)
[536.458612] Subtest mmap-unmap: SUCCESS (22.564s)
[536.568595] [089/132] (487s left) i915_module_load (reload)
[536.610430] Starting subtest: reload
[539.763783] Subtest reload: SUCCESS (3.156s)
[539.810804] [090/132] (484s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[541.087523] Starting subtest: flip-vs-cursor-busy-crc-atomic
[541.916058] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.828s)
[542.754523] [091/132] (481s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[542.837843] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[542.840142] Starting dynamic subtest: pipe-A-eDP-1
[542.898454] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[542.906639] Starting dynamic subtest: pipe-B-eDP-1
[542.962374] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[542.972037] Starting dynamic subtest: pipe-C-eDP-1
[543.028091] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[543.037583] Starting dynamic subtest: pipe-D-eDP-1
[543.095244] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[543.099565] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[545.132398] [092/132] (478s left) kms_psr (fbc-psr-cursor-render)
[545.228048] Starting subtest: fbc-psr-cursor-render
[545.230316] Starting dynamic subtest: eDP-1
[545.234651] Dynamic subtest eDP-1: SKIP (0.000s)
[545.238923] Subtest fbc-psr-cursor-render: SKIP (0.001s)
[545.320754] [093/132] (478s left) kms_dsc (dsc-basic)
[545.430098] Starting subtest: dsc-basic
[545.442674] Subtest dsc-basic: SKIP (0.000s)
[545.564119] [094/132] (478s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[545.669349] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[554.786594] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.120s)
[554.920193] [095/132] (469s left) kms_flip (flip-vs-dpms-on-nop)
[555.017511] Starting subtest: flip-vs-dpms-on-nop
[555.019488] Starting dynamic subtest: A-eDP1
[556.921793] Dynamic subtest A-eDP1: SUCCESS (1.906s)
[556.933267] Starting dynamic subtest: B-eDP1
[558.760934] Dynamic subtest B-eDP1: SUCCESS (1.837s)
[558.771969] Starting dynamic subtest: C-eDP1
[560.583497] Dynamic subtest C-eDP1: SUCCESS (1.820s)
[560.592697] Starting dynamic subtest: D-eDP1
[562.397201] Dynamic subtest D-eDP1: SUCCESS (1.812s)
[562.402353] Subtest flip-vs-dpms-on-nop: SUCCESS (7.389s)
[563.571569] [096/132] (460s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[563.678848] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[563.683089] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[563.764225] [097/132] (460s left) gem_exec_schedule (promotion)
[563.835509] Starting subtest: promotion
[563.837771] Starting dynamic subtest: rcs0
[563.931924] Dynamic subtest rcs0: SUCCESS (0.101s)
[563.943806] Starting dynamic subtest: vcs0
[564.013210] Dynamic subtest vcs0: SUCCESS (0.081s)
[564.025891] Starting dynamic subtest: vcs1
[564.099581] Dynamic subtest vcs1: SUCCESS (0.085s)
[564.114420] Starting dynamic subtest: vecs0
[564.176720] Dynamic subtest vecs0: SUCCESS (0.076s)
[564.190208] Starting dynamic subtest: ccs0
[564.263033] Dynamic subtest ccs0: SUCCESS (0.086s)
[564.265794] Subtest promotion: SUCCESS (0.433s)
[565.225328] [098/132] (459s left) syncobj_wait (multi-wait-all-signaled)
[565.280624] Starting subtest: multi-wait-all-signaled
[565.282668] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[565.401425] [099/132] (459s left) gem_mmap_gtt (basic-write-gtt)
[565.446565] Subtest basic-write-gtt: SKIP (0.000s)
[565.514142] [100/132] (458s left) gem_sync (basic-store-all)
[565.578642] Starting subtest: basic-store-all
[569.860965] Subtest basic-store-all: SUCCESS (4.293s)
[570.049518] [101/132] (454s left) kms_addfb_basic (legacy-format)
[570.106370] Starting subtest: legacy-format
[570.128501] Subtest legacy-format: SUCCESS (0.020s)
[570.279820] [102/132] (454s left) syncobj_timeline (32bits-limit)
[570.340523] Starting subtest: 32bits-limit
[570.342856] Subtest 32bits-limit: SUCCESS (0.001s)
[570.445660] [103/132] (454s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[570.551062] Starting subtest: fbcpsr-stridechange
[576.793424] Subtest fbcpsr-stridechange: SUCCESS (6.246s)
[576.918622] [104/132] (447s left) kms_prime (basic-modeset-hybrid)
[577.009227] Subtest basic-modeset-hybrid: SKIP (0.000s)
[577.101197] [105/132] (447s left) kms_pm_rpm (dpms-lpsp)
[579.231362] Starting subtest: dpms-lpsp
[579.545638] Subtest dpms-lpsp: SUCCESS (0.314s)
[579.648692] [106/132] (445s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[579.759288] Starting subtest: cursor-vs-flip-varying-size
[610.532582] Subtest cursor-vs-flip-varying-size: SUCCESS (30.770s)
[611.326552] [107/132] (413s left) kms_addfb_basic (clobberred-modifier)
[611.387104] Starting subtest: clobberred-modifier
[611.391390] Subtest clobberred-modifier: SKIP (0.000s)
[611.536559] [108/132] (413s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[611.626123] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[611.630386] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[611.704711] [109/132] (413s left) kms_cursor_crc (cursor-onscreen-32x10)
[611.867581] Starting subtest: cursor-onscreen-32x10
[611.916492] Subtest cursor-onscreen-32x10: SKIP (0.043s)
[612.158396] [110/132] (412s left) api_intel_bb (object-reloc-keep-cache)
[612.210625] Starting subtest: object-reloc-keep-cache
[612.214972] Subtest object-reloc-keep-cache: SKIP (0.002s)
[612.324059] [111/132] (412s left) gem_exec_create (basic)
[612.388444] Starting subtest: basic
[612.390507] Starting dynamic subtest: smem
[616.059024] Dynamic subtest smem: SUCCESS (3.668s)
[616.062064] Subtest basic: SUCCESS (3.670s)
[616.244390] [112/132] (408s left) gem_exec_fence (parallel)
[616.425942] Starting subtest: parallel
[616.428713] Starting dynamic subtest: rcs0
[618.575438] Dynamic subtest rcs0: SUCCESS (2.149s)
[618.587438] Starting dynamic subtest: bcs0
[620.724809] Dynamic subtest bcs0: SUCCESS (2.148s)
[620.735482] Starting dynamic subtest: vcs0
[622.873677] Dynamic subtest vcs0: SUCCESS (2.148s)
[622.884809] Starting dynamic subtest: vcs1
[625.031188] Dynamic subtest vcs1: SUCCESS (2.155s)
[625.041682] Starting dynamic subtest: vecs0
[627.184017] Dynamic subtest vecs0: SUCCESS (2.151s)
[627.197268] Starting dynamic subtest: ccs0
[629.335626] Dynamic subtest ccs0: SUCCESS (2.150s)
[629.337857] Subtest parallel: SUCCESS (12.909s)
[630.174795] [113/132] (394s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[630.273823] Starting subtest: pr-primary-plane-update-sf-dmg-area
[630.278180] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[630.350320] [114/132] (394s left) kms_psr (fbc-pr-sprite-render)
[630.436839] Starting subtest: fbc-pr-sprite-render
[630.440691] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[630.520158] [115/132] (394s left) i915_pm_rpm (gem-evict-pwrite)
[632.252561] Starting subtest: gem-evict-pwrite
[632.257420] Subtest gem-evict-pwrite: SKIP (0.000s)
[632.769540] [116/132] (392s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[632.861264] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[632.865261] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[632.959931] [117/132] (392s left) core_getversion (all-cards)
[633.003761] Starting subtest: all-cards
[633.010874] Subtest all-cards: SUCCESS (0.011s)
[633.058650] [118/132] (392s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[633.174367] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[633.178586] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[633.264736] [119/132] (392s left) gem_eio (wait-10ms)
[633.505261] Starting subtest: wait-10ms
[633.864165] Subtest wait-10ms: SUCCESS (0.360s)
[633.990788] [120/132] (391s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[634.098554] Starting subtest: y-tiled-8bpp-rotate-90
[634.102219] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[634.206844] [121/132] (391s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[634.318086] Starting subtest: 2x-long-cursor-vs-flip-atomic
[634.321923] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.001s)
[634.462019] [122/132] (390s left) gem_eio (throttle)
[634.682868] Starting subtest: throttle
[635.007827] Subtest throttle: SUCCESS (0.326s)
[635.149131] [123/132] (390s left) gem_ctx_exec (basic-invalid-context)
[635.206306] Starting subtest: basic-invalid-context
[635.210116] Subtest basic-invalid-context: SUCCESS (0.004s)
[635.323398] [124/132] (390s left) gem_softpin (allocator-evict)
[635.379020] Starting subtest: allocator-evict
[635.380896] Starting dynamic subtest: rcs0
[658.876431] Dynamic subtest rcs0: SUCCESS (23.492s)
[658.886070] Starting dynamic subtest: bcs0
[682.745465] Dynamic subtest bcs0: SUCCESS (23.863s)
[682.758065] Starting dynamic subtest: vcs0
[706.697689] Dynamic subtest vcs0: SUCCESS (23.946s)
[706.710190] Starting dynamic subtest: vcs1
[730.521095] Dynamic subtest vcs1: SUCCESS (23.815s)
[730.533819] Starting dynamic subtest: vecs0
[754.228685] Dynamic subtest vecs0: SUCCESS (23.701s)
[754.239193] Starting dynamic subtest: ccs0
[777.725907] Dynamic subtest ccs0: SUCCESS (23.490s)
[777.728347] Subtest allocator-evict: SUCCESS (142.318s)
[777.814326] [125/132] (247s left) gem_mmap_wc (read)
[777.909649] Subtest read: SKIP (0.000s)
[777.967202] [126/132] (247s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[778.074061] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[778.078446] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[778.189238] [127/132] (247s left) gem_linear_blits (normal)
[778.346265] Starting subtest: normal
[782.383748] Subtest normal: SUCCESS (4.038s)
[782.498949] [128/132] (243s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[782.637119] Starting subtest: modeset-transition-nonblocking-fencing
[782.639019] Starting dynamic subtest: 1x-outputs
[793.742008] Dynamic subtest 1x-outputs: SUCCESS (11.105s)
[793.744786] Subtest modeset-transition-nonblocking-fencing: SUCCESS (11.107s)
[794.603656] [129/132] (231s left) gen3_render_tiledx_blits
[794.725634] [130/132] (231s left) gem_gtt_cpu_tlb
[794.840728] [131/132] (230s left) sysfs_timeslice_duration (duration)
[794.889044] Starting subtest: duration
[794.891151] Starting dynamic subtest: vecs0
[808.046410] Dynamic subtest vecs0: SUCCESS (13.164s)
[808.059426] Starting dynamic subtest: ccs0
[821.183247] Dynamic subtest ccs0: SUCCESS (13.141s)
[821.196235] Starting dynamic subtest: vcs0
[835.030240] Dynamic subtest vcs0: SUCCESS (13.847s)
[835.044421] Starting dynamic subtest: bcs0
[848.162028] Dynamic subtest bcs0: SUCCESS (13.134s)
[848.174639] Starting dynamic subtest: rcs0
[861.315035] Dynamic subtest rcs0: SUCCESS (13.153s)
[861.329795] Starting dynamic subtest: vcs1
[875.030070] Dynamic subtest vcs1: SUCCESS (13.713s)
[875.032873] Subtest duration: SUCCESS (80.167s)
[875.110410] [132/132] (150s left) gem_ctx_persistence (legacy-engines-queued)
[875.185450] Starting subtest: legacy-engines-queued
[875.187779] Starting dynamic subtest: render
[875.268553] Dynamic subtest render: SUCCESS (0.082s)
[875.281689] Starting dynamic subtest: bsd1
[877.081318] Dynamic subtest bsd1: SUCCESS (1.809s)
[877.096768] Starting dynamic subtest: bsd2
[879.060620] Dynamic subtest bsd2: SUCCESS (1.976s)
[879.074032] Starting dynamic subtest: blt
[879.110415] Dynamic subtest blt: SUCCESS (0.049s)
[879.121833] Starting dynamic subtest: vebox
[881.046282] Dynamic subtest vebox: SUCCESS (1.932s)
[881.048923] Subtest legacy-engines-queued: SUCCESS (5.861s)
[881.123935] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_reloc
results: parsing output: 1/ for test: syncobj_timeline
results: parsing output: 2/ for test: kms_ccs
results: parsing output: 3/ for test: gem_render_copy
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_flip_scaled_crc
results: parsing output: 8/ for test: gem_exec_balancer
results: parsing output: 9/ for test: kms_invalid_mode
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_content_protection
results: parsing output: 12/ for test: i915_selftest
results: parsing output: 13/ for test: fbdev
results: parsing output: 14/ for test: perf_pmu
results: parsing output: 15/ for test: kms_plane_scaling
results: parsing output: 16/ for test: gem_userptr_blits
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: gem_ctx_param
results: parsing output: 19/ for test: syncobj_basic
results: parsing output: 20/ for test: perf_pmu
results: parsing output: 21/ for test: kms_cursor_edge_walk
results: parsing output: 22/ for test: kms_addfb_basic
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: gem_render_copy
results: parsing output: 26/ for test: kms_lease
results: parsing output: 27/ for test: kms_properties
results: parsing output: 28/ for test: gem_exec_whisper
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_psr
results: parsing output: 33/ for test: gem_exec_reloc
results: parsing output: 34/ for test: kms_cursor_legacy
results: parsing output: 35/ for test: kms_getfb
results: parsing output: 36/ for test: kms_sequence
results: parsing output: 37/ for test: gen7_exec_parse
results: parsing output: 38/ for test: kms_cursor_legacy
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: kms_cursor_legacy
results: parsing output: 41/ for test: kms_cursor_crc
results: parsing output: 42/ for test: kms_plane
results: parsing output: 43/ for test: i915_drm_fdinfo
results: parsing output: 44/ for test: syncobj_basic
results: parsing output: 45/ for test: sriov_basic
results: parsing output: 46/ for test: kms_chamelium_hpd
results: parsing output: 47/ for test: gem_exec_whisper
results: parsing output: 48/ for test: api_intel_allocator
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_exec_fence
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: kms_rotation_crc
results: parsing output: 55/ for test: kms_vblank
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_pipe_crc_basic
results: parsing output: 58/ for test: gem_media_fill
results: parsing output: 59/ for test: gem_pread
results: parsing output: 60/ for test: kms_color_pipeline
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_plane_scaling
results: parsing output: 63/ for test: gem_exec_schedule
results: parsing output: 64/ for test: gem_ctx_persistence
results: parsing output: 65/ for test: gem_exec_schedule
results: parsing output: 66/ for test: gem_wait
results: parsing output: 67/ for test: gem_madvise
results: parsing output: 68/ for test: gem_exec_flush
results: parsing output: 69/ for test: gem_mmap_gtt
results: parsing output: 70/ for test: kms_tv_load_detect
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: gem_wait
results: parsing output: 73/ for test: kms_ccs
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_properties
results: parsing output: 78/ for test: gem_mmap_gtt
results: parsing output: 79/ for test: kms_display_modes
results: parsing output: 80/ for test: kms_flip
results: parsing output: 81/ for test: kms_psr2_su
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_plane
results: parsing output: 85/ for test: kms_ccs
results: parsing output: 86/ for test: kms_chamelium_frames
results: parsing output: 87/ for test: gem_mmap_offset
results: parsing output: 88/ for test: i915_module_load
results: parsing output: 89/ for test: kms_cursor_legacy
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: kms_psr
results: parsing output: 92/ for test: kms_dsc
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_flip
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_exec_schedule
results: parsing output: 97/ for test: syncobj_wait
results: parsing output: 98/ for test: gem_mmap_gtt
results: parsing output: 99/ for test: gem_sync
results: parsing output: 100/ for test: kms_addfb_basic
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_prime
results: parsing output: 104/ for test: kms_pm_rpm
results: parsing output: 105/ for test: kms_cursor_legacy
results: parsing output: 106/ for test: kms_addfb_basic
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_cursor_crc
results: parsing output: 109/ for test: api_intel_bb
results: parsing output: 110/ for test: gem_exec_create
results: parsing output: 111/ for test: gem_exec_fence
results: parsing output: 112/ for test: kms_psr2_sf
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: i915_pm_rpm
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: core_getversion
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_eio
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_cursor_legacy
results: parsing output: 121/ for test: gem_eio
results: parsing output: 122/ for test: gem_ctx_exec
results: parsing output: 123/ for test: gem_softpin
results: parsing output: 124/ for test: gem_mmap_wc
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: gem_linear_blits
results: parsing output: 127/ for test: kms_atomic_transition
results: parsing output: 128/ for test: gen3_render_tiledx_blits
results: parsing output: 129/ for test: gem_gtt_cpu_tlb
results: parsing output: 130/ for test: sysfs_timeslice_duration
results: parsing output: 131/ for test: gem_ctx_persistence
Done.