[59.925554] Initializing watchdogs
[59.925596]   /dev/watchdog0
[59.939852] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.944097] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.946252] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.949205] [001/156] (960s left) syncobj_timeline (wait-zero-handles)
[60.027050] Starting subtest: wait-zero-handles
[60.029431] Subtest wait-zero-handles: SUCCESS (0.000s)
[60.136672] [002/156] (960s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[60.254752] Starting subtest: cursorB-vs-flipB-legacy
[60.259178] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[60.393778] [003/156] (960s left) gem_shrink (reclaim)
[60.457134] Starting subtest: reclaim
[62.904217] Subtest reclaim: SUCCESS (2.461s)
[63.047749] [004/156] (957s left) gem_mmap_offset (perf)
[63.108833] Starting subtest: perf
[63.111065] Starting dynamic subtest: smem0
[63.443832] Dynamic subtest smem0: SUCCESS (0.334s)
[63.446340] Subtest perf: SUCCESS (0.334s)
[63.549515] [005/156] (957s left) gem_ctx_persistence (heartbeat-hang)
[63.606306] Starting subtest: heartbeat-hang
[63.611016] Subtest heartbeat-hang: SKIP (0.000s)
[63.698844] [006/156] (956s left) gem_exec_whisper (basic-queues)
[63.752046] Starting subtest: basic-queues
[71.269999] Subtest basic-queues: SUCCESS (7.537s)
[71.448195] [007/156] (949s left) gem_ctx_shared (q-promotion)
[71.507858] Starting subtest: Q-promotion
[71.510204] Starting dynamic subtest: rcs0
[71.693713] Dynamic subtest rcs0: SUCCESS (0.184s)
[71.712536] Starting dynamic subtest: vcs0
[71.802373] Dynamic subtest vcs0: SUCCESS (0.110s)
[71.820209] Starting dynamic subtest: vcs1
[71.917049] Dynamic subtest vcs1: SUCCESS (0.114s)
[71.936889] Starting dynamic subtest: vecs0
[72.032031] Dynamic subtest vecs0: SUCCESS (0.115s)
[72.049426] Starting dynamic subtest: ccs0
[72.166486] Dynamic subtest ccs0: SUCCESS (0.132s)
[72.168736] Subtest Q-promotion: SUCCESS (0.658s)
[73.414118] [FACT gem_ctx_shared (q-promotion)] new: kernel.kmod_is_loaded.vgem: true
[73.417199] [008/156] (947s left) syncobj_wait (multi-wait-signaled)
[73.467775] Starting subtest: multi-wait-signaled
[73.470063] Subtest multi-wait-signaled: SUCCESS (0.000s)
[73.573112] [009/156] (947s left) gem_exec_balancer (bonded-pair)
[73.632973] Starting subtest: bonded-pair
[73.637354] Subtest bonded-pair: SKIP (0.000s)
[73.945213] [010/156] (946s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[74.036792] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[74.041570] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[75.227076] [011/156] (945s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-blt)
[75.324552] Starting subtest: psrhdr-rgb101010-draw-blt
[75.328612] Subtest psrhdr-rgb101010-draw-blt: SKIP (0.000s)
[75.405496] [012/156] (945s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[75.509654] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[75.513958] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[75.620623] [013/156] (945s left) gem_exec_reloc (basic-write-gtt-active)
[75.673446] Subtest basic-write-gtt-active: SKIP (0.000s)
[75.739953] [014/156] (945s left) gem_userptr_blits (coherency-unsync)
[75.800874] Subtest coherency-unsync: SKIP (0.000s)
[75.923369] [015/156] (945s left) gem_exec_schedule (out-order)
[75.981874] Starting subtest: out-order
[75.983642] Starting dynamic subtest: rcs0
[76.087614] Dynamic subtest rcs0: SUCCESS (0.108s)
[76.098813] Starting dynamic subtest: vcs0
[76.167041] Dynamic subtest vcs0: SUCCESS (0.079s)
[76.179778] Starting dynamic subtest: vcs1
[76.245700] Dynamic subtest vcs1: SUCCESS (0.078s)
[76.258568] Starting dynamic subtest: vecs0
[76.323601] Dynamic subtest vecs0: SUCCESS (0.078s)
[76.339633] Starting dynamic subtest: ccs0
[76.408528] Dynamic subtest ccs0: SUCCESS (0.085s)
[76.411038] Subtest out-order: SUCCESS (0.429s)
[77.405794] [016/156] (943s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[77.786119] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[78.639101] [017/156] (942s left) kms_async_flips (test-time-stamp)
[78.724193] Starting subtest: test-time-stamp
[79.111849] Starting dynamic subtest: pipe-A-eDP-1
[79.964981] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.851s)
[80.339966] Starting dynamic subtest: pipe-B-eDP-1
[81.195924] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.860s)
[81.571664] Starting dynamic subtest: pipe-C-eDP-1
[82.426985] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.862s)
[82.802726] Starting dynamic subtest: pipe-D-eDP-1
[83.571068] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s)
[83.573416] Subtest test-time-stamp: SUCCESS (4.850s)
[84.728334] [018/156] (936s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[84.830539] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-wc
[84.834744] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[84.943252] [019/156] (936s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[85.045994] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[85.050593] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[85.150131] [020/156] (936s left) gem_exec_params (secure-non-master)
[85.210799] Starting subtest: secure-non-master
[85.215392] Subtest secure-non-master: SKIP (0.000s)
[85.337407] [021/156] (935s left) kms_dsc (dsc-fractional-bpp)
[85.432081] Starting subtest: dsc-fractional-bpp
[85.436171] Subtest dsc-fractional-bpp: SKIP (0.000s)
[85.511914] [022/156] (935s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[85.602647] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[85.614503] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.010s)
[85.702781] [023/156] (935s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[85.800907] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[85.804476] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[85.915972] [024/156] (935s left) prime_mmap_coherency (ioctl-errors)
[85.971436] Starting subtest: ioctl-errors
[99.063110] Subtest ioctl-errors: SUCCESS (13.092s)
[99.296519] [025/156] (922s left) kms_psr (fbc-psr-dpms)
[99.430949] Starting subtest: fbc-psr-dpms
[99.432990] Starting dynamic subtest: eDP-1
[99.437298] Dynamic subtest eDP-1: SKIP (0.000s)
[99.441596] Subtest fbc-psr-dpms: SKIP (0.000s)
[99.503637] [026/156] (921s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-blt)
[99.614087] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-blt
[99.618463] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[99.713455] [027/156] (921s left) kms_atomic_interruptible (universal-setplane-primary)
[99.818674] Starting subtest: universal-setplane-primary
[99.820882] Starting dynamic subtest: pipe-A-eDP-1
[107.250213] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.430s)
[107.252499] Subtest universal-setplane-primary: SUCCESS (7.431s)
[108.088437] [028/156] (913s left) kms_frontbuffer_tracking (fbcpsrhdr-suspend)
[108.188634] Starting subtest: fbcpsrhdr-suspend
[108.192824] Subtest fbcpsrhdr-suspend: SKIP (0.000s)
[108.306400] [029/156] (913s left) kms_cursor_crc (cursor-random-64x64)
[108.474734] Starting subtest: cursor-random-64x64
[108.479315] Starting dynamic subtest: pipe-A-eDP-1
[111.848658] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.369s)
[111.880181] Starting dynamic subtest: pipe-D-eDP-1
[115.884710] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.012s)
[115.887081] Subtest cursor-random-64x64: SUCCESS (7.409s)
[116.774422] [030/156] (904s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[116.881419] Starting subtest: fbc-shrfb-scaledprimary
[119.772603] Subtest fbc-shrfb-scaledprimary: SUCCESS (2.895s)
[120.975720] [031/156] (900s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[121.355603] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[122.174241] [032/156] (899s left) kms_properties (invalid-properties-atomic)
[122.271873] Starting subtest: invalid-properties-atomic
[122.556716] Subtest invalid-properties-atomic: SUCCESS (0.285s)
[122.629394] [033/156] (899s left) gem_wait (write-wait)
[122.683747] Starting subtest: write-wait
[122.686183] Starting dynamic subtest: all-engines
[123.811218] Dynamic subtest all-engines: SUCCESS (1.127s)
[123.824021] Starting dynamic subtest: rcs0
[124.875901] Dynamic subtest rcs0: SUCCESS (1.064s)
[124.886363] Starting dynamic subtest: bcs0
[125.944278] Dynamic subtest bcs0: SUCCESS (1.066s)
[125.962852] Starting dynamic subtest: vcs0
[127.005479] Dynamic subtest vcs0: SUCCESS (1.059s)
[127.023271] Starting dynamic subtest: vcs1
[128.061222] Dynamic subtest vcs1: SUCCESS (1.055s)
[128.077510] Starting dynamic subtest: vecs0
[129.114616] Dynamic subtest vecs0: SUCCESS (1.052s)
[129.130566] Starting dynamic subtest: ccs0
[130.171209] Dynamic subtest ccs0: SUCCESS (1.056s)
[130.173805] Subtest write-wait: SUCCESS (7.488s)
[130.373612] [034/156] (891s left) gem_exec_balancer (persistence)
[130.632504] Starting subtest: persistence
[131.540970] Subtest persistence: SUCCESS (0.907s)
[131.676151] [035/156] (890s left) kms_getfb (getfb-handle-zero)
[131.782806] Starting subtest: getfb-handle-zero
[131.785075] Subtest getfb-handle-zero: SUCCESS (0.000s)
[131.885969] [036/156] (889s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-blt)
[131.985274] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-blt
[131.989577] Subtest fbchdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[132.072081] [037/156] (889s left) kms_psr (fbc-psr2-suspend)
[132.172479] Starting subtest: fbc-psr2-suspend
[132.174453] Starting dynamic subtest: eDP-1
[132.178671] Dynamic subtest eDP-1: SKIP (0.000s)
[132.182851] Subtest fbc-psr2-suspend: SKIP (0.000s)
[132.247215] [038/156] (889s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[132.338471] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[132.342807] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[132.437936] [039/156] (889s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[132.532784] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[132.535134] Starting dynamic subtest: pipe-A-default-mode
[135.266082] Dynamic subtest pipe-A-default-mode: SUCCESS (2.733s)
[135.268343] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.734s)
[136.136788] [040/156] (885s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[136.227412] Starting subtest: plane-lut1d-pre-ctm3x4
[136.384089] Starting dynamic subtest: pipe-A-plane-0
[136.539167] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[136.549840] Starting dynamic subtest: pipe-A-plane-1
[136.694931] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[136.705716] Starting dynamic subtest: pipe-A-plane-2
[136.839785] Dynamic subtest pipe-A-plane-2: SUCCESS (0.144s)
[138.018050] Starting dynamic subtest: pipe-B-plane-0
[138.162623] Dynamic subtest pipe-B-plane-0: SUCCESS (0.155s)
[138.172907] Starting dynamic subtest: pipe-B-plane-1
[138.352367] Dynamic subtest pipe-B-plane-1: SUCCESS (0.189s)
[138.362748] Starting dynamic subtest: pipe-B-plane-2
[138.540803] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s)
[139.740236] Starting dynamic subtest: pipe-C-plane-0
[139.864499] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[139.874871] Starting dynamic subtest: pipe-C-plane-1
[140.008822] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[140.019597] Starting dynamic subtest: pipe-C-plane-2
[140.175625] Dynamic subtest pipe-C-plane-2: SUCCESS (0.166s)
[141.403820] Starting dynamic subtest: pipe-D-plane-0
[141.562797] Dynamic subtest pipe-D-plane-0: SUCCESS (0.166s)
[141.572667] Starting dynamic subtest: pipe-D-plane-1
[141.718418] Dynamic subtest pipe-D-plane-1: SUCCESS (0.155s)
[141.731792] Starting dynamic subtest: pipe-D-plane-2
[141.851751] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[142.140199] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (5.912s)
[142.956291] [041/156] (879s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[143.060049] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[143.839483] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.781s)
[144.002238] [042/156] (878s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[144.058482] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[144.060728] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[144.160749] [043/156] (877s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[144.252610] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[144.264439] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[144.372881] [044/156] (877s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[144.429846] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[144.432512] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[144.522351] [045/156] (877s left) kms_hdr (invalid-metadata-sizes)
[144.620091] Starting subtest: invalid-metadata-sizes
[144.622247] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[144.626211] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[144.637266] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[144.641488] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[144.645777] Subtest invalid-metadata-sizes: SKIP (0.002s)
[144.691796] [046/156] (877s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[145.225493] Starting subtest: syncobj-backward-timeline-chain-engines
[145.327970] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.106s)
[146.404884] [047/156] (875s left) gem_mmap_wc (read)
[146.456632] Subtest read: SKIP (0.000s)
[146.511036] [048/156] (875s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[146.619171] Starting subtest: nonblocking-crc-frame-sequence
[146.621483] Starting dynamic subtest: pipe-A-eDP-1
[147.367967] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.747s)
[147.379603] Starting dynamic subtest: pipe-B-eDP-1
[148.771147] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.405s)
[148.781979] Starting dynamic subtest: pipe-C-eDP-1
[150.169465] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.396s)
[150.180841] Starting dynamic subtest: pipe-D-eDP-1
[151.586090] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.416s)
[151.588426] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.969s)
[152.398331] [049/156] (869s left) gem_madvise (dontneed-before-exec)
[152.435124] Starting subtest: dontneed-before-exec
[152.457240] Subtest dontneed-before-exec: SKIP (0.021s)
[152.519966] [050/156] (869s left) kms_psr (psr2-sprite-plane-move)
[152.606830] Starting subtest: psr2-sprite-plane-move
[152.609101] Starting dynamic subtest: eDP-1
[154.407514] Dynamic subtest eDP-1: SUCCESS (1.799s)
[154.409772] Subtest psr2-sprite-plane-move: SUCCESS (1.800s)
[155.250947] [051/156] (867s left) kms_scaling_modes (scaling-mode-full-aspect)
[155.354912] Starting subtest: scaling-mode-full-aspect
[155.357207] Starting dynamic subtest: pipe-A-eDP-1
[155.450963] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.098s)
[155.461914] Starting dynamic subtest: pipe-B-eDP-1
[156.685631] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.233s)
[156.695626] Starting dynamic subtest: pipe-C-eDP-1
[157.891541] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.205s)
[157.903816] Starting dynamic subtest: pipe-D-eDP-1
[159.029980] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.135s)
[159.032529] Subtest scaling-mode-full-aspect: SUCCESS (3.678s)
[160.205047] [052/156] (862s left) gem_ctx_persistence (engines-hostile)
[160.258847] Starting subtest: engines-hostile
[160.261148] Starting dynamic subtest: rcs0
[160.349717] Dynamic subtest rcs0: SUCCESS (0.090s)
[160.363429] Starting dynamic subtest: bcs0
[160.443840] Dynamic subtest bcs0: SUCCESS (0.092s)
[160.455759] Starting dynamic subtest: vcs0
[160.543709] Dynamic subtest vcs0: SUCCESS (0.098s)
[160.554170] Starting dynamic subtest: vcs1
[160.657629] Dynamic subtest vcs1: SUCCESS (0.112s)
[160.669852] Starting dynamic subtest: vecs0
[160.758634] Dynamic subtest vecs0: SUCCESS (0.099s)
[160.769358] Starting dynamic subtest: ccs0
[160.832242] Dynamic subtest ccs0: SUCCESS (0.071s)
[160.834786] Subtest engines-hostile: SUCCESS (0.574s)
[160.965346] [053/156] (861s left) kms_frontbuffer_tracking (fbchdr-modesetfrombusy)
[161.071790] Starting subtest: fbchdr-modesetfrombusy
[161.075788] Subtest fbchdr-modesetfrombusy: SKIP (0.000s)
[161.153169] [054/156] (861s left) fbdev (unaligned-read)
[161.194884] Starting subtest: unaligned-read
[161.208762] Subtest unaligned-read: SUCCESS (0.015s)
[161.266845] [055/156] (861s left) gem_exec_params (sol-reset-not-gen7)
[161.331465] Starting subtest: sol-reset-not-gen7
[161.336467] Subtest sol-reset-not-gen7: SUCCESS (0.004s)
[161.463319] [056/156] (861s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[161.562054] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[161.565438] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[161.687708] [057/156] (861s left) api_intel_bb (render)
[161.734232] Starting subtest: render
[161.735965] Starting dynamic subtest: render-none-512
[161.834839] Dynamic subtest render-none-512: SUCCESS (0.100s)
[161.845751] Starting dynamic subtest: render-none-1024
[162.220426] Dynamic subtest render-none-1024: SUCCESS (0.382s)
[162.232603] Starting dynamic subtest: render-x-512
[162.303974] Dynamic subtest render-x-512: SUCCESS (0.083s)
[162.315801] Starting dynamic subtest: render-x-1024
[162.636530] Dynamic subtest render-x-1024: SUCCESS (0.333s)
[162.649900] Starting dynamic subtest: render-y-512
[162.723289] Dynamic subtest render-y-512: SUCCESS (0.085s)
[162.736838] Starting dynamic subtest: render-y-1024
[163.059516] Dynamic subtest render-y-1024: SUCCESS (0.334s)
[163.062197] Subtest render: SUCCESS (1.324s)
[163.207948] [058/156] (859s left) perf_pmu (init-wait)
[163.246917] Starting subtest: init-wait
[163.249332] Starting dynamic subtest: rcs0
[163.251670] Dynamic subtest rcs0: SUCCESS (0.002s)
[163.262808] Starting dynamic subtest: bcs0
[163.265364] Dynamic subtest bcs0: SUCCESS (0.000s)
[163.274829] Starting dynamic subtest: vcs0
[163.277076] Dynamic subtest vcs0: SUCCESS (0.001s)
[163.288234] Starting dynamic subtest: vcs1
[163.290572] Dynamic subtest vcs1: SUCCESS (0.000s)
[163.300122] Starting dynamic subtest: vecs0
[163.301995] Dynamic subtest vecs0: SUCCESS (0.000s)
[163.312171] Starting dynamic subtest: ccs0
[163.314315] Dynamic subtest ccs0: SUCCESS (0.000s)
[163.316491] Subtest init-wait: SUCCESS (0.008s)
[163.370749] [059/156] (859s left) perf_pmu (busy)
[163.409741] Starting subtest: busy
[163.411753] Starting dynamic subtest: rcs0
[163.993826] Dynamic subtest rcs0: SUCCESS (0.586s)
[164.006264] Starting dynamic subtest: bcs0
[164.555303] Dynamic subtest bcs0: SUCCESS (0.559s)
[164.567210] Starting dynamic subtest: vcs0
[166.278175] Dynamic subtest vcs0: SUCCESS (1.719s)
[166.292071] Starting dynamic subtest: vcs1
[168.324962] Dynamic subtest vcs1: SUCCESS (2.044s)
[168.337473] Starting dynamic subtest: vecs0
[170.309840] Dynamic subtest vecs0: SUCCESS (1.981s)
[170.322947] Starting dynamic subtest: ccs0
[170.872899] Dynamic subtest ccs0: SUCCESS (0.561s)
[170.875940] Subtest busy: SUCCESS (7.465s)
[170.970791] [060/156] (851s left) gem_exec_schedule (timeslicing)
[171.037630] Starting subtest: timeslicing
[171.039973] Starting dynamic subtest: rcs0
[171.056197] Dynamic subtest rcs0: SUCCESS (0.025s)
[171.067681] Starting dynamic subtest: bcs0
[171.079592] Dynamic subtest bcs0: SUCCESS (0.023s)
[171.089206] Starting dynamic subtest: vcs0
[171.102629] Dynamic subtest vcs0: SUCCESS (0.022s)
[171.112744] Starting dynamic subtest: vcs1
[171.125744] Dynamic subtest vcs1: SUCCESS (0.022s)
[171.136258] Starting dynamic subtest: vecs0
[171.147268] Dynamic subtest vecs0: SUCCESS (0.022s)
[171.156832] Starting dynamic subtest: ccs0
[171.168452] Dynamic subtest ccs0: SUCCESS (0.021s)
[171.170822] Subtest timeslicing: SUCCESS (0.139s)
[172.335089] [061/156] (850s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[172.382639] Starting subtest: single-wait-all-for-submit-signaled
[172.384717] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[172.480302] [062/156] (850s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[172.590082] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[172.594411] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[172.693985] [063/156] (850s left) gem_create (busy-create)
[172.746323] Starting subtest: busy-create
[172.748640] Starting dynamic subtest: smem0
[206.278582] Dynamic subtest smem0: SUCCESS (33.529s)
[206.281250] Subtest busy-create: SUCCESS (33.531s)
[206.463981] [064/156] (816s left) i915_hangman (gt-engine-hang)
[206.533241] Starting subtest: gt-engine-hang
[206.535412] Starting dynamic subtest: rcs0
[208.292078] Dynamic subtest rcs0: SUCCESS (1.758s)
[208.306643] Starting dynamic subtest: bcs0
[210.275971] Dynamic subtest bcs0: SUCCESS (1.981s)
[210.288162] Starting dynamic subtest: vcs0
[212.260246] Dynamic subtest vcs0: SUCCESS (1.982s)
[212.273086] Starting dynamic subtest: vcs1
[214.245385] Dynamic subtest vcs1: SUCCESS (1.982s)
[214.258712] Starting dynamic subtest: vecs0
[216.227879] Dynamic subtest vecs0: SUCCESS (1.981s)
[216.240729] Starting dynamic subtest: ccs0
[218.276960] Dynamic subtest ccs0: SUCCESS (2.045s)
[218.279819] Subtest gt-engine-hang: SUCCESS (11.747s)
[218.405473] [065/156] (804s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[218.502087] Starting subtest: plane-downscale-factor-0-5-with-rotation
[218.504362] Starting dynamic subtest: pipe-A
[218.522669] Dynamic subtest pipe-A: SKIP (0.016s)
[218.534328] Starting dynamic subtest: pipe-B
[218.552454] Dynamic subtest pipe-B: SKIP (0.029s)
[218.562841] Starting dynamic subtest: pipe-C
[218.580425] Dynamic subtest pipe-C: SKIP (0.029s)
[218.590335] Starting dynamic subtest: pipe-D
[218.611816] Dynamic subtest pipe-D: SKIP (0.029s)
[218.616400] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.106s)
[218.708477] [066/156] (804s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[218.808698] Starting subtest: x-tiled-8bpp-rotate-90
[218.834075] Subtest x-tiled-8bpp-rotate-90: SKIP (0.021s)
[218.963645] [067/156] (804s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[219.078697] Starting subtest: cursorB-vs-flipB-varying-size
[219.083099] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[219.232822] [068/156] (803s left) gem_softpin (allocator-basic)
[219.296665] Starting subtest: allocator-basic
[219.356240] Subtest allocator-basic: SUCCESS (0.061s)
[219.495147] [069/156] (803s left) kms_chamelium_color (ctm-max)
[219.590537] Subtest ctm-max: SKIP (0.000s)
[219.638228] [070/156] (803s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[219.737257] Starting subtest: psr-1p-primscrn-cur-indfb-move
[227.049173] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.316s)
[227.187169] [071/156] (796s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[227.281054] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-pwrite
[227.285548] Subtest psrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[227.361783] [072/156] (795s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[229.500529] Starting subtest: gem-execbuf-stress-pc8
[229.537370] Subtest gem-execbuf-stress-pc8: SKIP (0.038s)
[229.899555] [073/156] (793s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[229.998764] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[230.001021] Starting dynamic subtest: pipe-A-eDP-1
[230.023618] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[230.034267] Starting dynamic subtest: pipe-B-eDP-1
[230.056107] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[230.065716] Starting dynamic subtest: pipe-C-eDP-1
[230.086763] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[230.096417] Starting dynamic subtest: pipe-D-eDP-1
[230.118114] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[230.122082] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[232.275574] [074/156] (791s left) syncobj_wait (wait-all-delayed-signal)
[232.326180] Starting subtest: wait-all-delayed-signal
[232.428067] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[232.505974] [075/156] (790s left) kms_addfb_basic (invalid-get-prop)
[232.559914] Starting subtest: invalid-get-prop
[232.562195] Subtest invalid-get-prop: SUCCESS (0.000s)
[232.717696] [076/156] (790s left) kms_vblank (wait-idle-hang)
[232.806798] Starting subtest: wait-idle-hang
[232.809049] Starting dynamic subtest: pipe-A-eDP-1
[259.075897] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.267s)
[259.094064] Starting dynamic subtest: pipe-D-eDP-1
[284.040157] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.964s)
[284.042723] Subtest wait-idle-hang: SUCCESS (51.235s)
[284.925316] [077/156] (738s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-msflip-blt)
[285.035701] Starting subtest: psrhdr-2p-scndscrn-shrfb-msflip-blt
[285.040188] Subtest psrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[285.135964] [078/156] (738s left) kms_vblank (query-idle)
[285.231249] Starting subtest: query-idle
[285.233089] Starting dynamic subtest: pipe-A-eDP-1
[286.993829] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.763s)
[287.009406] Starting dynamic subtest: pipe-D-eDP-1
[289.456400] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.460s)
[289.458756] Subtest query-idle: SUCCESS (4.227s)
[290.287842] [079/156] (733s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[290.389453] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[290.393615] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[290.481886] [080/156] (733s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-cpu)
[290.577118] Starting subtest: fbchdr-rgb565-draw-mmap-cpu
[290.581185] Subtest fbchdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[290.681223] [081/156] (733s left) dmabuf_sync_file (import-basic)
[290.719858] Starting subtest: import-basic
[290.722148] Subtest import-basic: SUCCESS (0.002s)
[290.774529] [082/156] (732s left) gem_mmap_wc (bad-object)
[290.824114] Subtest bad-object: SKIP (0.000s)
[290.882056] [083/156] (732s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[290.972168] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[298.471509] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.503s)
[298.594128] [084/156] (725s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[298.704763] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-wc
[298.708696] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[298.808908] [085/156] (725s left) perf_pmu (frequency)
[298.844490] Starting subtest: frequency
[298.850075] Starting dynamic subtest: gt0
[300.969078] Dynamic subtest gt0: SUCCESS (2.115s)
[300.981097] Starting dynamic subtest: idle-gt0
[301.474372] Dynamic subtest idle-gt0: SUCCESS (0.504s)
[301.486917] Starting dynamic subtest: gt1
[306.288391] Dynamic subtest gt1: SUCCESS (4.808s)
[306.300173] Starting dynamic subtest: idle-gt1
[306.830151] Dynamic subtest idle-gt1: SUCCESS (0.542s)
[306.832442] Subtest frequency: SUCCESS (7.989s)
[306.943550] [086/156] (716s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[307.049959] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[307.054353] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[307.145272] [087/156] (716s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[307.245789] Starting subtest: pr-cursor-plane-move-continuous-sf
[307.249801] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[307.319565] [088/156] (716s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier)
[307.417191] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier
[307.421647] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier: SKIP (0.000s)
[307.488286] [089/156] (716s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[307.588821] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[307.592996] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[307.657428] [090/156] (716s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[307.753314] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[307.755558] Starting dynamic subtest: pipe-A-eDP-1
[307.778346] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[307.791085] Starting dynamic subtest: pipe-B-eDP-1
[307.812136] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[307.822177] Starting dynamic subtest: pipe-C-eDP-1
[307.842433] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[307.852203] Starting dynamic subtest: pipe-D-eDP-1
[307.870470] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[307.873835] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[310.080529] [091/156] (714s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[310.146740] Starting subtest: etime-multi-wait-all-submitted-signaled
[310.256436] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.109s)
[310.333617] [092/156] (713s left) prime_self_import (basic-with_fd_dup)
[310.371982] Starting subtest: basic-with_fd_dup
[310.391850] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[310.506827] [093/156] (713s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[310.601930] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[314.613723] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (4.019s)
[315.790791] [094/156] (708s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[315.879960] Starting subtest: plane-upscale-20x20-with-pixel-format
[315.882231] Starting dynamic subtest: pipe-A
[316.928726] Dynamic subtest pipe-A: SUCCESS (1.050s)
[316.938491] Starting dynamic subtest: pipe-B
[319.097059] Dynamic subtest pipe-B: SUCCESS (2.167s)
[319.106672] Starting dynamic subtest: pipe-C
[320.297880] Dynamic subtest pipe-C: SUCCESS (1.200s)
[320.308420] Starting dynamic subtest: pipe-D
[321.517071] Dynamic subtest pipe-D: SUCCESS (1.217s)
[321.519828] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.639s)
[322.710683] [095/156] (701s left) gen9_exec_parse (unaligned-access)
[322.782254] Subtest unaligned-access: SKIP (0.000s)
[322.854665] [096/156] (701s left) syncobj_wait (multi-wait-submitted-signaled)
[322.906054] Starting subtest: multi-wait-submitted-signaled
[322.908057] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[323.007238] [097/156] (701s left) kms_feature_discovery (display-2x)
[323.095865] Starting subtest: display-2x
[323.100194] Subtest display-2x: SKIP (0.000s)
[323.168478] [098/156] (701s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[323.269603] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[323.273706] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[323.366095] [099/156] (701s left) kms_addfb_basic (invalid-set-prop-any)
[323.417275] Starting subtest: invalid-set-prop-any
[323.419585] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[323.553898] [100/156] (700s left) kms_lease (lease-invalid-crtc)
[323.646513] Starting subtest: lease-invalid-crtc
[323.648522] Starting dynamic subtest: pipe-A-eDP-1
[323.650464] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[323.660746] Starting dynamic subtest: pipe-B-eDP-1
[323.662666] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[323.671982] Starting dynamic subtest: pipe-C-eDP-1
[323.673982] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[323.683240] Starting dynamic subtest: pipe-D-eDP-1
[323.685078] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[323.686877] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[323.723464] [101/156] (700s left) kms_plane_multiple (2x-tiling-none)
[323.813968] Starting subtest: 2x-tiling-none
[323.817481] Subtest 2x-tiling-none: SKIP (0.000s)
[323.887435] [102/156] (700s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[323.975120] Starting subtest: addfb25-y-tiled-legacy
[323.976965] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[324.090684] [103/156] (700s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[324.189868] Starting subtest: psr-1p-primscrn-spr-indfb-move
[331.592358] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (7.406s)
[331.720894] [104/156] (692s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt)
[331.814771] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt
[336.329729] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (4.520s)
[337.526708] [105/156] (687s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[337.591110] Starting subtest: yf-tiled-ccs-to-y-tiled
[337.595161] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[337.770271] [106/156] (686s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[337.852098] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[337.853914] Starting dynamic subtest: pipe-A-eDP-1
[338.691116] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.831s)
[338.703189] Starting dynamic subtest: pipe-B-eDP-1
[340.327079] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.635s)
[340.338509] Starting dynamic subtest: pipe-C-eDP-1
[341.834081] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.506s)
[341.845308] Starting dynamic subtest: pipe-D-eDP-1
[343.371070] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.536s)
[343.373410] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.512s)
[344.555147] [107/156] (680s left) kms_flip (flip-vs-rmfb)
[344.656383] Starting subtest: flip-vs-rmfb
[344.658777] Starting dynamic subtest: A-eDP1
[353.351325] Dynamic subtest A-eDP1: SUCCESS (8.697s)
[353.364182] Starting dynamic subtest: B-eDP1
[361.977751] Dynamic subtest B-eDP1: SUCCESS (8.625s)
[361.987875] Starting dynamic subtest: C-eDP1
[370.587355] Dynamic subtest C-eDP1: SUCCESS (8.608s)
[370.599837] Starting dynamic subtest: D-eDP1
[379.220282] Dynamic subtest D-eDP1: SUCCESS (8.630s)
[379.224495] Subtest flip-vs-rmfb: SUCCESS (34.570s)
[380.413148] [108/156] (644s left) syncobj_wait (multi-wait-all-submitted)
[380.485766] Starting subtest: multi-wait-all-submitted
[380.586233] Subtest multi-wait-all-submitted: SUCCESS (0.102s)
[380.663689] [109/156] (644s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[380.759152] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[380.763580] Subtest hdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[380.843251] [110/156] (644s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[380.934563] Starting subtest: plane-all-modeset-transition-internal-panels
[380.936764] Starting dynamic subtest: pipe-A-eDP-1
[382.259887] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.325s)
[382.544426] Starting dynamic subtest: pipe-B-eDP-1
[383.576353] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.041s)
[383.850250] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.918s)
[384.685054] [111/156] (640s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-cpu)
[384.796726] Starting subtest: hdr-rgb101010-draw-mmap-cpu
[384.801179] Subtest hdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[384.895153] [112/156] (640s left) kms_frontbuffer_tracking (hdr-2p-primscrn-shrfb-msflip-blt)
[384.999869] Starting subtest: hdr-2p-primscrn-shrfb-msflip-blt
[385.004207] Subtest hdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[385.114474] [113/156] (639s left) gem_bad_reloc (negative-reloc-bltcopy)
[385.177858] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[385.244271] [114/156] (639s left) syncobj_wait (multi-wait-for-submit-submitted)
[385.299089] Starting subtest: multi-wait-for-submit-submitted
[385.402902] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s)
[385.494567] [115/156] (639s left) kms_pm_lpsp (kms-lpsp)
[386.588621] Starting subtest: kms-lpsp
[386.590939] Starting dynamic subtest: pipe-A-eDP-1
[386.723826] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.136s)
[387.027878] Subtest kms-lpsp: SUCCESS (0.439s)
[387.928134] [116/156] (637s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-pgflip-blt)
[388.037538] Starting subtest: fbchdr-1p-primscrn-indfb-pgflip-blt
[388.041709] Subtest fbchdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[388.143703] [117/156] (637s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[388.240521] Starting subtest: psr-rgb101010-draw-blt
[395.533181] Subtest psr-rgb101010-draw-blt: SUCCESS (7.299s)
[395.662765] [118/156] (629s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[395.759709] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[395.764034] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[395.865127] [119/156] (629s left) gem_flink_basic (bad-flink)
[395.919992] Starting subtest: bad-flink
[395.921805] Subtest bad-flink: SUCCESS (0.000s)
[396.022473] [120/156] (629s left) prime_vgem (busy)
[396.104651] Starting subtest: busy
[396.107273] Starting dynamic subtest: rcs0
[396.178452] Dynamic subtest rcs0: SUCCESS (0.075s)
[396.196465] Starting dynamic subtest: bcs0
[396.241437] Dynamic subtest bcs0: SUCCESS (0.060s)
[396.252872] Starting dynamic subtest: vcs0
[396.292603] Dynamic subtest vcs0: SUCCESS (0.049s)
[396.302578] Starting dynamic subtest: vcs1
[396.337746] Dynamic subtest vcs1: SUCCESS (0.044s)
[396.348037] Starting dynamic subtest: vecs0
[396.381771] Dynamic subtest vecs0: SUCCESS (0.043s)
[396.392169] Starting dynamic subtest: ccs0
[396.427783] Dynamic subtest ccs0: SUCCESS (0.045s)
[396.430243] Subtest busy: SUCCESS (0.325s)
[396.557523] [121/156] (628s left) syncobj_wait (wait-all-for-submit-snapshot)
[396.612473] Starting subtest: wait-all-for-submit-snapshot
[396.694415] Subtest wait-all-for-submit-snapshot: SUCCESS (0.081s)
[396.786208] [122/156] (628s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[396.889051] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[396.892687] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[396.986329] [123/156] (628s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-blt)
[397.088405] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-blt
[397.091816] Subtest hdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[397.180774] [124/156] (628s left) gen9_exec_parse (basic-rejected-ctx-param)
[397.235613] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[397.304716] [125/156] (628s left) gem_exec_params (readonly)
[397.355331] Starting subtest: readonly
[397.359617] Subtest readonly: SUCCESS (0.002s)
[397.472556] [126/156] (628s left) kms_psr (fbc-pr-primary-render)
[397.557724] Starting subtest: fbc-pr-primary-render
[397.561462] Subtest fbc-pr-primary-render: SKIP (0.000s)
[397.643477] [127/156] (628s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[397.731859] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[397.744123] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[397.831638] [128/156] (627s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[397.929637] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[397.933040] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[398.039922] [129/156] (627s left) kms_cursor_legacy (single-bo)
[398.157106] Starting subtest: single-bo
[398.158642] Starting dynamic subtest: pipe-A
[403.539046] Dynamic subtest pipe-A: SUCCESS (5.379s)
[403.549681] Starting dynamic subtest: pipe-B
[408.921864] Dynamic subtest pipe-B: SUCCESS (5.379s)
[408.934463] Starting dynamic subtest: pipe-C
[414.306640] Dynamic subtest pipe-C: SUCCESS (5.379s)
[414.320740] Starting dynamic subtest: pipe-D
[419.687423] Dynamic subtest pipe-D: SUCCESS (5.381s)
[419.699604] Starting dynamic subtest: all-pipes
[425.076626] Dynamic subtest all-pipes: SUCCESS (5.386s)
[425.079124] Subtest single-bo: SUCCESS (26.918s)
[425.234732] [130/156] (600s left) kms_cursor_crc (cursor-sliding-64x21)
[425.418100] Starting subtest: cursor-sliding-64x21
[425.465627] Subtest cursor-sliding-64x21: SKIP (0.042s)
[425.700829] [131/156] (600s left) gem_huc_copy (huc-copy)
[425.764936] Starting subtest: huc-copy
[425.767244] Subtest huc-copy: SUCCESS (0.004s)
[425.893731] [132/156] (599s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[425.987689] Starting subtest: primary-y-tiled-reflect-x-0
[426.047804] Subtest primary-y-tiled-reflect-x-0: SKIP (0.059s)
[426.174544] [133/156] (599s left) kms_properties (colorop-properties-atomic)
[426.263767] Starting subtest: colorop-properties-atomic
[426.265522] Starting dynamic subtest: pipe-A-eDP-1
[426.621324] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.359s)
[426.631704] Starting dynamic subtest: pipe-B-eDP-1
[427.717277] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.092s)
[427.727515] Starting dynamic subtest: pipe-C-eDP-1
[428.774784] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.058s)
[428.787440] Starting dynamic subtest: pipe-D-eDP-1
[429.886884] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.110s)
[429.889179] Subtest colorop-properties-atomic: SUCCESS (3.625s)
[430.705599] [134/156] (595s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[430.791326] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[430.795352] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[430.863293] [135/156] (595s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-msflip-blt)
[430.964310] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-msflip-blt
[430.968593] Subtest fbcpsrhdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[431.065297] [136/156] (594s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[431.116634] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[431.173618] [137/156] (594s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[431.260784] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[431.265209] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[431.334216] [138/156] (594s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-pwrite)
[431.429418] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-pwrite
[431.433740] Subtest hdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[431.528579] [139/156] (594s left) i915_pm_rps (thresholds-park)
[431.592740] Starting subtest: thresholds-park
[431.597563] Subtest thresholds-park: SKIP (0.001s)
[431.695698] [140/156] (594s left) kms_setmode (basic)
[431.740432] Starting subtest: basic
[432.061903] Starting dynamic subtest: pipe-A-eDP-1
[434.116606] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.050s)
[434.465572] Starting dynamic subtest: pipe-B-eDP-1
[436.544921] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.085s)
[436.864308] Subtest basic: SUCCESS (5.122s)
[437.695808] [141/156] (588s left) gem_partial_pwrite_pread (write)
[437.763405] Subtest write: SKIP (0.000s)
[437.854918] [142/156] (588s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[437.952806] Starting subtest: yf-tiled-addfb-size-offset-overflow
[437.957234] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[438.066302] [143/156] (588s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[438.169664] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[438.174101] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[438.250591] [144/156] (588s left) gem_softpin (overlap)
[438.304425] Starting subtest: overlap
[439.175071] Subtest overlap: SUCCESS (0.869s)
[439.319327] [145/156] (587s left) gem_softpin (softpin)
[439.378766] Starting subtest: softpin
[471.696661] Subtest softpin: SUCCESS (32.316s)
[471.813175] [146/156] (554s left) gem_spin_batch (resubmit-new-all)
[471.872012] Starting subtest: resubmit-new-all
[471.874331] Starting dynamic subtest: rcs0
[471.893651] Dynamic subtest rcs0: SUCCESS (0.021s)
[471.905587] Starting dynamic subtest: bcs0
[471.915593] Dynamic subtest bcs0: SUCCESS (0.021s)
[471.925536] Starting dynamic subtest: vcs0
[471.933987] Dynamic subtest vcs0: SUCCESS (0.018s)
[471.952066] Starting dynamic subtest: vcs1
[471.957256] Dynamic subtest vcs1: SUCCESS (0.019s)
[471.967641] Starting dynamic subtest: vecs0
[471.973657] Dynamic subtest vecs0: SUCCESS (0.018s)
[471.983565] Starting dynamic subtest: ccs0
[471.990361] Dynamic subtest ccs0: SUCCESS (0.016s)
[471.992624] Subtest resubmit-new-all: SUCCESS (0.119s)
[473.329345] [147/156] (553s left) kms_content_protection (dp-mst-lic-type-1)
[473.481407] Starting subtest: dp-mst-lic-type-1
[473.485815] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[473.591603] [148/156] (552s left) kms_cursor_crc (cursor-onscreen-512x512)
[473.871418] Starting subtest: cursor-onscreen-512x512
[473.875873] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[474.056436] [149/156] (552s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-move)
[474.149516] Starting subtest: psrhdr-2p-primscrn-cur-indfb-move
[474.153965] Subtest psrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[474.247851] [150/156] (552s left) kms_big_fb (linear-addfb)
[474.349244] Starting subtest: linear-addfb
[474.351176] Subtest linear-addfb: SUCCESS (0.000s)
[474.435848] [151/156] (552s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-blt)
[474.540828] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-blt
[474.544138] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[474.644157] [152/156] (552s left) kms_psr (psr2-cursor-mmap-cpu)
[474.730045] Starting subtest: psr2-cursor-mmap-cpu
[474.731983] Starting dynamic subtest: eDP-1
[476.470951] Dynamic subtest eDP-1: SUCCESS (1.740s)
[476.473628] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.741s)
[477.358729] [153/156] (549s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[477.462751] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[478.441962] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.978s)
[478.553116] [154/156] (548s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[478.660806] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[478.665186] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[478.753192] [155/156] (548s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[478.805772] Starting subtest: invalid-single-wait-unsubmitted
[478.807860] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[478.918962] [156/156] (547s left) api_intel_allocator (standalone)
[478.979963] Starting subtest: standalone
[478.996751] Subtest standalone: SUCCESS (0.017s)
[479.126070] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: kms_cursor_legacy
results: parsing output: 2/ for test: gem_shrink
results: parsing output: 3/ for test: gem_mmap_offset
results: parsing output: 4/ for test: gem_ctx_persistence
results: parsing output: 5/ for test: gem_exec_whisper
results: parsing output: 6/ for test: gem_ctx_shared
results: parsing output: 7/ for test: syncobj_wait
results: parsing output: 8/ for test: gem_exec_balancer
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gem_exec_reloc
results: parsing output: 13/ for test: gem_userptr_blits
results: parsing output: 14/ for test: gem_exec_schedule
results: parsing output: 15/ for test: kms_chamelium_hpd
results: parsing output: 16/ for test: kms_async_flips
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: gem_exec_params
results: parsing output: 20/ for test: kms_dsc
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: prime_mmap_coherency
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_atomic_interruptible
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_cursor_crc
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_chamelium_edid
results: parsing output: 31/ for test: kms_properties
results: parsing output: 32/ for test: gem_wait
results: parsing output: 33/ for test: gem_exec_balancer
results: parsing output: 34/ for test: kms_getfb
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_flip_scaled_crc
results: parsing output: 39/ for test: kms_color_pipeline
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: syncobj_wait
results: parsing output: 42/ for test: kms_flip
results: parsing output: 43/ for test: syncobj_timeline
results: parsing output: 44/ for test: kms_hdr
results: parsing output: 45/ for test: gem_exec_fence
results: parsing output: 46/ for test: gem_mmap_wc
results: parsing output: 47/ for test: kms_pipe_crc_basic
results: parsing output: 48/ for test: gem_madvise
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: kms_scaling_modes
results: parsing output: 51/ for test: gem_ctx_persistence
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: fbdev
results: parsing output: 54/ for test: gem_exec_params
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: api_intel_bb
results: parsing output: 57/ for test: perf_pmu
results: parsing output: 58/ for test: perf_pmu
results: parsing output: 59/ for test: gem_exec_schedule
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: gem_create
results: parsing output: 63/ for test: i915_hangman
results: parsing output: 64/ for test: kms_plane_scaling
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_cursor_legacy
results: parsing output: 67/ for test: gem_softpin
results: parsing output: 68/ for test: kms_chamelium_color
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: i915_pm_rpm
results: parsing output: 72/ for test: kms_ccs
results: parsing output: 73/ for test: syncobj_wait
results: parsing output: 74/ for test: kms_addfb_basic
results: parsing output: 75/ for test: kms_vblank
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_vblank
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: dmabuf_sync_file
results: parsing output: 81/ for test: gem_mmap_wc
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: perf_pmu
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_psr2_sf
results: parsing output: 87/ for test: kms_plane
results: parsing output: 88/ for test: kms_psr2_sf
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: prime_self_import
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_plane_scaling
results: parsing output: 94/ for test: gen9_exec_parse
results: parsing output: 95/ for test: syncobj_wait
results: parsing output: 96/ for test: kms_feature_discovery
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_addfb_basic
results: parsing output: 99/ for test: kms_lease
results: parsing output: 100/ for test: kms_plane_multiple
results: parsing output: 101/ for test: kms_addfb_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: gem_render_copy
results: parsing output: 105/ for test: kms_ccs
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: syncobj_wait
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_atomic_transition
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gem_bad_reloc
results: parsing output: 113/ for test: syncobj_wait
results: parsing output: 114/ for test: kms_pm_lpsp
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_flink_basic
results: parsing output: 119/ for test: prime_vgem
results: parsing output: 120/ for test: syncobj_wait
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gen9_exec_parse
results: parsing output: 124/ for test: gem_exec_params
results: parsing output: 125/ for test: kms_psr
results: parsing output: 126/ for test: kms_flip
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_cursor_legacy
results: parsing output: 129/ for test: kms_cursor_crc
results: parsing output: 130/ for test: gem_huc_copy
results: parsing output: 131/ for test: kms_rotation_crc
results: parsing output: 132/ for test: kms_properties
results: parsing output: 133/ for test: kms_plane
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: gem_mmap_gtt
results: parsing output: 136/ for test: kms_flip_scaled_crc
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: i915_pm_rps
results: parsing output: 139/ for test: kms_setmode
results: parsing output: 140/ for test: gem_partial_pwrite_pread
results: parsing output: 141/ for test: kms_big_fb
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: gem_softpin
results: parsing output: 144/ for test: gem_softpin
results: parsing output: 145/ for test: gem_spin_batch
results: parsing output: 146/ for test: kms_content_protection
results: parsing output: 147/ for test: kms_cursor_crc
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_big_fb
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: kms_psr
results: parsing output: 152/ for test: kms_big_fb
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: syncobj_timeline
results: parsing output: 155/ for test: api_intel_allocator
Done.