[58.054079] Initializing watchdogs
[58.054222]   /dev/watchdog0
[58.071094] [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.075645] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.078508] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.081943] [001/156] (960s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[58.207645] Starting subtest: primary-4-tiled-reflect-x-180
[65.201486] Subtest primary-4-tiled-reflect-x-180: SUCCESS (6.988s)
[66.444324] [002/156] (952s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[66.546565] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[66.550785] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[66.649449] [003/156] (952s left) perf (rc6-disable)
[66.772764] Starting subtest: rc6-disable
[66.948491] Subtest rc6-disable: SUCCESS (0.175s)
[66.991960] [004/156] (951s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-wc)
[67.103174] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-wc
[67.107177] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[67.201335] [005/156] (951s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[67.297987] Starting subtest: 2x-flip-vs-rmfb-interruptible
[67.310933] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.011s)
[67.412057] [006/156] (951s left) gem_exec_whisper (basic-fds-forked)
[67.478218] Starting subtest: basic-fds-forked
[93.507578] Subtest basic-fds-forked: SUCCESS (25.964s)
[93.647941] [007/156] (925s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode)
[94.043642] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s)
[94.874940] [008/156] (923s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[94.933458] Starting subtest: etime-multi-wait-all-available-unsubmitted
[95.042059] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.109s)
[95.128276] [009/156] (923s left) kms_color (invalid-gamma-lut-sizes)
[95.230129] Starting subtest: invalid-gamma-lut-sizes
[95.232522] Starting dynamic subtest: pipe-A
[95.523192] Dynamic subtest pipe-A: SUCCESS (0.293s)
[95.537436] Starting dynamic subtest: pipe-B
[95.540918] Dynamic subtest pipe-B: SUCCESS (0.000s)
[95.549632] Starting dynamic subtest: pipe-C
[95.551877] Dynamic subtest pipe-C: SUCCESS (0.000s)
[95.560025] Starting dynamic subtest: pipe-D
[95.562266] Dynamic subtest pipe-D: SUCCESS (0.000s)
[95.564442] Subtest invalid-gamma-lut-sizes: SUCCESS (0.296s)
[96.331172] [010/156] (922s left) kms_pm_rpm (package-g7)
[98.501499] Starting subtest: package-g7
[98.506228] Subtest package-g7: SKIP (0.000s)
[98.898425] [011/156] (920s left) gen9_exec_parse (allowed-all)
[98.966220] Subtest allowed-all: SKIP (0.000s)
[99.032479] [012/156] (919s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[99.185443] Starting subtest: cursorB-vs-flipA-atomic
[99.190162] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[99.336904] [013/156] (919s left) kms_getfb (getfb2-handle-zero)
[99.429031] Starting subtest: getfb2-handle-zero
[99.431414] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[99.528644] [014/156] (919s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[99.635272] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[99.639391] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[99.733288] [015/156] (919s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[99.779936] Starting subtest: multi-wait-for-submit-submitted-signaled
[99.782414] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[99.865583] [016/156] (919s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[99.958188] Starting subtest: psr-rgb565-draw-render
[107.132829] Subtest psr-rgb565-draw-render: SUCCESS (7.176s)
[107.259145] [017/156] (911s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[107.366119] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[107.370082] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[107.462197] [018/156] (911s left) gem_fenced_exec_thrash (no-spare-fences)
[107.523194] Subtest no-spare-fences: SKIP (0.000s)
[107.596326] [019/156] (911s left) kms_flip (2x-plain-flip-fb-recreate)
[107.686193] Starting subtest: 2x-plain-flip-fb-recreate
[107.696172] Subtest 2x-plain-flip-fb-recreate: SKIP (0.011s)
[107.784896] [020/156] (911s left) gem_exec_schedule (independent)
[107.848030] Starting subtest: independent
[107.849985] Starting dynamic subtest: rcs0
[109.319438] Dynamic subtest rcs0: SUCCESS (1.479s)
[109.332096] Starting dynamic subtest: vcs0
[111.318622] Dynamic subtest vcs0: SUCCESS (1.998s)
[111.330282] Starting dynamic subtest: vcs1
[113.304959] Dynamic subtest vcs1: SUCCESS (1.985s)
[113.318403] Starting dynamic subtest: vecs0
[115.280398] Dynamic subtest vecs0: SUCCESS (1.975s)
[115.292249] Starting dynamic subtest: ccs0
[117.317823] Dynamic subtest ccs0: SUCCESS (2.036s)
[117.320786] Subtest independent: SUCCESS (9.479s)
[117.617680] [021/156] (901s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[117.728297] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[117.732653] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[117.820975] [022/156] (901s left) gem_exec_capture (pi)
[117.893976] Starting subtest: pi
[117.896412] Starting dynamic subtest: rcs0
[122.407244] Dynamic subtest rcs0: SUCCESS (4.512s)
[122.418809] Starting dynamic subtest: bcs0
[126.584952] Dynamic subtest bcs0: SUCCESS (4.178s)
[126.594945] Starting dynamic subtest: vcs0
[130.721074] Dynamic subtest vcs0: SUCCESS (4.135s)
[130.733356] Starting dynamic subtest: vcs1
[134.855265] Dynamic subtest vcs1: SUCCESS (4.132s)
[134.866090] Starting dynamic subtest: vecs0
[138.947086] Dynamic subtest vecs0: SUCCESS (4.091s)
[138.958074] Starting dynamic subtest: ccs0
[143.117717] Dynamic subtest ccs0: SUCCESS (4.170s)
[143.120064] Subtest pi: SUCCESS (25.224s)
[143.195216] [023/156] (876s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-render)
[143.361429] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-render
[143.365759] Subtest hdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[143.459897] [024/156] (876s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[143.575108] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[143.579770] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[143.719888] [025/156] (875s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[143.824209] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[143.828234] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[143.918331] [026/156] (875s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[144.025678] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[144.029650] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[144.121119] [027/156] (875s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[144.215490] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[144.217397] Starting dynamic subtest: pipe-A-eDP-1
[144.237863] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[144.248054] Starting dynamic subtest: pipe-B-eDP-1
[144.265824] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[144.274630] Starting dynamic subtest: pipe-C-eDP-1
[144.293045] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[144.301661] Starting dynamic subtest: pipe-D-eDP-1
[144.319965] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[144.323545] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[146.501770] [028/156] (873s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-blt)
[146.598609] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-blt
[146.602719] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[146.695518] [029/156] (873s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[146.794987] Starting subtest: fbc-psr-cursor-mmap-cpu
[146.797297] Starting dynamic subtest: eDP-1
[146.801433] Dynamic subtest eDP-1: SKIP (0.000s)
[146.805675] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.000s)
[146.864999] [030/156] (872s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[146.956132] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[146.960184] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.000s)
[147.044353] [031/156] (872s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[147.151930] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[147.156101] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[147.259784] [032/156] (872s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[147.370739] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[151.324812] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.960s)
[152.476489] [033/156] (867s left) kms_psr (pr-primary-page-flip)
[152.572200] Starting subtest: pr-primary-page-flip
[152.576737] Subtest pr-primary-page-flip: SKIP (0.000s)
[152.645110] [034/156] (867s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[152.742216] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[156.792712] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (4.055s)
[157.955739] [035/156] (861s left) kms_psr (fbc-pr-cursor-plane-move)
[158.048184] Starting subtest: fbc-pr-cursor-plane-move
[158.052697] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[158.130257] [036/156] (861s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[158.238548] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[164.990464] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.755s)
[165.116276] [037/156] (854s left) gem_ctx_shared (exec-single-timeline)
[165.173805] Starting subtest: exec-single-timeline
[165.176268] Starting dynamic subtest: rcs0
[166.243702] Dynamic subtest rcs0: SUCCESS (1.067s)
[166.256958] Starting dynamic subtest: bcs0
[167.330494] Dynamic subtest bcs0: SUCCESS (1.086s)
[167.343678] Starting dynamic subtest: vcs0
[168.418671] Dynamic subtest vcs0: SUCCESS (1.087s)
[168.430927] Starting dynamic subtest: vcs1
[169.506533] Dynamic subtest vcs1: SUCCESS (1.085s)
[169.518463] Starting dynamic subtest: vecs0
[170.595492] Dynamic subtest vecs0: SUCCESS (1.087s)
[170.608888] Starting dynamic subtest: ccs0
[171.683807] Dynamic subtest ccs0: SUCCESS (1.085s)
[171.687299] Subtest exec-single-timeline: SUCCESS (6.508s)
[172.310273] [038/156] (847s left) prime_vgem (basic-gtt)
[172.433555] Starting subtest: basic-gtt
[172.437546] Subtest basic-gtt: SKIP (0.000s)
[172.538249] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true
[172.542141] [039/156] (847s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[172.653166] Starting subtest: fbcpsr-rgb565-draw-pwrite
[179.816613] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (7.167s)
[179.927502] [040/156] (840s left) gem_exec_flush (basic-wb-ro-default)
[179.994486] Starting subtest: basic-wb-ro-default
[185.481312] Subtest basic-wb-ro-default: SUCCESS (5.455s)
[185.633283] [041/156] (834s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[185.749596] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[185.753951] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[185.852134] [042/156] (834s left) kms_cursor_edge_walk (256x256-top-bottom)
[185.945085] Starting subtest: 256x256-top-bottom
[185.947360] Starting dynamic subtest: pipe-A-eDP-1
[189.392687] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.448s)
[189.406677] Starting dynamic subtest: pipe-D-eDP-1
[192.527492] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.135s)
[192.529840] Subtest 256x256-top-bottom: SUCCESS (6.585s)
[193.333325] [043/156] (826s left) i915_pm_rpm (gem-execbuf-stress)
[195.376814] Starting subtest: gem-execbuf-stress
[195.379192] Starting dynamic subtest: smem0
[208.341534] Dynamic subtest smem0: SUCCESS (12.965s)
[208.354035] Starting dynamic subtest: extra-wait-smem0
[270.446835] Dynamic subtest extra-wait-smem0: SUCCESS (62.098s)
[270.449865] Subtest gem-execbuf-stress: SUCCESS (75.067s)
[270.828359] [044/156] (749s left) gem_ctx_isolation (nonpriv)
[270.899620] Starting subtest: nonpriv
[270.902096] Starting dynamic subtest: rcs0
[271.141162] Dynamic subtest rcs0: SUCCESS (0.242s)
[271.151689] Starting dynamic subtest: bcs0
[271.407292] Dynamic subtest bcs0: SUCCESS (0.265s)
[271.419923] Starting dynamic subtest: vcs0
[271.645477] Dynamic subtest vcs0: SUCCESS (0.237s)
[271.657604] Starting dynamic subtest: vcs1
[273.548080] Dynamic subtest vcs1: SUCCESS (1.901s)
[273.558426] Starting dynamic subtest: vecs0
[275.580230] Dynamic subtest vecs0: SUCCESS (2.032s)
[275.591743] Starting dynamic subtest: ccs0
[277.604461] Dynamic subtest ccs0: SUCCESS (2.023s)
[277.606920] Subtest nonpriv: SUCCESS (6.704s)
[277.855096] [045/156] (742s left) gem_exec_schedule (fifo)
[277.920588] Starting subtest: fifo
[277.922941] Starting dynamic subtest: rcs0
[278.087066] Dynamic subtest rcs0: SUCCESS (0.175s)
[278.098404] Starting dynamic subtest: vcs0
[278.194804] Dynamic subtest vcs0: SUCCESS (0.108s)
[278.207386] Starting dynamic subtest: vcs1
[278.306944] Dynamic subtest vcs1: SUCCESS (0.112s)
[278.321722] Starting dynamic subtest: vecs0
[278.406694] Dynamic subtest vecs0: SUCCESS (0.099s)
[278.418864] Starting dynamic subtest: ccs0
[278.527558] Dynamic subtest ccs0: SUCCESS (0.119s)
[278.530409] Subtest fifo: SUCCESS (0.617s)
[279.396812] [046/156] (741s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-pwrite)
[279.494986] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-pwrite
[279.499182] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[279.595537] [047/156] (740s left) gem_exec_params (batch-first)
[279.657170] Starting subtest: batch-first
[279.663601] Subtest batch-first: SUCCESS (0.008s)
[279.789431] [048/156] (740s left) perf (mi-rpc)
[279.911819] Starting subtest: mi-rpc
[279.916434] Subtest mi-rpc: SKIP (0.000s)
[279.963516] [049/156] (740s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[280.069621] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[284.510429] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.449s)
[285.734673] [050/156] (734s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[285.844410] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[285.846752] Starting dynamic subtest: pipe-A-eDP-1
[285.870784] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[285.883199] Starting dynamic subtest: pipe-B-eDP-1
[285.905077] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[285.914939] Starting dynamic subtest: pipe-C-eDP-1
[285.936422] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[285.945964] Starting dynamic subtest: pipe-D-eDP-1
[285.968207] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[285.972582] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[288.174323] [051/156] (732s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-msflip-blt)
[288.276586] Starting subtest: hdr-1p-primscrn-shrfb-msflip-blt
[288.281058] Subtest hdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[288.381305] [052/156] (732s left) gem_exec_reloc (basic-cpu-gtt-active)
[288.432616] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[288.502920] [053/156] (732s left) gem_spin_batch (resubmit-all)
[288.574283] Starting subtest: resubmit-all
[288.576593] Starting dynamic subtest: rcs0
[288.585173] Dynamic subtest rcs0: SUCCESS (0.011s)
[288.595703] Starting dynamic subtest: bcs0
[288.598208] Dynamic subtest bcs0: SUCCESS (0.002s)
[288.607984] Starting dynamic subtest: vcs0
[288.610336] Dynamic subtest vcs0: SUCCESS (0.004s)
[288.619854] Starting dynamic subtest: vcs1
[288.622133] Dynamic subtest vcs1: SUCCESS (0.002s)
[288.631490] Starting dynamic subtest: vecs0
[288.633775] Dynamic subtest vecs0: SUCCESS (0.002s)
[288.643343] Starting dynamic subtest: ccs0
[288.645590] Dynamic subtest ccs0: SUCCESS (0.002s)
[288.647800] Subtest resubmit-all: SUCCESS (0.028s)
[290.350410] [054/156] (730s left) gem_create (create-massive)
[290.417186] Starting subtest: create-massive
[290.419276] Subtest create-massive: SUCCESS (0.000s)
[290.604187] [055/156] (730s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[290.669565] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[290.671874] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[290.787753] [056/156] (730s left) gem_exec_reloc (basic-gtt-wc-active)
[290.850557] Subtest basic-gtt-wc-active: SKIP (0.000s)
[290.935537] [057/156] (729s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[291.041164] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[291.045257] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[291.142907] [058/156] (729s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[291.250936] Starting subtest: psr2-cursor-plane-move-continuous-sf
[291.253283] Starting dynamic subtest: pipe-A-eDP-1
[301.625139] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.374s)
[301.636235] Starting dynamic subtest: pipe-B-eDP-1
[312.732763] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.103s)
[312.735092] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.479s)
[313.640763] [059/156] (707s left) kms_big_fb (4-tiled-addfb-size-overflow)
[313.750499] Starting subtest: 4-tiled-addfb-size-overflow
[313.752751] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[313.866656] [060/156] (707s left) gen3_render_tiledy_blits
[313.990467] [061/156] (707s left) kms_frontbuffer_tracking (fbc-tiling-4)
[314.086775] Starting subtest: fbc-tiling-4
[318.362742] Subtest fbc-tiling-4: SUCCESS (4.281s)
[319.522556] [062/156] (701s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[319.572960] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[319.638130] [063/156] (701s left) dumb_buffer (map-valid)
[319.696481] Starting subtest: map-valid
[319.698440] Subtest map-valid: SUCCESS (0.000s)
[319.781938] [064/156] (701s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[319.882364] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[319.886262] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[319.979358] [065/156] (701s left) kms_cdclk (mode-transition)
[320.071790] Starting subtest: mode-transition
[320.073973] Starting dynamic subtest: pipe-A-eDP-1
[320.368814] Dynamic subtest pipe-A-eDP-1: SKIP (0.295s)
[320.379268] Starting dynamic subtest: pipe-B-eDP-1
[320.384059] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[320.394670] Starting dynamic subtest: pipe-C-eDP-1
[320.399084] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[320.408347] Starting dynamic subtest: pipe-D-eDP-1
[320.412256] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[320.416139] Subtest mode-transition: SKIP (0.303s)
[321.241412] [066/156] (700s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-render)
[321.339268] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-render
[321.343706] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[321.421206] [067/156] (699s left) core_auth (getclient-master-drop)
[321.462578] Starting subtest: getclient-master-drop
[321.484203] Subtest getclient-master-drop: SUCCESS (0.022s)
[321.599116] [068/156] (699s left) kms_flip (flip-vs-modeset-vs-hang)
[321.689215] Starting subtest: flip-vs-modeset-vs-hang
[321.691577] Starting dynamic subtest: A-eDP1
[372.770483] Dynamic subtest A-eDP1: SUCCESS (51.078s)
[372.782154] Starting dynamic subtest: D-eDP1
[422.757275] Dynamic subtest D-eDP1: SUCCESS (49.981s)
[422.759640] Subtest flip-vs-modeset-vs-hang: SUCCESS (101.064s)
[423.147555] [069/156] (598s left) perf_pmu (busy-double-start)
[423.193915] Starting subtest: busy-double-start
[423.196310] Starting dynamic subtest: rcs0
[426.837831] Dynamic subtest rcs0: FAIL (3.589s)
[426.847842] Starting dynamic subtest: bcs0
[430.375949] Dynamic subtest bcs0: SUCCESS (3.591s)
[430.388497] Starting dynamic subtest: vcs0
[434.816748] Dynamic subtest vcs0: SUCCESS (4.439s)
[434.827847] Starting dynamic subtest: vcs1
[438.789944] Dynamic subtest vcs1: SUCCESS (3.971s)
[438.802229] Starting dynamic subtest: vecs0
[442.837650] Dynamic subtest vecs0: SUCCESS (4.046s)
[442.850582] Starting dynamic subtest: ccs0
[446.474083] Dynamic subtest ccs0: FAIL (3.565s)
[446.476383] Subtest busy-double-start: FAIL (23.210s)
[446.519044] [070/156] (574s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt)
[446.627176] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt
[446.631467] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[446.740292] [071/156] (574s left) kms_lease (lease-get)
[446.848068] Starting subtest: lease-get
[446.850253] Starting dynamic subtest: pipe-A-eDP-1
[446.852452] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[446.861990] Starting dynamic subtest: pipe-B-eDP-1
[446.863813] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[446.872764] Starting dynamic subtest: pipe-C-eDP-1
[446.874620] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[446.883841] Starting dynamic subtest: pipe-D-eDP-1
[446.885677] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[446.887551] Subtest lease-get: SUCCESS (0.010s)
[446.971847] [072/156] (574s left) perf (sysctl-defaults)
[447.038766] Starting subtest: sysctl-defaults
[447.041137] Subtest sysctl-defaults: SUCCESS (0.000s)
[447.114422] [073/156] (574s left) gem_ppgtt (shrink-vs-evict-pinned)
[447.177529] Starting subtest: shrink-vs-evict-pinned
[454.359271] Subtest shrink-vs-evict-pinned: SUCCESS (7.119s)
[454.403346] [074/156] (567s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[454.517337] Starting subtest: yf-tiled-8bpp-rotate-0
[454.522067] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[454.620779] [075/156] (567s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[454.720970] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[454.723334] Starting dynamic subtest: pipe-A-eDP-1
[454.746039] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[454.756892] Starting dynamic subtest: pipe-B-eDP-1
[454.778607] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[454.787912] Starting dynamic subtest: pipe-C-eDP-1
[454.808668] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[454.817865] Starting dynamic subtest: pipe-D-eDP-1
[454.839241] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[454.843643] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[457.004019] [076/156] (564s left) dmabuf_sync_file (export-before-signal)
[457.049966] Starting subtest: export-before-signal
[457.051914] Subtest export-before-signal: SUCCESS (0.001s)
[457.104100] [077/156] (564s left) kms_plane_lowres (tiling-none)
[457.192332] Starting subtest: tiling-none
[457.194684] Starting dynamic subtest: pipe-A-eDP-1
[457.199385] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[457.493765] Starting dynamic subtest: pipe-B-eDP-1
[457.498318] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[457.508047] Starting dynamic subtest: pipe-C-eDP-1
[457.512499] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[457.522125] Starting dynamic subtest: pipe-D-eDP-1
[457.526461] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[457.530495] Subtest tiling-none: SKIP (0.299s)
[458.344515] [078/156] (563s left) syncobj_timeline (single-wait-all-available-signaled)
[458.391378] Starting subtest: single-wait-all-available-signaled
[458.393400] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[458.487564] [079/156] (563s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[458.621806] Starting subtest: flip-vs-cursor-atomic
[466.243971] Subtest flip-vs-cursor-atomic: SUCCESS (7.622s)
[467.102601] [080/156] (554s left) kms_feature_discovery (display-4x)
[467.204190] Starting subtest: display-4x
[467.208666] Subtest display-4x: SKIP (0.000s)
[467.289837] [081/156] (554s left) perf_pmu (rc6)
[467.332144] Starting subtest: rc6
[467.334312] Starting dynamic subtest: gt0
[473.755754] Dynamic subtest gt0: SUCCESS (6.367s)
[473.767988] Starting dynamic subtest: runtime-pm-gt0
[481.470420] Dynamic subtest runtime-pm-gt0: SUCCESS (7.766s)
[481.483700] Starting dynamic subtest: runtime-pm-long-gt0
[493.933965] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.461s)
[493.946062] Starting dynamic subtest: other-idle-gt0
[500.281598] Dynamic subtest other-idle-gt0: SUCCESS (6.342s)
[500.292778] Starting dynamic subtest: gt1
[506.620651] Dynamic subtest gt1: SUCCESS (6.339s)
[506.632310] Starting dynamic subtest: runtime-pm-gt1
[514.063847] Dynamic subtest runtime-pm-gt1: SUCCESS (7.440s)
[514.077883] Starting dynamic subtest: runtime-pm-long-gt1
[526.573867] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.507s)
[526.587405] Starting dynamic subtest: other-idle-gt1
[532.919910] Dynamic subtest other-idle-gt1: SUCCESS (6.343s)
[532.923535] Subtest rc6: SUCCESS (65.585s)
[534.073169] [082/156] (487s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[534.170548] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[541.628256] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.464s)
[541.720642] [083/156] (480s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[541.818170] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[541.822120] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[541.886882] [084/156] (480s left) kms_chamelium_frames (dp-frame-dump)
[542.263326] Subtest dp-frame-dump: SKIP (0.000s)
[543.127201] [085/156] (478s left) core_setmaster_vs_auth
[543.273014] [086/156] (478s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[543.355280] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[543.357601] Starting dynamic subtest: pipe-A
[543.417639] Dynamic subtest pipe-A: SUCCESS (0.065s)
[543.431019] Starting dynamic subtest: pipe-B
[544.565351] Dynamic subtest pipe-B: SUCCESS (1.147s)
[544.575939] Starting dynamic subtest: pipe-C
[545.682306] Dynamic subtest pipe-C: SUCCESS (1.115s)
[545.692748] Starting dynamic subtest: pipe-D
[546.803231] Dynamic subtest pipe-D: SUCCESS (1.120s)
[546.805659] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.451s)
[547.949063] [087/156] (474s left) gem_ppgtt (flink-and-close-vma-leak)
[548.001940] Starting subtest: flink-and-close-vma-leak
[548.043074] Subtest flink-and-close-vma-leak: SUCCESS (0.041s)
[548.154219] [088/156] (474s left) gem_lmem_swapping (parallel-multi)
[548.194455] Subtest parallel-multi: SKIP (0.000s)
[548.236641] [089/156] (473s left) kms_big_fb (linear-8bpp-rotate-90)
[548.341174] Starting subtest: linear-8bpp-rotate-90
[548.361597] Subtest linear-8bpp-rotate-90: SKIP (0.020s)
[548.467381] [090/156] (473s left) prime_self_import (basic-with_fd_dup)
[548.507940] Starting subtest: basic-with_fd_dup
[548.528554] Subtest basic-with_fd_dup: SUCCESS (0.022s)
[548.608625] [091/156] (473s left) gem_mmap_wc (read-write)
[548.661654] Subtest read-write: SKIP (0.000s)
[548.723039] [092/156] (473s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[548.784065] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[548.786343] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[548.875513] [093/156] (473s left) gem_exec_schedule (u-fairslice)
[548.949675] Starting subtest: u-fairslice
[548.951358] Starting dynamic subtest: rcs0
[550.971191] Dynamic subtest rcs0: SUCCESS (2.025s)
[550.980972] Starting dynamic subtest: bcs0
[552.995279] Dynamic subtest bcs0: SUCCESS (2.023s)
[553.006219] Starting dynamic subtest: vcs0
[555.063926] Dynamic subtest vcs0: SUCCESS (2.018s)
[555.075642] Starting dynamic subtest: vcs1
[557.035302] Dynamic subtest vcs1: SUCCESS (2.018s)
[557.046608] Starting dynamic subtest: vecs0
[559.054489] Dynamic subtest vecs0: SUCCESS (2.020s)
[559.064124] Starting dynamic subtest: ccs0
[561.076084] Dynamic subtest ccs0: SUCCESS (2.021s)
[561.077952] Subtest u-fairslice: SUCCESS (12.131s)
[561.353462] [094/156] (461s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-msflip-blt)
[561.463431] Starting subtest: fbchdr-1p-primscrn-indfb-msflip-blt
[561.468161] Subtest fbchdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[561.539896] [095/156] (460s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-move)
[561.637767] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-move
[561.641662] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[561.713922] [096/156] (460s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-blt)
[561.816957] Starting subtest: fbchdr-rgb101010-draw-blt
[561.821613] Subtest fbchdr-rgb101010-draw-blt: SKIP (0.000s)
[561.908862] [097/156] (460s left) gem_exec_fence (nb-await)
[562.105833] Starting subtest: nb-await
[562.107846] Starting dynamic subtest: rcs0
[562.170344] Dynamic subtest rcs0: SUCCESS (0.067s)
[562.182815] Starting dynamic subtest: bcs0
[562.228320] Dynamic subtest bcs0: SUCCESS (0.056s)
[562.240939] Starting dynamic subtest: vcs0
[562.287876] Dynamic subtest vcs0: SUCCESS (0.058s)
[562.299159] Starting dynamic subtest: vcs1
[562.346294] Dynamic subtest vcs1: SUCCESS (0.056s)
[562.356530] Starting dynamic subtest: vecs0
[562.404961] Dynamic subtest vecs0: SUCCESS (0.056s)
[562.415338] Starting dynamic subtest: ccs0
[562.462711] Dynamic subtest ccs0: SUCCESS (0.056s)
[562.465153] Subtest nb-await: SUCCESS (0.361s)
[564.347691] [098/156] (458s left) kms_addfb_basic (unused-offsets)
[564.403035] Starting subtest: unused-offsets
[564.405155] Subtest unused-offsets: SUCCESS (0.000s)
[564.534429] [099/156] (458s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[564.633571] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[564.637625] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[564.717345] [100/156] (457s left) i915_selftest (perf)
[569.397034] Starting subtest: perf
[569.400366] Starting dynamic subtest: engine_cs
[573.812547] Dynamic subtest engine_cs: SUCCESS (4.414s)
[573.827970] Starting dynamic subtest: request
[579.148555] Dynamic subtest request: SUCCESS (5.333s)
[579.157105] Starting dynamic subtest: migrate
[582.062296] Dynamic subtest migrate: SUCCESS (2.912s)
[582.080215] Starting dynamic subtest: region
[587.977578] Dynamic subtest region: SUCCESS (5.912s)
[587.980233] Subtest perf: SUCCESS (18.581s)
[588.008757] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[588.010534] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[588.013886] [101/156] (434s left) gem_mmap_wc (write-gtt-read-wc)
[589.473342] Subtest write-gtt-read-wc: SKIP (0.000s)
[589.528811] [FACT gem_mmap_wc (write-gtt-read-wc)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[589.530078] [FACT gem_mmap_wc (write-gtt-read-wc)] new: kernel.kmod_is_loaded.i915: true
[589.534173] [102/156] (433s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[589.647348] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[597.026964] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.385s)
[597.142829] [103/156] (425s left) perf_pmu (render-node-busy-idle)
[597.209893] Starting subtest: render-node-busy-idle
[597.212296] Starting dynamic subtest: rcs0
[597.934868] Dynamic subtest rcs0: SUCCESS (0.724s)
[597.945784] Starting dynamic subtest: bcs0
[598.608918] Dynamic subtest bcs0: SUCCESS (0.672s)
[598.621103] Starting dynamic subtest: vcs0
[600.328667] Dynamic subtest vcs0: SUCCESS (1.715s)
[600.341085] Starting dynamic subtest: vcs1
[602.310765] Dynamic subtest vcs1: SUCCESS (1.979s)
[602.324365] Starting dynamic subtest: vecs0
[604.294783] Dynamic subtest vecs0: SUCCESS (1.981s)
[604.307246] Starting dynamic subtest: ccs0
[604.963027] Dynamic subtest ccs0: SUCCESS (0.667s)
[604.966069] Subtest render-node-busy-idle: SUCCESS (7.753s)
[605.020805] [104/156] (417s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[605.133376] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[606.179328] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.046s)
[606.291658] [105/156] (416s left) kms_busy (extended-pageflip-hang-oldfb)
[606.391700] Starting subtest: extended-pageflip-hang-oldfb
[606.393669] Starting dynamic subtest: pipe-A
[606.805963] Dynamic subtest pipe-A: SUCCESS (0.414s)
[606.816131] Starting dynamic subtest: pipe-D
[607.976018] Dynamic subtest pipe-D: SUCCESS (1.165s)
[607.978282] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.583s)
[608.800767] [106/156] (414s left) api_intel_bb (bb-with-allocator)
[608.848186] Starting subtest: bb-with-allocator
[608.853261] Subtest bb-with-allocator: SUCCESS (0.006s)
[608.960869] [107/156] (414s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[609.063739] Starting subtest: psr-2p-primscrn-spr-indfb-move
[609.067834] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[609.134342] [108/156] (413s left) i915_query (query-topology-matches-eu-total)
[609.189273] Starting subtest: query-topology-matches-eu-total
[609.191174] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[609.264903] [109/156] (413s left) syncobj_wait (wait-all-for-submit-complex)
[609.316532] Starting subtest: wait-all-for-submit-complex
[609.529845] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[609.582672] [110/156] (413s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[609.684297] Starting subtest: fb-8bpc-vs-panel-6bpc
[609.686636] Starting dynamic subtest: pipe-A-eDP-1
[611.082223] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.399s)
[611.084534] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.400s)
[611.870453] [111/156] (411s left) gem_ctx_persistence (legacy-engines-queued)
[611.933357] Starting subtest: legacy-engines-queued
[611.935283] Starting dynamic subtest: render
[612.001919] Dynamic subtest render: SUCCESS (0.070s)
[612.016328] Starting dynamic subtest: bsd1
[613.282165] Dynamic subtest bsd1: SUCCESS (1.277s)
[613.301292] Starting dynamic subtest: bsd2
[615.268468] Dynamic subtest bsd2: SUCCESS (1.982s)
[615.284724] Starting dynamic subtest: blt
[615.299708] Dynamic subtest blt: SUCCESS (0.028s)
[615.309050] Starting dynamic subtest: vebox
[617.254386] Dynamic subtest vebox: SUCCESS (1.952s)
[617.257218] Subtest legacy-engines-queued: SUCCESS (5.324s)
[617.362268] [112/156] (405s left) prime_vgem (coherency-blt)
[617.446124] Starting subtest: coherency-blt
[619.454527] Subtest coherency-blt: SUCCESS (2.008s)
[619.553662] [113/156] (403s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping)
[619.645883] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping
[619.650301] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[619.698431] [114/156] (403s left) kms_psr (psr2-primary-mmap-cpu)
[619.793333] Starting subtest: psr2-primary-mmap-cpu
[619.795680] Starting dynamic subtest: eDP-1
[621.585157] Dynamic subtest eDP-1: SUCCESS (1.792s)
[621.587496] Subtest psr2-primary-mmap-cpu: SUCCESS (1.793s)
[622.441309] [115/156] (400s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[622.548932] Starting subtest: y-tiled-32bpp-rotate-180
[622.553336] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[622.644086] [116/156] (400s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-blt)
[622.748321] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-blt
[622.752723] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[622.828988] [117/156] (400s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier)
[622.922208] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier
[622.926630] Subtest pixel-format-4-tiled-lnl-ccs-modifier: SKIP (0.000s)
[622.971700] [118/156] (400s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[623.070968] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[623.073168] Starting dynamic subtest: pipe-A-default-mode
[624.223320] Dynamic subtest pipe-A-default-mode: SKIP (1.151s)
[624.227694] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.152s)
[624.354317] [119/156] (399s left) gem_mmap_offset (perf)
[624.418456] Starting subtest: perf
[624.420452] Starting dynamic subtest: smem0
[624.783955] Dynamic subtest smem0: SUCCESS (0.364s)
[624.786442] Subtest perf: SUCCESS (0.365s)
[624.877052] [120/156] (398s left) kms_pm_rpm (basic-rte)
[624.914824] Starting subtest: basic-rte
[627.980034] Subtest basic-rte: SUCCESS (3.065s)
[629.482423] [121/156] (394s left) syncobj_timeline (invalid-transfer-non-existent-point)
[629.534305] Starting subtest: invalid-transfer-non-existent-point
[629.536301] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[629.614053] [122/156] (394s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[629.702096] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[629.706089] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[629.780718] [123/156] (393s left) kms_frontbuffer_tracking (fbc-suspend)
[629.882802] Starting subtest: fbc-suspend
[668.503829] Subtest fbc-suspend: SUCCESS (8.381s)
[669.669297] [124/156] (354s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format)
[669.774557] Starting subtest: plane-scaler-unity-scaling-with-pixel-format
[669.776910] Starting dynamic subtest: pipe-A
[670.905375] Dynamic subtest pipe-A: SUCCESS (1.129s)
[670.915784] Starting dynamic subtest: pipe-B
[673.178976] Dynamic subtest pipe-B: SUCCESS (2.265s)
[673.193195] Starting dynamic subtest: pipe-C
[674.385485] Dynamic subtest pipe-C: SUCCESS (1.201s)
[674.397994] Starting dynamic subtest: pipe-D
[675.633652] Dynamic subtest pipe-D: SUCCESS (1.245s)
[675.636532] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (5.843s)
[676.798736] [125/156] (346s left) i915_pm_freq_api (freq-reset-multiple)
[676.868720] Starting subtest: freq-reset-multiple
[676.871234] Starting dynamic subtest: gt0
[686.697244] Dynamic subtest gt0: SUCCESS (9.812s)
[686.708660] Starting dynamic subtest: gt1
[694.593157] Dynamic subtest gt1: SUCCESS (7.890s)
[694.596387] Subtest freq-reset-multiple: SUCCESS (17.705s)
[694.651223] [126/156] (329s left) gem_eio (in-flight-contexts-10ms)
[694.906773] Starting subtest: in-flight-contexts-10ms
[708.532720] Subtest in-flight-contexts-10ms: SUCCESS (13.631s)
[708.678054] [127/156] (315s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[708.783057] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[708.787410] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[708.829014] [128/156] (315s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[708.933960] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[708.938431] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[709.009844] [129/156] (314s left) i915_drm_fdinfo (busy-hang)
[709.052833] Starting subtest: busy-hang
[709.054973] Starting dynamic subtest: rcs0
[709.086930] Dynamic subtest rcs0: SKIP (0.032s)
[709.100164] Starting dynamic subtest: bcs0
[709.104460] Dynamic subtest bcs0: SKIP (0.001s)
[709.115454] Starting dynamic subtest: vcs0
[709.119323] Dynamic subtest vcs0: SKIP (0.000s)
[709.129426] Starting dynamic subtest: vcs1
[709.133393] Dynamic subtest vcs1: SKIP (0.000s)
[709.143698] Starting dynamic subtest: vecs0
[709.147784] Dynamic subtest vecs0: SKIP (0.000s)
[709.158070] Starting dynamic subtest: ccs0
[709.162224] Dynamic subtest ccs0: SKIP (0.000s)
[709.166347] Subtest busy-hang: SKIP (0.038s)
[709.204567] [130/156] (314s left) sysfs_heartbeat_interval (precise)
[709.277963] Starting subtest: precise
[709.279853] Starting dynamic subtest: vecs0
[716.491473] Dynamic subtest vecs0: SUCCESS (7.214s)
[716.503225] Starting dynamic subtest: ccs0
[717.635921] Dynamic subtest ccs0: SUCCESS (1.143s)
[717.649325] Starting dynamic subtest: vcs0
[725.656244] Dynamic subtest vcs0: SUCCESS (8.014s)
[725.670140] Starting dynamic subtest: bcs0
[727.031286] Dynamic subtest bcs0: SUCCESS (1.375s)
[727.043696] Starting dynamic subtest: rcs0
[728.159246] Dynamic subtest rcs0: SUCCESS (1.125s)
[728.171769] Starting dynamic subtest: vcs1
[735.505801] Dynamic subtest vcs1: SUCCESS (7.341s)
[735.508478] Subtest precise: SUCCESS (26.225s)
[735.561795] [131/156] (288s left) kms_atomic (plane-cursor-legacy)
[735.657248] Starting subtest: plane-cursor-legacy
[735.659930] Starting dynamic subtest: pipe-A-eDP-1
[736.037950] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.380s)
[736.040308] Subtest plane-cursor-legacy: SUCCESS (0.382s)
[736.851080] [132/156] (287s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[736.916216] Starting subtest: yf-tiled-ccs-to-y-tiled
[736.920473] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[737.086329] [133/156] (286s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[737.143992] Starting subtest: invalid-bsd2-flag-on-blt
[737.146459] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[737.228924] [134/156] (286s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[737.327486] Starting subtest: addfb25-y-tiled-small-legacy
[737.331872] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[737.410058] [135/156] (286s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[737.494398] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[737.498916] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[738.590496] [136/156] (285s left) sw_sync (alloc_merge_fence)
[738.630113] Starting subtest: alloc_merge_fence
[738.632557] Subtest alloc_merge_fence: SUCCESS (0.000s)
[738.677014] [137/156] (285s left) kms_invalid_mode (bad-vsync-end)
[738.772280] Starting subtest: bad-vsync-end
[738.774493] Starting dynamic subtest: pipe-A-eDP-1
[739.062065] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[739.074220] Starting dynamic subtest: pipe-B-eDP-1
[739.076055] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[739.086668] Starting dynamic subtest: pipe-C-eDP-1
[739.088852] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[739.099489] Starting dynamic subtest: pipe-D-eDP-1
[739.101791] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[739.103944] Subtest bad-vsync-end: SUCCESS (0.298s)
[739.924192] [138/156] (284s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[740.032814] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[740.037034] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[740.103525] [139/156] (284s left) kms_flip (flip-vs-absolute-wf_vblank)
[740.201419] Starting subtest: flip-vs-absolute-wf_vblank
[740.203300] Starting dynamic subtest: A-eDP1
[749.094804] Dynamic subtest A-eDP1: SUCCESS (8.892s)
[749.108219] Starting dynamic subtest: B-eDP1
[758.024426] Dynamic subtest B-eDP1: SUCCESS (8.926s)
[758.035683] Starting dynamic subtest: C-eDP1
[766.983970] Dynamic subtest C-eDP1: SUCCESS (8.910s)
[766.997517] Starting dynamic subtest: D-eDP1
[775.913502] Dynamic subtest D-eDP1: SUCCESS (8.930s)
[775.915492] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.671s)
[777.075549] [140/156] (247s left) syncobj_wait (multi-wait-signaled)
[777.139931] Starting subtest: multi-wait-signaled
[777.141766] Subtest multi-wait-signaled: SUCCESS (0.000s)
[777.229417] [141/156] (247s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[777.330125] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[777.334525] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[777.424569] [142/156] (247s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-gtt)
[777.535121] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-gtt
[777.539631] Subtest fbcpsrhdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[777.610627] [143/156] (246s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[777.701227] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[784.727052] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (7.030s)
[784.833980] [144/156] (239s left) kms_content_protection (dp-mst-type-0-suspend-resume)
[784.981910] Starting subtest: dp-mst-type-0-suspend-resume
[784.986665] Subtest dp-mst-type-0-suspend-resume: SKIP (0.000s)
[785.072018] [145/156] (239s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-fullscreen)
[785.162860] Starting subtest: hdr-2p-scndscrn-spr-indfb-fullscreen
[785.167008] Subtest hdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[785.250762] [146/156] (239s left) gem_exec_balancer (nohangcheck)
[785.520061] Starting subtest: nohangcheck
[786.460576] Subtest nohangcheck: SUCCESS (0.940s)
[786.565480] [147/156] (238s left) kms_cursor_crc (cursor-offscreen-max-size)
[786.913644] Starting subtest: cursor-offscreen-max-size
[786.920225] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[787.024277] [148/156] (237s left) kms_sequence (get-idle)
[787.117354] Starting subtest: get-idle
[787.119733] Starting dynamic subtest: pipe-A-eDP-1
[788.933419] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.815s)
[788.946872] Starting dynamic subtest: pipe-B-eDP-1
[791.381277] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.449s)
[791.395119] Starting dynamic subtest: pipe-C-eDP-1
[793.883973] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.503s)
[793.895773] Starting dynamic subtest: pipe-D-eDP-1
[796.322720] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.437s)
[796.325253] Subtest get-idle: SUCCESS (9.209s)
[797.119970] [149/156] (227s left) kms_vblank (ts-continuation-idle-hang)
[797.214102] Starting subtest: ts-continuation-idle-hang
[797.216592] Starting dynamic subtest: pipe-A-eDP-1
[823.253001] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.031s)
[823.273138] Starting dynamic subtest: pipe-D-eDP-1
[848.275593] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.020s)
[848.277970] Subtest ts-continuation-idle-hang: SUCCESS (51.055s)
[849.086267] [150/156] (175s left) gem_userptr_blits (vma-merge)
[849.159954] Starting subtest: vma-merge
[849.199791] Subtest vma-merge: SUCCESS (0.043s)
[849.290962] [151/156] (175s left) perf (non-system-wide-paranoid)
[849.387925] Starting subtest: non-system-wide-paranoid
[849.414282] Subtest non-system-wide-paranoid: SUCCESS (0.028s)
[849.468991] [152/156] (175s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[849.522859] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[849.527355] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[849.687931] [153/156] (175s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[849.779693] Starting subtest: fbc-2p-pri-indfb-multidraw
[849.784281] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[849.857999] [154/156] (175s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[849.945577] Starting subtest: fbc-psr2-primary-mmap-cpu
[849.947663] Starting dynamic subtest: eDP-1
[849.951840] Dynamic subtest eDP-1: SKIP (0.000s)
[849.956040] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[849.995532] [155/156] (175s left) kms_cursor_crc (cursor-random-32x32)
[850.146780] Starting subtest: cursor-random-32x32
[850.197087] Subtest cursor-random-32x32: SKIP (0.045s)
[850.487522] [156/156] (174s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-pgflip-blt)
[850.583296] Starting subtest: psrhdr-1p-primscrn-indfb-pgflip-blt
[850.587634] Subtest psrhdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[850.658354] Closing watchdogs
results: parsing output: 0/ for test: kms_rotation_crc
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: perf
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: gem_exec_whisper
results: parsing output: 6/ for test: kms_chamelium_hpd
results: parsing output: 7/ for test: syncobj_timeline
results: parsing output: 8/ for test: kms_color
results: parsing output: 9/ for test: kms_pm_rpm
results: parsing output: 10/ for test: gen9_exec_parse
results: parsing output: 11/ for test: kms_cursor_legacy
results: parsing output: 12/ for test: kms_getfb
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_fenced_exec_thrash
results: parsing output: 18/ for test: kms_flip
results: parsing output: 19/ for test: gem_exec_schedule
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: gem_exec_capture
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_cursor_legacy
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: kms_flip_scaled_crc
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_psr
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_ctx_shared
results: parsing output: 37/ for test: prime_vgem
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: gem_exec_flush
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_cursor_edge_walk
results: parsing output: 42/ for test: i915_pm_rpm
results: parsing output: 43/ for test: gem_ctx_isolation
results: parsing output: 44/ for test: gem_exec_schedule
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: gem_exec_params
results: parsing output: 47/ for test: perf
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_exec_reloc
results: parsing output: 52/ for test: gem_spin_batch
results: parsing output: 53/ for test: gem_create
results: parsing output: 54/ for test: syncobj_wait
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_psr2_sf
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: gen3_render_tiledy_blits
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_mmap_gtt
results: parsing output: 62/ for test: dumb_buffer
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_cdclk
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: core_auth
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: perf_pmu
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_lease
results: parsing output: 71/ for test: perf
results: parsing output: 72/ for test: gem_ppgtt
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: dmabuf_sync_file
results: parsing output: 76/ for test: kms_plane_lowres
results: parsing output: 77/ for test: syncobj_timeline
results: parsing output: 78/ for test: kms_cursor_legacy
results: parsing output: 79/ for test: kms_feature_discovery
results: parsing output: 80/ for test: perf_pmu
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_chamelium_frames
results: parsing output: 84/ for test: core_setmaster_vs_auth
results: parsing output: 85/ for test: kms_plane_scaling
results: parsing output: 86/ for test: gem_ppgtt
results: parsing output: 87/ for test: gem_lmem_swapping
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: prime_self_import
results: parsing output: 90/ for test: gem_mmap_wc
results: parsing output: 91/ for test: syncobj_timeline
results: parsing output: 92/ for test: gem_exec_schedule
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_exec_fence
results: parsing output: 97/ for test: kms_addfb_basic
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: i915_selftest
results: parsing output: 100/ for test: gem_mmap_wc
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: perf_pmu
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: kms_busy
results: parsing output: 105/ for test: api_intel_bb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: i915_query
results: parsing output: 108/ for test: syncobj_wait
results: parsing output: 109/ for test: kms_dither
results: parsing output: 110/ for test: gem_ctx_persistence
results: parsing output: 111/ for test: prime_vgem
results: parsing output: 112/ for test: kms_plane
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_plane
results: parsing output: 117/ for test: kms_flip_scaled_crc
results: parsing output: 118/ for test: gem_mmap_offset
results: parsing output: 119/ for test: kms_pm_rpm
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_plane_scaling
results: parsing output: 124/ for test: i915_pm_freq_api
results: parsing output: 125/ for test: gem_eio
results: parsing output: 126/ for test: kms_psr2_sf
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: i915_drm_fdinfo
results: parsing output: 129/ for test: sysfs_heartbeat_interval
results: parsing output: 130/ for test: kms_atomic
results: parsing output: 131/ for test: gem_render_copy
results: parsing output: 132/ for test: gem_exec_params
results: parsing output: 133/ for test: kms_addfb_basic
results: parsing output: 134/ for test: kms_ccs
results: parsing output: 135/ for test: sw_sync
results: parsing output: 136/ for test: kms_invalid_mode
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: kms_flip
results: parsing output: 139/ for test: syncobj_wait
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_content_protection
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: gem_exec_balancer
results: parsing output: 146/ for test: kms_cursor_crc
results: parsing output: 147/ for test: kms_sequence
results: parsing output: 148/ for test: kms_vblank
results: parsing output: 149/ for test: gem_userptr_blits
results: parsing output: 150/ for test: perf
results: parsing output: 151/ for test: gem_render_copy
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: kms_psr
results: parsing output: 154/ for test: kms_cursor_crc
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.