[41.917455] Initializing watchdogs
[41.917511]   /dev/watchdog0
[41.923596] [001/133] (960s left) gen9_exec_parse (bb-start-param)
[41.996908] Subtest bb-start-param: SKIP (0.000s)
[42.038616] [002/133] (960s left) gem_ctx_isolation (dirty-switch)
[42.108619] Starting subtest: dirty-switch
[42.109516] Starting dynamic subtest: rcs0
[42.625034] Dynamic subtest rcs0: SUCCESS (0.515s)
[42.629215] Starting dynamic subtest: bcs0
[43.170644] Dynamic subtest bcs0: SUCCESS (0.546s)
[43.174894] Starting dynamic subtest: vcs0
[43.735347] Dynamic subtest vcs0: SUCCESS (0.565s)
[43.739508] Starting dynamic subtest: vcs1
[44.267820] Dynamic subtest vcs1: SUCCESS (0.533s)
[44.272146] Starting dynamic subtest: vecs0
[44.808604] Dynamic subtest vecs0: SUCCESS (0.541s)
[44.813095] Starting dynamic subtest: vecs1
[45.358802] Dynamic subtest vecs1: SUCCESS (0.550s)
[45.363234] Starting dynamic subtest: ccs0
[45.898527] Dynamic subtest ccs0: SUCCESS (0.538s)
[45.902839] Starting dynamic subtest: ccs1
[46.451749] Dynamic subtest ccs1: SUCCESS (0.555s)
[46.456162] Starting dynamic subtest: ccs2
[46.979375] Dynamic subtest ccs2: SUCCESS (0.525s)
[46.983695] Starting dynamic subtest: ccs3
[47.498196] Dynamic subtest ccs3: SUCCESS (0.522s)
[47.499124] Subtest dirty-switch: SUCCESS (5.390s)
[47.740104] [003/133] (954s left) vgem_basic (create)
[47.867080] Starting subtest: create
[47.868673] Subtest create: SUCCESS (0.000s)
[47.935281] [004/133] (954s left) gem_exec_schedule (u-independent)
[48.039879] Starting subtest: u-independent
[48.040907] Starting dynamic subtest: rcs0
[48.423419] Dynamic subtest rcs0: SUCCESS (0.383s)
[48.428107] Starting dynamic subtest: bcs0
[48.717341] Dynamic subtest bcs0: SUCCESS (0.294s)
[48.722244] Starting dynamic subtest: vcs0
[49.012197] Dynamic subtest vcs0: SUCCESS (0.295s)
[49.016912] Starting dynamic subtest: vcs1
[49.304537] Dynamic subtest vcs1: SUCCESS (0.292s)
[49.309229] Starting dynamic subtest: vecs0
[49.601174] Dynamic subtest vecs0: SUCCESS (0.297s)
[49.606323] Starting dynamic subtest: vecs1
[49.899303] Dynamic subtest vecs1: SUCCESS (0.298s)
[49.903909] Starting dynamic subtest: ccs0
[50.228192] Dynamic subtest ccs0: SUCCESS (0.329s)
[50.232801] Starting dynamic subtest: ccs1
[50.567376] Dynamic subtest ccs1: SUCCESS (0.339s)
[50.572948] Starting dynamic subtest: ccs2
[50.911240] Dynamic subtest ccs2: SUCCESS (0.344s)
[50.916903] Starting dynamic subtest: ccs3
[51.248511] Dynamic subtest ccs3: SUCCESS (0.337s)
[51.249744] Subtest u-independent: SUCCESS (3.208s)
[51.607464] [005/133] (950s left) syncobj_timeline (invalid-signal-bad-pad)
[51.707234] Starting subtest: invalid-signal-bad-pad
[51.708151] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[51.781404] [006/133] (950s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[53.778899] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[53.780574] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[53.847070] [007/133] (948s left) kms_pm_backlight (bad-brightness)
[55.868497] Subtest bad-brightness: SKIP (0.000s)
[55.887598] [008/133] (946s left) kms_psr (fbc-psr2-primary-blt)
[57.836387] Subtest fbc-psr2-primary-blt: SKIP (0.000s)
[57.854499] [009/133] (944s left) syncobj_timeline (wait-all-snapshot)
[57.912187] Starting subtest: wait-all-snapshot
[57.994694] Subtest wait-all-snapshot: SUCCESS (0.082s)
[58.064955] [010/133] (944s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[60.700228] Subtest verify-pxp-stale-buf-optout-execution: SKIP (0.000s)
[60.771488] [011/133] (941s left) kms_cursor_crc (cursor-onscreen-128x128)
[62.845893] Starting subtest: cursor-onscreen-128x128
[62.857713] Starting dynamic subtest: pipe-A-HDMI-A-3
[65.490690] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.633s)
[65.524104] Starting dynamic subtest: pipe-D-HDMI-A-3
[68.041425] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (2.521s)
[68.042274] Subtest cursor-onscreen-128x128: SUCCESS (5.195s)
[68.324529] [012/133] (934s left) kms_big_joiner (basic)
[70.296321] Subtest basic: SKIP (0.000s)
[70.314334] [013/133] (932s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[72.267347] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[72.268278] Starting dynamic subtest: pipe-A-HDMI-A-3
[72.374856] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.108s)
[72.380293] Starting dynamic subtest: pipe-B-HDMI-A-3
[72.533302] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.158s)
[72.538303] Starting dynamic subtest: pipe-C-HDMI-A-3
[72.673890] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.140s)
[72.678833] Starting dynamic subtest: pipe-D-HDMI-A-3
[72.797430] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.123s)
[72.798545] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SUCCESS (0.531s)
[72.935509] [014/133] (929s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[74.896626] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[75.854108] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.958s)
[75.986276] [015/133] (926s left) gem_exec_balancer (bonded-pair)
[76.058430] Starting subtest: bonded-pair
[76.060342] Subtest bonded-pair: SKIP (0.000s)
[76.319124] [016/133] (926s left) prime_vgem (basic-gtt)
[76.421148] Starting subtest: basic-gtt
[76.422827] Subtest basic-gtt: SKIP (0.000s)
[76.526428] [017/133] (926s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[78.524096] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[78.525745] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[78.585774] [018/133] (924s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[80.589192] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[80.590770] Subtest fbcpsr-1p-pri-indfb-multidraw: SKIP (0.000s)
[80.672077] [019/133] (922s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[82.702275] Starting subtest: psr-rgb565-draw-mmap-gtt
[82.703916] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[82.768707] [020/133] (919s left) i915_pm_rpm (sysfs-read)
[87.619269] Starting subtest: sysfs-read
[88.872660] Subtest sysfs-read: SUCCESS (1.253s)
[90.002021] [021/133] (912s left) kms_psr (psr-primary-render)
[92.006317] Subtest psr-primary-render: SKIP (0.000s)
[92.026059] [022/133] (910s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[93.979625] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[93.981265] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[94.050361] [023/133] (908s left) kms_content_protection (legacy)
[96.113413] Starting subtest: legacy
[97.309253] Subtest legacy: SKIP (1.191s)
[97.455044] [024/133] (905s left) gem_mmap_offset (close-race)
[97.519897] Starting subtest: close-race
[117.565084] Subtest close-race: SUCCESS (20.045s)
[117.674839] [025/133] (885s left) gem_partial_pwrite_pread (write)
[117.776557] Subtest write: SKIP (0.000s)
[117.816287] [026/133] (885s left) prime_vgem (fence-write-hang)
[117.933419] Subtest fence-write-hang: SKIP (0.000s)
[118.026941] [027/133] (884s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[120.018543] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[120.020165] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[120.091276] [028/133] (882s left) gem_userptr_blits (readonly-unsync)
[120.240075] Subtest readonly-unsync: SKIP (0.000s)
[120.379523] [029/133] (882s left) gem_exec_whisper (basic-queues-forked-all)
[120.489324] Starting subtest: basic-queues-forked-all
[144.633267] Subtest basic-queues-forked-all: SUCCESS (24.142s)
[144.810164] [030/133] (858s left) gem_ctx_persistence (legacy-engines-hostile)
[144.871066] Starting subtest: legacy-engines-hostile
[144.871984] Starting dynamic subtest: render
[144.987441] Dynamic subtest render: SUCCESS (0.116s)
[144.994406] Starting dynamic subtest: bsd1
[145.076931] Dynamic subtest bsd1: SUCCESS (0.088s)
[145.084716] Starting dynamic subtest: bsd2
[145.147284] Dynamic subtest bsd2: SUCCESS (0.070s)
[145.157596] Starting dynamic subtest: blt
[145.185826] Dynamic subtest blt: SUCCESS (0.038s)
[145.202727] Starting dynamic subtest: vebox
[145.228169] Dynamic subtest vebox: SUCCESS (0.041s)
[145.229851] Subtest legacy-engines-hostile: SUCCESS (0.357s)
[145.346343] [031/133] (857s left) i915_selftest (live)
[147.063727] Starting subtest: live
[147.064616] Starting dynamic subtest: sanitycheck
[154.020040] Dynamic subtest sanitycheck: SUCCESS (6.956s)
[154.024966] Starting dynamic subtest: uncore
[160.936119] Dynamic subtest uncore: SUCCESS (6.916s)
[160.940927] Starting dynamic subtest: workarounds
[170.282015] Dynamic subtest workarounds: SUCCESS (9.346s)
[170.286831] Starting dynamic subtest: gt_engines
[178.299983] Dynamic subtest gt_engines: SUCCESS (8.018s)
[178.305371] Starting dynamic subtest: gt_timelines
[211.134037] Dynamic subtest gt_timelines: SUCCESS (32.834s)
[211.139065] Starting dynamic subtest: gt_contexts
[218.116953] Dynamic subtest gt_contexts: SUCCESS (6.983s)
[218.122108] Starting dynamic subtest: gt_lrc
[231.653015] Dynamic subtest gt_lrc: SUCCESS (13.536s)
[231.657886] Starting dynamic subtest: gt_mocs
[240.211959] Dynamic subtest gt_mocs: SUCCESS (8.559s)
[240.216904] Starting dynamic subtest: gt_pm
[248.076016] Dynamic subtest gt_pm: SUCCESS (7.864s)
[248.081181] Starting dynamic subtest: gt_heartbeat
[255.234913] Dynamic subtest gt_heartbeat: SUCCESS (7.159s)
[255.240166] Starting dynamic subtest: gt_tlb
[272.843977] Dynamic subtest gt_tlb: SUCCESS (17.609s)
[272.849502] Starting dynamic subtest: requests
[295.538156] Dynamic subtest requests: SUCCESS (22.694s)
[295.543436] Starting dynamic subtest: migrate
[305.855241] Dynamic subtest migrate: SUCCESS (10.317s)
[305.860352] Starting dynamic subtest: active
[312.801935] Dynamic subtest active: SUCCESS (6.946s)
[312.806679] Starting dynamic subtest: objects
[320.635041] Dynamic subtest objects: SUCCESS (7.833s)
[320.639943] Starting dynamic subtest: mman
[335.515027] Dynamic subtest mman: SUCCESS (14.880s)
[335.519657] Starting dynamic subtest: dmabuf
[342.456030] Dynamic subtest dmabuf: SUCCESS (6.940s)
[342.461009] Starting dynamic subtest: vma
[349.417100] Dynamic subtest vma: SUCCESS (6.960s)
[349.422228] Starting dynamic subtest: coherency
[359.191192] Dynamic subtest coherency: SUCCESS (9.773s)
[359.198095] Starting dynamic subtest: gtt
[373.818962] Dynamic subtest gtt: SUCCESS (14.627s)
[373.824180] Starting dynamic subtest: gem
[380.848117] Dynamic subtest gem: SUCCESS (7.028s)
[380.853413] Starting dynamic subtest: evict
[389.548107] Dynamic subtest evict: SUCCESS (8.699s)
[389.553146] Starting dynamic subtest: hugepages
[413.578912] Dynamic subtest hugepages: SUCCESS (24.030s)
[413.585537] Starting dynamic subtest: gem_contexts
[462.528820] Dynamic subtest gem_contexts: SUCCESS (48.949s)
[462.534445] Starting dynamic subtest: client
[469.504983] Dynamic subtest client: SUCCESS (6.975s)
[469.510470] Starting dynamic subtest: gem_migrate
[479.150191] Dynamic subtest gem_migrate: SUCCESS (9.644s)
[479.155252] Starting dynamic subtest: reset
[486.150252] Dynamic subtest reset: SUCCESS (6.999s)
[486.155058] Starting dynamic subtest: memory_region
[494.607055] Dynamic subtest memory_region: SUCCESS (8.456s)
[494.611772] Starting dynamic subtest: hangcheck
[543.812118] Dynamic subtest hangcheck: SUCCESS (49.204s)
[543.817021] Starting dynamic subtest: execlists
[550.749044] Dynamic subtest execlists: SUCCESS (6.936s)
[550.754305] Starting dynamic subtest: ring_submission
[557.687059] Dynamic subtest ring_submission: SUCCESS (6.937s)
[557.692691] Starting dynamic subtest: perf
[564.739838] Dynamic subtest perf: SUCCESS (7.052s)
[564.746300] Starting dynamic subtest: slpc
[579.552048] Dynamic subtest slpc: SUCCESS (14.811s)
[579.557304] Starting dynamic subtest: guc
[587.818078] Dynamic subtest guc: SUCCESS (8.265s)
[587.824206] Starting dynamic subtest: guc_multi_lrc
[594.798877] Dynamic subtest guc_multi_lrc: SUCCESS (6.980s)
[594.803727] Starting dynamic subtest: guc_hang
[602.393866] Dynamic subtest guc_hang: SUCCESS (7.594s)
[602.399099] Starting dynamic subtest: late_gt_pm
[609.399016] Dynamic subtest late_gt_pm: SUCCESS (7.004s)
[609.400163] Subtest live: SUCCESS (462.336s)
[609.641868] [032/133] (393s left) kms_addfb_basic (bad-pitch-0)
[618.355649] Starting subtest: bad-pitch-0
[618.356399] Subtest bad-pitch-0: SUCCESS (0.000s)
[621.230583] [033/133] (381s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[623.194548] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[623.196436] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[623.269101] [034/133] (379s left) kms_fb_coherency (memset-crc)
[625.275388] Starting subtest: memset-crc
[625.276283] Starting dynamic subtest: mmap-offset-fixed
[625.787127] Dynamic subtest mmap-offset-fixed: SUCCESS (0.512s)
[625.844965] Subtest memset-crc: SUCCESS (0.570s)
[625.999389] [035/133] (377s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[627.964651] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[627.966408] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[628.034037] [036/133] (375s left) syncobj_timeline (wait-all-interrupted)
[628.156305] Starting subtest: wait-all-interrupted
[628.265610] Subtest wait-all-interrupted: SUCCESS (0.109s)
[628.356779] [037/133] (374s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[630.354473] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[630.356128] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[630.436691] [038/133] (372s left) kms_flip (basic-flip-vs-modeset)
[632.479469] Starting subtest: basic-flip-vs-modeset
[632.485468] Starting dynamic subtest: A-HDMI-A3
[633.182211] Dynamic subtest A-HDMI-A3: SUCCESS (0.696s)
[633.189093] Starting dynamic subtest: B-HDMI-A3
[633.839563] Dynamic subtest B-HDMI-A3: SUCCESS (0.656s)
[633.845352] Starting dynamic subtest: C-HDMI-A3
[634.490257] Dynamic subtest C-HDMI-A3: SUCCESS (0.650s)
[634.495682] Starting dynamic subtest: D-HDMI-A3
[635.140445] Dynamic subtest D-HDMI-A3: SUCCESS (0.649s)
[635.141332] Subtest basic-flip-vs-modeset: SUCCESS (2.661s)
[635.268052] [039/133] (367s left) prime_mmap_kms (buffer-sharing)
[637.224518] Starting subtest: buffer-sharing
[637.536560] Subtest buffer-sharing: SUCCESS (0.310s)
[637.693859] [040/133] (365s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[639.654026] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[639.658924] Starting dynamic subtest: pipe-A-HDMI-A-3
[640.513484] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.856s)
[640.527396] Starting dynamic subtest: pipe-B-HDMI-A-3
[641.321692] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.802s)
[641.337020] Starting dynamic subtest: pipe-C-HDMI-A-3
[642.162994] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.834s)
[642.177939] Starting dynamic subtest: pipe-D-HDMI-A-3
[642.971427] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.801s)
[642.972491] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (3.319s)
[643.160630] [041/133] (360s left) kms_chamelium_color (ctm-max)
[645.129059] Subtest ctm-max: SKIP (0.000s)
[645.151961] [042/133] (358s left) gem_exec_fence (basic-busy-all)
[645.227175] Starting subtest: basic-busy-all
[645.355985] Subtest basic-busy-all: SUCCESS (0.128s)
[646.430523] [043/133] (356s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[648.391833] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[648.393547] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[648.461876] [044/133] (354s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[650.508026] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[650.509582] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[650.577783] [045/133] (352s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[652.604612] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[652.606642] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[652.679647] [046/133] (350s left) gem_mmap_wc (fault-concurrent)
[652.803063] Subtest fault-concurrent: SKIP (0.000s)
[652.841305] [047/133] (350s left) vgem_slow (nohang)
[652.912224] Starting subtest: nohang
[663.123131] Subtest nohang: SUCCESS (10.210s)
[663.182310] [048/133] (340s left) kms_async_flips (test-cursor)
[665.131571] Starting subtest: test-cursor
[665.294186] Starting dynamic subtest: pipe-A-HDMI-A-3
[665.546420] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.253s)
[665.713452] Starting dynamic subtest: pipe-B-HDMI-A-3
[665.771505] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.064s)
[665.891482] Starting dynamic subtest: pipe-C-HDMI-A-3
[665.958762] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.072s)
[666.079527] Starting dynamic subtest: pipe-D-HDMI-A-3
[666.138009] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.064s)
[666.138855] Subtest test-cursor: SUCCESS (1.007s)
[666.400638] [049/133] (336s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[668.374132] Starting subtest: coverage-vs-premult-vs-constant
[668.585383] Starting dynamic subtest: pipe-A-HDMI-A-3
[669.024570] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.438s)
[669.031512] Starting dynamic subtest: pipe-D-HDMI-A-3
[669.476178] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.451s)
[669.477155] Subtest coverage-vs-premult-vs-constant: SUCCESS (1.104s)
[669.620364] [050/133] (333s left) gem_softpin (allocator-nopin-reserve)
[669.707569] Starting subtest: allocator-nopin-reserve
[669.882892] Subtest allocator-nopin-reserve: SUCCESS (0.175s)
[669.991688] [051/133] (333s left) vc4/vc4_purgeable_bo (mark-unpurgeable-twice)
[670.069816] Subtest mark-unpurgeable-twice: SKIP (0.000s)
[670.117500] [052/133] (333s left) kms_psr2_sf (cursor-plane-move-continuous-sf)
[672.069476] Subtest cursor-plane-move-continuous-sf: SKIP (0.000s)
[672.093668] [053/133] (331s left) kms_addfb_basic (invalid-set-prop-any)
[672.153424] Starting subtest: invalid-set-prop-any
[672.154421] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[674.120481] [054/133] (329s left) gem_exec_params (secure-non-root)
[674.258984] Starting subtest: secure-non-root
[674.261402] Subtest secure-non-root: SKIP (0.000s)
[674.377538] [055/133] (329s left) gem_fence_thrash (bo-write-verify-threaded-none)
[674.459565] Starting subtest: bo-write-verify-threaded-none
[674.470582] Subtest bo-write-verify-threaded-none: SKIP (0.010s)
[674.512248] [056/133] (328s left) kms_vblank (query-busy-hang)
[676.467502] Starting subtest: query-busy-hang
[676.470086] Starting dynamic subtest: pipe-A-HDMI-A-3
[690.057578] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (13.589s)
[690.065673] Starting dynamic subtest: pipe-D-HDMI-A-3
[714.999184] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (24.938s)
[715.000255] Subtest query-busy-hang: SUCCESS (38.532s)
[715.100895] [057/133] (288s left) perf_pmu (all-busy-idle-check-all)
[715.152031] Starting subtest: all-busy-idle-check-all
[715.833787] Subtest all-busy-idle-check-all: SUCCESS (0.682s)
[715.937625] [058/133] (287s left) kms_cursor_edge_walk (128x128-right-edge)
[717.887989] Starting subtest: 128x128-right-edge
[717.888965] Starting dynamic subtest: pipe-A-HDMI-A-3
[721.194745] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (3.306s)
[721.202707] Starting dynamic subtest: pipe-D-HDMI-A-3
[724.385345] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (3.189s)
[724.386240] Subtest 128x128-right-edge: SUCCESS (6.499s)
[724.522294] [059/133] (278s left) kms_psr (fbc-psr-primary-mmap-cpu)
[726.492992] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s)
[726.512015] [060/133] (277s left) gem_pwrite (basic-random)
[726.567568] Subtest basic-random: SKIP (0.000s)
[726.597871] [061/133] (276s left) prime_vgem (basic-fence-mmap)
[726.710898] Starting subtest: basic-fence-mmap
[726.712784] Subtest basic-fence-mmap: SKIP (0.000s)
[726.830710] [062/133] (276s left) perf (gen12-group-concurrent-oa-buffer-read)
[727.197775] Starting subtest: gen12-group-concurrent-oa-buffer-read
[737.243193] Subtest gen12-group-concurrent-oa-buffer-read: SUCCESS (10.046s)
[737.268074] [063/133] (266s left) gem_exec_schedule (preempt-other-chain)
[737.337271] Starting subtest: preempt-other-chain
[737.338057] Starting dynamic subtest: rcs0
[738.200141] Dynamic subtest rcs0: SUCCESS (0.862s)
[738.206640] Starting dynamic subtest: bcs0
[739.018355] Dynamic subtest bcs0: SUCCESS (0.817s)
[739.024980] Starting dynamic subtest: vcs0
[739.892227] Dynamic subtest vcs0: SUCCESS (0.873s)
[739.898678] Starting dynamic subtest: vcs1
[741.058972] Dynamic subtest vcs1: SUCCESS (1.166s)
[741.066022] Starting dynamic subtest: vecs0
[741.918880] Dynamic subtest vecs0: SUCCESS (0.859s)
[741.925333] Starting dynamic subtest: vecs1
[742.729311] Dynamic subtest vecs1: SUCCESS (0.810s)
[742.735981] Starting dynamic subtest: ccs0
[743.751521] Dynamic subtest ccs0: SUCCESS (1.021s)
[743.758390] Starting dynamic subtest: ccs1
[744.566116] Dynamic subtest ccs1: SUCCESS (0.814s)
[744.572762] Starting dynamic subtest: ccs2
[745.502543] Dynamic subtest ccs2: SUCCESS (0.936s)
[745.509130] Starting dynamic subtest: ccs3
[746.326356] Dynamic subtest ccs3: SUCCESS (0.823s)
[746.327143] Subtest preempt-other-chain: SUCCESS (8.989s)
[746.675153] [064/133] (256s left) core_setmaster (master-drop-set-user)
[746.748446] Starting subtest: master-drop-set-user
[746.776389] Subtest master-drop-set-user: SUCCESS (0.028s)
[746.795214] [065/133] (256s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[748.768133] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[748.769863] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[748.862099] [066/133] (254s left) kms_vblank (wait-busy-hang)
[750.892967] Starting subtest: wait-busy-hang
[750.893960] Starting dynamic subtest: pipe-A-HDMI-A-3
[764.993623] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (14.099s)
[765.002050] Starting dynamic subtest: pipe-D-HDMI-A-3
[790.003227] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (25.007s)
[790.004075] Subtest wait-busy-hang: SUCCESS (39.112s)
[790.109111] [067/133] (213s left) api_intel_allocator (two-level-inception-interruptible)
[790.180294] Starting subtest: two-level-inception-interruptible
[795.598194] Subtest two-level-inception-interruptible: SUCCESS (5.418s)
[795.681836] [068/133] (208s left) kms_pm_rpm (i2c)
[800.557079] Starting subtest: i2c
[805.575358] Subtest i2c: SUCCESS (5.019s)
[806.612041] [069/133] (197s left) syncobj_wait (invalid-reset-bad-pad)
[806.744462] Starting subtest: invalid-reset-bad-pad
[806.745406] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[806.859406] [070/133] (196s left) syncobj_wait (wait-all-interrupted)
[806.929334] Starting subtest: wait-all-interrupted
[807.030960] Subtest wait-all-interrupted: SUCCESS (0.101s)
[807.103166] [071/133] (196s left) perf_pmu (init-busy)
[807.152504] Starting subtest: init-busy
[807.153466] Starting dynamic subtest: rcs0
[807.154422] Dynamic subtest rcs0: SUCCESS (0.001s)
[807.161705] Starting dynamic subtest: bcs0
[807.162718] Dynamic subtest bcs0: SUCCESS (0.000s)
[807.169971] Starting dynamic subtest: vcs0
[807.171010] Dynamic subtest vcs0: SUCCESS (0.000s)
[807.177889] Starting dynamic subtest: vcs1
[807.178993] Dynamic subtest vcs1: SUCCESS (0.001s)
[807.185572] Starting dynamic subtest: vecs0
[807.186521] Dynamic subtest vecs0: SUCCESS (0.000s)
[807.193304] Starting dynamic subtest: vecs1
[807.194503] Dynamic subtest vecs1: SUCCESS (0.001s)
[807.201244] Starting dynamic subtest: ccs0
[807.202226] Dynamic subtest ccs0: SUCCESS (0.000s)
[807.209323] Starting dynamic subtest: ccs1
[807.210563] Dynamic subtest ccs1: SUCCESS (0.000s)
[807.217616] Starting dynamic subtest: ccs2
[807.218917] Dynamic subtest ccs2: SUCCESS (0.000s)
[807.226553] Starting dynamic subtest: ccs3
[807.227737] Dynamic subtest ccs3: SUCCESS (0.001s)
[807.229076] Subtest init-busy: SUCCESS (0.013s)
[807.241963] [072/133] (196s left) prime_mmap (test_aperture_limit)
[807.302433] Starting subtest: test_aperture_limit
[807.303377] Starting dynamic subtest: test_aperture_limit-smem
[819.334132] [073/133] (184s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[821.388498] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[821.390160] Subtest fbcpsr-rgb101010-draw-pwrite: SKIP (0.000s)
[821.607712] [074/133] (182s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[823.587688] Starting subtest: extended-pageflip-modeset-hang-oldfb
[823.590472] Starting dynamic subtest: pipe-A
[831.426865] Dynamic subtest pipe-A: SUCCESS (7.837s)
[831.436419] Starting dynamic subtest: pipe-D
[839.168262] Dynamic subtest pipe-D: SUCCESS (7.739s)
[839.169131] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.581s)
[839.317825] [075/133] (164s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[839.395188] Starting subtest: y-tiled-to-vebox-yf-tiled
[839.397626] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[839.606542] [076/133] (164s left) kms_psr (fbc-pr-cursor-plane-move)
[841.572719] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[841.603801] [077/133] (162s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[843.551256] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[846.078430] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (2.528s)
[846.232758] [078/133] (157s left) gem_userptr_blits (huge-split)
[846.305922] Starting subtest: huge-split
[846.331273] Subtest huge-split: SUCCESS (0.025s)
[846.442371] [079/133] (157s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[848.387881] Starting subtest: yf-tiled-32bpp-rotate-0
[848.389503] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[848.462377] [080/133] (155s left) kms_chamelium_frames (hdmi-frame-dump)
[850.536291] Subtest hdmi-frame-dump: SKIP (0.000s)
[850.594356] [081/133] (153s left) kms_getfb (getfb2-accept-ccs)
[850.666282] Starting subtest: getfb2-accept-ccs
[850.667286] Subtest getfb2-accept-ccs: SUCCESS (0.000s)
[850.795044] [082/133] (153s left) perf_pmu (render-node-busy)
[850.896881] Starting subtest: render-node-busy
[850.897790] Starting dynamic subtest: rcs0
[851.480624] Dynamic subtest rcs0: SUCCESS (0.583s)
[851.494121] Starting dynamic subtest: bcs0
[852.047184] Dynamic subtest bcs0: SUCCESS (0.564s)
[852.059040] Starting dynamic subtest: vcs0
[852.608052] Dynamic subtest vcs0: SUCCESS (0.558s)
[852.619432] Starting dynamic subtest: vcs1
[853.171039] Dynamic subtest vcs1: SUCCESS (0.560s)
[853.183321] Starting dynamic subtest: vecs0
[853.731261] Dynamic subtest vecs0: SUCCESS (0.558s)
[853.742833] Starting dynamic subtest: vecs1
[854.291147] Dynamic subtest vecs1: SUCCESS (0.556s)
[854.303352] Starting dynamic subtest: ccs0
[854.860134] Dynamic subtest ccs0: SUCCESS (0.565s)
[854.872246] Starting dynamic subtest: ccs1
[855.424994] Dynamic subtest ccs1: SUCCESS (0.561s)
[855.436787] Starting dynamic subtest: ccs2
[855.993063] Dynamic subtest ccs2: SUCCESS (0.565s)
[856.005147] Starting dynamic subtest: ccs3
[856.557146] Dynamic subtest ccs3: SUCCESS (0.561s)
[856.559112] Subtest render-node-busy: SUCCESS (5.661s)
[856.619418] [083/133] (147s left) v3d/v3d_submit_cl (bad-in-sync)
[856.713876] Subtest bad-in-sync: SKIP (0.000s)
[856.787803] [084/133] (147s left) gem_shrink (reclaim)
[856.877084] Starting subtest: reclaim
[859.086455] Subtest reclaim: SUCCESS (2.209s)
[859.164269] [085/133] (144s left) gem_exec_balancer (semaphore)
[859.297875] Starting subtest: semaphore
[860.240511] Subtest semaphore: SUCCESS (0.943s)
[860.515051] [086/133] (143s left) kms_rotation_crc (sprite-rotation-180)
[862.470955] Starting subtest: sprite-rotation-180
[869.906557] Subtest sprite-rotation-180: SUCCESS (7.436s)
[870.114399] [087/133] (134s left) kms_fbcon_fbt (psr)
[870.192448] Starting subtest: psr
[870.194250] Subtest psr: SKIP (0.000s)
[870.296709] [088/133] (133s left) kms_lease (lessee-list)
[872.258025] Starting subtest: lessee-list
[872.258982] Starting dynamic subtest: pipe-A-HDMI-A-3
[872.260372] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.002s)
[872.269209] Starting dynamic subtest: pipe-B-HDMI-A-3
[872.270218] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.001s)
[872.278276] Starting dynamic subtest: pipe-C-HDMI-A-3
[872.279138] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.001s)
[872.287383] Starting dynamic subtest: pipe-D-HDMI-A-3
[872.288217] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.001s)
[872.289079] Subtest lessee-list: SUCCESS (0.012s)
[872.365218] [089/133] (131s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[874.358477] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[874.361069] Starting dynamic subtest: pipe-A-valid-mode
[875.650174] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.290s)
[875.651091] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (1.292s)
[875.793250] [090/133] (128s left) gem_tiled_partial_pwrite_pread (writes)
[875.873477] Subtest writes: SKIP (0.000s)
[875.953579] [091/133] (128s left) kms_big_fb (linear-64bpp-rotate-0)
[877.918721] Starting subtest: linear-64bpp-rotate-0
[879.988322] Subtest linear-64bpp-rotate-0: SUCCESS (2.070s)
[880.098014] [092/133] (124s left) gem_exec_params (sol-reset-invalid)
[880.155685] Starting subtest: sol-reset-invalid
[880.158919] Subtest sol-reset-invalid: SUCCESS (0.003s)
[880.289534] [093/133] (124s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[882.271969] Starting subtest: bad-rotation-90-y-tiled-ccs
[882.272971] Starting dynamic subtest: pipe-A-HDMI-A-3
[882.275086] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.000s)
[882.283789] Starting dynamic subtest: pipe-B-HDMI-A-3
[882.286037] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.000s)
[882.294465] Starting dynamic subtest: pipe-C-HDMI-A-3
[882.296347] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.000s)
[882.304347] Starting dynamic subtest: pipe-D-HDMI-A-3
[882.306636] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.000s)
[882.309213] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.006s)
[882.337215] [094/133] (121s left) v3d/v3d_perfmon (create-perfmon-exceed)
[882.422313] Subtest create-perfmon-exceed: SKIP (0.000s)
[882.501322] [095/133] (121s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[882.594619] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[882.696585] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.102s)
[882.791571] [096/133] (121s left) gem_ctx_isolation (preservation-reset)
[882.997479] Starting subtest: preservation-reset
[882.998378] Starting dynamic subtest: rcs0
[883.522138] Dynamic subtest rcs0: SUCCESS (0.524s)
[883.531629] Starting dynamic subtest: bcs0
[884.118975] Dynamic subtest bcs0: SUCCESS (0.596s)
[884.128150] Starting dynamic subtest: vcs0
[884.724409] Dynamic subtest vcs0: SUCCESS (0.605s)
[884.733627] Starting dynamic subtest: vcs1
[885.270409] Dynamic subtest vcs1: SUCCESS (0.545s)
[885.279506] Starting dynamic subtest: vecs0
[885.841061] Dynamic subtest vecs0: SUCCESS (0.569s)
[885.850217] Starting dynamic subtest: vecs1
[886.426423] Dynamic subtest vecs1: SUCCESS (0.585s)
[886.435376] Starting dynamic subtest: ccs0
[887.022148] Dynamic subtest ccs0: SUCCESS (0.595s)
[887.031271] Starting dynamic subtest: ccs1
[887.602447] Dynamic subtest ccs1: SUCCESS (0.579s)
[887.611658] Starting dynamic subtest: ccs2
[888.185097] Dynamic subtest ccs2: SUCCESS (0.582s)
[888.193998] Starting dynamic subtest: ccs3
[888.734393] Dynamic subtest ccs3: SUCCESS (0.549s)
[888.735195] Subtest preservation-reset: SUCCESS (5.737s)
[888.897726] [097/133] (115s left) gem_lmem_swapping (heavy-verify-random-ccs)
[899.867202] Starting subtest: heavy-verify-random-ccs
[899.868071] Starting dynamic subtest: lmem0
[912.193357] Dynamic subtest lmem0: SUCCESS (12.325s)
[912.194360] Subtest heavy-verify-random-ccs: SUCCESS (12.326s)
[914.134075] [098/133] ( 90s left) kms_atomic_transition (plane-toggle-modeset-transition)
[924.044947] Starting subtest: plane-toggle-modeset-transition
[924.046037] Starting dynamic subtest: pipe-A-HDMI-A-3
[925.341300] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (1.295s)
[925.349539] Starting dynamic subtest: pipe-B-HDMI-A-3
[926.384362] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (1.041s)
[926.385183] Subtest plane-toggle-modeset-transition: SUCCESS (2.341s)
[927.460731] [099/133] ( 77s left) dumb_buffer (map-invalid-size)
[927.582985] Starting subtest: map-invalid-size
[927.584296] Subtest map-invalid-size: SUCCESS (0.000s)
[927.696638] [100/133] ( 76s left) gem_exec_schedule (pi-userfault)
[927.935541] Starting subtest: pi-userfault
[927.937162] Starting dynamic subtest: rcs0
[927.964486] Dynamic subtest rcs0: SUCCESS (0.035s)
[927.986375] Starting dynamic subtest: bcs0
[927.987737] Dynamic subtest bcs0: SUCCESS (0.016s)
[928.008803] Starting dynamic subtest: vcs0
[928.009787] Dynamic subtest vcs0: SUCCESS (0.022s)
[928.021111] Starting dynamic subtest: vcs1
[928.022019] Dynamic subtest vcs1: SUCCESS (0.010s)
[928.031056] Starting dynamic subtest: vecs0
[928.031893] Dynamic subtest vecs0: SUCCESS (0.007s)
[928.039841] Starting dynamic subtest: vecs1
[928.040722] Dynamic subtest vecs1: SUCCESS (0.007s)
[928.048430] Starting dynamic subtest: ccs0
[928.049244] Dynamic subtest ccs0: SUCCESS (0.009s)
[928.057854] Starting dynamic subtest: ccs1
[928.058654] Dynamic subtest ccs1: SUCCESS (0.006s)
[928.066456] Starting dynamic subtest: ccs2
[928.067250] Dynamic subtest ccs2: SUCCESS (0.006s)
[928.074442] Starting dynamic subtest: ccs3
[928.075275] Dynamic subtest ccs3: SUCCESS (0.007s)
[928.076149] Subtest pi-userfault: SUCCESS (0.138s)
[928.281386] [101/133] ( 76s left) gem_madvise (dontneed-after-mmap)
[928.360834] Starting subtest: dontneed-after-mmap
[928.375453] Subtest dontneed-after-mmap: SUCCESS (0.016s)
[928.496527] [102/133] ( 76s left) kms_plane_scaling (planes-downscale-factor-0-25)
[930.457159] Starting subtest: planes-downscale-factor-0-25
[930.460986] Starting dynamic subtest: pipe-A-HDMI-A-3
[930.475378] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.013s)
[930.485496] Starting dynamic subtest: pipe-B-HDMI-A-3
[930.505177] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.025s)
[930.515353] Starting dynamic subtest: pipe-C-HDMI-A-3
[930.533626] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.025s)
[930.545201] Starting dynamic subtest: pipe-D-HDMI-A-3
[930.563664] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.025s)
[930.565467] Subtest planes-downscale-factor-0-25: SKIP (0.107s)
[930.641154] [103/133] ( 73s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[932.608051] Starting subtest: plane-primary-overlay-mutable-zpos
[934.287010] Subtest plane-primary-overlay-mutable-zpos: SKIP (1.678s)
[934.460493] [104/133] ( 70s left) gem_exec_fair (basic-throttle)
[934.538053] Subtest basic-throttle: SKIP (0.000s)
[934.582060] [105/133] ( 70s left) kms_rotation_crc (primary-rotation-180)
[936.538140] Starting subtest: primary-rotation-180
[946.632918] Subtest primary-rotation-180: SUCCESS (10.095s)
[946.819956] [106/133] ( 57s left) gem_basic (multigpu-create-close)
[946.887570] Starting subtest: multigpu-create-close
[946.889931] Subtest multigpu-create-close: SKIP (0.000s)
[946.965495] [107/133] ( 57s left) syncobj_timeline (multi-wait-available-signaled)
[947.057486] Starting subtest: multi-wait-available-signaled
[947.058466] Subtest multi-wait-available-signaled: SUCCESS (0.000s)
[947.142489] [108/133] ( 57s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[949.092839] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[949.094544] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[949.173351] [109/133] ( 55s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[951.200411] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[951.215710] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.015s)
[951.287721] [110/133] ( 53s left) i915_module_load (reload-with-fault-injection)
[951.374312] Starting subtest: reload-with-fault-injection
[1002.580693] Subtest reload-with-fault-injection: SUCCESS (51.207s)
[1002.594456] [111/133] (  2s left) core_setmaster_vs_auth
[1011.615705] Overall timeout time exceeded, stopping.
[1011.615866] Closing watchdogs
Done.