[57.336529] Initializing watchdogs
[57.336576]   /dev/watchdog0
[57.348735] [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]
[57.352660] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.354649] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.357950] [001/128] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[57.530531] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[60.857552] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (3.304s)
[60.973350] [002/128] (956s left) gem_mmap_gtt (coherency)
[61.028655] Subtest coherency: SKIP (0.000s)
[61.077382] [003/128] (956s left) i915_pm_rpm (debugfs-forcewake-user)
[63.124855] Starting subtest: debugfs-forcewake-user
[74.511276] Subtest debugfs-forcewake-user: SUCCESS (11.361s)
[74.854170] [004/128] (943s left) kms_plane (plane-position-covered)
[74.963919] Starting subtest: plane-position-covered
[75.476046] Starting dynamic subtest: pipe-A-plane-1
[76.755357] Dynamic subtest pipe-A-plane-1: SUCCESS (1.277s)
[76.761521] Starting dynamic subtest: pipe-A-plane-2
[78.028013] Dynamic subtest pipe-A-plane-2: SUCCESS (1.271s)
[78.034954] Starting dynamic subtest: pipe-A-plane-3
[79.262710] Dynamic subtest pipe-A-plane-3: SUCCESS (1.235s)
[79.270724] Starting dynamic subtest: pipe-A-plane-4
[80.478799] Dynamic subtest pipe-A-plane-4: SUCCESS (1.216s)
[80.486606] Starting dynamic subtest: pipe-A-plane-5
[80.513974] Dynamic subtest pipe-A-plane-5: SUCCESS (0.034s)
[81.630450] Starting dynamic subtest: pipe-B-plane-1
[82.842940] Dynamic subtest pipe-B-plane-1: SUCCESS (1.219s)
[82.850906] Starting dynamic subtest: pipe-B-plane-2
[84.067995] Dynamic subtest pipe-B-plane-2: SUCCESS (1.224s)
[84.075949] Starting dynamic subtest: pipe-B-plane-3
[85.298600] Dynamic subtest pipe-B-plane-3: SUCCESS (1.230s)
[85.306322] Starting dynamic subtest: pipe-B-plane-4
[86.523089] Dynamic subtest pipe-B-plane-4: SUCCESS (1.223s)
[86.530776] Starting dynamic subtest: pipe-B-plane-5
[86.557879] Dynamic subtest pipe-B-plane-5: SUCCESS (0.036s)
[86.560083] Subtest plane-position-covered: SUCCESS (11.585s)
[87.321067] [005/128] (930s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[87.384033] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[87.386476] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[87.490511] [006/128] (930s left) drm_fdinfo (isolation)
[87.527973] Starting subtest: isolation
[87.530219] Starting dynamic subtest: rcs0
[87.551561] Dynamic subtest rcs0: SKIP (0.022s)
[87.557614] Starting dynamic subtest: bcs0
[87.564176] Dynamic subtest bcs0: SKIP (0.012s)
[87.572075] Starting dynamic subtest: vcs0
[87.576474] Dynamic subtest vcs0: SKIP (0.000s)
[87.582598] Starting dynamic subtest: vcs1
[87.586717] Dynamic subtest vcs1: SKIP (0.000s)
[87.592853] Starting dynamic subtest: vecs0
[87.597343] Dynamic subtest vecs0: SKIP (0.000s)
[87.603384] Starting dynamic subtest: ccs0
[87.607832] Dynamic subtest ccs0: SKIP (0.000s)
[87.612200] Subtest isolation: SKIP (0.035s)
[87.638216] [007/128] (930s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[87.748406] Starting subtest: yf-tiled-32bpp-rotate-0
[87.752596] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[87.847680] [008/128] (930s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[87.953667] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[87.957750] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[88.059300] [009/128] (930s left) gem_ctx_param (invalid-get-engines)
[88.111312] Starting subtest: invalid-get-engines
[88.113563] Subtest invalid-get-engines: SUCCESS (0.002s)
[88.199583] [010/128] (929s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[88.296606] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[88.298495] Starting dynamic subtest: pipe-A-eDP-1
[88.315258] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[88.321710] Starting dynamic subtest: pipe-B-eDP-1
[88.337743] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[88.343780] Starting dynamic subtest: pipe-C-eDP-1
[88.359696] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[88.365987] Starting dynamic subtest: pipe-D-eDP-1
[88.382461] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[88.384230] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[90.474773] [011/128] (927s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[90.584551] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[96.978980] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.397s)
[97.094016] [012/128] (921s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[97.153416] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[97.155465] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[97.237373] [013/128] (921s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[97.338283] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[97.342330] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[97.427036] [014/128] (920s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[97.519427] Starting subtest: fbc-tiling-linear
[100.276340] Subtest fbc-tiling-linear: SUCCESS (2.760s)
[100.404851] [015/128] (917s left) gem_mmap_offset (close-race)
[100.469348] Starting subtest: close-race
[120.603725] Subtest close-race: SUCCESS (20.131s)
[120.744931] [016/128] (897s left) kms_prime (basic-modeset-hybrid)
[122.860161] Subtest basic-modeset-hybrid: SKIP (0.000s)
[122.945946] [FACT kms_prime (basic-modeset-hybrid)] new: kernel.kmod_is_loaded.amdgpu: true
[122.946705] [FACT kms_prime (basic-modeset-hybrid)] new: kernel.kmod_is_loaded.vgem: true
[122.946836] [FACT kms_prime (basic-modeset-hybrid)] new: kernel.kmod_is_loaded.xe: true
[122.949774] [017/128] (895s left) gem_exec_fence (invalid-timeline-fence-array)
[123.467316] Starting subtest: invalid-timeline-fence-array
[123.476390] Subtest invalid-timeline-fence-array: SUCCESS (0.007s)
[124.997738] [018/128] (893s left) kms_content_protection (lic-type-1)
[125.178363] Starting subtest: lic-type-1
[128.567609] Subtest lic-type-1: SKIP (3.387s)
[129.727418] [019/128] (888s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[130.114072] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[130.929046] [020/128] (887s left) perf_pmu (busy-check-all)
[130.969528] Starting subtest: busy-check-all
[130.971621] Starting dynamic subtest: rcs0
[131.525839] Dynamic subtest rcs0: SUCCESS (0.557s)
[131.536458] Starting dynamic subtest: bcs0
[132.077500] Dynamic subtest bcs0: SUCCESS (0.551s)
[132.087529] Starting dynamic subtest: vcs0
[133.931700] Dynamic subtest vcs0: SUCCESS (1.851s)
[133.942426] Starting dynamic subtest: vcs1
[135.916111] Dynamic subtest vcs1: SUCCESS (1.984s)
[135.927094] Starting dynamic subtest: vecs0
[137.963913] Dynamic subtest vecs0: SUCCESS (2.047s)
[137.974336] Starting dynamic subtest: ccs0
[138.516477] Dynamic subtest ccs0: SUCCESS (0.553s)
[138.519037] Subtest busy-check-all: SUCCESS (7.548s)
[138.631884] [021/128] (879s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[138.732077] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[138.734384] Starting dynamic subtest: pipe-A-eDP-1
[138.760098] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[138.767060] Starting dynamic subtest: pipe-B-eDP-1
[138.791160] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[138.798233] Starting dynamic subtest: pipe-C-eDP-1
[138.822610] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[138.831519] Starting dynamic subtest: pipe-D-eDP-1
[138.855111] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[138.859100] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.002s)
[140.895282] [022/128] (877s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[141.151551] Starting subtest: cursor-rapid-movement-512x170
[141.155926] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[141.311998] [023/128] (877s left) syncobj_wait (multi-wait-submitted-signaled)
[141.378274] Starting subtest: multi-wait-submitted-signaled
[141.380323] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[141.483156] [024/128] (877s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[141.583883] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[141.586060] Starting dynamic subtest: pipe-A-default-mode
[141.881091] Dynamic subtest pipe-A-default-mode: SKIP (0.294s)
[141.885712] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.295s)
[142.725419] [025/128] (875s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[147.678658] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[147.680987] Starting dynamic subtest: pipe-A-eDP-1
[148.485877] Dynamic subtest pipe-A-eDP-1: SKIP (0.805s)
[148.495073] Starting dynamic subtest: pipe-B-eDP-1
[149.712015] Dynamic subtest pipe-B-eDP-1: SKIP (1.225s)
[149.716709] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (2.033s)
[150.882825] [026/128] (867s left) kms_atomic (crtc-invalid-params)
[150.983067] Starting subtest: crtc-invalid-params
[150.985413] Starting dynamic subtest: pipe-A-eDP-1
[151.351677] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.366s)
[151.354072] Subtest crtc-invalid-params: SUCCESS (0.367s)
[152.207316] [027/128] (866s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[152.271642] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[152.329728] [028/128] (866s left) kms_psr (fbc-psr2-cursor-plane-move)
[152.425217] Starting subtest: fbc-psr2-cursor-plane-move
[152.427547] Starting dynamic subtest: eDP-1
[152.432104] Dynamic subtest eDP-1: SKIP (0.000s)
[152.436571] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[152.491845] [029/128] (866s left) gem_ctx_persistence (userptr)
[152.545784] Starting subtest: userptr
[152.734118] Subtest userptr: SUCCESS (0.188s)
[152.845933] [030/128] (866s left) kms_pm_backlight (brightness-with-dpms)
[152.944344] Starting subtest: brightness-with-dpms
[153.009462] Starting dynamic subtest: eDP-1
[155.696061] Dynamic subtest eDP-1: SUCCESS (2.685s)
[155.698650] Subtest brightness-with-dpms: SUCCESS (2.751s)
[156.555375] [031/128] (862s left) gem_set_tiling_vs_gtt
[156.679702] [032/128] (862s left) gem_exec_whisper (basic-fds-priority-all)
[156.748772] Starting subtest: basic-fds-priority-all
[178.898928] Subtest basic-fds-priority-all: SUCCESS (22.147s)
[179.069285] [033/128] (839s left) gem_exec_store (cachelines)
[179.143824] Starting subtest: cachelines
[179.145859] Starting dynamic subtest: rcs0
[179.272073] Dynamic subtest rcs0: SUCCESS (0.130s)
[179.282887] Starting dynamic subtest: bcs0
[179.338194] Dynamic subtest bcs0: SUCCESS (0.065s)
[179.348189] Starting dynamic subtest: vcs0
[179.399161] Dynamic subtest vcs0: SUCCESS (0.060s)
[179.406903] Starting dynamic subtest: vcs1
[179.458130] Dynamic subtest vcs1: SUCCESS (0.058s)
[179.465327] Starting dynamic subtest: vecs0
[179.518979] Dynamic subtest vecs0: SUCCESS (0.060s)
[179.526738] Starting dynamic subtest: ccs0
[179.583210] Dynamic subtest ccs0: SUCCESS (0.063s)
[179.585809] Subtest cachelines: SUCCESS (0.442s)
[179.759434] [034/128] (839s left) perf_pmu (most-busy-idle-check-all)
[179.803453] Starting subtest: most-busy-idle-check-all
[179.805717] Starting dynamic subtest: rcs0
[180.906737] Dynamic subtest rcs0: SUCCESS (1.101s)
[180.917424] Starting dynamic subtest: bcs0
[182.956592] Dynamic subtest bcs0: SUCCESS (2.048s)
[182.967083] Starting dynamic subtest: vcs0
[184.937854] Dynamic subtest vcs0: SUCCESS (1.980s)
[184.947789] Starting dynamic subtest: vcs1
[186.922704] Dynamic subtest vcs1: SUCCESS (1.983s)
[186.932791] Starting dynamic subtest: vecs0
[188.908351] Dynamic subtest vecs0: SUCCESS (1.984s)
[188.918157] Starting dynamic subtest: ccs0
[190.954700] Dynamic subtest ccs0: SUCCESS (2.045s)
[190.958042] Subtest most-busy-idle-check-all: SUCCESS (11.150s)
[191.060455] [035/128] (827s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[191.114806] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[191.222440] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[191.294332] [036/128] (827s left) kms_psr (psr-sprite-render)
[191.388439] Starting subtest: psr-sprite-render
[191.390767] Starting dynamic subtest: eDP-1
[193.174321] Dynamic subtest eDP-1: SUCCESS (1.785s)
[193.176658] Subtest psr-sprite-render: SUCCESS (1.786s)
[194.001486] [037/128] (825s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[194.104721] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[197.657077] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (3.557s)
[197.822476] [038/128] (821s left) kms_color (degamma)
[197.912667] Starting subtest: degamma
[197.914738] Starting dynamic subtest: pipe-A-eDP-1
[198.627048] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.711s)
[198.636068] Starting dynamic subtest: pipe-B-eDP-1
[200.027384] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.400s)
[200.035963] Starting dynamic subtest: pipe-C-eDP-1
[201.398363] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.372s)
[201.407395] Starting dynamic subtest: pipe-D-eDP-1
[202.743673] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.343s)
[202.746330] Subtest degamma: SUCCESS (4.829s)
[203.528336] [039/128] (815s left) perf (invalid-oa-metric-set-id)
[203.635069] Starting subtest: invalid-oa-metric-set-id
[203.651107] Subtest invalid-oa-metric-set-id: SUCCESS (0.016s)
[203.682423] [040/128] (815s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[203.791237] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[205.006329] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (1.217s)
[205.373090] [041/128] (813s left) gem_exec_flush (basic-batch-kernel-default-uc)
[205.439801] Starting subtest: basic-batch-kernel-default-uc
[212.237415] Subtest basic-batch-kernel-default-uc: SUCCESS (6.798s)
[212.422893] [042/128] (806s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[212.485110] Starting subtest: single-wait-for-submit-unsubmitted
[212.590076] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[212.660567] [043/128] (806s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[212.765397] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[212.769659] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[212.854563] [044/128] (806s left) api_intel_allocator (gem-pool)
[212.909074] Starting subtest: gem-pool
[212.913733] Subtest gem-pool: SUCCESS (0.007s)
[213.033521] [045/128] (806s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[213.098933] Starting subtest: invalid-bsd1-flag-on-vebox
[213.101061] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[213.205122] [046/128] (806s left) gem_ctx_param (invalid-param-get)
[213.262386] Starting subtest: invalid-param-get
[213.264875] Subtest invalid-param-get: SUCCESS (0.001s)
[213.373176] [047/128] (806s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[213.489512] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[224.678642] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.190s)
[225.553554] [048/128] (793s left) i915_pm_rpm (sysfs-read)
[227.081374] Starting subtest: sysfs-read
[228.242307] Subtest sysfs-read: SUCCESS (1.157s)
[228.583030] [049/128] (790s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-render)
[228.683062] Starting subtest: psr-1p-offscren-pri-indfb-draw-render
[233.503090] Subtest psr-1p-offscren-pri-indfb-draw-render: SUCCESS (4.823s)
[233.644649] [050/128] (785s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[238.781751] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[238.784141] Starting dynamic subtest: pipe-A-eDP-1
[245.373073] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.588s)
[245.382380] Starting dynamic subtest: pipe-B-eDP-1
[251.901709] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.528s)
[251.903897] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (13.120s)
[252.769583] [051/128] (766s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[252.871688] Starting subtest: stress-xrgb8888-ytiled
[252.876266] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[252.955927] [052/128] (766s left) kms_flip (blocking-absolute-wf_vblank)
[253.058108] Starting subtest: blocking-absolute-wf_vblank
[253.062877] Starting dynamic subtest: A-eDP1
[261.828244] Dynamic subtest A-eDP1: SUCCESS (8.767s)
[261.837322] Starting dynamic subtest: B-eDP1
[270.554896] Dynamic subtest B-eDP1: SUCCESS (8.725s)
[270.565905] Starting dynamic subtest: C-eDP1
[279.275583] Dynamic subtest C-eDP1: SUCCESS (8.718s)
[279.283608] Starting dynamic subtest: D-eDP1
[288.003606] Dynamic subtest D-eDP1: SUCCESS (8.726s)
[288.006086] Subtest blocking-absolute-wf_vblank: SUCCESS (34.946s)
[289.208393] [053/128] (730s left) gem_fenced_exec_thrash (no-spare-fences)
[289.269517] Subtest no-spare-fences: SKIP (0.000s)
[289.349847] [054/128] (730s left) kms_invalid_mode (int-max-clock)
[289.454019] Starting subtest: int-max-clock
[289.456328] Starting dynamic subtest: pipe-A-eDP-1
[289.734825] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.281s)
[289.745296] Starting dynamic subtest: pipe-B-eDP-1
[289.747189] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[289.754489] Starting dynamic subtest: pipe-C-eDP-1
[289.756748] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[289.764130] Starting dynamic subtest: pipe-D-eDP-1
[289.766273] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[289.768458] Subtest int-max-clock: SUCCESS (0.286s)
[290.532224] [055/128] (729s left) i915_suspend (debugfs-reader)
[290.596801] Starting subtest: debugfs-reader
[308.540387] Subtest debugfs-reader: SUCCESS (3.147s)
[308.597116] [056/128] (725s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[308.701340] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[313.921448] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (5.128s)
[314.043115] [057/128] (720s left) gem_wait (busy)
[314.106085] Starting subtest: busy
[314.108411] Starting dynamic subtest: all-engines
[315.689586] Dynamic subtest all-engines: SUCCESS (1.564s)
[315.700733] Starting dynamic subtest: rcs0
[316.230281] Dynamic subtest rcs0: SUCCESS (0.534s)
[316.241712] Starting dynamic subtest: bcs0
[316.768721] Dynamic subtest bcs0: SUCCESS (0.533s)
[316.779373] Starting dynamic subtest: vcs0
[318.697355] Dynamic subtest vcs0: SUCCESS (1.911s)
[318.708579] Starting dynamic subtest: vcs1
[320.682165] Dynamic subtest vcs1: SUCCESS (1.970s)
[320.692956] Starting dynamic subtest: vecs0
[322.729545] Dynamic subtest vecs0: SUCCESS (2.035s)
[322.742611] Starting dynamic subtest: ccs0
[323.272273] Dynamic subtest ccs0: SUCCESS (0.539s)
[323.274824] Subtest busy: SUCCESS (9.097s)
[323.461871] [058/128] (711s left) syncobj_timeline (etime-single-wait-all-submitted)
[323.511412] Starting subtest: etime-single-wait-all-submitted
[323.614109] Subtest etime-single-wait-all-submitted: SUCCESS (0.101s)
[323.686654] [059/128] (711s left) kms_big_fb (linear-64bpp-rotate-90)
[323.796267] Starting subtest: linear-64bpp-rotate-90
[323.817449] Subtest linear-64bpp-rotate-90: SKIP (0.018s)
[323.935296] [060/128] (710s left) gem_exec_balancer (full-pulse)
[323.996499] Starting subtest: full-pulse
[328.458207] Subtest full-pulse: SUCCESS (4.448s)
[328.785793] [061/128] (706s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[328.890168] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[328.894784] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[328.995206] [062/128] (705s left) kms_addfb_basic (bad-pitch-1024)
[329.054425] Starting subtest: bad-pitch-1024
[329.057073] Subtest bad-pitch-1024: SUCCESS (0.000s)
[329.206635] [063/128] (705s left) kms_plane_scaling (planes-downscale-factor-0-25)
[329.310395] Starting subtest: planes-downscale-factor-0-25
[329.312692] Starting dynamic subtest: pipe-A
[329.327904] Dynamic subtest pipe-A: SKIP (0.011s)
[329.336926] Starting dynamic subtest: pipe-B
[329.349366] Dynamic subtest pipe-B: SKIP (0.020s)
[329.357631] Starting dynamic subtest: pipe-C
[329.370074] Dynamic subtest pipe-C: SKIP (0.020s)
[329.385011] Starting dynamic subtest: pipe-D
[329.394231] Dynamic subtest pipe-D: SKIP (0.020s)
[329.400428] Subtest planes-downscale-factor-0-25: SKIP (0.074s)
[329.501290] [064/128] (705s left) kms_psr (fbc-psr-sprite-plane-onoff)
[329.595284] Starting subtest: fbc-psr-sprite-plane-onoff
[329.597409] Starting dynamic subtest: eDP-1
[329.601541] Dynamic subtest eDP-1: SKIP (0.000s)
[329.605828] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[329.660115] [065/128] (705s left) gem_create (create-ext-placement-all)
[329.825764] Starting subtest: create-ext-placement-all
[329.828365] Subtest create-ext-placement-all: SUCCESS (0.000s)
[329.938856] [066/128] (705s left) perf (disabled-read-error)
[330.019165] Starting subtest: disabled-read-error
[330.044775] Subtest disabled-read-error: SUCCESS (0.025s)
[330.081752] [067/128] (705s left) kms_psr (pr-sprite-render)
[330.183167] Starting subtest: pr-sprite-render
[330.187433] Subtest pr-sprite-render: SKIP (0.000s)
[330.249296] [068/128] (704s left) gem_mmap_gtt (cpuset-basic-small-copy)
[330.324771] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[330.371131] [069/128] (704s left) kms_flip (flip-vs-suspend)
[330.472376] Starting subtest: flip-vs-suspend
[330.474555] Starting dynamic subtest: A-eDP1
[379.500501] Dynamic subtest A-eDP1: SUCCESS (4.438s)
[379.514971] Starting dynamic subtest: B-eDP1
[428.499820] Dynamic subtest B-eDP1: SUCCESS (4.311s)
[428.513123] Starting dynamic subtest: C-eDP1
[477.512871] Dynamic subtest C-eDP1: SUCCESS (4.293s)
[477.526838] Starting dynamic subtest: D-eDP1
[526.492884] Dynamic subtest D-eDP1: SUCCESS (4.283s)
[526.515009] Subtest flip-vs-suspend: SUCCESS (17.335s)
[527.662371] [070/128] (686s left) gem_mmap_gtt (big-copy-odd)
[527.724052] Subtest big-copy-odd: SKIP (0.000s)
[527.787477] [071/128] (686s left) vgem_basic (dmabuf-fence)
[527.827130] Starting subtest: dmabuf-fence
[527.829433] Subtest dmabuf-fence: SUCCESS (0.001s)
[527.888762] [072/128] (686s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[527.995361] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[532.847191] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.772s)
[532.992834] [073/128] (681s left) kms_flip (flip-vs-rmfb-interruptible)
[533.106088] Starting subtest: flip-vs-rmfb-interruptible
[533.108572] Starting dynamic subtest: A-eDP1
[541.822812] Dynamic subtest A-eDP1: SUCCESS (8.652s)
[541.832110] Starting dynamic subtest: B-eDP1
[550.505915] Dynamic subtest B-eDP1: SUCCESS (8.658s)
[550.516868] Starting dynamic subtest: C-eDP1
[559.130018] Dynamic subtest C-eDP1: SUCCESS (8.615s)
[559.141716] Starting dynamic subtest: D-eDP1
[567.789767] Dynamic subtest D-eDP1: SUCCESS (8.660s)
[567.791912] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.592s)
[568.926035] [074/128] (645s left) gem_exec_schedule (semaphore-power)
[569.111599] Subtest semaphore-power: SKIP (0.000s)
[569.283095] [075/128] (645s left) syncobj_timeline (single-wait-signaled)
[569.343238] Starting subtest: single-wait-signaled
[569.345899] Subtest single-wait-signaled: SUCCESS (0.000s)
[569.424234] [076/128] (644s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[569.481597] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[569.486736] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[569.659452] [077/128] (644s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt)
[569.767985] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt
[569.772549] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[569.865419] [078/128] (644s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[569.970589] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[569.975879] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[570.061050] [079/128] (644s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[570.158097] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[570.163122] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[570.262676] [080/128] (644s left) kms_cursor_edge_walk (64x64-right-edge)
[570.359539] Starting subtest: 64x64-right-edge
[570.362246] Starting dynamic subtest: pipe-A-eDP-1
[573.782393] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.420s)
[573.792009] Starting dynamic subtest: pipe-D-eDP-1
[576.911025] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.125s)
[576.913378] Subtest 64x64-right-edge: SUCCESS (6.549s)
[577.703876] [081/128] (636s left) gem_eio (in-flight-1us)
[577.913220] Starting subtest: in-flight-1us
[585.857502] Subtest in-flight-1us: SUCCESS (7.940s)
[585.977678] [082/128] (628s left) gen9_exec_parse (bb-large)
[586.039078] Subtest bb-large: SKIP (0.000s)
[586.105394] [083/128] (628s left) perf_pmu (interrupts)
[586.154271] Starting subtest: interrupts
[587.222852] Subtest interrupts: SUCCESS (1.078s)
[587.345202] [084/128] (627s left) perf_pmu (all-busy-idle-check-all)
[587.388203] Starting subtest: all-busy-idle-check-all
[589.316268] Subtest all-busy-idle-check-all: SUCCESS (1.929s)
[589.439718] [085/128] (625s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[589.505269] Starting subtest: yf-tiled-to-vebox-yf-tiled
[589.510267] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[589.699739] [086/128] (625s left) gem_ccs (block-copy-uncompressed)
[589.761660] Starting subtest: block-copy-uncompressed
[589.764714] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[589.789900] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.027s)
[589.801759] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[589.815334] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.025s)
[589.825198] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[589.838240] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.022s)
[589.848599] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[589.860234] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.022s)
[589.863361] Subtest block-copy-uncompressed: SUCCESS (0.099s)
[589.994649] [087/128] (624s left) gem_pxp (create-protected-buffer)
[590.124235] Starting subtest: create-protected-buffer
[590.126793] Subtest create-protected-buffer: SUCCESS (0.000s)
[590.249424] [088/128] (624s left) gem_eio (context-create)
[590.442099] Starting subtest: context-create
[590.718032] Subtest context-create: SUCCESS (0.275s)
[590.858189] [089/128] (623s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[590.952157] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[590.954618] Starting dynamic subtest: pipe-A-eDP-1
[590.978012] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[590.987591] Starting dynamic subtest: pipe-B-eDP-1
[591.010202] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[591.018773] Starting dynamic subtest: pipe-C-eDP-1
[591.040861] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[591.048874] Starting dynamic subtest: pipe-D-eDP-1
[591.070648] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[591.075077] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[593.057402] [090/128] (621s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[593.109172] Starting subtest: multi-wait-all-for-submit-signaled
[593.111587] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[593.191239] [091/128] (621s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[593.298115] Starting subtest: y-tiled-32bpp-rotate-0
[593.303130] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[593.403854] [092/128] (621s left) syncobj_wait (invalid-reset-one-illegal-handle)
[593.471342] Starting subtest: invalid-reset-one-illegal-handle
[593.474283] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[593.566728] [093/128] (621s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[593.661215] Starting subtest: invalidate-primary-flip-overlay
[604.483293] Subtest invalidate-primary-flip-overlay: SUCCESS (10.819s)
[604.602961] [094/128] (610s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[604.705404] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[604.709799] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[605.785165] [095/128] (609s left) kms_cursor_crc (cursor-onscreen-512x170)
[606.070326] Starting subtest: cursor-onscreen-512x170
[606.075995] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[606.227129] [096/128] (608s left) kms_vblank (wait-idle-hang)
[606.324091] Starting subtest: wait-idle-hang
[606.326810] Starting dynamic subtest: pipe-A-eDP-1
[608.253783] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.927s)
[608.269911] Starting dynamic subtest: pipe-D-eDP-1
[610.750221] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.491s)
[610.753141] Subtest wait-idle-hang: SUCCESS (4.423s)
[611.541951] [097/128] (603s left) kms_busy (basic)
[611.646652] Starting subtest: basic
[611.649097] Starting dynamic subtest: flip
[612.283625] Dynamic subtest flip: SUCCESS (0.633s)
[612.294255] Starting dynamic subtest: modeset
[615.669523] Dynamic subtest modeset: SUCCESS (3.387s)
[615.672077] Subtest basic: SUCCESS (4.021s)
[616.457833] [098/128] (598s left) kms_psr (fbc-psr2-suspend)
[616.559980] Starting subtest: fbc-psr2-suspend
[616.562486] Starting dynamic subtest: eDP-1
[616.567966] Dynamic subtest eDP-1: SKIP (0.000s)
[616.573086] Subtest fbc-psr2-suspend: SKIP (0.001s)
[616.628689] [099/128] (598s left) gem_exec_schedule (implicit-read-write)
[616.708188] Starting subtest: implicit-read-write
[616.710965] Starting dynamic subtest: rcs0
[616.811332] Dynamic subtest rcs0: SUCCESS (0.116s)
[616.823621] Starting dynamic subtest: vcs0
[616.892377] Dynamic subtest vcs0: SUCCESS (0.081s)
[616.903991] Starting dynamic subtest: vcs1
[616.964661] Dynamic subtest vcs1: SUCCESS (0.072s)
[616.975947] Starting dynamic subtest: vecs0
[617.038599] Dynamic subtest vecs0: SUCCESS (0.073s)
[617.050535] Starting dynamic subtest: ccs0
[617.121949] Dynamic subtest ccs0: SUCCESS (0.082s)
[617.125008] Subtest implicit-read-write: SUCCESS (0.427s)
[618.415934] [100/128] (596s left) gem_gpgpu_fill (offset-16x16)
[618.474638] Starting subtest: offset-16x16
[618.480209] Subtest offset-16x16: SUCCESS (0.008s)
[618.612341] [101/128] (596s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[618.713018] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[620.003543] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (1.292s)
[620.226495] [102/128] (595s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[620.325211] Starting subtest: basic-max-pipe-crc-check
[620.330203] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[620.367333] [103/128] (594s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[620.468051] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[620.470777] Starting dynamic subtest: pipe-A-default-mode
[620.756472] Dynamic subtest pipe-A-default-mode: SKIP (0.282s)
[620.761494] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.284s)
[621.579653] [104/128] (593s left) kms_flip (modeset-vs-vblank-race)
[621.677332] Starting subtest: modeset-vs-vblank-race
[621.680093] Starting dynamic subtest: A-eDP1
[626.371339] Dynamic subtest A-eDP1: SUCCESS (4.696s)
[626.382647] Starting dynamic subtest: B-eDP1
[631.066905] Dynamic subtest B-eDP1: SUCCESS (4.696s)
[631.078097] Starting dynamic subtest: C-eDP1
[635.730084] Dynamic subtest C-eDP1: SUCCESS (4.664s)
[635.741953] Starting dynamic subtest: D-eDP1
[640.339242] Dynamic subtest D-eDP1: SUCCESS (4.609s)
[640.342072] Subtest modeset-vs-vblank-race: SUCCESS (18.672s)
[641.580601] [105/128] (573s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[641.689044] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[641.694066] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[641.787398] [106/128] (573s left) prime_vgem (basic-fence-mmap)
[641.883520] Starting subtest: basic-fence-mmap
[641.888493] Subtest basic-fence-mmap: SKIP (0.000s)
[641.997752] [107/128] (573s left) gem_workarounds (reset-fd)
[642.057080] Starting subtest: reset-fd
[642.235122] Subtest reset-fd: SUCCESS (0.175s)
[642.369969] [108/128] (573s left) kms_chamelium_edid (vga-edid-read)