[86.658478] Initializing watchdogs
[86.658528]   /dev/watchdog0
[86.673545] [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]
[86.677969] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[86.680286] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[86.683690] [001/133] (960s left) syncobj_wait (multi-wait-submitted-signaled)
[86.769052] Starting subtest: multi-wait-submitted-signaled
[86.771423] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[86.891825] [002/133] (960s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[86.988297] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[86.990519] Starting dynamic subtest: pipe-A-eDP-1
[87.013932] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[87.022942] Starting dynamic subtest: pipe-B-eDP-1
[87.044575] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[87.051864] Starting dynamic subtest: pipe-C-eDP-1
[87.073401] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[87.081066] Starting dynamic subtest: pipe-D-eDP-1
[87.104565] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[87.108774] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.005s)
[89.274059] [003/133] (957s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[89.371128] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[89.373376] Starting dynamic subtest: pipe-A
[89.711288] Dynamic subtest pipe-A: SUCCESS (0.340s)
[89.720126] Starting dynamic subtest: pipe-B
[91.154107] Dynamic subtest pipe-B: SUCCESS (1.441s)
[91.162996] Starting dynamic subtest: pipe-C
[92.599398] Dynamic subtest pipe-C: SUCCESS (1.445s)
[92.608534] Starting dynamic subtest: pipe-D
[94.024230] Dynamic subtest pipe-D: SUCCESS (1.424s)
[94.026461] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.653s)
[95.213246] [004/133] (952s left) gem_ccs (block-copy-compressed)
[95.288544] Starting subtest: block-copy-compressed
[95.293234] Subtest block-copy-compressed: SKIP (0.000s)
[95.395839] [005/133] (951s left) i915_query (query-topology-coherent-slice-mask)
[95.468807] Starting subtest: query-topology-coherent-slice-mask
[95.473135] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[95.606331] [006/133] (951s left) syncobj_timeline (invalid-transfer-bad-pad)
[95.654978] Starting subtest: invalid-transfer-bad-pad
[95.657297] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[95.774056] [007/133] (951s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[95.864239] Starting subtest: primary-x-tiled-reflect-x-180
[102.852474] Subtest primary-x-tiled-reflect-x-180: SUCCESS (6.988s)
[104.057984] [008/133] (943s left) kms_vrr (max-min)
[104.150139] Starting subtest: max-min
[104.156853] Subtest max-min: SKIP (0.000s)
[104.232152] [009/133] (943s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[104.333857] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[110.968796] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.638s)
[111.105772] [010/133] (936s left) kms_psr (pr-cursor-mmap-gtt)
[111.197631] Starting subtest: pr-cursor-mmap-gtt
[111.201744] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[111.293299] [011/133] (936s left) gem_sync (basic-all)
[111.376233] Starting subtest: basic-all
[114.839742] Subtest basic-all: SUCCESS (3.471s)
[115.036566] [012/133] (932s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[115.096813] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[115.101405] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[115.289965] [013/133] (932s left) kms_atomic_interruptible (atomic-setmode)
[115.398871] Starting subtest: atomic-setmode
[115.401297] Starting dynamic subtest: pipe-A-eDP-1
[122.318765] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.918s)
[122.321099] Subtest atomic-setmode: SUCCESS (6.919s)
[123.200481] [014/133] (924s left) gem_pxp (reject-modify-context-protection-off-3)
[123.335705] Starting subtest: reject-modify-context-protection-off-3
[123.338162] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[123.466728] [015/133] (924s left) kms_pm_dc (dc5-dpms)
[124.578239] Starting subtest: dc5-dpms
[126.661104] Subtest dc5-dpms: SUCCESS (2.082s)
[126.738179] [016/133] (921s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[126.840362] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[126.842686] Starting dynamic subtest: pipe-A
[127.205768] Dynamic subtest pipe-A: SUCCESS (0.365s)
[127.213809] Starting dynamic subtest: pipe-B
[128.639306] Dynamic subtest pipe-B: SUCCESS (1.433s)
[128.646630] Starting dynamic subtest: pipe-C
[130.116206] Dynamic subtest pipe-C: SUCCESS (1.476s)
[130.125152] Starting dynamic subtest: pipe-D
[131.501204] Dynamic subtest pipe-D: SUCCESS (1.384s)
[131.503539] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.661s)
[132.688278] [017/133] (915s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[132.789917] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[132.794389] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[132.916679] [018/133] (914s left) gem_exec_reloc (basic-wc-read)
[132.988092] Subtest basic-wc-read: SKIP (0.000s)
[133.051111] [019/133] (914s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[133.146171] Starting subtest: fbc-rgb101010-draw-render
[137.801753] Subtest fbc-rgb101010-draw-render: SUCCESS (4.658s)
[138.977297] [020/133] (908s left) gem_exec_schedule (semaphore-user)
[139.063858] Starting subtest: semaphore-user
[139.115924] Subtest semaphore-user: SUCCESS (0.060s)
[140.935271] [021/133] (907s left) kms_pm_rpm (universal-planes-dpms)
[143.089426] Starting subtest: universal-planes-dpms
[143.092553] Starting dynamic subtest: plane-34
[151.420384] Dynamic subtest plane-34: SUCCESS (8.331s)
[151.430997] Starting dynamic subtest: plane-64
[159.822944] Dynamic subtest plane-64: SUCCESS (8.403s)
[159.833416] Starting dynamic subtest: plane-94
[168.098818] Dynamic subtest plane-94: SUCCESS (8.275s)
[168.109231] Starting dynamic subtest: plane-124
[176.496377] Dynamic subtest plane-124: SUCCESS (8.396s)
[176.507158] Starting dynamic subtest: plane-134
[184.783145] Dynamic subtest plane-134: SUCCESS (8.285s)
[184.793053] Starting dynamic subtest: plane-144
[193.065867] Dynamic subtest plane-144: SUCCESS (8.280s)
[193.068764] Subtest universal-planes-dpms: SUCCESS (49.980s)
[193.448552] [022/133] (854s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[193.554708] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[193.559034] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[193.690612] [023/133] (854s left) syncobj_wait (reset-multiple-signaled)
[193.754750] Starting subtest: reset-multiple-signaled
[193.756965] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[193.869930] [024/133] (854s left) gem_ctx_param (set-priority-range)
[193.925691] Starting subtest: set-priority-range
[193.941814] Subtest set-priority-range: SUCCESS (0.016s)
[194.076024] [025/133] (854s left) gem_exec_whisper (basic-queues-forked)
[194.156161] Starting subtest: basic-queues-forked
[217.109849] Subtest basic-queues-forked: SUCCESS (22.949s)
[217.302971] [026/133] (830s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[217.396723] Starting subtest: basic-max-pipe-crc-check
[217.401159] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[217.439902] [027/133] (830s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[217.537289] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[217.564367] Starting dynamic subtest: pipe-A-plane-0
[217.852973] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[217.861746] Starting dynamic subtest: pipe-A-plane-3
[218.086401] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[218.095777] Starting dynamic subtest: pipe-A-plane-5
[218.100073] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[219.217407] Starting dynamic subtest: pipe-B-plane-0
[219.510228] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[219.520526] Starting dynamic subtest: pipe-B-plane-3
[219.743168] Dynamic subtest pipe-B-plane-3: SUCCESS (0.232s)
[219.752233] Starting dynamic subtest: pipe-B-plane-5
[219.756672] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[220.053703] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.517s)
[220.892456] [028/133] (827s left) gem_wait (write-busy)
[220.973413] Starting subtest: write-busy
[220.975604] Starting dynamic subtest: all-engines
[221.615615] Dynamic subtest all-engines: SUCCESS (0.645s)
[221.633188] Starting dynamic subtest: rcs0
[222.168582] Dynamic subtest rcs0: SUCCESS (0.551s)
[222.183230] Starting dynamic subtest: bcs0
[222.715734] Dynamic subtest bcs0: SUCCESS (0.546s)
[222.730485] Starting dynamic subtest: vcs0
[223.894203] Dynamic subtest vcs0: SUCCESS (1.176s)
[223.909589] Starting dynamic subtest: vcs1
[225.877779] Dynamic subtest vcs1: SUCCESS (1.982s)
[225.892970] Starting dynamic subtest: vecs0
[227.926053] Dynamic subtest vecs0: SUCCESS (2.046s)
[227.940841] Starting dynamic subtest: ccs0
[228.476811] Dynamic subtest ccs0: SUCCESS (0.550s)
[228.479222] Subtest write-busy: SUCCESS (7.507s)
[228.678753] [FACT gem_wait (write-busy)] new: kernel.kmod_is_loaded.vgem: true
[228.682375] [029/133] (819s left) kms_addfb_basic (bad-pitch-65536)
[228.741894] Starting subtest: bad-pitch-65536
[228.744105] Subtest bad-pitch-65536: SUCCESS (0.000s)
[228.886198] [030/133] (819s left) kms_flip (plain-flip-ts-check)
[228.990856] Starting subtest: plain-flip-ts-check
[228.993070] Starting dynamic subtest: A-eDP1
[237.870078] Dynamic subtest A-eDP1: SUCCESS (8.880s)
[237.881815] Starting dynamic subtest: B-eDP1
[246.703055] Dynamic subtest B-eDP1: SUCCESS (8.831s)
[246.716922] Starting dynamic subtest: C-eDP1
[255.524763] Dynamic subtest C-eDP1: SUCCESS (8.820s)
[255.535310] Starting dynamic subtest: D-eDP1
[264.361585] Dynamic subtest D-eDP1: SUCCESS (8.835s)
[264.367094] Subtest plain-flip-ts-check: SUCCESS (35.377s)
[265.554344] [031/133] (782s left) gem_userptr_blits (unsync-unmap-cycles)
[265.632286] Subtest unsync-unmap-cycles: SKIP (0.000s)
[265.785771] [032/133] (782s left) gem_mmap_offset (basic-uaf)
[265.840180] Starting subtest: basic-uaf
[265.842408] Subtest basic-uaf: SUCCESS (0.001s)
[265.964299] [033/133] (782s left) core_getversion (all-cards)
[265.998456] Starting subtest: all-cards
[266.011540] Subtest all-cards: SUCCESS (0.017s)
[266.086104] [034/133] (782s left) gem_exec_whisper (basic-contexts)
[266.153118] Starting subtest: basic-contexts
[272.087924] Subtest basic-contexts: SUCCESS (5.930s)
[272.297246] [035/133] (776s left) kms_feature_discovery (display)
[272.399332] Starting subtest: display
[272.401719] Subtest display: SUCCESS (0.000s)
[272.484228] [036/133] (776s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[272.592897] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[279.547313] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (6.957s)
[279.715372] [037/133] (768s left) gem_mmap_wc (bad-offset)
[279.779674] Subtest bad-offset: SKIP (0.000s)
[279.856083] [038/133] (768s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[279.984462] Starting subtest: cursorB-vs-flipB-varying-size
[279.989051] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[280.138933] [039/133] (768s left) perf_pmu (module-unload)
[280.234231] Starting subtest: module-unload
[288.591693] Subtest module-unload: FAIL (8.252s)
[288.623098] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[288.623954] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[288.627047] [040/133] (760s left) i915_hangman (detector)
[290.208364] Starting subtest: detector
[290.210633] Starting dynamic subtest: rcs0
[301.886652] Dynamic subtest rcs0: SUCCESS (11.667s)
[301.906200] Starting dynamic subtest: bcs0
[313.845061] Dynamic subtest bcs0: SUCCESS (11.964s)
[313.855640] Starting dynamic subtest: vcs0
[324.854690] Dynamic subtest vcs0: SUCCESS (11.006s)
[324.866056] Starting dynamic subtest: vcs1
[336.821302] Dynamic subtest vcs1: SUCCESS (11.964s)
[336.833816] Starting dynamic subtest: vecs0
[348.855077] Dynamic subtest vecs0: SUCCESS (12.030s)
[348.865708] Starting dynamic subtest: ccs0
[360.885921] Dynamic subtest ccs0: SUCCESS (12.028s)
[360.888981] Subtest detector: SUCCESS (70.674s)
[360.940091] [FACT i915_hangman (detector)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[360.941316] [FACT i915_hangman (detector)] new: kernel.kmod_is_loaded.i915: true
[360.944843] [041/133] (687s left) syncobj_basic (create-signaled)
[361.014639] Starting subtest: create-signaled
[361.016894] Subtest create-signaled: SUCCESS (0.000s)
[361.142429] [042/133] (687s left) gem_exec_fence (submit3)
[361.326251] Starting subtest: submit3
[361.330626] Subtest submit3: SKIP (0.000s)
[363.031098] [043/133] (685s left) kms_chamelium_edid (hdmi-edid-read)
[363.411893] Subtest hdmi-edid-read: SKIP (0.000s)
[364.234732] [044/133] (684s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[364.344773] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[364.347119] Starting dynamic subtest: pipe-A-eDP-1
[364.370864] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[364.381304] Starting dynamic subtest: pipe-B-eDP-1
[364.403282] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[364.411497] Starting dynamic subtest: pipe-C-eDP-1
[364.434670] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[364.443051] Starting dynamic subtest: pipe-D-eDP-1
[364.464636] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[364.468985] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.013s)
[366.632133] [045/133] (682s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[366.745574] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[373.348076] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.607s)
[373.509835] [046/133] (675s left) syncobj_timeline (etime-single-wait-all-submitted)
[373.573161] Starting subtest: etime-single-wait-all-submitted
[373.675286] Subtest etime-single-wait-all-submitted: SUCCESS (0.101s)
[373.760305] [047/133] (675s left) gem_exec_capture (pi)
[373.824493] Starting subtest: pi
[373.826697] Starting dynamic subtest: rcs0
[378.317980] Dynamic subtest rcs0: SUCCESS (4.495s)
[378.329561] Starting dynamic subtest: bcs0
[382.506105] Dynamic subtest bcs0: SUCCESS (4.186s)
[382.516743] Starting dynamic subtest: vcs0
[386.638044] Dynamic subtest vcs0: SUCCESS (4.130s)
[386.649860] Starting dynamic subtest: vcs1
[390.774818] Dynamic subtest vcs1: SUCCESS (4.134s)
[390.786465] Starting dynamic subtest: vecs0
[394.911767] Dynamic subtest vecs0: SUCCESS (4.134s)
[394.921555] Starting dynamic subtest: ccs0
[399.080597] Dynamic subtest ccs0: SUCCESS (4.166s)
[399.083217] Subtest pi: SUCCESS (25.258s)
[399.171930] [048/133] (649s left) kms_flip (nonblocking-read)
[399.332134] Starting subtest: nonblocking-read
[399.334030] Subtest nonblocking-read: SUCCESS (0.000s)
[399.468637] [049/133] (649s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[399.571443] Starting subtest: yf-tiled-16bpp-rotate-90
[399.575534] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[399.703439] [050/133] (649s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[399.815989] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[399.820479] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[399.957222] [051/133] (649s left) gem_softpin (full)
[400.028914] Starting subtest: full
[400.037815] Subtest full: SUCCESS (0.009s)
[400.196047] [052/133] (649s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[400.588897] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[401.362743] [053/133] (647s left) perf (rc6-disable)
[401.502729] Starting subtest: rc6-disable
[401.680044] Subtest rc6-disable: SUCCESS (0.178s)
[401.730280] [054/133] (647s left) gem_partial_pwrite_pread (reads-snoop)
[401.791511] Subtest reads-snoop: SKIP (0.000s)
[401.875376] [055/133] (647s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[402.146578] Starting subtest: cursor-rapid-movement-512x512
[402.151114] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[402.342877] [056/133] (647s left) gem_mmap_gtt (cpuset-big-copy)
[402.405228] Subtest cpuset-big-copy: SKIP (0.000s)
[402.479981] [057/133] (647s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[402.600248] Starting subtest: modeset-atomic-cursor-hotspot
[402.603962] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[402.753443] [058/133] (646s left) kms_psr (psr-sprite-mmap-cpu)
[402.847282] Starting subtest: psr-sprite-mmap-cpu
[402.849132] Starting dynamic subtest: eDP-1
[404.596892] Dynamic subtest eDP-1: SUCCESS (1.746s)
[404.599298] Subtest psr-sprite-mmap-cpu: SUCCESS (1.747s)
[405.473219] [059/133] (644s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[405.582832] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[405.586791] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[405.717915] [060/133] (643s left) gem_exec_reloc (basic-wc-cpu-active)
[405.788760] Subtest basic-wc-cpu-active: SKIP (0.000s)
[405.872601] [061/133] (643s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[405.975807] Starting subtest: basic-modeset-with-all-modifiers-formats
[406.349975] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[407.123420] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.773s)
[407.132243] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[407.145298] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[407.154020] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[407.167448] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[407.176144] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[407.322896] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.155s)
[407.332773] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[407.345315] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[407.354378] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[407.367682] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[407.376444] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[407.389792] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.021s)
[407.398480] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[407.412026] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.021s)
[407.420467] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[407.434235] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[407.443423] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[407.456413] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.021s)
[407.465956] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[407.478669] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s)
[407.487438] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[407.500623] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[407.509526] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[407.645592] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.144s)
[407.654758] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[407.667660] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.021s)
[407.678295] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[407.690020] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[407.699059] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[407.711842] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[407.720667] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[407.734256] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.021s)
[407.742881] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[407.756446] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.021s)
[407.765149] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[407.778704] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.021s)
[407.789282] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[407.801163] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.021s)
[407.810327] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[407.823761] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[407.838323] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[407.979713] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.155s)
[407.991762] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[408.001220] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.021s)
[408.009773] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[408.023570] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.021s)
[408.033416] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[408.045481] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.021s)
[408.055355] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[408.067532] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[408.076043] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[408.089858] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[408.098102] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[408.112049] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.021s)
[408.120508] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[408.134259] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.021s)
[408.142651] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[408.156543] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.021s)
[408.536853] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.561s)
[409.315499] [062/133] (640s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[409.413234] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[409.417803] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[410.541962] [063/133] (639s left) kms_flip (flip-vs-dpms-on-nop)
[410.678160] Starting subtest: flip-vs-dpms-on-nop
[410.682892] Starting dynamic subtest: A-eDP1
[412.619304] Dynamic subtest A-eDP1: SUCCESS (1.938s)
[412.631054] Starting dynamic subtest: B-eDP1
[414.447198] Dynamic subtest B-eDP1: SUCCESS (1.826s)
[414.458214] Starting dynamic subtest: C-eDP1
[416.267938] Dynamic subtest C-eDP1: SUCCESS (1.819s)
[416.278766] Starting dynamic subtest: D-eDP1
[418.083944] Dynamic subtest D-eDP1: SUCCESS (1.815s)
[418.086383] Subtest flip-vs-dpms-on-nop: SUCCESS (7.410s)
[419.280442] [064/133] (630s left) vgem_basic (unload)
[419.315374] Starting subtest: unload
[448.551343] Subtest unload: SUCCESS (29.233s)
[448.660772] [065/133] (601s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[448.750697] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[448.752954] Starting dynamic subtest: pipe-A-default-mode
[449.925814] Dynamic subtest pipe-A-default-mode: SKIP (1.174s)
[449.930406] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.175s)
[450.118353] [066/133] (599s left) syncobj_wait (multi-wait-signaled)
[450.179757] Starting subtest: multi-wait-signaled
[450.182163] Subtest multi-wait-signaled: SUCCESS (0.000s)
[450.291902] [067/133] (599s left) kms_properties (get_properties-sanity-atomic)
[450.390101] Starting subtest: get_properties-sanity-atomic
[450.392431] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[450.490966] [068/133] (599s left) sysfs_heartbeat_interval (nopreempt)
[450.549058] Starting subtest: nopreempt
[450.551333] Starting dynamic subtest: vecs0
[459.954482] Dynamic subtest vecs0: SUCCESS (9.403s)
[459.966276] Starting dynamic subtest: ccs0
[464.017834] Dynamic subtest ccs0: SUCCESS (4.060s)
[464.029484] Starting dynamic subtest: vcs0
[473.969567] Dynamic subtest vcs0: SUCCESS (9.948s)
[473.983083] Starting dynamic subtest: bcs0
[477.957097] Dynamic subtest bcs0: SUCCESS (3.987s)
[477.968850] Starting dynamic subtest: rcs0
[481.993276] Dynamic subtest rcs0: SUCCESS (4.033s)
[482.005356] Starting dynamic subtest: vcs1
[491.952234] Dynamic subtest vcs1: SUCCESS (9.957s)
[491.954629] Subtest nopreempt: SUCCESS (41.402s)
[492.045241] [069/133] (557s left) kms_async_flips (test-time-stamp-atomic)
[492.152838] Starting subtest: test-time-stamp-atomic
[492.528913] Starting dynamic subtest: pipe-A-eDP-1
[493.291339] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.760s)
[493.671503] Starting dynamic subtest: pipe-B-eDP-1
[494.429705] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.762s)
[494.792983] Starting dynamic subtest: pipe-C-eDP-1
[495.557594] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.769s)
[495.922795] Starting dynamic subtest: pipe-D-eDP-1
[496.686037] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.768s)
[496.688457] Subtest test-time-stamp-atomic: SUCCESS (4.532s)
[497.856435] [070/133] (552s left) kms_busy (extended-pageflip-hang-newfb)
[497.956149] Starting subtest: extended-pageflip-hang-newfb
[497.958276] Starting dynamic subtest: pipe-A
[524.713116] Dynamic subtest pipe-A: SUCCESS (26.755s)
[524.725412] Starting dynamic subtest: pipe-D
[539.705067] Dynamic subtest pipe-D: SUCCESS (14.987s)
[539.707543] Subtest extended-pageflip-hang-newfb: SUCCESS (41.748s)
[540.514134] [071/133] (509s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[540.618544] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[540.622929] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[540.743935] [072/133] (509s left) i915_pm_sseu (full-enable)
[540.814068] Subtest full-enable: SKIP (0.000s)
[540.919619] [073/133] (509s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[541.013605] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[541.017701] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[541.145150] [074/133] (509s left) kms_vblank (query-busy)
[541.244175] Starting subtest: query-busy
[541.246028] Starting dynamic subtest: pipe-A-eDP-1
[543.126295] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.881s)
[543.140542] Starting dynamic subtest: pipe-D-eDP-1
[545.670775] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.541s)
[545.673026] Subtest query-busy: SUCCESS (4.426s)
[546.482863] [075/133] (503s left) kms_psr (fbc-psr2-primary-blt)
[546.583154] Starting subtest: fbc-psr2-primary-blt
[546.585299] Starting dynamic subtest: eDP-1
[546.589236] Dynamic subtest eDP-1: SKIP (0.000s)
[546.593258] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[546.667963] [076/133] (503s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[546.774873] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[546.778765] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[546.877579] [077/133] (503s left) gem_ctx_param (vm)
[546.935084] Starting subtest: vm
[546.981191] Subtest vm: SUCCESS (0.046s)
[547.146333] [078/133] (503s left) kms_big_fb (linear-64bpp-rotate-0)
[547.253708] Starting subtest: linear-64bpp-rotate-0
[548.965300] Subtest linear-64bpp-rotate-0: SUCCESS (1.711s)
[549.168986] [079/133] (501s left) gem_exec_parallel (engines)
[549.236393] Starting subtest: engines
[549.238726] Starting dynamic subtest: basic
[550.889202] Dynamic subtest basic: SUCCESS (1.651s)
[550.904774] Starting dynamic subtest: contexts
[555.308247] Dynamic subtest contexts: SUCCESS (4.415s)
[555.320633] Starting dynamic subtest: fds
[559.189978] Dynamic subtest fds: SUCCESS (3.879s)
[559.201499] Starting dynamic subtest: userptr
[560.701035] Dynamic subtest userptr: SUCCESS (1.510s)
[560.703719] Subtest engines: SUCCESS (11.465s)
[560.907227] [080/133] (489s left) perf (invalid-create-userspace-config)
[560.999015] Starting subtest: invalid-create-userspace-config
[561.001412] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[561.047120] [081/133] (489s left) gem_exec_balancer (individual)
[561.114835] Starting subtest: individual
[562.923867] Subtest individual: SUCCESS (1.808s)
[563.273330] [082/133] (487s left) api_intel_allocator (execbuf-with-allocator)
[563.336073] Starting subtest: execbuf-with-allocator
[563.342164] Subtest execbuf-with-allocator: SUCCESS (0.009s)
[563.499172] [083/133] (487s left) kms_cursor_edge_walk (64x64-top-edge)
[563.604955] Starting subtest: 64x64-top-edge
[563.607150] Starting dynamic subtest: pipe-A-eDP-1
[567.046186] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.441s)
[567.061893] Starting dynamic subtest: pipe-D-eDP-1
[570.165814] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.118s)
[570.168236] Subtest 64x64-top-edge: SUCCESS (6.562s)
[571.001017] [084/133] (479s left) kms_psr (fbc-psr-cursor-plane-onoff)
[571.093146] Starting subtest: fbc-psr-cursor-plane-onoff
[571.095304] Starting dynamic subtest: eDP-1
[571.099206] Dynamic subtest eDP-1: SKIP (0.000s)
[571.103209] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[571.178866] [085/133] (479s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[571.284670] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[571.288759] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[571.418734] [086/133] (479s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[571.477350] Starting subtest: y-tiled-ccs-to-y-tiled
[571.481861] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[571.675721] [087/133] (479s left) gem_mmap_wc (close)
[571.737856] Subtest close: SKIP (0.000s)
[571.801991] [088/133] (478s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[571.902121] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[571.928957] Starting dynamic subtest: pipe-A-plane-0
[571.933610] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[571.943619] Starting dynamic subtest: pipe-A-plane-3
[571.948170] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[571.959698] Starting dynamic subtest: pipe-A-plane-5
[571.964300] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[573.066246] Starting dynamic subtest: pipe-B-plane-0
[573.070811] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[573.080245] Starting dynamic subtest: pipe-B-plane-3
[573.084956] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[573.094082] Starting dynamic subtest: pipe-B-plane-5
[573.098717] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[573.393388] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (1.488s)
[574.258183] [089/133] (476s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[574.362233] Starting subtest: x-tiled-16bpp-rotate-270
[574.381595] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[574.516648] [090/133] (476s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[574.617508] Starting subtest: yf-tiled-addfb-size-offset-overflow
[574.621962] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[574.739351] [091/133] (476s left) sw_sync (sync_expired_merge)
[574.780009] Starting subtest: sync_expired_merge
[575.853843] Subtest sync_expired_merge: SUCCESS (1.074s)
[575.926399] [092/133] (474s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[576.040078] Starting subtest: fbc-pr-overlay-plane-update-sf-dmg-area
[576.044339] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[576.123064] [093/133] (474s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[576.223247] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[583.249449] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.029s)
[583.394371] [094/133] (467s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[583.492272] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[583.494502] Starting dynamic subtest: pipe-A-eDP-1
[589.412193] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.922s)
[589.422538] Starting dynamic subtest: pipe-B-eDP-1
[596.053947] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.639s)
[596.056316] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.565s)
[596.935582] [095/133] (454s left) gem_create (create-ext-cpu-access-big)
[597.116220] Starting subtest: create-ext-cpu-access-big
[597.121271] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[597.217859] [096/133] (453s left) gem_ctx_persistence (saturated-hostile)
[597.283432] Starting subtest: saturated-hostile
[597.285737] Starting dynamic subtest: rcs0
[598.139208] Dynamic subtest rcs0: SUCCESS (0.852s)
[598.150049] Starting dynamic subtest: bcs0
[598.949702] Dynamic subtest bcs0: SUCCESS (0.812s)
[598.961614] Starting dynamic subtest: vcs0
[599.757358] Dynamic subtest vcs0: SUCCESS (0.806s)
[599.769229] Starting dynamic subtest: vcs1
[600.583849] Dynamic subtest vcs1: SUCCESS (0.824s)
[600.600328] Starting dynamic subtest: vecs0
[601.409902] Dynamic subtest vecs0: SUCCESS (0.824s)
[601.423840] Starting dynamic subtest: ccs0
[602.246102] Dynamic subtest ccs0: SUCCESS (0.834s)
[602.249138] Subtest saturated-hostile: SUCCESS (4.964s)
[602.339572] [097/133] (448s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[602.391769] Starting subtest: wait-all-for-submit-delayed-submit
[602.495619] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[602.586628] [098/133] (448s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[602.693986] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[602.698419] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[602.805878] [099/133] (448s left) kms_content_protection (atomic-hdcp14)
[602.950641] Starting subtest: atomic-hdcp14
[602.955245] Subtest atomic-hdcp14: SKIP (0.000s)
[603.084582] [100/133] (448s left) gem_tiled_blits (normal)
[603.149786] Subtest normal: SKIP (0.000s)
[603.220288] [101/133] (448s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[603.329260] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[603.333108] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[603.471475] [102/133] (447s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[603.584542] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[603.587853] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[603.702474] [103/133] (447s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[603.803245] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[603.805089] Starting dynamic subtest: pipe-A-eDP-1
[605.077373] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.276s)
[605.087109] Starting dynamic subtest: pipe-B-eDP-1
[607.395488] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.316s)
[607.405684] Starting dynamic subtest: pipe-C-eDP-1
[609.723363] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.326s)
[609.733725] Starting dynamic subtest: pipe-D-eDP-1
[612.040253] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.315s)
[612.042579] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (8.240s)
[613.262172] [104/133] (438s left) gem_exec_schedule (implicit-write-read)
[613.341502] Starting subtest: implicit-write-read
[613.343886] Starting dynamic subtest: rcs0
[613.432859] Dynamic subtest rcs0: SUCCESS (0.103s)
[613.445133] Starting dynamic subtest: vcs0
[613.518825] Dynamic subtest vcs0: SUCCESS (0.085s)
[613.531586] Starting dynamic subtest: vcs1
[613.595390] Dynamic subtest vcs1: SUCCESS (0.076s)
[613.607386] Starting dynamic subtest: vecs0
[613.678282] Dynamic subtest vecs0: SUCCESS (0.082s)
[613.690172] Starting dynamic subtest: ccs0
[613.783482] Dynamic subtest ccs0: SUCCESS (0.103s)
[613.786081] Subtest implicit-write-read: SUCCESS (0.454s)
[615.013788] [105/133] (436s left) core_auth (getclient-simple)
[615.060636] Starting subtest: getclient-simple
[615.081407] Subtest getclient-simple: SUCCESS (0.020s)
[615.208506] [106/133] (436s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[615.306961] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[615.975856] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.670s)
[616.195269] [107/133] (435s left) i915_drm_fdinfo (basics)
[616.238986] Starting subtest: basics
[616.241313] Subtest basics: SUCCESS (0.000s)
[616.320018] [108/133] (435s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[616.427509] Starting subtest: fbcpsr-rgb101010-draw-blt
[623.246125] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (6.821s)
[623.416899] [109/133] (428s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[623.544399] Starting subtest: short-flip-before-cursor-toggle
[624.458495] Subtest short-flip-before-cursor-toggle: SUCCESS (0.910s)
[625.329575] [110/133] (426s left) kms_color (invalid-degamma-lut-sizes)
[625.424023] Starting subtest: invalid-degamma-lut-sizes
[625.426315] Starting dynamic subtest: pipe-A
[625.709469] Dynamic subtest pipe-A: SUCCESS (0.285s)
[625.719413] Starting dynamic subtest: pipe-B
[625.721769] Dynamic subtest pipe-B: SUCCESS (0.000s)
[625.731247] Starting dynamic subtest: pipe-C
[625.733540] Dynamic subtest pipe-C: SUCCESS (0.000s)
[625.743884] Starting dynamic subtest: pipe-D
[625.746244] Dynamic subtest pipe-D: SUCCESS (0.000s)
[625.748515] Subtest invalid-degamma-lut-sizes: SUCCESS (0.289s)
[626.555630] [111/133] (425s left) kms_invalid_mode (bad-vsync-start)
[626.650385] Starting subtest: bad-vsync-start
[626.652803] Starting dynamic subtest: pipe-A-eDP-1
[626.939621] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[626.949935] Starting dynamic subtest: pipe-B-eDP-1
[626.951913] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[626.960565] Starting dynamic subtest: pipe-C-eDP-1
[626.962535] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[626.971675] Starting dynamic subtest: pipe-D-eDP-1
[626.974280] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[626.976911] Subtest bad-vsync-start: SUCCESS (0.295s)
[627.783171] [112/133] (423s left) kms_vblank (wait-forked)
[627.876977] Starting subtest: wait-forked
[627.879268] Starting dynamic subtest: pipe-A-eDP-1
[629.691727] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.814s)
[629.705558] Starting dynamic subtest: pipe-D-eDP-1
[632.167332] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.472s)
[632.169737] Subtest wait-forked: SUCCESS (4.292s)
[633.037656] [113/133] (418s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[633.152440] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[633.156801] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[633.286978] [114/133] (418s left) kms_fence_pin_leak
[633.491371] [115/133] (418s left) gem_userptr_blits (usage-restrictions)
[633.563745] Starting subtest: usage-restrictions
[633.565946] Subtest usage-restrictions: SUCCESS (0.000s)
[633.722060] [116/133] (418s left) kms_lease (lease-unleased-crtc)
[633.817493] Starting subtest: lease-unleased-crtc
[633.819762] Starting dynamic subtest: pipe-A-eDP-1
[634.107773] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.290s)
[634.117462] Starting dynamic subtest: pipe-B-eDP-1
[634.887425] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.778s)
[634.898192] Starting dynamic subtest: pipe-C-eDP-1
[634.900308] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[634.908839] Starting dynamic subtest: pipe-D-eDP-1
[634.910751] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[634.912621] Subtest lease-unleased-crtc: SUCCESS (1.084s)
[635.283456] [117/133] (416s left) gem_caching (writes)
[635.352761] Subtest writes: SKIP (0.000s)
[635.424998] [118/133] (416s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[635.526616] Starting subtest: pr-overlay-plane-update-continuous-sf
[635.530771] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[635.642496] [119/133] (416s left) kms_flip (2x-plain-flip-fb-recreate)
[635.749056] Starting subtest: 2x-plain-flip-fb-recreate
[635.760978] Subtest 2x-plain-flip-fb-recreate: SKIP (0.011s)
[635.903822] [120/133] (416s left) gem_tiled_partial_pwrite_pread (reads)
[635.976749] Subtest reads: SKIP (0.000s)
[636.093990] [121/133] (416s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[636.207380] Starting subtest: psr-indfb-scaledprimary
[641.972343] Subtest psr-indfb-scaledprimary: SUCCESS (5.766s)
[642.119718] [122/133] (410s left) syncobj_timeline (multi-wait-available-signaled)
[642.174243] Starting subtest: multi-wait-available-signaled
[642.176770] Subtest multi-wait-available-signaled: SUCCESS (0.000s)
[642.310153] [123/133] (409s left) gem_exec_reloc (basic-cpu-gtt)
[642.379690] Subtest basic-cpu-gtt: SKIP (0.000s)
[642.476346] [124/133] (409s left) gem_shrink (reclaim)
[642.533014] Starting subtest: reclaim
[644.909135] Subtest reclaim: SUCCESS (2.366s)
[645.100530] [125/133] (407s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[645.211434] Starting subtest: fbc-rgb101010-draw-blt
[649.822694] Subtest fbc-rgb101010-draw-blt: SUCCESS (4.614s)
[651.017433] [126/133] (401s left) gem_ctx_sseu (engines)
[651.079405] Subtest engines: SKIP (0.000s)
[651.163216] [127/133] (401s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[651.216674] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[651.323535] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.106s)
[651.412213] [128/133] (401s left) gen9_exec_parse (bb-start-far)
[651.477402] Subtest bb-start-far: SKIP (0.000s)
[651.553079] [129/133] (400s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[651.641121] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[651.643062] Starting dynamic subtest: pipe-A-eDP-1
[653.753885] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.114s)
[653.763098] Starting dynamic subtest: pipe-B-eDP-1
[655.952114] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.197s)
[655.962163] Starting dynamic subtest: pipe-C-eDP-1
[658.105683] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.152s)
[658.115262] Starting dynamic subtest: pipe-D-eDP-1
[660.298123] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.191s)
[660.299921] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.660s)
[661.531254] [130/133] (391s left) prime_vgem (sync)
[661.626679] Starting subtest: sync
[661.628820] Starting dynamic subtest: rcs0
[661.693377] Dynamic subtest rcs0: SUCCESS (0.066s)
[661.705093] Starting dynamic subtest: bcs0
[661.784978] Dynamic subtest bcs0: SUCCESS (0.092s)
[661.794121] Starting dynamic subtest: vcs0
[661.833096] Dynamic subtest vcs0: SUCCESS (0.047s)
[661.842402] Starting dynamic subtest: vcs1
[661.877269] Dynamic subtest vcs1: SUCCESS (0.043s)
[661.887190] Starting dynamic subtest: vecs0
[661.923187] Dynamic subtest vecs0: SUCCESS (0.045s)
[661.932650] Starting dynamic subtest: ccs0
[661.968800] Dynamic subtest ccs0: SUCCESS (0.045s)
[661.970939] Subtest sync: SUCCESS (0.344s)
[662.117104] [131/133] (390s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[664.049045] Starting subtest: gem-execbuf-stress-pc8
[664.092045] Subtest gem-execbuf-stress-pc8: SKIP (0.041s)
[664.461185] [132/133] (388s left) kms_cursor_crc (cursor-onscreen-128x128)
[664.692604] Starting subtest: cursor-onscreen-128x128
[664.697431] Starting dynamic subtest: pipe-A-eDP-1
[666.825069] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.129s)
[666.856343] Starting dynamic subtest: pipe-D-eDP-1
[669.598227] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.748s)
[669.600523] Subtest cursor-onscreen-128x128: SUCCESS (4.903s)
[670.407957] [133/133] (382s left) kms_psr (psr2-sprite-blt)
[670.501236] Starting subtest: psr2-sprite-blt
[670.503505] Starting dynamic subtest: eDP-1
[672.202635] Dynamic subtest eDP-1: SUCCESS (1.699s)
[672.205070] Subtest psr2-sprite-blt: SUCCESS (1.701s)
[673.070386] Closing watchdogs
results: parsing output: 0/ for test: syncobj_wait
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: kms_plane_scaling
results: parsing output: 3/ for test: gem_ccs
results: parsing output: 4/ for test: i915_query
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: kms_rotation_crc
results: parsing output: 7/ for test: kms_vrr
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: gem_sync
results: parsing output: 11/ for test: gem_render_copy
results: parsing output: 12/ for test: kms_atomic_interruptible
results: parsing output: 13/ for test: gem_pxp
results: parsing output: 14/ for test: kms_pm_dc
results: parsing output: 15/ for test: kms_plane_scaling
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_exec_reloc
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_exec_schedule
results: parsing output: 20/ for test: kms_pm_rpm
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: syncobj_wait
results: parsing output: 23/ for test: gem_ctx_param
results: parsing output: 24/ for test: gem_exec_whisper
results: parsing output: 25/ for test: kms_multipipe_modeset
results: parsing output: 26/ for test: kms_plane
results: parsing output: 27/ for test: gem_wait
results: parsing output: 28/ for test: kms_addfb_basic
results: parsing output: 29/ for test: kms_flip
results: parsing output: 30/ for test: gem_userptr_blits
results: parsing output: 31/ for test: gem_mmap_offset
results: parsing output: 32/ for test: core_getversion
results: parsing output: 33/ for test: gem_exec_whisper
results: parsing output: 34/ for test: kms_feature_discovery
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_mmap_wc
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: perf_pmu
results: parsing output: 39/ for test: i915_hangman
results: parsing output: 40/ for test: syncobj_basic
results: parsing output: 41/ for test: gem_exec_fence
results: parsing output: 42/ for test: kms_chamelium_edid
results: parsing output: 43/ for test: kms_ccs
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: gem_exec_capture
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_softpin
results: parsing output: 51/ for test: kms_chamelium_hpd
results: parsing output: 52/ for test: perf
results: parsing output: 53/ for test: gem_partial_pwrite_pread
results: parsing output: 54/ for test: kms_cursor_crc
results: parsing output: 55/ for test: gem_mmap_gtt
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: kms_psr
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_exec_reloc
results: parsing output: 60/ for test: kms_async_flips
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: kms_flip
results: parsing output: 63/ for test: vgem_basic
results: parsing output: 64/ for test: kms_flip_scaled_crc
results: parsing output: 65/ for test: syncobj_wait
results: parsing output: 66/ for test: kms_properties
results: parsing output: 67/ for test: sysfs_heartbeat_interval
results: parsing output: 68/ for test: kms_async_flips
results: parsing output: 69/ for test: kms_busy
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: i915_pm_sseu
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_vblank
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_ctx_param
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: gem_exec_parallel
results: parsing output: 79/ for test: perf
results: parsing output: 80/ for test: gem_exec_balancer
results: parsing output: 81/ for test: api_intel_allocator
results: parsing output: 82/ for test: kms_cursor_edge_walk
results: parsing output: 83/ for test: kms_psr
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_render_copy
results: parsing output: 86/ for test: gem_mmap_wc
results: parsing output: 87/ for test: kms_plane
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: sw_sync
results: parsing output: 91/ for test: kms_psr2_sf
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_psr2_sf
results: parsing output: 94/ for test: gem_create
results: parsing output: 95/ for test: gem_ctx_persistence
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_content_protection
results: parsing output: 99/ for test: gem_tiled_blits
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: gem_exec_schedule
results: parsing output: 104/ for test: core_auth
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: i915_drm_fdinfo
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_cursor_legacy
results: parsing output: 109/ for test: kms_color
results: parsing output: 110/ for test: kms_invalid_mode
results: parsing output: 111/ for test: kms_vblank
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_fence_pin_leak
results: parsing output: 114/ for test: gem_userptr_blits
results: parsing output: 115/ for test: kms_lease
results: parsing output: 116/ for test: gem_caching
results: parsing output: 117/ for test: kms_psr2_sf
results: parsing output: 118/ for test: kms_flip
results: parsing output: 119/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: gem_exec_reloc
results: parsing output: 123/ for test: gem_shrink
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_ctx_sseu
results: parsing output: 126/ for test: syncobj_timeline
results: parsing output: 127/ for test: gen9_exec_parse
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: prime_vgem
results: parsing output: 130/ for test: i915_pm_rpm
results: parsing output: 131/ for test: kms_cursor_crc
results: parsing output: 132/ for test: kms_psr
Done.