[50.827785] Initializing watchdogs
[50.828373]   /dev/watchdog0
[50.859968] [001/141] (960s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[51.403385] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[51.411312] Starting dynamic subtest: pipe-A-default-mode
[51.822227] Dynamic subtest pipe-A-default-mode: SKIP (0.404s)
[51.828962] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.415s)
[52.752335] [002/141] (958s left) gem_exec_schedule (timeslicing)
[53.166496] Starting subtest: timeslicing
[53.169350] Starting dynamic subtest: rcs0
[53.207263] Dynamic subtest rcs0: SUCCESS (0.041s)
[53.250210] Starting dynamic subtest: bcs0
[53.253763] Dynamic subtest bcs0: SUCCESS (0.030s)
[53.281855] Starting dynamic subtest: vcs0
[53.285490] Dynamic subtest vcs0: SUCCESS (0.032s)
[53.313376] Starting dynamic subtest: vcs1
[53.317199] Dynamic subtest vcs1: SUCCESS (0.026s)
[53.347817] Starting dynamic subtest: vecs0
[53.352470] Dynamic subtest vecs0: SUCCESS (0.024s)
[53.380908] Starting dynamic subtest: ccs0
[53.383915] Dynamic subtest ccs0: SUCCESS (0.027s)
[53.387197] Subtest timeslicing: SUCCESS (0.183s)
[54.568498] [003/141] (956s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[54.946668] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[55.037860] [004/141] (956s left) kms_tv_load_detect (load-detect)
[55.410956] Subtest load-detect: SKIP (0.000s)
[55.502665] [005/141] (956s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[56.051562] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[60.121471] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (4.070s)
[60.628591] [006/141] (951s left) perf (gen8-unprivileged-single-ctx-counters)
[61.344456] Starting subtest: gen8-unprivileged-single-ctx-counters
[61.350400] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[61.408743] [007/141] (950s left) kms_flip (basic-flip-vs-wf_vblank)
[61.788682] Starting subtest: basic-flip-vs-wf_vblank
[61.813513] Starting dynamic subtest: A-eDP1
[64.299209] Dynamic subtest A-eDP1: SUCCESS (2.485s)
[64.319973] Starting dynamic subtest: B-eDP1
[66.455505] Dynamic subtest B-eDP1: SUCCESS (2.155s)
[66.483606] Starting dynamic subtest: C-eDP1
[68.554550] Dynamic subtest C-eDP1: SUCCESS (2.098s)
[68.580793] Starting dynamic subtest: D-eDP1
[70.811122] Dynamic subtest D-eDP1: SUCCESS (2.254s)
[70.820798] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.038s)
[72.244653] [008/141] (939s left) gem_exec_fence (expired-history)
[74.335149] Starting subtest: expired-history
[80.284343] Subtest expired-history: SUCCESS (5.953s)
[81.610085] [009/141] (930s left) kms_pipe_crc_basic (nonblocking-crc)
[82.081215] Starting subtest: nonblocking-crc
[82.088776] Starting dynamic subtest: pipe-A-eDP-1
[83.358338] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.268s)
[83.389777] Starting dynamic subtest: pipe-B-eDP-1
[85.300445] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.936s)
[85.339159] Starting dynamic subtest: pipe-C-eDP-1
[87.444997] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.136s)
[87.475657] Starting dynamic subtest: pipe-D-eDP-1
[89.877124] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.425s)
[89.881325] Subtest nonblocking-crc: SUCCESS (7.794s)
[90.813642] [010/141] (921s left) gem_userptr_blits (unsync-unmap-cycles)
[91.216990] Subtest unsync-unmap-cycles: SKIP (0.000s)
[91.484871] [011/141] (920s left) kms_vblank (pipe-a-ts-continuation-dpms-rpm)
[91.952768] Starting subtest: pipe-A-ts-continuation-dpms-rpm
[94.810504] Subtest pipe-A-ts-continuation-dpms-rpm: SUCCESS (2.859s)
[95.743308] [012/141] (916s left) gem_tiled_blits (basic)
[96.129958] Subtest basic: SKIP (0.000s)
[96.233540] [013/141] (915s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[96.732649] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[96.739831] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.001s)
[96.886824] [014/141] (915s left) kms_async_flips (crc)
[97.358212] Starting subtest: crc
[99.588676] Starting dynamic subtest: pipe-A-eDP-1
[104.069748] Dynamic subtest pipe-A-eDP-1: FAIL (3.751s)
[105.490692] Starting dynamic subtest: pipe-B-eDP-1
[108.899830] Dynamic subtest pipe-B-eDP-1: FAIL (2.707s)
[109.945264] Starting dynamic subtest: pipe-C-eDP-1
[113.652120] Dynamic subtest pipe-C-eDP-1: FAIL (2.989s)
[114.313651] Starting dynamic subtest: pipe-D-eDP-1
[118.001078] Dynamic subtest pipe-D-eDP-1: FAIL (2.979s)
[118.004341] Subtest crc: FAIL (19.904s)
[118.621871] [015/141] (893s left) vc4/vc4_perfmon (get-values-invalid-perfmon)
[119.091820] Subtest get-values-invalid-perfmon: SKIP (0.000s)
[119.201671] [016/141] (893s left) kms_ccs (pipe-a-random-ccs-data-y_tiled_gen12_rc_ccs)
[120.160470] Starting subtest: pipe-A-random-ccs-data-y_tiled_gen12_rc_ccs
[120.170483] Subtest pipe-A-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[121.574099] [017/141] (890s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[122.078073] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[122.084467] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[122.244837] [018/141] (890s left) gem_linear_blits (basic)
[125.365803] Starting subtest: basic
[125.381263] Subtest basic: SUCCESS (0.016s)
[125.547942] [019/141] (887s left) perf (sysctl-defaults)
[126.300795] Starting subtest: sysctl-defaults
[126.305071] Subtest sysctl-defaults: SUCCESS (0.000s)
[126.535617] [020/141] (886s left) kms_addfb_basic (bo-too-small)
[127.050777] Starting subtest: bo-too-small
[127.054485] Subtest bo-too-small: SUCCESS (0.001s)
[127.212474] [021/141] (885s left) v3d/v3d_submit_csd (bad-in-sync)
[127.686767] Subtest bad-in-sync: SKIP (0.000s)
[127.805144] [022/141] (885s left) gem_exec_schedule (implicit-write-read)
[128.224740] Starting subtest: implicit-write-read
[128.228451] Starting dynamic subtest: rcs0
[128.613369] Dynamic subtest rcs0: SUCCESS (0.386s)
[128.647234] Starting dynamic subtest: bcs0
[128.875464] Dynamic subtest bcs0: SUCCESS (0.260s)
[128.915617] Starting dynamic subtest: vcs0
[129.149647] Dynamic subtest vcs0: SUCCESS (0.272s)
[129.186064] Starting dynamic subtest: vcs1
[129.419211] Dynamic subtest vcs1: SUCCESS (0.268s)
[129.456986] Starting dynamic subtest: vecs0
[129.765529] Dynamic subtest vecs0: SUCCESS (0.344s)
[129.802061] Starting dynamic subtest: ccs0
[130.058166] Dynamic subtest ccs0: SUCCESS (0.290s)
[130.061711] Subtest implicit-write-read: SUCCESS (1.833s)
[131.719971] [023/141] (881s left) gem_pread (bench)
[132.095385] Subtest bench: SKIP (0.000s)
[132.218842] [024/141] (880s left) i915_query (hwconfig_table)
[132.589230] Starting subtest: hwconfig_table
[132.999860] Subtest hwconfig_table: SUCCESS (0.006s)
[133.054385] [025/141] (880s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-gtt)
[133.573505] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-gtt
[133.580912] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[133.745502] [026/141] (879s left) kms_psr (primary_page_flip)
[134.221248] Starting subtest: primary_page_flip
[137.557450] Subtest primary_page_flip: SUCCESS (3.337s)
[138.507768] [027/141] (874s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_ccs)
[139.941100] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_ccs
[139.947766] Subtest pipe-C-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.002s)
[140.797914] [028/141] (872s left) kms_flip (flip-vs-suspend-interruptible)
[141.211205] Starting subtest: flip-vs-suspend-interruptible
[141.242275] Starting dynamic subtest: A-eDP1
[177.260071] Dynamic subtest A-eDP1: SUCCESS (36.018s)
[177.292547] Starting dynamic subtest: B-eDP1
[212.227698] Dynamic subtest B-eDP1: SUCCESS (34.961s)
[212.259210] Starting dynamic subtest: C-eDP1
[247.278446] Dynamic subtest C-eDP1: SUCCESS (35.052s)
[247.324902] Starting dynamic subtest: D-eDP1
[282.241957] Dynamic subtest D-eDP1: SUCCESS (34.956s)
[282.264049] Subtest flip-vs-suspend-interruptible: SUCCESS (141.052s)
[283.826552] [029/141] (729s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[284.339165] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[284.346728] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[284.514672] [030/141] (728s left) sysfs_heartbeat_interval (invalid)
[284.923119] Starting subtest: invalid
[284.926823] Starting dynamic subtest: vecs0
[284.930321] Dynamic subtest vecs0: SUCCESS (0.002s)
[284.975926] Starting dynamic subtest: ccs0
[284.979353] Dynamic subtest ccs0: SUCCESS (0.001s)
[285.024241] Starting dynamic subtest: vcs0
[285.028537] Dynamic subtest vcs0: SUCCESS (0.001s)
[285.071381] Starting dynamic subtest: bcs0
[285.074980] Dynamic subtest bcs0: SUCCESS (0.001s)
[285.117177] Starting dynamic subtest: rcs0
[285.120831] Dynamic subtest rcs0: SUCCESS (0.001s)
[285.163172] Starting dynamic subtest: vcs1
[285.166830] Dynamic subtest vcs1: SUCCESS (0.001s)
[285.170370] Subtest invalid: SUCCESS (0.033s)
[285.234835] [031/141] (728s left) kms_chamelium_hpd (hdmi-hpd-storm)
[286.118298] Subtest hdmi-hpd-storm: SKIP (0.000s)
[286.982657] [032/141] (726s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[287.482339] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[287.488765] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[287.664087] [033/141] (726s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[288.178751] Starting subtest: fbc-indfb-scaledprimary
[294.127264] Subtest fbc-indfb-scaledprimary: SUCCESS (5.949s)
[294.392956] [034/141] (719s left) gen7_exec_parse (bitmasks)
[294.796568] Subtest bitmasks: SKIP (0.000s)
[294.911334] [035/141] (719s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[295.469969] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[297.515289] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (2.048s)
[298.450420] [036/141] (715s left) kms_atomic_transition (plane-all-transition-fencing)
[298.922233] Starting subtest: plane-all-transition-fencing
[298.929859] Starting dynamic subtest: pipe-A-eDP-1
[304.414798] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.483s)
[304.923398] Starting dynamic subtest: pipe-B-eDP-1
[310.908308] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.027s)
[311.324251] Subtest plane-all-transition-fencing: SUCCESS (12.400s)
[312.272771] [037/141] (701s left) kms_plane (plane-position-hole-dpms)
[312.758920] Starting subtest: plane-position-hole-dpms
[312.766887] Starting dynamic subtest: pipe-A-planes
[325.701570] Dynamic subtest pipe-A-planes: SUCCESS (12.935s)
[325.759151] Starting dynamic subtest: pipe-B-planes
[338.998224] Dynamic subtest pipe-B-planes: SUCCESS (13.285s)
[339.001498] Subtest plane-position-hole-dpms: SUCCESS (26.243s)
[339.864151] [038/141] (674s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[340.413908] Starting subtest: y-tiled-32bpp-rotate-180
[340.420327] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[340.563922] [039/141] (673s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_dg2_rc_ccs)
[341.784369] Starting subtest: pipe-B-random-ccs-data-4_tiled_dg2_rc_ccs
[341.794613] Subtest pipe-B-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[342.975046] [040/141] (671s left) kms_flip (flip-vs-expired-vblank-interruptible)
[343.389244] Starting subtest: flip-vs-expired-vblank-interruptible
[343.416169] Starting dynamic subtest: A-eDP1
[352.684903] Dynamic subtest A-eDP1: SUCCESS (9.268s)
[352.732695] Starting dynamic subtest: B-eDP1
[361.735424] Dynamic subtest B-eDP1: SUCCESS (9.043s)
[361.781514] Starting dynamic subtest: C-eDP1
[370.809470] Dynamic subtest C-eDP1: SUCCESS (9.068s)
[370.855355] Starting dynamic subtest: D-eDP1
[379.909281] Dynamic subtest D-eDP1: SUCCESS (9.095s)
[379.937852] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (36.553s)
[381.480594] [041/141] (632s left) fbdev (info)
[381.854189] Starting subtest: info
[381.857860] Subtest info: SUCCESS (0.000s)
[382.034296] [042/141] (632s left) i915_pm_rpm (debugfs-read)
[384.651493] Starting subtest: debugfs-read
[505.755276] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[508.279174] Closing watchdogs
[508.294086] Initializing watchdogs
[508.294404]   /dev/watchdog0
[508.354989] [043/141] (506s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled)
[508.733922] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[508.737591] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.002s)
[508.850979] [044/141] (505s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt)
[509.355753] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt
[509.362279] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[509.527145] [045/141] (505s left) gem_exec_suspend (basic-s4-devices)
[509.942800] Starting subtest: basic-S4-devices
[509.945855] Starting dynamic subtest: smem
[531.428877] Dynamic subtest smem: SUCCESS (21.483s)
[531.432691] Subtest basic-S4-devices: SUCCESS (21.487s)
[533.747803] [046/141] (483s left) syncobj_timeline (invalid-signal-illegal-handle)
[534.136248] Starting subtest: invalid-signal-illegal-handle
[534.139783] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[534.306230] [047/141] (482s left) gem_lmem_swapping (parallel-multi)
[534.687176] Subtest parallel-multi: SKIP (0.000s)
[534.815883] [048/141] (482s left) kms_ccs (pipe-a-bad-aux-stride-4_tiled_mtl_rc_ccs_cc)
[535.804541] Starting subtest: pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc
[535.807752] Subtest pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.003s)
[537.128505] [049/141] (480s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[538.386399] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[538.389887] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.003s)
[539.456525] [050/141] (477s left) kms_ccs (pipe-d-bad-rotation-90-y_tiled_gen12_rc_ccs_cc)
[541.085354] Starting subtest: pipe-D-bad-rotation-90-y_tiled_gen12_rc_ccs_cc
[541.091810] Subtest pipe-D-bad-rotation-90-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[541.863161] [051/141] (475s left) gem_ctx_shared (disjoint-timelines)
[542.281072] Starting subtest: disjoint-timelines
[542.328074] Subtest disjoint-timelines: SUCCESS (0.046s)
[542.657999] [052/141] (474s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[543.618317] Starting subtest: cursor-rapid-movement-128x42
[543.653565] Starting dynamic subtest: pipe-A-eDP-1
[544.941221] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.287s)
[545.139172] Starting dynamic subtest: pipe-D-eDP-1
[547.709142] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.616s)
[547.712107] Subtest cursor-rapid-movement-128x42: SUCCESS (4.092s)
[548.665840] [053/141] (468s left) gem_exec_reloc (basic-wc-noreloc)
[549.077700] Subtest basic-wc-noreloc: SKIP (0.000s)
[549.221295] [054/141] (468s left) perf_pmu (busy-accuracy-2)
[549.617282] Starting subtest: busy-accuracy-2
[549.620822] Starting dynamic subtest: rcs0
[552.731783] Dynamic subtest rcs0: SUCCESS (3.109s)
[552.778505] Starting dynamic subtest: bcs0
[555.850807] Dynamic subtest bcs0: SUCCESS (3.115s)
[555.901111] Starting dynamic subtest: vcs0
[558.956567] Dynamic subtest vcs0: SUCCESS (3.103s)
[559.004195] Starting dynamic subtest: vcs1
[562.064573] Dynamic subtest vcs1: SUCCESS (3.103s)
[562.112388] Starting dynamic subtest: vecs0
[565.169430] Dynamic subtest vecs0: SUCCESS (3.101s)
[565.216593] Starting dynamic subtest: ccs0
[568.283054] Dynamic subtest ccs0: SUCCESS (3.110s)
[568.286606] Subtest busy-accuracy-2: SUCCESS (18.664s)
[568.513453] [055/141] (449s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[569.007289] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[569.012624] Starting dynamic subtest: pipe-A-default-mode
[572.665365] Dynamic subtest pipe-A-default-mode: SUCCESS (3.651s)
[572.668760] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (3.665s)
[573.620450] [056/141] (444s left) kms_ccs (pipe-d-missing-ccs-buffer-yf_tiled_ccs)
[575.367350] Starting subtest: pipe-D-missing-ccs-buffer-yf_tiled_ccs
[575.374060] Subtest pipe-D-missing-ccs-buffer-yf_tiled_ccs: SKIP (0.000s)
[576.045948] [057/141] (441s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[576.588810] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[581.819853] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: FAIL (4.446s)
[581.897590] [058/141] (436s left) gem_userptr_blits (create-destroy-unsync)
[582.307569] Subtest create-destroy-unsync: SKIP (0.000s)
[582.589470] [059/141] (435s left) gem_exec_balancer (full-pulse)
[583.020407] Starting subtest: full-pulse
[592.454696] Subtest full-pulse: SUCCESS (9.435s)
[593.482653] [060/141] (424s left) syncobj_wait (invalid-wait-illegal-handle)
[593.859256] Starting subtest: invalid-wait-illegal-handle
[593.863875] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[594.046678] [061/141] (424s left) kms_getfb (getfb2-handle-protection)
[594.422421] Starting subtest: getfb2-handle-protection
[594.485212] Subtest getfb2-handle-protection: SUCCESS (0.064s)
[594.692529] [062/141] (423s left) perf_pmu (most-busy-idle-check-all)
[595.085650] Starting subtest: most-busy-idle-check-all
[595.089221] Starting dynamic subtest: rcs0
[596.516803] Dynamic subtest rcs0: SUCCESS (1.434s)
[596.567476] Starting dynamic subtest: bcs0
[598.500844] Dynamic subtest bcs0: SUCCESS (1.980s)
[598.551364] Starting dynamic subtest: vcs0
[600.484531] Dynamic subtest vcs0: SUCCESS (1.980s)
[600.534923] Starting dynamic subtest: vcs1
[602.532537] Dynamic subtest vcs1: SUCCESS (2.044s)
[602.582890] Starting dynamic subtest: vecs0
[604.516753] Dynamic subtest vecs0: SUCCESS (1.980s)
[604.567303] Starting dynamic subtest: ccs0
[606.500933] Dynamic subtest ccs0: SUCCESS (1.980s)
[606.505996] Subtest most-busy-idle-check-all: SUCCESS (11.422s)
[606.692441] [063/141] (411s left) kms_big_fb (linear-64bpp-rotate-180)
[607.239830] Starting subtest: linear-64bpp-rotate-180
[614.374360] Subtest linear-64bpp-rotate-180: SUCCESS (7.132s)
[615.054914] [064/141] (403s left) kms_prop_blob (invalid-get-prop-any)
[615.414952] Starting subtest: invalid-get-prop-any
[615.418731] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[615.557561] [065/141] (403s left) kms_scaling_modes (scaling-mode-none)
[616.024823] Starting subtest: scaling-mode-none
[616.028422] Starting dynamic subtest: eDP-1-pipe-A
[616.285153] Dynamic subtest eDP-1-pipe-A: SKIP (0.254s)
[616.356841] Starting dynamic subtest: eDP-1-pipe-B
[616.468415] Dynamic subtest eDP-1-pipe-B: SKIP (0.171s)
[616.523980] Starting dynamic subtest: eDP-1-pipe-C
[616.640548] Dynamic subtest eDP-1-pipe-C: SKIP (0.163s)
[616.696476] Starting dynamic subtest: eDP-1-pipe-D
[616.808645] Dynamic subtest eDP-1-pipe-D: SKIP (0.158s)
[616.815553] Subtest scaling-mode-none: SKIP (0.787s)
[616.997171] [066/141] (401s left) drm_fdinfo (busy-idle-check-all)
[617.391708] Starting subtest: busy-idle-check-all
[617.395607] Starting dynamic subtest: rcs0
[617.409276] Dynamic subtest rcs0: SKIP (0.012s)
[617.460291] Starting dynamic subtest: bcs0
[617.467287] Dynamic subtest bcs0: SKIP (0.003s)
[617.517302] Starting dynamic subtest: vcs0
[617.523934] Dynamic subtest vcs0: SKIP (0.003s)
[617.572145] Starting dynamic subtest: vcs1
[617.578686] Dynamic subtest vcs1: SKIP (0.002s)
[617.626317] Starting dynamic subtest: vecs0
[617.632784] Dynamic subtest vecs0: SKIP (0.003s)
[617.680383] Starting dynamic subtest: ccs0
[617.686920] Dynamic subtest ccs0: SKIP (0.003s)
[617.693377] Subtest busy-idle-check-all: SKIP (0.050s)
[617.763505] [067/141] (401s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[618.235861] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[618.242707] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[618.374436] [068/141] (400s left) v3d/v3d_wait_bo (used-bo-0ns)
[618.853360] Subtest used-bo-0ns: SKIP (0.000s)
[618.993186] [069/141] (400s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[619.521643] Starting subtest: 4-tiled-64bpp-rotate-0
[625.828651] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (6.307s)
[626.614185] [070/141] (392s left) v3d/v3d_submit_cl (bad-in-sync)
[627.082930] Subtest bad-in-sync: SKIP (0.000s)
[627.219994] [071/141] (391s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[627.738960] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[633.168362] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (5.437s)
[633.463284] [072/141] (385s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[633.969118] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[633.975580] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[634.128228] [073/141] (385s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted)
[634.508180] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted
[634.610935] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.103s)
[634.768173] [074/141] (384s left) syncobj_wait (wait-any-snapshot)
[635.154223] Starting subtest: wait-any-snapshot
[635.232706] Subtest wait-any-snapshot: SUCCESS (0.083s)
[635.385107] [075/141] (384s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[635.887234] Starting subtest: fbcpsr-slowdraw
[668.608856] Subtest fbcpsr-slowdraw: SUCCESS (32.721s)
[668.875686] [076/141] (350s left) kms_chamelium_hpd (dp-hpd)
[669.770614] Subtest dp-hpd: SKIP (0.000s)
[670.646323] [077/141] (349s left) api_intel_bb (add-remove-objects)
[671.021150] Starting subtest: add-remove-objects
[671.044817] Subtest add-remove-objects: SUCCESS (0.029s)
[671.233470] [078/141] (348s left) kms_content_protection (dp-mst-lic-type-1)
[671.902956] Starting subtest: dp-mst-lic-type-1
[671.910200] Subtest dp-mst-lic-type-1: SKIP (0.001s)
[672.109365] [079/141] (347s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[672.608885] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[672.615797] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[672.764649] [080/141] (347s left) i915_pm_backlight (fade-with-dpms)
[673.244168] Starting subtest: fade-with-dpms
[675.787434] Starting dynamic subtest: eDP-1
[678.604466] Dynamic subtest eDP-1: SUCCESS (2.815s)
[678.608492] Subtest fade-with-dpms: SUCCESS (5.361s)
[679.923504] [081/141] (340s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[680.435590] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[680.442577] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[680.600833] [082/141] (339s left) kms_lease (cursor_implicit_plane)
[681.070639] Starting subtest: cursor_implicit_plane
[681.080402] Starting dynamic subtest: pipe-A-eDP-1
[681.758502] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.679s)
[681.813874] Starting dynamic subtest: pipe-B-eDP-1
[683.098338] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.329s)
[683.155326] Starting dynamic subtest: pipe-C-eDP-1
[684.710248] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.602s)
[684.765228] Starting dynamic subtest: pipe-D-eDP-1
[686.587993] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.871s)
[686.591521] Subtest cursor_implicit_plane: SUCCESS (5.519s)
[687.533441] [083/141] (332s left) gem_exec_schedule (noreorder)
[688.131956] Starting subtest: noreorder
[688.135579] Starting dynamic subtest: rcs0
[688.218661] Dynamic subtest rcs0: SUCCESS (0.086s)
[688.284324] Starting dynamic subtest: bcs0
[688.306725] Dynamic subtest bcs0: SUCCESS (0.083s)
[688.366660] Starting dynamic subtest: vcs0
[689.473776] Dynamic subtest vcs0: SUCCESS (1.161s)
[689.523923] Starting dynamic subtest: vcs1
[691.521137] Dynamic subtest vcs1: SUCCESS (2.043s)
[691.577953] Starting dynamic subtest: vecs0
[693.565860] Dynamic subtest vecs0: SUCCESS (2.039s)
[693.615439] Starting dynamic subtest: ccs0
[693.649389] Dynamic subtest ccs0: SUCCESS (0.080s)
[693.653863] Subtest noreorder: SUCCESS (5.521s)
[693.970399] [084/141] (326s left) kms_psr (psr2_cursor_mmap_gtt)
[694.463482] Starting subtest: psr2_cursor_mmap_gtt
[697.087799] Subtest psr2_cursor_mmap_gtt: SKIP (2.623s)
[697.439704] [085/141] (323s left) kms_cursor_crc (cursor-random-256x256)
[698.427517] Starting subtest: cursor-random-256x256
[698.457829] Starting dynamic subtest: pipe-A-eDP-1
[705.491967] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.029s)
[705.690953] Starting dynamic subtest: pipe-D-eDP-1
[712.040931] Dynamic subtest pipe-D-eDP-1: SUCCESS (6.393s)
[712.044788] Subtest cursor-random-256x256: SUCCESS (13.612s)
[713.002557] [086/141] (307s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[713.574986] Starting subtest: flip-vs-cursor-legacy
[725.403394] Subtest flip-vs-cursor-legacy: SUCCESS (11.827s)
[726.361470] [087/141] (294s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[726.897585] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[730.183224] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (3.282s)
[730.579755] [088/141] (290s left) sw_sync (sync_multi_timeline_wait)
[730.936053] Starting subtest: sync_multi_timeline_wait
[730.940427] Subtest sync_multi_timeline_wait: SUCCESS (0.003s)
[731.082224] [089/141] (289s left) kms_vblank (pipe-a-ts-continuation-suspend)
[731.560920] Starting subtest: pipe-A-ts-continuation-suspend
[748.608885] Subtest pipe-A-ts-continuation-suspend: SUCCESS (17.049s)
[749.552375] [090/141] (271s left) kms_chamelium_audio (hdmi-audio-edid)
[750.457359] Subtest hdmi-audio-edid: SKIP (0.000s)
[751.328367] [091/141] (269s left) kms_ccs (pipe-d-crc-primary-rotation-180-y_tiled_ccs)
[752.956258] Starting subtest: pipe-D-crc-primary-rotation-180-y_tiled_ccs
[752.962547] Subtest pipe-D-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.002s)
[753.674159] [092/141] (267s left) kms_plane_cursor (viewport)
[754.155463] Starting subtest: viewport
[754.162386] Starting dynamic subtest: pipe-A-eDP-1-size-64
[761.442688] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (7.279s)
[761.557188] Starting dynamic subtest: pipe-A-eDP-1-size-128
[768.911117] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (7.393s)
[769.024391] Starting dynamic subtest: pipe-A-eDP-1-size-256
[773.133107] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (4.149s)
[773.644757] Starting dynamic subtest: pipe-B-eDP-1-size-64
[780.003835] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (6.399s)
[780.126493] Starting dynamic subtest: pipe-B-eDP-1-size-128
[787.470659] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (7.410s)
[787.581673] Starting dynamic subtest: pipe-B-eDP-1-size-256
[794.870119] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (7.327s)
[795.389783] Starting dynamic subtest: pipe-C-eDP-1-size-64
[802.837402] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (7.493s)
[802.943223] Starting dynamic subtest: pipe-C-eDP-1-size-128
[807.934177] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (5.034s)
[808.030249] Starting dynamic subtest: pipe-C-eDP-1-size-256
[814.903269] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (6.916s)
[815.396913] Starting dynamic subtest: pipe-D-eDP-1-size-64
[820.659775] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (5.307s)
[820.765793] Starting dynamic subtest: pipe-D-eDP-1-size-128
[827.295117] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (6.574s)
[827.417224] Starting dynamic subtest: pipe-D-eDP-1-size-256
[834.213829] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (6.856s)
[834.668926] Subtest viewport: SUCCESS (80.512s)
[835.626871] [093/141] (185s left) gem_create (create-ext-cpu-access-big)
[836.159332] Starting subtest: create-ext-cpu-access-big
[836.166437] Subtest create-ext-cpu-access-big: SKIP (0.003s)
[836.311258] [094/141] (185s left) gen9_exec_parse (unaligned-access)
[836.703391] Subtest unaligned-access: SKIP (0.000s)
[836.826994] [095/141] (184s left) gem_exec_whisper (basic-queues-forked)
[837.252579] Starting subtest: basic-queues-forked
[869.621385] Subtest basic-queues-forked: SUCCESS (32.363s)
[869.935555] [096/141] (151s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[870.456401] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[886.733180] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (16.280s)
[887.007681] [097/141] (134s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[887.494952] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[887.502846] Starting dynamic subtest: pipe-A-eDP-1
[887.691359] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.190s)
[887.739193] Starting dynamic subtest: pipe-B-eDP-1
[889.115430] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.415s)
[889.138976] Starting dynamic subtest: pipe-C-eDP-1
[890.433953] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.312s)
[890.460929] Starting dynamic subtest: pipe-D-eDP-1
[891.787691] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.336s)
[891.791421] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (4.290s)
[893.130691] [098/141] (128s left) kms_ccs (pipe-d-bad-aux-stride-y_tiled_gen12_mc_ccs)
[894.374332] Starting subtest: pipe-D-bad-aux-stride-y_tiled_gen12_mc_ccs
[894.381681] Subtest pipe-D-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[894.936960] [099/141] (126s left) kms_vblank (pipe-a-ts-continuation-idle)
[895.130881] Starting subtest: pipe-A-ts-continuation-idle
[895.575118] Subtest pipe-A-ts-continuation-idle: SUCCESS (0.446s)
[896.621297] [100/141] (125s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[896.998808] Starting subtest: etime-single-wait-all-for-submit-submitted
[897.107696] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.108s)
[897.265923] [101/141] (124s left) kms_vblank (pipe-d-wait-forked-busy-hang)
[897.741353] Starting subtest: pipe-D-wait-forked-busy-hang
[913.769534] Subtest pipe-D-wait-forked-busy-hang: SUCCESS (16.028s)
[914.730791] [102/141] (107s left) gem_softpin (safe-alignment)
[915.127145] Starting subtest: safe-alignment
[915.222997] Subtest safe-alignment: SUCCESS (0.096s)
[916.671886] [103/141] (105s left) gem_ctx_param (invalid-ctx-set)
[917.047124] Starting subtest: invalid-ctx-set
[917.050928] Subtest invalid-ctx-set: SUCCESS (0.001s)
[917.199250] [104/141] (105s left) gem_exec_fence (basic-wait-all)
[917.642588] Starting subtest: basic-wait-all
[917.741866] Subtest basic-wait-all: SUCCESS (0.100s)
[920.000897] [105/141] (102s left) dmabuf_sync_file (export-before-signal)
[920.402919] Starting subtest: export-before-signal
[920.406730] Subtest export-before-signal: SUCCESS (0.003s)
[920.557938] [106/141] (101s left) kms_flip (plain-flip-fb-recreate)
[920.959622] Starting subtest: plain-flip-fb-recreate
[920.990450] Starting dynamic subtest: A-eDP1
[930.425269] Dynamic subtest A-eDP1: SUCCESS (9.435s)
[930.487942] Starting dynamic subtest: B-eDP1
[939.718056] Dynamic subtest B-eDP1: SUCCESS (9.287s)
[939.774675] Starting dynamic subtest: C-eDP1
[948.979090] Dynamic subtest C-eDP1: SUCCESS (9.256s)
[949.036694] Starting dynamic subtest: D-eDP1
[958.227492] Dynamic subtest D-eDP1: SUCCESS (9.242s)
[958.253243] Subtest plain-flip-fb-recreate: SUCCESS (37.295s)
[959.857646] [107/141] ( 62s left) kms_vblank (pipe-d-query-forked-hang)
[960.342169] Starting subtest: pipe-D-query-forked-hang
[976.776394] Subtest pipe-D-query-forked-hang: SUCCESS (16.432s)
[977.739936] [108/141] ( 44s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[978.969149] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[978.972788] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SUCCESS (0.001s)
[980.113149] [109/141] ( 42s left) gem_ctx_persistence (hang)
[980.511568] Starting subtest: hang
[980.520205] Subtest hang: SKIP (0.001s)
[980.695544] [110/141] ( 42s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[981.201116] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[986.612746] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (5.420s)
[986.906112] [111/141] ( 35s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[987.420357] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[987.427239] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[987.589887] [112/141] ( 35s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[989.170076] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[989.186502] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.013s)
[990.026738] [113/141] ( 33s left) gem_exec_flush (basic-wb-ro-default)
[990.446863] Starting subtest: basic-wb-ro-default
[996.314222] Subtest basic-wb-ro-default: SUCCESS (5.867s)
[996.632458] [114/141] ( 26s left) gem_render_copy (yf-tiled)
[997.060861] Starting subtest: yf-tiled
[997.067719] Subtest yf-tiled: SKIP (0.000s)
[997.368776] [115/141] ( 25s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[997.754141] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[997.859611] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.105s)
[998.046896] [116/141] ( 25s left) kms_flip (2x-flip-vs-wf_vblank)
[998.444132] Starting subtest: 2x-flip-vs-wf_vblank
[998.606368] Subtest 2x-flip-vs-wf_vblank: SKIP (0.161s)
[998.811175] [117/141] ( 24s left) kms_vblank (pipe-a-query-idle)
[999.296309] Starting subtest: pipe-A-query-idle
[1001.238503] Subtest pipe-A-query-idle: SUCCESS (1.945s)
[1002.203669] [118/141] ( 21s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[1002.589385] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[1002.592965] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.004s)
[1002.758999] [119/141] ( 20s left) kms_properties (plane-properties-atomic)
[1003.237494] Starting subtest: plane-properties-atomic
[1007.842814] Subtest plane-properties-atomic: SUCCESS (4.602s)
[1008.803769] [120/141] ( 14s left) gem_exec_params (invalid-flag)
[1009.205145] Starting subtest: invalid-flag
[1009.208773] Subtest invalid-flag: SUCCESS (0.001s)
[1009.392427] [121/141] ( 14s left) kms_ccs (pipe-a-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[1010.271971] Starting subtest: pipe-A-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[1010.278250] Subtest pipe-A-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[1011.798674] [122/141] ( 12s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[1012.313512] Starting subtest: psr-indfb-scaledprimary
[1019.804817] Subtest psr-indfb-scaledprimary: SUCCESS (7.493s)
[1020.051917] [123/141] (  3s left) gem_exec_balancer (noheartbeat)
[1021.324648] Starting subtest: noheartbeat
[1021.332109] Subtest noheartbeat: SKIP (0.001s)
[1021.486379] [124/141] (  2s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[1021.866969] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[1021.870872] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.002s)
[1022.027967] [125/141] (  2s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[1022.394647] Starting subtest: single-wait-all-for-submit-signaled
[1022.398313] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.001s)
[1022.543845] [126/141] (  1s left) gem_ctx_param (invalid-get-ringsize)
[1022.917244] Starting subtest: invalid-get-ringsize
[1022.920925] Subtest invalid-get-ringsize: SUCCESS (0.002s)
[1023.076100] [127/141] (  1s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[1023.454115] Starting subtest: rel-constants-invalid-rel-gen5
[1023.457947] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.001s)
[1023.668275] [128/141] (  0s left) kms_cursor_edge_walk (128x128-right-edge)
[1024.151276] Starting subtest: 128x128-right-edge
[1024.161773] Starting dynamic subtest: pipe-A-eDP-1
[1028.044226] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.878s)
[1028.101079] Starting dynamic subtest: pipe-D-eDP-1
[1031.497792] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.449s)
[1031.501379] Subtest 128x128-right-edge: SUCCESS (7.349s)
[1032.441735] Overall timeout time exceeded, stopping.
[1032.442987] Closing watchdogs
Done.