[61.706460] Initializing watchdogs
[61.706508]   /dev/watchdog0
[61.720963] [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]
[61.725667] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.728166] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.731605] [001/133] (960s left) kms_plane (pixel-format-linear-modifier-source-clamping)
[61.856887] Starting subtest: pixel-format-linear-modifier-source-clamping
[61.894552] Starting dynamic subtest: pipe-A-plane-0
[65.394467] Dynamic subtest pipe-A-plane-0: SUCCESS (3.500s)
[65.404328] Starting dynamic subtest: pipe-A-plane-3
[67.127514] Dynamic subtest pipe-A-plane-3: SUCCESS (1.733s)
[68.262700] Starting dynamic subtest: pipe-B-plane-0
[71.777653] Dynamic subtest pipe-B-plane-0: SUCCESS (3.522s)
[71.786026] Starting dynamic subtest: pipe-B-plane-3
[73.733414] Dynamic subtest pipe-B-plane-3: SUCCESS (1.955s)
[74.041829] Subtest pixel-format-linear-modifier-source-clamping: SUCCESS (12.185s)
[74.856865] [002/133] (947s left) kms_flip (plain-flip-fb-recreate-interruptible)
[74.958294] Starting subtest: plain-flip-fb-recreate-interruptible
[74.960574] Starting dynamic subtest: A-eDP1
[83.834058] Dynamic subtest A-eDP1: SUCCESS (8.875s)
[83.843405] Starting dynamic subtest: B-eDP1
[92.672029] Dynamic subtest B-eDP1: SUCCESS (8.836s)
[92.682719] Starting dynamic subtest: C-eDP1
[101.491257] Dynamic subtest C-eDP1: SUCCESS (8.818s)
[101.500436] Starting dynamic subtest: D-eDP1
[110.318867] Dynamic subtest D-eDP1: SUCCESS (8.827s)
[110.323041] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.363s)
[111.506808] [003/133] (910s left) kms_frontbuffer_tracking (basic)
[111.611645] Starting subtest: basic
[114.832412] Subtest basic: SUCCESS (3.226s)
[114.943881] [004/133] (907s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[115.332812] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[116.118966] [005/133] (906s left) kms_pm_rpm (legacy-planes)
[117.648719] Starting subtest: legacy-planes
[117.651189] Starting dynamic subtest: plane-65
[125.942435] Dynamic subtest plane-65: SUCCESS (8.296s)
[125.953152] Starting dynamic subtest: plane-95
[134.239861] Dynamic subtest plane-95: SUCCESS (8.296s)
[134.250766] Starting dynamic subtest: plane-125
[142.650387] Dynamic subtest plane-125: SUCCESS (8.409s)
[142.661301] Starting dynamic subtest: plane-135
[151.143423] Dynamic subtest plane-135: SUCCESS (8.489s)
[151.146463] Subtest legacy-planes: SUCCESS (33.497s)
[151.533715] [006/133] (870s left) kms_content_protection (atomic-dpms-hdcp14)
[151.688962] Starting subtest: atomic-dpms-hdcp14
[151.693649] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[151.821151] [007/133] (870s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[151.929840] Starting subtest: x-tiled-64bpp-rotate-90
[151.949525] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[152.046403] [008/133] (870s left) kms_flip (flip-vs-dpms-on-nop)
[152.146337] Starting subtest: flip-vs-dpms-on-nop
[152.148557] Starting dynamic subtest: A-eDP1
[154.017902] Dynamic subtest A-eDP1: SUCCESS (1.873s)
[154.030925] Starting dynamic subtest: B-eDP1
[155.854726] Dynamic subtest B-eDP1: SUCCESS (1.835s)
[155.864675] Starting dynamic subtest: C-eDP1
[157.679972] Dynamic subtest C-eDP1: SUCCESS (1.823s)
[157.687986] Starting dynamic subtest: D-eDP1
[159.508743] Dynamic subtest D-eDP1: SUCCESS (1.825s)
[159.511159] Subtest flip-vs-dpms-on-nop: SUCCESS (7.367s)
[160.686663] [009/133] (861s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[160.783313] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[160.787661] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[160.886171] [010/133] (861s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[160.992770] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[168.271999] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.282s)
[168.394623] [011/133] (854s left) vgem_basic (second-client)
[168.515915] Starting subtest: second-client
[168.537324] Subtest second-client: SUCCESS (0.021s)
[168.607025] [FACT vgem_basic (second-client)] new: kernel.kmod_is_loaded.vgem: true
[168.610288] [012/133] (854s left) gem_exec_schedule (submit-early-slice)
[168.678175] Starting subtest: submit-early-slice
[168.680137] Starting dynamic subtest: rcs0
[168.718453] Dynamic subtest rcs0: SUCCESS (0.046s)
[168.729969] Starting dynamic subtest: bcs0
[168.759723] Dynamic subtest bcs0: SUCCESS (0.040s)
[168.769567] Starting dynamic subtest: vcs0
[168.797490] Dynamic subtest vcs0: SUCCESS (0.038s)
[168.806173] Starting dynamic subtest: vcs1
[168.840283] Dynamic subtest vcs1: SUCCESS (0.042s)
[168.848920] Starting dynamic subtest: vecs0
[168.882253] Dynamic subtest vecs0: SUCCESS (0.041s)
[168.891482] Starting dynamic subtest: ccs0
[168.921478] Dynamic subtest ccs0: SUCCESS (0.038s)
[168.924232] Subtest submit-early-slice: SUCCESS (0.250s)
[170.403374] [013/133] (852s left) gem_mmap_gtt (zero-extend)
[170.465602] Subtest zero-extend: SKIP (0.000s)
[170.523315] [014/133] (852s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[170.620018] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[170.622145] Starting dynamic subtest: pipe-A-eDP-1
[187.473910] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.530s)
[187.485038] Starting dynamic subtest: pipe-B-eDP-1
[204.490877] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.500s)
[204.500060] Starting dynamic subtest: pipe-C-eDP-1
[221.434655] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.440s)
[221.444982] Starting dynamic subtest: pipe-D-eDP-1
[238.397608] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.315s)
[238.399918] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (8.790s)
[239.580187] [015/133] (783s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[239.705224] Starting subtest: fbc-1p-pri-indfb-multidraw
[252.730631] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.022s)
[254.107106] [016/133] (768s left) kms_big_fb (linear-8bpp-rotate-90)
[254.210627] Starting subtest: linear-8bpp-rotate-90
[254.232271] Subtest linear-8bpp-rotate-90: SKIP (0.021s)
[254.335927] [017/133] (768s left) gem_exec_params (cliprects_ptr-dirt)
[254.389208] Starting subtest: cliprects_ptr-dirt
[254.391439] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[254.479123] [018/133] (768s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[254.569216] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[258.860351] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.292s)
[259.996984] [019/133] (762s left) kms_plane (plane-position-hole-dpms)
[260.086865] Starting subtest: plane-position-hole-dpms
[260.613417] Starting dynamic subtest: pipe-A-plane-1
[262.999057] Dynamic subtest pipe-A-plane-1: SUCCESS (2.386s)
[263.008263] Starting dynamic subtest: pipe-A-plane-2
[265.345664] Dynamic subtest pipe-A-plane-2: SUCCESS (2.346s)
[265.355814] Starting dynamic subtest: pipe-A-plane-3
[267.712960] Dynamic subtest pipe-A-plane-3: SUCCESS (2.363s)
[267.724236] Starting dynamic subtest: pipe-A-plane-4
[270.106083] Dynamic subtest pipe-A-plane-4: SUCCESS (2.393s)
[270.117395] Starting dynamic subtest: pipe-A-plane-5
[270.132379] Dynamic subtest pipe-A-plane-5: SUCCESS (0.026s)
[271.361232] Starting dynamic subtest: pipe-B-plane-1
[273.777167] Dynamic subtest pipe-B-plane-1: SUCCESS (2.425s)
[273.786800] Starting dynamic subtest: pipe-B-plane-2
[276.131845] Dynamic subtest pipe-B-plane-2: SUCCESS (2.352s)
[276.140562] Starting dynamic subtest: pipe-B-plane-3
[278.502106] Dynamic subtest pipe-B-plane-3: SUCCESS (2.369s)
[278.513908] Starting dynamic subtest: pipe-B-plane-4
[280.867328] Dynamic subtest pipe-B-plane-4: SUCCESS (2.364s)
[280.878240] Starting dynamic subtest: pipe-B-plane-5
[280.894308] Dynamic subtest pipe-B-plane-5: SUCCESS (0.025s)
[280.896571] Subtest plane-position-hole-dpms: SUCCESS (20.806s)
[281.682539] [020/133] (741s left) gem_exec_reloc (basic-gtt-cpu)
[281.756809] Subtest basic-gtt-cpu: SKIP (0.000s)
[281.823720] [021/133] (741s left) syncobj_eventfd (binary-wait-signaled)
[281.877096] Starting subtest: binary-wait-signaled
[281.879357] Subtest binary-wait-signaled: SUCCESS (0.000s)
[281.993954] [022/133] (741s left) gen9_exec_parse (bb-start-out)
[282.052196] Subtest bb-start-out: SKIP (0.000s)
[282.125214] [023/133] (741s left) gem_pipe_control_store_loop (reused-buffer)
[282.188987] Starting subtest: reused-buffer
[284.344465] Subtest reused-buffer: SUCCESS (2.154s)
[284.462418] [024/133] (738s left) gem_exec_params (invalid-batch-start-offset)
[284.533990] Starting subtest: invalid-batch-start-offset
[284.536321] Subtest invalid-batch-start-offset: SUCCESS (0.002s)
[284.646056] [025/133] (738s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[284.741025] Starting subtest: psr-shrfb-scaledprimary
[290.565848] Subtest psr-shrfb-scaledprimary: SUCCESS (5.828s)
[290.694613] [026/133] (732s left) kms_vblank (wait-busy)
[290.794513] Starting subtest: wait-busy
[290.796360] Starting dynamic subtest: pipe-A-eDP-1
[292.683305] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.888s)
[292.696348] Starting dynamic subtest: pipe-D-eDP-1
[295.235988] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.550s)
[295.238297] Subtest wait-busy: SUCCESS (4.442s)
[296.049710] [027/133] (727s left) dmabuf_sync_file (import-multiple-read-write)
[296.090701] Starting subtest: import-multiple-read-write
[296.092915] Subtest import-multiple-read-write: SUCCESS (0.004s)
[296.144779] [028/133] (727s left) gem_exec_reloc (basic-write-read-noreloc)
[296.205286] Subtest basic-write-read-noreloc: SKIP (0.000s)
[296.281159] [029/133] (727s left) gem_exec_reloc (basic-range-active)
[296.343059] Subtest basic-range-active: SKIP (0.000s)
[296.416666] [030/133] (727s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[296.513958] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[296.516096] Starting dynamic subtest: pipe-A-eDP-1
[296.610155] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[296.620151] Starting dynamic subtest: pipe-B-eDP-1
[297.723386] Dynamic subtest pipe-B-eDP-1: SKIP (1.111s)
[297.728017] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.213s)
[298.905160] [031/133] (724s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[299.007285] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[299.011636] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[299.116734] [032/133] (724s left) perf_pmu (busy)
[299.151710] Starting subtest: busy
[299.154027] Starting dynamic subtest: rcs0
[299.758362] Dynamic subtest rcs0: SUCCESS (0.606s)
[299.773052] Starting dynamic subtest: bcs0
[300.327919] Dynamic subtest bcs0: SUCCESS (0.566s)
[300.339203] Starting dynamic subtest: vcs0
[302.261163] Dynamic subtest vcs0: SUCCESS (1.929s)
[302.273350] Starting dynamic subtest: vcs1
[304.308521] Dynamic subtest vcs1: SUCCESS (2.044s)
[304.320927] Starting dynamic subtest: vecs0
[306.292134] Dynamic subtest vecs0: SUCCESS (1.981s)
[306.304565] Starting dynamic subtest: ccs0
[306.854973] Dynamic subtest ccs0: SUCCESS (0.561s)
[306.857490] Subtest busy: SUCCESS (7.703s)
[306.972420] [033/133] (716s left) gem_exec_whisper (basic-contexts-priority)
[307.041560] Starting subtest: basic-contexts-priority
[331.730752] Subtest basic-contexts-priority: SUCCESS (24.680s)
[331.916752] [034/133] (691s left) i915_drm_fdinfo (isolation)
[331.958910] Starting subtest: isolation
[331.961424] Starting dynamic subtest: rcs0
[331.985013] Dynamic subtest rcs0: SKIP (0.024s)
[331.996192] Starting dynamic subtest: bcs0
[332.000530] Dynamic subtest bcs0: SKIP (0.000s)
[332.009586] Starting dynamic subtest: vcs0
[332.013774] Dynamic subtest vcs0: SKIP (0.000s)
[332.022833] Starting dynamic subtest: vcs1
[332.027035] Dynamic subtest vcs1: SKIP (0.000s)
[332.035908] Starting dynamic subtest: vecs0
[332.040286] Dynamic subtest vecs0: SKIP (0.000s)
[332.049415] Starting dynamic subtest: ccs0
[332.053730] Dynamic subtest ccs0: SKIP (0.000s)
[332.058039] Subtest isolation: SKIP (0.030s)
[332.098299] [035/133] (691s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[332.197336] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[339.657852] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.465s)
[339.808634] [036/133] (683s left) kms_flip (absolute-wf_vblank)
[339.913267] Starting subtest: absolute-wf_vblank
[339.915721] Starting dynamic subtest: A-eDP1
[348.673527] Dynamic subtest A-eDP1: SUCCESS (8.763s)
[348.683338] Starting dynamic subtest: B-eDP1
[357.406119] Dynamic subtest B-eDP1: SUCCESS (8.731s)
[357.416014] Starting dynamic subtest: C-eDP1
[366.130629] Dynamic subtest C-eDP1: SUCCESS (8.723s)
[366.140759] Starting dynamic subtest: D-eDP1
[374.869539] Dynamic subtest D-eDP1: SUCCESS (8.737s)
[374.871963] Subtest absolute-wf_vblank: SUCCESS (34.964s)
[376.078452] [037/133] (647s left) gem_exec_schedule (implicit-read-write)
[376.153940] Starting subtest: implicit-read-write
[376.156263] Starting dynamic subtest: rcs0
[376.261779] Dynamic subtest rcs0: SUCCESS (0.118s)
[376.276300] Starting dynamic subtest: vcs0
[376.344685] Dynamic subtest vcs0: SUCCESS (0.082s)
[376.355269] Starting dynamic subtest: vcs1
[376.422699] Dynamic subtest vcs1: SUCCESS (0.077s)
[376.435690] Starting dynamic subtest: vecs0
[376.500366] Dynamic subtest vecs0: SUCCESS (0.077s)
[376.512864] Starting dynamic subtest: ccs0
[376.585850] Dynamic subtest ccs0: SUCCESS (0.085s)
[376.588286] Subtest implicit-read-write: SUCCESS (0.444s)
[378.384760] [038/133] (645s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[378.501841] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[378.505886] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[378.613900] [039/133] (645s left) kms_psr (fbc-pr-sprite-plane-move)
[378.723258] Starting subtest: fbc-pr-sprite-plane-move
[378.727436] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[378.801911] [040/133] (645s left) i915_hangman (engine-engine-hang)
[378.900617] Starting subtest: engine-engine-hang
[378.903031] Starting dynamic subtest: rcs0
[380.180388] Dynamic subtest rcs0: SUCCESS (1.279s)
[380.192886] Starting dynamic subtest: bcs0
[382.227009] Dynamic subtest bcs0: SUCCESS (2.044s)
[382.239337] Starting dynamic subtest: vcs0
[384.275068] Dynamic subtest vcs0: SUCCESS (2.045s)
[384.287446] Starting dynamic subtest: vcs1
[386.258244] Dynamic subtest vcs1: SUCCESS (1.981s)
[386.272124] Starting dynamic subtest: vecs0
[388.243180] Dynamic subtest vecs0: SUCCESS (1.982s)
[388.257402] Starting dynamic subtest: ccs0
[390.228450] Dynamic subtest ccs0: SUCCESS (1.983s)
[390.231253] Subtest engine-engine-hang: SUCCESS (11.330s)
[390.312126] [041/133] (633s left) kms_draw_crc (draw-method-mmap-gtt)
[390.404751] Starting subtest: draw-method-mmap-gtt
[390.409261] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[390.506150] [042/133] (633s left) kms_getfb (getfb2-handle-closed)
[390.593125] Starting subtest: getfb2-handle-closed
[390.595048] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[390.689902] [043/133] (633s left) gem_userptr_blits (sync-unmap-cycles)
[390.758214] Starting subtest: sync-unmap-cycles
[396.132494] Subtest sync-unmap-cycles: SUCCESS (5.375s)
[396.243822] [044/133] (627s left) gem_userptr_blits (input-checking)
[396.309735] Starting subtest: input-checking
[396.311619] Subtest input-checking: SUCCESS (0.000s)
[396.426431] [045/133] (627s left) gem_ppgtt (shrink-vs-evict-any)
[396.477976] Starting subtest: shrink-vs-evict-any
[402.262280] Subtest shrink-vs-evict-any: SUCCESS (5.781s)
[402.343843] [046/133] (621s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[402.395207] Starting subtest: invalid-multi-wait-all-unsubmitted
[402.397638] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[402.500226] [047/133] (621s left) gem_exec_basic (basic)
[402.563558] Starting subtest: basic
[402.565463] Starting dynamic subtest: rcs0-smem
[402.570187] Dynamic subtest rcs0-smem: SUCCESS (0.006s)
[402.580033] Starting dynamic subtest: bcs0-smem
[402.582275] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[402.591757] Starting dynamic subtest: vcs0-smem
[402.593605] Dynamic subtest vcs0-smem: SUCCESS (0.003s)
[402.602535] Starting dynamic subtest: vcs1-smem
[402.604539] Dynamic subtest vcs1-smem: SUCCESS (0.003s)
[402.613741] Starting dynamic subtest: vecs0-smem
[402.615693] Dynamic subtest vecs0-smem: SUCCESS (0.003s)
[402.625088] Starting dynamic subtest: ccs0-smem
[402.626971] Dynamic subtest ccs0-smem: SUCCESS (0.004s)
[402.628897] Subtest basic: SUCCESS (0.027s)
[404.327884] [048/133] (619s left) kms_invalid_mode (overflow-vrefresh)
[404.425029] Starting subtest: overflow-vrefresh
[404.427335] Starting dynamic subtest: pipe-A-eDP-1
[404.716090] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.292s)
[404.726872] Starting dynamic subtest: pipe-B-eDP-1
[404.729310] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[404.738889] Starting dynamic subtest: pipe-C-eDP-1
[404.741218] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[404.751230] Starting dynamic subtest: pipe-D-eDP-1
[404.753766] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[404.756277] Subtest overflow-vrefresh: SUCCESS (0.299s)
[405.537949] [049/133] (618s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[405.631885] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[409.330956] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.701s)
[410.546252] [050/133] (613s left) syncobj_wait (wait-delayed-signal)
[410.597517] Starting subtest: wait-delayed-signal
[410.700954] Subtest wait-delayed-signal: SUCCESS (0.103s)
[410.777946] [051/133] (613s left) gem_create (hog-create)
[410.831744] Starting subtest: hog-create
[410.833900] Starting dynamic subtest: smem0
[443.315139] Dynamic subtest smem0: SUCCESS (32.477s)
[443.318024] Subtest hog-create: SUCCESS (32.478s)
[443.501880] [052/133] (580s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[443.597873] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[443.600234] Starting dynamic subtest: pipe-A
[444.656536] Dynamic subtest pipe-A: SUCCESS (1.061s)
[444.668608] Starting dynamic subtest: pipe-B
[446.753585] Dynamic subtest pipe-B: SUCCESS (2.096s)
[446.762958] Starting dynamic subtest: pipe-C
[447.971535] Dynamic subtest pipe-C: SUCCESS (1.216s)
[447.981425] Starting dynamic subtest: pipe-D
[449.202533] Dynamic subtest pipe-D: SUCCESS (1.229s)
[449.204721] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.607s)
[450.388960] [053/133] (574s left) gem_wait (write-wait)
[450.457108] Starting subtest: write-wait
[450.459540] Starting dynamic subtest: all-engines
[451.583746] Dynamic subtest all-engines: SUCCESS (1.125s)
[451.601328] Starting dynamic subtest: rcs0
[452.646702] Dynamic subtest rcs0: SUCCESS (1.061s)
[452.664662] Starting dynamic subtest: bcs0
[453.706710] Dynamic subtest bcs0: SUCCESS (1.058s)
[453.725173] Starting dynamic subtest: vcs0
[454.763616] Dynamic subtest vcs0: SUCCESS (1.054s)
[454.781366] Starting dynamic subtest: vcs1
[455.821397] Dynamic subtest vcs1: SUCCESS (1.055s)
[455.839255] Starting dynamic subtest: vecs0
[456.875386] Dynamic subtest vecs0: SUCCESS (1.051s)
[456.897036] Starting dynamic subtest: ccs0
[457.933129] Dynamic subtest ccs0: SUCCESS (1.055s)
[457.936155] Subtest write-wait: SUCCESS (7.476s)
[458.148101] [054/133] (566s left) kms_force_connector_basic (force-connector-state)
[458.236649] Starting subtest: force-connector-state
[458.862974] Subtest force-connector-state: SUCCESS (0.626s)
[459.873692] [055/133] (564s left) gem_render_copy_redux (flink)
[459.931915] Starting subtest: flink
[460.697263] Subtest flink: SUCCESS (0.768s)
[460.827626] [056/133] (563s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[460.885461] Starting subtest: multi-wait-all-for-submit-signaled
[460.887698] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[460.982219] [057/133] (563s left) syncobj_wait (wait-all-for-submit-snapshot)
[461.039722] Starting subtest: wait-all-for-submit-snapshot
[461.119896] Subtest wait-all-for-submit-snapshot: SUCCESS (0.081s)
[461.200342] [058/133] (563s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[461.311601] Starting subtest: fbcpsr-modesetfrombusy
[467.446149] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.137s)
[467.577279] [059/133] (557s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[467.667017] Starting subtest: modeset-transition-nonblocking-fencing
[467.668613] Starting dynamic subtest: 1x-outputs
[478.493835] Dynamic subtest 1x-outputs: SUCCESS (10.825s)
[478.496187] Subtest modeset-transition-nonblocking-fencing: SUCCESS (10.827s)
[479.328387] [060/133] (545s left) kms_getfb (getfb-handle-closed)
[479.434383] Starting subtest: getfb-handle-closed
[479.436731] Subtest getfb-handle-closed: SUCCESS (0.000s)
[479.538271] [061/133] (545s left) gem_exec_reloc (basic-gtt-read-noreloc)
[479.605593] Subtest basic-gtt-read-noreloc: SKIP (0.000s)
[479.687826] [062/133] (545s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[479.782597] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[479.784842] Starting dynamic subtest: pipe-A-eDP-1
[479.840979] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[479.851487] Starting dynamic subtest: pipe-B-eDP-1
[479.908341] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[479.919621] Starting dynamic subtest: pipe-C-eDP-1
[479.976190] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[479.986295] Starting dynamic subtest: pipe-D-eDP-1
[480.043949] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[480.048171] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[482.037771] [063/133] (542s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[482.096806] Starting subtest: basic-batch-kernel-default-cmd
[482.101344] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[482.297522] [064/133] (542s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[482.407173] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[482.411671] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[482.513598] [065/133] (542s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[482.579120] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[482.680103] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.101s)
[482.756258] [066/133] (542s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[482.866028] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[482.870157] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[482.963722] [067/133] (542s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[483.070483] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[483.074908] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[483.141800] [068/133] (541s left) perf_pmu (init-wait)
[483.188297] Starting subtest: init-wait
[483.190403] Starting dynamic subtest: rcs0
[483.192656] Dynamic subtest rcs0: SUCCESS (0.002s)
[483.207841] Starting dynamic subtest: bcs0
[483.209684] Dynamic subtest bcs0: SUCCESS (0.000s)
[483.219522] Starting dynamic subtest: vcs0
[483.221748] Dynamic subtest vcs0: SUCCESS (0.001s)
[483.231784] Starting dynamic subtest: vcs1
[483.233972] Dynamic subtest vcs1: SUCCESS (0.001s)
[483.243421] Starting dynamic subtest: vecs0
[483.245700] Dynamic subtest vecs0: SUCCESS (0.001s)
[483.255526] Starting dynamic subtest: ccs0
[483.257778] Dynamic subtest ccs0: SUCCESS (0.000s)
[483.259917] Subtest init-wait: SUCCESS (0.008s)
[483.308830] [069/133] (541s left) kms_plane_lowres (tiling-y)
[483.406800] Starting subtest: tiling-y
[483.411244] Subtest tiling-y: SKIP (0.000s)
[483.505127] [070/133] (541s left) kms_flip (2x-flip-vs-wf_vblank)
[483.599923] Starting subtest: 2x-flip-vs-wf_vblank
[483.609937] Subtest 2x-flip-vs-wf_vblank: SKIP (0.012s)
[483.691598] [071/133] (541s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[483.792617] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[487.893265] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.103s)
[489.039946] [072/133] (536s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[489.142803] Starting subtest: plane-lut1d-post-ctm3x4
[489.290413] Starting dynamic subtest: pipe-A-plane-0
[489.455091] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[489.465420] Starting dynamic subtest: pipe-A-plane-1
[489.610374] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[489.620749] Starting dynamic subtest: pipe-A-plane-2
[489.754595] Dynamic subtest pipe-A-plane-2: SUCCESS (0.144s)
[490.983941] Starting dynamic subtest: pipe-B-plane-0
[491.136162] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[491.146522] Starting dynamic subtest: pipe-B-plane-1
[491.269272] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[491.279370] Starting dynamic subtest: pipe-B-plane-2
[491.424651] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[492.576453] Starting dynamic subtest: pipe-C-plane-0
[492.712283] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[492.725411] Starting dynamic subtest: pipe-C-plane-1
[492.867734] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[492.877659] Starting dynamic subtest: pipe-C-plane-2
[493.012231] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[494.179289] Starting dynamic subtest: pipe-D-plane-0
[494.304142] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[494.314392] Starting dynamic subtest: pipe-D-plane-1
[494.448618] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[494.458751] Starting dynamic subtest: pipe-D-plane-2
[494.593127] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[494.892769] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.750s)
[495.736718] [073/133] (529s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[495.834937] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[495.837102] Starting dynamic subtest: pipe-A-eDP-1
[495.859500] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[495.872383] Starting dynamic subtest: pipe-B-eDP-1
[495.893938] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[495.903852] Starting dynamic subtest: pipe-C-eDP-1
[495.925244] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[495.934739] Starting dynamic subtest: pipe-D-eDP-1
[495.956357] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[495.960387] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[498.139380] [074/133] (527s left) gem_sync (basic-all)
[498.226234] Starting subtest: basic-all
[502.325451] Subtest basic-all: SUCCESS (4.109s)
[502.529973] [075/133] (522s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[502.648963] Starting subtest: 2x-long-flip-vs-cursor-atomic
[502.653445] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[502.816393] [076/133] (522s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[502.929294] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[502.933381] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[503.024083] [077/133] (522s left) i915_pm_rps (thresholds-park)
[503.086822] Starting subtest: thresholds-park
[503.091298] Subtest thresholds-park: SKIP (0.001s)
[503.191467] [078/133] (522s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[503.280879] Starting subtest: random-ccs-data-yf-tiled-ccs
[503.283198] Starting dynamic subtest: pipe-A-eDP-1
[503.308716] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[503.320875] Starting dynamic subtest: pipe-B-eDP-1
[503.344754] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[503.354449] Starting dynamic subtest: pipe-C-eDP-1
[503.378651] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[503.387967] Starting dynamic subtest: pipe-D-eDP-1
[503.411940] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[503.416342] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[505.519086] [079/133] (520s left) i915_pm_rpm (system-suspend)
[507.453214] Starting subtest: system-suspend
[525.054872] Subtest system-suspend: SUCCESS (2.884s)
[525.434155] [080/133] (500s left) gem_exec_whisper (basic-sync)
[525.510181] Starting subtest: basic-sync
[532.082794] Subtest basic-sync: SUCCESS (6.565s)
[532.284100] [081/133] (493s left) syncobj_timeline (single-wait-all-available-signaled)
[532.331062] Starting subtest: single-wait-all-available-signaled
[532.333249] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[532.426191] [082/133] (493s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[532.489507] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[532.494043] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[532.675942] [083/133] (493s left) kms_force_connector_basic (force-edid)
[532.754904] Starting subtest: force-edid
[533.129557] Subtest force-edid: SUCCESS (0.376s)
[533.292672] [084/133] (492s left) kms_flip (2x-dpms-vs-vblank-race-interruptible)
[533.413701] Starting subtest: 2x-dpms-vs-vblank-race-interruptible
[533.426984] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.011s)
[533.507104] [085/133] (492s left) gem_exec_create (basic)
[533.567295] Starting subtest: basic
[533.569747] Starting dynamic subtest: smem
[535.990084] Dynamic subtest smem: SUCCESS (2.423s)
[535.992777] Subtest basic: SUCCESS (2.425s)
[536.179335] [086/133] (489s left) kms_chamelium_edid (hdmi-edid-read)
[536.570876] Subtest hdmi-edid-read: SKIP (0.000s)
[537.392099] [087/133] (488s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[537.472877] Starting subtest: bad-pixel-format-yf-tiled-ccs
[537.475205] Starting dynamic subtest: pipe-A-eDP-1
[537.498047] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[537.511184] Starting dynamic subtest: pipe-B-eDP-1
[537.533237] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[537.543116] Starting dynamic subtest: pipe-C-eDP-1
[537.565423] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[537.578027] Starting dynamic subtest: pipe-D-eDP-1
[537.600452] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[537.604881] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.005s)
[539.707334] [088/133] (486s left) syncobj_timeline (wait-for-submit-complex)
[539.765026] Starting subtest: wait-for-submit-complex
[539.825642] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[539.916683] [089/133] (486s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[540.014181] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[540.018323] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[540.119588] [090/133] (485s left) gem_spin_batch (resubmit)
[540.178745] Starting subtest: resubmit
[540.181043] Starting dynamic subtest: rcs0
[540.185723] Dynamic subtest rcs0: SUCCESS (0.006s)
[540.196305] Starting dynamic subtest: bcs0
[540.198406] Dynamic subtest bcs0: SUCCESS (0.005s)
[540.207957] Starting dynamic subtest: vcs0
[540.209792] Dynamic subtest vcs0: SUCCESS (0.004s)
[540.218942] Starting dynamic subtest: vcs1
[540.220776] Dynamic subtest vcs1: SUCCESS (0.002s)
[540.229996] Starting dynamic subtest: vecs0
[540.231825] Dynamic subtest vecs0: SUCCESS (0.002s)
[540.240823] Starting dynamic subtest: ccs0
[540.242690] Dynamic subtest ccs0: SUCCESS (0.002s)
[540.244499] Subtest resubmit: SUCCESS (0.029s)
[540.460618] [091/133] (485s left) kms_psr (fbc-psr-primary-render)
[540.557277] Starting subtest: fbc-psr-primary-render
[540.559398] Starting dynamic subtest: eDP-1
[540.563822] Dynamic subtest eDP-1: SKIP (0.000s)
[540.567963] Subtest fbc-psr-primary-render: SKIP (0.002s)
[540.637077] [092/133] (485s left) kms_async_flips (async-flip-suspend-resume)
[540.724958] Starting subtest: async-flip-suspend-resume
[541.102735] Starting dynamic subtest: pipe-A-eDP-1
[559.249843] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.907s)
[559.630434] Starting dynamic subtest: pipe-B-eDP-1
[577.281765] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.862s)
[577.643895] Starting dynamic subtest: pipe-C-eDP-1
[595.284647] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.937s)
[595.651023] Starting dynamic subtest: pipe-D-eDP-1
[613.315469] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.946s)
[613.317791] Subtest async-flip-suspend-resume: SUCCESS (13.115s)
[614.489247] [093/133] (411s left) gem_exec_reloc (basic-cpu-wc)
[614.548242] Subtest basic-cpu-wc: SKIP (0.000s)
[614.614906] [094/133] (411s left) perf_pmu (interrupts-sync)
[614.653593] Starting subtest: interrupts-sync
[615.755776] Subtest interrupts-sync: SUCCESS (1.099s)
[615.875228] [095/133] (410s left) gem_exec_balancer (bonded-sync)
[615.923441] Starting subtest: bonded-sync
[615.927720] Subtest bonded-sync: SKIP (0.000s)
[616.233128] [096/133] (410s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[616.338804] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[616.341200] Starting dynamic subtest: pipe-A-default-mode
[619.061479] Dynamic subtest pipe-A-default-mode: SUCCESS (2.717s)
[619.063685] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.719s)
[619.910085] [097/133] (406s left) gem_tiled_blits (basic)
[619.966342] Subtest basic: SKIP (0.000s)
[620.039778] [098/133] (406s left) gem_ctx_shared (q-smoketest)
[620.112950] Starting subtest: Q-smoketest
[620.115336] Starting dynamic subtest: rcs0
[625.666943] Dynamic subtest rcs0: SUCCESS (5.497s)
[625.680270] Starting dynamic subtest: bcs0
[631.171308] Dynamic subtest bcs0: SUCCESS (5.499s)
[631.186160] Starting dynamic subtest: vcs0
[636.652855] Dynamic subtest vcs0: SUCCESS (5.480s)
[636.666004] Starting dynamic subtest: vcs1
[642.133619] Dynamic subtest vcs1: SUCCESS (5.471s)
[642.145593] Starting dynamic subtest: vecs0
[647.614553] Dynamic subtest vecs0: SUCCESS (5.484s)
[647.625012] Starting dynamic subtest: ccs0
[653.121271] Dynamic subtest ccs0: SUCCESS (5.503s)
[653.123611] Subtest Q-smoketest: SUCCESS (32.943s)
[653.269175] [099/133] (373s left) kms_cursor_crc (cursor-sliding-256x256)
[653.532222] Starting subtest: cursor-sliding-256x256
[653.537172] Starting dynamic subtest: pipe-A-eDP-1
[656.786213] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.246s)
[656.820394] Starting dynamic subtest: pipe-D-eDP-1
[660.654972] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.846s)
[660.656921] Subtest cursor-sliding-256x256: SUCCESS (7.121s)
[661.537873] [100/133] (364s left) gem_pxp (reject-modify-context-protection-off-2)
[661.668247] Starting subtest: reject-modify-context-protection-off-2
[661.670696] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[661.787599] [101/133] (364s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[661.891812] Starting subtest: yf-tiled-addfb-size-offset-overflow
[661.895807] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[662.022946] [102/133] (364s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[662.121267] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[663.145358] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.023s)
[663.255701] [103/133] (363s left) gem_userptr_blits (usage-restrictions)
[663.325920] Starting subtest: usage-restrictions
[663.327654] Subtest usage-restrictions: SUCCESS (0.000s)
[663.463048] [104/133] (363s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[663.548769] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[670.057928] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.508s)
[670.183631] [105/133] (356s left) kms_plane_alpha_blend (constant-alpha-max)
[670.290848] Starting subtest: constant-alpha-max
[670.586339] Starting dynamic subtest: pipe-A-eDP-1
[671.642877] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.054s)
[671.653212] Starting dynamic subtest: pipe-D-eDP-1
[673.352429] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.710s)
[673.354769] Subtest constant-alpha-max: SUCCESS (3.063s)
[674.211367] [106/133] (352s left) kms_atomic_transition (plane-toggle-modeset-transition)
[674.307941] Starting subtest: plane-toggle-modeset-transition
[674.310169] Starting dynamic subtest: pipe-A-eDP-1
[687.330489] Dynamic subtest pipe-A-eDP-1: SUCCESS (13.013s)
[687.341758] Starting dynamic subtest: pipe-B-eDP-1
[700.229955] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.895s)
[700.232591] Subtest plane-toggle-modeset-transition: SUCCESS (25.913s)
[701.035098] [107/133] (325s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[701.134792] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[701.915601] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.782s)
[702.078580] [108/133] (324s left) api_intel_bb (offset-control)
[702.133592] Starting subtest: offset-control
[702.139329] Subtest offset-control: SUCCESS (0.008s)
[702.258007] [109/133] (324s left) kms_cursor_legacy (torture-move)
[702.385919] Starting subtest: torture-move
[702.387814] Starting dynamic subtest: pipe-A
[708.634598] Dynamic subtest pipe-A: SUCCESS (6.242s)
[708.647231] Starting dynamic subtest: pipe-B
[716.755327] Dynamic subtest pipe-B: SUCCESS (8.122s)
[716.769006] Starting dynamic subtest: pipe-C
[724.104642] Dynamic subtest pipe-C: SUCCESS (7.348s)
[724.119995] Starting dynamic subtest: pipe-D
[730.605628] Dynamic subtest pipe-D: SUCCESS (6.501s)
[730.616646] Starting dynamic subtest: all-pipes
[738.091464] Dynamic subtest all-pipes: SUCCESS (7.482s)
[738.094412] Subtest torture-move: SUCCESS (35.707s)
[738.255339] [110/133] (288s left) kms_selftest (drm_format)
[738.305854] Starting subtest: drm_format
[738.373092] Starting dynamic subtest: drm_test_format_block_width_invalid
[738.459682] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.086s)
[738.470817] Starting dynamic subtest: drm_test_format_block_width_one_plane
[738.556752] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.096s)
[738.567678] Starting dynamic subtest: drm_test_format_block_width_two_plane
[738.658702] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.101s)
[738.669909] Starting dynamic subtest: drm_test_format_block_width_three_plane
[738.764463] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.106s)
[738.775647] Starting dynamic subtest: drm_test_format_block_width_tiled
[738.868792] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.103s)
[738.880238] Starting dynamic subtest: drm_test_format_block_height_invalid
[738.959764] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.090s)
[738.971395] Starting dynamic subtest: drm_test_format_block_height_one_plane
[739.064998] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.105s)
[739.076592] Starting dynamic subtest: drm_test_format_block_height_two_plane
[739.165373] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.097s)
[739.176197] Starting dynamic subtest: drm_test_format_block_height_three_plane
[739.261095] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.094s)
[739.271868] Starting dynamic subtest: drm_test_format_block_height_tiled
[739.351155] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.089s)
[739.362161] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[739.438277] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.087s)
[739.447910] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[739.535004] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.096s)
[739.545676] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[739.633161] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.097s)
[739.644341] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[739.725540] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.092s)
[739.736931] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[739.818105] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.092s)
[739.829358] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[739.908328] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.089s)
[739.918392] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[740.001577] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.093s)
[740.011100] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[740.099422] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.096s)
[740.101193] Subtest drm_format: SUCCESS (1.792s)
[740.253646] [111/133] (286s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[740.305948] Starting subtest: invalid-multi-wait-all-unsubmitted
[740.307943] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[740.400054] [112/133] (286s left) kms_psr2_su (frontbuffer-xrgb8888)
[740.463462] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[740.531887] [113/133] (286s left) gem_lmem_swapping (verify-random)
[740.588763] Subtest verify-random: SKIP (0.000s)
[740.635254] [114/133] (286s left) kms_lease (lease-invalid-connector)
[740.728927] Starting subtest: lease-invalid-connector
[740.731010] Starting dynamic subtest: pipe-A-eDP-1
[740.732943] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[740.743222] Starting dynamic subtest: pipe-B-eDP-1
[740.745115] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[740.754867] Starting dynamic subtest: pipe-C-eDP-1
[740.756898] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[740.768197] Starting dynamic subtest: pipe-D-eDP-1
[740.770041] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[740.771823] Subtest lease-invalid-connector: SUCCESS (0.004s)
[740.808689] [115/133] (286s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[740.977750] Starting subtest: cursor-rapid-movement-64x64
[740.982409] Starting dynamic subtest: pipe-A-eDP-1
[741.546166] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.563s)
[741.576762] Starting dynamic subtest: pipe-D-eDP-1
[742.804499] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s)
[742.807097] Subtest cursor-rapid-movement-64x64: SUCCESS (1.826s)
[743.607688] [116/133] (283s left) gem_userptr_blits (unsync-unmap-after-close)
[743.683975] Subtest unsync-unmap-after-close: SKIP (0.000s)
[743.797848] [117/133] (283s left) gem_ctx_exec (basic-nohangcheck)
[743.854312] Starting subtest: basic-nohangcheck
[744.101230] Subtest basic-nohangcheck: SUCCESS (0.247s)
[744.219607] [118/133] (283s left) sriov_basic (enable-vfs-autoprobe-on)
[744.286330] Starting subtest: enable-vfs-autoprobe-on
[744.288707] Starting dynamic subtest: numvfs-1
[744.362435] Dynamic subtest numvfs-1: FAIL (0.018s)
[744.374124] Starting dynamic subtest: numvfs-2
[744.446602] Dynamic subtest numvfs-2: FAIL (0.004s)
[744.457507] Starting dynamic subtest: numvfs-3
[744.531083] Dynamic subtest numvfs-3: FAIL (0.005s)
[744.542421] Starting dynamic subtest: numvfs-4
[744.615045] Dynamic subtest numvfs-4: FAIL (0.004s)
[744.626979] Starting dynamic subtest: numvfs-5
[744.695935] Dynamic subtest numvfs-5: FAIL (0.005s)
[744.705695] Starting dynamic subtest: numvfs-6
[744.774101] Dynamic subtest numvfs-6: FAIL (0.004s)
[744.784472] Starting dynamic subtest: numvfs-7
[744.855968] Dynamic subtest numvfs-7: FAIL (0.004s)
[744.867761] Starting dynamic subtest: numvfs-random
[744.940583] Dynamic subtest numvfs-random: FAIL (0.005s)
[744.956015] Starting dynamic subtest: numvfs-all
[745.026746] Dynamic subtest numvfs-all: FAIL (0.006s)
[745.028332] Subtest enable-vfs-autoprobe-on: FAIL (0.566s)
[745.065180] [119/133] (282s left) syncobj_wait (multi-wait-for-submit-signaled)
[745.131255] Starting subtest: multi-wait-for-submit-signaled
[745.133252] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[745.250872] [120/133] (282s left) kms_cursor_crc (cursor-offscreen-64x21)
[745.452331] Starting subtest: cursor-offscreen-64x21
[745.496812] Subtest cursor-offscreen-64x21: SKIP (0.041s)
[745.762981] [121/133] (281s left) kms_psr (pr-sprite-plane-onoff)
[745.848652] Starting subtest: pr-sprite-plane-onoff
[745.851879] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[745.924817] [122/133] (281s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-blt)
[746.031093] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-blt
[752.882256] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.853s)
[753.045987] [123/133] (274s left) kms_pm_rpm (cursor-dpms)
[754.588092] Starting subtest: cursor-dpms
[754.592889] Subtest cursor-dpms: SKIP (0.000s)
[755.324426] [124/133] (272s left) kms_psr (psr2-suspend)
[755.433238] Starting subtest: psr2-suspend
[755.435664] Starting dynamic subtest: eDP-1
[773.685428] Dynamic subtest eDP-1: SUCCESS (2.934s)
[773.688338] Subtest psr2-suspend: SUCCESS (2.936s)
[774.513120] [125/133] (253s left) gem_exec_params (sol-reset-not-gen7)
[774.581537] Starting subtest: sol-reset-not-gen7
[774.587281] Subtest sol-reset-not-gen7: SUCCESS (0.003s)
[774.707405] [126/133] (252s left) gem_exec_fence (nb-await)
[774.884147] Starting subtest: nb-await
[774.886792] Starting dynamic subtest: rcs0
[774.955324] Dynamic subtest rcs0: SUCCESS (0.071s)
[774.967680] Starting dynamic subtest: bcs0
[775.014485] Dynamic subtest bcs0: SUCCESS (0.057s)
[775.026687] Starting dynamic subtest: vcs0
[775.075915] Dynamic subtest vcs0: SUCCESS (0.059s)
[775.087649] Starting dynamic subtest: vcs1
[775.135172] Dynamic subtest vcs1: SUCCESS (0.058s)
[775.148791] Starting dynamic subtest: vecs0
[775.194129] Dynamic subtest vecs0: SUCCESS (0.057s)
[775.206570] Starting dynamic subtest: ccs0
[775.252564] Dynamic subtest ccs0: SUCCESS (0.056s)
[775.255565] Subtest nb-await: SUCCESS (0.372s)
[776.864635] [127/133] (250s left) kms_psr (psr-dpms)
[776.956631] Starting subtest: psr-dpms
[776.959455] Starting dynamic subtest: eDP-1
[779.865521] Dynamic subtest eDP-1: SUCCESS (2.914s)
[779.867953] Subtest psr-dpms: SUCCESS (2.915s)
[780.697252] [128/133] (246s left) gem_ctx_persistence (heartbeat-hang)
[780.765870] Starting subtest: heartbeat-hang
[780.770797] Subtest heartbeat-hang: SKIP (0.000s)
[780.872632] [129/133] (246s left) api_intel_bb (add-remove-objects)
[780.936213] Starting subtest: add-remove-objects
[780.945555] Subtest add-remove-objects: SUCCESS (0.013s)
[781.081128] [130/133] (246s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[781.183046] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[787.657658] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.486s)
[787.772315] [131/133] (240s left) gem_busy (parallel)
[787.958601] Starting subtest: parallel
[787.961661] Starting dynamic subtest: rcs0
[789.774421] Dynamic subtest rcs0: SUCCESS (1.819s)
[789.787743] Starting dynamic subtest: bcs0
[791.821984] Dynamic subtest bcs0: SUCCESS (2.045s)
[791.835528] Starting dynamic subtest: vcs0
[793.805563] Dynamic subtest vcs0: SUCCESS (1.982s)
[793.818344] Starting dynamic subtest: vcs1
[795.791578] Dynamic subtest vcs1: SUCCESS (1.984s)
[795.804747] Starting dynamic subtest: vecs0
[797.774682] Dynamic subtest vecs0: SUCCESS (1.981s)
[797.789863] Starting dynamic subtest: ccs0
[799.821847] Dynamic subtest ccs0: SUCCESS (2.045s)
[799.824797] Subtest parallel: SUCCESS (11.873s)
[800.058087] [132/133] (227s left) kms_frontbuffer_tracking (psr-2p-rte)
[800.165358] Starting subtest: psr-2p-rte
[800.169525] Subtest psr-2p-rte: SKIP (0.000s)
[800.264167] [133/133] (227s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[800.327561] Starting subtest: invalid-multi-wait-unsubmitted
[800.330494] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[800.431492] Closing watchdogs
results: parsing output: 0/ for test: kms_plane
results: parsing output: 1/ for test: kms_flip
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_chamelium_hpd
results: parsing output: 4/ for test: kms_pm_rpm
results: parsing output: 5/ for test: kms_content_protection
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: kms_flip
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: vgem_basic
results: parsing output: 11/ for test: gem_exec_schedule
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: gem_exec_params
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_plane
results: parsing output: 19/ for test: gem_exec_reloc
results: parsing output: 20/ for test: syncobj_eventfd
results: parsing output: 21/ for test: gen9_exec_parse
results: parsing output: 22/ for test: gem_pipe_control_store_loop
results: parsing output: 23/ for test: gem_exec_params
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_vblank
results: parsing output: 26/ for test: dmabuf_sync_file
results: parsing output: 27/ for test: gem_exec_reloc
results: parsing output: 28/ for test: gem_exec_reloc
results: parsing output: 29/ for test: kms_psr2_sf
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: perf_pmu
results: parsing output: 32/ for test: gem_exec_whisper
results: parsing output: 33/ for test: i915_drm_fdinfo
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_flip
results: parsing output: 36/ for test: gem_exec_schedule
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: i915_hangman
results: parsing output: 40/ for test: kms_draw_crc
results: parsing output: 41/ for test: kms_getfb
results: parsing output: 42/ for test: gem_userptr_blits
results: parsing output: 43/ for test: gem_userptr_blits
results: parsing output: 44/ for test: gem_ppgtt
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: gem_exec_basic
results: parsing output: 47/ for test: kms_invalid_mode
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: syncobj_wait
results: parsing output: 50/ for test: gem_create
results: parsing output: 51/ for test: kms_plane_scaling
results: parsing output: 52/ for test: gem_wait
results: parsing output: 53/ for test: kms_force_connector_basic
results: parsing output: 54/ for test: gem_render_copy_redux
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: syncobj_wait
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_atomic_transition
results: parsing output: 59/ for test: kms_getfb
results: parsing output: 60/ for test: gem_exec_reloc
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: gem_exec_flush
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: syncobj_wait
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_psr2_sf
results: parsing output: 67/ for test: perf_pmu
results: parsing output: 68/ for test: kms_plane_lowres
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_color_pipeline
results: parsing output: 72/ for test: kms_ccs
results: parsing output: 73/ for test: gem_sync
results: parsing output: 74/ for test: kms_cursor_legacy
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: i915_pm_rps
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: i915_pm_rpm
results: parsing output: 79/ for test: gem_exec_whisper
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: gem_render_copy
results: parsing output: 82/ for test: kms_force_connector_basic
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: gem_exec_create
results: parsing output: 85/ for test: kms_chamelium_edid
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: syncobj_timeline
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_spin_batch
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: kms_async_flips
results: parsing output: 92/ for test: gem_exec_reloc
results: parsing output: 93/ for test: perf_pmu
results: parsing output: 94/ for test: gem_exec_balancer
results: parsing output: 95/ for test: kms_flip_scaled_crc
results: parsing output: 96/ for test: gem_tiled_blits
results: parsing output: 97/ for test: gem_ctx_shared
results: parsing output: 98/ for test: kms_cursor_crc
results: parsing output: 99/ for test: gem_pxp
results: parsing output: 100/ for test: kms_big_fb
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: gem_userptr_blits
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_plane_alpha_blend
results: parsing output: 105/ for test: kms_atomic_transition
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: api_intel_bb
results: parsing output: 108/ for test: kms_cursor_legacy
results: parsing output: 109/ for test: kms_selftest
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_psr2_su
results: parsing output: 112/ for test: gem_lmem_swapping
results: parsing output: 113/ for test: kms_lease
results: parsing output: 114/ for test: kms_cursor_crc
results: parsing output: 115/ for test: gem_userptr_blits
results: parsing output: 116/ for test: gem_ctx_exec
results: parsing output: 117/ for test: sriov_basic
results: parsing output: 118/ for test: syncobj_wait
results: parsing output: 119/ for test: kms_cursor_crc
results: parsing output: 120/ for test: kms_psr
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_pm_rpm
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: gem_exec_params
results: parsing output: 125/ for test: gem_exec_fence
results: parsing output: 126/ for test: kms_psr
results: parsing output: 127/ for test: gem_ctx_persistence
results: parsing output: 128/ for test: api_intel_bb
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_busy
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: syncobj_timeline
Done.