[142.948905] Initializing watchdogs
[142.949080]   /dev/watchdog0
[142.963204] [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]
[142.967358] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[142.969563] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[142.972946] [001/133] (960s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[143.108990] Starting subtest: fbc-rgb101010-draw-render
[147.707686] Subtest fbc-rgb101010-draw-render: SUCCESS (4.603s)
[148.889817] [002/133] (954s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[148.948779] Starting subtest: y-tiled-to-vebox-x-tiled
[148.953306] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[149.139177] [003/133] (954s left) gem_exec_gttfill (basic)
[149.220534] Starting subtest: basic
[151.780347] Subtest basic: SUCCESS (2.559s)
[151.975053] [004/133] (951s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[152.132137] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[156.541413] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.415s)
[157.687017] [005/133] (945s left) kms_pm_rpm (dpms-non-lpsp)
[159.246372] Starting subtest: dpms-non-lpsp
[159.251216] Subtest dpms-non-lpsp: SKIP (0.001s)
[160.022836] [006/133] (943s left) gem_exec_schedule (submit-golden-slice)
[160.103182] Starting subtest: submit-golden-slice
[160.105459] Starting dynamic subtest: rcs0
[160.128752] Dynamic subtest rcs0: SUCCESS (0.033s)
[160.137164] Starting dynamic subtest: bcs0
[160.157908] Dynamic subtest bcs0: SUCCESS (0.029s)
[160.165583] Starting dynamic subtest: vcs0
[160.191509] Dynamic subtest vcs0: SUCCESS (0.033s)
[160.202742] Starting dynamic subtest: vcs1
[160.221087] Dynamic subtest vcs1: SUCCESS (0.029s)
[160.228822] Starting dynamic subtest: vecs0
[160.249513] Dynamic subtest vecs0: SUCCESS (0.029s)
[160.257119] Starting dynamic subtest: ccs0
[160.279444] Dynamic subtest ccs0: SUCCESS (0.029s)
[160.281716] Subtest submit-golden-slice: SUCCESS (0.184s)
[162.070020] [007/133] (941s left) syncobj_timeline (reset-during-wait-for-submit)
[162.129823] Starting subtest: reset-during-wait-for-submit
[162.233856] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[162.315209] [008/133] (941s left) kms_properties (connector-properties-legacy)
[162.410152] Starting subtest: connector-properties-legacy
[162.412440] Starting dynamic subtest: pipe-A-eDP-1
[170.207455] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.794s)
[170.216706] Starting dynamic subtest: pipe-None-DP-1
[170.220880] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[170.229065] Starting dynamic subtest: pipe-None-HDMI-A-1
[170.232941] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[170.239957] Starting dynamic subtest: pipe-None-DP-2
[170.244079] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[170.251691] Starting dynamic subtest: pipe-None-HDMI-A-2
[170.256472] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[170.265471] Starting dynamic subtest: pipe-None-DP-3
[170.269676] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[170.276438] Starting dynamic subtest: pipe-None-DP-4
[170.280760] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[170.283158] Subtest connector-properties-legacy: SUCCESS (7.804s)
[171.078946] [009/133] (932s left) perf (i915-ref-count)
[171.159683] Starting subtest: i915-ref-count
[171.222700] Subtest i915-ref-count: SUCCESS (0.061s)
[171.282353] [010/133] (932s left) api_intel_allocator (gem-pool)
[171.346748] Starting subtest: gem-pool
[171.352840] Subtest gem-pool: SUCCESS (0.007s)
[171.504733] [011/133] (932s left) kms_frontbuffer_tracking (fbc-stridechange)
[171.599331] Starting subtest: fbc-stridechange
[174.905757] Subtest fbc-stridechange: SUCCESS (3.309s)
[176.081326] [012/133] (927s left) kms_addfb_basic (bad-pitch-1024)
[176.144015] Starting subtest: bad-pitch-1024
[176.146355] Subtest bad-pitch-1024: SUCCESS (0.000s)
[176.323994] [013/133] (927s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[176.424288] Starting subtest: bad-aux-stride-y-tiled-ccs
[176.426695] Starting dynamic subtest: pipe-A-eDP-1
[176.449370] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[176.459767] Starting dynamic subtest: pipe-B-eDP-1
[176.482451] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[176.490571] Starting dynamic subtest: pipe-C-eDP-1
[176.513336] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[176.522630] Starting dynamic subtest: pipe-D-eDP-1
[176.544816] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[176.548950] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.003s)
[178.612720] [014/133] (925s left) gem_workarounds (suspend-resume-fd)
[178.684890] Starting subtest: suspend-resume-fd
[194.523468] Subtest suspend-resume-fd: SUCCESS (1.311s)
[194.689044] [015/133] (909s left) gem_ctx_persistence (legacy-engines-mixed)
[194.764545] Starting subtest: legacy-engines-mixed
[194.766908] Starting dynamic subtest: render
[194.886024] Dynamic subtest render: SUCCESS (0.120s)
[194.900502] Starting dynamic subtest: bsd1
[194.966865] Dynamic subtest bsd1: SUCCESS (0.079s)
[194.978274] Starting dynamic subtest: bsd2
[195.040666] Dynamic subtest bsd2: SUCCESS (0.072s)
[195.051809] Starting dynamic subtest: blt
[195.107304] Dynamic subtest blt: SUCCESS (0.065s)
[195.119472] Starting dynamic subtest: vebox
[195.187657] Dynamic subtest vebox: SUCCESS (0.080s)
[195.190246] Subtest legacy-engines-mixed: SUCCESS (0.423s)
[195.281135] [016/133] (908s left) gem_exec_schedule (out-order)
[195.344738] Starting subtest: out-order
[195.346999] Starting dynamic subtest: rcs0
[195.431749] Dynamic subtest rcs0: SUCCESS (0.096s)
[195.442437] Starting dynamic subtest: vcs0
[195.512119] Dynamic subtest vcs0: SUCCESS (0.080s)
[195.523865] Starting dynamic subtest: vcs1
[195.592183] Dynamic subtest vcs1: SUCCESS (0.080s)
[195.604296] Starting dynamic subtest: vecs0
[195.668764] Dynamic subtest vecs0: SUCCESS (0.076s)
[195.681621] Starting dynamic subtest: ccs0
[195.753340] Dynamic subtest ccs0: SUCCESS (0.084s)
[195.755531] Subtest out-order: SUCCESS (0.418s)
[197.584827] [017/133] (906s left) gem_exec_fence (parallel)
[197.771804] Starting subtest: parallel
[197.774112] Starting dynamic subtest: rcs0
[199.924026] Dynamic subtest rcs0: SUCCESS (2.154s)
[199.934939] Starting dynamic subtest: bcs0
[202.083203] Dynamic subtest bcs0: SUCCESS (2.157s)
[202.095782] Starting dynamic subtest: vcs0
[204.233818] Dynamic subtest vcs0: SUCCESS (2.150s)
[204.244508] Starting dynamic subtest: vcs1
[206.389355] Dynamic subtest vcs1: SUCCESS (2.154s)
[206.400027] Starting dynamic subtest: vecs0
[208.541407] Dynamic subtest vecs0: SUCCESS (2.151s)
[208.553122] Starting dynamic subtest: ccs0
[210.696854] Dynamic subtest ccs0: SUCCESS (2.154s)
[210.698909] Subtest parallel: SUCCESS (12.923s)
[211.547805] [FACT gem_exec_fence (parallel)] new: kernel.kmod_is_loaded.vgem: true
[211.551123] [018/133] (892s left) sysfs_heartbeat_interval (idempotent)
[211.604326] Starting subtest: idempotent
[211.606542] Starting dynamic subtest: vecs0
[211.608825] Dynamic subtest vecs0: SUCCESS (0.001s)
[211.616936] Starting dynamic subtest: ccs0
[211.618835] Dynamic subtest ccs0: SUCCESS (0.002s)
[211.626430] Starting dynamic subtest: vcs0
[211.628285] Dynamic subtest vcs0: SUCCESS (0.000s)
[211.635831] Starting dynamic subtest: bcs0
[211.637731] Dynamic subtest bcs0: SUCCESS (0.002s)
[211.645238] Starting dynamic subtest: rcs0
[211.647146] Dynamic subtest rcs0: SUCCESS (0.002s)
[211.654720] Starting dynamic subtest: vcs1
[211.656640] Dynamic subtest vcs1: SUCCESS (0.000s)
[211.658954] Subtest idempotent: SUCCESS (0.009s)
[211.760386] [019/133] (892s left) gem_caching (read-writes)
[211.818932] Subtest read-writes: SKIP (0.000s)
[211.910317] [020/133] (892s left) syncobj_wait (reset-multiple-signaled)
[211.973201] Starting subtest: reset-multiple-signaled
[211.975580] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[212.095935] [021/133] (892s left) gem_mmap_offset (partial-remap)
[212.150117] Starting subtest: partial-remap
[212.152374] Starting dynamic subtest: smem0
[212.164607] Dynamic subtest smem0: SUCCESS (0.009s)
[212.166395] Subtest partial-remap: SUCCESS (0.010s)
[212.313225] [022/133] (891s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[212.377905] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[212.481290] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[212.584336] [023/133] (891s left) gem_spin_batch (spin-each)
[212.636667] Starting subtest: spin-each
[216.004029] Subtest spin-each: SUCCESS (3.368s)
[216.523931] [024/133] (887s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[216.627514] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[216.631914] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[216.760917] [025/133] (887s left) i915_pm_rpm (debugfs-read)
[218.583028] Starting subtest: debugfs-read
[219.740603] Subtest debugfs-read: SUCCESS (1.158s)
[220.117793] [026/133] (884s left) perf (blocking-parameterized)
[220.212215] Starting subtest: blocking-parameterized
[240.231527] Subtest blocking-parameterized: SUCCESS (20.017s)
[240.279589] [027/133] (864s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[240.386350] Starting subtest: basic-batch-kernel-default-cmd
[240.390924] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[240.590569] [028/133] (863s left) syncobj_wait (invalid-reset-zero-handles)
[240.650392] Starting subtest: invalid-reset-zero-handles
[240.652661] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[240.783821] [029/133] (863s left) kms_flip (flip-vs-blocking-wf-vblank)
[240.895148] Starting subtest: flip-vs-blocking-wf-vblank
[240.897447] Starting dynamic subtest: A-eDP1
[249.845614] Dynamic subtest A-eDP1: SUCCESS (8.951s)
[249.856053] Starting dynamic subtest: B-eDP1
[258.778494] Dynamic subtest B-eDP1: SUCCESS (8.931s)
[258.792845] Starting dynamic subtest: C-eDP1
[267.700186] Dynamic subtest C-eDP1: SUCCESS (8.920s)
[267.708587] Starting dynamic subtest: D-eDP1
[276.644248] Dynamic subtest D-eDP1: SUCCESS (8.942s)
[276.648738] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.753s)
[277.867286] [030/133] (826s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[277.965119] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[284.466054] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.502s)
[284.626522] [031/133] (820s left) gem_exec_balancer (bonded-semaphore)
[284.688260] Starting subtest: bonded-semaphore
[284.692860] Subtest bonded-semaphore: SKIP (0.000s)
[285.018582] [032/133] (819s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[285.111785] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[285.116125] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[285.240023] [033/133] (819s left) kms_invalid_mode (int-max-clock)
[285.334746] Starting subtest: int-max-clock
[285.337014] Starting dynamic subtest: pipe-A-eDP-1
[285.615492] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[285.625386] Starting dynamic subtest: pipe-B-eDP-1
[285.627554] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[285.635590] Starting dynamic subtest: pipe-C-eDP-1
[285.637807] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[285.645860] Starting dynamic subtest: pipe-D-eDP-1
[285.648003] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[285.650125] Subtest int-max-clock: SUCCESS (0.289s)
[286.427760] [034/133] (818s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[286.540069] Starting subtest: 4-tiled-16bpp-rotate-0
[287.718177] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.178s)
[287.851350] [035/133] (816s left) kms_addfb_basic (bad-pitch-999)
[287.908500] Starting subtest: bad-pitch-999
[287.910797] Subtest bad-pitch-999: SUCCESS (0.000s)
[288.068276] [036/133] (816s left) gen7_exec_parse (cmd-crossing-page)
[288.128972] Subtest cmd-crossing-page: SKIP (0.000s)
[288.217661] [037/133] (816s left) kms_async_flips (crc)
[288.310829] Starting subtest: crc
[288.682939] Starting dynamic subtest: pipe-A-eDP-1
[291.638781] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.956s)
[292.015672] Starting dynamic subtest: pipe-B-eDP-1
[294.966396] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.960s)
[295.340666] Starting dynamic subtest: pipe-C-eDP-1
[298.269406] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.939s)
[298.629827] Starting dynamic subtest: pipe-D-eDP-1
[301.494429] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.873s)
[301.496755] Subtest crc: SUCCESS (13.187s)
[302.676715] [038/133] (802s left) syncobj_wait (single-wait-all-for-submit-submitted)
[302.748594] Starting subtest: single-wait-all-for-submit-submitted
[302.856661] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.107s)
[302.952577] [039/133] (802s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[303.056432] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[303.060581] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[303.192595] [040/133] (801s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[303.291440] Starting subtest: primary-yf-tiled-reflect-x-0
[303.350969] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.060s)
[303.464715] [041/133] (801s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[303.564740] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[303.569083] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[303.690895] [042/133] (801s left) kms_legacy_colorkey (basic)
[303.793324] Starting subtest: basic
[303.795649] Starting dynamic subtest: pipe-A
[303.836119] Dynamic subtest pipe-A: SUCCESS (0.045s)
[303.847911] Starting dynamic subtest: pipe-B
[303.849889] Dynamic subtest pipe-B: SUCCESS (0.001s)
[303.857962] Starting dynamic subtest: pipe-C
[303.860388] Dynamic subtest pipe-C: SUCCESS (0.000s)
[303.868658] Starting dynamic subtest: pipe-D
[303.870601] Dynamic subtest pipe-D: SUCCESS (0.000s)
[303.872516] Subtest basic: SUCCESS (0.050s)
[303.914126] [043/133] (801s left) kms_plane_alpha_blend (alpha-7efc)
[304.002359] Starting subtest: alpha-7efc
[304.280663] Starting dynamic subtest: pipe-A-eDP-1
[304.865880] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.585s)
[304.894993] Starting dynamic subtest: pipe-D-eDP-1
[306.137823] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.251s)
[306.140214] Subtest alpha-7efc: SUCCESS (2.136s)
[307.026529] [044/133] (798s left) gem_mmap (big-bo)
[307.102981] Subtest big-bo: SKIP (0.000s)
[307.171153] [045/133] (798s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[307.270880] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[307.275329] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[307.401917] [046/133] (797s left) gem_exec_schedule (u-submit-golden-slice)
[307.480100] Starting subtest: u-submit-golden-slice
[307.482388] Starting dynamic subtest: rcs0
[307.505211] Dynamic subtest rcs0: SUCCESS (0.032s)
[307.518284] Starting dynamic subtest: bcs0
[307.534012] Dynamic subtest bcs0: SUCCESS (0.028s)
[307.543019] Starting dynamic subtest: vcs0
[307.568422] Dynamic subtest vcs0: SUCCESS (0.034s)
[307.577928] Starting dynamic subtest: vcs1
[307.597481] Dynamic subtest vcs1: SUCCESS (0.029s)
[307.607033] Starting dynamic subtest: vecs0
[307.632355] Dynamic subtest vecs0: SUCCESS (0.034s)
[307.642751] Starting dynamic subtest: ccs0
[307.662523] Dynamic subtest ccs0: SUCCESS (0.030s)
[307.664684] Subtest u-submit-golden-slice: SUCCESS (0.190s)
[309.586144] [047/133] (795s left) gem_exec_async (forked-writes)
[309.656032] Starting subtest: forked-writes
[309.658262] Starting dynamic subtest: rcs0
[309.682228] Dynamic subtest rcs0: SUCCESS (0.026s)
[309.691437] Starting dynamic subtest: bcs0
[309.703993] Dynamic subtest bcs0: SUCCESS (0.021s)
[309.715768] Starting dynamic subtest: vcs0
[309.726006] Dynamic subtest vcs0: SUCCESS (0.021s)
[309.735580] Starting dynamic subtest: vcs1
[309.751553] Dynamic subtest vcs1: SUCCESS (0.024s)
[309.760801] Starting dynamic subtest: vecs0
[309.772156] Dynamic subtest vecs0: SUCCESS (0.020s)
[309.781333] Starting dynamic subtest: ccs0
[309.795207] Dynamic subtest ccs0: SUCCESS (0.021s)
[309.797394] Subtest forked-writes: SUCCESS (0.139s)
[310.057179] [048/133] (795s left) core_auth (many-magics)
[310.118441] Starting subtest: many-magics
[310.865589] Subtest many-magics: SUCCESS (0.750s)
[311.073110] [049/133] (794s left) kms_color (deep-color)
[311.181641] Starting subtest: deep-color
[312.274957] Starting dynamic subtest: pipe-A-eDP-1-gamma
[312.989463] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.713s)
[312.998473] Starting dynamic subtest: pipe-A-eDP-1-degamma
[314.467081] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.475s)
[314.476579] Starting dynamic subtest: pipe-A-eDP-1-ctm
[315.906562] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.443s)
[316.683376] Starting dynamic subtest: pipe-B-eDP-1-gamma
[317.413896] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.736s)
[317.424258] Starting dynamic subtest: pipe-B-eDP-1-degamma
[318.831197] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.418s)
[318.840060] Starting dynamic subtest: pipe-B-eDP-1-ctm
[320.274190] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.443s)
[321.066008] Starting dynamic subtest: pipe-C-eDP-1-gamma
[321.738296] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.679s)
[321.746821] Starting dynamic subtest: pipe-C-eDP-1-degamma
[323.128504] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.392s)
[323.138084] Starting dynamic subtest: pipe-C-eDP-1-ctm
[324.521137] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.389s)
[325.270385] Starting dynamic subtest: pipe-D-eDP-1-gamma
[325.932986] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.671s)
[325.941908] Starting dynamic subtest: pipe-D-eDP-1-degamma
[327.302788] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.365s)
[327.319687] Starting dynamic subtest: pipe-D-eDP-1-ctm
[328.652335] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.352s)
[328.654562] Subtest deep-color: SUCCESS (17.473s)
[329.533736] [050/133] (775s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[329.637353] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[329.641437] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[329.773822] [051/133] (775s left) gem_exec_fence (syncobj-timeline-unused-fence)
[330.346636] Starting subtest: syncobj-timeline-unused-fence
[330.349204] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[331.533929] [052/133] (773s left) kms_psr (fbc-psr-cursor-plane-move)
[331.640123] Starting subtest: fbc-psr-cursor-plane-move
[331.642397] Starting dynamic subtest: eDP-1
[331.646484] Dynamic subtest eDP-1: SKIP (0.000s)
[331.650526] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[331.722952] [053/133] (773s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[331.813640] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[331.815550] Starting dynamic subtest: pipe-A
[332.150066] Dynamic subtest pipe-A: SUCCESS (0.339s)
[332.158838] Starting dynamic subtest: pipe-B
[333.510714] Dynamic subtest pipe-B: SUCCESS (1.360s)
[333.519641] Starting dynamic subtest: pipe-C
[334.946257] Dynamic subtest pipe-C: SUCCESS (1.435s)
[334.955390] Starting dynamic subtest: pipe-D
[336.381548] Dynamic subtest pipe-D: SUCCESS (1.434s)
[336.383917] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.572s)
[337.568186] [054/133] (768s left) kms_flip (2x-flip-vs-suspend)
[337.665909] Starting subtest: 2x-flip-vs-suspend
[337.676216] Subtest 2x-flip-vs-suspend: SKIP (0.010s)
[337.766350] [055/133] (767s left) gem_exec_capture (pi)
[337.835624] Starting subtest: pi
[337.837866] Starting dynamic subtest: rcs0
[342.153922] Dynamic subtest rcs0: SUCCESS (4.318s)
[342.165412] Starting dynamic subtest: bcs0
[346.303159] Dynamic subtest bcs0: SUCCESS (4.147s)
[346.313630] Starting dynamic subtest: vcs0
[350.393558] Dynamic subtest vcs0: SUCCESS (4.088s)
[350.405064] Starting dynamic subtest: vcs1
[354.442053] Dynamic subtest vcs1: SUCCESS (4.047s)
[354.452660] Starting dynamic subtest: vecs0
[358.489404] Dynamic subtest vecs0: SUCCESS (4.045s)
[358.499044] Starting dynamic subtest: ccs0
[362.513863] Dynamic subtest ccs0: SUCCESS (4.022s)
[362.516684] Subtest pi: SUCCESS (24.679s)
[362.594393] [056/133] (743s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[362.755167] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[362.785408] Starting dynamic subtest: pipe-A-plane-0
[363.062773] Dynamic subtest pipe-A-plane-0: SUCCESS (0.277s)
[363.071782] Starting dynamic subtest: pipe-A-plane-3
[363.296120] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[364.441613] Starting dynamic subtest: pipe-B-plane-0
[364.689518] Dynamic subtest pipe-B-plane-0: SUCCESS (0.255s)
[364.698290] Starting dynamic subtest: pipe-B-plane-3
[364.923979] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[365.242258] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.488s)
[366.136564] [057/133] (739s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[366.238870] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[366.241099] Starting dynamic subtest: pipe-A-default-mode
[368.978543] Dynamic subtest pipe-A-default-mode: SUCCESS (2.739s)
[368.980932] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.741s)
[369.828009] [058/133] (735s left) i915_query (query-regions-garbage-items)
[369.887206] Starting subtest: query-regions-garbage-items
[369.889175] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[370.002410] [059/133] (735s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[370.108478] Starting subtest: x-tiled-8bpp-rotate-180
[371.277042] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.169s)
[371.445655] [060/133] (734s left) kms_content_protection (srm)
[371.596404] Starting subtest: srm
[371.602776] Subtest srm: SKIP (0.003s)
[371.761669] [061/133] (734s left) gem_ctx_persistence (engines-cleanup)
[371.826713] Starting subtest: engines-cleanup
[371.828795] Starting dynamic subtest: rcs0
[371.942239] Dynamic subtest rcs0: SUCCESS (0.114s)
[371.954207] Starting dynamic subtest: bcs0
[372.019158] Dynamic subtest bcs0: SUCCESS (0.075s)
[372.029171] Starting dynamic subtest: vcs0
[372.097406] Dynamic subtest vcs0: SUCCESS (0.076s)
[372.109139] Starting dynamic subtest: vcs1
[372.162889] Dynamic subtest vcs1: SUCCESS (0.064s)
[372.173415] Starting dynamic subtest: vecs0
[372.228949] Dynamic subtest vecs0: SUCCESS (0.064s)
[372.241662] Starting dynamic subtest: ccs0
[372.300058] Dynamic subtest ccs0: SUCCESS (0.070s)
[372.302493] Subtest engines-cleanup: SUCCESS (0.474s)
[372.390690] [062/133] (733s left) gem_exec_flush (basic-batch-kernel-default-wb)
[372.452480] Starting subtest: basic-batch-kernel-default-wb
[378.074419] Subtest basic-batch-kernel-default-wb: SUCCESS (5.621s)
[378.275333] [063/133] (727s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[378.401532] Starting subtest: 4-tiled-8bpp-rotate-0
[379.519673] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.117s)
[379.677107] [064/133] (726s left) gem_mmap_gtt (close-race)
[379.733038] Subtest close-race: SKIP (0.000s)
[379.804483] [065/133] (726s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[379.903037] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[379.906792] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[379.987960] [066/133] (726s left) kms_flip (2x-flip-vs-expired-vblank)
[380.068379] Starting subtest: 2x-flip-vs-expired-vblank
[380.079826] Subtest 2x-flip-vs-expired-vblank: SKIP (0.010s)
[380.188207] [067/133] (725s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[380.290284] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[380.291959] Starting dynamic subtest: A-eDP1
[383.925287] Dynamic subtest A-eDP1: SUCCESS (3.634s)
[383.935326] Starting dynamic subtest: B-eDP1
[387.148432] Dynamic subtest B-eDP1: SUCCESS (3.221s)
[387.158192] Starting dynamic subtest: C-eDP1
[390.241169] Dynamic subtest C-eDP1: SUCCESS (3.091s)
[390.251731] Starting dynamic subtest: D-eDP1
[393.342946] Dynamic subtest D-eDP1: SUCCESS (3.101s)
[393.345377] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.055s)
[394.156153] [068/133] (711s left) gem_softpin (softpin)
[394.228332] Starting subtest: softpin
[426.531006] Subtest softpin: SUCCESS (32.296s)
[426.678851] [069/133] (679s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[426.783382] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[426.787572] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[426.915231] [070/133] (679s left) gem_exec_params (invalid-ring2)
[426.978385] Starting subtest: invalid-ring2
[426.980548] Subtest invalid-ring2: SUCCESS (0.000s)
[427.096080] [071/133] (679s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[427.196177] Starting subtest: dsc-fractional-bpp-with-bpc
[427.226901] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s)
[427.298858] [072/133] (679s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[427.525098] Starting subtest: cursor-rapid-movement-128x42
[427.566726] Subtest cursor-rapid-movement-128x42: SKIP (0.038s)
[427.791569] [073/133] (678s left) i915_hangman (gt-error-state-capture)
[427.855783] Starting subtest: gt-error-state-capture
[427.857473] Starting dynamic subtest: rcs0
[438.387665] Dynamic subtest rcs0: SUCCESS (10.529s)
[438.399626] Starting dynamic subtest: bcs0
[449.395359] Dynamic subtest bcs0: SUCCESS (11.003s)
[449.406913] Starting dynamic subtest: vcs0
[461.363593] Dynamic subtest vcs0: SUCCESS (11.964s)
[461.375859] Starting dynamic subtest: vcs1
[473.330955] Dynamic subtest vcs1: SUCCESS (11.963s)
[473.343990] Starting dynamic subtest: vecs0
[483.378963] Dynamic subtest vecs0: SUCCESS (10.045s)
[483.390122] Starting dynamic subtest: ccs0
[495.347774] Dynamic subtest ccs0: SUCCESS (11.964s)
[495.350943] Subtest gt-error-state-capture: SUCCESS (67.485s)
[495.530349] [074/133] (610s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[495.642496] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[495.646892] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[495.740891] [075/133] (610s left) kms_selftest (drm_format)
[495.788377] Starting subtest: drm_format
[495.832683] Starting dynamic subtest: drm_test_format_block_width_invalid
[495.974539] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.140s)
[495.984857] Starting dynamic subtest: drm_test_format_block_width_one_plane
[496.110284] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.135s)
[496.120356] Starting dynamic subtest: drm_test_format_block_width_two_plane
[496.245365] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.134s)
[496.255902] Starting dynamic subtest: drm_test_format_block_width_three_plane
[496.372078] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.126s)
[496.382591] Starting dynamic subtest: drm_test_format_block_width_tiled
[496.509181] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.136s)
[496.520127] Starting dynamic subtest: drm_test_format_block_height_invalid
[496.643013] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.133s)
[496.653187] Starting dynamic subtest: drm_test_format_block_height_one_plane
[496.751593] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.108s)
[496.762074] Starting dynamic subtest: drm_test_format_block_height_two_plane
[496.845358] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.094s)
[496.854561] Starting dynamic subtest: drm_test_format_block_height_three_plane
[497.010541] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.161s)
[497.021094] Starting dynamic subtest: drm_test_format_block_height_tiled
[497.168259] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.157s)
[497.176968] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[497.318117] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.149s)
[497.326951] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[497.453366] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.132s)
[497.462184] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[497.601674] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.148s)
[497.609974] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[497.749562] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.146s)
[497.758150] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[497.890373] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.141s)
[497.898979] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[498.047977] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.157s)
[498.056274] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[498.212304] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.164s)
[498.221118] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[498.357164] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.142s)
[498.359359] Subtest drm_format: SUCCESS (2.567s)
[498.581745] [076/133] (607s left) syncobj_timeline (invalid-signal-illegal-handle)
[498.650307] Starting subtest: invalid-signal-illegal-handle
[498.652650] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[498.780521] [077/133] (607s left) gem_ctx_param (invalid-ctx-get)
[498.841132] Starting subtest: invalid-ctx-get
[498.844243] Subtest invalid-ctx-get: SUCCESS (0.000s)
[498.925787] [078/133] (607s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[499.029434] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[505.581778] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.553s)
[505.722537] [079/133] (600s left) prime_self_import (basic-with_fd_dup)
[505.762646] Starting subtest: basic-with_fd_dup
[505.781434] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[505.916267] [080/133] (600s left) i915_drm_fdinfo (busy-idle-check-all)
[505.955948] Starting subtest: busy-idle-check-all
[505.957881] Starting dynamic subtest: rcs0
[505.962436] Dynamic subtest rcs0: SKIP (0.004s)
[505.971936] Starting dynamic subtest: bcs0
[505.976345] Dynamic subtest bcs0: SKIP (0.000s)
[505.984824] Starting dynamic subtest: vcs0
[505.989017] Dynamic subtest vcs0: SKIP (0.000s)
[505.997464] Starting dynamic subtest: vcs1
[506.001863] Dynamic subtest vcs1: SKIP (0.000s)
[506.010339] Starting dynamic subtest: vecs0
[506.014596] Dynamic subtest vecs0: SKIP (0.000s)
[506.022980] Starting dynamic subtest: ccs0
[506.027375] Dynamic subtest ccs0: SKIP (0.000s)
[506.031599] Subtest busy-idle-check-all: SKIP (0.009s)
[506.068075] [081/133] (600s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[506.209556] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[506.211816] Starting dynamic subtest: pipe-A-eDP-1
[506.234105] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[506.244329] Starting dynamic subtest: pipe-B-eDP-1
[506.265567] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[506.273785] Starting dynamic subtest: pipe-C-eDP-1
[506.295304] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[506.303760] Starting dynamic subtest: pipe-D-eDP-1
[506.325170] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[506.329501] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[508.504455] [082/133] (598s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[508.576122] Starting subtest: map-fixed-invalidate-overlap-busy
[508.580484] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[508.738887] [083/133] (598s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[508.837254] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[508.841582] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[508.942326] [084/133] (597s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[509.048640] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[509.052994] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[509.179802] [085/133] (597s left) gem_create (create-valid-nonaligned)
[509.241955] Starting subtest: create-valid-nonaligned
[509.243891] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[509.441301] [086/133] (597s left) kms_vblank (wait-forked-busy)
[509.539219] Starting subtest: wait-forked-busy
[509.541416] Starting dynamic subtest: pipe-A-eDP-1
[511.415981] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.877s)
[511.430055] Starting dynamic subtest: pipe-D-eDP-1
[513.960775] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.541s)
[513.963131] Subtest wait-forked-busy: SUCCESS (4.423s)
[514.776536] [087/133] (592s left) api_intel_bb (delta-check)
[514.846791] Starting subtest: delta-check
[514.851966] Subtest delta-check: SUCCESS (0.006s)
[515.023876] [088/133] (591s left) kms_chamelium_frames (dp-crc-fast)
[515.415825] Subtest dp-crc-fast: SKIP (0.000s)
[516.276131] [089/133] (590s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[516.379965] Starting subtest: psr-farfromfence-mmap-gtt
[516.384220] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[516.500715] [090/133] (590s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[516.589857] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[521.015424] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.429s)
[522.200339] [091/133] (584s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[522.319051] Starting subtest: 2x-cursor-vs-flip-legacy
[522.323426] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s)
[522.479282] [092/133] (584s left) gem_pxp (reject-modify-context-protection-off-2)
[522.610235] Starting subtest: reject-modify-context-protection-off-2
[522.612510] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[522.744156] [093/133] (584s left) syncobj_timeline (single-wait-available-submitted)
[522.808015] Starting subtest: single-wait-available-submitted
[522.810235] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[522.919747] [094/133] (584s left) kms_cursor_legacy (torture-bo)
[523.045022] Starting subtest: torture-bo
[523.047350] Starting dynamic subtest: pipe-A
[529.496175] Dynamic subtest pipe-A: SUCCESS (6.446s)
[529.508213] Starting dynamic subtest: pipe-B
[537.824566] Dynamic subtest pipe-B: SUCCESS (8.328s)
[537.839455] Starting dynamic subtest: pipe-C
[545.045140] Dynamic subtest pipe-C: SUCCESS (7.219s)
[545.058553] Starting dynamic subtest: pipe-D
[551.889391] Dynamic subtest pipe-D: SUCCESS (6.842s)
[551.903477] Starting dynamic subtest: all-pipes
[559.132112] Dynamic subtest all-pipes: SUCCESS (7.239s)
[559.134634] Subtest torture-bo: SUCCESS (36.084s)
[559.317219] [095/133] (547s left) perf_pmu (init-sema)
[559.356300] Starting subtest: init-sema
[559.358823] Starting dynamic subtest: rcs0
[559.361304] Dynamic subtest rcs0: SUCCESS (0.003s)
[559.376632] Starting dynamic subtest: bcs0
[559.379157] Dynamic subtest bcs0: SUCCESS (0.001s)
[559.390555] Starting dynamic subtest: vcs0
[559.392722] Dynamic subtest vcs0: SUCCESS (0.001s)
[559.401156] Starting dynamic subtest: vcs1
[559.403418] Dynamic subtest vcs1: SUCCESS (0.001s)
[559.411819] Starting dynamic subtest: vecs0
[559.414055] Dynamic subtest vecs0: SUCCESS (0.001s)
[559.422499] Starting dynamic subtest: ccs0
[559.425155] Dynamic subtest ccs0: SUCCESS (0.001s)
[559.427849] Subtest init-sema: SUCCESS (0.011s)
[559.494809] [096/133] (547s left) kms_pm_rpm (system-suspend-idle)
[561.621701] Starting subtest: system-suspend-idle
[580.681564] Subtest system-suspend-idle: SUCCESS (4.244s)
[581.077916] [097/133] (526s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[581.192918] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[587.683371] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.488s)
[587.829642] [098/133] (519s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[587.933671] Starting subtest: random-ccs-data-yf-tiled-ccs
[587.935960] Starting dynamic subtest: pipe-A-eDP-1
[587.960480] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[587.970753] Starting dynamic subtest: pipe-B-eDP-1
[587.993779] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[588.002990] Starting dynamic subtest: pipe-C-eDP-1
[588.026652] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[588.036026] Starting dynamic subtest: pipe-D-eDP-1
[588.060093] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[588.064172] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[590.283106] [099/133] (517s left) kms_draw_crc (fill-fb)
[590.394509] Starting subtest: fill-fb
[590.823955] Subtest fill-fb: SUCCESS (0.431s)
[590.983752] [100/133] (516s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[591.097805] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[591.110940] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[591.215155] [101/133] (516s left) kms_psr (psr2-suspend)
[591.316027] Starting subtest: psr2-suspend
[591.317817] Starting dynamic subtest: eDP-1
[608.927948] Dynamic subtest eDP-1: SUCCESS (2.936s)
[608.930249] Subtest psr2-suspend: SUCCESS (2.937s)
[609.742716] [102/133] (497s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[609.853011] Starting subtest: psr-1p-pri-indfb-multidraw
[632.139693] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.283s)
[632.285443] [103/133] (475s left) kms_cursor_edge_walk (256x256-right-edge)
[632.384638] Starting subtest: 256x256-right-edge
[632.386644] Starting dynamic subtest: pipe-A-eDP-1
[635.829715] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.442s)
[635.840777] Starting dynamic subtest: pipe-D-eDP-1
[638.948615] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.117s)
[638.950545] Subtest 256x256-right-edge: SUCCESS (6.562s)
[639.763579] [104/133] (467s left) gem_ccs (block-copy-compressed)
[639.825033] Starting subtest: block-copy-compressed
[639.829423] Subtest block-copy-compressed: SKIP (0.000s)
[639.953584] [105/133] (467s left) gem_exec_reloc (basic-wc-cpu-active)
[640.018019] Subtest basic-wc-cpu-active: SKIP (0.000s)
[640.095109] [106/133] (467s left) perf (invalid-remove-userspace-config)
[640.200851] Starting subtest: invalid-remove-userspace-config
[640.214124] Subtest invalid-remove-userspace-config: SUCCESS (0.011s)
[640.251581] [107/133] (467s left) gem_lmem_swapping (massive)
[640.290811] Subtest massive: SKIP (0.000s)
[640.329188] [108/133] (467s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[640.418017] Starting subtest: fbc-psr2-cursor-plane-onoff
[640.420238] Starting dynamic subtest: eDP-1
[640.424238] Dynamic subtest eDP-1: SKIP (0.000s)
[640.428438] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[640.538593] [109/133] (467s left) gem_ctx_exec (basic-invalid-context)
[640.599429] Starting subtest: basic-invalid-context
[640.603789] Subtest basic-invalid-context: SUCCESS (0.007s)
[640.739656] [110/133] (467s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs)
[640.829993] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs
[640.831939] Starting dynamic subtest: pipe-A-eDP-1
[640.879182] Dynamic subtest pipe-A-eDP-1: SKIP (0.002s)
[640.890830] Starting dynamic subtest: pipe-B-eDP-1
[640.938240] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[640.947876] Starting dynamic subtest: pipe-C-eDP-1
[640.994031] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[641.003115] Starting dynamic subtest: pipe-D-eDP-1
[641.049468] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[641.053094] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.011s)
[643.129541] [111/133] (464s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[643.238053] Starting subtest: yf-tiled-16bpp-rotate-90
[643.242487] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[643.369119] [112/133] (464s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping)
[643.479183] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping
[643.492063] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[643.565052] [113/133] (464s left) kms_big_fb (linear-8bpp-rotate-90)
[643.672731] Starting subtest: linear-8bpp-rotate-90
[643.693532] Subtest linear-8bpp-rotate-90: SKIP (0.020s)
[643.842659] [114/133] (464s left) kms_chamelium_hpd (dp-hpd-storm)
[644.245334] Subtest dp-hpd-storm: SKIP (0.000s)
[645.024278] [115/133] (463s left) syncobj_timeline (32bits-limit)
[645.093316] Starting subtest: 32bits-limit
[645.095660] Subtest 32bits-limit: SUCCESS (0.001s)
[645.207223] [116/133] (462s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[645.308922] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[645.313270] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[645.418014] [117/133] (462s left) perf_pmu (interrupts)
[645.461962] Starting subtest: interrupts
[646.560016] Subtest interrupts: SUCCESS (1.098s)
[646.701580] [118/133] (461s left) kms_psr (psr-primary-page-flip)
[646.797382] Starting subtest: psr-primary-page-flip
[646.799604] Starting dynamic subtest: eDP-1
[648.539508] Dynamic subtest eDP-1: SUCCESS (1.741s)
[648.541794] Subtest psr-primary-page-flip: SUCCESS (1.742s)
[649.416772] [119/133] (458s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[649.513584] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[649.515810] Starting dynamic subtest: pipe-A-eDP-1
[650.304032] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.781s)
[650.314766] Starting dynamic subtest: pipe-B-eDP-1
[651.815185] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.510s)
[651.825596] Starting dynamic subtest: pipe-C-eDP-1
[653.322382] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.504s)
[653.332787] Starting dynamic subtest: pipe-D-eDP-1
[654.848373] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.526s)
[654.850632] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.327s)
[656.044606] [120/133] (452s left) prime_mmap (test_correct_cpu_write)
[656.107868] Starting subtest: test_correct_cpu_write
[656.109935] Starting dynamic subtest: test_correct_cpu_write-smem
[656.111984] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[656.114027] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[656.222379] [121/133] (452s left) gem_exec_reloc (basic-range-active)
[656.278828] Subtest basic-range-active: SKIP (0.000s)
[656.342769] [122/133] (452s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[656.446979] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[656.451327] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[656.524708] [123/133] (451s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[656.656608] Starting subtest: cursorB-vs-flipB-legacy
[656.661076] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[656.822368] [124/133] (451s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[656.917332] Starting subtest: flip-vs-dpms-off-vs-modeset
[656.919636] Starting dynamic subtest: A-eDP1
[660.545817] Dynamic subtest A-eDP1: SUCCESS (3.626s)
[660.555571] Starting dynamic subtest: B-eDP1
[663.744370] Dynamic subtest B-eDP1: SUCCESS (3.195s)
[663.754071] Starting dynamic subtest: C-eDP1
[666.853205] Dynamic subtest C-eDP1: SUCCESS (3.106s)
[666.863264] Starting dynamic subtest: D-eDP1
[669.974267] Dynamic subtest D-eDP1: SUCCESS (3.117s)
[669.977093] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.053s)
[670.776352] [125/133] (437s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[670.875762] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[670.879939] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[670.990394] [126/133] (437s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[671.086485] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[671.088760] Starting dynamic subtest: pipe-A
[671.108527] Dynamic subtest pipe-A: SKIP (0.017s)
[671.118301] Starting dynamic subtest: pipe-B
[671.140470] Dynamic subtest pipe-B: SKIP (0.029s)
[671.150094] Starting dynamic subtest: pipe-C
[671.169658] Dynamic subtest pipe-C: SKIP (0.029s)
[671.179352] Starting dynamic subtest: pipe-D
[671.199792] Dynamic subtest pipe-D: SKIP (0.030s)
[671.203584] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.108s)
[671.328588] [127/133] (437s left) kms_cursor_crc (cursor-onscreen-64x21)
[671.523105] Starting subtest: cursor-onscreen-64x21
[671.567496] Subtest cursor-onscreen-64x21: SKIP (0.039s)
[671.838430] [128/133] (436s left) core_setmaster (master-drop-set-user)
[671.941834] Starting subtest: master-drop-set-user
[671.953988] Subtest master-drop-set-user: SUCCESS (0.015s)
[671.991331] [129/133] (436s left) prime_vgem (fence-wait)
[672.075846] Starting subtest: fence-wait
[672.077778] Starting dynamic subtest: rcs0
[673.134973] Dynamic subtest rcs0: SUCCESS (1.057s)
[673.146997] Starting dynamic subtest: bcs0
[674.210651] Dynamic subtest bcs0: SUCCESS (1.074s)
[674.222583] Starting dynamic subtest: vcs0
[675.260799] Dynamic subtest vcs0: SUCCESS (1.049s)
[675.272781] Starting dynamic subtest: vcs1
[676.303222] Dynamic subtest vcs1: SUCCESS (1.040s)
[676.320242] Starting dynamic subtest: vecs0
[677.346526] Dynamic subtest vecs0: SUCCESS (1.040s)
[677.362491] Starting dynamic subtest: ccs0
[678.390815] Dynamic subtest ccs0: SUCCESS (1.041s)
[678.393637] Subtest fence-wait: SUCCESS (6.313s)
[678.533273] [130/133] (430s left) gem_mmap_wc (write-wc-read-gtt)
[678.600463] Subtest write-wc-read-gtt: SKIP (0.000s)
[678.658101] [131/133] (430s left) kms_psr (pr-cursor-blt)
[678.764285] Starting subtest: pr-cursor-blt
[678.768640] Subtest pr-cursor-blt: SKIP (0.000s)
[678.847763] [132/133] (429s left) gen7_exec_parse (basic-allowed)
[678.910537] Subtest basic-allowed: SKIP (0.000s)
[678.986788] [133/133] (429s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[679.083805] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[679.086107] Starting dynamic subtest: pipe-A-eDP-1
[690.390825] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.310s)
[690.400586] Starting dynamic subtest: pipe-B-eDP-1
[702.378608] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.982s)
[702.380925] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.295s)
[703.251004] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_render_copy
results: parsing output: 2/ for test: gem_exec_gttfill
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_pm_rpm
results: parsing output: 5/ for test: gem_exec_schedule
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_properties
results: parsing output: 8/ for test: perf
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_addfb_basic
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: gem_workarounds
results: parsing output: 14/ for test: gem_ctx_persistence
results: parsing output: 15/ for test: gem_exec_schedule
results: parsing output: 16/ for test: gem_exec_fence
results: parsing output: 17/ for test: sysfs_heartbeat_interval
results: parsing output: 18/ for test: gem_caching
results: parsing output: 19/ for test: syncobj_wait
results: parsing output: 20/ for test: gem_mmap_offset
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: gem_spin_batch
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: i915_pm_rpm
results: parsing output: 25/ for test: perf
results: parsing output: 26/ for test: gem_exec_flush
results: parsing output: 27/ for test: syncobj_wait
results: parsing output: 28/ for test: kms_flip
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_exec_balancer
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_invalid_mode
results: parsing output: 33/ for test: kms_big_fb
results: parsing output: 34/ for test: kms_addfb_basic
results: parsing output: 35/ for test: gen7_exec_parse
results: parsing output: 36/ for test: kms_async_flips
results: parsing output: 37/ for test: syncobj_wait
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_rotation_crc
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_legacy_colorkey
results: parsing output: 42/ for test: kms_plane_alpha_blend
results: parsing output: 43/ for test: gem_mmap
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_exec_schedule
results: parsing output: 46/ for test: gem_exec_async
results: parsing output: 47/ for test: core_auth
results: parsing output: 48/ for test: kms_color
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_fence
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_plane_scaling
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: gem_exec_capture
results: parsing output: 55/ for test: kms_plane
results: parsing output: 56/ for test: kms_flip_scaled_crc
results: parsing output: 57/ for test: i915_query
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_content_protection
results: parsing output: 60/ for test: gem_ctx_persistence
results: parsing output: 61/ for test: gem_exec_flush
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: gem_mmap_gtt
results: parsing output: 64/ for test: kms_psr2_sf
results: parsing output: 65/ for test: kms_flip
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: gem_softpin
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: gem_exec_params
results: parsing output: 70/ for test: kms_dsc
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: i915_hangman
results: parsing output: 73/ for test: kms_flip_scaled_crc
results: parsing output: 74/ for test: kms_selftest
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: gem_ctx_param
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: prime_self_import
results: parsing output: 79/ for test: i915_drm_fdinfo
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: gem_userptr_blits
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_create
results: parsing output: 85/ for test: kms_vblank
results: parsing output: 86/ for test: api_intel_bb
results: parsing output: 87/ for test: kms_chamelium_frames
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_cursor_legacy
results: parsing output: 91/ for test: gem_pxp
results: parsing output: 92/ for test: syncobj_timeline
results: parsing output: 93/ for test: kms_cursor_legacy
results: parsing output: 94/ for test: perf_pmu
results: parsing output: 95/ for test: kms_pm_rpm
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_draw_crc
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_cursor_edge_walk
results: parsing output: 103/ for test: gem_ccs
results: parsing output: 104/ for test: gem_exec_reloc
results: parsing output: 105/ for test: perf
results: parsing output: 106/ for test: gem_lmem_swapping
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: gem_ctx_exec
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: kms_plane
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_chamelium_hpd
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: perf_pmu
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: kms_ccs
results: parsing output: 119/ for test: prime_mmap
results: parsing output: 120/ for test: gem_exec_reloc
results: parsing output: 121/ for test: kms_psr2_sf
results: parsing output: 122/ for test: kms_cursor_legacy
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_plane_scaling
results: parsing output: 126/ for test: kms_cursor_crc
results: parsing output: 127/ for test: core_setmaster
results: parsing output: 128/ for test: prime_vgem
results: parsing output: 129/ for test: gem_mmap_wc
results: parsing output: 130/ for test: kms_psr
results: parsing output: 131/ for test: gen7_exec_parse
results: parsing output: 132/ for test: kms_psr2_sf
Done.