[42.904750] Initializing watchdogs [42.904892] /dev/watchdog0 [42.917493] [001/133] (960s left) kms_psr (fbc-psr2-primary-blt) [43.039087] Starting subtest: fbc-psr2-primary-blt [43.040407] Starting dynamic subtest: eDP-1 [43.042858] Dynamic subtest eDP-1: SKIP (0.000s) [43.045218] Subtest fbc-psr2-primary-blt: SKIP (0.001s) [43.105485] [002/133] (960s left) gem_ctx_shared (detached-shared-gtt) [43.178660] Starting subtest: detached-shared-gtt [45.335468] Subtest detached-shared-gtt: SUCCESS (2.155s) [45.648811] [003/133] (957s left) gem_exec_whisper (basic-fds-all) [45.737666] Starting subtest: basic-fds-all [66.601127] Subtest basic-fds-all: SUCCESS (20.859s) [66.775245] [004/133] (936s left) gen9_exec_parse (bb-large) [66.847544] Subtest bb-large: SKIP (0.000s) [66.873588] [005/133] (936s left) gem_exec_balancer (parallel-keep-submit-fence) [67.149121] Starting subtest: parallel-keep-submit-fence [68.127019] Subtest parallel-keep-submit-fence: SUCCESS (0.976s) [68.197197] [006/133] (935s left) gem_pxp (create-regular-context-2) [70.802880] Subtest create-regular-context-2: SKIP (0.000s) [70.867663] [007/133] (932s left) kms_psr (psr2-primary-render) [70.961368] Starting subtest: psr2-primary-render [70.962360] Starting dynamic subtest: eDP-1 [72.832018] Dynamic subtest eDP-1: SUCCESS (1.869s) [72.833243] Subtest psr2-primary-render: SUCCESS (1.870s) [73.734755] [008/133] (929s left) vc4/vc4_perfmon (create-single-perfmon) [73.810886] Subtest create-single-perfmon: SKIP (0.000s) [73.854547] [009/133] (929s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip) [73.966049] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip [73.968401] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s) [74.042511] [010/133] (929s left) gem_exec_fair (basic-none-rrul) [74.108262] Subtest basic-none-rrul: SKIP (0.000s) [74.164868] [011/133] (929s left) kms_atomic_transition (plane-all-transition) [74.246892] Starting subtest: plane-all-transition [74.249336] Starting dynamic subtest: pipe-A-eDP-1 [76.756804] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.507s) [77.066120] Starting dynamic subtest: pipe-B-eDP-1 [80.387533] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.332s) [80.685400] Subtest plane-all-transition: SUCCESS (6.438s) [81.609045] [012/133] (922s left) gem_busy (busy) [81.706441] Starting subtest: busy [81.708426] Starting dynamic subtest: all-engines [81.782718] Dynamic subtest all-engines: SUCCESS (0.077s) [81.791564] Starting dynamic subtest: rcs0 [83.482500] Dynamic subtest rcs0: SUCCESS (1.699s) [83.495353] Starting dynamic subtest: bcs0 [83.521674] Dynamic subtest bcs0: SUCCESS (0.039s) [83.533528] Starting dynamic subtest: vcs0 [83.562772] Dynamic subtest vcs0: SUCCESS (0.039s) [83.575085] Starting dynamic subtest: vcs1 [85.461075] Dynamic subtest vcs1: SUCCESS (1.897s) [85.473687] Starting dynamic subtest: vecs0 [87.508845] Dynamic subtest vecs0: SUCCESS (2.048s) [87.521638] Starting dynamic subtest: ccs0 [89.492089] Dynamic subtest ccs0: SUCCESS (1.984s) [89.493715] Subtest busy: SUCCESS (7.784s) [89.834086] [013/133] (913s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa [89.908928] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled [90.020177] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.108s) [90.086609] [014/133] (913s left) kms_feature_discovery (display-3x) [90.175221] Starting subtest: display-3x [90.177448] Subtest display-3x: SKIP (0.000s) [90.232765] [015/133] (913s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0) [90.293823] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0 [91.473627] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.181s) [91.589355] [016/133] (912s left) syncobj_wait (invalid-signal-illegal-handle) [91.657617] Starting subtest: invalid-signal-illegal-handle [91.658837] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [91.737466] [017/133] (912s left) kms_cursor_crc (cursor-rapid-movement-64x21) [91.904171] Starting subtest: cursor-rapid-movement-64x21 [91.934125] Subtest cursor-rapid-movement-64x21: SKIP (0.027s) [92.160665] [018/133] (911s left) kms_async_flips (invalid-async-flip) [92.245065] Starting subtest: invalid-async-flip [92.247571] Subtest invalid-async-flip: SKIP (0.000s) [93.513294] [019/133] (910s left) syncobj_wait (invalid-multi-wait-all-unsubmitted) [93.568739] Starting subtest: invalid-multi-wait-all-unsubmitted [93.569831] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s) [93.640187] [020/133] (910s left) kms_frontbuffer_tracking (fbc-tiling-4) [93.725970] Starting subtest: fbc-tiling-4 [96.898682] Subtest fbc-tiling-4: SUCCESS (3.174s) [97.016466] [021/133] (906s left) gem_tiled_partial_pwrite_pread (reads) [97.105193] Subtest reads: SKIP (0.000s) [97.156368] [022/133] (906s left) prime_vgem (basic-write) [97.305110] Starting subtest: basic-write [97.314677] Subtest basic-write: SKIP (0.009s) [97.403569] [023/133] (906s left) kms_flip (flip-vs-panning) [97.502098] Starting subtest: flip-vs-panning [97.509596] Starting dynamic subtest: A-eDP1 [106.393033] Dynamic subtest A-eDP1: SUCCESS (8.883s) [106.406950] Starting dynamic subtest: B-eDP1 [115.224607] Dynamic subtest B-eDP1: SUCCESS (8.831s) [115.241874] Starting dynamic subtest: C-eDP1 [124.046392] Dynamic subtest C-eDP1: SUCCESS (8.821s) [124.059879] Starting dynamic subtest: D-eDP1 [132.876368] Dynamic subtest D-eDP1: SUCCESS (8.829s) [132.884277] Subtest flip-vs-panning: SUCCESS (35.383s) [134.164416] [024/133] (869s left) perf_pmu (busy-start) [134.219582] Starting subtest: busy-start [134.221025] Starting dynamic subtest: rcs0 [136.774207] Dynamic subtest rcs0: SUCCESS (2.554s) [136.788213] Starting dynamic subtest: bcs0 [139.326362] Dynamic subtest bcs0: SUCCESS (2.552s) [139.339990] Starting dynamic subtest: vcs0 [142.480831] Dynamic subtest vcs0: SUCCESS (3.152s) [142.494201] Starting dynamic subtest: vcs1 [146.449534] Dynamic subtest vcs1: SUCCESS (3.967s) [146.469183] Starting dynamic subtest: vecs0 [150.417463] Dynamic subtest vecs0: SUCCESS (3.967s) [150.438174] Starting dynamic subtest: ccs0 [152.975729] Dynamic subtest ccs0: SUCCESS (2.559s) [152.978834] Subtest busy-start: SUCCESS (18.756s) [153.060044] [025/133] (850s left) kms_properties (invalid-properties-atomic) [153.157615] Starting subtest: invalid-properties-atomic [153.390483] Subtest invalid-properties-atomic: SUCCESS (0.232s) [153.453528] [026/133] (850s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite) [153.568248] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite [158.965723] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.397s) [159.072651] [027/133] (844s left) gem_exec_reloc (basic-write-cpu-noreloc) [159.156738] Subtest basic-write-cpu-noreloc: SKIP (0.000s) [159.218395] [028/133] (844s left) syncobj_wait (wait-all-delayed-signal) [159.300398] Starting subtest: wait-all-delayed-signal [159.403184] Subtest wait-all-delayed-signal: SUCCESS (0.102s) [159.470888] [029/133] (844s left) gem_exec_reloc (basic-wc-read-noreloc) [159.550501] Subtest basic-wc-read-noreloc: SKIP (0.000s) [159.609590] [030/133] (844s left) gem_exec_params (batch-first) [159.671394] Starting subtest: batch-first [159.678293] Subtest batch-first: SUCCESS (0.007s) [159.771587] [031/133] (844s left) v3d/v3d_submit_cl (bad-multisync-out-sync) [159.842830] Subtest bad-multisync-out-sync: SKIP (0.000s) [159.884642] [032/133] (844s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [159.995855] Starting subtest: cursorA-vs-flipA-atomic-transitions [171.188133] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.191s) [172.107842] [033/133] (832s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite) [172.202949] Starting subtest: fbcpsr-rgb101010-draw-pwrite [177.045923] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (4.846s) [177.137780] [034/133] (827s left) kms_psr (fbc-psr-basic) [177.207616] Starting subtest: fbc-psr-basic [177.208744] Starting dynamic subtest: eDP-1 [177.210903] Dynamic subtest eDP-1: SKIP (0.000s) [177.213076] Subtest fbc-psr-basic: SKIP (0.001s) [177.263522] [035/133] (826s left) i915_pm_rpm (system-suspend) [178.813190] Starting subtest: system-suspend [202.372528] Subtest system-suspend: SUCCESS (8.160s) [208.237626] [036/133] (816s left) vc4/vc4_wait_bo (unused-bo-1ns) [208.341479] Subtest unused-bo-1ns: SKIP (0.000s) [208.392962] [037/133] (816s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite) [208.480490] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite [208.482809] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [208.564975] [038/133] (816s left) gem_mmap_gtt (cpuset-big-copy-odd) [208.623404] Subtest cpuset-big-copy-odd: SKIP (0.000s) [208.673716] [039/133] (816s left) kms_invalid_mode (zero-vdisplay) [208.777692] Starting subtest: zero-vdisplay [208.778919] Starting dynamic subtest: pipe-A-eDP-1 [209.061568] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s) [209.078859] Starting dynamic subtest: pipe-B-eDP-1 [209.080117] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [209.089797] Starting dynamic subtest: pipe-C-eDP-1 [209.091160] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [209.097448] Starting dynamic subtest: pipe-D-eDP-1 [209.098613] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [209.099648] Subtest zero-vdisplay: SUCCESS (0.296s) [209.972347] [040/133] (814s left) kms_content_protection (atomic-dpms) [210.105872] Starting subtest: atomic-dpms [213.641174] Subtest atomic-dpms: SKIP (3.533s) [214.899875] [041/133] (809s left) kms_psr2_sf (overlay-plane-update-sf-dmg-area) [220.126047] Starting subtest: overlay-plane-update-sf-dmg-area [220.128952] Starting dynamic subtest: psr2-pipe-A-eDP-1 [226.967843] Dynamic subtest psr2-pipe-A-eDP-1: SUCCESS (6.839s) [226.982153] Starting dynamic subtest: psr2-pipe-B-eDP-1 [233.807138] Dynamic subtest psr2-pipe-B-eDP-1: SUCCESS (6.835s) [233.808496] Subtest overlay-plane-update-sf-dmg-area: SUCCESS (13.680s) [234.740925] [042/133] (790s left) kms_content_protection (type1) [234.893367] Starting subtest: type1 [238.407338] Subtest type1: SKIP (3.513s) [239.664511] [043/133] (785s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip) [239.759461] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip [240.641542] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.882s) [240.778217] [044/133] (784s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs) [240.863639] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs [240.864801] Starting dynamic subtest: pipe-A-eDP-1 [241.337641] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.475s) [241.353180] Starting dynamic subtest: pipe-B-eDP-1 [242.923365] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.585s) [242.937856] Starting dynamic subtest: pipe-C-eDP-1 [244.548264] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.624s) [244.563449] Starting dynamic subtest: pipe-D-eDP-1 [246.109936] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.561s) [246.111155] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (5.248s) [247.348940] [045/133] (777s left) api_intel_allocator (simple-allocator) [247.426726] Starting subtest: simple-allocator [247.427954] Starting dynamic subtest: basic [247.434590] Dynamic subtest basic: SUCCESS (0.008s) [247.451477] Starting dynamic subtest: parallel-one [247.720388] Dynamic subtest parallel-one: SUCCESS (0.282s) [247.727155] Starting dynamic subtest: print [247.728362] Dynamic subtest print: SUCCESS (0.000s) [247.734260] Starting dynamic subtest: reuse [247.735332] Dynamic subtest reuse: SUCCESS (0.000s) [247.740448] Starting dynamic subtest: reserve [247.741442] Dynamic subtest reserve: SUCCESS (0.000s) [247.746601] Starting dynamic subtest: fork-reopen-allocator [247.748881] Dynamic subtest fork-reopen-allocator: SUCCESS (0.024s) [247.750007] Subtest simple-allocator: SUCCESS (0.323s) [247.846743] [046/133] (777s left) sysfs_timeslice_duration (duration) [247.902772] Starting subtest: duration [247.906109] Starting dynamic subtest: vecs0 [261.844951] Dynamic subtest vecs0: SUCCESS (13.938s) [261.860527] Starting dynamic subtest: ccs0 [274.998567] Dynamic subtest ccs0: SUCCESS (13.154s) [275.014098] Starting dynamic subtest: vcs0 [288.853243] Dynamic subtest vcs0: SUCCESS (13.851s) [288.868839] Starting dynamic subtest: bcs0 [303.966279] Dynamic subtest bcs0: SUCCESS (15.114s) [303.982946] Starting dynamic subtest: rcs0 [317.134500] Dynamic subtest rcs0: SUCCESS (13.167s) [317.149950] Starting dynamic subtest: vcs1 [330.837456] Dynamic subtest vcs1: SUCCESS (13.699s) [330.839136] Subtest duration: SUCCESS (82.933s) [330.911675] [047/133] (694s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs) [330.982507] Starting subtest: ccs-on-another-bo-yf-tiled-ccs [330.984707] Starting dynamic subtest: pipe-A-eDP-1 [330.986652] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [331.001452] Starting dynamic subtest: pipe-B-eDP-1 [331.004325] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [331.019023] Starting dynamic subtest: pipe-C-eDP-1 [331.021141] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [331.032091] Starting dynamic subtest: pipe-D-eDP-1 [331.034275] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [331.036203] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.009s) [331.056867] [048/133] (693s left) kms_chamelium_hpd (dp-hpd-after-suspend) [331.412561] Subtest dp-hpd-after-suspend: SKIP (0.000s) [332.310214] [049/133] (692s left) prime_busy (hang) [332.389954] Starting subtest: hang [332.393181] Starting dynamic subtest: rcs0 [346.395319] Dynamic subtest rcs0: SUCCESS (14.002s) [346.409679] Starting dynamic subtest: bcs0 [353.551627] Dynamic subtest bcs0: SUCCESS (7.156s) [353.557340] Starting dynamic subtest: vcs0 [360.527255] Dynamic subtest vcs0: SUCCESS (6.973s) [360.532393] Starting dynamic subtest: vcs1 [367.549175] Dynamic subtest vcs1: SUCCESS (7.022s) [367.564336] Starting dynamic subtest: vecs0 [374.547159] Dynamic subtest vecs0: SUCCESS (6.994s) [374.562245] Starting dynamic subtest: ccs0 [388.384970] Dynamic subtest ccs0: SUCCESS (13.840s) [388.386120] Subtest hang: SUCCESS (55.993s) [388.483308] [050/133] (636s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180) [388.604575] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180 [388.606665] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s) [388.695994] [051/133] (636s left) drm_fdinfo (busy-check-all) [388.763345] Starting subtest: busy-check-all [388.764476] Starting dynamic subtest: rcs0 [388.773457] Dynamic subtest rcs0: SKIP (0.008s) [388.784369] Starting dynamic subtest: bcs0 [388.786461] Dynamic subtest bcs0: SKIP (0.001s) [388.793883] Starting dynamic subtest: vcs0 [388.796246] Dynamic subtest vcs0: SKIP (0.001s) [388.802269] Starting dynamic subtest: vcs1 [388.804270] Dynamic subtest vcs1: SKIP (0.001s) [388.809763] Starting dynamic subtest: vecs0 [388.811801] Dynamic subtest vecs0: SKIP (0.000s) [388.817106] Starting dynamic subtest: ccs0 [388.819256] Dynamic subtest ccs0: SKIP (0.000s) [388.821270] Subtest busy-check-all: SKIP (0.018s) [388.829988] [052/133] (636s left) kms_getfb (getfb-handle-not-fb) [388.885280] Starting subtest: getfb-handle-not-fb [388.910841] Subtest getfb-handle-not-fb: SUCCESS (0.026s) [388.999538] [053/133] (636s left) syncobj_wait (invalid-signal-zero-handles) [389.075953] Starting subtest: invalid-signal-zero-handles [389.077478] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [389.161194] [054/133] (635s left) gem_userptr_blits (invalid-null-pointer) [389.237007] Starting subtest: invalid-null-pointer [389.238601] Subtest invalid-null-pointer: SUCCESS (0.001s) [389.353805] [055/133] (635s left) gem_lmem_swapping (parallel-random-engines) [389.412951] Subtest parallel-random-engines: SKIP (0.000s) [389.428241] [056/133] (635s left) syncobj_timeline (32bits-limit) [389.490459] Starting subtest: 32bits-limit [389.491939] Subtest 32bits-limit: SUCCESS (0.001s) [389.578261] [057/133] (635s left) kms_flip (2x-absolute-wf_vblank) [389.660694] Starting subtest: 2x-absolute-wf_vblank [389.673746] Subtest 2x-absolute-wf_vblank: SKIP (0.014s) [389.745268] [058/133] (635s left) gem_exec_balancer (bonded-semaphore) [389.815930] Starting subtest: bonded-semaphore [389.819719] Subtest bonded-semaphore: SKIP (0.000s) [390.101710] [059/133] (635s left) gem_exec_reloc (basic-cpu) [390.176461] Subtest basic-cpu: SKIP (0.000s) [390.241317] [060/133] (635s left) kms_atomic_interruptible (legacy-cursor) [390.333339] Starting subtest: legacy-cursor [390.336173] Starting dynamic subtest: pipe-A-eDP-1 [397.856012] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.519s) [397.857150] Subtest legacy-cursor: SUCCESS (7.522s) [398.769065] [061/133] (626s left) gem_exec_fence (syncobj-invalid-flags) [399.228917] Starting subtest: syncobj-invalid-flags [399.231013] Subtest syncobj-invalid-flags: SUCCESS (0.000s) [400.935723] [062/133] (624s left) gem_flink_race (flink_close) [400.991829] Starting subtest: flink_close [406.053463] Subtest flink_close: SUCCESS (5.061s) [406.123461] [063/133] (619s left) syncobj_timeline (single-wait-for-submit-signaled) [406.193706] Starting subtest: single-wait-for-submit-signaled [406.194991] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s) [406.290091] [064/133] (619s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs) [406.385562] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs [406.386738] Starting dynamic subtest: pipe-A-eDP-1 [406.388937] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [406.404839] Starting dynamic subtest: pipe-B-eDP-1 [406.411923] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [406.422860] Starting dynamic subtest: pipe-C-eDP-1 [406.424809] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [406.431481] Starting dynamic subtest: pipe-D-eDP-1 [406.433601] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [406.435199] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.010s) [406.448059] [065/133] (618s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [406.506347] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [407.641790] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.136s) [407.770659] [066/133] (617s left) syncobj_wait (wait-for-submit-delayed-submit) [407.843649] Starting subtest: wait-for-submit-delayed-submit [407.946242] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s) [408.018259] [067/133] (617s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt) [408.139603] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt [408.141889] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [408.239447] [068/133] (617s left) syncobj_wait (invalid-signal-one-illegal-handle) [408.293877] Starting subtest: invalid-signal-one-illegal-handle [408.295099] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s) [408.377035] [069/133] (617s left) kms_psr (psr-sprite-mmap-gtt) [408.438199] Starting subtest: psr-sprite-mmap-gtt [408.439503] Starting dynamic subtest: eDP-1 [409.987843] Dynamic subtest eDP-1: SKIP (1.546s) [409.990855] Subtest psr-sprite-mmap-gtt: SKIP (1.548s) [410.120728] [070/133] (615s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render) [410.203329] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render [410.205774] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s) [410.280656] [071/133] (615s left) kms_sequence (get-forked) [410.356778] Starting subtest: get-forked [410.358142] Starting dynamic subtest: pipe-A-eDP-1 [412.247838] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.890s) [412.262818] Starting dynamic subtest: pipe-B-eDP-1 [414.895019] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.644s) [414.913468] Starting dynamic subtest: pipe-C-eDP-1 [417.482212] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.584s) [417.499167] Starting dynamic subtest: pipe-D-eDP-1 [420.121904] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.637s) [420.123153] Subtest get-forked: SUCCESS (9.766s) [421.057607] [072/133] (604s left) gem_exec_params (readonly) [421.122053] Starting subtest: readonly [421.125707] Subtest readonly: SUCCESS (0.004s) [421.230874] [073/133] (604s left) gem_exec_schedule (reorder-wide) [421.425060] Subtest reorder-wide: SKIP (0.000s) [421.601922] [074/133] (604s left) kms_cursor_legacy (torture-bo) [421.697262] Starting subtest: torture-bo [421.698472] Starting dynamic subtest: pipe-A [428.202122] Dynamic subtest pipe-A: SUCCESS (6.500s) [428.219044] Starting dynamic subtest: pipe-B [435.215153] Dynamic subtest pipe-B: SUCCESS (7.012s) [435.231114] Starting dynamic subtest: pipe-C [441.262932] Dynamic subtest pipe-C: SUCCESS (6.048s) [441.278152] Starting dynamic subtest: pipe-D [447.184917] Dynamic subtest pipe-D: SUCCESS (5.916s) [447.202646] Starting dynamic subtest: all-pipes [453.380317] Dynamic subtest all-pipes: SUCCESS (6.198s) [453.382358] Subtest torture-bo: SUCCESS (31.682s) [453.531849] [075/133] (572s left) kms_writeback (writeback-invalid-parameters) [453.638295] Subtest writeback-invalid-parameters: SKIP (0.000s) [453.676465] [076/133] (571s left) sw_sync (sync_expired_merge) [453.738226] Starting subtest: sync_expired_merge [454.815366] Subtest sync_expired_merge: SUCCESS (1.074s) [454.862055] [077/133] (570s left) gem_exec_schedule (manyslice) [454.946339] Starting subtest: manyslice [454.947480] Starting dynamic subtest: rcs0 [460.549730] Dynamic subtest rcs0: SUCCESS (5.604s) [460.566088] Starting dynamic subtest: bcs0 [480.042718] Dynamic subtest bcs0: SUCCESS (19.491s) [480.060532] Starting dynamic subtest: vcs0 [486.118251] Dynamic subtest vcs0: SUCCESS (6.074s) [486.137136] Starting dynamic subtest: vcs1 [492.689339] Dynamic subtest vcs1: SUCCESS (6.570s) [492.703139] Starting dynamic subtest: vecs0 [499.252900] Dynamic subtest vecs0: SUCCESS (6.562s) [499.269397] Starting dynamic subtest: ccs0 [505.398024] Dynamic subtest ccs0: SUCCESS (6.143s) [505.399691] Subtest manyslice: SUCCESS (50.454s) [505.705852] [078/133] (520s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions) [505.828801] Starting subtest: cursorB-vs-flipA-atomic-transitions [505.831144] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s) [505.912943] [079/133] (519s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move) [506.005279] Starting subtest: psr-1p-primscrn-cur-indfb-move [511.516730] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (5.512s) [511.636353] [080/133] (514s left) gem_userptr_blits (sync-unmap-cycles) [511.727736] Starting subtest: sync-unmap-cycles [517.100525] Subtest sync-unmap-cycles: SUCCESS (5.375s) [517.211228] [081/133] (508s left) gem_create (create-ext-cpu-access-big) [517.379875] Starting subtest: create-ext-cpu-access-big [517.383859] Subtest create-ext-cpu-access-big: SKIP (0.001s) [517.452732] [082/133] (508s left) gem_spin_batch (resubmit-all) [517.521753] Starting subtest: resubmit-all [517.525056] Starting dynamic subtest: rcs0 [517.566435] Dynamic subtest rcs0: SUCCESS (0.044s) [517.578811] Starting dynamic subtest: bcs0 [517.579823] Dynamic subtest bcs0: SUCCESS (0.003s) [517.585363] Starting dynamic subtest: vcs0 [517.586366] Dynamic subtest vcs0: SUCCESS (0.002s) [517.591794] Starting dynamic subtest: vcs1 [517.592786] Dynamic subtest vcs1: SUCCESS (0.002s) [517.598129] Starting dynamic subtest: vecs0 [517.599154] Dynamic subtest vecs0: SUCCESS (0.002s) [517.604482] Starting dynamic subtest: ccs0 [517.605458] Dynamic subtest ccs0: SUCCESS (0.002s) [517.606449] Subtest resubmit-all: SUCCESS (0.058s) [517.813994] [083/133] (508s left) kms_flip (2x-modeset-vs-vblank-race-interruptible) [517.900804] Starting subtest: 2x-modeset-vs-vblank-race-interruptible [517.914454] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.014s) [517.989847] [084/133] (507s left) kms_big_fb (4-tiled-64bpp-rotate-90) [518.106526] Starting subtest: 4-tiled-64bpp-rotate-90 [518.126790] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.020s) [518.226861] [085/133] (507s left) api_intel_bb (reset-flags) [518.277475] Starting subtest: reset-flags [518.294880] Subtest reset-flags: SUCCESS (0.019s) [518.397203] [086/133] (507s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt) [518.470495] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt [523.667162] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (5.199s) [523.785112] [087/133] (502s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite) [523.894128] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite [527.126631] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (3.232s) [527.246215] [088/133] (498s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt) [527.322719] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt [527.325083] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s) [527.409591] [089/133] (498s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling) [527.519918] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling [527.521809] Starting dynamic subtest: pipe-A-default-mode [528.735885] Dynamic subtest pipe-A-default-mode: SKIP (1.212s) [528.738238] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.214s) [528.872990] [090/133] (497s left) gen3_render_mixed_blits [529.006655] [091/133] (497s left) syncobj_wait (single-wait-for-submit-signaled) [529.057603] Starting subtest: single-wait-for-submit-signaled [529.058806] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s) [529.129559] [092/133] (496s left) kms_pm_dc (dc3co-vpb-simulation) [530.207345] Starting subtest: dc3co-vpb-simulation [539.177798] Subtest dc3co-vpb-simulation: SKIP (8.967s) [539.293650] [093/133] (486s left) gem_exercise_blt (fast-copy) [539.371907] Starting subtest: fast-copy [539.373066] Starting dynamic subtest: linear-smem-smem [539.403281] Dynamic subtest linear-smem-smem: SUCCESS (0.031s) [539.420123] Starting dynamic subtest: xmajor-smem-smem [539.423960] Dynamic subtest xmajor-smem-smem: SUCCESS (0.020s) [539.440296] Starting dynamic subtest: tile4-smem-smem [539.443340] Dynamic subtest tile4-smem-smem: SUCCESS (0.018s) [539.457541] Starting dynamic subtest: tile64-smem-smem [539.458947] Dynamic subtest tile64-smem-smem: SUCCESS (0.017s) [539.460006] Subtest fast-copy: SUCCESS (0.087s) [539.567181] [094/133] (486s left) gem_ctx_param (invalid-size-get) [539.628704] Starting subtest: invalid-size-get [539.630241] Subtest invalid-size-get: SUCCESS (0.001s) [539.715825] [095/133] (486s left) i915_hangman (hangcheck-unterminated) [539.781914] Starting subtest: hangcheck-unterminated [550.870637] Subtest hangcheck-unterminated: SUCCESS (11.086s) [550.976456] [096/133] (475s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs) [551.072383] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs [551.074835] Subtest y-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s) [551.261546] [097/133] (474s left) kms_force_connector_basic (force-load-detect) [551.332507] Starting subtest: force-load-detect [551.335382] Subtest force-load-detect: SKIP (0.000s) [551.418691] [098/133] (474s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling) [551.501352] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling [551.502615] Starting dynamic subtest: pipe-A-default-mode [554.444897] Dynamic subtest pipe-A-default-mode: SUCCESS (2.944s) [554.446155] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.945s) [555.381764] [099/133] (470s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render) [555.466681] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render [555.468480] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [555.548510] [100/133] (470s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted) [555.603172] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted [555.604267] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s) [555.685749] [101/133] (470s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move) [555.794728] Starting subtest: fbc-1p-primscrn-spr-indfb-move [559.073857] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.279s) [559.208274] [102/133] (467s left) kms_chamelium_color (ctm-limited-range) [559.314268] Subtest ctm-limited-range: SKIP (0.000s) [559.357107] [103/133] (467s left) v3d/v3d_submit_csd (valid-multisync-submission) [559.428006] Subtest valid-multisync-submission: SKIP (0.000s) [559.468968] [104/133] (466s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render) [559.546275] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render [559.548828] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [559.639932] [105/133] (466s left) i915_suspend (basic-s3-without-i915) [559.675177] Starting subtest: basic-s3-without-i915 [561.265693] Subtest basic-s3-without-i915: SKIP (1.586s) [562.284167] [106/133] (464s left) kms_psr2_sf (cursor-plane-update-sf) [567.579841] Starting subtest: cursor-plane-update-sf [567.583534] Starting dynamic subtest: psr2-pipe-A-eDP-1 [568.976149] Dynamic subtest psr2-pipe-A-eDP-1: SUCCESS (1.393s) [568.992721] Starting dynamic subtest: psr2-pipe-B-eDP-1 [570.351618] Dynamic subtest psr2-pipe-B-eDP-1: SUCCESS (1.373s) [570.352879] Subtest cursor-plane-update-sf: SUCCESS (2.773s) [571.248311] [107/133] (455s left) gem_pxp (verify-pxp-stale-buf-execution) [573.873453] Subtest verify-pxp-stale-buf-execution: SKIP (0.000s) [573.938389] [108/133] (452s left) kms_plane (pixel-format) [574.032781] Starting subtest: pixel-format [574.033810] Starting dynamic subtest: pipe-A [584.301402] Dynamic subtest pipe-A: SUCCESS (10.266s) [584.320063] Starting dynamic subtest: pipe-B [595.425753] Dynamic subtest pipe-B: SUCCESS (11.122s) [595.427005] Subtest pixel-format: SUCCESS (21.394s) [596.308160] [109/133] (430s left) gem_wait (await) [596.383116] Starting subtest: await [596.384090] Starting dynamic subtest: all-engines [597.465918] Dynamic subtest all-engines: SUCCESS (1.082s) [597.492623] Starting dynamic subtest: rcs0 [598.531366] Dynamic subtest rcs0: SUCCESS (1.063s) [598.561997] Starting dynamic subtest: bcs0 [599.595463] Dynamic subtest bcs0: SUCCESS (1.063s) [599.620794] Starting dynamic subtest: vcs0 [600.658815] Dynamic subtest vcs0: SUCCESS (1.062s) [600.684684] Starting dynamic subtest: vcs1 [601.721175] Dynamic subtest vcs1: SUCCESS (1.060s) [601.745218] Starting dynamic subtest: vecs0 [602.783829] Dynamic subtest vecs0: SUCCESS (1.061s) [602.808728] Starting dynamic subtest: ccs0 [603.844946] Dynamic subtest ccs0: SUCCESS (1.060s) [603.846614] Subtest await: SUCCESS (7.462s) [604.051707] [110/133] (422s left) gem_userptr_blits (sync-unmap) [604.143102] Starting subtest: sync-unmap [604.166057] Subtest sync-unmap: SUCCESS (0.023s) [604.278659] [111/133] (422s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite) [604.372698] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite [604.375230] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [604.467606] [112/133] (422s left) gem_ctx_persistence (engines-mixed) [604.555732] Starting subtest: engines-mixed [604.557113] Starting dynamic subtest: rcs0 [604.626471] Dynamic subtest rcs0: SUCCESS (0.070s) [604.642462] Starting dynamic subtest: bcs0 [604.671938] Dynamic subtest bcs0: SUCCESS (0.045s) [604.691029] Starting dynamic subtest: vcs0 [604.724966] Dynamic subtest vcs0: SUCCESS (0.052s) [604.740972] Starting dynamic subtest: vcs1 [604.777919] Dynamic subtest vcs1: SUCCESS (0.052s) [604.793626] Starting dynamic subtest: vecs0 [604.828929] Dynamic subtest vecs0: SUCCESS (0.050s) [604.844093] Starting dynamic subtest: ccs0 [604.883329] Dynamic subtest ccs0: SUCCESS (0.053s) [604.884645] Subtest engines-mixed: SUCCESS (0.328s) [604.959499] [113/133] (421s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180) [605.050654] Starting subtest: primary-yf-tiled-reflect-x-180 [605.111886] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s) [605.197313] [114/133] (421s left) gem_exec_flush (basic-wb-rw-before-default) [605.261066] Starting subtest: basic-wb-rw-before-default [610.753802] Subtest basic-wb-rw-before-default: SUCCESS (5.494s) [610.943683] [115/133] (415s left) kms_mmap_write_crc (main) [611.054575] Starting subtest: main [611.055740] Starting dynamic subtest: pipe-A-eDP-1 [614.815035] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.757s) [614.816467] Subtest main: SUCCESS (3.758s) [615.733013] [116/133] (410s left) kms_vblank (wait-forked-hang) [615.799827] Starting subtest: wait-forked-hang [615.801607] Starting dynamic subtest: pipe-A-eDP-1 [629.703958] Dynamic subtest pipe-A-eDP-1: SUCCESS (13.904s) [629.726594] Starting dynamic subtest: pipe-D-eDP-1 [644.688430] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.977s) [644.689937] Subtest wait-forked-hang: SUCCESS (28.889s) [645.620565] [117/133] (381s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc) [645.690051] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc [645.691230] Starting dynamic subtest: pipe-A-eDP-1 [646.002201] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.309s) [646.019807] Starting dynamic subtest: pipe-B-eDP-1 [647.397729] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.394s) [647.416161] Starting dynamic subtest: pipe-C-eDP-1 [648.783530] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.383s) [648.801594] Starting dynamic subtest: pipe-D-eDP-1 [650.190899] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.405s) [650.192045] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.502s) [651.458085] [118/133] (375s left) gem_eio (in-flight-contexts-1us) [651.686463] Starting subtest: in-flight-contexts-1us [664.929375] Subtest in-flight-contexts-1us: SUCCESS (13.243s) [665.073114] [119/133] (361s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render) [665.180679] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render [665.183004] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [665.259275] [120/133] (361s left) tools_test (sysfs_l3_parity) [665.322631] Starting subtest: sysfs_l3_parity [665.428569] Subtest sysfs_l3_parity: SKIP (0.112s) [665.484573] [121/133] (361s left) kms_pm_rpm (dpms-non-lpsp) [667.003935] Starting subtest: dpms-non-lpsp [667.006704] Subtest dpms-non-lpsp: SKIP (0.000s) [667.799044] [122/133] (359s left) kms_plane (planar-pixel-format-settings) [667.893346] Starting subtest: planar-pixel-format-settings [667.917965] Subtest planar-pixel-format-settings: SUCCESS (0.027s) [667.994493] [123/133] (358s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode) [668.368673] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s) [669.274735] [124/133] (357s left) drm_read (short-buffer-wakeup) [669.417206] Starting subtest: short-buffer-wakeup [701.634603] Subtest short-buffer-wakeup: SUCCESS (32.217s) [701.738519] [125/133] (325s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-20x20) [701.836202] Starting subtest: planes-downscale-factor-0-25-upscale-20x20 [701.838924] Starting dynamic subtest: pipe-A-eDP-1 [701.854574] Dynamic subtest pipe-A-eDP-1: SKIP (0.014s) [701.869583] Starting dynamic subtest: pipe-B-eDP-1 [701.871656] Dynamic subtest pipe-B-eDP-1: SKIP (0.016s) [701.880313] Starting dynamic subtest: pipe-C-eDP-1 [701.887455] Dynamic subtest pipe-C-eDP-1: SKIP (0.016s) [701.894351] Starting dynamic subtest: pipe-D-eDP-1 [701.904879] Dynamic subtest pipe-D-eDP-1: SKIP (0.016s) [701.906918] Subtest planes-downscale-factor-0-25-upscale-20x20: SKIP (0.069s) [701.992975] [126/133] (325s left) v3d/v3d_perfmon (destroy-valid-perfmon) [702.086948] Subtest destroy-valid-perfmon: SKIP (0.000s) [702.130860] [127/133] (324s left) kms_psr (pr-sprite-render) [702.229672] Starting subtest: pr-sprite-render [702.231858] Subtest pr-sprite-render: SKIP (0.000s) [702.296258] [128/133] (324s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw) [702.395138] Starting subtest: psr-2p-pri-indfb-multidraw [702.397366] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s) [702.493957] [129/133] (324s left) kms_cursor_crc (cursor-sliding-128x128) [702.748480] Starting subtest: cursor-sliding-128x128 [702.754191] Starting dynamic subtest: pipe-A-eDP-1 [706.554384] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.799s) [706.592312] Starting dynamic subtest: pipe-D-eDP-1 [711.091022] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.517s) [711.092764] Subtest cursor-sliding-128x128: SUCCESS (8.343s) [711.989033] [130/133] (315s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff) [712.066911] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff [715.011088] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (2.945s) [715.137169] [131/133] (311s left) kms_big_fb (yf-tiled-addfb) [715.238957] Starting subtest: yf-tiled-addfb [715.241217] Subtest yf-tiled-addfb: SKIP (0.000s) [715.337559] [132/133] (311s left) syncobj_timeline (multi-wait-all-for-submit-signaled) [715.408993] Starting subtest: multi-wait-all-for-submit-signaled [715.410641] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s) [715.494002] [133/133] (311s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc) [715.583386] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc [715.585386] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [715.666966] Closing watchdogs Done.