commit 2c65b0dd1a45b3d2e2125a5c9ce9fc9a88e2d4b5
author ramindani <ramindani@google.com> Mon Oct 30 10:37:31 2023 -0700
committer ramindani <ramindani@google.com> Thu Nov 02 10:06:47 2023 -0700
tree 644d599068f3ecde0024f0b016c25e5e651684d4
parent d4354a9df28c38136f4bfdf75ea10067e3340d8b

[SF] Adds ATRACE for VsyncPredictor
Adds ATRACE with ExpectedPresentTime for the
present

BUG: 284845445
Test: Verified with a trace
Change-Id: I88f8ed8a398e6cb6e93ccad2df7d98d2db448719

services/surfaceflinger/Scheduler/VSyncPredictor.cpp

diff --git a/services/surfaceflinger/Scheduler/VSyncPredictor.cpp b/services/surfaceflinger/Scheduler/VSyncPredictor.cpp
index 799466b..57aa010 100644
--- a/services/surfaceflinger/Scheduler/VSyncPredictor.cpp
+++ b/services/surfaceflinger/Scheduler/VSyncPredictor.cpp
@@ -294,8 +294,10 @@
     if (renderRatePhase == 0) {
         const auto vsyncTime = mLastVsyncSequence->vsyncTime;
         if (FlagManager::getInstance().vrr_config() && mDisplayModeDataOpt) {
-            mVsyncTrackerCallback.onVsyncGenerated(mId, TimePoint::fromNs(vsyncTime),
-                                                   *mDisplayModeDataOpt,
+            const auto vsyncTimePoint = TimePoint::fromNs(vsyncTime);
+            ATRACE_FORMAT("%s InPhase vsyncIn %.2fms", __func__,
+                          ticks<std::milli, float>(vsyncTimePoint - TimePoint::now()));
+            mVsyncTrackerCallback.onVsyncGenerated(mId, vsyncTimePoint, *mDisplayModeDataOpt,
                                                    Period::fromNs(mIdealPeriod));
         }
         return vsyncTime;
@@ -306,7 +308,10 @@
     const auto nextAnticipatedVsyncTime =
             nextAnticipatedVSyncTimeFromLocked(approximateNextVsync - slope / 2);
     if (FlagManager::getInstance().vrr_config() && mDisplayModeDataOpt) {
-        mVsyncTrackerCallback.onVsyncGenerated(mId, TimePoint::fromNs(nextAnticipatedVsyncTime),
+        const auto nextAnticipatedVsyncTimePoint = TimePoint::fromNs(nextAnticipatedVsyncTime);
+        ATRACE_FORMAT("%s outOfPhase vsyncIn %.2fms", __func__,
+                      ticks<std::milli, float>(nextAnticipatedVsyncTimePoint - TimePoint::now()));
+        mVsyncTrackerCallback.onVsyncGenerated(mId, nextAnticipatedVsyncTimePoint,
                                                *mDisplayModeDataOpt, Period::fromNs(mIdealPeriod));
     }
     return nextAnticipatedVsyncTime;