[94.670482] Initializing watchdogs
[94.670710]   /dev/watchdog0
[94.709994] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[94.723636] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[94.729509] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[94.734438] [001/133] (960s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[94.937103] Starting subtest: fbc-rgb101010-draw-render
[98.173380] Subtest fbc-rgb101010-draw-render: SUCCESS (3.236s)
[98.460870] [002/133] (956s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[98.570185] Starting subtest: y-tiled-to-vebox-x-tiled
[98.572223] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[98.834264] [003/133] (956s left) gem_exec_gttfill (basic)
[98.956981] Starting subtest: basic
[114.227906] Subtest basic: SUCCESS (15.271s)
[114.480762] [004/133] (940s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[114.731345] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[117.206674] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (2.474s)
[117.509323] [005/133] (938s left) kms_pm_rpm (dpms-non-lpsp)
[119.428579] Starting subtest: dpms-non-lpsp
[119.552173] Subtest dpms-non-lpsp: SUCCESS (0.126s)
[119.840284] [006/133] (935s left) gem_exec_schedule (submit-golden-slice)
[119.973812] Starting subtest: submit-golden-slice
[119.974798] Starting dynamic subtest: rcs0
[120.025892] Dynamic subtest rcs0: SUCCESS (0.052s)
[120.044879] Starting dynamic subtest: bcs0
[120.075843] Dynamic subtest bcs0: SUCCESS (0.049s)
[120.096827] Starting dynamic subtest: vcs0
[120.121817] Dynamic subtest vcs0: SUCCESS (0.044s)
[120.143205] Starting dynamic subtest: vecs0
[120.162201] Dynamic subtest vecs0: SUCCESS (0.041s)
[120.163506] Subtest submit-golden-slice: SUCCESS (0.189s)
[120.588188] [007/133] (935s left) syncobj_timeline (reset-during-wait-for-submit)
[120.681430] Starting subtest: reset-during-wait-for-submit
[120.787115] Subtest reset-during-wait-for-submit: SUCCESS (0.105s)
[120.941190] [008/133] (934s left) kms_properties (connector-properties-legacy)
[121.103890] Starting subtest: connector-properties-legacy
[121.106891] Starting dynamic subtest: pipe-A-HDMI-A-1
[122.909590] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.802s)
[122.930806] Starting dynamic subtest: pipe-A-HDMI-A-2
[124.575117] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.664s)
[124.575992] Subtest connector-properties-legacy: SUCCESS (3.471s)
[124.881884] [009/133] (930s left) perf (i915-ref-count)
[125.099130] Starting subtest: i915-ref-count
[125.184202] Subtest i915-ref-count: SUCCESS (0.085s)
[125.294122] [010/133] (930s left) api_intel_allocator (gem-pool)
[125.384879] Starting subtest: gem-pool
[125.401098] Subtest gem-pool: SUCCESS (0.015s)
[125.611531] [011/133] (930s left) kms_frontbuffer_tracking (fbc-stridechange)
[125.787604] Starting subtest: fbc-stridechange
[127.880311] Subtest fbc-stridechange: SUCCESS (2.090s)
[128.181617] [012/133] (927s left) kms_addfb_basic (bad-pitch-1024)
[128.274271] Starting subtest: bad-pitch-1024
[128.275085] Subtest bad-pitch-1024: SUCCESS (0.000s)
[128.514184] [013/133] (927s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[128.675543] Starting subtest: bad-aux-stride-y-tiled-ccs
[128.676461] Starting dynamic subtest: pipe-A-HDMI-A-1
[128.684127] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.002s)
[128.700739] Starting dynamic subtest: pipe-A-HDMI-A-2
[128.708483] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.002s)
[128.725705] Starting dynamic subtest: pipe-B-HDMI-A-1
[128.733237] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.002s)
[128.750124] Starting dynamic subtest: pipe-B-HDMI-A-2
[128.758764] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.002s)
[128.776187] Starting dynamic subtest: pipe-C-HDMI-A-1
[128.784747] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[128.801231] Starting dynamic subtest: pipe-C-HDMI-A-2
[128.808801] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[128.810645] Subtest bad-aux-stride-y-tiled-ccs: SUCCESS (0.023s)
[129.559448] [014/133] (926s left) gem_workarounds (suspend-resume-fd)
[129.683160] Starting subtest: suspend-resume-fd
[148.324629] Subtest suspend-resume-fd: SUCCESS (1.775s)
[156.412632] [015/133] (907s left) gem_ctx_persistence (legacy-engines-mixed)
[156.524775] Starting subtest: legacy-engines-mixed
[156.526413] Starting dynamic subtest: render
[156.574891] Dynamic subtest render: SUCCESS (0.048s)
[156.597892] Starting dynamic subtest: bsd
[156.631943] Dynamic subtest bsd: SUCCESS (0.052s)
[156.651584] Starting dynamic subtest: blt
[156.685824] Dynamic subtest blt: SUCCESS (0.050s)
[156.706407] Starting dynamic subtest: vebox
[156.749722] Dynamic subtest vebox: SUCCESS (0.061s)
[156.751043] Subtest legacy-engines-mixed: SUCCESS (0.223s)
[156.905973] [016/133] (907s left) gem_exec_schedule (out-order)
[157.027223] Starting subtest: out-order
[157.028240] Starting dynamic subtest: rcs0
[157.143596] Dynamic subtest rcs0: SUCCESS (0.113s)
[157.167194] Starting dynamic subtest: bcs0
[157.268714] Dynamic subtest bcs0: SUCCESS (0.122s)
[157.290253] Starting dynamic subtest: vcs0
[157.378659] Dynamic subtest vcs0: SUCCESS (0.106s)
[157.398745] Starting dynamic subtest: vecs0
[157.486134] Dynamic subtest vecs0: SUCCESS (0.104s)
[157.487663] Subtest out-order: SUCCESS (0.450s)
[157.916959] [017/133] (906s left) gem_exec_fence (parallel)
[158.155923] Starting subtest: parallel
[158.157132] Starting dynamic subtest: rcs0
[160.335758] Dynamic subtest rcs0: SUCCESS (2.157s)
[160.355344] Starting dynamic subtest: bcs0
[162.501210] Dynamic subtest bcs0: SUCCESS (2.149s)
[162.522001] Starting dynamic subtest: vcs0
[164.671814] Dynamic subtest vcs0: SUCCESS (2.155s)
[164.692142] Starting dynamic subtest: vecs0
[166.831569] Dynamic subtest vecs0: SUCCESS (2.149s)
[166.832612] Subtest parallel: SUCCESS (8.613s)
[167.595009] [FACT gem_exec_fence (parallel)] new: kernel.kmod_is_loaded.vgem: true
[167.598708] [018/133] (897s left) sysfs_heartbeat_interval (idempotent)
[167.698322] Starting subtest: idempotent
[167.699332] Starting dynamic subtest: vecs0
[167.702958] Dynamic subtest vecs0: SUCCESS (0.004s)
[167.723462] Starting dynamic subtest: vcs0
[167.724402] Dynamic subtest vcs0: SUCCESS (0.003s)
[167.744420] Starting dynamic subtest: bcs0
[167.745499] Dynamic subtest bcs0: SUCCESS (0.003s)
[167.772444] Starting dynamic subtest: rcs0
[167.773845] Dynamic subtest rcs0: SUCCESS (0.002s)
[167.775245] Subtest idempotent: SUCCESS (0.017s)
[167.907155] [019/133] (896s left) gem_caching (read-writes)
[168.010505] Starting subtest: read-writes
[187.571714] Subtest read-writes: SUCCESS (19.535s)
[187.794776] [020/133] (877s left) syncobj_wait (reset-multiple-signaled)
[187.892554] Starting subtest: reset-multiple-signaled
[187.893504] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[188.054268] [021/133] (876s left) gem_mmap_offset (partial-remap)
[188.147423] Starting subtest: partial-remap
[188.148405] Starting dynamic subtest: smem0
[188.158451] Dynamic subtest smem0: SUCCESS (0.009s)
[188.159325] Subtest partial-remap: SUCCESS (0.010s)
[188.357963] [022/133] (876s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[188.448615] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[188.551428] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[188.721487] [023/133] (876s left) gem_spin_batch (spin-each)
[188.834535] Starting subtest: spin-each
[192.121451] Subtest spin-each: SUCCESS (3.286s)
[192.447684] [024/133] (872s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[192.626472] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[192.628225] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[192.814374] [025/133] (872s left) i915_pm_rpm (debugfs-read)
[194.687810] Starting subtest: debugfs-read
[194.883256] Subtest debugfs-read: SUCCESS (0.196s)
[195.251259] [026/133] (870s left) perf (blocking-parameterized)
[195.451442] Starting subtest: blocking-parameterized
[215.532498] Subtest blocking-parameterized: SUCCESS (20.073s)
[215.630715] [027/133] (849s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[215.741876] Starting subtest: basic-batch-kernel-default-cmd
[221.508615] Subtest basic-batch-kernel-default-cmd: SUCCESS (5.762s)
[221.794217] [028/133] (843s left) syncobj_wait (invalid-reset-zero-handles)
[221.891313] Starting subtest: invalid-reset-zero-handles
[221.892252] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[222.072232] [029/133] (843s left) kms_flip (flip-vs-blocking-wf-vblank)
[222.238014] Starting subtest: flip-vs-blocking-wf-vblank
[222.239497] Starting dynamic subtest: A-HDMI-A1
[228.146262] Dynamic subtest A-HDMI-A1: SUCCESS (5.903s)
[228.167456] Starting dynamic subtest: B-HDMI-A1
[233.808777] Dynamic subtest B-HDMI-A1: SUCCESS (5.659s)
[233.831990] Starting dynamic subtest: C-HDMI-A1
[239.470659] Dynamic subtest C-HDMI-A1: SUCCESS (5.658s)
[239.493760] Starting dynamic subtest: A-HDMI-A2
[245.152227] Dynamic subtest A-HDMI-A2: SUCCESS (5.676s)
[245.176319] Starting dynamic subtest: B-HDMI-A2
[250.976860] Dynamic subtest B-HDMI-A2: SUCCESS (5.825s)
[250.999617] Starting dynamic subtest: C-HDMI-A2
[256.799062] Dynamic subtest C-HDMI-A2: SUCCESS (5.823s)
[256.800237] Subtest flip-vs-blocking-wf-vblank: SUCCESS (34.560s)
[257.261920] [030/133] (808s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[257.445620] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[257.447383] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[257.625885] [031/133] (808s left) gem_exec_balancer (bonded-semaphore)
[257.735152] Starting subtest: bonded-semaphore
[261.615564] Subtest bonded-semaphore: SUCCESS (3.882s)
[261.892183] [032/133] (804s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[262.076332] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[263.390926] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (1.314s)
[263.759990] [033/133] (802s left) kms_invalid_mode (int-max-clock)
[263.927701] Starting subtest: int-max-clock
[263.928619] Starting dynamic subtest: pipe-A-HDMI-A-1
[264.319720] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.391s)
[264.342043] Starting dynamic subtest: pipe-A-HDMI-A-2
[264.343039] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.002s)
[264.364058] Starting dynamic subtest: pipe-B-HDMI-A-1
[264.365022] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.001s)
[264.386474] Starting dynamic subtest: pipe-B-HDMI-A-2
[264.387441] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.001s)
[264.408749] Starting dynamic subtest: pipe-C-HDMI-A-1
[264.409771] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.001s)
[264.433026] Starting dynamic subtest: pipe-C-HDMI-A-2
[264.433966] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.001s)
[264.434918] Subtest int-max-clock: SUCCESS (0.417s)
[264.652798] [034/133] (801s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[264.834902] Starting subtest: 4-tiled-16bpp-rotate-0
[264.836882] Subtest 4-tiled-16bpp-rotate-0: SKIP (0.000s)
[264.986152] [035/133] (801s left) kms_addfb_basic (bad-pitch-999)
[265.077145] Starting subtest: bad-pitch-999
[265.078332] Subtest bad-pitch-999: SUCCESS (0.000s)
[265.319933] [036/133] (800s left) gen7_exec_parse (cmd-crossing-page)
[265.422576] Subtest cmd-crossing-page: SKIP (0.000s)
[265.535847] [037/133] (800s left) kms_async_flips (crc)
[265.699277] Starting subtest: crc
[266.251092] Starting dynamic subtest: pipe-A-HDMI-A-1
[268.533250] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.282s)
[268.874620] Starting dynamic subtest: pipe-A-HDMI-A-2
[271.131886] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.278s)
[271.473401] Starting dynamic subtest: pipe-B-HDMI-A-1
[273.754528] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.300s)
[274.063498] Starting dynamic subtest: pipe-B-HDMI-A-2
[276.343244] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.299s)
[276.658343] Starting dynamic subtest: pipe-C-HDMI-A-1
[278.916572] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.279s)
[279.243008] Starting dynamic subtest: pipe-C-HDMI-A-2
[281.522673] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.299s)
[281.523813] Subtest crc: SUCCESS (15.829s)
[282.098609] [038/133] (784s left) syncobj_wait (single-wait-all-for-submit-submitted)
[282.198235] Starting subtest: single-wait-all-for-submit-submitted
[282.303721] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.104s)
[282.450054] [039/133] (784s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[282.626658] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[282.628301] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[282.781322] [040/133] (783s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[282.945514] Starting subtest: primary-yf-tiled-reflect-x-0
[282.948681] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.003s)
[283.098980] [041/133] (783s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[283.275787] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[286.197090] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SUCCESS (2.920s)
[286.442918] [042/133] (780s left) kms_legacy_colorkey (basic)
[286.609419] Starting subtest: basic
[286.610269] Starting dynamic subtest: pipe-A
[286.645306] Dynamic subtest pipe-A: SUCCESS (0.037s)
[286.667578] Starting dynamic subtest: pipe-B
[286.697606] Dynamic subtest pipe-B: SUCCESS (0.050s)
[286.720072] Starting dynamic subtest: pipe-C
[286.720962] Dynamic subtest pipe-C: SUCCESS (0.001s)
[286.721897] Subtest basic: SUCCESS (0.094s)
[286.865563] [043/133] (780s left) kms_plane_alpha_blend (alpha-7efc)
[287.030392] Starting subtest: alpha-7efc
[287.299068] Starting dynamic subtest: pipe-A-HDMI-A-1
[288.220956] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.922s)
[288.288923] Starting dynamic subtest: pipe-C-HDMI-A-1
[289.111111] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.838s)
[289.114739] Subtest alpha-7efc: SUCCESS (2.080s)
[289.488784] [044/133] (777s left) gem_mmap (big-bo)
[289.583050] Starting subtest: big-bo
[290.933196] Subtest big-bo: SUCCESS (1.350s)
[291.113611] [045/133] (776s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[291.416945] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[291.420643] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[291.610060] [046/133] (775s left) gem_exec_schedule (u-submit-golden-slice)
[291.750822] Starting subtest: u-submit-golden-slice
[291.751743] Starting dynamic subtest: rcs0
[291.798528] Dynamic subtest rcs0: SUCCESS (0.048s)
[291.824929] Starting dynamic subtest: bcs0
[291.852053] Dynamic subtest bcs0: SUCCESS (0.049s)
[291.877626] Starting dynamic subtest: vcs0
[291.895609] Dynamic subtest vcs0: SUCCESS (0.043s)
[291.925624] Starting dynamic subtest: vecs0
[291.941268] Dynamic subtest vecs0: SUCCESS (0.042s)
[291.943005] Subtest u-submit-golden-slice: SUCCESS (0.193s)
[292.400709] [047/133] (774s left) gem_exec_async (forked-writes)
[292.512479] Starting subtest: forked-writes
[292.513612] Starting dynamic subtest: rcs0
[292.573657] Dynamic subtest rcs0: SUCCESS (0.060s)
[292.600617] Starting dynamic subtest: bcs0
[292.629612] Dynamic subtest bcs0: SUCCESS (0.054s)
[292.654557] Starting dynamic subtest: vcs0
[292.684803] Dynamic subtest vcs0: SUCCESS (0.052s)
[292.710507] Starting dynamic subtest: vecs0
[292.739586] Dynamic subtest vecs0: SUCCESS (0.052s)
[292.740898] Subtest forked-writes: SUCCESS (0.228s)
[293.066700] [048/133] (774s left) core_auth (many-magics)
[293.162198] Starting subtest: many-magics
[294.341938] Subtest many-magics: SUCCESS (1.181s)
[294.546491] [049/133] (772s left) kms_color (deep-color)
[294.715338] Starting subtest: deep-color
[294.717125] Subtest deep-color: SKIP (0.000s)
[294.884831] [050/133] (772s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[295.063545] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[295.065317] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[295.227738] [051/133] (772s left) gem_exec_fence (syncobj-timeline-unused-fence)
[295.882337] Starting subtest: syncobj-timeline-unused-fence
[295.886448] Subtest syncobj-timeline-unused-fence: SUCCESS (0.005s)
[296.199831] [052/133] (771s left) kms_psr (fbc-psr-cursor-plane-move)
[296.369089] Subtest fbc-psr-cursor-plane-move: SKIP (0.000s)
[296.474371] [053/133] (771s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[296.637284] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[296.638193] Starting dynamic subtest: pipe-A
[297.693064] Dynamic subtest pipe-A: SUCCESS (1.056s)
[297.716436] Starting dynamic subtest: pipe-B
[298.756001] Dynamic subtest pipe-B: SUCCESS (1.060s)
[298.779562] Starting dynamic subtest: pipe-C
[299.820129] Dynamic subtest pipe-C: SUCCESS (1.062s)
[299.824440] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.186s)
[300.334243] [054/133] (767s left) kms_flip (2x-flip-vs-suspend)
[300.503585] Starting subtest: 2x-flip-vs-suspend
[300.507204] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2