[47.411120] Initializing watchdogs
[47.411252]   /dev/watchdog0
[47.422086] [001/138] (960s left) i915_pm_rps (fence-order)
[47.474283] Starting subtest: fence-order
[50.024565] Subtest fence-order: SUCCESS (2.548s)
[50.074294] [002/138] (957s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[50.150582] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[50.152517] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[50.230398] [003/138] (957s left) kms_draw_crc (draw-method-mmap-wc)
[50.298096] Starting subtest: draw-method-mmap-wc
[50.299215] Starting dynamic subtest: xrgb8888-untiled
[50.554900] Dynamic subtest xrgb8888-untiled: SUCCESS (0.256s)
[50.563898] Starting dynamic subtest: rgb565-untiled
[50.799284] Dynamic subtest rgb565-untiled: SUCCESS (0.244s)
[50.806955] Starting dynamic subtest: xrgb2101010-untiled
[50.999257] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.200s)
[51.005332] Starting dynamic subtest: xrgb8888-xtiled
[51.133084] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.134s)
[51.142868] Starting dynamic subtest: rgb565-xtiled
[51.288151] Dynamic subtest rgb565-xtiled: SUCCESS (0.155s)
[51.296949] Starting dynamic subtest: xrgb2101010-xtiled
[51.400718] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.111s)
[51.404836] Starting dynamic subtest: xrgb8888-4tiled
[51.543911] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[51.552140] Starting dynamic subtest: rgb565-4tiled
[51.699340] Dynamic subtest rgb565-4tiled: SUCCESS (0.155s)
[51.708325] Starting dynamic subtest: xrgb2101010-4tiled
[51.832641] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.133s)
[51.834210] Subtest draw-method-mmap-wc: SUCCESS (1.534s)
[51.912588] [004/138] (956s left) kms_cursor_crc (cursor-offscreen-64x21)
[52.004321] Starting subtest: cursor-offscreen-64x21
[52.009422] Starting dynamic subtest: pipe-A-eDP-1
[54.099917] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.090s)
[54.130148] Starting dynamic subtest: pipe-D-eDP-1
[56.832445] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.710s)
[56.833560] Subtest cursor-offscreen-64x21: SUCCESS (4.827s)
[57.719918] [005/138] (950s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[57.796613] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[63.029898] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.235s)
[63.138894] [006/138] (944s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[63.231587] Starting subtest: 4-tiled-16bpp-rotate-270
[63.248177] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.016s)
[63.337283] [007/138] (944s left) kms_getfb (getfb2-into-addfb2)
[63.386424] Starting subtest: getfb2-into-addfb2
[63.387716] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[63.469179] [008/138] (944s left) kms_plane_scaling (plane-downscale-factor-0-25-with-modifiers)
[63.516597] Starting subtest: plane-downscale-factor-0-25-with-modifiers
[63.517741] Starting dynamic subtest: pipe-A-eDP-1
[63.544110] Dynamic subtest pipe-A-eDP-1: SKIP (0.025s)
[63.549874] Starting dynamic subtest: pipe-B-eDP-1
[63.575530] Dynamic subtest pipe-B-eDP-1: SKIP (0.031s)
[63.584124] Starting dynamic subtest: pipe-C-eDP-1
[63.608526] Dynamic subtest pipe-C-eDP-1: SKIP (0.031s)
[63.615818] Starting dynamic subtest: pipe-D-eDP-1
[63.639949] Dynamic subtest pipe-D-eDP-1: SKIP (0.032s)
[63.645404] Subtest plane-downscale-factor-0-25-with-modifiers: SKIP (0.122s)
[63.735581] [009/138] (944s left) kms_vblank (wait-forked)
[63.797864] Starting subtest: wait-forked
[63.800273] Starting dynamic subtest: pipe-A-eDP-1
[65.610292] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.811s)
[65.624128] Starting dynamic subtest: pipe-D-eDP-1
[68.184414] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.568s)
[68.185528] Subtest wait-forked: SUCCESS (4.387s)
[69.104260] [010/138] (938s left) gem_wait (invalid-flags)
[69.165159] Starting subtest: invalid-flags
[69.166269] Subtest invalid-flags: SUCCESS (0.000s)
[69.362515] [011/138] (938s left) prime_busy (after)
[69.415796] Starting subtest: after
[69.423952] Starting dynamic subtest: rcs0
[69.453204] Dynamic subtest rcs0: SUCCESS (0.029s)
[69.462601] Starting dynamic subtest: bcs0
[69.492134] Dynamic subtest bcs0: SUCCESS (0.039s)
[69.501308] Starting dynamic subtest: vcs0
[69.530965] Dynamic subtest vcs0: SUCCESS (0.039s)
[69.540404] Starting dynamic subtest: vcs1
[69.560718] Dynamic subtest vcs1: SUCCESS (0.030s)
[69.570539] Starting dynamic subtest: vecs0
[69.582628] Dynamic subtest vecs0: SUCCESS (0.022s)
[69.592022] Starting dynamic subtest: ccs0
[69.607467] Dynamic subtest ccs0: SUCCESS (0.025s)
[69.719381] Subtest after: SUCCESS (0.301s)
[69.778359] [012/138] (938s left) gem_exec_flush (basic-wb-rw-before-default)
[69.829809] Starting subtest: basic-wb-rw-before-default
[75.303424] Subtest basic-wb-rw-before-default: SUCCESS (5.474s)
[75.478983] [013/138] (932s left) gem_linear_blits (basic)
[75.820689] Starting subtest: basic
[75.825438] Subtest basic: SUCCESS (0.005s)
[75.937956] [014/138] (932s left) gem_exec_balancer (noheartbeat)
[76.235436] Starting subtest: noheartbeat
[76.237916] Subtest noheartbeat: SKIP (0.000s)
[76.296603] [015/138] (931s left) gem_vm_create (invalid-create)
[76.353351] Starting subtest: invalid-create
[76.354495] Subtest invalid-create: SUCCESS (0.000s)
[76.442845] [016/138] (931s left) kms_flip (dpms-vs-vblank-race)
[76.503997] Starting subtest: dpms-vs-vblank-race
[76.509755] Starting dynamic subtest: A-eDP1
[80.642677] Dynamic subtest A-eDP1: SUCCESS (4.133s)
[80.653222] Starting dynamic subtest: B-eDP1
[84.364332] Dynamic subtest B-eDP1: SUCCESS (3.720s)
[84.375058] Starting dynamic subtest: C-eDP1
[88.001411] Dynamic subtest C-eDP1: SUCCESS (3.637s)
[88.012244] Starting dynamic subtest: D-eDP1
[91.648886] Dynamic subtest D-eDP1: SUCCESS (3.647s)
[91.655498] Subtest dpms-vs-vblank-race: SUCCESS (15.153s)
[92.544575] [017/138] (915s left) debugfs_test (basic-hwmon)
[92.600465] Starting subtest: basic-hwmon
[92.603296] Subtest basic-hwmon: SKIP (0.000s)
[92.704375] [018/138] (915s left) gem_lmem_swapping (basic)
[92.772762] Subtest basic: SKIP (0.000s)
[92.806242] [019/138] (915s left) kms_ccs (pipe-b-bad-pixel-format-4-tiled-dg2-rc-ccs)
[93.183310] Starting subtest: pipe-B-bad-pixel-format-4-tiled-dg2-rc-ccs
[93.186138] Subtest pipe-B-bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[94.077973] [020/138] (914s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[94.129804] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[97.172670] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (3.045s)
[97.287613] [021/138] (911s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[97.373672] Starting subtest: cursor-vs-flip-atomic-transitions
[126.079077] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (28.705s)
[126.975572] [022/138] (881s left) gem_ringfill (engines-basic)
[132.456746] Starting subtest: engines-basic
[132.459441] Starting dynamic subtest: rcs0
[132.502026] Dynamic subtest rcs0: SUCCESS (0.047s)
[132.514098] Starting dynamic subtest: bcs0
[132.544941] Dynamic subtest bcs0: SUCCESS (0.042s)
[132.556067] Starting dynamic subtest: vcs0
[132.566293] Dynamic subtest vcs0: SUCCESS (0.021s)
[132.577675] Starting dynamic subtest: vcs1
[132.594719] Dynamic subtest vcs1: SUCCESS (0.028s)
[132.605323] Starting dynamic subtest: vecs0
[132.614780] Dynamic subtest vecs0: SUCCESS (0.019s)
[132.625522] Starting dynamic subtest: ccs0
[132.657230] Dynamic subtest ccs0: SUCCESS (0.042s)
[132.659058] Subtest engines-basic: SUCCESS (0.202s)
[132.725541] [023/138] (875s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[132.798313] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[133.367982] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.570s)
[133.488805] [024/138] (874s left) gem_exec_schedule (u-semaphore-user)
[133.554239] Starting subtest: u-semaphore-user
[133.562994] Subtest u-semaphore-user: SUCCESS (0.008s)
[135.514247] [025/138] (872s left) kms_chamelium_hpd (common-hpd-after-suspend)
[135.863747] Subtest common-hpd-after-suspend: SKIP (0.000s)
[136.729546] [026/138] (871s left) perf_pmu (busy-check-all)
[136.796801] Starting subtest: busy-check-all
[136.798124] Starting dynamic subtest: rcs0
[137.343455] Dynamic subtest rcs0: SUCCESS (0.546s)
[137.355466] Starting dynamic subtest: bcs0
[137.893555] Dynamic subtest bcs0: SUCCESS (0.547s)
[137.905475] Starting dynamic subtest: vcs0
[139.420433] Dynamic subtest vcs0: SUCCESS (1.526s)
[139.433250] Starting dynamic subtest: vcs1
[141.469540] Dynamic subtest vcs1: SUCCESS (2.048s)
[141.481059] Starting dynamic subtest: vecs0
[143.452932] Dynamic subtest vecs0: SUCCESS (1.983s)
[143.465430] Starting dynamic subtest: ccs0
[144.002267] Dynamic subtest ccs0: SUCCESS (0.551s)
[144.004303] Subtest busy-check-all: SUCCESS (7.205s)
[144.072815] [027/138] (864s left) kms_ccs (pipe-c-missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[144.473663] Starting subtest: pipe-C-missing-ccs-buffer-4-tiled-mtl-mc-ccs
[144.497170] Subtest pipe-C-missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.024s)
[145.319819] [028/138] (863s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[145.371096] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[145.373187] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[145.451965] [029/138] (863s left) kms_psr (psr2_basic)
[145.526365] Starting subtest: psr2_basic
[147.219979] Subtest psr2_basic: SUCCESS (1.694s)
[148.146088] [030/138] (860s left) gem_exec_fence (syncobj-timeline-export)
[148.716043] Starting subtest: syncobj-timeline-export
[148.717910] Subtest syncobj-timeline-export: SUCCESS (0.004s)
[150.517598] [031/138] (858s left) kms_pm_rpm (pm-tiling)
[152.647642] Starting subtest: pm-tiling
[152.650549] Subtest pm-tiling: SKIP (0.000s)
[152.991383] [032/138] (855s left) syncobj_timeline (reset-unsignaled)
[153.036436] Starting subtest: reset-unsignaled
[153.037453] Subtest reset-unsignaled: SUCCESS (0.000s)
[153.087162] [033/138] (855s left) v3d/v3d_submit_cl (single-out-sync)
[153.146735] Subtest single-out-sync: SKIP (0.000s)
[153.193633] [034/138] (855s left) gem_mmap_gtt (basic-small-bo)
[153.243755] Subtest basic-small-bo: SKIP (0.000s)
[153.280488] [035/138] (855s left) vgem_basic (dmabuf-fence)
[153.337417] Starting subtest: dmabuf-fence
[153.338527] Subtest dmabuf-fence: SUCCESS (0.000s)
[153.381651] [036/138] (855s left) kms_busy (extended-pageflip-hang-newfb)
[153.438809] Starting subtest: extended-pageflip-hang-newfb
[153.440068] Starting dynamic subtest: pipe-A
[167.341718] Dynamic subtest pipe-A: SUCCESS (13.902s)
[167.357544] Starting dynamic subtest: pipe-D
[182.310774] Dynamic subtest pipe-D: SUCCESS (14.963s)
[182.311921] Subtest extended-pageflip-hang-newfb: SUCCESS (28.873s)
[183.187529] [037/138] (825s left) gem_ppgtt (blt-vs-render-ctx0)
[183.237135] Starting subtest: blt-vs-render-ctx0
[215.670960] Subtest blt-vs-render-ctx0: SUCCESS (32.432s)
[215.773322] [038/138] (792s left) kms_ccs (pipe-a-crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[216.169913] Starting subtest: pipe-A-crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[217.281093] Subtest pipe-A-crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (1.111s)
[218.436556] [039/138] (790s left) kms_frontbuffer_tracking (basic)
[218.500587] Starting subtest: basic
[221.728293] Subtest basic: SUCCESS (3.229s)
[221.823592] [040/138] (786s left) gem_exec_schedule (deep)
[222.020585] Starting subtest: deep
[222.022041] Starting dynamic subtest: rcs0
[228.669040] Dynamic subtest rcs0: SUCCESS (6.646s)
[228.682860] Starting dynamic subtest: vcs0
[235.131090] Dynamic subtest vcs0: SUCCESS (6.463s)
[235.145692] Starting dynamic subtest: vcs1
[241.682782] Dynamic subtest vcs1: SUCCESS (6.552s)
[241.696203] Starting dynamic subtest: vecs0
[248.264990] Dynamic subtest vecs0: SUCCESS (6.582s)
[248.279080] Starting dynamic subtest: ccs0
[255.820213] Dynamic subtest ccs0: SUCCESS (7.554s)
[255.821448] Subtest deep: SUCCESS (33.800s)
[256.398652] [041/138] (752s left) gem_exec_create (legacy)
[256.441506] Starting subtest: legacy
[256.443094] Starting dynamic subtest: smem
[260.450247] Dynamic subtest smem: SUCCESS (4.007s)
[260.451658] Subtest legacy: SUCCESS (4.007s)
[260.626036] [042/138] (748s left) gem_userptr_blits (unsync-unmap-after-close)
[260.688568] Subtest unsync-unmap-after-close: SKIP (0.000s)
[260.788378] [043/138] (748s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[260.869307] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[263.421037] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (2.554s)
[263.547876] [044/138] (745s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[263.627808] Starting subtest: yf-tiled-64bpp-rotate-180
[263.629709] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[263.711569] [045/138] (745s left) kms_ccs (pipe-b-crc-primary-basic-yf-tiled-ccs)
[264.084680] Starting subtest: pipe-B-crc-primary-basic-yf-tiled-ccs
[264.088084] Subtest pipe-B-crc-primary-basic-yf-tiled-ccs: SKIP (0.000s)
[264.962944] [046/138] (743s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[265.046528] Starting subtest: psr-modesetfrombusy
[269.306261] Subtest psr-modesetfrombusy: SUCCESS (4.262s)
[269.425290] [047/138] (739s left) v3d/v3d_wait_bo (bad-bo)
[269.532818] Subtest bad-bo: SKIP (0.000s)
[269.579798] [048/138] (739s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[269.651878] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[269.654078] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[269.738128] [049/138] (739s left) gem_exec_whisper (basic-contexts-all)
[269.795438] Starting subtest: basic-contexts-all
[283.423650] Subtest basic-contexts-all: SUCCESS (13.625s)
[283.600111] [050/138] (725s left) kms_pm_dc (dc5-dpms-negative)
[284.681773] Starting subtest: dc5-dpms-negative
[284.684081] Subtest dc5-dpms-negative: SKIP (0.000s)
[284.744048] [051/138] (724s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[284.819104] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[284.821181] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[284.904778] [052/138] (724s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-render)
[284.970188] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-render
[288.022416] Subtest fbc-1p-offscren-pri-shrfb-draw-render: SUCCESS (3.055s)
[288.149129] [053/138] (720s left) gem_mmap_gtt (basic-small-copy-odd)
[288.190564] Subtest basic-small-copy-odd: SKIP (0.000s)
[288.211308] [054/138] (720s left) kms_plane_scaling (planes-upscale-factor-0-25)
[288.259485] Starting subtest: planes-upscale-factor-0-25
[288.260593] Starting dynamic subtest: pipe-A-eDP-1
[288.319802] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.060s)
[288.332739] Starting dynamic subtest: pipe-B-eDP-1
[289.530401] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.207s)
[289.541766] Starting dynamic subtest: pipe-C-eDP-1
[290.734193] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.200s)
[290.747602] Starting dynamic subtest: pipe-D-eDP-1
[291.948863] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.213s)
[291.950054] Subtest planes-upscale-factor-0-25: SUCCESS (3.690s)
[293.185057] [055/138] (715s left) kms_cursor_crc (cursor-onscreen-max-size)
[293.367370] Starting subtest: cursor-onscreen-max-size
[293.370173] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[293.480862] [056/138] (715s left) vc4/vc4_purgeable_bo (mark-willneed)
[293.585683] Subtest mark-willneed: SKIP (0.000s)
[293.633354] [057/138] (715s left) gen9_exec_parse (valid-registers)
[293.703458] Subtest valid-registers: SKIP (0.000s)
[293.722470] [058/138] (715s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[293.783785] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[293.786022] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[293.874118] [059/138] (715s left) sw_sync (sync_merge)
[293.923088] Starting subtest: sync_merge
[293.924311] Subtest sync_merge: SUCCESS (0.000s)
[293.971461] [060/138] (715s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[294.053596] Starting subtest: x-tiled-8bpp-rotate-180
[295.334324] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.280s)
[295.454540] [061/138] (713s left) kms_ccs (pipe-b-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[295.836050] Starting subtest: pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[295.837550] Subtest pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.000s)
[296.683285] [062/138] (712s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[296.755732] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[300.052743] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (3.298s)
[300.181915] [063/138] (709s left) perf (gen8-unprivileged-single-ctx-counters)
[300.317382] Starting subtest: gen8-unprivileged-single-ctx-counters
[300.319536] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[300.338269] [064/138] (708s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[300.368648] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[300.378048] [065/138] (708s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[300.431430] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[300.433625] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[300.499405] [066/138] (708s left) kms_ccs (pipe-d-crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[300.937180] Starting subtest: pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[300.940324] Subtest pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[301.811199] [067/138] (707s left) prime_udl
[301.894878] [068/138] (707s left) i915_pm_freq_api (freq-basic-api)
[301.944028] Starting subtest: freq-basic-api
[301.945063] Starting dynamic subtest: gt0
[301.950581] Dynamic subtest gt0: SUCCESS (0.006s)
[301.957577] Starting dynamic subtest: gt1
[301.958729] Dynamic subtest gt1: SUCCESS (0.003s)
[301.959903] Subtest freq-basic-api: SUCCESS (0.011s)
[302.043200] [069/138] (707s left) kms_ccs (pipe-d-bad-rotation-90-4-tiled-dg2-mc-ccs)
[302.445948] Starting subtest: pipe-D-bad-rotation-90-4-tiled-dg2-mc-ccs
[302.448876] Subtest pipe-D-bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[303.292625] [070/138] (706s left) drm_fdinfo (busy-hang)
[303.339963] Starting subtest: busy-hang
[303.341257] Starting dynamic subtest: rcs0
[303.343522] Dynamic subtest rcs0: SKIP (0.001s)
[303.348416] Starting dynamic subtest: bcs0
[303.350451] Dynamic subtest bcs0: SKIP (0.000s)
[303.354594] Starting dynamic subtest: vcs0
[303.356516] Dynamic subtest vcs0: SKIP (0.000s)
[303.360835] Starting dynamic subtest: vcs1
[303.362852] Dynamic subtest vcs1: SKIP (0.000s)
[303.366976] Starting dynamic subtest: vecs0
[303.368971] Dynamic subtest vecs0: SKIP (0.000s)
[303.373085] Starting dynamic subtest: ccs0
[303.375032] Dynamic subtest ccs0: SKIP (0.000s)
[303.377023] Subtest busy-hang: SKIP (0.004s)
[303.387362] [071/138] (705s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[303.448482] Starting subtest: compare-crc-sanitycheck-nv12
[303.449770] Starting dynamic subtest: pipe-A-eDP-1
[304.138480] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.693s)
[304.154099] Starting dynamic subtest: pipe-B-eDP-1
[305.536477] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.393s)
[305.552157] Starting dynamic subtest: pipe-C-eDP-1
[306.869893] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.331s)
[306.884817] Starting dynamic subtest: pipe-D-eDP-1
[308.205590] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.332s)
[308.210425] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.761s)
[309.109598] [072/138] (700s left) gem_reg_read (timestamp-moving)
[309.150393] Starting subtest: timestamp-moving
[310.153569] Subtest timestamp-moving: SUCCESS (1.001s)
[310.219662] [073/138] (699s left) kms_psr (no_drrs)
[310.276533] Starting subtest: no_drrs
[311.976210] Subtest no_drrs: SUCCESS (1.698s)
[312.885152] [074/138] (696s left) kms_chamelium_frames (hdmi-crc-fast)
[313.232581] Subtest hdmi-crc-fast: SKIP (0.000s)
[314.140260] [075/138] (695s left) gem_ctx_param (invalid-size-get)
[314.177138] Starting subtest: invalid-size-get
[314.178249] Subtest invalid-size-get: SUCCESS (0.000s)
[314.227258] [076/138] (695s left) kms_invalid_mode (bad-htotal)
[314.291610] Starting subtest: bad-htotal
[314.292615] Starting dynamic subtest: pipe-A-eDP-1
[314.582936] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.290s)
[314.596961] Starting dynamic subtest: pipe-B-eDP-1
[314.598057] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[314.605815] Starting dynamic subtest: pipe-C-eDP-1
[314.606757] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[314.611824] Starting dynamic subtest: pipe-D-eDP-1
[314.612711] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[314.613536] Subtest bad-htotal: SUCCESS (0.303s)
[315.524509] [077/138] (693s left) kms_addfb_basic (addfb25-4-tiled)
[315.575206] Starting subtest: addfb25-4-tiled
[315.576321] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[315.656417] [078/138] (693s left) fbdev (write)
[315.689721] Starting subtest: write
[316.082380] Subtest write: SUCCESS (0.390s)
[316.126176] [079/138] (693s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[316.220153] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[316.222388] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[316.308765] [080/138] (693s left) gem_mmap_wc (write-prefaulted)
[316.347190] Subtest write-prefaulted: SKIP (0.000s)
[316.363561] [081/138] (693s left) kms_psr (psr2_sprite_render)
[316.442568] Starting subtest: psr2_sprite_render
[318.237659] Subtest psr2_sprite_render: SUCCESS (1.794s)
[319.169226] [082/138] (690s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[319.224466] Starting subtest: y-tiled-8bpp-rotate-270
[319.226613] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[319.316915] [083/138] (690s left) gem_exec_nop (basic-sequential)
[319.372955] Starting subtest: basic-sequential
[335.528869] Subtest basic-sequential: SUCCESS (16.159s)
[335.734268] [084/138] (673s left) gem_ctx_shared (q-smoketest-all)
[335.810088] Starting subtest: Q-smoketest-all
[368.170801] Subtest Q-smoketest-all: SUCCESS (32.337s)
[369.501760] [085/138] (640s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[369.565850] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu
[374.250920] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (4.686s)
[374.366201] [086/138] (635s left) gen3_render_tiledx_blits
[374.433326] [087/138] (635s left) kms_ccs (pipe-c-crc-primary-basic-4-tiled-dg2-mc-ccs)
[374.830065] Starting subtest: pipe-C-crc-primary-basic-4-tiled-dg2-mc-ccs
[374.832685] Subtest pipe-C-crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[375.665175] [088/138] (634s left) gem_create (create-ext-placement-sanity-check)
[375.828840] Starting subtest: create-ext-placement-sanity-check
[375.833114] Subtest create-ext-placement-sanity-check: SUCCESS (0.005s)
[375.914151] [089/138] (633s left) gem_exec_alignment (single)
[375.965725] Starting subtest: single
[375.966844] Subtest single: SUCCESS (0.002s)
[376.064135] [090/138] (633s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[376.142697] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[376.166030] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.022s)
[376.236589] [091/138] (633s left) gem_pwrite (basic-self)
[376.284621] Subtest basic-self: SKIP (0.000s)
[376.321248] [092/138] (633s left) kms_ccs (pipe-d-crc-primary-rotation-180-y-tiled-ccs)
[376.711569] Starting subtest: pipe-D-crc-primary-rotation-180-y-tiled-ccs
[376.714542] Subtest pipe-D-crc-primary-rotation-180-y-tiled-ccs: SKIP (0.000s)
[377.587729] [093/138] (632s left) gem_exec_schedule (noreorder)
[377.755303] Starting subtest: noreorder
[377.756679] Starting dynamic subtest: rcs0
[377.802507] Dynamic subtest rcs0: SUCCESS (0.049s)
[377.815536] Starting dynamic subtest: vcs0
[379.451078] Dynamic subtest vcs0: SUCCESS (1.645s)
[379.464296] Starting dynamic subtest: vcs1
[381.497858] Dynamic subtest vcs1: SUCCESS (2.045s)
[381.511704] Starting dynamic subtest: vecs0
[383.482401] Dynamic subtest vecs0: SUCCESS (1.982s)
[383.495035] Starting dynamic subtest: ccs0
[398.541853] Dynamic subtest ccs0: FAIL (15.032s)
[398.542978] Subtest noreorder: FAIL (20.761s)
[398.928744] [094/138] (610s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[398.990100] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[399.007952] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.016s)
[399.076253] [095/138] (610s left) gem_madvise (dontneed-before-pwrite)
[399.129886] Starting subtest: dontneed-before-pwrite
[399.133066] Subtest dontneed-before-pwrite: SKIP (0.001s)
[399.159531] [096/138] (610s left) prime_mmap (test_forked)
[399.190558] Starting subtest: test_forked
[399.191799] Starting dynamic subtest: test_forked-smem
[399.237574] Dynamic subtest test_forked-smem: SUCCESS (0.050s)
[399.239578] Subtest test_forked: SUCCESS (0.050s)
[399.334250] [097/138] (610s left) kms_content_protection (content-type-change)
[399.439272] Starting subtest: content-type-change
[402.956685] Subtest content-type-change: SKIP (3.517s)
[404.225804] [098/138] (605s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[404.282558] Subtest writes-after-reads: SKIP (0.000s)
[404.308879] [099/138] (605s left) v3d/v3d_submit_cl (bad-in-sync)
[404.372563] Subtest bad-in-sync: SKIP (0.000s)
[404.418767] [100/138] (605s left) gem_ctx_persistence (engines-mixed-process)
[404.465015] Starting subtest: engines-mixed-process
[404.466105] Starting dynamic subtest: rcs0
[406.462205] Dynamic subtest rcs0: SUCCESS (1.997s)
[406.476549] Starting dynamic subtest: bcs0
[408.451348] Dynamic subtest bcs0: SUCCESS (1.988s)
[408.465883] Starting dynamic subtest: vcs0
[428.483777] Dynamic subtest vcs0: SUCCESS (20.031s)
[428.497649] Starting dynamic subtest: vcs1
[448.552631] Dynamic subtest vcs1: SUCCESS (20.068s)
[448.571100] Starting dynamic subtest: vecs0
[468.608426] Dynamic subtest vecs0: SUCCESS (20.054s)
[468.624465] Starting dynamic subtest: ccs0
[470.471114] Dynamic subtest ccs0: SUCCESS (1.860s)
[470.472407] Subtest engines-mixed-process: SUCCESS (66.006s)
[470.544358] [101/138] (539s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[470.627603] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[470.630114] Starting dynamic subtest: pipe-A-default-mode
[471.829769] Dynamic subtest pipe-A-default-mode: SKIP (1.197s)
[471.832628] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.201s)
[471.964519] [102/138] (538s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[472.013559] Starting subtest: single-wait-all-for-submit-available-signaled
[472.014846] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[472.076382] [103/138] (538s left) kms_ccs (pipe-a-bad-aux-stride-4-tiled-mtl-rc-ccs)
[472.451228] Starting subtest: pipe-A-bad-aux-stride-4-tiled-mtl-rc-ccs
[472.452391] Subtest pipe-A-bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.001s)
[473.345907] [104/138] (536s left) kms_ccs (pipe-c-crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[473.742819] Starting subtest: pipe-C-crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[474.774740] Subtest pipe-C-crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (1.030s)
[475.971834] [105/138] (534s left) sysfs_heartbeat_interval (nopreempt)
[476.018408] Starting subtest: nopreempt
[476.019437] Starting dynamic subtest: vecs0
[485.431490] Dynamic subtest vecs0: SUCCESS (9.408s)
[485.446030] Starting dynamic subtest: ccs0
[489.223697] Dynamic subtest ccs0: SUCCESS (3.794s)
[489.238634] Starting dynamic subtest: vcs0
[498.421850] Dynamic subtest vcs0: SUCCESS (9.197s)
[498.435815] Starting dynamic subtest: bcs0
[502.157922] Dynamic subtest bcs0: SUCCESS (3.732s)
[502.172101] Starting dynamic subtest: rcs0
[505.875629] Dynamic subtest rcs0: SUCCESS (3.716s)
[505.890075] Starting dynamic subtest: vcs1
[515.446415] Dynamic subtest vcs1: SUCCESS (9.568s)
[515.447835] Subtest nopreempt: SUCCESS (39.426s)
[515.516065] [106/138] (494s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[515.893211] Starting subtest: pipe-B-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[517.817961] Subtest pipe-B-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (1.923s)
[519.022149] [107/138] (491s left) kms_ccs (pipe-c-bad-aux-stride-y-tiled-ccs)
[519.401686] Starting subtest: pipe-C-bad-aux-stride-y-tiled-ccs
[519.404318] Subtest pipe-C-bad-aux-stride-y-tiled-ccs: SKIP (0.000s)
[520.244029] [108/138] (489s left) gem_userptr_blits (unsync-unmap)
[520.283148] Subtest unsync-unmap: SKIP (0.000s)
[520.385602] [109/138] (489s left) gem_ctx_param (set-priority-range)
[520.415140] Starting subtest: set-priority-range
[520.433193] Subtest set-priority-range: SUCCESS (0.019s)
[520.528634] [110/138] (489s left) gem_userptr_blits (set-cache-level)
[520.574651] Starting subtest: set-cache-level
[520.576847] Subtest set-cache-level: SKIP (0.000s)
[520.693263] [111/138] (489s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[520.750370] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[520.752222] Starting dynamic subtest: pipe-A-default-mode
[521.045749] Dynamic subtest pipe-A-default-mode: SKIP (0.291s)
[521.047961] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.295s)
[521.975449] [112/138] (488s left) syncobj_timeline (invalid-signal-illegal-handle)
[522.032232] Starting subtest: invalid-signal-illegal-handle
[522.033581] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[522.093852] [113/138] (488s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[522.150932] Starting subtest: single-wait-all-for-submit-unsubmitted
[522.250964] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[522.313811] [114/138] (488s left) gem_mmap_gtt (basic)
[522.345043] Subtest basic: SKIP (0.000s)
[522.362800] [115/138] (488s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[522.416037] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[522.418124] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[522.503556] [116/138] (487s left) kms_flip (wf_vblank-ts-check-interruptible)
[522.557917] Starting subtest: wf_vblank-ts-check-interruptible
[522.564701] Starting dynamic subtest: A-eDP1
[531.555065] Dynamic subtest A-eDP1: SUCCESS (8.990s)
[531.573987] Starting dynamic subtest: B-eDP1
[540.579687] Dynamic subtest B-eDP1: SUCCESS (9.021s)
[540.594081] Starting dynamic subtest: C-eDP1
[549.590119] Dynamic subtest C-eDP1: SUCCESS (9.011s)
[549.606280] Starting dynamic subtest: D-eDP1
[558.552226] Dynamic subtest D-eDP1: SUCCESS (8.960s)
[558.560261] Subtest wf_vblank-ts-check-interruptible: SUCCESS (36.002s)
[559.805764] [117/138] (450s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[559.888463] Starting subtest: yf-tiled-16bpp-rotate-180
[559.890556] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[559.969230] [118/138] (450s left) kms_selftest (drm_plane_helper)
[560.016568] Starting subtest: drm_plane_helper
[560.148726] Starting dynamic subtest: drm_test_check_plane_state
[560.159799] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.013s)
[560.174263] Starting dynamic subtest: drm_test_check_invalid_plane_state
[560.175464] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.001s)
[560.176554] Subtest drm_plane_helper: SUCCESS (0.145s)
[560.335513] [119/138] (450s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[560.401604] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[560.403735] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[560.497037] [120/138] (449s left) syncobj_wait (invalid-wait-zero-handles)
[560.548329] Starting subtest: invalid-wait-zero-handles
[560.549516] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[560.611540] [121/138] (449s left) perf (unprivileged-single-ctx-counters)
[560.716987] Starting subtest: unprivileged-single-ctx-counters
[560.719058] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[560.749787] [122/138] (449s left) kms_plane_alpha_blend (constant-alpha-mid)
[560.815760] Starting subtest: constant-alpha-mid
[561.060102] Starting dynamic subtest: pipe-A-eDP-1
[562.007411] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.946s)
[562.023312] Starting dynamic subtest: pipe-D-eDP-1
[563.700754] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.692s)
[563.702100] Subtest constant-alpha-mid: SUCCESS (2.886s)
[564.596293] [123/138] (445s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[564.654277] Starting subtest: x-tiled-to-vebox-yf-tiled
[564.656079] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[564.831602] [124/138] (445s left) gem_exec_balancer (full-late-pulse)
[564.876763] Starting subtest: full-late-pulse
[569.050131] Subtest full-late-pulse: SUCCESS (4.173s)
[569.300410] [125/138] (441s left) kms_atomic (atomic-plane-damage)
[569.354732] Starting subtest: atomic-plane-damage
[569.402592] Starting dynamic subtest: pipe-A-eDP-1
[569.945132] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.543s)
[569.946328] Subtest atomic-plane-damage: SUCCESS (0.591s)
[570.883585] [126/138] (439s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[570.922307] Starting subtest: etime-multi-wait-all-for-submit-submitted
[571.028486] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.104s)
[571.095738] [127/138] (439s left) kms_cursor_legacy (flip-vs-cursor-toggle)
[571.187047] Starting subtest: flip-vs-cursor-toggle
[577.435611] Subtest flip-vs-cursor-toggle: SUCCESS (6.248s)
[578.375749] [128/138] (432s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[578.480397] Starting subtest: cursorA-vs-flipA-varying-size
[589.627901] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.148s)
[590.530223] [129/138] (420s left) gem_ctx_isolation (nonpriv-switch)
[590.593382] Starting subtest: nonpriv-switch
[590.594644] Starting dynamic subtest: rcs0
[590.751842] Dynamic subtest rcs0: SUCCESS (0.158s)
[590.758472] Starting dynamic subtest: bcs0
[590.961642] Dynamic subtest bcs0: SUCCESS (0.209s)
[590.976100] Starting dynamic subtest: vcs0
[591.162701] Dynamic subtest vcs0: SUCCESS (0.201s)
[591.171133] Starting dynamic subtest: vcs1
[592.630272] Dynamic subtest vcs1: SUCCESS (1.467s)
[592.643307] Starting dynamic subtest: vecs0
[594.679253] Dynamic subtest vecs0: SUCCESS (2.049s)
[594.693050] Starting dynamic subtest: ccs0
[596.708898] Dynamic subtest ccs0: SUCCESS (2.029s)
[596.710071] Subtest nonpriv-switch: SUCCESS (6.116s)
[596.935949] [130/138] (413s left) kms_ccs (pipe-a-missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[597.319280] Starting subtest: pipe-A-missing-ccs-buffer-4-tiled-mtl-rc-ccs
[597.320707] Subtest pipe-A-missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.000s)
[598.174522] [131/138] (412s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[598.230295] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[598.231688] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[598.285738] [132/138] (412s left) gem_mmap_wc (copy)
[598.321485] Subtest copy: SKIP (0.000s)
[598.356619] [133/138] (412s left) kms_cursor_edge_walk (256x256-right-edge)
[598.417883] Starting subtest: 256x256-right-edge
[598.419167] Starting dynamic subtest: pipe-A-eDP-1
[601.974099] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.557s)
[601.990650] Starting dynamic subtest: pipe-D-eDP-1
[605.217627] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.239s)
[605.218755] Subtest 256x256-right-edge: SUCCESS (6.801s)
[606.127891] [134/138] (404s left) kms_ccs (pipe-d-random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[606.512550] Starting subtest: pipe-D-random-ccs-data-4-tiled-mtl-rc-ccs-cc
[607.303049] Subtest pipe-D-random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.791s)
[608.514314] [135/138] (402s left) kms_dsc (dsc-with-bpc-formats)
[608.583389] Subtest dsc-with-bpc-formats: SKIP (0.000s)
[608.594647] [136/138] (402s left) gem_userptr_blits (map-fixed-invalidate)
[608.667025] Starting subtest: map-fixed-invalidate
[608.669309] Subtest map-fixed-invalidate: SKIP (0.000s)
[608.779104] [137/138] (402s left) perf (gen12-invalid-class-instance)
[608.885990] Starting subtest: gen12-invalid-class-instance
[608.894416] Subtest gen12-invalid-class-instance: SUCCESS (0.008s)
[608.910547] [138/138] (401s left) api_intel_bb (full-batch)
[608.948100] Starting subtest: full-batch
[608.951717] Subtest full-batch: SUCCESS (0.004s)
[609.038916] Closing watchdogs
Done.