[58.495573] Initializing watchdogs
[58.495617]   /dev/watchdog0
[58.508550] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.512660] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.514889] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.518364] [001/132] (960s left) gem_softpin (evict-snoop)
[58.611955] Starting subtest: evict-snoop
[58.616278] Subtest evict-snoop: SKIP (0.000s)
[58.770368] [002/132] (960s left) kms_scaling_modes (scaling-mode-full-aspect)
[58.870966] Starting subtest: scaling-mode-full-aspect
[58.873352] Starting dynamic subtest: pipe-A-eDP-1
[59.021944] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.152s)
[59.030970] Starting dynamic subtest: pipe-B-eDP-1
[60.190071] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.165s)
[60.198080] Starting dynamic subtest: pipe-C-eDP-1
[61.296239] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.104s)
[61.309278] Starting dynamic subtest: pipe-D-eDP-1
[62.419652] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s)
[62.421911] Subtest scaling-mode-full-aspect: SUCCESS (3.552s)
[63.618379] [003/132] (955s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[63.726340] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[70.915893] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.193s)
[71.084360] [004/132] (948s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[76.100860] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[76.103355] Starting dynamic subtest: pipe-A-eDP-1
[93.270761] Dynamic subtest pipe-A-eDP-1: SUCCESS (17.165s)
[93.279781] Starting dynamic subtest: pipe-B-eDP-1
[110.414429] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.142s)
[110.416877] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (34.311s)
[111.240527] [005/132] (907s left) i915_suspend (fence-restore-tiled2untiled)
[111.302823] Starting subtest: fence-restore-tiled2untiled
[111.308000] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[111.435406] [006/132] (907s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt)
[111.543402] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt
[111.547379] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[111.652412] [007/132] (907s left) gem_exec_balancer (parallel-contexts)
[111.909438] Starting subtest: parallel-contexts
[114.402038] Subtest parallel-contexts: SUCCESS (2.493s)
[114.545990] [008/132] (904s left) gem_ctx_persistence (legacy-engines-cleanup)
[114.606797] Starting subtest: legacy-engines-cleanup
[114.608679] Starting dynamic subtest: render
[114.706580] Dynamic subtest render: SUCCESS (0.102s)
[114.716547] Starting dynamic subtest: bsd1
[114.773587] Dynamic subtest bsd1: SUCCESS (0.065s)
[114.782879] Starting dynamic subtest: bsd2
[114.846897] Dynamic subtest bsd2: SUCCESS (0.071s)
[114.856319] Starting dynamic subtest: blt
[114.904815] Dynamic subtest blt: SUCCESS (0.056s)
[114.913542] Starting dynamic subtest: vebox
[114.961840] Dynamic subtest vebox: SUCCESS (0.055s)
[114.964662] Subtest legacy-engines-cleanup: SUCCESS (0.358s)
[115.060994] [009/132] (904s left) kms_psr (fbc-psr2-sprite-blt)
[115.161571] Starting subtest: fbc-psr2-sprite-blt
[115.163734] Starting dynamic subtest: eDP-1
[115.167867] Dynamic subtest eDP-1: SKIP (0.000s)
[115.172063] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[115.256366] [010/132] (904s left) kms_pipe_crc_basic (suspend-read-crc)
[115.348057] Starting subtest: suspend-read-crc
[115.350323] Starting dynamic subtest: pipe-A-eDP-1
[133.192990] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.231s)
[133.202300] Starting dynamic subtest: pipe-B-eDP-1
[151.175855] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.116s)
[151.185121] Starting dynamic subtest: pipe-C-eDP-1
[169.116700] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.979s)
[169.133415] Starting dynamic subtest: pipe-D-eDP-1
[187.152037] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.085s)
[187.154977] Subtest suspend-read-crc: SUCCESS (15.414s)
[188.000976] [011/132] (831s left) gem_exec_schedule (u-semaphore-resolve)
[188.098400] Starting subtest: u-semaphore-resolve
[188.131900] Subtest u-semaphore-resolve: SUCCESS (0.040s)
[188.497465] [012/132] (830s left) gem_vm_create (isolation)
[188.557560] Starting subtest: isolation
[188.567426] Subtest isolation: SUCCESS (0.010s)
[188.688583] [013/132] (830s left) gem_exec_reloc (basic-cpu-gtt-active)
[188.749496] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[188.832518] [014/132] (830s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[189.079166] Starting subtest: cursor-rapid-movement-256x85
[189.145529] Subtest cursor-rapid-movement-256x85: SKIP (0.058s)
[189.341517] [015/132] (830s left) kms_plane_scaling (invalid-num-scalers)
[189.434157] Starting subtest: invalid-num-scalers
[189.436319] Starting dynamic subtest: pipe-A-eDP-1-invalid-num-scalers
[189.450303] Dynamic subtest pipe-A-eDP-1-invalid-num-scalers: SUCCESS (0.018s)
[189.458334] Starting dynamic subtest: pipe-B-eDP-1-invalid-num-scalers
[189.482331] Dynamic subtest pipe-B-eDP-1-invalid-num-scalers: SUCCESS (0.029s)
[189.489960] Starting dynamic subtest: pipe-C-eDP-1-invalid-num-scalers
[189.512250] Dynamic subtest pipe-C-eDP-1-invalid-num-scalers: SUCCESS (0.030s)
[189.520327] Starting dynamic subtest: pipe-D-eDP-1-invalid-num-scalers
[189.543273] Dynamic subtest pipe-D-eDP-1-invalid-num-scalers: SUCCESS (0.028s)
[189.545329] Subtest invalid-num-scalers: SUCCESS (0.109s)
[189.675223] [016/132] (829s left) prime_self_import (basic-llseek-size)
[189.717714] Starting subtest: basic-llseek-size
[189.737390] Subtest basic-llseek-size: SUCCESS (0.020s)
[189.885798] [017/132] (829s left) syncobj_wait (multi-wait-for-submit-submitted)
[189.938631] Starting subtest: multi-wait-for-submit-submitted
[190.044484] Subtest multi-wait-for-submit-submitted: SUCCESS (0.105s)
[190.139978] [018/132] (829s left) kms_dsc (dsc-with-output-formats-with-bpc)
[190.234979] Starting subtest: dsc-with-output-formats-with-bpc
[190.295514] Subtest dsc-with-output-formats-with-bpc: SKIP (0.001s)
[190.330022] [019/132] (829s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[190.447387] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[190.451932] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[190.612421] [020/132] (829s left) kms_setmode (basic)
[190.681191] Starting subtest: basic
[191.035232] Starting dynamic subtest: pipe-A-eDP-1
[194.609521] Dynamic subtest pipe-A-eDP-1: FAIL (3.431s)
[194.805753] Starting dynamic subtest: pipe-B-eDP-1
[198.429993] Dynamic subtest pipe-B-eDP-1: FAIL (3.479s)
[199.293587] Subtest basic: FAIL (8.603s)
[199.681976] [021/132] (820s left) gem_readwrite (write-bad-handle)
[199.745723] Subtest write-bad-handle: SKIP (0.000s)
[199.802416] [022/132] (820s left) perf (polling-parameterized)
[199.948394] Starting subtest: polling-parameterized
[219.972505] Subtest polling-parameterized: SUCCESS (20.013s)
[220.018275] [023/132] (799s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[220.133775] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[220.139341] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[220.264819] [024/132] (799s left) fbdev (eof)
[220.321629] Starting subtest: eof
[220.324940] Subtest eof: SUCCESS (0.000s)
[220.413544] [025/132] (799s left) kms_async_flips (alternate-sync-async-flip)
[220.507541] Starting subtest: alternate-sync-async-flip
[220.874650] Starting dynamic subtest: pipe-A-eDP-1
[223.656808] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.782s)
[224.026085] Starting dynamic subtest: pipe-B-eDP-1
[226.786229] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.766s)
[227.154714] Starting dynamic subtest: pipe-C-eDP-1
[229.921157] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.772s)
[230.290490] Starting dynamic subtest: pipe-D-eDP-1
[233.054894] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.773s)
[233.057472] Subtest alternate-sync-async-flip: SUCCESS (12.544s)
[234.238744] [026/132] (785s left) syncobj_timeline (multi-wait-all-signaled)
[234.296548] Starting subtest: multi-wait-all-signaled
[234.299728] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[234.451911] [027/132] (785s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[234.550494] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[234.553059] Starting dynamic subtest: pipe-A-eDP-1
[234.975734] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.395s)
[234.984154] Starting dynamic subtest: pipe-B-eDP-1
[236.466360] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.489s)
[236.473975] Starting dynamic subtest: pipe-C-eDP-1
[237.831304] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.360s)
[237.840648] Starting dynamic subtest: pipe-D-eDP-1
[239.148621] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.314s)
[239.151735] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.564s)
[240.367290] [028/132] (779s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[240.491575] Starting subtest: short-flip-before-cursor-toggle
[241.417928] Subtest short-flip-before-cursor-toggle: SUCCESS (0.927s)
[242.293568] [029/132] (777s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[242.354500] Starting subtest: basic-batch-kernel-default-cmd
[242.359618] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[242.556893] [030/132] (777s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[242.663621] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[242.668554] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[242.782160] [031/132] (777s left) prime_vgem (wait)
[242.935574] Starting subtest: wait
[242.938400] Starting dynamic subtest: rcs0
[242.980667] Dynamic subtest rcs0: SUCCESS (0.048s)
[242.989283] Starting dynamic subtest: bcs0
[243.056510] Dynamic subtest bcs0: SUCCESS (0.075s)
[243.064651] Starting dynamic subtest: vcs0
[243.103523] Dynamic subtest vcs0: SUCCESS (0.046s)
[243.111641] Starting dynamic subtest: vcs1
[243.152247] Dynamic subtest vcs1: SUCCESS (0.048s)
[243.160255] Starting dynamic subtest: vecs0
[243.197753] Dynamic subtest vecs0: SUCCESS (0.045s)
[243.205771] Starting dynamic subtest: ccs0
[243.248777] Dynamic subtest ccs0: SUCCESS (0.050s)
[243.251433] Subtest wait: SUCCESS (0.317s)
[243.407925] [FACT prime_vgem (wait)] new: kernel.kmod_is_loaded.vgem: true
[243.411577] [032/132] (776s left) kms_pm_rpm (legacy-planes)
[245.441870] Starting subtest: legacy-planes
[245.444602] Starting dynamic subtest: plane-63
[254.058885] Dynamic subtest plane-63: SUCCESS (8.615s)
[254.070028] Starting dynamic subtest: plane-93
[262.353219] Dynamic subtest plane-93: SUCCESS (8.288s)
[262.364797] Starting dynamic subtest: plane-123
[270.667736] Dynamic subtest plane-123: SUCCESS (8.308s)
[270.678041] Starting dynamic subtest: plane-133
[278.988537] Dynamic subtest plane-133: SUCCESS (8.314s)
[278.991713] Subtest legacy-planes: SUCCESS (33.535s)
[279.379320] [033/132] (740s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[279.503429] Starting subtest: basic-flip-before-cursor-varying-size
[280.427482] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.926s)
[281.296832] [034/132] (739s left) i915_pm_rpm (gem-execbuf)
[283.425979] Starting subtest: gem-execbuf
[283.428558] Starting dynamic subtest: smem0
[284.949373] Dynamic subtest smem0: SUCCESS (1.530s)
[284.951883] Subtest gem-execbuf: SUCCESS (1.532s)
[285.060180] [035/132] (735s left) vgem_basic (bad-flag)
[285.111629] Starting subtest: bad-flag
[285.114570] Subtest bad-flag: SUCCESS (0.000s)
[285.190210] [036/132] (735s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[285.292708] Starting subtest: yf-tiled-64bpp-rotate-180
[285.297866] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[285.402999] [037/132] (735s left) kms_addfb_basic (unused-modifier)
[285.467613] Starting subtest: unused-modifier
[285.470082] Subtest unused-modifier: SUCCESS (0.000s)
[285.631067] [038/132] (734s left) gem_exec_schedule (implicit-write-read)
[285.705884] Starting subtest: implicit-write-read
[285.708308] Starting dynamic subtest: rcs0
[285.806640] Dynamic subtest rcs0: SUCCESS (0.113s)
[285.817631] Starting dynamic subtest: vcs0
[285.890100] Dynamic subtest vcs0: SUCCESS (0.082s)
[285.900370] Starting dynamic subtest: vcs1
[285.964883] Dynamic subtest vcs1: SUCCESS (0.075s)
[285.982377] Starting dynamic subtest: vecs0
[286.035950] Dynamic subtest vecs0: SUCCESS (0.071s)
[286.046154] Starting dynamic subtest: ccs0
[286.116069] Dynamic subtest ccs0: SUCCESS (0.079s)
[286.118535] Subtest implicit-write-read: SUCCESS (0.425s)
[287.454480] [039/132] (733s left) dumb_buffer (create-clear)
[287.528140] Starting subtest: create-clear
[321.186742] Subtest create-clear: SUCCESS (33.684s)
[321.338594] [040/132] (699s left) gem_huc_copy (huc-copy)
[321.406385] Starting subtest: huc-copy
[321.411421] Subtest huc-copy: SUCCESS (0.005s)
[321.585237] [041/132] (699s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[321.680115] Starting subtest: fbc-rgb565-draw-mmap-cpu
[325.695324] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.019s)
[326.884865] [042/132] (693s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[326.976382] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[326.978974] Starting dynamic subtest: pipe-A-eDP-1
[327.135710] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.150s)
[327.145638] Starting dynamic subtest: pipe-B-eDP-1
[327.166468] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[327.176053] Starting dynamic subtest: pipe-C-eDP-1
[327.197535] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[327.207020] Starting dynamic subtest: pipe-D-eDP-1
[327.228477] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.010s)
[327.230561] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.187s)
[329.428192] [043/132] (691s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[329.533267] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[336.746581] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.215s)
[336.918208] [044/132] (683s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[337.024530] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[337.029533] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[337.163706] [045/132] (683s left) kms_addfb_basic (bad-pitch-65536)
[337.225869] Starting subtest: bad-pitch-65536
[337.228613] Subtest bad-pitch-65536: SUCCESS (0.000s)
[337.382840] [046/132] (683s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[337.455644] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[337.461288] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[337.663706] [047/132] (683s left) kms_big_fb (x-tiled-addfb-size-overflow)
[337.761727] Starting subtest: x-tiled-addfb-size-overflow
[337.764538] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[337.880966] [048/132] (683s left) gem_softpin (evict-active)
[337.944263] Starting subtest: evict-active
[337.951255] Subtest evict-active: SUCCESS (0.007s)
[338.096017] [049/132] (682s left) kms_pm_backlight (basic-brightness)
[338.200454] Starting subtest: basic-brightness
[338.252711] Starting dynamic subtest: eDP-1
[338.765447] Dynamic subtest eDP-1: SUCCESS (0.513s)
[338.768029] Subtest basic-brightness: SUCCESS (0.566s)
[339.601230] [050/132] (681s left) gem_exec_fence (submit67)
[339.786577] Starting subtest: submit67
[339.792655] Subtest submit67: SKIP (0.000s)
[341.390923] [051/132] (679s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[341.497881] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[341.502956] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[341.634306] [052/132] (679s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[341.736714] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[341.741048] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[341.854791] [053/132] (679s left) gem_exec_params (secure-non-master)
[341.908952] Starting subtest: secure-non-master
[341.913508] Subtest secure-non-master: SKIP (0.000s)
[342.031755] [054/132] (679s left) gem_exec_balancer (bonded-sync)
[342.088628] Starting subtest: bonded-sync
[342.094349] Subtest bonded-sync: SKIP (0.000s)
[342.403082] [055/132] (678s left) perf (buffer-fill)
[342.514993] Starting subtest: buffer-fill
[342.517476] Starting dynamic subtest: 0-rcs0
[344.771325] Dynamic subtest 0-rcs0: SUCCESS (2.257s)
[344.779986] Starting dynamic subtest: 1-vcs1
[349.163074] Dynamic subtest 1-vcs1: SUCCESS (4.391s)
[349.165745] Subtest buffer-fill: SUCCESS (6.651s)
[349.204545] [056/132] (672s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[349.308343] Starting subtest: plane-all-transition-nonblocking-fencing
[349.310852] Starting dynamic subtest: pipe-A-eDP-1
[351.809959] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.503s)
[352.136628] Starting dynamic subtest: pipe-B-eDP-1
[355.335832] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.210s)
[355.655793] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.349s)
[356.490042] [057/132] (664s left) kms_joiner (invalid-modeset-big-joiner)
[356.599984] Starting subtest: invalid-modeset-big-joiner
[356.605712] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[356.687224] [058/132] (664s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[356.746296] Starting subtest: single-wait-for-submit-unsubmitted
[356.852446] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.105s)
[356.954039] [059/132] (664s left) gem_ctx_isolation (preservation-reset)
[357.131446] Starting subtest: preservation-reset
[357.133926] Starting dynamic subtest: rcs0
[357.400437] Dynamic subtest rcs0: SUCCESS (0.272s)
[357.411765] Starting dynamic subtest: bcs0
[357.721935] Dynamic subtest bcs0: SUCCESS (0.321s)
[357.731378] Starting dynamic subtest: vcs0
[358.049072] Dynamic subtest vcs0: SUCCESS (0.326s)
[358.060850] Starting dynamic subtest: vcs1
[359.630718] Dynamic subtest vcs1: SUCCESS (1.580s)
[359.642506] Starting dynamic subtest: vecs0
[361.620023] Dynamic subtest vecs0: SUCCESS (1.989s)
[361.631739] Starting dynamic subtest: ccs0
[363.608306] Dynamic subtest ccs0: SUCCESS (1.988s)
[363.610961] Subtest preservation-reset: SUCCESS (6.483s)
[363.769499] [060/132] (657s left) kms_setmode (invalid-clone-exclusive-crtc)
[363.826155] Starting subtest: invalid-clone-exclusive-crtc
[363.833999] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[363.961391] [061/132] (657s left) gem_render_copy_redux (normal)
[364.021167] Starting subtest: normal
[364.456363] Subtest normal: SUCCESS (0.436s)
[364.624828] [062/132] (656s left) kms_chamelium_frames (dp-frame-dump)
[365.012608] Subtest dp-frame-dump: SKIP (0.000s)
[365.792395] [063/132] (655s left) kms_psr (psr2-cursor-mmap-cpu)
[365.895658] Starting subtest: psr2-cursor-mmap-cpu
[365.898458] Starting dynamic subtest: eDP-1
[367.583735] Dynamic subtest eDP-1: SUCCESS (1.688s)
[367.586523] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.689s)
[368.420206] [064/132] (653s left) perf_pmu (busy-accuracy-98)
[368.470569] Starting subtest: busy-accuracy-98
[368.473091] Starting dynamic subtest: rcs0
[371.513599] Dynamic subtest rcs0: SUCCESS (3.040s)
[371.523652] Starting dynamic subtest: bcs0
[374.573557] Dynamic subtest bcs0: SUCCESS (3.057s)
[374.583372] Starting dynamic subtest: vcs0
[377.618448] Dynamic subtest vcs0: SUCCESS (3.041s)
[377.628521] Starting dynamic subtest: vcs1
[380.665529] Dynamic subtest vcs1: SUCCESS (3.044s)
[380.675431] Starting dynamic subtest: vecs0
[383.711587] Dynamic subtest vecs0: SUCCESS (3.044s)
[383.721694] Starting dynamic subtest: ccs0
[386.768733] Dynamic subtest ccs0: SUCCESS (3.054s)
[386.771643] Subtest busy-accuracy-98: SUCCESS (18.298s)
[386.927121] [065/132] (634s left) syncobj_basic (bad-handle-to-fd)
[386.992252] Starting subtest: bad-handle-to-fd
[386.994860] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[387.130610] [066/132] (634s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[387.243826] Starting subtest: 4-tiled-16bpp-rotate-180
[388.633917] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.390s)
[388.811132] [067/132] (632s left) i915_pm_freq_api (freq-basic-api)
[388.883572] Starting subtest: freq-basic-api
[388.886139] Starting dynamic subtest: gt0
[388.888196] Dynamic subtest gt0: SUCCESS (0.004s)
[388.903087] Starting dynamic subtest: gt1
[388.905459] Dynamic subtest gt1: SUCCESS (0.004s)
[388.908028] Subtest freq-basic-api: SUCCESS (0.009s)
[389.030264] [068/132] (632s left) kms_lease (lease-invalid-connector)
[389.128402] Starting subtest: lease-invalid-connector
[389.130638] Starting dynamic subtest: pipe-A-eDP-1
[389.133246] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[389.142639] Starting dynamic subtest: pipe-B-eDP-1
[389.145382] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[389.154077] Starting dynamic subtest: pipe-C-eDP-1
[389.156482] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[389.165124] Starting dynamic subtest: pipe-D-eDP-1
[389.167544] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[389.169883] Subtest lease-invalid-connector: SUCCESS (0.004s)
[389.224311] [069/132] (632s left) kms_fbcon_fbt (fbc-suspend)
[389.294173] Starting subtest: fbc-suspend
[438.541056] Subtest fbc-suspend: SUCCESS (19.904s)
[439.887742] [070/132] (581s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[440.006786] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[440.009186] Starting dynamic subtest: pipe-A
[440.070248] Dynamic subtest pipe-A: SUCCESS (0.065s)
[440.080023] Starting dynamic subtest: pipe-B
[441.223258] Dynamic subtest pipe-B: SUCCESS (1.150s)
[441.234285] Starting dynamic subtest: pipe-C
[442.350673] Dynamic subtest pipe-C: SUCCESS (1.125s)
[442.366471] Starting dynamic subtest: pipe-D
[443.467058] Dynamic subtest pipe-D: SUCCESS (1.113s)
[443.469749] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.460s)
[444.662620] [071/132] (577s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[444.754809] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[444.757262] Starting dynamic subtest: pipe-A-default-mode
[445.043526] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[445.048084] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.287s)
[445.891476] [072/132] (576s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[445.986737] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[445.988830] Starting dynamic subtest: pipe-A-eDP-1
[446.010199] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[446.022038] Starting dynamic subtest: pipe-B-eDP-1
[446.045699] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[446.054282] Starting dynamic subtest: pipe-C-eDP-1
[446.077269] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[446.086873] Starting dynamic subtest: pipe-D-eDP-1
[446.109688] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[446.112242] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[448.179267] [073/132] (573s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[448.283260] Starting subtest: y-tiled-8bpp-rotate-0
[448.288538] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[448.420046] [074/132] (573s left) sysfs_heartbeat_interval (idempotent)
[448.485125] Starting subtest: idempotent
[448.487811] Starting dynamic subtest: vecs0
[448.490246] Dynamic subtest vecs0: SUCCESS (0.001s)
[448.500564] Starting dynamic subtest: ccs0
[448.503031] Dynamic subtest ccs0: SUCCESS (0.001s)
[448.512232] Starting dynamic subtest: vcs0
[448.514922] Dynamic subtest vcs0: SUCCESS (0.000s)
[448.524255] Starting dynamic subtest: bcs0
[448.526952] Dynamic subtest bcs0: SUCCESS (0.001s)
[448.536242] Starting dynamic subtest: rcs0
[448.538998] Dynamic subtest rcs0: SUCCESS (0.000s)
[448.548140] Starting dynamic subtest: vcs1
[448.550984] Dynamic subtest vcs1: SUCCESS (0.000s)
[448.553416] Subtest idempotent: SUCCESS (0.008s)
[448.622804] [075/132] (573s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[448.719254] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[448.729962] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.010s)
[448.833469] [076/132] (573s left) prime_vgem (basic-gtt)
[448.924226] Starting subtest: basic-gtt
[448.929247] Subtest basic-gtt: SKIP (0.000s)
[449.065610] [077/132] (573s left) kms_color (deep-color)
[449.155381] Starting subtest: deep-color
[450.238293] Starting dynamic subtest: pipe-A-eDP-1-gamma
[450.960789] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.721s)
[450.971908] Starting dynamic subtest: pipe-A-eDP-1-degamma
[452.443098] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.480s)
[452.454237] Starting dynamic subtest: pipe-A-eDP-1-ctm
[453.881778] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.439s)
[454.661561] Starting dynamic subtest: pipe-B-eDP-1-gamma
[455.465087] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.812s)
[455.475222] Starting dynamic subtest: pipe-B-eDP-1-degamma
[456.949808] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.485s)
[456.965183] Starting dynamic subtest: pipe-B-eDP-1-ctm
[458.377214] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.425s)
[459.154308] Starting dynamic subtest: pipe-C-eDP-1-gamma
[459.843504] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.699s)
[459.853767] Starting dynamic subtest: pipe-C-eDP-1-degamma
[461.208865] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.363s)
[461.218952] Starting dynamic subtest: pipe-C-eDP-1-ctm
[462.663061] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.452s)
[463.454504] Starting dynamic subtest: pipe-D-eDP-1-gamma
[464.153849] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.707s)
[464.169202] Starting dynamic subtest: pipe-D-eDP-1-degamma
[465.523254] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.370s)
[465.534862] Starting dynamic subtest: pipe-D-eDP-1-ctm
[466.972987] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.448s)
[466.975845] Subtest deep-color: SUCCESS (17.811s)
[467.842729] [078/132] (554s left) gem_exec_whisper (basic-fds-forked)
[467.922020] Starting subtest: basic-fds-forked
[493.833260] Subtest basic-fds-forked: SUCCESS (25.911s)
[494.039598] [079/132] (528s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[494.156833] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[494.162010] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[494.293731] [080/132] (527s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[494.432056] Starting subtest: cursorA-vs-flipB-atomic
[494.437173] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[494.629501] [081/132] (527s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-mc-ccs)
[494.734935] Starting subtest: crc-primary-suspend-y-tiled-gen12-mc-ccs
[494.737482] Starting dynamic subtest: pipe-A-eDP-1
[494.746929] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[494.756891] Starting dynamic subtest: pipe-B-eDP-1
[494.766908] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[494.776426] Starting dynamic subtest: pipe-C-eDP-1
[494.787131] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[494.797237] Starting dynamic subtest: pipe-D-eDP-1
[494.807691] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[494.812756] Subtest crc-primary-suspend-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[496.978588] [082/132] (525s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[497.079725] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[497.085123] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[497.188616] [083/132] (525s left) kms_cursor_crc (cursor-onscreen-64x21)
[497.387087] Starting subtest: cursor-onscreen-64x21
[497.432482] Subtest cursor-onscreen-64x21: SKIP (0.041s)
[497.721596] [084/132] (524s left) kms_content_protection (srm)
[497.883513] Starting subtest: srm
[497.888949] Subtest srm: SKIP (0.003s)
[498.057192] [085/132] (524s left) kms_big_fb (linear-8bpp-rotate-180)
[498.163808] Starting subtest: linear-8bpp-rotate-180
[499.476691] Subtest linear-8bpp-rotate-180: SUCCESS (1.313s)
[499.641502] [086/132] (522s left) gem_mmap_offset (clear)
[499.701051] Starting subtest: clear
[499.703490] Starting dynamic subtest: smem0
[524.917260] Dynamic subtest smem0: SUCCESS (25.208s)
[524.919874] Subtest clear: SUCCESS (25.209s)
[525.099336] [087/132] (497s left) kms_vblank (wait-forked-busy)
[525.265259] Starting subtest: wait-forked-busy
[525.267815] Starting dynamic subtest: pipe-A-eDP-1
[527.149212] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.885s)
[527.166395] Starting dynamic subtest: pipe-D-eDP-1
[529.732085] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.580s)
[529.735139] Subtest wait-forked-busy: SUCCESS (4.470s)
[530.554167] [088/132] (492s left) kms_cursor_crc (cursor-sliding-64x21)
[530.764086] Starting subtest: cursor-sliding-64x21
[530.811218] Subtest cursor-sliding-64x21: SKIP (0.040s)
[531.066788] [089/132] (491s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[531.171553] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[531.174018] Starting dynamic subtest: pipe-A-default-mode
[531.488082] Dynamic subtest pipe-A-default-mode: SKIP (0.311s)
[531.493494] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.313s)
[532.290464] [090/132] (490s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[532.358998] Starting subtest: etime-single-wait-for-submit-submitted
[532.468339] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.108s)
[532.573436] [091/132] (490s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[532.678945] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[536.805541] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.129s)
[537.996589] [092/132] (484s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[538.090104] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[538.092887] Starting dynamic subtest: pipe-A-eDP-1
[538.118722] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[538.129162] Starting dynamic subtest: pipe-B-eDP-1
[538.154800] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[538.164387] Starting dynamic subtest: pipe-C-eDP-1
[538.191079] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[538.200719] Starting dynamic subtest: pipe-D-eDP-1
[538.227007] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[538.231817] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[540.354228] [093/132] (482s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode)
[540.752992] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s)
[541.543803] [094/132] (481s left) api_intel_bb (delta-check)
[541.603050] Starting subtest: delta-check
[541.606482] Subtest delta-check: SUCCESS (0.006s)
[541.765293] [095/132] (481s left) gem_softpin (noreloc)
[541.837765] Starting subtest: noreloc
[547.238915] Subtest noreloc: SUCCESS (5.403s)
[547.435742] [096/132] (475s left) gem_exec_nop (basic-series)
[547.524036] Starting subtest: basic-series
[563.619445] Subtest basic-series: SUCCESS (16.106s)
[563.860833] [097/132] (459s left) gen9_exec_parse (valid-registers)
[563.918089] Subtest valid-registers: SKIP (0.000s)
[563.991502] [098/132] (459s left) syncobj_timeline (reset-multiple-signaled)
[564.044225] Starting subtest: reset-multiple-signaled
[564.047001] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[564.178469] [099/132] (458s left) prime_vgem (basic-write)
[564.241936] Starting subtest: basic-write
[564.298081] Subtest basic-write: SKIP (0.053s)
[564.440563] [100/132] (458s left) gen9_exec_parse (bb-start-far)
[564.504108] Subtest bb-start-far: SKIP (0.000s)
[564.590228] [101/132] (458s left) kms_psr (psr-cursor-mmap-gtt)
[564.694740] Starting subtest: psr-cursor-mmap-gtt
[564.697013] Starting dynamic subtest: eDP-1
[566.453136] Dynamic subtest eDP-1: SUCCESS (1.755s)
[566.455734] Subtest psr-cursor-mmap-gtt: SUCCESS (1.755s)
[567.349352] [102/132] (455s left) gem_pxp (create-valid-protected-context)
[567.475230] Starting subtest: create-valid-protected-context
[567.477986] Subtest create-valid-protected-context: SUCCESS (0.002s)
[567.628551] [103/132] (455s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[567.721996] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[567.727051] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[567.858461] [104/132] (455s left) kms_psr (fbc-psr-suspend)
[567.958662] Starting subtest: fbc-psr-suspend
[567.961146] Starting dynamic subtest: eDP-1
[567.966243] Dynamic subtest eDP-1: SKIP (0.000s)
[567.971022] Subtest fbc-psr-suspend: SKIP (0.001s)
[568.056955] [105/132] (455s left) kms_chamelium_color (ctm-0-25)
[568.158920] Subtest ctm-0-25: SKIP (0.000s)
[568.205804] [106/132] (455s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[568.310179] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[568.946428] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.637s)
[569.166548] [107/132] (454s left) gem_exec_reloc (basic-write-read-noreloc)
[569.233493] Subtest basic-write-read-noreloc: SKIP (0.000s)
[569.339514] [108/132] (454s left) api_intel_bb (bb-with-allocator)
[569.391713] Starting subtest: bb-with-allocator
[569.401143] Subtest bb-with-allocator: SUCCESS (0.011s)
[569.549789] [109/132] (453s left) gem_exec_reloc (basic-scanout)
[569.607966] Subtest basic-scanout: SKIP (0.000s)
[569.668353] [110/132] (453s left) gem_close_race (multigpu-basic-process)
[569.740779] Starting subtest: multigpu-basic-process
[569.745720] Subtest multigpu-basic-process: SKIP (0.000s)
[570.004616] [111/132] (453s left) kms_vblank (query-idle-hang)
[570.090891] Starting subtest: query-idle-hang
[570.093775] Starting dynamic subtest: pipe-A-eDP-1
[597.347428] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.249s)
[597.364915] Starting dynamic subtest: pipe-D-eDP-1
[622.369215] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.015s)
[622.372096] Subtest query-idle-hang: SUCCESS (52.269s)
[623.209851] [112/132] (400s left) gem_eio (wait-1us)
[623.427082] Starting subtest: wait-1us
[623.732166] Subtest wait-1us: SUCCESS (0.305s)
[623.899609] [113/132] (399s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[624.002048] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[624.007043] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[624.143978] [114/132] (399s left) kms_prop_blob (invalid-get-prop-any)
[624.198252] Starting subtest: invalid-get-prop-any
[624.200478] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[624.342034] [115/132] (399s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[624.462857] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[624.466635] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[624.608323] [116/132] (399s left) gem_softpin (allocator-nopin-reserve)
[624.668593] Starting subtest: allocator-nopin-reserve
[624.768118] Subtest allocator-nopin-reserve: SUCCESS (0.100s)
[624.918097] [117/132] (398s left) gem_mmap (bad-size)
[624.978596] Subtest bad-size: SKIP (0.000s)
[625.041440] [118/132] (398s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[625.145519] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[625.149125] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[625.286205] [119/132] (398s left) gem_exec_reloc (basic-cpu-wc-active)
[625.352568] Subtest basic-cpu-wc-active: SKIP (0.000s)
[625.424820] [120/132] (398s left) kms_display_modes (extended-mode-basic)
[625.519844] Starting subtest: extended-mode-basic
[625.524052] Subtest extended-mode-basic: SKIP (0.000s)
[625.566818] [121/132] (398s left) kms_psr (fbc-pr-cursor-plane-move)
[625.652822] Starting subtest: fbc-pr-cursor-plane-move
[625.656973] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[625.753544] [122/132] (398s left) syncobj_basic (test-valid-cycle)
[625.812134] Starting subtest: test-valid-cycle
[625.814573] Subtest test-valid-cycle: SUCCESS (0.000s)
[625.948708] [123/132] (398s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[630.989254] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[630.994380] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.002s)
[631.859969] [124/132] (392s left) drm_read (invalid-buffer)
[632.039643] Starting subtest: invalid-buffer
[632.042358] Subtest invalid-buffer: SUCCESS (0.000s)
[632.212467] [125/132] (392s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[632.277030] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[632.379317] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.103s)
[632.480826] [126/132] (391s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[632.583250] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[639.124939] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.542s)
[639.289587] [127/132] (385s left) gem_ctx_shared (q-out-order)
[639.356985] Starting subtest: Q-out-order
[639.359992] Starting dynamic subtest: rcs0
[639.464231] Dynamic subtest rcs0: SUCCESS (0.107s)
[639.477060] Starting dynamic subtest: vcs0
[639.551140] Dynamic subtest vcs0: SUCCESS (0.086s)
[639.567130] Starting dynamic subtest: vcs1
[639.640082] Dynamic subtest vcs1: SUCCESS (0.088s)
[639.655269] Starting dynamic subtest: vecs0
[639.718794] Dynamic subtest vecs0: SUCCESS (0.076s)
[639.736257] Starting dynamic subtest: ccs0
[639.808478] Dynamic subtest ccs0: SUCCESS (0.089s)
[639.811258] Subtest Q-out-order: SUCCESS (0.452s)
[640.747491] [128/132] (383s left) kms_getfb (getfb2-handle-closed)
[640.847883] Starting subtest: getfb2-handle-closed
[640.850498] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[640.962375] [129/132] (383s left) kms_flip (flip-vs-blocking-wf-vblank)
[641.063988] Starting subtest: flip-vs-blocking-wf-vblank
[641.066763] Starting dynamic subtest: A-eDP1
[650.002562] Dynamic subtest A-eDP1: SUCCESS (8.937s)
[650.013737] Starting dynamic subtest: B-eDP1
[658.941971] Dynamic subtest B-eDP1: SUCCESS (8.937s)
[658.953544] Starting dynamic subtest: C-eDP1
[667.855850] Dynamic subtest C-eDP1: SUCCESS (8.913s)
[667.868243] Starting dynamic subtest: D-eDP1
[676.786058] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[676.788478] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.726s)
[678.041078] [130/132] (346s left) gem_pxp (verify-pxp-stale-ctx-execution)
[678.100119] Starting subtest: verify-pxp-stale-ctx-execution
[679.165269] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.061s)
[679.316250] [131/132] (345s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[679.421874] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[686.696470] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.280s)
[686.856712] [132/132] (337s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[686.955167] Starting subtest: psr-rgb565-draw-pwrite
[693.835044] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.881s)
[693.968700] Closing watchdogs
results: parsing output: 0/ for test: gem_softpin
results: parsing output: 1/ for test: kms_scaling_modes
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_psr2_sf
results: parsing output: 4/ for test: i915_suspend
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: gem_exec_balancer
results: parsing output: 7/ for test: gem_ctx_persistence
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_pipe_crc_basic
results: parsing output: 10/ for test: gem_exec_schedule
results: parsing output: 11/ for test: gem_vm_create
results: parsing output: 12/ for test: gem_exec_reloc
results: parsing output: 13/ for test: kms_cursor_crc
results: parsing output: 14/ for test: kms_plane_scaling
results: parsing output: 15/ for test: prime_self_import
results: parsing output: 16/ for test: syncobj_wait
results: parsing output: 17/ for test: kms_dsc
results: parsing output: 18/ for test: kms_cursor_legacy
results: parsing output: 19/ for test: kms_setmode
results: parsing output: 20/ for test: gem_readwrite
results: parsing output: 21/ for test: perf
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: fbdev
results: parsing output: 24/ for test: kms_async_flips
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: gem_exec_flush
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: prime_vgem
results: parsing output: 31/ for test: kms_pm_rpm
results: parsing output: 32/ for test: kms_cursor_legacy
results: parsing output: 33/ for test: i915_pm_rpm
results: parsing output: 34/ for test: vgem_basic
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: kms_addfb_basic
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: dumb_buffer
results: parsing output: 39/ for test: gem_huc_copy
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_ccs
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_addfb_basic
results: parsing output: 45/ for test: gem_render_copy
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: gem_softpin
results: parsing output: 48/ for test: kms_pm_backlight
results: parsing output: 49/ for test: gem_exec_fence
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_exec_params
results: parsing output: 53/ for test: gem_exec_balancer
results: parsing output: 54/ for test: perf
results: parsing output: 55/ for test: kms_atomic_transition
results: parsing output: 56/ for test: kms_joiner
results: parsing output: 57/ for test: syncobj_wait
results: parsing output: 58/ for test: gem_ctx_isolation
results: parsing output: 59/ for test: kms_setmode
results: parsing output: 60/ for test: gem_render_copy_redux
results: parsing output: 61/ for test: kms_chamelium_frames
results: parsing output: 62/ for test: kms_psr
results: parsing output: 63/ for test: perf_pmu
results: parsing output: 64/ for test: syncobj_basic
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: i915_pm_freq_api
results: parsing output: 67/ for test: kms_lease
results: parsing output: 68/ for test: kms_fbcon_fbt
results: parsing output: 69/ for test: kms_plane_scaling
results: parsing output: 70/ for test: kms_flip_scaled_crc
results: parsing output: 71/ for test: kms_ccs
results: parsing output: 72/ for test: kms_big_fb
results: parsing output: 73/ for test: sysfs_heartbeat_interval
results: parsing output: 74/ for test: kms_flip
results: parsing output: 75/ for test: prime_vgem
results: parsing output: 76/ for test: kms_color
results: parsing output: 77/ for test: gem_exec_whisper
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_cursor_legacy
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_cursor_crc
results: parsing output: 83/ for test: kms_content_protection
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: gem_mmap_offset
results: parsing output: 86/ for test: kms_vblank
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: kms_flip_scaled_crc
results: parsing output: 89/ for test: syncobj_timeline
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: kms_chamelium_hpd
results: parsing output: 93/ for test: api_intel_bb
results: parsing output: 94/ for test: gem_softpin
results: parsing output: 95/ for test: gem_exec_nop
results: parsing output: 96/ for test: gen9_exec_parse
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: prime_vgem
results: parsing output: 99/ for test: gen9_exec_parse
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: gem_pxp
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: kms_chamelium_color
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: gem_exec_reloc
results: parsing output: 107/ for test: api_intel_bb
results: parsing output: 108/ for test: gem_exec_reloc
results: parsing output: 109/ for test: gem_close_race
results: parsing output: 110/ for test: kms_vblank
results: parsing output: 111/ for test: gem_eio
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_prop_blob
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: gem_softpin
results: parsing output: 116/ for test: gem_mmap
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_exec_reloc
results: parsing output: 119/ for test: kms_display_modes
results: parsing output: 120/ for test: kms_psr
results: parsing output: 121/ for test: syncobj_basic
results: parsing output: 122/ for test: kms_psr2_sf
results: parsing output: 123/ for test: drm_read
results: parsing output: 124/ for test: syncobj_timeline
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_ctx_shared
results: parsing output: 127/ for test: kms_getfb
results: parsing output: 128/ for test: kms_flip
results: parsing output: 129/ for test: gem_pxp
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_frontbuffer_tracking
Done.