[87.398424] Initializing watchdogs
[87.398632]   /dev/watchdog0
[87.437736] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[87.451788] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.459772] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.467124] [001/132] (960s left) gem_exec_params (dr4-dirt)
[87.594476] Starting subtest: DR4-dirt
[87.596598] Subtest DR4-dirt: SUCCESS (0.000s)
[87.751960] [002/132] (960s left) gem_wait (invalid-buf)
[87.858520] Starting subtest: invalid-buf
[87.860494] Subtest invalid-buf: SUCCESS (0.000s)
[88.138170] [003/132] (959s left) gem_exec_whisper (basic-forked)
[88.260553] Starting subtest: basic-forked
[92.670054] Subtest basic-forked: SUCCESS (4.402s)
[92.900085] [004/132] (955s left) kms_pm_dc (dc5-psr)
[94.090686] Starting subtest: dc5-psr
[94.095078] Subtest dc5-psr: SKIP (0.000s)
[94.220066] [005/132] (954s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[94.411489] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[94.415446] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[94.552376] [006/132] (953s left) kms_getfb (getfb-handle-not-fb)
[94.726164] Starting subtest: getfb-handle-not-fb
[94.728211] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[94.870206] [007/132] (953s left) syncobj_eventfd (timeline-wait-signaled)
[94.972122] Starting subtest: timeline-wait-signaled
[94.974193] Subtest timeline-wait-signaled: SUCCESS (0.002s)
[95.104146] [008/132] (953s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[95.287802] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[95.292022] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[95.438622] [009/132] (953s left) gem_pread (bench)
[95.539199] Starting subtest: bench
[98.526789] Subtest bench: SUCCESS (2.983s)
[98.674377] [010/132] (950s left) prime_mmap (test_map_unmap)
[98.771029] Starting subtest: test_map_unmap
[98.772997] Starting dynamic subtest: test_map_unmap-smem
[98.779077] Dynamic subtest test_map_unmap-smem: SUCCESS (0.008s)
[98.781294] Subtest test_map_unmap: SUCCESS (0.009s)
[98.905556] [011/132] (949s left) syncobj_wait (invalid-signal-one-illegal-handle)
[99.006899] Starting subtest: invalid-signal-one-illegal-handle
[99.008948] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[99.141460] [012/132] (949s left) gem_exec_schedule (u-semaphore-user)
[99.271770] Starting subtest: u-semaphore-user
[99.292661] Subtest u-semaphore-user: SUCCESS (0.022s)
[99.690274] [013/132] (949s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[99.862235] Starting subtest: cursorB-vs-flipA-atomic
[111.667484] Subtest cursorB-vs-flipA-atomic: SUCCESS (11.798s)
[111.936361] [014/132] (937s left) gem_exec_params (invalid-batch-start-offset)
[112.044037] Starting subtest: invalid-batch-start-offset
[112.052926] Subtest invalid-batch-start-offset: SUCCESS (0.008s)
[112.214906] [015/132] (936s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[112.395714] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[112.400056] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[112.534101] [016/132] (936s left) prime_self_import (basic-with_one_bo)
[112.632117] Starting subtest: basic-with_one_bo
[112.636712] Subtest basic-with_one_bo: SUCCESS (0.007s)
[112.791489] [017/132] (936s left) kms_sequence (get-busy)
[112.964854] Starting subtest: get-busy
[112.967129] Starting dynamic subtest: pipe-A-HDMI-A-1
[115.833814] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.868s)
[115.856162] Starting dynamic subtest: pipe-A-HDMI-A-2
[118.426557] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.589s)
[118.446556] Starting dynamic subtest: pipe-B-HDMI-A-1
[120.992290] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.564s)
[121.011880] Starting dynamic subtest: pipe-B-HDMI-A-2
[123.562111] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.565s)
[123.581971] Starting dynamic subtest: pipe-C-HDMI-A-1
[126.135966] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.572s)
[126.157601] Starting dynamic subtest: pipe-C-HDMI-A-2
[128.711216] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.571s)
[128.713078] Subtest get-busy: SUCCESS (15.746s)
[128.968827] [018/132] (920s left) kms_psr (psr-cursor-plane-onoff)
[129.143669] Subtest psr-cursor-plane-onoff: SKIP (0.000s)
[129.244036] [019/132] (920s left) kms_color_pipeline (plane-lut1d)
[129.411577] Starting subtest: plane-lut1d
[130.816279] Subtest plane-lut1d: SKIP (1.401s)
[131.073664] [020/132] (918s left) api_intel_bb (full-batch)
[131.172324] Starting subtest: full-batch
[131.179094] Subtest full-batch: SUCCESS (0.006s)
[131.325693] [021/132] (918s left) kms_psr (psr-sprite-render)
[131.498187] Subtest psr-sprite-render: SKIP (0.000s)
[131.596124] [022/132] (918s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[131.779219] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[131.782980] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[131.927660] [023/132] (917s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[132.113354] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[134.781716] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SUCCESS (2.667s)
[134.992519] [024/132] (914s left) kms_vrr (seamless-rr-switch-vrr)
[135.164610] Starting subtest: seamless-rr-switch-vrr
[135.172146] Subtest seamless-rr-switch-vrr: SKIP (0.000s)
[135.306983] [025/132] (914s left) gem_userptr_blits (create-destroy-unsync)
[135.420838] Subtest create-destroy-unsync: SKIP (0.000s)
[135.607535] [026/132] (914s left) core_hotunplug (unbind-rebind)
[135.780639] Starting subtest: unbind-rebind
[139.528936] Subtest unbind-rebind: SUCCESS (3.746s)
[139.609534] [027/132] (910s left) kms_plane (pixel-format-y-tiled-modifier)
[139.801889] Starting subtest: pixel-format-y-tiled-modifier
[140.223482] Starting dynamic subtest: pipe-A-plane-0
[141.023317] Dynamic subtest pipe-A-plane-0: SUCCESS (0.799s)
[141.044541] Starting dynamic subtest: pipe-A-plane-1
[141.773737] Dynamic subtest pipe-A-plane-1: SUCCESS (0.749s)
[141.794533] Starting dynamic subtest: pipe-A-plane-2
[142.340102] Dynamic subtest pipe-A-plane-2: SUCCESS (0.565s)
[142.361622] Starting dynamic subtest: pipe-A-plane-3
[142.857384] Dynamic subtest pipe-A-plane-3: SUCCESS (0.516s)
[142.877957] Starting dynamic subtest: pipe-A-plane-4
[142.882298] Dynamic subtest pipe-A-plane-4: SKIP (0.000s)
[143.214879] Starting dynamic subtest: pipe-B-plane-0
[143.930947] Dynamic subtest pipe-B-plane-0: SUCCESS (0.733s)
[143.952508] Starting dynamic subtest: pipe-B-plane-1
[144.663888] Dynamic subtest pipe-B-plane-1: SUCCESS (0.732s)
[144.685547] Starting dynamic subtest: pipe-B-plane-2
[145.214049] Dynamic subtest pipe-B-plane-2: SUCCESS (0.549s)
[145.234690] Starting dynamic subtest: pipe-B-plane-3
[145.764267] Dynamic subtest pipe-B-plane-3: SUCCESS (0.549s)
[145.784597] Starting dynamic subtest: pipe-B-plane-4
[145.788308] Dynamic subtest pipe-B-plane-4: SKIP (0.000s)
[145.977260] Subtest pixel-format-y-tiled-modifier: SUCCESS (6.174s)
[146.260591] [028/132] (903s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[146.377193] Starting subtest: y-tiled-ccs-to-yf-tiled
[146.379193] Starting dynamic subtest: smem
[146.695121] Dynamic subtest smem: SUCCESS (0.316s)
[146.697189] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (0.317s)
[146.926709] [029/132] (903s left) gem_exec_params (invalid-fence-in)
[147.035934] Starting subtest: invalid-fence-in
[147.038438] Subtest invalid-fence-in: SUCCESS (0.001s)
[147.194769] [030/132] (903s left) api_intel_bb (crc32)
[147.299128] Starting subtest: crc32
[147.304444] Subtest crc32: SKIP (0.000s)
[147.424904] [031/132] (903s left) gem_mmap_gtt (cpuset-medium-copy)
[147.526884] Starting subtest: cpuset-medium-copy
[155.960987] Subtest cpuset-medium-copy: SUCCESS (8.434s)
[156.119522] [032/132] (894s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[156.353806] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[156.457730] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.102s)
[156.624843] [033/132] (893s left) syncobj_timeline (wait-all-delayed-signal)
[156.725760] Starting subtest: wait-all-delayed-signal
[156.830313] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[156.973481] [034/132] (893s left) gem_exec_balancer (bonded-chain)
[157.093127] Starting subtest: bonded-chain
[161.175234] Subtest bonded-chain: SUCCESS (4.080s)
[161.429692] [035/132] (889s left) gem_exec_schedule (independent)
[161.552976] Starting subtest: independent
[161.554966] Starting dynamic subtest: rcs0
[161.719965] Dynamic subtest rcs0: SUCCESS (0.163s)
[161.749210] Starting dynamic subtest: bcs0
[161.890963] Dynamic subtest bcs0: SUCCESS (0.172s)
[161.918295] Starting dynamic subtest: vcs0
[162.062980] Dynamic subtest vcs0: SUCCESS (0.168s)
[162.089350] Starting dynamic subtest: vecs0
[162.221250] Dynamic subtest vecs0: SUCCESS (0.158s)
[162.223192] Subtest independent: SUCCESS (0.669s)
[162.596566] [036/132] (888s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[162.778985] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[162.783062] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SKIP (0.000s)
[162.914730] [037/132] (888s left) kms_plane (pixel-format-yf-tiled-modifier)
[163.087349] Starting subtest: pixel-format-yf-tiled-modifier
[163.295655] Starting dynamic subtest: pipe-A-plane-0
[163.996059] Dynamic subtest pipe-A-plane-0: SUCCESS (0.700s)
[164.018868] Starting dynamic subtest: pipe-A-plane-1
[164.645696] Dynamic subtest pipe-A-plane-1: SUCCESS (0.649s)
[164.668597] Starting dynamic subtest: pipe-A-plane-2
[165.062745] Dynamic subtest pipe-A-plane-2: SUCCESS (0.415s)
[165.085548] Starting dynamic subtest: pipe-A-plane-3
[165.463760] Dynamic subtest pipe-A-plane-3: SUCCESS (0.398s)
[165.485466] Starting dynamic subtest: pipe-A-plane-4
[165.489180] Dynamic subtest pipe-A-plane-4: SKIP (0.000s)
[165.827772] Starting dynamic subtest: pipe-B-plane-0
[166.408989] Dynamic subtest pipe-B-plane-0: SUCCESS (0.600s)
[166.432302] Starting dynamic subtest: pipe-B-plane-1
[167.007771] Dynamic subtest pipe-B-plane-1: SUCCESS (0.597s)
[167.030591] Starting dynamic subtest: pipe-B-plane-2
[167.425267] Dynamic subtest pipe-B-plane-2: SUCCESS (0.416s)
[167.447703] Starting dynamic subtest: pipe-B-plane-3
[167.808503] Dynamic subtest pipe-B-plane-3: SUCCESS (0.381s)
[167.830188] Starting dynamic subtest: pipe-B-plane-4
[167.834047] Dynamic subtest pipe-B-plane-4: SKIP (0.000s)
[168.010674] Subtest pixel-format-yf-tiled-modifier: SUCCESS (4.921s)
[168.298653] [038/132] (882s left) syncobj_timeline (reset-unsignaled)
[168.402244] Starting subtest: reset-unsignaled
[168.404273] Subtest reset-unsignaled: SUCCESS (0.000s)
[168.531165] [039/132] (882s left) gem_exec_reloc (basic-write-read)
[168.641078] Starting subtest: basic-write-read
[168.657383] Subtest basic-write-read: SUCCESS (0.016s)
[168.878857] [040/132] (882s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[169.064898] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[169.068696] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[169.229163] [041/132] (882s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[169.413343] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[171.882419] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SUCCESS (2.469s)
[172.099483] [042/132] (879s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[172.274290] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[172.276262] Starting dynamic subtest: pipe-A
[173.281052] Dynamic subtest pipe-A: SUCCESS (1.008s)
[173.304918] Starting dynamic subtest: pipe-B
[174.358748] Dynamic subtest pipe-B: SUCCESS (1.075s)
[174.381202] Starting dynamic subtest: pipe-C
[174.771165] Dynamic subtest pipe-C: SUCCESS (0.411s)
[174.773059] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (2.501s)
[175.255714] [043/132] (876s left) kms_vblank (wait-busy-hang)
[175.432752] Starting subtest: wait-busy-hang
[175.434531] Starting dynamic subtest: pipe-A-HDMI-A-1
[182.900069] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.465s)
[182.922521] Starting dynamic subtest: pipe-A-HDMI-A-2
[189.873339] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (6.969s)
[189.905044] Starting dynamic subtest: pipe-C-HDMI-A-1
[196.897209] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (7.014s)
[196.921738] Starting dynamic subtest: pipe-C-HDMI-A-2
[203.874271] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (6.972s)
[203.876294] Subtest wait-busy-hang: SUCCESS (28.443s)
[204.164803] [044/132] (847s left) gem_userptr_blits (process-exit)
[204.295022] Starting subtest: process-exit
[204.318119] Subtest process-exit: SUCCESS (0.024s)
[204.496948] [045/132] (847s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[204.681353] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[204.685040] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[204.833482] [046/132] (846s left) kms_cdclk (plane-scaling)
[204.933861] Subtest plane-scaling: SKIP (0.000s)
[205.036647] [047/132] (846s left) i915_pm_rpm (gem-evict-pwrite)
[206.939341] Starting subtest: gem-evict-pwrite
[207.505682] Subtest gem-evict-pwrite: SUCCESS (0.569s)
[207.866414] [048/132] (844s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[207.976028] Starting subtest: invalid-bsd2-flag-on-vebox
[207.977932] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.001s)
[208.127910] [049/132] (843s left) kms_frontbuffer_tracking (psr-1p-rte)
[208.312036] Starting subtest: psr-1p-rte
[208.316104] Subtest psr-1p-rte: SKIP (0.000s)
[208.466837] [050/132] (843s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[208.639972] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[208.643888] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SKIP (0.000s)
[208.764954] [051/132] (843s left) i915_suspend (basic-s3-without-i915)
[208.861074] Starting subtest: basic-s3-without-i915