[56.828788] Initializing watchdogs
[56.828838]   /dev/watchdog0
[56.842928] [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]
[56.847430] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.849592] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.852868] [001/130] (960s left) kms_properties (crtc-properties-legacy)
[57.020945] Starting subtest: crtc-properties-legacy
[57.023300] Starting dynamic subtest: pipe-A-eDP-1
[57.505178] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.484s)
[57.512980] Starting dynamic subtest: pipe-B-eDP-1
[58.705939] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.207s)
[58.713794] Starting dynamic subtest: pipe-C-eDP-1
[59.807507] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.109s)
[59.815692] Starting dynamic subtest: pipe-D-eDP-1
[60.930871] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s)
[60.933282] Subtest crtc-properties-legacy: SUCCESS (3.930s)
[61.725761] [002/130] (955s left) gen7_exec_parse (basic-offset)
[61.800314] Subtest basic-offset: SKIP (0.000s)
[61.875183] [003/130] (955s left) kms_pm_rpm (pm-tiling)
[64.111307] Starting subtest: pm-tiling
[64.116763] Subtest pm-tiling: SKIP (0.000s)
[64.456522] [004/130] (952s left) kms_flip (dpms-off-confusion-interruptible)
[64.555774] Starting subtest: dpms-off-confusion-interruptible
[64.558066] Starting dynamic subtest: A-eDP1
[73.248085] Dynamic subtest A-eDP1: SUCCESS (8.703s)
[73.256344] Starting dynamic subtest: B-eDP1
[81.971429] Dynamic subtest B-eDP1: SUCCESS (8.727s)
[81.978066] Starting dynamic subtest: C-eDP1
[90.673589] Dynamic subtest C-eDP1: SUCCESS (8.700s)
[90.680075] Starting dynamic subtest: D-eDP1
[99.294708] Dynamic subtest D-eDP1: SUCCESS (8.619s)
[99.297235] Subtest dpms-off-confusion-interruptible: SUCCESS (34.755s)
[100.451836] [005/130] (916s left) gem_ccs (suspend-resume)
[100.516251] Starting subtest: suspend-resume
[100.521083] Subtest suspend-resume: SKIP (0.000s)
[100.607525] [006/130] (916s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[100.708273] Starting subtest: psr-indfb-scaledprimary
[104.333030] Subtest psr-indfb-scaledprimary: SUCCESS (3.625s)
[104.473248] [007/130] (913s left) kms_addfb_basic (bad-pitch-63)
[104.540659] Starting subtest: bad-pitch-63
[104.543441] Subtest bad-pitch-63: SUCCESS (0.000s)
[104.663833] [008/130] (912s left) syncobj_timeline (reset-unsignaled)
[104.726931] Starting subtest: reset-unsignaled
[104.728915] Subtest reset-unsignaled: SUCCESS (0.000s)
[104.821782] [009/130] (912s left) kms_psr (pr-primary-blt)
[104.909319] Starting subtest: pr-primary-blt
[104.913982] Subtest pr-primary-blt: SKIP (0.000s)
[104.975402] [010/130] (912s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[105.089163] Starting subtest: x-tiled-16bpp-rotate-90
[105.107819] Subtest x-tiled-16bpp-rotate-90: SKIP (0.018s)
[105.224999] [011/130] (912s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[105.330559] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[105.332512] Starting dynamic subtest: pipe-A-default-mode
[105.620355] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[105.625164] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.285s)
[106.427486] [012/130] (911s left) perf_pmu (invalid-open)
[106.471772] Starting subtest: invalid-open
[106.474167] Subtest invalid-open: SUCCESS (0.000s)
[106.576830] [013/130] (911s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[106.627819] Starting subtest: multi-wait-all-available-submitted-signaled
[106.630042] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[106.723766] [014/130] (911s left) api_intel_bb (intel-bb-blit-y)
[106.773910] Starting subtest: intel-bb-blit-y
[110.268312] Subtest intel-bb-blit-y: SUCCESS (3.494s)
[110.406567] [015/130] (907s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[110.521074] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[110.523365] Starting dynamic subtest: pipe-A-eDP-1
[110.544262] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[110.552663] Starting dynamic subtest: pipe-B-eDP-1
[110.571452] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[110.577983] Starting dynamic subtest: pipe-C-eDP-1
[110.598559] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[110.605511] Starting dynamic subtest: pipe-D-eDP-1
[110.625020] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[110.626941] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[112.677173] [016/130] (905s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[112.779192] Starting subtest: psr-dirtyfb-ioctl
[112.781515] Starting dynamic subtest: A-eDP-1
[114.625964] Dynamic subtest A-eDP-1: SUCCESS (1.846s)
[114.628707] Subtest psr-dirtyfb-ioctl: SUCCESS (1.846s)
[115.408697] [017/130] (902s left) gem_ctx_param (set-priority-range)
[115.466942] Starting subtest: set-priority-range
[115.484788] Subtest set-priority-range: SUCCESS (0.018s)
[115.589776] [018/130] (902s left) kms_content_protection (lic-type-0)
[115.763055] Starting subtest: lic-type-0
[119.134652] Subtest lic-type-0: SKIP (3.370s)
[120.302091] [019/130] (897s left) gem_exec_reloc (basic-wc-gtt-active)
[120.364480] Subtest basic-wc-gtt-active: SKIP (0.000s)
[120.415565] [020/130] (897s left) kms_addfb_basic (legacy-format)
[120.479828] Starting subtest: legacy-format
[120.511143] Subtest legacy-format: SUCCESS (0.028s)
[120.660861] [021/130] (897s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[120.779445] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[125.323566] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (4.552s)
[125.462321] [022/130] (892s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[125.575478] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[126.640421] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.067s)
[126.781460] [023/130] (891s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[126.883427] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[130.592029] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (3.711s)
[130.714473] [024/130] (887s left) gem_workarounds (basic-read-context)
[130.784627] Starting subtest: basic-read-context
[130.795660] Subtest basic-read-context: SUCCESS (0.010s)
[130.920249] [025/130] (887s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[131.019835] Starting subtest: y-tiled-64bpp-rotate-90
[131.023747] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[131.116540] [026/130] (887s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[131.214251] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[131.217835] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[131.316489] [027/130] (886s left) kms_chamelium_hpd (dp-hpd)
[131.702583] Subtest dp-hpd: SKIP (0.000s)
[132.477269] [028/130] (885s left) gem_userptr_blits (huge-split)
[132.555447] Starting subtest: huge-split
[132.561446] Subtest huge-split: SUCCESS (0.006s)
[132.683695] [029/130] (885s left) kms_setmode (basic)
[132.747357] Starting subtest: basic
[133.075051] Starting dynamic subtest: pipe-A-eDP-1
[135.117871] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.038s)
[135.466725] Starting dynamic subtest: pipe-B-eDP-1
[137.547570] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.080s)
[137.855739] Subtest basic: SUCCESS (5.108s)
[138.673544] [030/130] (879s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[138.779955] Starting subtest: 4-tiled-8bpp-rotate-90
[138.800152] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.020s)
[138.893646] [031/130] (879s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[138.998569] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[142.045306] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (3.050s)
[142.197048] [032/130] (876s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[142.309814] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[146.619104] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (4.312s)
[146.733480] [033/130] (871s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[146.860801] Starting subtest: short-flip-before-cursor-toggle
[147.768843] Subtest short-flip-before-cursor-toggle: SUCCESS (0.907s)
[148.567646] [034/130] (869s left) gem_ctx_exec (basic-norecovery)
[148.631367] Starting subtest: basic-norecovery
[148.930761] Subtest basic-norecovery: SUCCESS (0.301s)
[149.074797] [035/130] (869s left) gem_mmap (basic)
[149.133542] Subtest basic: SKIP (0.000s)
[149.180616] [036/130] (869s left) kms_cursor_crc (cursor-random-512x512)
[149.438378] Starting subtest: cursor-random-512x512
[149.442664] Subtest cursor-random-512x512: SKIP (0.001s)
[149.594587] [037/130] (868s left) core_auth (getclient-simple)
[149.630634] Starting subtest: getclient-simple
[149.653302] Subtest getclient-simple: SUCCESS (0.023s)
[149.766125] [038/130] (868s left) gem_exec_balancer (sliced)
[149.835519] Starting subtest: sliced
[149.839499] Subtest sliced: SKIP (0.000s)
[150.133087] [039/130] (868s left) kms_color (ctm-green-to-red)
[150.241294] Starting subtest: ctm-green-to-red
[150.243630] Starting dynamic subtest: pipe-A-eDP-1
[150.940292] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.698s)
[150.949283] Starting dynamic subtest: pipe-B-eDP-1
[152.340498] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.399s)
[152.347629] Starting dynamic subtest: pipe-C-eDP-1
[153.628316] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.286s)
[153.636658] Starting dynamic subtest: pipe-D-eDP-1
[154.945284] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.316s)
[154.947843] Subtest ctm-green-to-red: SUCCESS (4.703s)
[155.771076] [040/130] (862s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[155.867192] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[155.869475] Starting dynamic subtest: pipe-A-eDP-1
[158.020483] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.152s)
[158.027268] Starting dynamic subtest: pipe-B-eDP-1
[160.096068] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.075s)
[160.103384] Starting dynamic subtest: pipe-C-eDP-1
[162.125824] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.027s)
[162.133701] Starting dynamic subtest: pipe-D-eDP-1
[164.164698] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.038s)
[164.166973] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.297s)
[165.369848] [041/130] (853s left) i915_hangman (gt-engine-hang)
[165.446637] Starting subtest: gt-engine-hang
[165.448824] Starting dynamic subtest: rcs0
[166.925257] Dynamic subtest rcs0: SUCCESS (1.478s)
[166.936220] Starting dynamic subtest: bcs0
[168.903418] Dynamic subtest bcs0: SUCCESS (1.977s)
[168.911824] Starting dynamic subtest: vcs0
[170.888090] Dynamic subtest vcs0: SUCCESS (1.982s)
[170.898681] Starting dynamic subtest: vcs1
[172.873611] Dynamic subtest vcs1: SUCCESS (1.983s)
[172.881518] Starting dynamic subtest: vecs0
[174.922110] Dynamic subtest vecs0: SUCCESS (2.046s)
[174.932970] Starting dynamic subtest: ccs0
[176.905187] Dynamic subtest ccs0: SUCCESS (1.981s)
[176.908461] Subtest gt-engine-hang: SUCCESS (11.460s)
[176.979450] [042/130] (841s left) kms_writeback (writeback-invalid-parameters)
[177.081171] Subtest writeback-invalid-parameters: SKIP (0.000s)
[177.119469] [043/130] (841s left) gem_ppgtt (blt-vs-render-ctxn)
[177.180273] Starting subtest: blt-vs-render-ctxN
[210.311659] Subtest blt-vs-render-ctxN: SUCCESS (33.128s)
[210.448416] [044/130] (808s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[210.607034] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[211.520496] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.915s)
[211.698656] [045/130] (807s left) kms_flip (basic-flip-vs-wf_vblank)
[211.803235] Starting subtest: basic-flip-vs-wf_vblank
[211.805498] Starting dynamic subtest: A-eDP1
[213.716736] Dynamic subtest A-eDP1: SUCCESS (1.913s)
[213.725422] Starting dynamic subtest: B-eDP1
[215.641767] Dynamic subtest B-eDP1: SUCCESS (1.924s)
[215.649535] Starting dynamic subtest: C-eDP1
[217.560665] Dynamic subtest C-eDP1: SUCCESS (1.917s)
[217.570963] Starting dynamic subtest: D-eDP1
[219.488951] Dynamic subtest D-eDP1: SUCCESS (1.928s)
[219.491596] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.689s)
[220.701764] [046/130] (798s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[220.799343] Starting subtest: fbc-psr2-cursor-mmap-gtt
[220.801634] Starting dynamic subtest: eDP-1
[220.805751] Dynamic subtest eDP-1: SKIP (0.000s)
[220.809864] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[220.869078] [047/130] (797s left) kms_pm_backlight (brightness-with-dpms)
[220.970324] Starting subtest: brightness-with-dpms
[221.037342] Starting dynamic subtest: eDP-1
[223.751648] Dynamic subtest eDP-1: SUCCESS (2.714s)
[223.754060] Subtest brightness-with-dpms: SUCCESS (2.782s)
[224.604323] [048/130] (794s left) gem_exec_schedule (deep)
[224.805570] Starting subtest: deep
[224.808032] Starting dynamic subtest: rcs0
[229.717228] Dynamic subtest rcs0: SKIP (4.907s)
[229.727127] Starting dynamic subtest: vcs0
[240.441403] Dynamic subtest vcs0: SUCCESS (10.725s)
[240.453744] Starting dynamic subtest: vcs1
[251.752882] Dynamic subtest vcs1: SUCCESS (11.311s)
[251.761486] Starting dynamic subtest: vecs0
[262.698541] Dynamic subtest vecs0: SUCCESS (10.945s)
[262.709114] Starting dynamic subtest: ccs0
[274.888741] Dynamic subtest ccs0: SUCCESS (12.188s)
[274.892017] Subtest deep: SUCCESS (50.081s)
[275.702373] [FACT gem_exec_schedule (deep)] new: kernel.kmod_is_loaded.vgem: true
[275.705423] [049/130] (743s left) vgem_basic (setversion)
[275.753217] Starting subtest: setversion
[275.757587] Subtest setversion: SUCCESS (0.000s)
[275.802094] [050/130] (743s left) perf_pmu (busy-accuracy-2)
[275.846127] Starting subtest: busy-accuracy-2
[275.848136] Starting dynamic subtest: rcs0
[278.898994] Dynamic subtest rcs0: SUCCESS (3.052s)
[278.907136] Starting dynamic subtest: bcs0
[281.960535] Dynamic subtest bcs0: SUCCESS (3.059s)
[281.971181] Starting dynamic subtest: vcs0
[285.007361] Dynamic subtest vcs0: SUCCESS (3.045s)
[285.015841] Starting dynamic subtest: vcs1
[288.054497] Dynamic subtest vcs1: SUCCESS (3.045s)
[288.063737] Starting dynamic subtest: vecs0
[291.100790] Dynamic subtest vecs0: SUCCESS (3.044s)
[291.109266] Starting dynamic subtest: ccs0
[294.168193] Dynamic subtest ccs0: SUCCESS (3.066s)
[294.170650] Subtest busy-accuracy-2: SUCCESS (18.321s)
[294.285212] [051/130] (724s left) kms_rotation_crc (bad-pixel-format)
[294.388197] Starting subtest: bad-pixel-format
[294.392520] Subtest bad-pixel-format: SKIP (0.001s)
[294.460595] [052/130] (724s left) gem_pread (snoop)
[294.511749] Subtest snoop: SKIP (0.000s)
[294.570034] [053/130] (724s left) gem_mmap_gtt (big-copy-odd)
[294.631281] Subtest big-copy-odd: SKIP (0.000s)
[294.688933] [054/130] (724s left) kms_invalid_mode (zero-clock)
[294.802774] Starting subtest: zero-clock
[294.805257] Starting dynamic subtest: pipe-A-eDP-1
[295.097279] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.292s)
[295.107585] Starting dynamic subtest: pipe-B-eDP-1
[295.109921] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[295.118164] Starting dynamic subtest: pipe-C-eDP-1
[295.120631] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[295.128862] Starting dynamic subtest: pipe-D-eDP-1
[295.131197] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[295.133542] Subtest zero-clock: SUCCESS (0.299s)
[295.931775] [055/130] (723s left) kms_big_fb (y-tiled-addfb)
[296.045652] Starting subtest: y-tiled-addfb
[296.049878] Subtest y-tiled-addfb: SKIP (0.000s)
[296.154183] [056/130] (722s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[296.261146] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[296.265164] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[296.357855] [057/130] (722s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[296.473653] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[296.477835] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[296.584361] [058/130] (722s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[296.689576] Starting subtest: fbcpsr-modesetfrombusy
[300.710889] Subtest fbcpsr-modesetfrombusy: SUCCESS (4.025s)
[300.867913] [059/130] (718s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[300.975760] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[300.979314] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[301.095966] [060/130] (718s left) api_intel_allocator (default-alignment)
[301.161736] Starting subtest: default-alignment
[301.163722] Subtest default-alignment: SUCCESS (0.003s)
[301.306406] [061/130] (718s left) syncobj_timeline (wait-any-interrupted)
[301.362060] Starting subtest: wait-any-interrupted
[301.462075] Subtest wait-any-interrupted: SUCCESS (0.101s)
[301.532955] [062/130] (717s left) kms_psr (psr2-primary-mmap-gtt)
[301.635540] Starting subtest: psr2-primary-mmap-gtt
[301.637460] Starting dynamic subtest: eDP-1
[303.102253] Dynamic subtest eDP-1: SKIP (1.463s)
[303.107226] Subtest psr2-primary-mmap-gtt: SKIP (1.465s)
[303.213620] [063/130] (716s left) gem_lmem_swapping (heavy-verify-random)
[303.261522] Subtest heavy-verify-random: SKIP (0.000s)
[303.299253] [064/130] (716s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[303.404222] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[308.639285] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (5.238s)
[308.758827] [065/130] (710s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[308.862400] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[308.864764] Starting dynamic subtest: pipe-A-default-mode
[310.032864] Dynamic subtest pipe-A-default-mode: SKIP (1.167s)
[310.037241] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.168s)
[310.169397] [066/130] (709s left) gem_exec_fence (syncobj-timeline-signal)
[310.741880] Starting subtest: syncobj-timeline-signal
[310.747698] Subtest syncobj-timeline-signal: SUCCESS (0.008s)
[311.969900] [067/130] (707s left) gem_mmap_wc (read-write)
[312.032688] Subtest read-write: SKIP (0.000s)
[312.094280] [068/130] (707s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[317.257214] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[317.259642] Starting dynamic subtest: pipe-A-eDP-1
[318.044053] Dynamic subtest pipe-A-eDP-1: SKIP (0.782s)
[318.052969] Starting dynamic subtest: pipe-B-eDP-1
[319.166402] Dynamic subtest pipe-B-eDP-1: SKIP (1.121s)
[319.170907] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.907s)
[320.338843] [069/130] (699s left) drm_fdinfo (memory-info-idle)
[320.390059] Starting subtest: memory-info-idle
[320.392397] Starting dynamic subtest: smem0
[320.468352] Dynamic subtest smem0: SUCCESS (0.079s)
[320.470851] Subtest memory-info-idle: SUCCESS (0.080s)
[320.537812] [070/130] (699s left) kms_pm_rpm (i2c)
[322.072606] Starting subtest: i2c
[327.727730] Subtest i2c: SUCCESS (5.653s)
[327.821071] [071/130] (691s left) kms_cursor_edge_walk (128x128-top-bottom)
[327.933079] Starting subtest: 128x128-top-bottom
[327.935395] Starting dynamic subtest: pipe-A-eDP-1
[331.359542] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.422s)
[331.368313] Starting dynamic subtest: pipe-D-eDP-1
[334.459514] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.100s)
[334.461922] Subtest 128x128-top-bottom: SUCCESS (6.525s)
[335.281267] [072/130] (684s left) gem_exec_flush (basic-wb-ro-default)
[335.347087] Starting subtest: basic-wb-ro-default
[340.826740] Subtest basic-wb-ro-default: SUCCESS (5.479s)
[341.007601] [073/130] (678s left) gem_ctx_shared (q-out-order)
[341.074078] Starting subtest: Q-out-order
[341.076442] Starting dynamic subtest: rcs0
[341.246802] Dynamic subtest rcs0: SUCCESS (0.171s)
[341.262119] Starting dynamic subtest: vcs0
[341.354857] Dynamic subtest vcs0: SUCCESS (0.109s)
[341.366775] Starting dynamic subtest: vcs1
[341.467283] Dynamic subtest vcs1: SUCCESS (0.111s)
[341.482133] Starting dynamic subtest: vecs0
[341.577493] Dynamic subtest vecs0: SUCCESS (0.110s)
[341.595728] Starting dynamic subtest: ccs0
[341.732653] Dynamic subtest ccs0: SUCCESS (0.153s)
[341.735921] Subtest Q-out-order: SUCCESS (0.657s)
[343.064807] [074/130] (676s left) gem_exec_balancer (noheartbeat)
[343.316416] Starting subtest: noheartbeat
[343.321413] Subtest noheartbeat: SKIP (0.000s)
[343.435103] [075/130] (676s left) gem_exec_schedule (u-submit-early-slice)
[343.514063] Starting subtest: u-submit-early-slice
[343.516496] Starting dynamic subtest: rcs0
[343.573294] Dynamic subtest rcs0: SUCCESS (0.064s)
[343.583051] Starting dynamic subtest: bcs0
[343.630050] Dynamic subtest bcs0: SUCCESS (0.055s)
[343.642189] Starting dynamic subtest: vcs0
[343.685239] Dynamic subtest vcs0: SUCCESS (0.055s)
[343.696384] Starting dynamic subtest: vcs1
[343.741126] Dynamic subtest vcs1: SUCCESS (0.055s)
[343.751034] Starting dynamic subtest: vecs0
[343.787682] Dynamic subtest vecs0: SUCCESS (0.045s)
[343.796943] Starting dynamic subtest: ccs0
[343.840171] Dynamic subtest ccs0: SUCCESS (0.053s)
[343.842535] Subtest u-submit-early-slice: SUCCESS (0.332s)
[345.055553] [076/130] (674s left) core_getclient
[345.204870] [077/130] (674s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[345.312334] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[345.316899] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[346.405160] [078/130] (673s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[346.464810] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[346.565647] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.102s)
[346.637402] [079/130] (673s left) perf (gen8-unprivileged-single-ctx-counters)
[346.761042] Starting subtest: gen8-unprivileged-single-ctx-counters
[346.765498] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[346.802867] [080/130] (673s left) kms_frontbuffer_tracking (fbc-1p-rte)
[346.903191] Starting subtest: fbc-1p-rte
[351.330327] Subtest fbc-1p-rte: SUCCESS (4.431s)
[351.486111] [081/130] (668s left) gem_ctx_shared (q-smoketest)
[351.559356] Starting subtest: Q-smoketest
[351.561723] Starting dynamic subtest: rcs0
[357.143971] Dynamic subtest rcs0: SUCCESS (5.535s)
[357.152897] Starting dynamic subtest: bcs0
[362.662034] Dynamic subtest bcs0: SUCCESS (5.517s)
[362.670496] Starting dynamic subtest: vcs0
[368.187891] Dynamic subtest vcs0: SUCCESS (5.524s)
[368.198087] Starting dynamic subtest: vcs1
[373.686695] Dynamic subtest vcs1: SUCCESS (5.502s)
[373.695955] Starting dynamic subtest: vecs0
[379.199970] Dynamic subtest vecs0: SUCCESS (5.508s)
[379.209546] Starting dynamic subtest: ccs0
[384.713049] Dynamic subtest ccs0: SUCCESS (5.512s)
[384.715091] Subtest Q-smoketest: SUCCESS (33.103s)
[384.846596] [082/130] (635s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[384.952314] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[384.956642] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[385.040995] [083/130] (635s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[385.139803] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[385.142160] Starting dynamic subtest: pipe-A
[385.305241] Dynamic subtest pipe-A: SKIP (0.158s)
[385.315221] Starting dynamic subtest: pipe-B
[386.520927] Dynamic subtest pipe-B: SKIP (1.216s)
[386.529934] Starting dynamic subtest: pipe-C
[387.715953] Dynamic subtest pipe-C: SUCCESS (1.198s)
[387.726068] Starting dynamic subtest: pipe-D
[388.956042] Dynamic subtest pipe-D: SUCCESS (1.241s)
[388.958440] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.816s)
[390.110511] [084/130] (630s left) i915_query (query-regions-garbage-items)
[390.175326] Starting subtest: query-regions-garbage-items
[390.177976] Subtest query-regions-garbage-items: SUCCESS (0.001s)
[390.273275] [085/130] (629s left) kms_flip (blocking-wf_vblank)
[390.372130] Starting subtest: blocking-wf_vblank
[390.374340] Starting dynamic subtest: A-eDP1
[399.365072] Dynamic subtest A-eDP1: SUCCESS (8.992s)
[399.375841] Starting dynamic subtest: B-eDP1
[408.286679] Dynamic subtest B-eDP1: SUCCESS (8.920s)
[408.296138] Starting dynamic subtest: C-eDP1
[417.204348] Dynamic subtest C-eDP1: SUCCESS (8.915s)
[417.212965] Starting dynamic subtest: D-eDP1
[426.133044] Dynamic subtest D-eDP1: SUCCESS (8.928s)
[426.135254] Subtest blocking-wf_vblank: SUCCESS (35.764s)
[427.352562] [086/130] (592s left) gem_pxp (create-regular-buffer)
[427.479885] Starting subtest: create-regular-buffer
[427.482428] Subtest create-regular-buffer: SUCCESS (0.000s)
[427.582466] [087/130] (592s left) kms_rmfb (close-fd)
[427.682164] Starting subtest: close-fd
[427.684532] Starting dynamic subtest: pipe-A-eDP-1
[427.802167] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.116s)
[427.819464] Starting dynamic subtest: pipe-B-eDP-1
[429.010993] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.211s)
[429.022457] Starting dynamic subtest: pipe-C-eDP-1
[430.144402] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.130s)
[430.154475] Starting dynamic subtest: pipe-D-eDP-1
[431.258248] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.114s)
[431.260698] Subtest close-fd: SUCCESS (3.575s)
[432.459144] [088/130] (587s left) gen9_exec_parse (bb-start-param)
[432.533092] Subtest bb-start-param: SKIP (0.000s)
[432.595801] [089/130] (587s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[432.705609] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[432.709747] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[432.816990] [090/130] (587s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[432.942995] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[432.982684] [091/130] (587s left) gem_mmap_offset (partial-mmap)
[433.044928] Starting subtest: partial-mmap
[433.046915] Starting dynamic subtest: smem0
[433.049299] Dynamic subtest smem0: SUCCESS (0.005s)
[433.051551] Subtest partial-mmap: SUCCESS (0.005s)
[433.166726] [092/130] (587s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[433.266350] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[433.268686] Starting dynamic subtest: pipe-A-default-mode
[433.566426] Dynamic subtest pipe-A-default-mode: SKIP (0.297s)
[433.570766] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.298s)
[434.377477] [093/130] (586s left) kms_cursor_crc (cursor-sliding-max-size)
[434.661663] Starting subtest: cursor-sliding-max-size
[434.668175] Subtest cursor-sliding-max-size: SKIP (0.000s)
[434.785802] [094/130] (585s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[434.909994] Starting subtest: cursor-vs-flip-toggle
[465.316134] Subtest cursor-vs-flip-toggle: SUCCESS (30.407s)
[466.109446] [095/130] (554s left) gem_softpin (allocator-nopin-reserve)
[466.178087] Starting subtest: allocator-nopin-reserve
[466.270227] Subtest allocator-nopin-reserve: SUCCESS (0.091s)
[466.398509] [096/130] (554s left) kms_addfb_basic (unused-pitches)
[466.451621] Starting subtest: unused-pitches
[466.453593] Subtest unused-pitches: SUCCESS (0.000s)
[466.585942] [097/130] (554s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[466.695512] Starting subtest: yf-tiled-8bpp-rotate-90
[466.699691] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[466.796891] [098/130] (553s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[466.890285] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[466.892568] Starting dynamic subtest: pipe-A
[466.901411] Dynamic subtest pipe-A: SKIP (0.004s)
[466.910751] Starting dynamic subtest: pipe-B
[466.919801] Dynamic subtest pipe-B: SKIP (0.015s)
[466.928498] Starting dynamic subtest: pipe-C
[466.937210] Dynamic subtest pipe-C: SKIP (0.015s)
[466.945752] Starting dynamic subtest: pipe-D
[466.954203] Dynamic subtest pipe-D: SKIP (0.016s)
[466.958501] Subtest plane-downscale-factor-0-5-with-pixel-format: SKIP (0.053s)
[467.032829] [099/130] (553s left) gen3_render_tiledx_blits
[467.151702] [100/130] (553s left) kms_psr (psr-cursor-plane-move)
[467.241488] Starting subtest: psr-cursor-plane-move
[467.243422] Starting dynamic subtest: eDP-1
[469.000153] Dynamic subtest eDP-1: SUCCESS (1.756s)
[469.002343] Subtest psr-cursor-plane-move: SUCCESS (1.757s)
[469.856781] [101/130] (550s left) gem_exec_whisper (basic-fds-all)
[469.933913] Starting subtest: basic-fds-all
[481.272044] Subtest basic-fds-all: SUCCESS (11.335s)
[481.453162] [102/130] (539s left) kms_cdclk (mode-transition-all-outputs)
[481.557614] Starting subtest: mode-transition-all-outputs
[481.845085] Subtest mode-transition-all-outputs: SKIP (0.283s)
[482.677916] [103/130] (538s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[482.769138] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[482.771433] Starting dynamic subtest: pipe-A-eDP-1
[482.821327] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.051s)
[482.830131] Starting dynamic subtest: pipe-B-eDP-1
[482.869852] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.048s)
[482.879329] Starting dynamic subtest: pipe-C-eDP-1
[482.920549] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.050s)
[482.929693] Starting dynamic subtest: pipe-D-eDP-1
[482.970726] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.049s)
[482.973076] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.202s)
[485.136383] [104/130] (535s left) gem_exec_reloc (basic-write-wc-active)
[485.211125] Subtest basic-write-wc-active: SKIP (0.000s)
[485.286120] [105/130] (535s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[485.345411] Starting subtest: yf-tiled-ccs-to-x-tiled
[485.349940] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[485.524291] [106/130] (535s left) kms_force_connector_basic (prune-stale-modes)
[485.612341] Starting subtest: prune-stale-modes
[486.015406] Subtest prune-stale-modes: SUCCESS (0.403s)
[486.170263] [107/130] (534s left) kms_getfb (getfb2-handle-not-fb)
[486.222573] Starting subtest: getfb2-handle-not-fb
[486.260469] Subtest getfb2-handle-not-fb: SUCCESS (0.041s)
[486.355439] [108/130] (534s left) kms_chamelium_frames (dp-crc-single)
[486.748101] Subtest dp-crc-single: SKIP (0.000s)
[487.534048] [109/130] (533s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[487.650777] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[487.655085] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[487.761957] [110/130] (533s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[487.889490] Starting subtest: cursorB-vs-flipA-atomic
[487.893972] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[488.018436] [111/130] (533s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[488.120157] Starting subtest: fbc-psr2-sprite-plane-onoff
[488.122411] Starting dynamic subtest: eDP-1
[488.126408] Dynamic subtest eDP-1: SKIP (0.000s)
[488.130367] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[488.188297] [112/130] (532s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[488.297315] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[488.301426] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[488.375540] [113/130] (532s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[488.475786] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[488.480250] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[488.561233] [114/130] (532s left) gem_create (create-valid-nonaligned)
[488.620390] Starting subtest: create-valid-nonaligned
[488.622047] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[488.808213] [115/130] (532s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[493.822197] Starting subtest: psr2-plane-move-sf-dmg-area
[493.824622] Starting dynamic subtest: pipe-A-eDP-1
[499.013470] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.189s)
[499.022815] Starting dynamic subtest: pipe-B-eDP-1
[504.203887] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.189s)
[504.206290] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.382s)
[505.052174] [116/130] (516s left) kms_vblank (invalid)
[505.149288] Starting subtest: invalid
[505.151608] Starting dynamic subtest: pipe-A-eDP-1
[505.556858] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.405s)
[505.559253] Subtest invalid: SUCCESS (0.406s)
[506.351433] [117/130] (514s left) gem_exec_schedule (promotion)
[506.433901] Starting subtest: promotion
[506.436471] Starting dynamic subtest: rcs0
[506.525904] Dynamic subtest rcs0: SUCCESS (0.100s)
[506.538091] Starting dynamic subtest: vcs0
[506.608862] Dynamic subtest vcs0: SUCCESS (0.083s)
[506.620529] Starting dynamic subtest: vcs1
[506.686346] Dynamic subtest vcs1: SUCCESS (0.077s)
[506.697949] Starting dynamic subtest: vecs0
[506.766467] Dynamic subtest vecs0: SUCCESS (0.080s)
[506.777770] Starting dynamic subtest: ccs0
[506.860907] Dynamic subtest ccs0: SUCCESS (0.094s)
[506.863396] Subtest promotion: SUCCESS (0.436s)
[508.060493] [118/130] (513s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[508.187013] Starting subtest: cursorA-vs-flipA-toggle
[519.336433] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.150s)
[520.150433] [119/130] (501s left) i915_pm_rpm (system-suspend-execbuf)
[522.090973] Starting subtest: system-suspend-execbuf
[580.371818] Subtest system-suspend-execbuf: SUCCESS (43.076s)
[580.736869] [120/130] (455s left) fbdev (pan)
[580.783754] Starting subtest: pan
[580.814605] Subtest pan: SUCCESS (0.035s)
[580.879464] [121/130] (455s left) syncobj_eventfd (binary-wait-signaled)
[580.952965] Starting subtest: binary-wait-signaled
[580.955252] Subtest binary-wait-signaled: SUCCESS (0.000s)
[581.058185] [122/130] (455s left) syncobj_timeline (invalid-query-zero-handles)
[581.110936] Starting subtest: invalid-query-zero-handles
[581.113292] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[581.212378] [123/130] (455s left) kms_plane_alpha_blend (alpha-basic)
[581.309689] Starting subtest: alpha-basic
[581.734466] Starting dynamic subtest: pipe-A-eDP-1
[583.632125] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.895s)
[583.654226] Starting dynamic subtest: pipe-D-eDP-1
[586.272967] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.627s)
[586.275554] Subtest alpha-basic: SUCCESS (4.964s)
[587.140542] [124/130] (449s left) syncobj_wait (single-wait-for-submit-submitted)
[587.204110] Starting subtest: single-wait-for-submit-submitted
[587.313426] Subtest single-wait-for-submit-submitted: SUCCESS (0.108s)
[587.384324] [125/130] (449s left) gem_mmap (bad-offset)
[587.448108] Subtest bad-offset: SKIP (0.000s)
[587.506469] [126/130] (449s left) kms_lease (master-vs-lease)
[587.603679] Starting subtest: master-vs-lease
[587.606374] Subtest master-vs-lease: SUCCESS (0.002s)
[587.710629] [127/130] (449s left) gem_exec_flush (basic-uc-set-default)
[587.770551] Starting subtest: basic-uc-set-default
[593.247814] Subtest basic-uc-set-default: SUCCESS (5.478s)
[593.434649] [128/130] (443s left) gem_ctx_isolation (preservation-reset)
[593.607522] Starting subtest: preservation-reset
[593.610524] Starting dynamic subtest: rcs0
[594.060076] Dynamic subtest rcs0: SUCCESS (0.451s)
[594.071759] Starting dynamic subtest: bcs0
[594.581106] Dynamic subtest bcs0: SUCCESS (0.520s)
[594.593348] Starting dynamic subtest: vcs0
[595.062147] Dynamic subtest vcs0: SUCCESS (0.481s)
[595.078774] Starting dynamic subtest: vcs1
[596.672508] Dynamic subtest vcs1: SUCCESS (1.610s)
[596.685328] Starting dynamic subtest: vecs0
[598.705952] Dynamic subtest vecs0: SUCCESS (2.032s)
[598.723027] Starting dynamic subtest: ccs0
[600.709592] Dynamic subtest ccs0: SUCCESS (2.004s)
[600.712420] Subtest preservation-reset: SUCCESS (7.106s)
[600.870301] [129/130] (436s left) gem_userptr_blits (sync-unmap-after-close)
[600.936175] Starting subtest: sync-unmap-after-close
[600.953758] Subtest sync-unmap-after-close: SUCCESS (0.017s)
[601.107366] [130/130] (435s left) gem_ctx_param (invalid-ctx-set)
[601.172452] Starting subtest: invalid-ctx-set
[601.175016] Subtest invalid-ctx-set: SUCCESS (0.001s)
[601.256121] Closing watchdogs
Done.