[58.741651] Initializing watchdogs
[58.741701]   /dev/watchdog0
[58.757226] [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]
[58.761857] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.764430] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.767959] [001/156] (960s left) i915_pm_freq_api (freq-reset-multiple)
[58.873360] Starting subtest: freq-reset-multiple
[58.875436] Starting dynamic subtest: gt0
[66.289083] Dynamic subtest gt0: SUCCESS (7.415s)
[66.298544] Starting dynamic subtest: gt1
[72.845604] Dynamic subtest gt1: SUCCESS (6.555s)
[72.849827] Subtest freq-reset-multiple: SUCCESS (13.972s)
[72.953681] [002/156] (946s left) kms_big_fb (linear-16bpp-rotate-180)
[73.156434] Starting subtest: linear-16bpp-rotate-180
[74.671494] Subtest linear-16bpp-rotate-180: SUCCESS (1.516s)
[74.802225] [003/156] (944s left) perf_pmu (busy-idle-no-semaphores)
[74.843951] Starting subtest: busy-idle-no-semaphores
[74.846637] Starting dynamic subtest: rcs0
[75.500854] Dynamic subtest rcs0: SUCCESS (0.655s)
[75.514284] Starting dynamic subtest: bcs0
[76.151087] Dynamic subtest bcs0: SUCCESS (0.649s)
[76.161841] Starting dynamic subtest: vcs0
[76.803225] Dynamic subtest vcs0: SUCCESS (0.651s)
[76.813581] Starting dynamic subtest: vcs1
[77.925430] Dynamic subtest vcs1: SUCCESS (1.121s)
[77.936185] Starting dynamic subtest: vecs0
[79.914492] Dynamic subtest vecs0: SUCCESS (1.988s)
[79.928861] Starting dynamic subtest: ccs0
[81.900707] Dynamic subtest ccs0: SUCCESS (1.985s)
[81.903335] Subtest busy-idle-no-semaphores: SUCCESS (7.056s)
[82.016122] [004/156] (937s left) kms_addfb_basic (no-handle)
[82.073588] Starting subtest: no-handle
[82.076059] Subtest no-handle: SUCCESS (0.000s)
[82.250974] [005/156] (937s left) kms_fb_coherency (memset-crc)
[82.350227] Starting subtest: memset-crc
[82.352544] Starting dynamic subtest: mmap-offset-wc
[82.749925] Dynamic subtest mmap-offset-wc: SUCCESS (0.400s)
[83.840684] Starting dynamic subtest: mmap-offset-uc
[89.520490] Dynamic subtest mmap-offset-uc: SUCCESS (5.693s)
[89.812880] Subtest memset-crc: SUCCESS (7.463s)
[90.621810] [006/156] (928s left) gem_render_copy (linear)
[90.688970] Starting subtest: linear
[90.691305] Starting dynamic subtest: smem
[90.727997] Dynamic subtest smem: SUCCESS (0.041s)
[90.731060] Subtest linear: SUCCESS (0.041s)
[90.911533] [007/156] (928s left) kms_cursor_crc (cursor-alpha-opaque)
[91.002078] Starting subtest: cursor-alpha-opaque
[91.004206] Starting dynamic subtest: pipe-A-eDP-1
[91.646852] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.646s)
[91.656874] Starting dynamic subtest: pipe-D-eDP-1
[92.913667] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.265s)
[92.916140] Subtest cursor-alpha-opaque: SUCCESS (1.912s)
[93.809167] [008/156] (925s left) gem_userptr_blits (vma-merge)
[93.890186] Starting subtest: vma-merge
[93.932586] Subtest vma-merge: SUCCESS (0.042s)
[94.044037] [009/156] (925s left) dmabuf_sync_file (import-multiple-read-write)
[94.129889] Starting subtest: import-multiple-read-write
[94.132272] Subtest import-multiple-read-write: SUCCESS (0.003s)
[94.187636] [FACT dmabuf_sync_file (import-multiple-read-write)] new: kernel.kmod_is_loaded.vgem: true
[94.191623] [010/156] (925s left) gem_ctx_persistence (smoketest)
[94.247074] Starting subtest: smoketest
[134.236694] Subtest smoketest: SUCCESS (39.987s)
[134.332478] [011/156] (885s left) syncobj_wait (multi-wait-for-submit-signaled)
[134.381227] Starting subtest: multi-wait-for-submit-signaled
[134.383771] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[134.476603] [012/156] (885s left) gem_ccs (block-multicopy-compressed)
[134.535191] Starting subtest: block-multicopy-compressed
[134.539225] Subtest block-multicopy-compressed: SKIP (0.000s)
[134.635662] [013/156] (885s left) kms_chamelium_hpd (common-hpd-after-suspend)
[135.017238] Subtest common-hpd-after-suspend: SKIP (0.000s)
[135.810431] [014/156] (884s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[135.915997] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[135.920650] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[136.025118] [015/156] (883s left) kms_frontbuffer_tracking (hdr-2p-pri-indfb-multidraw)
[136.125509] Starting subtest: hdr-2p-pri-indfb-multidraw
[136.129574] Subtest hdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[136.221151] [016/156] (883s left) gem_mmap_offset (mmap-boundaries)
[136.272933] Starting subtest: mmap-boundaries
[136.274999] Starting dynamic subtest: smem0
[136.557268] Dynamic subtest smem0: SUCCESS (0.285s)
[136.559904] Subtest mmap-boundaries: SUCCESS (0.285s)
[136.677856] [017/156] (883s left) kms_psr (fbc-psr-cursor-blt)
[136.784419] Starting subtest: fbc-psr-cursor-blt
[136.786822] Starting dynamic subtest: eDP-1
[136.791355] Dynamic subtest eDP-1: SKIP (0.000s)
[136.795889] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[136.872422] [018/156] (883s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf)
[136.971968] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf
[136.974134] Starting dynamic subtest: pipe-A-eDP-1
[147.651880] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.683s)
[147.661618] Starting dynamic subtest: pipe-B-eDP-1
[159.027805] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.373s)
[159.030583] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.058s)
[159.897845] [019/156] (860s left) gem_exec_params (invalid-bsd-ring)
[159.960882] Starting subtest: invalid-bsd-ring
[159.963299] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[160.074910] [020/156] (860s left) gem_exec_gttfill (multigpu-basic)
[160.254973] Starting subtest: multigpu-basic
[171.484385] Subtest multigpu-basic: SUCCESS (11.230s)
[171.541339] [021/156] (848s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[171.698272] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[171.702817] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[171.794321] [022/156] (848s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[171.922698] Starting subtest: cursorA-vs-flipB-varying-size
[171.927407] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[172.070064] [023/156] (848s left) vgem_basic (dmabuf-export)
[172.122302] Starting subtest: dmabuf-export
[172.161688] Subtest dmabuf-export: SUCCESS (0.043s)
[172.266032] [024/156] (847s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[172.359724] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[172.361997] Starting dynamic subtest: pipe-A-eDP-1
[172.384795] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[172.396481] Starting dynamic subtest: pipe-B-eDP-1
[172.418049] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[172.427382] Starting dynamic subtest: pipe-C-eDP-1
[172.448762] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[172.457875] Starting dynamic subtest: pipe-D-eDP-1
[172.480259] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[172.484297] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.003s)
[174.638318] [025/156] (845s left) gem_userptr_blits (access-control)
[174.721888] Starting subtest: access-control
[174.726611] Subtest access-control: SKIP (0.000s)
[174.867153] [026/156] (845s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[174.967369] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[174.969724] Starting dynamic subtest: pipe-A-eDP-1
[175.028452] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[175.038086] Starting dynamic subtest: pipe-B-eDP-1
[175.093390] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[175.105322] Starting dynamic subtest: pipe-C-eDP-1
[175.161026] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[175.171871] Starting dynamic subtest: pipe-D-eDP-1
[175.230159] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[175.234241] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.007s)
[177.305354] [027/156] (843s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[177.411773] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-mmap-wc
[177.415742] Subtest hdr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[177.501019] [028/156] (842s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[177.598160] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[177.602300] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[177.703278] [029/156] (842s left) kms_cursor_edge_walk (128x128-left-edge)
[177.797328] Starting subtest: 128x128-left-edge
[177.799712] Starting dynamic subtest: pipe-A-eDP-1
[181.273767] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.478s)
[181.285640] Starting dynamic subtest: pipe-D-eDP-1
[184.412818] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.135s)
[184.414882] Subtest 128x128-left-edge: SUCCESS (6.615s)
[185.218131] [030/156] (835s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[185.322491] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[185.327100] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[185.426340] [031/156] (835s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[185.517825] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[185.522093] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[185.599243] [032/156] (835s left) core_getstats (basic)
[185.650968] Starting subtest: basic
[185.653151] Subtest basic: SUCCESS (0.000s)
[185.746489] [033/156] (834s left) kms_pipe_crc_basic (bad-source)
[185.836952] Starting subtest: bad-source
[185.839244] Subtest bad-source: SUCCESS (0.000s)
[185.921842] [034/156] (834s left) perf_pmu (most-busy-check-all)
[185.967799] Starting subtest: most-busy-check-all
[185.969662] Starting dynamic subtest: rcs0
[187.325545] Dynamic subtest rcs0: SUCCESS (1.356s)
[187.337959] Starting dynamic subtest: bcs0
[189.246401] Dynamic subtest bcs0: SUCCESS (1.918s)
[189.258864] Starting dynamic subtest: vcs0
[191.292637] Dynamic subtest vcs0: SUCCESS (2.045s)
[191.303403] Starting dynamic subtest: vcs1
[193.277740] Dynamic subtest vcs1: SUCCESS (1.983s)
[193.290296] Starting dynamic subtest: vecs0
[195.260942] Dynamic subtest vecs0: SUCCESS (1.981s)
[195.272616] Starting dynamic subtest: ccs0
[197.339239] Dynamic subtest ccs0: SUCCESS (2.046s)
[197.343897] Subtest most-busy-check-all: SUCCESS (11.341s)
[197.399552] [035/156] (823s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[197.512858] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[204.484887] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.977s)
[204.633265] [036/156] (816s left) kms_addfb_basic (master-rmfb)
[204.684519] Starting subtest: master-rmfb
[204.686602] Subtest master-rmfb: SUCCESS (0.001s)
[204.849444] [037/156] (815s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-fullscreen)
[204.957580] Starting subtest: hdr-2p-scndscrn-spr-indfb-fullscreen
[204.961627] Subtest hdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[205.062993] [038/156] (815s left) kms_color (invalid-ctm-matrix-sizes)
[205.160832] Starting subtest: invalid-ctm-matrix-sizes
[205.163105] Starting dynamic subtest: pipe-A
[205.164959] Dynamic subtest pipe-A: SUCCESS (0.002s)
[205.174555] Starting dynamic subtest: pipe-B
[205.176819] Dynamic subtest pipe-B: SUCCESS (0.004s)
[205.185795] Starting dynamic subtest: pipe-C
[205.187761] Dynamic subtest pipe-C: SUCCESS (0.004s)
[205.197060] Starting dynamic subtest: pipe-D
[205.199288] Dynamic subtest pipe-D: SUCCESS (0.004s)
[205.201144] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.016s)
[205.251017] [039/156] (815s left) api_intel_bb (delta-check)
[205.306925] Starting subtest: delta-check
[205.311383] Subtest delta-check: SUCCESS (0.005s)
[205.435587] [040/156] (815s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[205.493149] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[205.497130] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[205.678481] [041/156] (815s left) kms_rotation_crc (sprite-rotation-270)
[205.766147] Starting subtest: sprite-rotation-270
[205.770790] Subtest sprite-rotation-270: SKIP (0.001s)
[205.840322] [042/156] (815s left) i915_pm_rps (thresholds-idle-park)
[205.899797] Starting subtest: thresholds-idle-park
[205.904472] Subtest thresholds-idle-park: SKIP (0.001s)
[205.975268] [043/156] (815s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[206.079800] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[206.084298] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[206.194971] [044/156] (814s left) kms_psr (fbc-pr-cursor-plane-onoff)
[206.290761] Starting subtest: fbc-pr-cursor-plane-onoff
[206.295268] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[206.365594] [045/156] (814s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[206.461978] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[206.464173] Starting dynamic subtest: pipe-A-default-mode
[207.628085] Dynamic subtest pipe-A-default-mode: SKIP (1.163s)
[207.632059] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.166s)
[207.800424] [046/156] (813s left) kms_chamelium_frames (hdmi-crc-single)
[208.180873] Subtest hdmi-crc-single: SKIP (0.000s)
[209.006868] [047/156] (812s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[209.068413] Starting subtest: multi-wait-all-for-submit-submitted
[209.177389] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.108s)
[209.266306] [048/156] (812s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[209.324828] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[209.433300] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.107s)
[209.513767] [049/156] (811s left) gen7_exec_parse (oacontrol-tracking)
[209.566813] Subtest oacontrol-tracking: SKIP (0.000s)
[209.631787] [050/156] (811s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[209.733752] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[209.737862] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[209.832726] [051/156] (811s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[209.923571] Starting subtest: fb-8bpc-vs-panel-8bpc
[209.925840] Starting dynamic subtest: pipe-A-eDP-1
[211.304350] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.380s)
[211.306348] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.381s)
[212.118320] [052/156] (809s left) kms_chamelium_color (ctm-0-50)
[212.212651] Subtest ctm-0-50: SKIP (0.000s)
[212.266775] [053/156] (809s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[212.362758] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[212.389371] Starting dynamic subtest: pipe-A-plane-0
[212.678453] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[212.689083] Starting dynamic subtest: pipe-A-plane-3
[212.911640] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[212.923304] Starting dynamic subtest: pipe-A-plane-5
[212.927833] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[214.001806] Starting dynamic subtest: pipe-B-plane-0
[214.282621] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[214.293070] Starting dynamic subtest: pipe-B-plane-3
[214.514992] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[214.524686] Starting dynamic subtest: pipe-B-plane-5
[214.528939] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[214.837235] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.474s)
[215.683691] [054/156] (805s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[215.786577] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[215.790821] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[215.886620] [055/156] (805s left) kms_psr (pr-suspend)
[215.982256] Starting subtest: pr-suspend
[215.986112] Subtest pr-suspend: SKIP (0.000s)
[216.053967] [056/156] (805s left) prime_busy (before-wait)
[216.112668] Starting subtest: before-wait
[216.115224] Starting dynamic subtest: rcs0
[216.140519] Dynamic subtest rcs0: SUCCESS (0.028s)
[216.151908] Starting dynamic subtest: bcs0
[216.186787] Dynamic subtest bcs0: SUCCESS (0.046s)
[216.197098] Starting dynamic subtest: vcs0
[216.229870] Dynamic subtest vcs0: SUCCESS (0.042s)
[216.243830] Starting dynamic subtest: vcs1
[216.271538] Dynamic subtest vcs1: SUCCESS (0.041s)
[216.280687] Starting dynamic subtest: vecs0
[216.311271] Dynamic subtest vecs0: SUCCESS (0.039s)
[216.320588] Starting dynamic subtest: ccs0
[216.351764] Dynamic subtest ccs0: SUCCESS (0.040s)
[216.460662] Subtest before-wait: SUCCESS (0.349s)
[216.532202] [057/156] (805s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[216.646804] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[224.094349] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.456s)
[224.223136] [058/156] (797s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[224.321624] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[224.325703] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[224.427359] [059/156] (797s left) kms_pipe_stress (stress-xrgb8888-yftiled)
[224.523271] Starting subtest: stress-xrgb8888-yftiled
[224.527544] Subtest stress-xrgb8888-yftiled: SKIP (0.000s)
[224.587323] [060/156] (797s left) gem_exec_whisper (basic-queues-all)
[224.663083] Starting subtest: basic-queues-all
[236.189178] Subtest basic-queues-all: SUCCESS (11.523s)
[236.387408] [061/156] (785s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[236.498750] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[243.219303] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.726s)
[243.346184] [062/156] (778s left) kms_flip (basic-flip-vs-dpms)
[243.448554] Starting subtest: basic-flip-vs-dpms
[243.450898] Starting dynamic subtest: A-eDP1
[246.057936] Dynamic subtest A-eDP1: SUCCESS (2.608s)
[246.068083] Starting dynamic subtest: B-eDP1
[248.309810] Dynamic subtest B-eDP1: SUCCESS (2.253s)
[248.320055] Starting dynamic subtest: C-eDP1
[250.495967] Dynamic subtest C-eDP1: SUCCESS (2.183s)
[250.506784] Starting dynamic subtest: D-eDP1
[252.747077] Dynamic subtest D-eDP1: SUCCESS (2.252s)
[252.749841] Subtest basic-flip-vs-dpms: SUCCESS (9.303s)
[253.585667] [063/156] (768s left) gem_exec_balancer (hog)
[253.647761] Starting subtest: hog
[253.652294] Subtest hog: SKIP (0.000s)
[253.952226] [064/156] (768s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[254.013544] Starting subtest: invalid-bsd1-flag-on-vebox
[254.015878] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[254.111183] [065/156] (767s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[254.210398] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[254.212795] Starting dynamic subtest: pipe-A-eDP-1
[254.236359] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[254.248430] Starting dynamic subtest: pipe-B-eDP-1
[254.270444] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[254.279809] Starting dynamic subtest: pipe-C-eDP-1
[254.302929] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[254.312631] Starting dynamic subtest: pipe-D-eDP-1
[254.334646] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[254.339085] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[256.546551] [066/156] (765s left) sysfs_heartbeat_interval (mixed)
[256.603798] Starting subtest: mixed
[256.606054] Starting dynamic subtest: vecs0
[261.834396] Dynamic subtest vecs0: SUCCESS (5.229s)
[261.846667] Starting dynamic subtest: ccs0
[267.029947] Dynamic subtest ccs0: SUCCESS (5.193s)
[267.041525] Starting dynamic subtest: vcs0
[272.295884] Dynamic subtest vcs0: SUCCESS (5.265s)
[272.307972] Starting dynamic subtest: bcs0
[277.484694] Dynamic subtest bcs0: SUCCESS (5.188s)
[277.496642] Starting dynamic subtest: rcs0
[282.729751] Dynamic subtest rcs0: SUCCESS (5.245s)
[282.742268] Starting dynamic subtest: vcs1
[287.964419] Dynamic subtest vcs1: SUCCESS (5.234s)
[287.967095] Subtest mixed: SUCCESS (31.361s)
[288.043982] [067/156] (734s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt)
[288.146602] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt
[288.150711] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[288.257254] [068/156] (733s left) gem_exec_flush (basic-wb-pro-default)
[288.319331] Starting subtest: basic-wb-pro-default
[293.784752] Subtest basic-wb-pro-default: SUCCESS (5.466s)
[293.970872] [069/156] (728s left) perf (i915-ref-count)
[294.060767] Starting subtest: i915-ref-count
[294.157151] Subtest i915-ref-count: SUCCESS (0.096s)
[294.224116] [070/156] (728s left) prime_self_import (basic-with_one_bo_two_files)
[294.258357] Starting subtest: basic-with_one_bo_two_files
[294.281209] Subtest basic-with_one_bo_two_files: SUCCESS (0.024s)
[294.372431] [071/156] (727s left) kms_frontbuffer_tracking (fbchdr-2p-rte)
[294.472547] Starting subtest: fbchdr-2p-rte
[294.476610] Subtest fbchdr-2p-rte: SKIP (0.000s)
[294.571063] [072/156] (727s left) kms_frontbuffer_tracking (fbcpsrhdr-indfb-scaledprimary)
[294.663213] Starting subtest: fbcpsrhdr-indfb-scaledprimary
[294.667490] Subtest fbcpsrhdr-indfb-scaledprimary: SKIP (0.000s)
[294.780468] [073/156] (727s left) kms_atomic (plane-primary-legacy)
[294.876667] Starting subtest: plane-primary-legacy
[294.878963] Starting dynamic subtest: pipe-A-eDP-1
[295.299418] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.421s)
[295.301890] Subtest plane-primary-legacy: SUCCESS (0.423s)
[296.158087] [074/156] (726s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[296.255597] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[296.259719] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[296.357455] [075/156] (726s left) gem_tiled_blits (normal)
[296.419802] Subtest normal: SKIP (0.000s)
[296.477935] [076/156] (726s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-pwrite)
[296.573723] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-pwrite
[296.578078] Subtest hdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[296.681542] [077/156] (725s left) kms_joiner (invalid-modeset-force-big-joiner)
[296.777724] Starting subtest: invalid-modeset-force-big-joiner
[296.782106] Subtest invalid-modeset-force-big-joiner: SKIP (0.000s)
[296.858821] [078/156] (725s left) kms_vblank (query-forked-busy-hang)
[296.953194] Starting subtest: query-forked-busy-hang
[296.955379] Starting dynamic subtest: pipe-A-eDP-1
[323.064302] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.112s)
[323.078825] Starting dynamic subtest: pipe-D-eDP-1
[348.032138] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.967s)
[348.034524] Subtest query-forked-busy-hang: SUCCESS (51.083s)
[348.910146] [079/156] (673s left) gem_exec_balancer (sliced)
[348.983054] Starting subtest: sliced
[348.987839] Subtest sliced: SKIP (0.000s)
[349.314162] [080/156] (673s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[349.408658] Starting subtest: primary-y-tiled-reflect-x-90
[349.413714] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[349.482344] [081/156] (673s left) gem_pread (self)
[349.540162] Subtest self: SKIP (0.000s)
[349.609585] [082/156] (673s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[349.662080] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[349.664361] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[349.778444] [083/156] (673s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[350.351333] Starting subtest: syncobj-timeline-invalid-flags
[350.353604] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s)
[351.391445] [084/156] (671s left) gem_exec_reloc (basic-wc-gtt)
[351.447608] Subtest basic-wc-gtt: SKIP (0.000s)
[351.521689] [085/156] (671s left) kms_mmap_write_crc (main)
[351.607709] Starting subtest: main
[351.610089] Starting dynamic subtest: pipe-A-eDP-1
[355.146670] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.537s)
[355.148988] Subtest main: SUCCESS (3.538s)
[356.039651] [086/156] (666s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[356.141654] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[356.146067] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[356.245690] [087/156] (666s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[356.347451] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[356.351619] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[356.426325] [088/156] (666s left) kms_busy (basic)
[356.530332] Starting subtest: basic
[356.532267] Starting dynamic subtest: flip
[357.878853] Dynamic subtest flip: SUCCESS (1.346s)
[357.890825] Starting dynamic subtest: modeset
[360.832641] Dynamic subtest modeset: SUCCESS (2.955s)
[360.835275] Subtest basic: SUCCESS (4.306s)
[361.656156] [089/156] (661s left) gem_exec_schedule (preempt-other)
[361.730081] Starting subtest: preempt-other
[361.732306] Starting dynamic subtest: rcs0
[361.796165] Dynamic subtest rcs0: SUCCESS (0.071s)
[361.809913] Starting dynamic subtest: bcs0
[361.865218] Dynamic subtest bcs0: SUCCESS (0.068s)
[361.881438] Starting dynamic subtest: vcs0
[361.935808] Dynamic subtest vcs0: SUCCESS (0.070s)
[361.950784] Starting dynamic subtest: vcs1
[362.004184] Dynamic subtest vcs1: SUCCESS (0.068s)
[362.018400] Starting dynamic subtest: vecs0
[362.072614] Dynamic subtest vecs0: SUCCESS (0.067s)
[362.086204] Starting dynamic subtest: ccs0
[362.143676] Dynamic subtest ccs0: SUCCESS (0.070s)
[362.146536] Subtest preempt-other: SUCCESS (0.419s)
[363.408353] [090/156] (659s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[363.515599] Starting subtest: y-tiled-8bpp-rotate-0
[363.519779] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[363.616307] [091/156] (659s left) kms_vblank (wait-busy-hang)
[363.707614] Starting subtest: wait-busy-hang
[363.709746] Starting dynamic subtest: pipe-A-eDP-1
[390.078733] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.369s)
[390.094369] Starting dynamic subtest: pipe-D-eDP-1
[415.038514] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.957s)
[415.040787] Subtest wait-busy-hang: SUCCESS (51.331s)
[415.919925] [092/156] (607s left) syncobj_wait (wait-all-snapshot)
[415.971570] Starting subtest: wait-all-snapshot
[416.051660] Subtest wait-all-snapshot: SUCCESS (0.081s)
[416.131798] [093/156] (607s left) kms_frontbuffer_tracking (psrhdr-2p-pri-indfb-multidraw)
[416.229250] Starting subtest: psrhdr-2p-pri-indfb-multidraw
[416.233364] Subtest psrhdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[416.325291] [094/156] (606s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[416.424724] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[416.426975] Starting dynamic subtest: pipe-A-eDP-1
[416.449306] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[416.462049] Starting dynamic subtest: pipe-B-eDP-1
[416.483725] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[416.493760] Starting dynamic subtest: pipe-C-eDP-1
[416.515027] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[416.524781] Starting dynamic subtest: pipe-D-eDP-1
[416.547266] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[416.551347] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.004s)
[418.681257] [095/156] (604s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[418.793682] Starting subtest: fbc-rgb101010-draw-pwrite
[422.107358] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (3.320s)
[423.303200] [096/156] (600s left) kms_psr (psr2-sprite-mmap-gtt)
[423.407230] Starting subtest: psr2-sprite-mmap-gtt
[423.409648] Starting dynamic subtest: eDP-1
[424.868935] Dynamic subtest eDP-1: SKIP (1.458s)
[424.873943] Subtest psr2-sprite-mmap-gtt: SKIP (1.460s)
[425.023045] [097/156] (598s left) i915_query (query-topology-kernel-writes)
[425.081606] Starting subtest: query-topology-kernel-writes
[425.084025] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[425.202811] [098/156] (598s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[425.297439] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[425.301853] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[425.376132] [099/156] (598s left) gem_ctx_shared (q-in-order)
[425.432301] Starting subtest: Q-in-order
[425.434689] Starting dynamic subtest: rcs0
[425.530356] Dynamic subtest rcs0: SUCCESS (0.097s)
[425.545570] Starting dynamic subtest: vcs0
[425.613719] Dynamic subtest vcs0: SUCCESS (0.082s)
[425.631230] Starting dynamic subtest: vcs1
[425.705637] Dynamic subtest vcs1: SUCCESS (0.091s)
[425.723119] Starting dynamic subtest: vecs0
[425.791048] Dynamic subtest vecs0: SUCCESS (0.085s)
[425.808039] Starting dynamic subtest: ccs0
[425.872414] Dynamic subtest ccs0: SUCCESS (0.080s)
[425.874785] Subtest Q-in-order: SUCCESS (0.440s)
[427.352968] [100/156] (596s left) kms_psr (fbc-psr2-cursor-render)
[427.454628] Starting subtest: fbc-psr2-cursor-render
[427.456834] Starting dynamic subtest: eDP-1
[427.460860] Dynamic subtest eDP-1: SKIP (0.000s)
[427.464947] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[427.533836] [101/156] (596s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[427.632519] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[427.636907] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[427.728167] [102/156] (595s left) kms_addfb_basic (bad-pitch-63)
[427.780240] Starting subtest: bad-pitch-63
[427.782377] Subtest bad-pitch-63: SUCCESS (0.000s)
[427.914505] [103/156] (595s left) kms_plane_scaling (planes-downscale-factor-0-75)
[428.014031] Starting subtest: planes-downscale-factor-0-75
[428.016267] Starting dynamic subtest: pipe-A
[428.035221] Dynamic subtest pipe-A: SKIP (0.019s)
[428.046107] Starting dynamic subtest: pipe-B
[428.070982] Dynamic subtest pipe-B: SKIP (0.034s)
[428.081383] Starting dynamic subtest: pipe-C
[428.104661] Dynamic subtest pipe-C: SKIP (0.033s)
[428.114814] Starting dynamic subtest: pipe-D
[428.138191] Dynamic subtest pipe-D: SKIP (0.033s)
[428.142461] Subtest planes-downscale-factor-0-75: SKIP (0.122s)
[428.240588] [104/156] (595s left) kms_prop_blob (blob-multiple)
[428.297307] Starting subtest: blob-multiple
[428.299565] Subtest blob-multiple: SUCCESS (0.002s)
[428.404897] [105/156] (595s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[428.492108] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[428.494036] Starting dynamic subtest: pipe-A
[428.555724] Dynamic subtest pipe-A: SUCCESS (0.065s)
[428.568014] Starting dynamic subtest: pipe-B
[429.734723] Dynamic subtest pipe-B: SUCCESS (1.177s)
[429.745121] Starting dynamic subtest: pipe-C
[430.841555] Dynamic subtest pipe-C: SUCCESS (1.105s)
[430.852019] Starting dynamic subtest: pipe-D
[431.979871] Dynamic subtest pipe-D: SUCCESS (1.137s)
[431.982786] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.489s)
[433.145535] [106/156] (590s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-pri-indfb-multidraw)
[433.271152] Starting subtest: fbcpsrhdr-2p-pri-indfb-multidraw
[433.275695] Subtest fbcpsrhdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[433.359721] [107/156] (590s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[433.458442] Starting subtest: fbc-farfromfence-mmap-gtt
[433.462905] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[433.552236] [108/156] (590s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[433.649064] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[433.653280] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[433.754902] [109/156] (590s left) kms_cursor_crc (cursor-onscreen-max-size)
[434.080269] Starting subtest: cursor-onscreen-max-size
[434.085906] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[434.214215] [110/156] (589s left) gem_exec_await (wide-all)
[434.390737] Starting subtest: wide-all
[458.023754] Subtest wide-all: SUCCESS (23.630s)
[458.216606] [111/156] (565s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[458.339101] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[458.341420] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[458.415753] [112/156] (565s left) gem_mmap_gtt (fault-concurrent)
[458.472505] Subtest fault-concurrent: SKIP (0.000s)
[458.532101] [113/156] (565s left) gem_exec_parallel (basic)
[458.593710] Starting subtest: basic
[458.596194] Starting dynamic subtest: rcs0
[460.137651] Dynamic subtest rcs0: SUCCESS (1.543s)
[460.151564] Starting dynamic subtest: bcs0
[461.662779] Dynamic subtest bcs0: SUCCESS (1.523s)
[461.675913] Starting dynamic subtest: vcs0
[463.304513] Dynamic subtest vcs0: SUCCESS (1.639s)
[463.315771] Starting dynamic subtest: vcs1
[465.277458] Dynamic subtest vcs1: SUCCESS (1.971s)
[465.290995] Starting dynamic subtest: vecs0
[467.261540] Dynamic subtest vecs0: SUCCESS (1.980s)
[467.274803] Starting dynamic subtest: ccs0
[468.734426] Dynamic subtest ccs0: SUCCESS (1.474s)
[468.736911] Subtest basic: SUCCESS (10.142s)
[468.939293] [114/156] (555s left) gem_exec_whisper (basic-queues-priority-all)
[468.999866] Starting subtest: basic-queues-priority-all
[492.511300] Subtest basic-queues-priority-all: SUCCESS (23.509s)
[492.709882] [115/156] (531s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-mmap-cpu)
[492.833957] Starting subtest: psrhdr-rgb101010-draw-mmap-cpu
[492.839177] Subtest psrhdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[492.928390] [116/156] (531s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[493.028639] Starting subtest: yf-tiled-16bpp-rotate-0
[493.033293] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[493.118079] [117/156] (531s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[493.178107] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[493.238412] [118/156] (531s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[493.331785] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[493.336256] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[493.432096] [119/156] (530s left) kms_hdr (bpc-switch)
[493.534190] Starting subtest: bpc-switch
[493.536058] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[498.079814] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (4.546s)
[498.093522] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[502.726251] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (4.644s)
[502.728672] Subtest bpc-switch: SUCCESS (9.195s)
[502.830614] [120/156] (521s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[502.929241] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[502.933397] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[503.014459] [121/156] (521s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-blt)
[503.118150] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-blt
[503.122315] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[503.229792] [122/156] (521s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-wc)
[503.326822] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-wc
[503.331115] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[503.447869] [123/156] (521s left) kms_plane_multiple (2x-tiling-4)
[503.546880] Starting subtest: 2x-tiling-4
[503.551066] Subtest 2x-tiling-4: SKIP (0.000s)
[503.635777] [124/156] (520s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[503.731144] Starting subtest: x-tiled-64bpp-rotate-0
[505.333961] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.602s)
[505.500450] [125/156] (519s left) vgem_basic (debugfs)
[505.542312] Starting subtest: debugfs
[505.544224] Subtest debugfs: SUCCESS (0.001s)
[505.599860] [126/156] (519s left) gem_softpin (allocator-nopin)
[505.652969] Starting subtest: allocator-nopin
[505.743068] Subtest allocator-nopin: SUCCESS (0.090s)
[505.870127] [127/156] (518s left) gem_ctx_persistence (engines-hang)
[505.931375] Starting subtest: engines-hang
[505.933377] Starting dynamic subtest: rcs0
[506.051409] Dynamic subtest rcs0: SUCCESS (0.120s)
[506.062733] Starting dynamic subtest: bcs0
[506.142272] Dynamic subtest bcs0: SUCCESS (0.089s)
[506.156995] Starting dynamic subtest: vcs0
[506.231607] Dynamic subtest vcs0: SUCCESS (0.087s)
[506.244082] Starting dynamic subtest: vcs1
[506.325399] Dynamic subtest vcs1: SUCCESS (0.092s)
[506.338905] Starting dynamic subtest: vecs0
[506.417588] Dynamic subtest vecs0: SUCCESS (0.089s)
[506.430541] Starting dynamic subtest: ccs0
[506.511454] Dynamic subtest ccs0: SUCCESS (0.092s)
[506.513671] Subtest engines-hang: SUCCESS (0.581s)
[506.604391] [128/156] (518s left) kms_pm_rpm (basic-rte)
[506.640103] Starting subtest: basic-rte
[509.927668] Subtest basic-rte: SUCCESS (3.288s)
[511.437798] [129/156] (513s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[511.546128] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[511.550278] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[511.628334] [130/156] (513s left) kms_cursor_crc (cursor-sliding-128x128)
[511.838608] Starting subtest: cursor-sliding-128x128
[511.843708] Starting dynamic subtest: pipe-A-eDP-1
[515.039638] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.196s)
[515.076206] Starting dynamic subtest: pipe-D-eDP-1
[518.933301] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.863s)
[518.935464] Subtest cursor-sliding-128x128: SUCCESS (7.091s)
[519.791423] [131/156] (505s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[519.902373] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[519.913975] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.011s)
[520.017658] [132/156] (504s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-move)
[520.114617] Starting subtest: psrhdr-2p-primscrn-spr-indfb-move
[520.118824] Subtest psrhdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[520.211237] [133/156] (504s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[520.307804] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[520.311829] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[520.413144] [134/156] (504s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[520.510676] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[520.515126] Subtest fbchdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[520.606499] [135/156] (504s left) kms_prop_blob (invalid-set-prop)
[520.660080] Starting subtest: invalid-set-prop
[520.662295] Subtest invalid-set-prop: SUCCESS (0.000s)
[520.784676] [136/156] (504s left) kms_flip (2x-dpms-vs-vblank-race)
[520.870684] Starting subtest: 2x-dpms-vs-vblank-race
[520.883645] Subtest 2x-dpms-vs-vblank-race: SKIP (0.011s)
[520.967391] [137/156] (504s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[521.072903] Starting subtest: 4-tiled-64bpp-rotate-180
[522.718874] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.645s)
[522.910810] [138/156] (502s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[523.010557] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[523.014110] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[523.097856] [139/156] (502s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[523.197101] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[523.200503] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[523.301383] [140/156] (501s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[523.393183] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[523.396891] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[523.488047] [141/156] (501s left) i915_pm_rpm (gem-idle)
[525.413675] Starting subtest: gem-idle
[531.613236] Subtest gem-idle: SUCCESS (6.200s)
[531.961540] [142/156] (493s left) gem_reg_read (timestamp-monotonic)
[533.280306] Starting subtest: timestamp-monotonic
[537.882321] Subtest timestamp-monotonic: SUCCESS (4.602s)
[537.958053] [143/156] (487s left) syncobj_wait (multi-wait-for-submit-submitted)
[538.022701] Starting subtest: multi-wait-for-submit-submitted
[538.129267] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s)
[538.206463] [144/156] (487s left) syncobj_timeline (wait-any-snapshot)
[538.262132] Starting subtest: wait-any-snapshot
[538.342942] Subtest wait-any-snapshot: SUCCESS (0.081s)
[538.442326] [145/156] (486s left) gen9_exec_parse (unaligned-jump)
[538.500332] Subtest unaligned-jump: SKIP (0.000s)
[538.581121] [146/156] (486s left) kms_psr (psr-sprite-blt)
[538.672831] Starting subtest: psr-sprite-blt
[538.675154] Starting dynamic subtest: eDP-1
[540.388086] Dynamic subtest eDP-1: SUCCESS (1.713s)
[540.390827] Subtest psr-sprite-blt: SUCCESS (1.714s)
[541.244208] [147/156] (484s left) gem_exec_schedule (smoketest-all)
[541.325805] Starting subtest: smoketest-all
[573.700778] Subtest smoketest-all: SUCCESS (32.334s)
[575.339508] [148/156] (450s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[575.445660] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[575.450059] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[575.542506] [149/156] (450s left) gem_ctx_engines (independent)
[575.599359] Starting subtest: independent
[575.601806] Starting dynamic subtest: rcs0
[575.745281] Dynamic subtest rcs0: SUCCESS (0.144s)
[575.759368] Starting dynamic subtest: bcs0
[575.856952] Dynamic subtest bcs0: SUCCESS (0.111s)
[575.871102] Starting dynamic subtest: vcs0
[575.988674] Dynamic subtest vcs0: SUCCESS (0.130s)
[576.002749] Starting dynamic subtest: vcs1
[576.111535] Dynamic subtest vcs1: SUCCESS (0.122s)
[576.122491] Starting dynamic subtest: vecs0
[576.243327] Dynamic subtest vecs0: SUCCESS (0.130s)
[576.257673] Starting dynamic subtest: ccs0
[576.376625] Dynamic subtest ccs0: SUCCESS (0.133s)
[576.386988] Starting dynamic subtest: all-engines
[576.878885] Dynamic subtest all-engines: SUCCESS (0.501s)
[576.881342] Subtest independent: SUCCESS (1.279s)
[577.436976] [150/156] (448s left) gem_exec_reloc (basic-write-read)
[577.494684] Subtest basic-write-read: SKIP (0.000s)
[577.571911] [151/156] (448s left) gem_userptr_blits (process-exit-busy)
[577.632447] Starting subtest: process-exit-busy
[577.644797] Subtest process-exit-busy: SUCCESS (0.013s)
[577.783864] [152/156] (447s left) syncobj_timeline (multi-wait-available-submitted)
[577.852037] Starting subtest: multi-wait-available-submitted
[577.854396] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[577.944677] [153/156] (447s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[578.041688] Starting subtest: fbcpsr-rgb101010-draw-render
[582.819942] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (4.783s)
[582.946635] [154/156] (442s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[583.008984] Starting subtest: mixed-tiled-to-y-tiled-ccs
[583.013580] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[583.196160] [155/156] (442s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-pwrite)
[583.304068] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-pwrite
[583.308324] Subtest hdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[583.399344] [156/156] (442s left) gem_exec_schedule (u-submit-early-slice)
[583.460675] Starting subtest: u-submit-early-slice
[583.462893] Starting dynamic subtest: rcs0
[583.493887] Dynamic subtest rcs0: SUCCESS (0.041s)
[583.505223] Starting dynamic subtest: bcs0
[583.536103] Dynamic subtest bcs0: SUCCESS (0.041s)
[583.550223] Starting dynamic subtest: vcs0
[583.578911] Dynamic subtest vcs0: SUCCESS (0.042s)
[583.593721] Starting dynamic subtest: vcs1
[583.618074] Dynamic subtest vcs1: SUCCESS (0.038s)
[583.629636] Starting dynamic subtest: vecs0
[583.663980] Dynamic subtest vecs0: SUCCESS (0.044s)
[583.677431] Starting dynamic subtest: ccs0
[583.702938] Dynamic subtest ccs0: SUCCESS (0.038s)
[583.705428] Subtest u-submit-early-slice: SUCCESS (0.251s)
[585.347987] Closing watchdogs
results: parsing output: 0/ for test: i915_pm_freq_api
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: perf_pmu
results: parsing output: 3/ for test: kms_addfb_basic
results: parsing output: 4/ for test: kms_fb_coherency
results: parsing output: 5/ for test: gem_render_copy
results: parsing output: 6/ for test: kms_cursor_crc
results: parsing output: 7/ for test: gem_userptr_blits
results: parsing output: 8/ for test: dmabuf_sync_file
results: parsing output: 9/ for test: gem_ctx_persistence
results: parsing output: 10/ for test: syncobj_wait
results: parsing output: 11/ for test: gem_ccs
results: parsing output: 12/ for test: kms_chamelium_hpd
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_mmap_offset
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: kms_psr2_sf
results: parsing output: 18/ for test: gem_exec_params
results: parsing output: 19/ for test: gem_exec_gttfill
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_cursor_legacy
results: parsing output: 22/ for test: vgem_basic
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: gem_userptr_blits
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_cursor_edge_walk
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: kms_flip_scaled_crc
results: parsing output: 31/ for test: core_getstats
results: parsing output: 32/ for test: kms_pipe_crc_basic
results: parsing output: 33/ for test: perf_pmu
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_addfb_basic
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_color
results: parsing output: 38/ for test: api_intel_bb
results: parsing output: 39/ for test: gem_render_copy
results: parsing output: 40/ for test: kms_rotation_crc
results: parsing output: 41/ for test: i915_pm_rps
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_flip_scaled_crc
results: parsing output: 45/ for test: kms_chamelium_frames
results: parsing output: 46/ for test: syncobj_wait
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: gen7_exec_parse
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_dither
results: parsing output: 51/ for test: kms_chamelium_color
results: parsing output: 52/ for test: kms_plane
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: prime_busy
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_pipe_stress
results: parsing output: 59/ for test: gem_exec_whisper
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: gem_exec_balancer
results: parsing output: 63/ for test: gem_exec_params
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: sysfs_heartbeat_interval
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_exec_flush
results: parsing output: 68/ for test: perf
results: parsing output: 69/ for test: prime_self_import
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_atomic
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_tiled_blits
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_joiner
results: parsing output: 77/ for test: kms_vblank
results: parsing output: 78/ for test: gem_exec_balancer
results: parsing output: 79/ for test: kms_rotation_crc
results: parsing output: 80/ for test: gem_pread
results: parsing output: 81/ for test: syncobj_timeline
results: parsing output: 82/ for test: gem_exec_fence
results: parsing output: 83/ for test: gem_exec_reloc
results: parsing output: 84/ for test: kms_mmap_write_crc
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_busy
results: parsing output: 88/ for test: gem_exec_schedule
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: kms_vblank
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_ccs
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_psr
results: parsing output: 96/ for test: i915_query
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gem_ctx_shared
results: parsing output: 99/ for test: kms_psr
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_addfb_basic
results: parsing output: 102/ for test: kms_plane_scaling
results: parsing output: 103/ for test: kms_prop_blob
results: parsing output: 104/ for test: kms_plane_scaling
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_cursor_crc
results: parsing output: 109/ for test: gem_exec_await
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: gem_mmap_gtt
results: parsing output: 112/ for test: gem_exec_parallel
results: parsing output: 113/ for test: gem_exec_whisper
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: gem_mmap_gtt
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_hdr
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_plane_multiple
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: vgem_basic
results: parsing output: 125/ for test: gem_softpin
results: parsing output: 126/ for test: gem_ctx_persistence
results: parsing output: 127/ for test: kms_pm_rpm
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_cursor_crc
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_prop_blob
results: parsing output: 135/ for test: kms_flip
results: parsing output: 136/ for test: kms_big_fb
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: i915_pm_rpm
results: parsing output: 141/ for test: gem_reg_read
results: parsing output: 142/ for test: syncobj_wait
results: parsing output: 143/ for test: syncobj_timeline
results: parsing output: 144/ for test: gen9_exec_parse
results: parsing output: 145/ for test: kms_psr
results: parsing output: 146/ for test: gem_exec_schedule
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: gem_ctx_engines
results: parsing output: 149/ for test: gem_exec_reloc
results: parsing output: 150/ for test: gem_userptr_blits
results: parsing output: 151/ for test: syncobj_timeline
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: gem_render_copy
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: gem_exec_schedule
Done.