[86.727790] Initializing watchdogs
[86.728062]   /dev/watchdog0
[86.766875] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[86.782201] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[86.788528] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[86.793581] [001/133] (960s left) kms_joiner (invalid-modeset-big-joiner)
[86.992678] Starting subtest: invalid-modeset-big-joiner
[86.997083] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[87.128397] [002/133] (960s left) prime_self_import (export-vs-gem_close-race)
[87.230247] Starting subtest: export-vs-gem_close-race
[92.244602] Subtest export-vs-gem_close-race: SUCCESS (5.015s)
[92.397528] [003/133] (955s left) i915_power (sanity)
[92.501701] Starting subtest: sanity
[96.534827] Subtest sanity: SUCCESS (4.027s)
[96.685268] [004/133] (950s left) kms_psr (psr-primary-page-flip)
[96.865247] Subtest psr-primary-page-flip: SKIP (0.000s)
[96.956393] [005/133] (950s left) prime_vgem (basic-fence-mmap)
[97.198416] Starting subtest: basic-fence-mmap
[97.258124] Subtest basic-fence-mmap: SUCCESS (0.058s)
[97.410719] [FACT prime_vgem (basic-fence-mmap)] new: kernel.kmod_is_loaded.vgem: true
[97.414498] [006/133] (950s left) kms_tiled_display (basic-test-pattern)
[97.703470] Starting subtest: basic-test-pattern
[99.046529] Subtest basic-test-pattern: FAIL (1.095s)
[99.125157] [007/133] (948s left) gem_exec_suspend (basic-s4-devices)
[99.264873] Starting subtest: basic-S4-devices
[99.266655] Starting dynamic subtest: smem
[110.946134] Dynamic subtest smem: SUCCESS (11.683s)
[110.947255] Subtest basic-S4-devices: SUCCESS (11.685s)
[116.243876] [008/133] (936s left) gem_userptr_blits (dmabuf-unsync)
[116.365149] Subtest dmabuf-unsync: SKIP (0.000s)
[116.550211] [009/133] (936s left) kms_plane_scaling (planes-downscale-factor-0-5)
[116.674049] Starting subtest: planes-downscale-factor-0-5
[116.677546] Starting dynamic subtest: pipe-A
[117.003047] Dynamic subtest pipe-A: SUCCESS (0.319s)
[117.057107] Starting dynamic subtest: pipe-B
[117.420187] Dynamic subtest pipe-B: SUCCESS (0.428s)
[117.465293] Starting dynamic subtest: pipe-C
[117.511379] Dynamic subtest pipe-C: SUCCESS (0.089s)
[117.515488] Subtest planes-downscale-factor-0-5: SUCCESS (0.838s)
[117.994547] [010/133] (935s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[118.125336] Starting subtest: plane-lut1d-ctm3x4-lut1d
[119.341252] Subtest plane-lut1d-ctm3x4-lut1d: SKIP (1.211s)
[119.688390] [011/133] (933s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[119.838287] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[119.839996] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.000s)
[119.971376] [012/133] (933s left) gem_ctx_persistence (heartbeat-stop)
[120.081400] Starting subtest: heartbeat-stop
[120.216138] Subtest heartbeat-stop: SUCCESS (0.134s)
[120.354961] [013/133] (933s left) syncobj_timeline (etime-multi-wait-all-submitted)
[120.462082] Starting subtest: etime-multi-wait-all-submitted
[120.572245] Subtest etime-multi-wait-all-submitted: SUCCESS (0.107s)
[120.720585] [014/133] (932s left) gem_exec_reloc (basic-scanout)
[120.870096] Starting subtest: basic-scanout
[120.873877] Starting dynamic subtest: rcs0
[121.418595] Dynamic subtest rcs0: SUCCESS (0.548s)
[121.439474] Starting dynamic subtest: bcs0
[121.615622] Dynamic subtest bcs0: SUCCESS (0.197s)
[121.637282] Starting dynamic subtest: vcs0
[121.814579] Dynamic subtest vcs0: SUCCESS (0.196s)
[121.835497] Starting dynamic subtest: vecs0
[122.013365] Dynamic subtest vecs0: SUCCESS (0.200s)
[122.014478] Subtest basic-scanout: SUCCESS (1.148s)
[122.291295] [015/133] (931s left) gem_exec_balancer (bonded-pair)
[122.420279] Starting subtest: bonded-pair
[126.812589] Subtest bonded-pair: SUCCESS (4.393s)
[127.075230] [016/133] (926s left) syncobj_wait (invalid-reset-bad-pad)
[127.175317] Starting subtest: invalid-reset-bad-pad
[127.177277] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[127.324338] [017/133] (926s left) i915_drm_fdinfo (memory-info-resident)
[127.433476] Starting subtest: memory-info-resident
[127.436225] Starting dynamic subtest: smem0
[128.807158] Dynamic subtest smem0: SUCCESS (1.374s)
[128.810516] Subtest memory-info-resident: SUCCESS (1.375s)
[128.959205] [018/133] (924s left) api_intel_allocator (simple-allocator)
[129.060789] Starting subtest: simple-allocator
[129.062131] Starting dynamic subtest: basic
[129.069136] Dynamic subtest basic: SUCCESS (0.007s)
[129.089103] Starting dynamic subtest: parallel-one
[129.487159] Dynamic subtest parallel-one: SUCCESS (0.420s)
[129.527333] Starting dynamic subtest: print
[129.528497] Dynamic subtest print: SUCCESS (0.000s)
[129.551318] Starting dynamic subtest: reuse
[129.552507] Dynamic subtest reuse: SUCCESS (0.000s)
[129.576221] Starting dynamic subtest: reserve
[129.579544] Dynamic subtest reserve: SUCCESS (0.000s)
[129.599723] Starting dynamic subtest: fork-reopen-allocator
[129.600840] Dynamic subtest fork-reopen-allocator: SUCCESS (0.032s)
[129.601950] Subtest simple-allocator: SUCCESS (0.464s)
[129.682888] [019/133] (924s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[129.845751] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[129.850054] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[130.003532] [020/133] (923s left) kms_cursor_crc (cursor-random-32x10)
[130.150135] Starting subtest: cursor-random-32x10
[130.314149] Subtest cursor-random-32x10: SKIP (0.160s)
[130.473858] [021/133] (923s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[130.606409] Starting subtest: primary-y-tiled-reflect-x-180
[130.610062] Subtest primary-y-tiled-reflect-x-180: SKIP (0.003s)
[130.743828] [022/133] (923s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[130.880253] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[130.886193] Starting dynamic subtest: pipe-A-HDMI-A-1
[131.411521] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.514s)
[131.450770] Starting dynamic subtest: pipe-A-HDMI-A-2
[131.864768] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.457s)
[131.907369] Starting dynamic subtest: pipe-B-HDMI-A-1
[132.344469] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.477s)
[132.365725] Starting dynamic subtest: pipe-B-HDMI-A-2
[132.780032] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.431s)
[132.814250] Starting dynamic subtest: pipe-C-HDMI-A-1
[132.847528] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s)
[132.879416] Starting dynamic subtest: pipe-C-HDMI-A-2
[132.887273] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[132.888088] Subtest crc-primary-rotation-180-y-tiled-ccs: SUCCESS (1.910s)
[133.586334] [023/133] (920s left) gem_exec_whisper (basic-fds-priority)
[133.717499] Starting subtest: basic-fds-priority
[143.290328] Subtest basic-fds-priority: SUCCESS (9.573s)
[143.539183] [024/133] (910s left) kms_chamelium_frames (hdmi-crc-multiple)
[144.050294] Subtest hdmi-crc-multiple: SKIP (0.000s)
[144.349882] [025/133] (910s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[144.508080] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[144.511329] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[144.652344] [026/133] (909s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier)
[144.786563] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier
[144.788458] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier: SKIP (0.000s)
[144.919567] [027/133] (909s left) syncobj_wait (single-wait-signaled)
[145.022170] Starting subtest: single-wait-signaled
[145.023416] Subtest single-wait-signaled: SUCCESS (0.001s)
[145.168916] [028/133] (909s left) kms_cursor_crc (cursor-offscreen-64x21)
[145.313857] Starting subtest: cursor-offscreen-64x21
[145.378138] Starting dynamic subtest: pipe-A-HDMI-A-1
[148.250351] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.864s)
[148.359058] Starting dynamic subtest: pipe-C-HDMI-A-1
[150.833828] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.524s)
[150.835364] Subtest cursor-offscreen-64x21: SUCCESS (5.517s)
[151.171326] [029/133] (903s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[151.332190] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[151.337078] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[151.484344] [030/133] (903s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[151.639527] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[151.642205] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.001s)
[151.783916] [031/133] (903s left) gem_pxp (reject-modify-context-protection-off-3)
[154.422396] Starting subtest: reject-modify-context-protection-off-3
[154.426591] Subtest reject-modify-context-protection-off-3: SKIP (0.000s)
[154.571301] [032/133] (900s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[154.675361] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[154.788209] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.109s)
[154.935543] [033/133] (900s left) prime_mmap (test_correct)
[155.036143] Starting subtest: test_correct
[155.038099] Starting dynamic subtest: test_correct-smem
[155.043804] Dynamic subtest test_correct-smem: SUCCESS (0.011s)
[155.044895] Subtest test_correct: SUCCESS (0.012s)
[155.203196] [034/133] (900s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[155.356076] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[155.583883] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.230s)
[155.833956] [035/133] (899s left) kms_atomic (plane-cursor-legacy)
[155.972055] Starting subtest: plane-cursor-legacy
[155.982077] Starting dynamic subtest: pipe-A-HDMI-A-1
[156.511070] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.533s)
[156.512148] Subtest plane-cursor-legacy: SUCCESS (0.539s)
[156.847960] [036/133] (898s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[157.012172] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[157.020249] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.004s)
[157.162730] [037/133] (898s left) vgem_basic (create)
[157.279681] Starting subtest: create
[157.280684] Subtest create: SUCCESS (0.001s)
[157.400721] [038/133] (898s left) syncobj_wait (single-wait-all-for-submit-submitted)
[157.500205] Starting subtest: single-wait-all-for-submit-submitted
[157.602632] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.101s)
[157.748635] [039/133] (898s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[157.892277] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[160.941284] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SUCCESS (3.049s)
[161.150485] [040/133] (894s left) gem_spin_batch (engines)
[161.273061] Starting subtest: engines
[161.276698] Starting dynamic subtest: rcs0
[164.510237] Dynamic subtest rcs0: SUCCESS (3.231s)
[164.531233] Starting dynamic subtest: bcs0
[167.742232] Dynamic subtest bcs0: SUCCESS (3.230s)
[167.762894] Starting dynamic subtest: vcs0
[170.969058] Dynamic subtest vcs0: SUCCESS (3.229s)
[170.991800] Starting dynamic subtest: vecs0
[174.197973] Dynamic subtest vecs0: SUCCESS (3.227s)
[174.199158] Subtest engines: SUCCESS (12.923s)
[174.451718] [041/133] (881s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[174.566395] Starting subtest: tiled-to-untiled
[174.682936] Subtest tiled-to-untiled: SUCCESS (0.114s)
[174.834975] [042/133] (881s left) perf_pmu (init-wait)
[174.944058] Starting subtest: init-wait
[174.945412] Starting dynamic subtest: rcs0
[174.946476] Dynamic subtest rcs0: SUCCESS (0.003s)
[174.983718] Starting dynamic subtest: bcs0
[174.984833] Dynamic subtest bcs0: SUCCESS (0.001s)
[175.007277] Starting dynamic subtest: vcs0
[175.009154] Dynamic subtest vcs0: SUCCESS (0.001s)
[175.029924] Starting dynamic subtest: vecs0
[175.031264] Dynamic subtest vecs0: SUCCESS (0.001s)
[175.032430] Subtest init-wait: SUCCESS (0.012s)
[175.114662] [043/133] (881s left) gem_exec_await (wide-all)
[175.413906] Starting subtest: wide-all
[197.639200] Subtest wide-all: SUCCESS (22.231s)
[197.915033] [044/133] (858s left) kms_frontbuffer_tracking (fbc-tiling-4)
[198.187615] Starting subtest: fbc-tiling-4
[198.191135] Subtest fbc-tiling-4: SKIP (0.000s)
[198.349548] [045/133] (857s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[198.462404] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[198.464082] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.002s)
[198.599481] [046/133] (857s left) drm_read (empty-nonblock)
[199.047390] Starting subtest: empty-nonblock
[199.048472] Subtest empty-nonblock: SUCCESS (0.000s)
[199.349154] [047/133] (857s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[199.515113] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[201.959072] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SUCCESS (2.444s)
[202.149543] [048/133] (854s left) kms_cursor_legacy (torture-bo)
[202.283832] Starting subtest: torture-bo
[202.287121] Starting dynamic subtest: pipe-A
[208.723098] Dynamic subtest pipe-A: SUCCESS (6.436s)
[208.750238] Starting dynamic subtest: pipe-B
[215.189622] Dynamic subtest pipe-B: SUCCESS (6.468s)
[215.211499] Starting dynamic subtest: pipe-C
[220.714768] Dynamic subtest pipe-C: SUCCESS (5.521s)
[220.737135] Starting dynamic subtest: all-pipes
[226.236466] Dynamic subtest all-pipes: SUCCESS (5.522s)
[226.240196] Subtest torture-bo: SUCCESS (23.956s)
[226.402664] [049/133] (830s left) dumb_buffer (map-valid)
[226.504901] Starting subtest: map-valid
[226.505972] Subtest map-valid: SUCCESS (0.003s)
[226.665507] [050/133] (830s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[226.819191] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[226.821878] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[227.028289] [051/133] (829s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[227.169209] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[227.173714] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[227.315120] [052/133] (829s left) gem_wait (await)
[227.453284] Starting subtest: await
[227.455357] Starting dynamic subtest: all-engines
[228.497589] Dynamic subtest all-engines: SUCCESS (1.040s)
[228.524960] Starting dynamic subtest: rcs0
[229.526375] Dynamic subtest rcs0: SUCCESS (1.029s)
[229.555669] Starting dynamic subtest: bcs0
[230.556134] Dynamic subtest bcs0: SUCCESS (1.030s)
[230.587435] Starting dynamic subtest: vcs0
[231.590353] Dynamic subtest vcs0: SUCCESS (1.029s)
[231.616336] Starting dynamic subtest: vecs0
[232.622567] Dynamic subtest vecs0: SUCCESS (1.034s)
[232.623509] Subtest await: SUCCESS (5.174s)
[232.901523] [053/133] (824s left) gem_mmap_offset (close-race)
[233.010700] Starting subtest: close-race
[253.726798] Subtest close-race: SUCCESS (20.722s)
[253.883816] [054/133] (803s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[254.548183] Starting subtest: syncobj-timeline-invalid-flags
[254.549215] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s)
[254.804131] [055/133] (802s left) kms_prop_blob (blob-prop-lifetime)
[254.904752] Starting subtest: blob-prop-lifetime
[254.906962] Subtest blob-prop-lifetime: SUCCESS (0.002s)
[255.050640] [056/133] (802s left) gem_eio (in-flight-suspend)
[255.185724] Starting subtest: in-flight-suspend