[57.826635] Initializing watchdogs
[57.826745]   /dev/watchdog0
[57.843587] [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]
[57.848083] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.850470] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.853677] [001/154] (960s left) dmabuf_sync_file (import-basic)
[57.947309] Starting subtest: import-basic
[57.949379] Subtest import-basic: SUCCESS (0.001s)
[57.999478] [FACT dmabuf_sync_file (import-basic)] new: kernel.kmod_is_loaded.vgem: true
[58.004245] [002/154] (960s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[58.095375] Starting subtest: primary-yf-tiled-reflect-x-0
[58.175823] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.081s)
[58.274229] [003/154] (960s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[58.371189] Starting subtest: plane-lut1d-pre-ctm3x4
[58.527899] Starting dynamic subtest: pipe-A-plane-0
[58.688987] Dynamic subtest pipe-A-plane-0: SUCCESS (0.167s)
[58.697749] Starting dynamic subtest: pipe-A-plane-1
[58.820527] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[58.828316] Starting dynamic subtest: pipe-A-plane-2
[58.971555] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[60.439318] Starting dynamic subtest: pipe-B-plane-0
[60.605637] Dynamic subtest pipe-B-plane-0: SUCCESS (0.178s)
[60.614967] Starting dynamic subtest: pipe-B-plane-1
[60.736111] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[60.744275] Starting dynamic subtest: pipe-B-plane-2
[60.889245] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[62.092920] Starting dynamic subtest: pipe-C-plane-0
[62.216725] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[62.226029] Starting dynamic subtest: pipe-C-plane-1
[62.369833] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[62.378807] Starting dynamic subtest: pipe-C-plane-2
[62.514225] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[63.734871] Starting dynamic subtest: pipe-D-plane-0
[63.892969] Dynamic subtest pipe-D-plane-0: SUCCESS (0.167s)
[63.901702] Starting dynamic subtest: pipe-D-plane-1
[64.024846] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[64.033216] Starting dynamic subtest: pipe-D-plane-2
[64.167824] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[64.467250] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (6.197s)
[65.276143] [004/154] (953s left) kms_vblank (crtc-id)
[65.369155] Starting subtest: crtc-id
[65.371443] Starting dynamic subtest: pipe-A-eDP-1
[65.822884] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.458s)
[65.837480] Starting dynamic subtest: pipe-D-eDP-1
[67.009426] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.195s)
[67.011653] Subtest crtc-id: SUCCESS (1.656s)
[67.842402] [005/154] (950s left) perf_pmu (busy-idle-no-semaphores)
[67.885109] Starting subtest: busy-idle-no-semaphores
[67.887534] Starting dynamic subtest: rcs0
[68.547047] Dynamic subtest rcs0: SUCCESS (0.664s)
[68.557926] Starting dynamic subtest: bcs0
[69.195811] Dynamic subtest bcs0: SUCCESS (0.651s)
[69.205166] Starting dynamic subtest: vcs0
[69.846708] Dynamic subtest vcs0: SUCCESS (0.653s)
[69.857605] Starting dynamic subtest: vcs1
[71.917070] Dynamic subtest vcs1: SUCCESS (2.079s)
[71.928100] Starting dynamic subtest: vecs0
[73.901574] Dynamic subtest vecs0: SUCCESS (1.991s)
[73.912294] Starting dynamic subtest: ccs0
[75.882476] Dynamic subtest ccs0: SUCCESS (1.985s)
[75.885324] Subtest busy-idle-no-semaphores: SUCCESS (8.029s)
[76.016040] [006/154] (942s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[76.127578] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[83.532045] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.422s)
[83.656506] [007/154] (934s left) kms_psr (psr2-cursor-mmap-cpu)
[83.754944] Starting subtest: psr2-cursor-mmap-cpu
[83.757204] Starting dynamic subtest: eDP-1
[85.433373] Dynamic subtest eDP-1: SUCCESS (1.680s)
[85.435721] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.680s)
[86.307835] [008/154] (932s left) gem_exec_params (readonly)
[86.359435] Starting subtest: readonly
[86.361816] Subtest readonly: SUCCESS (0.003s)
[86.484662] [009/154] (932s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[86.592779] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[86.597225] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[86.698070] [010/154] (932s left) gem_ctx_isolation (preservation-s3)
[86.758944] Starting subtest: preservation-S3
[86.761471] Starting dynamic subtest: rcs0
[103.163773] Dynamic subtest rcs0: SUCCESS (1.445s)
[103.178047] Starting dynamic subtest: bcs0
[119.141988] Dynamic subtest bcs0: SUCCESS (1.282s)
[119.151641] Starting dynamic subtest: vcs0
[135.128309] Dynamic subtest vcs0: SUCCESS (2.926s)
[135.139747] Starting dynamic subtest: vcs1
[153.124621] Dynamic subtest vcs1: SUCCESS (5.243s)
[153.134089] Starting dynamic subtest: vecs0
[171.130239] Dynamic subtest vecs0: SUCCESS (5.174s)
[171.139943] Starting dynamic subtest: ccs0
[189.121122] Dynamic subtest ccs0: SUCCESS (3.222s)
[189.123337] Subtest preservation-S3: SUCCESS (19.296s)
[189.357111] [011/154] (829s left) perf_pmu (idle-no-semaphores)
[189.398500] Starting subtest: idle-no-semaphores
[189.400435] Starting dynamic subtest: rcs0
[189.969554] Dynamic subtest rcs0: SUCCESS (0.587s)
[189.979323] Starting dynamic subtest: bcs0
[190.511720] Dynamic subtest bcs0: SUCCESS (0.538s)
[190.523790] Starting dynamic subtest: vcs0
[191.007280] Dynamic subtest vcs0: SUCCESS (0.526s)
[191.018483] Starting dynamic subtest: vcs1
[191.532432] Dynamic subtest vcs1: SUCCESS (0.538s)
[191.543554] Starting dynamic subtest: vecs0
[192.057513] Dynamic subtest vecs0: SUCCESS (0.534s)
[192.068623] Starting dynamic subtest: ccs0
[192.574288] Dynamic subtest ccs0: SUCCESS (0.526s)
[192.576849] Subtest idle-no-semaphores: SUCCESS (3.260s)
[192.682942] [012/154] (826s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[192.777028] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[192.779361] Starting dynamic subtest: pipe-A-default-mode
[195.457619] Dynamic subtest pipe-A-default-mode: SUCCESS (2.726s)
[195.459910] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.727s)
[196.322197] [013/154] (822s left) kms_async_flips (test-cursor)
[196.414104] Starting subtest: test-cursor
[196.779882] Starting dynamic subtest: pipe-A-eDP-1
[197.606516] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.837s)
[197.977969] Starting dynamic subtest: pipe-B-eDP-1
[198.723463] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.761s)
[199.102078] Starting dynamic subtest: pipe-C-eDP-1
[199.850255] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.766s)
[200.212040] Starting dynamic subtest: pipe-D-eDP-1
[200.982579] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.783s)
[200.984954] Subtest test-cursor: SUCCESS (4.615s)
[202.153021] [014/154] (816s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[202.260295] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[202.264238] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[202.358398] [015/154] (816s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[202.422139] Starting subtest: yf-tiled-to-vebox-x-tiled
[202.426807] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[202.617153] [016/154] (816s left) gem_sync (basic-store-each)
[202.696000] Starting subtest: basic-store-each
[205.151574] Subtest basic-store-each: SUCCESS (2.479s)
[205.335847] [017/154] (813s left) kms_psr (fbc-pr-cursor-plane-onoff)
[205.431909] Starting subtest: fbc-pr-cursor-plane-onoff
[205.436345] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[205.503454] [018/154] (813s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[205.601617] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[212.660137] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.083s)
[212.765205] [019/154] (806s left) gem_exec_fence (nb-await)
[212.942720] Starting subtest: nb-await
[212.945194] Starting dynamic subtest: rcs0
[213.009439] Dynamic subtest rcs0: SUCCESS (0.066s)
[213.020009] Starting dynamic subtest: bcs0
[213.069439] Dynamic subtest bcs0: SUCCESS (0.058s)
[213.080216] Starting dynamic subtest: vcs0
[213.129939] Dynamic subtest vcs0: SUCCESS (0.059s)
[213.140212] Starting dynamic subtest: vcs1
[213.189211] Dynamic subtest vcs1: SUCCESS (0.058s)
[213.199186] Starting dynamic subtest: vecs0
[213.250752] Dynamic subtest vecs0: SUCCESS (0.060s)
[213.262062] Starting dynamic subtest: ccs0
[213.309815] Dynamic subtest ccs0: SUCCESS (0.058s)
[213.312594] Subtest nb-await: SUCCESS (0.369s)
[215.057710] [020/154] (804s left) kms_cursor_legacy (torture-move)
[215.173542] Starting subtest: torture-move
[215.175420] Starting dynamic subtest: pipe-A
[221.156039] Dynamic subtest pipe-A: SUCCESS (6.003s)
[221.167071] Starting dynamic subtest: pipe-B
[226.794177] Dynamic subtest pipe-B: SUCCESS (5.679s)
[226.806838] Starting dynamic subtest: pipe-C
[233.262972] Dynamic subtest pipe-C: SUCCESS (6.491s)
[233.284855] Starting dynamic subtest: pipe-D
[240.054002] Dynamic subtest pipe-D: SUCCESS (6.800s)
[240.064381] Starting dynamic subtest: all-pipes
[246.902030] Dynamic subtest all-pipes: SUCCESS (6.852s)
[246.905109] Subtest torture-move: SUCCESS (31.835s)
[247.071363] [021/154] (772s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[247.181574] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[247.183838] Starting dynamic subtest: pipe-A-eDP-1
[258.185550] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.007s)
[258.197102] Starting dynamic subtest: pipe-B-eDP-1
[269.924568] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.734s)
[269.926867] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (22.743s)
[270.772625] [022/154] (748s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[270.876442] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[270.881029] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[270.974814] [023/154] (748s left) kms_hdr (static-toggle)
[271.065473] Starting subtest: static-toggle
[271.067764] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[271.071832] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[271.084082] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[271.089006] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[271.093374] Subtest static-toggle: SKIP (0.002s)
[271.154026] [024/154] (748s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[271.243991] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[271.245900] Starting dynamic subtest: pipe-A-eDP-1
[272.115069] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.865s)
[272.127243] Starting dynamic subtest: pipe-B-eDP-1
[273.746470] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.628s)
[273.757219] Starting dynamic subtest: pipe-C-eDP-1
[275.311661] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.564s)
[275.322360] Starting dynamic subtest: pipe-D-eDP-1
[276.858075] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.545s)
[276.860413] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.608s)
[278.018748] [025/154] (741s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[278.115207] Starting subtest: x-tiled-64bpp-rotate-0
[279.803723] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.690s)
[279.981488] [026/154] (739s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[280.040657] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[280.042566] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.001s)
[280.141015] [027/154] (739s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[280.246411] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[280.249928] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[280.350303] [028/154] (739s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-onoff)
[280.450000] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-onoff
[280.453439] Subtest fbchdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[280.545037] [029/154] (738s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[280.612498] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[280.723356] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.108s)
[280.798199] [030/154] (738s left) syncobj_timeline (reset-signaled)
[280.857243] Starting subtest: reset-signaled
[280.859655] Subtest reset-signaled: SUCCESS (0.000s)
[280.955381] [031/154] (738s left) gem_ctx_persistence (legacy-engines-mixed)
[281.019361] Starting subtest: legacy-engines-mixed
[281.021324] Starting dynamic subtest: render
[281.098245] Dynamic subtest render: SUCCESS (0.081s)
[281.112145] Starting dynamic subtest: bsd1
[281.150212] Dynamic subtest bsd1: SUCCESS (0.049s)
[281.161424] Starting dynamic subtest: bsd2
[281.215535] Dynamic subtest bsd2: SUCCESS (0.062s)
[281.227810] Starting dynamic subtest: blt
[281.275944] Dynamic subtest blt: SUCCESS (0.058s)
[281.290713] Starting dynamic subtest: vebox
[281.342546] Dynamic subtest vebox: SUCCESS (0.063s)
[281.345571] Subtest legacy-engines-mixed: SUCCESS (0.327s)
[281.435561] [032/154] (738s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[281.537689] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[281.541893] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[281.630077] [033/154] (738s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-pwrite)
[281.722233] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-pwrite
[281.726652] Subtest hdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[281.815009] [034/154] (737s left) syncobj_wait (invalid-signal-zero-handles)
[281.873693] Starting subtest: invalid-signal-zero-handles
[281.875451] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[281.964069] [035/154] (737s left) gen9_exec_parse (shadow-peek)
[282.022788] Subtest shadow-peek: SKIP (0.000s)
[282.092352] [036/154] (737s left) kms_cursor_edge_walk (256x256-left-edge)
[282.186783] Starting subtest: 256x256-left-edge
[282.188822] Starting dynamic subtest: pipe-A-eDP-1
[285.669299] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.483s)
[285.678996] Starting dynamic subtest: pipe-D-eDP-1
[288.806801] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.138s)
[288.814263] Subtest 256x256-left-edge: SUCCESS (6.624s)
[289.619784] [037/154] (730s left) kms_cursor_crc (cursor-onscreen-128x128)
[289.830654] Starting subtest: cursor-onscreen-128x128
[289.835555] Starting dynamic subtest: pipe-A-eDP-1
[291.946820] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.114s)
[291.978340] Starting dynamic subtest: pipe-D-eDP-1
[294.721862] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.752s)
[294.723727] Subtest cursor-onscreen-128x128: SUCCESS (4.891s)
[295.587555] [038/154] (724s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-onoff)
[295.683170] Starting subtest: fbchdr-2p-primscrn-cur-indfb-onoff
[295.687578] Subtest fbchdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[295.766228] [039/154] (724s left) i915_query (engine-info)
[295.826036] Starting subtest: engine-info
[295.830613] Subtest engine-info: SUCCESS (0.004s)
[295.933830] [040/154] (723s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[296.022197] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[296.024100] Starting dynamic subtest: pipe-A-eDP-1
[297.318397] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.296s)
[297.605237] Starting dynamic subtest: pipe-B-eDP-1
[298.641083] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.046s)
[298.909362] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.890s)
[299.743485] [041/154] (720s left) prime_vgem (sync)
[299.820985] Starting subtest: sync
[299.823293] Starting dynamic subtest: rcs0
[299.875518] Dynamic subtest rcs0: SUCCESS (0.054s)
[299.884794] Starting dynamic subtest: bcs0
[299.948515] Dynamic subtest bcs0: SUCCESS (0.072s)
[299.957837] Starting dynamic subtest: vcs0
[299.996684] Dynamic subtest vcs0: SUCCESS (0.047s)
[300.005862] Starting dynamic subtest: vcs1
[300.042300] Dynamic subtest vcs1: SUCCESS (0.045s)
[300.051561] Starting dynamic subtest: vecs0
[300.089345] Dynamic subtest vecs0: SUCCESS (0.046s)
[300.098599] Starting dynamic subtest: ccs0
[300.136652] Dynamic subtest ccs0: SUCCESS (0.046s)
[300.138826] Subtest sync: SUCCESS (0.316s)
[300.279121] [042/154] (719s left) prime_self_import (basic-with_two_bos)
[300.312679] Starting subtest: basic-with_two_bos
[300.330212] Subtest basic-with_two_bos: SUCCESS (0.020s)
[300.448042] [043/154] (719s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[300.550148] Starting subtest: yf-tiled-32bpp-rotate-90
[300.554695] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[300.659220] [044/154] (719s left) kms_properties (colorop-properties-atomic)
[300.747755] Starting subtest: colorop-properties-atomic
[300.749423] Starting dynamic subtest: pipe-A-eDP-1
[301.118568] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.372s)
[301.129943] Starting dynamic subtest: pipe-B-eDP-1
[302.222447] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.102s)
[302.234981] Starting dynamic subtest: pipe-C-eDP-1
[303.347138] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.124s)
[303.362067] Starting dynamic subtest: pipe-D-eDP-1
[304.396128] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.047s)
[304.398731] Subtest colorop-properties-atomic: SUCCESS (3.650s)
[305.310906] [045/154] (714s left) gen9_exec_parse (valid-registers)
[305.359752] Subtest valid-registers: SKIP (0.000s)
[305.427018] [046/154] (714s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-plflip-blt)
[305.517375] Starting subtest: hdr-2p-scndscrn-shrfb-plflip-blt
[305.521271] Subtest hdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[305.617320] [047/154] (714s left) gem_exec_whisper (basic-normal)
[305.670956] Starting subtest: basic-normal
[309.296604] Subtest basic-normal: SUCCESS (3.627s)
[309.485649] [048/154] (710s left) kms_chamelium_color (ctm-negative)
[309.593467] Subtest ctm-negative: SKIP (0.000s)
[309.640171] [049/154] (710s left) kms_vblank (query-forked-busy-hang)
[309.738351] Starting subtest: query-forked-busy-hang
[309.740487] Starting dynamic subtest: pipe-A-eDP-1
[335.740241] Dynamic subtest pipe-A-eDP-1: SUCCESS (25.999s)
[335.754688] Starting dynamic subtest: pipe-D-eDP-1
[350.783187] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.039s)
[350.785881] Subtest query-forked-busy-hang: SUCCESS (41.043s)
[351.648145] [050/154] (668s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[351.747533] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[358.460225] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.718s)
[358.586579] [051/154] (661s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[358.692500] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[358.696789] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[358.813583] [052/154] (661s left) gem_userptr_blits (usage-restrictions)
[358.872361] Starting subtest: usage-restrictions
[358.874303] Subtest usage-restrictions: SUCCESS (0.000s)
[359.015018] [053/154] (661s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[361.149407] Starting subtest: modeset-lpsp-stress-no-wait
[371.085625] Subtest modeset-lpsp-stress-no-wait: SUCCESS (9.940s)
[371.160960] [054/154] (649s left) gem_eio (create-ext)
[371.361552] Starting subtest: create-ext
[371.653633] Subtest create-ext: SUCCESS (0.291s)
[371.782722] [055/154] (648s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-blt)
[371.887662] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-blt
[371.891534] Subtest psrhdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[371.998115] [056/154] (648s left) gem_lmem_swapping (random)
[372.037221] Subtest random: SKIP (0.000s)
[372.084805] [057/154] (648s left) gem_userptr_blits (readonly-unsync)
[372.142280] Subtest readonly-unsync: SKIP (0.000s)
[372.257367] [058/154] (648s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs)
[372.339261] Starting subtest: missing-ccs-buffer-yf-tiled-ccs
[372.341134] Starting dynamic subtest: pipe-A-eDP-1
[372.360044] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[372.381267] Starting dynamic subtest: pipe-B-eDP-1
[372.399938] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[372.420142] Starting dynamic subtest: pipe-C-eDP-1
[372.439392] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[372.460897] Starting dynamic subtest: pipe-D-eDP-1
[372.482429] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[372.486184] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.004s)
[374.604688] [059/154] (646s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-move)
[374.711932] Starting subtest: fbchdr-1p-primscrn-cur-indfb-move
[374.716120] Subtest fbchdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[374.808896] [060/154] (645s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[374.895347] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[374.897597] Starting dynamic subtest: pipe-A-eDP-1
[374.920439] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[374.931398] Starting dynamic subtest: pipe-B-eDP-1
[374.952246] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[374.962299] Starting dynamic subtest: pipe-C-eDP-1
[374.983276] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[374.996712] Starting dynamic subtest: pipe-D-eDP-1
[375.017830] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[375.021876] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[377.196361] [061/154] (643s left) kms_frontbuffer_tracking (fbc-stridechange)
[377.307984] Starting subtest: fbc-stridechange
[380.519684] Subtest fbc-stridechange: SUCCESS (3.217s)
[381.685747] [062/154] (639s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[381.786188] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[381.790585] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[381.912489] [063/154] (638s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[381.967755] Starting subtest: multi-wait-all-for-submit-submitted
[382.074915] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.108s)
[382.152586] [064/154] (638s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[382.259895] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[382.263869] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[382.360106] [065/154] (638s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[382.450426] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[382.452612] Starting dynamic subtest: pipe-A-eDP-1
[382.474589] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[382.485970] Starting dynamic subtest: pipe-B-eDP-1
[382.506465] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[382.516387] Starting dynamic subtest: pipe-C-eDP-1
[382.537761] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[382.550225] Starting dynamic subtest: pipe-D-eDP-1
[382.572095] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[382.576421] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[384.752164] [066/154] (636s left) gem_ccs (large-ctrl-surf-copy)
[384.821110] Starting subtest: large-ctrl-surf-copy
[384.825263] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[384.925726] [067/154] (636s left) gem_exec_reloc (basic-wc-gtt-active)
[384.976181] Subtest basic-wc-gtt-active: SKIP (0.000s)
[385.064313] [068/154] (636s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[385.165756] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[385.170222] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[385.264187] [069/154] (635s left) kms_flip (busy-flip)
[385.348413] Starting subtest: busy-flip
[385.351101] Starting dynamic subtest: A-eDP1
[387.064398] Dynamic subtest A-eDP1: SUCCESS (1.715s)
[387.079383] Starting dynamic subtest: B-eDP1
[388.797883] Dynamic subtest B-eDP1: SUCCESS (1.732s)
[388.809551] Starting dynamic subtest: C-eDP1
[390.513900] Dynamic subtest C-eDP1: SUCCESS (1.715s)
[390.531903] Starting dynamic subtest: D-eDP1
[392.242471] Dynamic subtest D-eDP1: SUCCESS (1.726s)
[392.244910] Subtest busy-flip: SUCCESS (6.899s)
[393.410790] [070/154] (627s left) kms_plane_lowres (tiling-yf)
[393.504326] Starting subtest: tiling-yf
[393.508586] Subtest tiling-yf: SKIP (0.000s)
[393.578952] [071/154] (627s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-render)
[393.705875] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-render
[393.709977] Subtest fbchdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[393.798759] [072/154] (627s left) kms_feature_discovery (psr1)
[393.883366] Starting subtest: psr1
[393.885656] Subtest psr1: SUCCESS (0.000s)
[393.956288] [073/154] (627s left) gem_softpin (overlap)
[394.022543] Starting subtest: overlap
[394.947702] Subtest overlap: SUCCESS (0.925s)
[395.064380] [074/154] (626s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[395.176362] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[396.222305] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.045s)
[396.346961] [075/154] (625s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[396.439261] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[396.442623] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[396.521682] [076/154] (624s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[396.621409] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[396.624877] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[396.726107] [077/154] (624s left) i915_pm_freq_api (freq-suspend)
[396.798063] Starting subtest: freq-suspend
[396.799982] Starting dynamic subtest: gt0
[413.082305] Dynamic subtest gt0: SUCCESS (1.200s)
[413.097548] Starting dynamic subtest: gt1
[429.092018] Dynamic subtest gt1: SUCCESS (1.217s)
[429.094641] Subtest freq-suspend: SUCCESS (2.420s)
[429.172714] [078/154] (592s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[429.272157] Starting subtest: fbc-rgb565-draw-mmap-wc
[433.180610] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.057s)
[434.321403] [079/154] (587s left) gem_mmap_offset (partial-mmap)
[434.398790] Starting subtest: partial-mmap
[434.401208] Starting dynamic subtest: smem0
[434.403410] Dynamic subtest smem0: SUCCESS (0.004s)
[434.405670] Subtest partial-mmap: SUCCESS (0.004s)
[434.523138] [080/154] (587s left) gem_exec_capture (capture-recoverable)
[434.577525] Starting subtest: capture-recoverable
[434.579853] Subtest capture-recoverable: SUCCESS (0.003s)
[434.709964] [081/154] (586s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[434.978304] Starting subtest: cursor-rapid-movement-512x512
[434.982438] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[435.155641] [082/154] (586s left) gem_pxp (create-regular-context-1)
[435.269817] Starting subtest: create-regular-context-1
[435.271579] Subtest create-regular-context-1: SUCCESS (0.001s)
[435.371632] [083/154] (586s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-fullscreen)
[435.464366] Starting subtest: hdr-1p-primscrn-spr-indfb-fullscreen
[435.467725] Subtest hdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[435.570711] [084/154] (586s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[435.667547] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[435.671009] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[435.758149] [085/154] (586s left) kms_pm_backlight (brightness-with-dpms)
[435.838273] Starting subtest: brightness-with-dpms
[435.891091] Starting dynamic subtest: eDP-1
[438.616481] Dynamic subtest eDP-1: SUCCESS (2.772s)
[438.619407] Subtest brightness-with-dpms: SUCCESS (2.825s)
[439.468259] [086/154] (582s left) gem_exec_flush (basic-wb-set-default)
[439.529631] Starting subtest: basic-wb-set-default
[444.936723] Subtest basic-wb-set-default: SUCCESS (5.454s)
[445.128116] [087/154] (576s left) gem_mmap_gtt (basic-write)
[445.184360] Subtest basic-write: SKIP (0.000s)
[445.245973] [088/154] (576s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite)
[445.340390] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite
[445.344511] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[445.443196] [089/154] (576s left) gem_exec_balancer (full-late-pulse)
[445.505568] Starting subtest: full-late-pulse
[449.715665] Subtest full-late-pulse: SUCCESS (4.229s)
[450.037443] [090/154] (571s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[450.097300] Starting subtest: multi-wait-all-for-submit-unsubmitted
[450.198692] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[450.272117] [091/154] (571s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[450.330797] Subtest tiled-to-untiled: SKIP (0.000s)
[450.402122] [092/154] (571s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[450.518633] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[450.522867] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[450.622801] [093/154] (571s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[450.727311] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[454.595659] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.885s)
[455.787084] [094/154] (566s left) kms_pm_rpm (basic-rte)
[455.822481] Starting subtest: basic-rte
[459.486029] Subtest basic-rte: SUCCESS (3.670s)
[460.997291] [095/154] (561s left) syncobj_wait (single-wait-all-submitted)
[461.056212] Starting subtest: single-wait-all-submitted
[461.161007] Subtest single-wait-all-submitted: SUCCESS (0.104s)
[461.240149] [096/154] (561s left) gem_exec_schedule (semaphore-codependency)
[461.301980] Starting subtest: semaphore-codependency
[461.307442] Subtest semaphore-codependency: SUCCESS (0.012s)
[461.647436] [097/154] (560s left) syncobj_timeline (wait-any-interrupted)
[461.698995] Starting subtest: wait-any-interrupted
[461.799582] Subtest wait-any-interrupted: SUCCESS (0.101s)
[461.893519] [098/154] (560s left) gem_mmap_gtt (basic-write-read)
[461.949851] Subtest basic-write-read: SKIP (0.000s)
[462.007977] [099/154] (560s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[462.098965] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[462.101163] Starting dynamic subtest: A-eDP1
[471.040647] Dynamic subtest A-eDP1: SUCCESS (8.929s)
[471.052472] Starting dynamic subtest: B-eDP1
[479.977220] Dynamic subtest B-eDP1: SUCCESS (8.930s)
[479.988671] Starting dynamic subtest: C-eDP1
[488.907849] Dynamic subtest C-eDP1: SUCCESS (8.927s)
[488.918192] Starting dynamic subtest: D-eDP1
[497.839549] Dynamic subtest D-eDP1: SUCCESS (8.929s)
[497.841976] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.723s)
[499.107797] [100/154] (523s left) syncobj_wait (multi-wait-all-signaled)
[499.156224] Starting subtest: multi-wait-all-signaled
[499.158585] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[499.254335] [101/154] (523s left) kms_joiner (invalid-modeset-big-joiner)
[499.348429] Starting subtest: invalid-modeset-big-joiner
[499.352526] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[499.413954] [102/154] (523s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[499.796765] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[500.587672] [103/154] (521s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[500.689039] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[500.692980] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[500.783493] [104/154] (521s left) kms_vblank (ts-continuation-dpms-suspend)
[500.876751] Starting subtest: ts-continuation-dpms-suspend
[500.878944] Starting dynamic subtest: pipe-A-eDP-1
[518.669594] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.855s)
[518.685630] Starting dynamic subtest: pipe-D-eDP-1
[536.644651] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.574s)
[536.646868] Subtest ts-continuation-dpms-suspend: SUCCESS (4.433s)
[537.497752] [105/154] (485s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[537.607510] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[537.619037] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[537.694675] [106/154] (485s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[537.801600] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[537.805504] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[537.883209] [107/154] (484s left) kms_psr (psr2-no-drrs)
[537.969699] Starting subtest: psr2-no-drrs
[537.971979] Starting dynamic subtest: eDP-1
[539.532768] Dynamic subtest eDP-1: SUCCESS (1.618s)
[539.535058] Subtest psr2-no-drrs: SUCCESS (1.619s)
[540.390536] [108/154] (482s left) gem_exec_params (sol-reset-not-gen7)
[540.435942] Starting subtest: sol-reset-not-gen7
[540.440945] Subtest sol-reset-not-gen7: SUCCESS (0.003s)
[540.561576] [109/154] (482s left) gem_reg_read (timestamp-monotonic)
[540.615601] Starting subtest: timestamp-monotonic
[545.538126] Subtest timestamp-monotonic: SUCCESS (5.017s)
[545.630522] [110/154] (477s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[545.731066] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[545.735423] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[545.817027] [111/154] (477s left) kms_psr (psr-primary-render)
[545.913225] Starting subtest: psr-primary-render
[545.915489] Starting dynamic subtest: eDP-1
[547.671821] Dynamic subtest eDP-1: SUCCESS (1.779s)
[547.674042] Subtest psr-primary-render: SUCCESS (1.780s)
[548.521804] [112/154] (474s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-render)
[548.629056] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-render
[548.633357] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[548.739765] [113/154] (474s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-pgflip-blt)
[548.840467] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-pgflip-blt
[548.844323] Subtest fbcpsrhdr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[548.932151] [114/154] (474s left) kms_big_fb (linear-16bpp-rotate-0)
[549.033579] Starting subtest: linear-16bpp-rotate-0
[550.490972] Subtest linear-16bpp-rotate-0: SUCCESS (1.469s)
[550.618637] [115/154] (472s left) syncobj_timeline (multi-wait-all-available-submitted)
[550.686300] Starting subtest: multi-wait-all-available-submitted
[550.687969] Subtest multi-wait-all-available-submitted: SUCCESS (0.000s)
[550.810275] [116/154] (472s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[550.904090] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[550.907511] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[550.998546] [117/154] (472s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[551.094573] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[551.480633] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[554.272571] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.805s)
[554.285918] Starting dynamic subtest: pipe-A-eDP-1-4
[556.456878] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.193s)
[556.467178] Starting dynamic subtest: pipe-A-eDP-1-4
[558.470951] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.018s)
[558.485464] Starting dynamic subtest: pipe-A-eDP-1-x
[560.643401] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.177s)
[561.005408] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[563.762737] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.772s)
[563.773446] Starting dynamic subtest: pipe-B-eDP-1-4
[565.954971] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.194s)
[565.966209] Starting dynamic subtest: pipe-B-eDP-1-4
[567.961814] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.008s)
[567.976627] Starting dynamic subtest: pipe-B-eDP-1-x
[570.138270] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.176s)
[570.514430] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[573.287650] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.779s)
[573.301280] Starting dynamic subtest: pipe-C-eDP-1-4
[575.464186] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.172s)
[575.476974] Starting dynamic subtest: pipe-C-eDP-1-4
[577.477323] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[577.490346] Starting dynamic subtest: pipe-C-eDP-1-x
[579.669021] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.189s)
[580.035335] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[582.827565] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.797s)
[582.842035] Starting dynamic subtest: pipe-D-eDP-1-4
[585.002295] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.171s)
[585.013511] Starting dynamic subtest: pipe-D-eDP-1-4
[587.013755] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[587.026812] Starting dynamic subtest: pipe-D-eDP-1-x
[589.193417] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.177s)
[589.196314] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.133s)
[590.418348] [118/154] (432s left) kms_plane (pixel-format-yf-tiled-modifier)
[590.517453] Starting subtest: pixel-format-yf-tiled-modifier
[590.521851] Subtest pixel-format-yf-tiled-modifier: SKIP (0.000s)
[590.587451] [119/154] (432s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[590.686824] Starting subtest: fbc-1p-pri-indfb-multidraw
[603.801467] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.120s)
[605.163801] [120/154] (418s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[605.213397] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[605.215710] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[605.315040] [121/154] (418s left) i915_pm_rpm (gem-pread)
[607.451039] Starting subtest: gem-pread
[609.217205] Subtest gem-pread: SUCCESS (1.769s)
[609.316746] [122/154] (414s left) kms_chamelium_hpd (dp-hpd)
[609.705756] Subtest dp-hpd: SKIP (0.000s)
[610.499237] [123/154] (412s left) kms_big_fb (y-tiled-addfb)
[610.601027] Starting subtest: y-tiled-addfb
[610.605442] Subtest y-tiled-addfb: SKIP (0.000s)
[610.712262] [124/154] (412s left) gem_pxp (reject-modify-context-protection-off-2)
[610.813874] Starting subtest: reject-modify-context-protection-off-2
[610.816283] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[610.930613] [125/154] (412s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[611.015205] Starting subtest: fbc-psr2-cursor-mmap-cpu
[611.017598] Starting dynamic subtest: eDP-1
[611.021971] Dynamic subtest eDP-1: SKIP (0.000s)
[611.026398] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.001s)
[611.102290] [126/154] (412s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-move)
[611.219575] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-move
[611.224122] Subtest psrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[611.332571] [127/154] (412s left) gem_userptr_blits (map-fixed-invalidate-busy)
[611.397751] Starting subtest: map-fixed-invalidate-busy
[611.402560] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[611.516487] [128/154] (412s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[611.605964] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[611.609188] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[611.696311] [129/154] (412s left) kms_content_protection (dp-mst-type-0)
[611.830745] Starting subtest: dp-mst-type-0
[611.834124] Subtest dp-mst-type-0: SKIP (0.000s)
[611.961957] [130/154] (411s left) gem_softpin (allocator-nopin)
[612.010528] Starting subtest: allocator-nopin
[612.099296] Subtest allocator-nopin: SUCCESS (0.088s)
[612.245361] [131/154] (411s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-move)
[612.344040] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-move
[612.347561] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[612.446763] [132/154] (411s left) gem_lmem_swapping (heavy-multi)
[612.488357] Subtest heavy-multi: SKIP (0.000s)
[612.535655] [133/154] (411s left) kms_plane (plane-panning-top-left)
[612.622970] Starting subtest: plane-panning-top-left
[612.624731] Starting dynamic subtest: pipe-A
[614.282508] Dynamic subtest pipe-A: SUCCESS (1.658s)
[614.293632] Starting dynamic subtest: pipe-B
[616.645176] Dynamic subtest pipe-B: SUCCESS (2.364s)
[616.647582] Subtest plane-panning-top-left: SUCCESS (4.025s)
[617.461014] [134/154] (406s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-shrfb-fliptrack-mmap-gtt)
[617.573049] Starting subtest: fbcpsrhdr-2p-shrfb-fliptrack-mmap-gtt
[617.577377] Subtest fbcpsrhdr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[617.665819] [135/154] (406s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[617.759593] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[617.763646] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[617.863496] [136/154] (406s left) api_intel_allocator (two-level-inception)
[617.914931] Starting subtest: two-level-inception
[623.314684] Subtest two-level-inception: SUCCESS (5.401s)
[623.448756] [137/154] (400s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[623.547417] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[623.549861] Starting dynamic subtest: pipe-A-default-mode
[624.725377] Dynamic subtest pipe-A-default-mode: SKIP (1.176s)
[624.729407] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.178s)
[624.906734] [138/154] (399s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[625.042929] Starting subtest: basic-flip-after-cursor-varying-size
[625.993826] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.947s)
[626.843930] [139/154] (397s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-blt)
[626.940527] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-blt
[626.944799] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[627.042977] [140/154] (397s left) gem_exec_schedule (lateslice)
[627.114860] Starting subtest: lateslice
[627.116741] Starting dynamic subtest: rcs0
[627.128650] Dynamic subtest rcs0: SUCCESS (0.021s)
[627.138878] Starting dynamic subtest: bcs0
[627.148282] Dynamic subtest bcs0: SUCCESS (0.019s)
[627.158942] Starting dynamic subtest: vcs0
[627.165745] Dynamic subtest vcs0: SUCCESS (0.017s)
[627.176281] Starting dynamic subtest: vcs1
[627.184686] Dynamic subtest vcs1: SUCCESS (0.018s)
[627.195592] Starting dynamic subtest: vecs0
[627.203445] Dynamic subtest vecs0: SUCCESS (0.018s)
[627.215192] Starting dynamic subtest: ccs0
[627.221269] Dynamic subtest ccs0: SUCCESS (0.017s)
[627.223497] Subtest lateslice: SUCCESS (0.114s)
[628.342685] [141/154] (395s left) gem_vm_create (create-ext)
[628.395948] Starting subtest: create-ext
[628.402234] Subtest create-ext: SUCCESS (0.005s)
[628.517743] [142/154] (395s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[628.613328] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[635.787869] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (7.179s)
[635.927858] [143/154] (388s left) kms_frontbuffer_tracking (hdr-2p-primscrn-shrfb-msflip-blt)
[636.026901] Starting subtest: hdr-2p-primscrn-shrfb-msflip-blt
[636.031213] Subtest hdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[636.127003] [144/154] (388s left) gem_eio (wait-1us)
[636.341398] Starting subtest: wait-1us
[636.677773] Subtest wait-1us: SUCCESS (0.338s)
[636.819669] [145/154] (387s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[636.876197] Subtest no-spare-fences-busy: SKIP (0.000s)
[636.950696] [146/154] (387s left) kms_flip (flip-vs-wf_vblank-interruptible)
[637.040420] Starting subtest: flip-vs-wf_vblank-interruptible
[637.044629] Starting dynamic subtest: A-eDP1
[638.957813] Dynamic subtest A-eDP1: SUCCESS (1.914s)
[638.971527] Starting dynamic subtest: B-eDP1
[640.886437] Dynamic subtest B-eDP1: SUCCESS (1.925s)
[640.899547] Starting dynamic subtest: C-eDP1
[642.800968] Dynamic subtest C-eDP1: SUCCESS (1.911s)
[642.811890] Starting dynamic subtest: D-eDP1
[644.724810] Dynamic subtest D-eDP1: SUCCESS (1.922s)
[644.729896] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.684s)
[645.963690] [147/154] (378s left) kms_lease (page-flip-implicit-plane)
[646.050190] Starting subtest: page-flip-implicit-plane
[646.052607] Starting dynamic subtest: pipe-A-eDP-1
[646.542691] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.492s)
[646.553851] Starting dynamic subtest: pipe-B-eDP-1
[647.708765] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.165s)
[647.721730] Starting dynamic subtest: pipe-C-eDP-1
[648.824659] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.111s)
[648.835973] Starting dynamic subtest: pipe-D-eDP-1
[649.947485] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s)
[649.949824] Subtest page-flip-implicit-plane: SUCCESS (3.897s)
[650.780845] [148/154] (373s left) gem_tiled_wb
[650.892746] [149/154] (373s left) gem_eio (suspend)
[651.115983] Starting subtest: suspend
[663.351063] Subtest suspend: SUCCESS (12.234s)
[663.469310] [150/154] (361s left) gem_ccs (suspend-resume)
[663.541564] Starting subtest: suspend-resume
[663.546291] Subtest suspend-resume: SKIP (0.000s)
[663.660418] [151/154] (360s left) kms_flip (bo-too-big)
[663.750060] Starting subtest: bo-too-big
[663.751937] Starting dynamic subtest: A-eDP1
[665.086420] Dynamic subtest A-eDP1: SUCCESS (1.338s)
[665.097682] Starting dynamic subtest: B-eDP1
[666.413837] Dynamic subtest B-eDP1: SUCCESS (1.326s)
[666.427152] Starting dynamic subtest: C-eDP1
[667.749406] Dynamic subtest C-eDP1: SUCCESS (1.334s)
[667.762353] Starting dynamic subtest: D-eDP1
[669.085259] Dynamic subtest D-eDP1: SUCCESS (1.335s)
[669.087672] Subtest bo-too-big: SUCCESS (5.341s)
[670.302436] [152/154] (354s left) kms_psr (fbc-psr-dpms)
[670.397683] Starting subtest: fbc-psr-dpms
[670.400019] Starting dynamic subtest: eDP-1
[670.404249] Dynamic subtest eDP-1: SKIP (0.000s)
[670.408487] Subtest fbc-psr-dpms: SKIP (0.001s)
[670.470363] [153/154] (354s left) api_intel_bb (reset-flags)
[670.533745] Starting subtest: reset-flags
[670.538333] Subtest reset-flags: SUCCESS (0.008s)
[670.653713] [154/154] (354s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[670.740992] Starting subtest: coverage-vs-premult-vs-constant
[671.022649] Starting dynamic subtest: pipe-A-eDP-1
[671.591197] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.569s)
[671.603034] Starting dynamic subtest: pipe-D-eDP-1
[672.905268] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.309s)
[672.907687] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.164s)
[673.737856] Closing watchdogs
results: parsing output: 0/ for test: dmabuf_sync_file
results: parsing output: 1/ for test: kms_rotation_crc
results: parsing output: 2/ for test: kms_color_pipeline
results: parsing output: 3/ for test: kms_vblank
results: parsing output: 4/ for test: perf_pmu
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_psr
results: parsing output: 7/ for test: gem_exec_params
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_ctx_isolation
results: parsing output: 10/ for test: perf_pmu
results: parsing output: 11/ for test: kms_flip_scaled_crc
results: parsing output: 12/ for test: kms_async_flips
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: gem_render_copy
results: parsing output: 15/ for test: gem_sync
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_exec_fence
results: parsing output: 19/ for test: kms_cursor_legacy
results: parsing output: 20/ for test: kms_psr2_sf
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_hdr
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: syncobj_timeline
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: gem_ctx_persistence
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: syncobj_wait
results: parsing output: 34/ for test: gen9_exec_parse
results: parsing output: 35/ for test: kms_cursor_edge_walk
results: parsing output: 36/ for test: kms_cursor_crc
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: i915_query
results: parsing output: 39/ for test: kms_atomic_transition
results: parsing output: 40/ for test: prime_vgem
results: parsing output: 41/ for test: prime_self_import
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: kms_properties
results: parsing output: 44/ for test: gen9_exec_parse
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: gem_exec_whisper
results: parsing output: 47/ for test: kms_chamelium_color
results: parsing output: 48/ for test: kms_vblank
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_userptr_blits
results: parsing output: 52/ for test: kms_pm_rpm
results: parsing output: 53/ for test: gem_eio
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_lmem_swapping
results: parsing output: 56/ for test: gem_userptr_blits
results: parsing output: 57/ for test: kms_ccs
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: syncobj_wait
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: gem_ccs
results: parsing output: 66/ for test: gem_exec_reloc
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_flip
results: parsing output: 69/ for test: kms_plane_lowres
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_feature_discovery
results: parsing output: 72/ for test: gem_softpin
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: i915_pm_freq_api
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_mmap_offset
results: parsing output: 79/ for test: gem_exec_capture
results: parsing output: 80/ for test: kms_cursor_crc
results: parsing output: 81/ for test: gem_pxp
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_pm_backlight
results: parsing output: 85/ for test: gem_exec_flush
results: parsing output: 86/ for test: gem_mmap_gtt
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_exec_balancer
results: parsing output: 89/ for test: syncobj_wait
results: parsing output: 90/ for test: gem_set_tiling_vs_blt
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_pm_rpm
results: parsing output: 94/ for test: syncobj_wait
results: parsing output: 95/ for test: gem_exec_schedule
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: gem_mmap_gtt
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: syncobj_wait
results: parsing output: 100/ for test: kms_joiner
results: parsing output: 101/ for test: kms_chamelium_edid
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_vblank
results: parsing output: 104/ for test: kms_flip
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: gem_exec_params
results: parsing output: 108/ for test: gem_reg_read
results: parsing output: 109/ for test: kms_flip_scaled_crc
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_async_flips
results: parsing output: 117/ for test: kms_plane
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: syncobj_wait
results: parsing output: 120/ for test: i915_pm_rpm
results: parsing output: 121/ for test: kms_chamelium_hpd
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: gem_pxp
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_userptr_blits
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_content_protection
results: parsing output: 129/ for test: gem_softpin
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_lmem_swapping
results: parsing output: 132/ for test: kms_plane
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: api_intel_allocator
results: parsing output: 136/ for test: kms_flip_scaled_crc
results: parsing output: 137/ for test: kms_cursor_legacy
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: gem_exec_schedule
results: parsing output: 140/ for test: gem_vm_create
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: gem_eio
results: parsing output: 144/ for test: gem_fenced_exec_thrash
results: parsing output: 145/ for test: kms_flip
results: parsing output: 146/ for test: kms_lease
results: parsing output: 147/ for test: gem_tiled_wb
results: parsing output: 148/ for test: gem_eio
results: parsing output: 149/ for test: gem_ccs
results: parsing output: 150/ for test: kms_flip
results: parsing output: 151/ for test: kms_psr
results: parsing output: 152/ for test: api_intel_bb
results: parsing output: 153/ for test: kms_plane_alpha_blend
Done.