[60.791486] Initializing watchdogs
[60.791533]   /dev/watchdog0
[60.807218] [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.812036] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.814377] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.816426] [001/156] (960s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[60.923268] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt
[60.924991] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[61.036748] [002/156] (960s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[61.128281] Starting subtest: read-crc-frame-sequence
[61.129221] Starting dynamic subtest: pipe-A-eDP-1
[61.884166] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.748s)
[61.892884] Starting dynamic subtest: pipe-B-eDP-1
[63.272650] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.377s)
[63.281729] Starting dynamic subtest: pipe-C-eDP-1
[64.675951] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.393s)
[64.683211] Starting dynamic subtest: pipe-D-eDP-1
[66.011927] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.328s)
[66.012880] Subtest read-crc-frame-sequence: SUCCESS (4.849s)
[66.833960] [003/156] (954s left) kms_cursor_crc (cursor-onscreen-32x10)
[66.999904] Starting subtest: cursor-onscreen-32x10
[67.044956] Subtest cursor-onscreen-32x10: SKIP (0.042s)
[67.312574] [004/156] (954s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[67.409050] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[67.409976] Starting dynamic subtest: pipe-A-default-mode
[68.608159] Dynamic subtest pipe-A-default-mode: SKIP (1.192s)
[68.609850] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.193s)
[68.759958] [005/156] (952s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[68.870126] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[80.095997] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.204s)
[80.933257] [006/156] (940s left) api_intel_bb (render-ccs)
[80.982032] Starting subtest: render-ccs
[81.357955] Subtest render-ccs: SUCCESS (0.368s)
[81.456315] [007/156] (940s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[81.548610] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[81.550396] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[81.635629] [008/156] (939s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[81.683466] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[81.685446] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[81.863191] [009/156] (939s left) gem_ctx_persistence (engines-mixed)
[81.918612] Starting subtest: engines-mixed
[81.919727] Starting dynamic subtest: rcs0
[82.010302] Dynamic subtest rcs0: SUCCESS (0.091s)
[82.020707] Starting dynamic subtest: bcs0
[82.063742] Dynamic subtest bcs0: SUCCESS (0.052s)
[82.071501] Starting dynamic subtest: vcs0
[82.109516] Dynamic subtest vcs0: SUCCESS (0.044s)
[82.118888] Starting dynamic subtest: vcs1
[82.154763] Dynamic subtest vcs1: SUCCESS (0.043s)
[82.166314] Starting dynamic subtest: vecs0
[82.206554] Dynamic subtest vecs0: SUCCESS (0.051s)
[82.218260] Starting dynamic subtest: ccs0
[82.261939] Dynamic subtest ccs0: SUCCESS (0.053s)
[82.263773] Subtest engines-mixed: SUCCESS (0.343s)
[82.333165] [010/156] (939s left) gem_mmap_gtt (big-copy-odd)
[82.378259] Subtest big-copy-odd: SKIP (0.000s)
[82.429545] [011/156] (939s left) kms_vblank (query-forked-busy)
[82.514394] Starting subtest: query-forked-busy
[82.515269] Starting dynamic subtest: pipe-A-eDP-1
[84.380797] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.864s)
[84.394636] Starting dynamic subtest: pipe-D-eDP-1
[87.002564] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.619s)
[87.003521] Subtest query-forked-busy: SUCCESS (4.487s)
[87.800444] [012/156] (933s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[87.911227] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[87.913119] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[87.999516] [013/156] (933s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite)
[88.089128] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite
[88.090764] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[88.184250] [014/156] (933s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[88.283144] Starting subtest: multiplane-rotation-cropping-top
[105.713099] Subtest multiplane-rotation-cropping-top: SUCCESS (17.427s)
[105.826589] [015/156] (915s left) drm_read (short-buffer-block)
[105.970908] Starting subtest: short-buffer-block
[105.972088] Subtest short-buffer-block: SUCCESS (0.000s)
[106.100029] [016/156] (915s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-msflip-blt)
[106.187146] Starting subtest: psrhdr-1p-primscrn-shrfb-msflip-blt
[106.188938] Subtest psrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[106.294761] [017/156] (915s left) sriov_basic (enable-vfs-autoprobe-on)
[106.338947] Starting subtest: enable-vfs-autoprobe-on
[106.339876] Starting dynamic subtest: numvfs-1
[106.367102] Dynamic subtest numvfs-1: FAIL (0.010s)
[106.374609] Starting dynamic subtest: numvfs-2
[106.405549] Dynamic subtest numvfs-2: FAIL (0.006s)
[106.416268] Starting dynamic subtest: numvfs-3
[106.446528] Dynamic subtest numvfs-3: FAIL (0.004s)
[106.454295] Starting dynamic subtest: numvfs-4
[106.481363] Dynamic subtest numvfs-4: FAIL (0.004s)
[106.487794] Starting dynamic subtest: numvfs-5
[106.515343] Dynamic subtest numvfs-5: FAIL (0.004s)
[106.521784] Starting dynamic subtest: numvfs-6
[106.548728] Dynamic subtest numvfs-6: FAIL (0.004s)
[106.555304] Starting dynamic subtest: numvfs-7
[106.583222] Dynamic subtest numvfs-7: FAIL (0.004s)
[106.589950] Starting dynamic subtest: numvfs-random
[106.614451] Dynamic subtest numvfs-random: FAIL (0.004s)
[106.620629] Starting dynamic subtest: numvfs-all
[106.645941] Dynamic subtest numvfs-all: FAIL (0.004s)
[106.646763] Subtest enable-vfs-autoprobe-on: FAIL (0.049s)
[106.673757] [018/156] (915s left) syncobj_timeline (multi-wait-all-available-signaled)
[106.716900] Starting subtest: multi-wait-all-available-signaled
[106.717989] Subtest multi-wait-all-available-signaled: SUCCESS (0.001s)
[106.804954] [019/156] (915s left) kms_atomic (atomic-invalid-params)
[106.893404] Starting subtest: atomic-invalid-params
[106.894433] Starting dynamic subtest: pipe-A-eDP-1
[107.284328] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.390s)
[107.285266] Subtest atomic-invalid-params: SUCCESS (0.391s)
[108.128412] [020/156] (913s left) perf (rc6-disable)
[108.232822] Starting subtest: rc6-disable
[108.421845] Subtest rc6-disable: SUCCESS (0.189s)
[108.466737] [021/156] (913s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[108.564658] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[115.478782] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.915s)
[115.607171] [022/156] (906s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-pwrite)
[115.706440] Starting subtest: fbchdr-rgb565-draw-pwrite
[115.708156] Subtest fbchdr-rgb565-draw-pwrite: SKIP (0.000s)
[115.793903] [023/156] (906s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[115.882565] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[115.883447] Starting dynamic subtest: pipe-A-eDP-1
[115.891819] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[115.899143] Starting dynamic subtest: pipe-B-eDP-1
[115.907652] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[115.914935] Starting dynamic subtest: pipe-C-eDP-1
[115.923240] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[115.930195] Starting dynamic subtest: pipe-D-eDP-1
[115.938462] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[115.940049] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[118.224195] [024/156] (903s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[118.306357] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[118.318182] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[118.403207] [025/156] (903s left) perf (polling-small-buf)
[118.486280] Starting subtest: polling-small-buf
[118.577760] Subtest polling-small-buf: SUCCESS (0.090s)
[118.617468] [026/156] (903s left) i915_pm_rc6_residency (rc6-fence)
[118.660274] Starting subtest: rc6-fence
[118.685640] Starting dynamic subtest: gt0
[131.022061] Dynamic subtest gt0: SUCCESS (12.323s)
[131.030081] Starting dynamic subtest: gt1
[145.318797] Dynamic subtest gt1: SUCCESS (14.296s)
[145.319987] Subtest rc6-fence: SUCCESS (26.650s)
[145.383298] [027/156] (876s left) api_intel_allocator (reloc-allocator)
[145.430234] Starting subtest: reloc-allocator
[145.431085] Starting dynamic subtest: basic
[145.433421] Dynamic subtest basic: SUCCESS (0.004s)
[145.441098] Starting dynamic subtest: parallel-one
[145.443072] Dynamic subtest parallel-one: SUCCESS (0.008s)
[145.450477] Starting dynamic subtest: print
[145.451360] Dynamic subtest print: SUCCESS (0.000s)
[145.458242] Starting dynamic subtest: reuse
[145.459074] Dynamic subtest reuse: SUCCESS (0.000s)
[145.465762] Starting dynamic subtest: fork-reopen-allocator
[145.466558] Dynamic subtest fork-reopen-allocator: SUCCESS (0.010s)
[145.467345] Subtest reloc-allocator: SUCCESS (0.026s)
[145.580808] [028/156] (876s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[145.627444] Starting subtest: multi-wait-all-for-submit-signaled
[145.628395] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[145.720088] [029/156] (876s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[145.821773] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[146.480731] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.659s)
[146.646642] [030/156] (875s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-onoff)
[146.727253] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-onoff
[146.728859] Subtest psrhdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[146.823713] [031/156] (875s left) kms_addfb_basic (small-bo)
[146.911025] Starting subtest: small-bo
[146.911921] Subtest small-bo: SUCCESS (0.000s)
[146.989716] [032/156] (875s left) gem_pwrite (basic-random)
[147.034047] Subtest basic-random: SKIP (0.000s)
[147.088168] [033/156] (875s left) gem_exec_schedule (preempt-queue-contexts)
[147.158966] Subtest preempt-queue-contexts: SKIP (0.000s)
[147.466592] [034/156] (874s left) syncobj_timeline (wait-all-snapshot)
[147.513264] Starting subtest: wait-all-snapshot
[147.596809] Subtest wait-all-snapshot: SUCCESS (0.082s)
[147.665008] [035/156] (874s left) kms_frontbuffer_tracking (fbcpsrhdr-stridechange)
[147.759692] Starting subtest: fbcpsrhdr-stridechange
[147.761410] Subtest fbcpsrhdr-stridechange: SKIP (0.000s)
[147.869814] [036/156] (874s left) gem_exec_reloc (basic-range)
[147.930434] Subtest basic-range: SKIP (0.000s)
[147.999640] [037/156] (874s left) gem_mmap_gtt (coherency)
[148.055994] Subtest coherency: SKIP (0.000s)
[148.119660] [038/156] (874s left) kms_flip (dpms-off-confusion)
[148.208470] Starting subtest: dpms-off-confusion
[148.211058] Starting dynamic subtest: A-eDP1
[156.965432] Dynamic subtest A-eDP1: SUCCESS (8.726s)
[156.974845] Starting dynamic subtest: B-eDP1
[165.690663] Dynamic subtest B-eDP1: SUCCESS (8.725s)
[165.699828] Starting dynamic subtest: C-eDP1
[174.419394] Dynamic subtest C-eDP1: SUCCESS (8.714s)
[174.428877] Starting dynamic subtest: D-eDP1
[183.052167] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[183.053285] Subtest dpms-off-confusion: SUCCESS (34.807s)
[184.186170] [039/156] (838s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt)
[184.277594] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt
[184.279262] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[184.368030] [040/156] (838s left) gem_eio (kms)
[184.633218] Starting subtest: kms
[203.476167] Subtest kms: SUCCESS (18.832s)
[204.635978] [FACT gem_eio (kms)] new: kernel.kmod_is_loaded.vgem: true
[204.637898] [041/156] (817s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[204.738468] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[204.740035] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[204.830812] [042/156] (817s left) kms_psr (psr-primary-mmap-gtt)
[204.917727] Starting subtest: psr-primary-mmap-gtt
[204.918521] Starting dynamic subtest: eDP-1
[206.352586] Dynamic subtest eDP-1: SKIP (1.424s)
[206.354621] Subtest psr-primary-mmap-gtt: SKIP (1.426s)
[206.474845] [043/156] (816s left) perf (gen12-oa-tlb-invalidate)
[206.547249] Starting subtest: gen12-oa-tlb-invalidate
[206.548353] Starting dynamic subtest: 0-rcs0
[216.648800] Dynamic subtest 0-rcs0: SUCCESS (10.055s)
[216.658729] Starting dynamic subtest: 1-vcs0
[226.701496] Dynamic subtest 1-vcs0: SUCCESS (10.050s)
[226.702701] Subtest gen12-oa-tlb-invalidate: SUCCESS (20.108s)
[226.750306] [044/156] (795s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[226.856345] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[226.858025] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[226.944243] [045/156] (795s left) gem_exec_balancer (persistence)
[227.219780] Starting subtest: persistence
[228.266832] Subtest persistence: SUCCESS (1.041s)
[228.365086] [046/156] (794s left) syncobj_wait (multi-wait-all-submitted)
[228.414799] Starting subtest: multi-wait-all-submitted
[228.522839] Subtest multi-wait-all-submitted: SUCCESS (0.106s)
[228.606469] [047/156] (794s left) kms_psr (fbc-psr-primary-mmap-cpu)
[228.700125] Starting subtest: fbc-psr-primary-mmap-cpu
[228.701632] Starting dynamic subtest: eDP-1
[228.703992] Dynamic subtest eDP-1: SKIP (0.000s)
[228.705857] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s)
[228.769515] [048/156] (794s left) kms_atomic_transition (plane-all-transition-nonblocking)
[228.859916] Starting subtest: plane-all-transition-nonblocking
[228.860872] Starting dynamic subtest: pipe-A-eDP-1
[231.357620] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s)
[231.678766] Starting dynamic subtest: pipe-B-eDP-1
[234.907193] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.235s)
[235.205346] Subtest plane-all-transition-nonblocking: SUCCESS (6.345s)
[236.026990] [049/156] (786s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[236.115746] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[239.840625] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.725s)
[241.036285] [050/156] (781s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-render)
[241.140048] Starting subtest: hdr-rgb101010-draw-render
[241.141922] Subtest hdr-rgb101010-draw-render: SKIP (0.000s)
[241.241870] [051/156] (781s left) gem_exec_balancer (invalid-balancer)
[241.301867] Starting subtest: invalid-balancer
[242.310829] Subtest invalid-balancer: SUCCESS (0.998s)
[242.618834] [052/156] (780s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-blt)
[242.715086] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-blt
[242.716944] Subtest hdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[242.836566] [053/156] (780s left) gem_ctx_persistence (hang)
[242.887842] Starting subtest: hang
[242.889300] Subtest hang: SKIP (0.000s)
[242.984285] [054/156] (780s left) kms_pm_rpm (i2c)
[244.526177] Starting subtest: i2c
[249.905185] Subtest i2c: SUCCESS (5.380s)
[249.998409] [055/156] (773s left) kms_content_protection (lic-type-1)
[250.144574] Starting subtest: lic-type-1
[250.147335] Subtest lic-type-1: SKIP (0.000s)
[250.282838] [056/156] (772s left) gem_exec_schedule (preempt-self)
[250.357481] Starting subtest: preempt-self
[250.358498] Starting dynamic subtest: rcs0
[250.382164] Dynamic subtest rcs0: SUCCESS (0.024s)
[250.394571] Starting dynamic subtest: bcs0
[250.400368] Dynamic subtest bcs0: SUCCESS (0.017s)
[250.408889] Starting dynamic subtest: vcs0
[250.421367] Dynamic subtest vcs0: SUCCESS (0.020s)
[250.429881] Starting dynamic subtest: vcs1
[250.440523] Dynamic subtest vcs1: SUCCESS (0.018s)
[250.448741] Starting dynamic subtest: vecs0
[250.470968] Dynamic subtest vecs0: SUCCESS (0.029s)
[250.480107] Starting dynamic subtest: ccs0
[250.494477] Dynamic subtest ccs0: SUCCESS (0.023s)
[250.495606] Subtest preempt-self: SUCCESS (0.138s)
[252.341870] [057/156] (770s left) syncobj_wait (wait-all-delayed-signal)
[252.391879] Starting subtest: wait-all-delayed-signal
[252.495236] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[252.570220] [058/156] (770s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-fullscreen)
[252.659981] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-fullscreen
[252.661888] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[252.740439] [059/156] (770s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-blt)
[252.837254] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-blt
[252.839461] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[252.945030] [060/156] (770s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[253.049494] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[253.752780] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.703s)
[253.909926] [061/156] (769s left) kms_fbcon_fbt (psr)
[253.957470] Starting subtest: psr
[261.441681] Subtest psr: SUCCESS (7.472s)
[261.505991] [062/156] (761s left) kms_vrr (flip-basic-fastset)
[261.599459] Starting subtest: flip-basic-fastset
[261.601868] Subtest flip-basic-fastset: SKIP (0.000s)
[261.669226] [063/156] (761s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[261.754384] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[261.755898] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[261.845617] [064/156] (761s left) gem_userptr_blits (vma-merge)
[261.920618] Starting subtest: vma-merge
[261.963339] Subtest vma-merge: SUCCESS (0.042s)
[262.086462] [065/156] (761s left) gem_mmap_offset (bad-object)
[262.128624] Starting subtest: bad-object
[262.129535] Subtest bad-object: SUCCESS (0.001s)
[262.219173] [066/156] (761s left) sysfs_defaults (readonly)
[262.264153] Starting subtest: readonly
[262.265027] Starting dynamic subtest: vecs0
[262.265945] Dynamic subtest vecs0: SUCCESS (0.000s)
[262.274203] Starting dynamic subtest: ccs0
[262.275090] Dynamic subtest ccs0: SUCCESS (0.000s)
[262.283047] Starting dynamic subtest: vcs0
[262.283921] Dynamic subtest vcs0: SUCCESS (0.000s)
[262.291736] Starting dynamic subtest: bcs0
[262.292632] Dynamic subtest bcs0: SUCCESS (0.000s)
[262.300376] Starting dynamic subtest: rcs0
[262.301284] Dynamic subtest rcs0: SUCCESS (0.000s)
[262.308929] Starting dynamic subtest: vcs1
[262.309845] Dynamic subtest vcs1: SUCCESS (0.000s)
[262.310902] Subtest readonly: SUCCESS (0.006s)
[262.341282] [067/156] (761s left) gem_ccs (suspend-resume)
[262.387098] Starting subtest: suspend-resume
[262.389089] Subtest suspend-resume: SKIP (0.000s)
[262.488037] [068/156] (761s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[262.585283] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[262.587157] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[262.683972] [069/156] (760s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[262.772373] Starting subtest: primary-x-tiled-reflect-x-0
[269.739393] Subtest primary-x-tiled-reflect-x-0: SUCCESS (6.968s)
[270.894431] [070/156] (752s left) kms_hdr (static-toggle-dpms)
[270.987401] Starting subtest: static-toggle-dpms
[270.988515] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[270.990310] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[270.998940] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[271.000726] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[271.002516] Subtest static-toggle-dpms: SKIP (0.003s)
[271.058679] [071/156] (752s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[271.153625] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[271.155382] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[271.260912] [072/156] (752s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[271.353055] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[271.354743] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[271.420163] [073/156] (752s left) gem_lmem_swapping (heavy-random)
[271.467260] Subtest heavy-random: SKIP (0.000s)
[271.505857] [074/156] (752s left) kms_joiner (invalid-modeset-ultra-joiner)
[271.594985] Starting subtest: invalid-modeset-ultra-joiner
[271.596892] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[271.687633] [075/156] (752s left) gem_exec_reloc (basic-wc-cpu-active)
[271.739986] Subtest basic-wc-cpu-active: SKIP (0.000s)
[271.812088] [076/156] (752s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[271.899394] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[271.900975] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[271.982768] [077/156] (751s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[272.264702] Starting subtest: cursor-rapid-movement-512x512
[272.266662] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[272.416369] [078/156] (751s left) kms_pm_dc (dc3co-vpb-simulation)
[273.515456] Starting subtest: dc3co-vpb-simulation
[282.920423] Subtest dc3co-vpb-simulation: SKIP (9.357s)
[282.994122] [079/156] (740s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[283.120763] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[301.365588] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (18.243s)
[302.246797] [080/156] (721s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[302.341085] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[302.343066] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[302.435428] [081/156] (721s left) kms_getfb (getfb-handle-zero)
[302.525727] Starting subtest: getfb-handle-zero
[302.526733] Subtest getfb-handle-zero: SUCCESS (0.000s)
[302.630658] [082/156] (721s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[302.742885] Starting subtest: cursor-vs-flip-atomic
[332.602645] Subtest cursor-vs-flip-atomic: SUCCESS (29.858s)
[333.467569] [083/156] (690s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[333.510750] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[333.617955] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.106s)
[333.702482] [084/156] (690s left) i915_suspend (fence-restore-untiled)
[333.745865] Starting subtest: fence-restore-untiled
[333.747385] Subtest fence-restore-untiled: SKIP (0.000s)
[333.847983] [085/156] (690s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[333.960690] Starting subtest: cursorB-vs-flipB-atomic-transitions
[333.962479] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[334.114596] [086/156] (690s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[334.202432] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[334.203426] Starting dynamic subtest: pipe-A-eDP-1
[334.211278] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[334.219911] Starting dynamic subtest: pipe-B-eDP-1
[334.228845] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[334.239061] Starting dynamic subtest: pipe-C-eDP-1
[334.246124] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[334.254245] Starting dynamic subtest: pipe-D-eDP-1
[334.261129] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[334.261906] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[336.547522] [087/156] (687s left) gem_userptr_blits (unsync-overlap)
[336.608559] Subtest unsync-overlap: SKIP (0.000s)
[336.721331] [088/156] (687s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[336.820241] Starting subtest: x-tiled-8bpp-rotate-180
[338.078638] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.259s)
[338.197312] [089/156] (686s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-blt)
[338.293123] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-blt
[338.294795] Subtest fbchdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[338.381330] [090/156] (685s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[338.465704] Starting subtest: fbcpsr-modesetfrombusy
[344.623742] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.159s)
[344.818564] [091/156] (679s left) gem_exec_schedule (fifo)
[344.875574] Starting subtest: fifo
[344.876596] Starting dynamic subtest: rcs0
[344.979543] Dynamic subtest rcs0: SUCCESS (0.102s)
[344.995048] Starting dynamic subtest: vcs0
[345.061998] Dynamic subtest vcs0: SUCCESS (0.082s)
[345.073517] Starting dynamic subtest: vcs1
[345.143035] Dynamic subtest vcs1: SUCCESS (0.080s)
[345.153295] Starting dynamic subtest: vecs0
[345.229523] Dynamic subtest vecs0: SUCCESS (0.086s)
[345.241457] Starting dynamic subtest: ccs0
[345.322642] Dynamic subtest ccs0: SUCCESS (0.092s)
[345.324351] Subtest fifo: SUCCESS (0.447s)
[346.422980] [092/156] (677s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[346.513125] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[346.540509] Starting dynamic subtest: pipe-A-plane-0
[346.829070] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[346.839060] Starting dynamic subtest: pipe-A-plane-3
[347.040542] Dynamic subtest pipe-A-plane-3: SUCCESS (0.210s)
[348.205989] Starting dynamic subtest: pipe-B-plane-0
[348.474945] Dynamic subtest pipe-B-plane-0: SUCCESS (0.278s)
[348.484631] Starting dynamic subtest: pipe-B-plane-3
[348.686061] Dynamic subtest pipe-B-plane-3: SUCCESS (0.210s)
[348.994317] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.480s)
[349.873930] [093/156] (674s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[349.996274] Starting subtest: 2x-long-flip-vs-cursor-atomic
[349.998992] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[350.139504] [094/156] (674s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-gtt)
[350.230890] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-gtt
[350.232547] Subtest fbcpsrhdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[350.308785] [095/156] (674s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[350.409407] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[350.410975] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[350.512181] [096/156] (674s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[350.615871] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[358.007129] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.393s)
[358.126460] [097/156] (666s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[358.211326] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[358.213074] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[358.304642] [098/156] (666s left) gem_spin_batch (spin-all-new)
[358.354451] Starting subtest: spin-all-new
[358.369195] Subtest spin-all-new: SUCCESS (0.015s)
[360.384927] [099/156] (664s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[360.471412] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[360.473208] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[360.562235] [100/156] (664s left) syncobj_wait (invalid-reset-one-illegal-handle)
[360.617880] Starting subtest: invalid-reset-one-illegal-handle
[360.618760] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[360.724424] [101/156] (663s left) kms_pm_rpm (pc8-residency)
[362.454319] Starting subtest: pc8-residency
[362.456555] Subtest pc8-residency: SKIP (0.000s)
[362.967458] [102/156] (661s left) syncobj_basic (bad-destroy-pad)
[363.025852] Starting subtest: bad-destroy-pad
[363.026743] Subtest bad-destroy-pad: SUCCESS (0.000s)
[363.134245] [103/156] (661s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[363.224802] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[363.226615] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[363.324693] [104/156] (661s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-wc)
[363.419976] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-wc
[363.421933] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[363.534404] [105/156] (661s left) kms_chamelium_edid (hdmi-mode-timings)
[363.909871] Subtest hdmi-mode-timings: SKIP (0.000s)
[364.772735] [106/156] (660s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[364.864784] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[364.866695] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[364.963958] [107/156] (659s left) kms_dsc (dsc-with-output-formats)
[365.064699] Starting subtest: dsc-with-output-formats
[365.073081] Subtest dsc-with-output-formats: SKIP (0.001s)
[365.132381] [108/156] (659s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[365.221803] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[365.222836] Starting dynamic subtest: pipe-A-eDP-1
[365.752334] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.521s)
[365.762878] Starting dynamic subtest: pipe-B-eDP-1
[367.272128] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.518s)
[367.281062] Starting dynamic subtest: pipe-C-eDP-1
[368.699792] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.427s)
[368.708487] Starting dynamic subtest: pipe-D-eDP-1
[370.039404] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.337s)
[370.040240] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.808s)
[371.231458] [109/156] (653s left) kms_frontbuffer_tracking (fbchdr-2p-pri-indfb-multidraw)
[371.330229] Starting subtest: fbchdr-2p-pri-indfb-multidraw
[371.331898] Subtest fbchdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[371.418873] [110/156] (653s left) gem_softpin (overlap)
[371.473948] Starting subtest: overlap
[372.452387] Subtest overlap: SUCCESS (0.978s)
[372.560241] [111/156] (652s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-pwrite)
[372.664404] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-pwrite
[372.665979] Subtest hdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[372.776948] [112/156] (652s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[372.845738] Starting subtest: map-fixed-invalidate-overlap
[372.847607] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[372.965686] [113/156] (652s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-render)
[373.050797] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-render
[373.052731] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[373.169501] [114/156] (652s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[373.251667] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[373.252611] Starting dynamic subtest: pipe-A-eDP-1
[374.551362] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.300s)
[374.560877] Starting dynamic subtest: pipe-B-eDP-1
[376.919762] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.367s)
[376.928441] Starting dynamic subtest: pipe-C-eDP-1
[379.271370] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.350s)
[379.280678] Starting dynamic subtest: pipe-D-eDP-1
[381.633117] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.360s)
[381.633988] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (8.382s)
[382.805525] [115/156] (642s left) i915_hangman (engine-engine-error)
[382.871447] Starting subtest: engine-engine-error
[382.872445] Starting dynamic subtest: rcs0
[384.326765] Dynamic subtest rcs0: SUCCESS (1.446s)
[384.335795] Starting dynamic subtest: bcs0
[386.308849] Dynamic subtest bcs0: SUCCESS (1.979s)
[386.318579] Starting dynamic subtest: vcs0
[388.293344] Dynamic subtest vcs0: SUCCESS (1.982s)
[388.304686] Starting dynamic subtest: vcs1
[390.279473] Dynamic subtest vcs1: SUCCESS (1.984s)
[390.290294] Starting dynamic subtest: vecs0
[392.323599] Dynamic subtest vecs0: SUCCESS (2.043s)
[392.334660] Starting dynamic subtest: ccs0
[394.307606] Dynamic subtest ccs0: SUCCESS (1.981s)
[394.309413] Subtest engine-engine-error: SUCCESS (11.429s)
[394.462860] [116/156] (630s left) kms_lease (lease-revoke)
[394.562107] Starting subtest: lease-revoke
[394.562944] Starting dynamic subtest: pipe-A-eDP-1
[394.594822] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.033s)
[394.607919] Starting dynamic subtest: pipe-B-eDP-1
[394.624426] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.027s)
[394.634246] Starting dynamic subtest: pipe-C-eDP-1
[394.655297] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.028s)
[394.664460] Starting dynamic subtest: pipe-D-eDP-1
[394.682941] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.027s)
[394.683966] Subtest lease-revoke: SUCCESS (0.122s)
[394.809847] [117/156] (630s left) gem_lmem_swapping (smem-oom)
[394.844949] Subtest smem-oom: SKIP (0.000s)
[394.881312] [118/156] (630s left) kms_cursor_edge_walk (128x128-left-edge)
[394.966764] Starting subtest: 128x128-left-edge
[394.967711] Starting dynamic subtest: pipe-A-eDP-1
[398.468206] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.500s)
[398.479208] Starting dynamic subtest: pipe-D-eDP-1
[401.604160] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.135s)
[401.604939] Subtest 128x128-left-edge: SUCCESS (6.638s)
[402.407663] [119/156] (622s left) gem_eio (hibernate)
[402.608963] Starting subtest: hibernate
[418.467167] Subtest hibernate: SUCCESS (15.859s)
[424.645908] [120/156] (606s left) kms_big_fb (linear-8bpp-rotate-180)
[424.751048] Starting subtest: linear-8bpp-rotate-180
[426.058876] Subtest linear-8bpp-rotate-180: SUCCESS (1.309s)
[426.189066] [121/156] (605s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[426.277428] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[426.278392] Starting dynamic subtest: pipe-A
[426.505914] Dynamic subtest pipe-A: SUCCESS (0.229s)
[426.515575] Starting dynamic subtest: pipe-B
[427.794725] Dynamic subtest pipe-B: SUCCESS (1.288s)
[427.806824] Starting dynamic subtest: pipe-C
[429.128752] Dynamic subtest pipe-C: SUCCESS (1.333s)
[429.138614] Starting dynamic subtest: pipe-D
[430.457910] Dynamic subtest pipe-D: SUCCESS (1.328s)
[430.458728] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.182s)
[431.638591] [122/156] (599s left) kms_rmfb (close-fd)
[431.741410] Starting subtest: close-fd
[431.742404] Starting dynamic subtest: pipe-A-eDP-1
[431.848967] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.107s)
[431.860072] Starting dynamic subtest: pipe-B-eDP-1
[432.998486] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.148s)
[433.008098] Starting dynamic subtest: pipe-C-eDP-1
[434.219771] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.221s)
[434.231096] Starting dynamic subtest: pipe-D-eDP-1
[435.466313] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.243s)
[435.467246] Subtest close-fd: SUCCESS (3.725s)
[436.661578] [123/156] (594s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[436.753519] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[436.755428] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[436.862114] [124/156] (594s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[436.953445] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[436.955245] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[437.055071] [125/156] (594s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[437.431158] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[438.270326] [126/156] (593s left) kms_psr (psr2-cursor-plane-move)
[438.362240] Starting subtest: psr2-cursor-plane-move
[438.363054] Starting dynamic subtest: eDP-1
[440.086839] Dynamic subtest eDP-1: SUCCESS (1.724s)
[440.087667] Subtest psr2-cursor-plane-move: SUCCESS (1.725s)
[440.960050] [127/156] (590s left) kms_psr (fbc-pr-sprite-plane-onoff)
[441.055497] Starting subtest: fbc-pr-sprite-plane-onoff
[441.057401] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[441.126220] [128/156] (590s left) kms_addfb_basic (basic)
[441.168780] Starting subtest: basic
[441.169996] Subtest basic: SUCCESS (0.000s)
[441.296217] [129/156] (590s left) kms_3d (basic)
[441.345095] Starting subtest: basic
[443.662619] Subtest basic: SUCCESS (2.317s)
[444.128818] [130/156] (587s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[444.216441] Starting subtest: plane-lut1d-ctm3x4
[444.342578] Starting dynamic subtest: pipe-A-plane-0
[444.519494] Dynamic subtest pipe-A-plane-0: SUCCESS (0.177s)
[444.529965] Starting dynamic subtest: pipe-A-plane-1
[444.708398] Dynamic subtest pipe-A-plane-1: SUCCESS (0.188s)
[444.718479] Starting dynamic subtest: pipe-A-plane-2
[444.875181] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[446.096296] Starting dynamic subtest: pipe-B-plane-0
[446.242842] Dynamic subtest pipe-B-plane-0: SUCCESS (0.155s)
[446.252849] Starting dynamic subtest: pipe-B-plane-1
[446.409235] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[446.421480] Starting dynamic subtest: pipe-B-plane-2
[446.597996] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s)
[447.780463] Starting dynamic subtest: pipe-C-plane-0
[447.927044] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[447.941640] Starting dynamic subtest: pipe-C-plane-1
[448.093589] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[448.103731] Starting dynamic subtest: pipe-C-plane-2
[448.260096] Dynamic subtest pipe-C-plane-2: SUCCESS (0.166s)
[449.455810] Starting dynamic subtest: pipe-D-plane-0
[449.590786] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[449.600708] Starting dynamic subtest: pipe-D-plane-1
[449.735264] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[449.746148] Starting dynamic subtest: pipe-D-plane-2
[449.901983] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[450.188346] Subtest plane-lut1d-ctm3x4: SUCCESS (5.972s)
[451.013064] [131/156] (580s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[451.108461] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[459.185750] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (8.079s)
[459.299205] [132/156] (572s left) kms_plane_lowres (tiling-y)
[459.398016] Starting subtest: tiling-y
[459.399926] Subtest tiling-y: SKIP (0.000s)
[459.471476] [133/156] (572s left) syncobj_timeline (multi-wait-for-submit-signaled)
[459.530329] Starting subtest: multi-wait-for-submit-signaled
[459.531474] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[459.634377] [134/156] (572s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[459.726965] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[459.728591] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[459.810090] [135/156] (572s left) kms_atomic_interruptible (legacy-setmode)
[459.904524] Starting subtest: legacy-setmode
[459.905359] Starting dynamic subtest: pipe-A-eDP-1
[466.790538] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.885s)
[466.791399] Subtest legacy-setmode: SUCCESS (6.886s)
[467.598834] [136/156] (564s left) sw_sync (alloc_fence_invalid_timeline)
[467.628829] Starting subtest: alloc_fence_invalid_timeline
[467.629749] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[467.694437] [137/156] (564s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[467.788938] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[467.789945] Starting dynamic subtest: pipe-A-eDP-1
[470.018635] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.230s)
[470.029511] Starting dynamic subtest: pipe-B-eDP-1
[472.435602] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.415s)
[472.446426] Starting dynamic subtest: pipe-C-eDP-1
[474.548270] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.111s)
[474.559491] Starting dynamic subtest: pipe-D-eDP-1
[476.638645] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.089s)
[476.639734] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.851s)
[477.791658] [138/156] (554s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[477.881842] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[484.579556] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.699s)
[484.721187] [139/156] (547s left) kms_psr (fbc-pr-no-drrs)
[484.821842] Starting subtest: fbc-pr-no-drrs
[484.823749] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[484.891515] [140/156] (547s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-wc)
[484.980192] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-wc
[484.981943] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[485.090925] [141/156] (547s left) syncobj_wait (multi-wait-all-submitted-signaled)
[485.133260] Starting subtest: multi-wait-all-submitted-signaled
[485.248697] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.107s)
[485.315780] [142/156] (546s left) kms_flip (2x-blocking-wf_vblank)
[485.403035] Starting subtest: 2x-blocking-wf_vblank
[485.413721] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[485.513756] [143/156] (546s left) kms_dp_link_training (uhbr-mst)
[485.606711] Starting subtest: uhbr-mst
[485.611196] Subtest uhbr-mst: SKIP (0.000s)
[485.675447] [144/156] (546s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[485.767407] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[485.769068] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[485.853394] [145/156] (546s left) gem_exec_whisper (basic-fds-priority)
[485.910701] Starting subtest: basic-fds-priority
[509.792532] Subtest basic-fds-priority: SUCCESS (23.880s)
[509.978435] [146/156] (522s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-blt)
[510.078068] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-blt
[510.079870] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[510.176779] [147/156] (522s left) i915_drm_fdinfo (virtual-busy-all)
[510.209370] Starting subtest: virtual-busy-all
[510.211253] Subtest virtual-busy-all: SKIP (0.000s)
[510.282851] [148/156] (522s left) dumb_buffer (create-clear)
[510.334859] Starting subtest: create-clear
[544.796497] Subtest create-clear: SUCCESS (34.414s)
[544.862662] [149/156] (487s left) gem_exec_fence (syncobj-invalid-wait)
[545.337437] Starting subtest: syncobj-invalid-wait
[545.338954] Subtest syncobj-invalid-wait: SUCCESS (0.001s)
[546.416252] [150/156] (486s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt)
[546.504185] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt
[546.505859] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[546.602258] [151/156] (485s left) gem_pxp (hw-rejects-pxp-context)
[546.725151] Starting subtest: hw-rejects-pxp-context
[546.727670] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[546.840133] [152/156] (485s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[546.893851] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[546.895046] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[546.992371] [153/156] (485s left) kms_frontbuffer_tracking (hdr-2p-primscrn-shrfb-pgflip-blt)
[547.091251] Starting subtest: hdr-2p-primscrn-shrfb-pgflip-blt
[547.093055] Subtest hdr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[547.194307] [154/156] (485s left) gen9_exec_parse (allowed-all)
[547.245086] Subtest allowed-all: SKIP (0.000s)
[547.302943] [155/156] (485s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[547.359881] Starting subtest: etime-multi-wait-all-for-submit-submitted
[547.465694] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.105s)
[547.545718] [156/156] (485s left) fbdev (info)
[547.575430] Starting subtest: info
[547.576434] Subtest info: SUCCESS (0.000s)
[547.639833] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_pipe_crc_basic
results: parsing output: 2/ for test: kms_cursor_crc
results: parsing output: 3/ for test: kms_flip_scaled_crc
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: api_intel_bb
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_render_copy
results: parsing output: 8/ for test: gem_ctx_persistence
results: parsing output: 9/ for test: gem_mmap_gtt
results: parsing output: 10/ for test: kms_vblank
results: parsing output: 11/ for test: kms_big_fb
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_rotation_crc
results: parsing output: 14/ for test: drm_read
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: sriov_basic
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_atomic
results: parsing output: 19/ for test: perf
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_ccs
results: parsing output: 23/ for test: kms_flip
results: parsing output: 24/ for test: perf
results: parsing output: 25/ for test: i915_pm_rc6_residency
results: parsing output: 26/ for test: api_intel_allocator
results: parsing output: 27/ for test: syncobj_wait
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_addfb_basic
results: parsing output: 31/ for test: gem_pwrite
results: parsing output: 32/ for test: gem_exec_schedule
results: parsing output: 33/ for test: syncobj_timeline
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_exec_reloc
results: parsing output: 36/ for test: gem_mmap_gtt
results: parsing output: 37/ for test: kms_flip
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: gem_eio
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: perf
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_exec_balancer
results: parsing output: 45/ for test: syncobj_wait
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_atomic_transition
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_balancer
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_ctx_persistence
results: parsing output: 53/ for test: kms_pm_rpm
results: parsing output: 54/ for test: kms_content_protection
results: parsing output: 55/ for test: gem_exec_schedule
results: parsing output: 56/ for test: syncobj_wait
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_fbcon_fbt
results: parsing output: 61/ for test: kms_vrr
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_userptr_blits
results: parsing output: 64/ for test: gem_mmap_offset
results: parsing output: 65/ for test: sysfs_defaults
results: parsing output: 66/ for test: gem_ccs
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_rotation_crc
results: parsing output: 69/ for test: kms_hdr
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_psr2_sf
results: parsing output: 72/ for test: gem_lmem_swapping
results: parsing output: 73/ for test: kms_joiner
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: kms_flip_scaled_crc
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: kms_pm_dc
results: parsing output: 78/ for test: kms_cursor_legacy
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_getfb
results: parsing output: 81/ for test: kms_cursor_legacy
results: parsing output: 82/ for test: syncobj_wait
results: parsing output: 83/ for test: i915_suspend
results: parsing output: 84/ for test: kms_cursor_legacy
results: parsing output: 85/ for test: kms_ccs
results: parsing output: 86/ for test: gem_userptr_blits
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: gem_exec_schedule
results: parsing output: 91/ for test: kms_plane
results: parsing output: 92/ for test: kms_cursor_legacy
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: gem_spin_batch
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: syncobj_wait
results: parsing output: 100/ for test: kms_pm_rpm
results: parsing output: 101/ for test: syncobj_basic
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_chamelium_edid
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_dsc
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: gem_softpin
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_userptr_blits
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_ccs
results: parsing output: 114/ for test: i915_hangman
results: parsing output: 115/ for test: kms_lease
results: parsing output: 116/ for test: gem_lmem_swapping
results: parsing output: 117/ for test: kms_cursor_edge_walk
results: parsing output: 118/ for test: gem_eio
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_plane_scaling
results: parsing output: 121/ for test: kms_rmfb
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_chamelium_hpd
results: parsing output: 125/ for test: kms_psr
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: kms_addfb_basic
results: parsing output: 128/ for test: kms_3d
results: parsing output: 129/ for test: kms_color_pipeline
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_plane_lowres
results: parsing output: 132/ for test: syncobj_timeline
results: parsing output: 133/ for test: kms_psr2_sf
results: parsing output: 134/ for test: kms_atomic_interruptible
results: parsing output: 135/ for test: sw_sync
results: parsing output: 136/ for test: kms_ccs
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: kms_psr
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: syncobj_wait
results: parsing output: 141/ for test: kms_flip
results: parsing output: 142/ for test: kms_dp_link_training
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: gem_exec_whisper
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: i915_drm_fdinfo
results: parsing output: 147/ for test: dumb_buffer
results: parsing output: 148/ for test: gem_exec_fence
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: gem_pxp
results: parsing output: 151/ for test: syncobj_timeline
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: gen9_exec_parse
results: parsing output: 154/ for test: syncobj_timeline
results: parsing output: 155/ for test: fbdev
Done.