Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 1 | /* |
| 2 | * Copyright 2018 The Android Open Source Project |
| 3 | * |
| 4 | * Licensed under the Apache License, Version 2.0 (the "License"); |
| 5 | * you may not use this file except in compliance with the License. |
| 6 | * You may obtain a copy of the License at |
| 7 | * |
| 8 | * http://www.apache.org/licenses/LICENSE-2.0 |
| 9 | * |
| 10 | * Unless required by applicable law or agreed to in writing, software |
| 11 | * distributed under the License is distributed on an "AS IS" BASIS, |
| 12 | * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. |
| 13 | * See the License for the specific language governing permissions and |
| 14 | * limitations under the License. |
| 15 | */ |
Ady Abraham | b0dbdaa | 2020-01-06 16:19:42 -0800 | [diff] [blame] | 16 | |
| 17 | // TODO(b/129481165): remove the #pragma below and fix conversion issues |
| 18 | #pragma clang diagnostic push |
| 19 | #pragma clang diagnostic ignored "-Wconversion" |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 20 | #undef LOG_TAG |
| 21 | #define LOG_TAG "TimeStats" |
| 22 | #define ATRACE_TAG ATRACE_TAG_GRAPHICS |
| 23 | |
| 24 | #include "TimeStats.h" |
| 25 | |
| 26 | #include <android-base/stringprintf.h> |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 27 | #include <log/log.h> |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 28 | #include <utils/String8.h> |
Yiwei Zhang | 3a226d2 | 2018-10-16 09:23:03 -0700 | [diff] [blame] | 29 | #include <utils/Timers.h> |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 30 | #include <utils/Trace.h> |
| 31 | |
| 32 | #include <algorithm> |
Alec Mouri | 9519bf1 | 2019-11-15 16:54:44 -0800 | [diff] [blame] | 33 | #include <chrono> |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 34 | |
| 35 | namespace android { |
| 36 | |
Alec Mouri | fb571ea | 2019-01-24 18:42:10 -0800 | [diff] [blame] | 37 | namespace impl { |
| 38 | |
Alec Mouri | 8e2f31b | 2020-01-16 22:04:35 +0000 | [diff] [blame] | 39 | status_pull_atom_return_t TimeStats::pullGlobalAtomCallback(int32_t atom_tag, |
| 40 | pulled_stats_event_list* data, |
| 41 | void* cookie) { |
| 42 | impl::TimeStats* timeStats = reinterpret_cast<impl::TimeStats*>(cookie); |
| 43 | if (atom_tag != android::util::SURFACEFLINGER_STATS_GLOBAL_INFO) { |
| 44 | return STATS_PULL_SKIP; |
| 45 | } |
| 46 | |
| 47 | std::lock_guard<std::mutex> lock(timeStats->mMutex); |
| 48 | |
| 49 | const auto& stats = timeStats->mTimeStats; |
| 50 | if (stats.statsStart == 0) { |
| 51 | return STATS_PULL_SKIP; |
| 52 | } |
| 53 | timeStats->flushPowerTimeLocked(); |
| 54 | |
| 55 | struct stats_event* event = timeStats->mStatsDelegate->addStatsEventToPullData(data); |
| 56 | timeStats->mStatsDelegate->statsEventSetAtomId(event, |
| 57 | android::util::SURFACEFLINGER_STATS_GLOBAL_INFO); |
| 58 | timeStats->mStatsDelegate->statsEventWriteInt64(event, stats.totalFrames); |
| 59 | timeStats->mStatsDelegate->statsEventWriteInt64(event, stats.missedFrames); |
| 60 | timeStats->mStatsDelegate->statsEventWriteInt64(event, stats.clientCompositionFrames); |
| 61 | timeStats->mStatsDelegate->statsEventWriteInt64(event, stats.displayOnTime); |
| 62 | timeStats->mStatsDelegate->statsEventWriteInt64(event, stats.presentToPresent.totalTime()); |
| 63 | timeStats->mStatsDelegate->statsEventBuild(event); |
| 64 | timeStats->clearGlobalLocked(); |
| 65 | |
| 66 | return STATS_PULL_SUCCESS; |
| 67 | } |
| 68 | |
| 69 | TimeStats::TimeStats() : TimeStats(nullptr) {} |
| 70 | |
| 71 | TimeStats::TimeStats(std::unique_ptr<StatsEventDelegate> statsDelegate) { |
| 72 | if (statsDelegate != nullptr) { |
| 73 | mStatsDelegate = std::move(statsDelegate); |
| 74 | } |
| 75 | } |
| 76 | |
| 77 | void TimeStats::onBootFinished() { |
Alec Mouri | b3885ad | 2019-09-06 17:08:55 -0700 | [diff] [blame] | 78 | // Temporarily enable TimeStats by default. Telemetry is disabled while |
| 79 | // we move onto statsd, so TimeStats is currently not exercised at all |
Alec Mouri | 8e2f31b | 2020-01-16 22:04:35 +0000 | [diff] [blame] | 80 | // during testing without enabling by default. |
| 81 | // TODO: remove this, as we should only be paying this overhead on devices |
| 82 | // where statsd exists. |
Alec Mouri | b3885ad | 2019-09-06 17:08:55 -0700 | [diff] [blame] | 83 | enable(); |
| 84 | } |
| 85 | |
Dominik Laskowski | c286714 | 2019-01-21 11:33:38 -0800 | [diff] [blame] | 86 | void TimeStats::parseArgs(bool asProto, const Vector<String16>& args, std::string& result) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 87 | ATRACE_CALL(); |
| 88 | |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 89 | std::unordered_map<std::string, int32_t> argsMap; |
Dominik Laskowski | c286714 | 2019-01-21 11:33:38 -0800 | [diff] [blame] | 90 | for (size_t index = 0; index < args.size(); ++index) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 91 | argsMap[std::string(String8(args[index]).c_str())] = index; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 92 | } |
| 93 | |
| 94 | if (argsMap.count("-disable")) { |
| 95 | disable(); |
| 96 | } |
| 97 | |
| 98 | if (argsMap.count("-dump")) { |
Yiwei Zhang | 8a4015c | 2018-05-08 16:03:47 -0700 | [diff] [blame] | 99 | std::optional<uint32_t> maxLayers = std::nullopt; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 100 | auto iter = argsMap.find("-maxlayers"); |
| 101 | if (iter != argsMap.end() && iter->second + 1 < static_cast<int32_t>(args.size())) { |
Yiwei Zhang | 8a4015c | 2018-05-08 16:03:47 -0700 | [diff] [blame] | 102 | int64_t value = strtol(String8(args[iter->second + 1]).c_str(), nullptr, 10); |
| 103 | value = std::clamp(value, int64_t(0), int64_t(UINT32_MAX)); |
| 104 | maxLayers = static_cast<uint32_t>(value); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 105 | } |
| 106 | |
Yiwei Zhang | 8a4015c | 2018-05-08 16:03:47 -0700 | [diff] [blame] | 107 | dump(asProto, maxLayers, result); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 108 | } |
| 109 | |
| 110 | if (argsMap.count("-clear")) { |
Alec Mouri | 8e2f31b | 2020-01-16 22:04:35 +0000 | [diff] [blame] | 111 | clearAll(); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 112 | } |
| 113 | |
| 114 | if (argsMap.count("-enable")) { |
| 115 | enable(); |
| 116 | } |
| 117 | } |
| 118 | |
Yiwei Zhang | 7eb58b7 | 2019-04-22 19:00:02 -0700 | [diff] [blame] | 119 | std::string TimeStats::miniDump() { |
| 120 | ATRACE_CALL(); |
| 121 | |
| 122 | std::string result = "TimeStats miniDump:\n"; |
| 123 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | e926ab5 | 2019-08-14 15:16:00 -0700 | [diff] [blame] | 124 | android::base::StringAppendF(&result, "Number of layers currently being tracked is %zu\n", |
Yiwei Zhang | 7eb58b7 | 2019-04-22 19:00:02 -0700 | [diff] [blame] | 125 | mTimeStatsTracker.size()); |
Yiwei Zhang | e926ab5 | 2019-08-14 15:16:00 -0700 | [diff] [blame] | 126 | android::base::StringAppendF(&result, "Number of layers in the stats pool is %zu\n", |
| 127 | mTimeStats.stats.size()); |
Yiwei Zhang | 7eb58b7 | 2019-04-22 19:00:02 -0700 | [diff] [blame] | 128 | return result; |
| 129 | } |
| 130 | |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 131 | void TimeStats::incrementTotalFrames() { |
| 132 | if (!mEnabled.load()) return; |
| 133 | |
| 134 | ATRACE_CALL(); |
| 135 | |
| 136 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 137 | mTimeStats.totalFrames++; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 138 | } |
| 139 | |
Yiwei Zhang | 621f9d4 | 2018-05-07 10:40:55 -0700 | [diff] [blame] | 140 | void TimeStats::incrementMissedFrames() { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 141 | if (!mEnabled.load()) return; |
| 142 | |
| 143 | ATRACE_CALL(); |
| 144 | |
| 145 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 146 | mTimeStats.missedFrames++; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 147 | } |
| 148 | |
| 149 | void TimeStats::incrementClientCompositionFrames() { |
| 150 | if (!mEnabled.load()) return; |
| 151 | |
| 152 | ATRACE_CALL(); |
| 153 | |
| 154 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 155 | mTimeStats.clientCompositionFrames++; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 156 | } |
| 157 | |
Vishnu Nair | 9b079a2 | 2020-01-21 14:36:08 -0800 | [diff] [blame^] | 158 | void TimeStats::incrementClientCompositionReusedFrames() { |
| 159 | if (!mEnabled.load()) return; |
| 160 | |
| 161 | ATRACE_CALL(); |
| 162 | |
| 163 | std::lock_guard<std::mutex> lock(mMutex); |
| 164 | mTimeStats.clientCompositionReusedFrames++; |
| 165 | } |
| 166 | |
Alec Mouri | 9519bf1 | 2019-11-15 16:54:44 -0800 | [diff] [blame] | 167 | static int32_t msBetween(nsecs_t start, nsecs_t end) { |
| 168 | int64_t delta = std::chrono::duration_cast<std::chrono::milliseconds>( |
| 169 | std::chrono::nanoseconds(end - start)) |
| 170 | .count(); |
| 171 | delta = std::clamp(delta, int64_t(INT32_MIN), int64_t(INT32_MAX)); |
| 172 | return static_cast<int32_t>(delta); |
| 173 | } |
| 174 | |
| 175 | void TimeStats::recordFrameDuration(nsecs_t startTime, nsecs_t endTime) { |
| 176 | if (!mEnabled.load()) return; |
| 177 | |
| 178 | std::lock_guard<std::mutex> lock(mMutex); |
| 179 | if (mPowerTime.powerMode == HWC_POWER_MODE_NORMAL) { |
| 180 | mTimeStats.frameDuration.insert(msBetween(startTime, endTime)); |
| 181 | } |
| 182 | } |
| 183 | |
Alec Mouri | e4034bb | 2019-11-19 12:45:54 -0800 | [diff] [blame] | 184 | void TimeStats::recordRenderEngineDuration(nsecs_t startTime, nsecs_t endTime) { |
| 185 | if (!mEnabled.load()) return; |
| 186 | |
| 187 | std::lock_guard<std::mutex> lock(mMutex); |
| 188 | if (mGlobalRecord.renderEngineDurations.size() == MAX_NUM_TIME_RECORDS) { |
| 189 | ALOGE("RenderEngineTimes are already at its maximum size[%zu]", MAX_NUM_TIME_RECORDS); |
| 190 | mGlobalRecord.renderEngineDurations.pop_front(); |
| 191 | } |
| 192 | mGlobalRecord.renderEngineDurations.push_back({startTime, endTime}); |
| 193 | } |
| 194 | |
| 195 | void TimeStats::recordRenderEngineDuration(nsecs_t startTime, |
| 196 | const std::shared_ptr<FenceTime>& endTime) { |
| 197 | if (!mEnabled.load()) return; |
| 198 | |
| 199 | std::lock_guard<std::mutex> lock(mMutex); |
| 200 | if (mGlobalRecord.renderEngineDurations.size() == MAX_NUM_TIME_RECORDS) { |
| 201 | ALOGE("RenderEngineTimes are already at its maximum size[%zu]", MAX_NUM_TIME_RECORDS); |
| 202 | mGlobalRecord.renderEngineDurations.pop_front(); |
| 203 | } |
| 204 | mGlobalRecord.renderEngineDurations.push_back({startTime, endTime}); |
| 205 | } |
| 206 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 207 | bool TimeStats::recordReadyLocked(int32_t layerId, TimeRecord* timeRecord) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 208 | if (!timeRecord->ready) { |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 209 | ALOGV("[%d]-[%" PRIu64 "]-presentFence is still not received", layerId, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 210 | timeRecord->frameTime.frameNumber); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 211 | return false; |
| 212 | } |
| 213 | |
| 214 | if (timeRecord->acquireFence != nullptr) { |
| 215 | if (timeRecord->acquireFence->getSignalTime() == Fence::SIGNAL_TIME_PENDING) { |
| 216 | return false; |
| 217 | } |
| 218 | if (timeRecord->acquireFence->getSignalTime() != Fence::SIGNAL_TIME_INVALID) { |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 219 | timeRecord->frameTime.acquireTime = timeRecord->acquireFence->getSignalTime(); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 220 | timeRecord->acquireFence = nullptr; |
| 221 | } else { |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 222 | ALOGV("[%d]-[%" PRIu64 "]-acquireFence signal time is invalid", layerId, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 223 | timeRecord->frameTime.frameNumber); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 224 | } |
| 225 | } |
| 226 | |
| 227 | if (timeRecord->presentFence != nullptr) { |
| 228 | if (timeRecord->presentFence->getSignalTime() == Fence::SIGNAL_TIME_PENDING) { |
| 229 | return false; |
| 230 | } |
| 231 | if (timeRecord->presentFence->getSignalTime() != Fence::SIGNAL_TIME_INVALID) { |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 232 | timeRecord->frameTime.presentTime = timeRecord->presentFence->getSignalTime(); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 233 | timeRecord->presentFence = nullptr; |
| 234 | } else { |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 235 | ALOGV("[%d]-[%" PRIu64 "]-presentFence signal time invalid", layerId, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 236 | timeRecord->frameTime.frameNumber); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 237 | } |
| 238 | } |
| 239 | |
| 240 | return true; |
| 241 | } |
| 242 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 243 | void TimeStats::flushAvailableRecordsToStatsLocked(int32_t layerId) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 244 | ATRACE_CALL(); |
| 245 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 246 | LayerRecord& layerRecord = mTimeStatsTracker[layerId]; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 247 | TimeRecord& prevTimeRecord = layerRecord.prevTimeRecord; |
Yiwei Zhang | c5f2c45 | 2018-05-08 16:31:56 -0700 | [diff] [blame] | 248 | std::deque<TimeRecord>& timeRecords = layerRecord.timeRecords; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 249 | while (!timeRecords.empty()) { |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 250 | if (!recordReadyLocked(layerId, &timeRecords[0])) break; |
| 251 | ALOGV("[%d]-[%" PRIu64 "]-presentFenceTime[%" PRId64 "]", layerId, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 252 | timeRecords[0].frameTime.frameNumber, timeRecords[0].frameTime.presentTime); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 253 | |
| 254 | if (prevTimeRecord.ready) { |
Yiwei Zhang | eafa5cc | 2019-07-26 15:06:25 -0700 | [diff] [blame] | 255 | const std::string& layerName = layerRecord.layerName; |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 256 | if (!mTimeStats.stats.count(layerName)) { |
| 257 | mTimeStats.stats[layerName].layerName = layerName; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 258 | } |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 259 | TimeStatsHelper::TimeStatsLayer& timeStatsLayer = mTimeStats.stats[layerName]; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 260 | timeStatsLayer.totalFrames++; |
Yiwei Zhang | eaeea06 | 2018-06-28 14:46:51 -0700 | [diff] [blame] | 261 | timeStatsLayer.droppedFrames += layerRecord.droppedFrames; |
| 262 | layerRecord.droppedFrames = 0; |
| 263 | |
| 264 | const int32_t postToAcquireMs = msBetween(timeRecords[0].frameTime.postTime, |
| 265 | timeRecords[0].frameTime.acquireTime); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 266 | ALOGV("[%d]-[%" PRIu64 "]-post2acquire[%d]", layerId, |
Yiwei Zhang | eaeea06 | 2018-06-28 14:46:51 -0700 | [diff] [blame] | 267 | timeRecords[0].frameTime.frameNumber, postToAcquireMs); |
| 268 | timeStatsLayer.deltas["post2acquire"].insert(postToAcquireMs); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 269 | |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 270 | const int32_t postToPresentMs = msBetween(timeRecords[0].frameTime.postTime, |
| 271 | timeRecords[0].frameTime.presentTime); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 272 | ALOGV("[%d]-[%" PRIu64 "]-post2present[%d]", layerId, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 273 | timeRecords[0].frameTime.frameNumber, postToPresentMs); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 274 | timeStatsLayer.deltas["post2present"].insert(postToPresentMs); |
| 275 | |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 276 | const int32_t acquireToPresentMs = msBetween(timeRecords[0].frameTime.acquireTime, |
| 277 | timeRecords[0].frameTime.presentTime); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 278 | ALOGV("[%d]-[%" PRIu64 "]-acquire2present[%d]", layerId, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 279 | timeRecords[0].frameTime.frameNumber, acquireToPresentMs); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 280 | timeStatsLayer.deltas["acquire2present"].insert(acquireToPresentMs); |
| 281 | |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 282 | const int32_t latchToPresentMs = msBetween(timeRecords[0].frameTime.latchTime, |
| 283 | timeRecords[0].frameTime.presentTime); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 284 | ALOGV("[%d]-[%" PRIu64 "]-latch2present[%d]", layerId, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 285 | timeRecords[0].frameTime.frameNumber, latchToPresentMs); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 286 | timeStatsLayer.deltas["latch2present"].insert(latchToPresentMs); |
| 287 | |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 288 | const int32_t desiredToPresentMs = msBetween(timeRecords[0].frameTime.desiredTime, |
| 289 | timeRecords[0].frameTime.presentTime); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 290 | ALOGV("[%d]-[%" PRIu64 "]-desired2present[%d]", layerId, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 291 | timeRecords[0].frameTime.frameNumber, desiredToPresentMs); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 292 | timeStatsLayer.deltas["desired2present"].insert(desiredToPresentMs); |
| 293 | |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 294 | const int32_t presentToPresentMs = msBetween(prevTimeRecord.frameTime.presentTime, |
| 295 | timeRecords[0].frameTime.presentTime); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 296 | ALOGV("[%d]-[%" PRIu64 "]-present2present[%d]", layerId, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 297 | timeRecords[0].frameTime.frameNumber, presentToPresentMs); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 298 | timeStatsLayer.deltas["present2present"].insert(presentToPresentMs); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 299 | } |
| 300 | prevTimeRecord = timeRecords[0]; |
Yiwei Zhang | c5f2c45 | 2018-05-08 16:31:56 -0700 | [diff] [blame] | 301 | timeRecords.pop_front(); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 302 | layerRecord.waitData--; |
| 303 | } |
| 304 | } |
| 305 | |
Yiwei Zhang | 8bec7e8 | 2019-10-07 18:08:26 -0700 | [diff] [blame] | 306 | static constexpr const char* kPopupWindowPrefix = "PopupWindow"; |
| 307 | static const size_t kMinLenLayerName = std::strlen(kPopupWindowPrefix); |
Yiwei Zhang | bd40832 | 2018-10-15 18:31:53 -0700 | [diff] [blame] | 308 | |
Yiwei Zhang | 8bec7e8 | 2019-10-07 18:08:26 -0700 | [diff] [blame] | 309 | // Avoid tracking the "PopupWindow:<random hash>#<number>" layers |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 310 | static bool layerNameIsValid(const std::string& layerName) { |
Yiwei Zhang | 8bec7e8 | 2019-10-07 18:08:26 -0700 | [diff] [blame] | 311 | return layerName.length() >= kMinLenLayerName && |
| 312 | layerName.compare(0, kMinLenLayerName, kPopupWindowPrefix) != 0; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 313 | } |
| 314 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 315 | void TimeStats::setPostTime(int32_t layerId, uint64_t frameNumber, const std::string& layerName, |
Yiwei Zhang | 8e8fe52 | 2018-11-02 18:34:07 -0700 | [diff] [blame] | 316 | nsecs_t postTime) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 317 | if (!mEnabled.load()) return; |
| 318 | |
| 319 | ATRACE_CALL(); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 320 | ALOGV("[%d]-[%" PRIu64 "]-[%s]-PostTime[%" PRId64 "]", layerId, frameNumber, layerName.c_str(), |
Yiwei Zhang | 8e8fe52 | 2018-11-02 18:34:07 -0700 | [diff] [blame] | 321 | postTime); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 322 | |
| 323 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | e926ab5 | 2019-08-14 15:16:00 -0700 | [diff] [blame] | 324 | if (!mTimeStats.stats.count(layerName) && mTimeStats.stats.size() >= MAX_NUM_LAYER_STATS) { |
| 325 | return; |
| 326 | } |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 327 | if (!mTimeStatsTracker.count(layerId) && mTimeStatsTracker.size() < MAX_NUM_LAYER_RECORDS && |
Yiwei Zhang | 7eb58b7 | 2019-04-22 19:00:02 -0700 | [diff] [blame] | 328 | layerNameIsValid(layerName)) { |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 329 | mTimeStatsTracker[layerId].layerName = layerName; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 330 | } |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 331 | if (!mTimeStatsTracker.count(layerId)) return; |
| 332 | LayerRecord& layerRecord = mTimeStatsTracker[layerId]; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 333 | if (layerRecord.timeRecords.size() == MAX_NUM_TIME_RECORDS) { |
Yiwei Zhang | af8ee94 | 2018-11-22 00:15:23 -0800 | [diff] [blame] | 334 | ALOGE("[%d]-[%s]-timeRecords is at its maximum size[%zu]. Ignore this when unittesting.", |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 335 | layerId, layerRecord.layerName.c_str(), MAX_NUM_TIME_RECORDS); |
| 336 | mTimeStatsTracker.erase(layerId); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 337 | return; |
| 338 | } |
| 339 | // For most media content, the acquireFence is invalid because the buffer is |
| 340 | // ready at the queueBuffer stage. In this case, acquireTime should be given |
| 341 | // a default value as postTime. |
| 342 | TimeRecord timeRecord = { |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 343 | .frameTime = |
| 344 | { |
| 345 | .frameNumber = frameNumber, |
| 346 | .postTime = postTime, |
Yiwei Zhang | af8ee94 | 2018-11-22 00:15:23 -0800 | [diff] [blame] | 347 | .latchTime = postTime, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 348 | .acquireTime = postTime, |
Yiwei Zhang | af8ee94 | 2018-11-22 00:15:23 -0800 | [diff] [blame] | 349 | .desiredTime = postTime, |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 350 | }, |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 351 | }; |
| 352 | layerRecord.timeRecords.push_back(timeRecord); |
| 353 | if (layerRecord.waitData < 0 || |
| 354 | layerRecord.waitData >= static_cast<int32_t>(layerRecord.timeRecords.size())) |
| 355 | layerRecord.waitData = layerRecord.timeRecords.size() - 1; |
| 356 | } |
| 357 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 358 | void TimeStats::setLatchTime(int32_t layerId, uint64_t frameNumber, nsecs_t latchTime) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 359 | if (!mEnabled.load()) return; |
| 360 | |
| 361 | ATRACE_CALL(); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 362 | ALOGV("[%d]-[%" PRIu64 "]-LatchTime[%" PRId64 "]", layerId, frameNumber, latchTime); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 363 | |
| 364 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 365 | if (!mTimeStatsTracker.count(layerId)) return; |
| 366 | LayerRecord& layerRecord = mTimeStatsTracker[layerId]; |
Yiwei Zhang | cb7dd00 | 2019-04-16 11:03:01 -0700 | [diff] [blame] | 367 | if (layerRecord.waitData < 0 || |
| 368 | layerRecord.waitData >= static_cast<int32_t>(layerRecord.timeRecords.size())) |
| 369 | return; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 370 | TimeRecord& timeRecord = layerRecord.timeRecords[layerRecord.waitData]; |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 371 | if (timeRecord.frameTime.frameNumber == frameNumber) { |
| 372 | timeRecord.frameTime.latchTime = latchTime; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 373 | } |
| 374 | } |
| 375 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 376 | void TimeStats::setDesiredTime(int32_t layerId, uint64_t frameNumber, nsecs_t desiredTime) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 377 | if (!mEnabled.load()) return; |
| 378 | |
| 379 | ATRACE_CALL(); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 380 | ALOGV("[%d]-[%" PRIu64 "]-DesiredTime[%" PRId64 "]", layerId, frameNumber, desiredTime); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 381 | |
| 382 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 383 | if (!mTimeStatsTracker.count(layerId)) return; |
| 384 | LayerRecord& layerRecord = mTimeStatsTracker[layerId]; |
Yiwei Zhang | cb7dd00 | 2019-04-16 11:03:01 -0700 | [diff] [blame] | 385 | if (layerRecord.waitData < 0 || |
| 386 | layerRecord.waitData >= static_cast<int32_t>(layerRecord.timeRecords.size())) |
| 387 | return; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 388 | TimeRecord& timeRecord = layerRecord.timeRecords[layerRecord.waitData]; |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 389 | if (timeRecord.frameTime.frameNumber == frameNumber) { |
| 390 | timeRecord.frameTime.desiredTime = desiredTime; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 391 | } |
| 392 | } |
| 393 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 394 | void TimeStats::setAcquireTime(int32_t layerId, uint64_t frameNumber, nsecs_t acquireTime) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 395 | if (!mEnabled.load()) return; |
| 396 | |
| 397 | ATRACE_CALL(); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 398 | ALOGV("[%d]-[%" PRIu64 "]-AcquireTime[%" PRId64 "]", layerId, frameNumber, acquireTime); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 399 | |
| 400 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 401 | if (!mTimeStatsTracker.count(layerId)) return; |
| 402 | LayerRecord& layerRecord = mTimeStatsTracker[layerId]; |
Yiwei Zhang | cb7dd00 | 2019-04-16 11:03:01 -0700 | [diff] [blame] | 403 | if (layerRecord.waitData < 0 || |
| 404 | layerRecord.waitData >= static_cast<int32_t>(layerRecord.timeRecords.size())) |
| 405 | return; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 406 | TimeRecord& timeRecord = layerRecord.timeRecords[layerRecord.waitData]; |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 407 | if (timeRecord.frameTime.frameNumber == frameNumber) { |
| 408 | timeRecord.frameTime.acquireTime = acquireTime; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 409 | } |
| 410 | } |
| 411 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 412 | void TimeStats::setAcquireFence(int32_t layerId, uint64_t frameNumber, |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 413 | const std::shared_ptr<FenceTime>& acquireFence) { |
| 414 | if (!mEnabled.load()) return; |
| 415 | |
| 416 | ATRACE_CALL(); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 417 | ALOGV("[%d]-[%" PRIu64 "]-AcquireFenceTime[%" PRId64 "]", layerId, frameNumber, |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 418 | acquireFence->getSignalTime()); |
| 419 | |
| 420 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 421 | if (!mTimeStatsTracker.count(layerId)) return; |
| 422 | LayerRecord& layerRecord = mTimeStatsTracker[layerId]; |
Yiwei Zhang | cb7dd00 | 2019-04-16 11:03:01 -0700 | [diff] [blame] | 423 | if (layerRecord.waitData < 0 || |
| 424 | layerRecord.waitData >= static_cast<int32_t>(layerRecord.timeRecords.size())) |
| 425 | return; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 426 | TimeRecord& timeRecord = layerRecord.timeRecords[layerRecord.waitData]; |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 427 | if (timeRecord.frameTime.frameNumber == frameNumber) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 428 | timeRecord.acquireFence = acquireFence; |
| 429 | } |
| 430 | } |
| 431 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 432 | void TimeStats::setPresentTime(int32_t layerId, uint64_t frameNumber, nsecs_t presentTime) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 433 | if (!mEnabled.load()) return; |
| 434 | |
| 435 | ATRACE_CALL(); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 436 | ALOGV("[%d]-[%" PRIu64 "]-PresentTime[%" PRId64 "]", layerId, frameNumber, presentTime); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 437 | |
| 438 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 439 | if (!mTimeStatsTracker.count(layerId)) return; |
| 440 | LayerRecord& layerRecord = mTimeStatsTracker[layerId]; |
Yiwei Zhang | cb7dd00 | 2019-04-16 11:03:01 -0700 | [diff] [blame] | 441 | if (layerRecord.waitData < 0 || |
| 442 | layerRecord.waitData >= static_cast<int32_t>(layerRecord.timeRecords.size())) |
| 443 | return; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 444 | TimeRecord& timeRecord = layerRecord.timeRecords[layerRecord.waitData]; |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 445 | if (timeRecord.frameTime.frameNumber == frameNumber) { |
| 446 | timeRecord.frameTime.presentTime = presentTime; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 447 | timeRecord.ready = true; |
| 448 | layerRecord.waitData++; |
| 449 | } |
| 450 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 451 | flushAvailableRecordsToStatsLocked(layerId); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 452 | } |
| 453 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 454 | void TimeStats::setPresentFence(int32_t layerId, uint64_t frameNumber, |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 455 | const std::shared_ptr<FenceTime>& presentFence) { |
| 456 | if (!mEnabled.load()) return; |
| 457 | |
| 458 | ATRACE_CALL(); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 459 | ALOGV("[%d]-[%" PRIu64 "]-PresentFenceTime[%" PRId64 "]", layerId, frameNumber, |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 460 | presentFence->getSignalTime()); |
| 461 | |
| 462 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 463 | if (!mTimeStatsTracker.count(layerId)) return; |
| 464 | LayerRecord& layerRecord = mTimeStatsTracker[layerId]; |
Yiwei Zhang | cb7dd00 | 2019-04-16 11:03:01 -0700 | [diff] [blame] | 465 | if (layerRecord.waitData < 0 || |
| 466 | layerRecord.waitData >= static_cast<int32_t>(layerRecord.timeRecords.size())) |
| 467 | return; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 468 | TimeRecord& timeRecord = layerRecord.timeRecords[layerRecord.waitData]; |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 469 | if (timeRecord.frameTime.frameNumber == frameNumber) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 470 | timeRecord.presentFence = presentFence; |
| 471 | timeRecord.ready = true; |
| 472 | layerRecord.waitData++; |
| 473 | } |
| 474 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 475 | flushAvailableRecordsToStatsLocked(layerId); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 476 | } |
| 477 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 478 | void TimeStats::onDestroy(int32_t layerId) { |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 479 | ATRACE_CALL(); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 480 | ALOGV("[%d]-onDestroy", layerId); |
Mikael Pessa | 90092f4 | 2019-08-26 17:22:04 -0700 | [diff] [blame] | 481 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 482 | mTimeStatsTracker.erase(layerId); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 483 | } |
| 484 | |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 485 | void TimeStats::removeTimeRecord(int32_t layerId, uint64_t frameNumber) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 486 | if (!mEnabled.load()) return; |
| 487 | |
| 488 | ATRACE_CALL(); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 489 | ALOGV("[%d]-[%" PRIu64 "]-removeTimeRecord", layerId, frameNumber); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 490 | |
| 491 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | 1a88c40 | 2019-11-18 10:43:58 -0800 | [diff] [blame] | 492 | if (!mTimeStatsTracker.count(layerId)) return; |
| 493 | LayerRecord& layerRecord = mTimeStatsTracker[layerId]; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 494 | size_t removeAt = 0; |
| 495 | for (const TimeRecord& record : layerRecord.timeRecords) { |
Yiwei Zhang | cf50ab9 | 2018-06-14 10:50:12 -0700 | [diff] [blame] | 496 | if (record.frameTime.frameNumber == frameNumber) break; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 497 | removeAt++; |
| 498 | } |
| 499 | if (removeAt == layerRecord.timeRecords.size()) return; |
| 500 | layerRecord.timeRecords.erase(layerRecord.timeRecords.begin() + removeAt); |
| 501 | if (layerRecord.waitData > static_cast<int32_t>(removeAt)) { |
Yiwei Zhang | eaeea06 | 2018-06-28 14:46:51 -0700 | [diff] [blame] | 502 | layerRecord.waitData--; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 503 | } |
Yiwei Zhang | eaeea06 | 2018-06-28 14:46:51 -0700 | [diff] [blame] | 504 | layerRecord.droppedFrames++; |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 505 | } |
| 506 | |
Yiwei Zhang | 3a226d2 | 2018-10-16 09:23:03 -0700 | [diff] [blame] | 507 | void TimeStats::flushPowerTimeLocked() { |
Yiwei Zhang | e5c49d5 | 2018-10-29 00:15:31 -0700 | [diff] [blame] | 508 | if (!mEnabled.load()) return; |
| 509 | |
Yiwei Zhang | 3a226d2 | 2018-10-16 09:23:03 -0700 | [diff] [blame] | 510 | nsecs_t curTime = systemTime(); |
| 511 | // elapsedTime is in milliseconds. |
| 512 | int64_t elapsedTime = (curTime - mPowerTime.prevTime) / 1000000; |
| 513 | |
| 514 | switch (mPowerTime.powerMode) { |
| 515 | case HWC_POWER_MODE_NORMAL: |
| 516 | mTimeStats.displayOnTime += elapsedTime; |
| 517 | break; |
| 518 | case HWC_POWER_MODE_OFF: |
| 519 | case HWC_POWER_MODE_DOZE: |
| 520 | case HWC_POWER_MODE_DOZE_SUSPEND: |
| 521 | default: |
| 522 | break; |
| 523 | } |
| 524 | |
| 525 | mPowerTime.prevTime = curTime; |
| 526 | } |
| 527 | |
| 528 | void TimeStats::setPowerMode(int32_t powerMode) { |
| 529 | if (!mEnabled.load()) { |
| 530 | std::lock_guard<std::mutex> lock(mMutex); |
| 531 | mPowerTime.powerMode = powerMode; |
| 532 | return; |
| 533 | } |
| 534 | |
| 535 | std::lock_guard<std::mutex> lock(mMutex); |
| 536 | if (powerMode == mPowerTime.powerMode) return; |
| 537 | |
| 538 | flushPowerTimeLocked(); |
| 539 | mPowerTime.powerMode = powerMode; |
| 540 | } |
| 541 | |
Alec Mouri | fb571ea | 2019-01-24 18:42:10 -0800 | [diff] [blame] | 542 | void TimeStats::recordRefreshRate(uint32_t fps, nsecs_t duration) { |
| 543 | std::lock_guard<std::mutex> lock(mMutex); |
| 544 | if (mTimeStats.refreshRateStats.count(fps)) { |
| 545 | mTimeStats.refreshRateStats[fps] += duration; |
| 546 | } else { |
| 547 | mTimeStats.refreshRateStats.insert({fps, duration}); |
| 548 | } |
| 549 | } |
| 550 | |
Yiwei Zhang | ce6ebc0 | 2018-10-20 12:42:38 -0700 | [diff] [blame] | 551 | void TimeStats::flushAvailableGlobalRecordsToStatsLocked() { |
| 552 | ATRACE_CALL(); |
| 553 | |
| 554 | while (!mGlobalRecord.presentFences.empty()) { |
| 555 | const nsecs_t curPresentTime = mGlobalRecord.presentFences.front()->getSignalTime(); |
| 556 | if (curPresentTime == Fence::SIGNAL_TIME_PENDING) break; |
| 557 | |
| 558 | if (curPresentTime == Fence::SIGNAL_TIME_INVALID) { |
| 559 | ALOGE("GlobalPresentFence is invalid!"); |
| 560 | mGlobalRecord.prevPresentTime = 0; |
| 561 | mGlobalRecord.presentFences.pop_front(); |
| 562 | continue; |
| 563 | } |
| 564 | |
| 565 | ALOGV("GlobalPresentFenceTime[%" PRId64 "]", |
| 566 | mGlobalRecord.presentFences.front()->getSignalTime()); |
| 567 | |
Yiwei Zhang | e5c49d5 | 2018-10-29 00:15:31 -0700 | [diff] [blame] | 568 | if (mGlobalRecord.prevPresentTime != 0) { |
| 569 | const int32_t presentToPresentMs = |
| 570 | msBetween(mGlobalRecord.prevPresentTime, curPresentTime); |
| 571 | ALOGV("Global present2present[%d] prev[%" PRId64 "] curr[%" PRId64 "]", |
| 572 | presentToPresentMs, mGlobalRecord.prevPresentTime, curPresentTime); |
| 573 | mTimeStats.presentToPresent.insert(presentToPresentMs); |
| 574 | } |
Yiwei Zhang | ce6ebc0 | 2018-10-20 12:42:38 -0700 | [diff] [blame] | 575 | |
Yiwei Zhang | ce6ebc0 | 2018-10-20 12:42:38 -0700 | [diff] [blame] | 576 | mGlobalRecord.prevPresentTime = curPresentTime; |
| 577 | mGlobalRecord.presentFences.pop_front(); |
| 578 | } |
Alec Mouri | e4034bb | 2019-11-19 12:45:54 -0800 | [diff] [blame] | 579 | while (!mGlobalRecord.renderEngineDurations.empty()) { |
| 580 | const auto duration = mGlobalRecord.renderEngineDurations.front(); |
| 581 | const auto& endTime = duration.endTime; |
| 582 | |
| 583 | nsecs_t endNs = -1; |
| 584 | |
| 585 | if (auto val = std::get_if<nsecs_t>(&endTime)) { |
| 586 | endNs = *val; |
| 587 | } else { |
| 588 | endNs = std::get<std::shared_ptr<FenceTime>>(endTime)->getSignalTime(); |
| 589 | } |
| 590 | |
| 591 | if (endNs == Fence::SIGNAL_TIME_PENDING) break; |
| 592 | |
| 593 | if (endNs < 0) { |
| 594 | ALOGE("RenderEngineTiming is invalid!"); |
| 595 | mGlobalRecord.renderEngineDurations.pop_front(); |
| 596 | continue; |
| 597 | } |
| 598 | |
| 599 | const int32_t renderEngineMs = msBetween(duration.startTime, endNs); |
| 600 | mTimeStats.renderEngineTiming.insert(renderEngineMs); |
| 601 | |
| 602 | mGlobalRecord.renderEngineDurations.pop_front(); |
| 603 | } |
Yiwei Zhang | ce6ebc0 | 2018-10-20 12:42:38 -0700 | [diff] [blame] | 604 | } |
| 605 | |
| 606 | void TimeStats::setPresentFenceGlobal(const std::shared_ptr<FenceTime>& presentFence) { |
| 607 | if (!mEnabled.load()) return; |
| 608 | |
| 609 | ATRACE_CALL(); |
| 610 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | e5c49d5 | 2018-10-29 00:15:31 -0700 | [diff] [blame] | 611 | if (presentFence == nullptr || !presentFence->isValid()) { |
| 612 | mGlobalRecord.prevPresentTime = 0; |
| 613 | return; |
| 614 | } |
| 615 | |
| 616 | if (mPowerTime.powerMode != HWC_POWER_MODE_NORMAL) { |
| 617 | // Try flushing the last present fence on HWC_POWER_MODE_NORMAL. |
| 618 | flushAvailableGlobalRecordsToStatsLocked(); |
| 619 | mGlobalRecord.presentFences.clear(); |
Yiwei Zhang | ce6ebc0 | 2018-10-20 12:42:38 -0700 | [diff] [blame] | 620 | mGlobalRecord.prevPresentTime = 0; |
| 621 | return; |
| 622 | } |
| 623 | |
| 624 | if (mGlobalRecord.presentFences.size() == MAX_NUM_TIME_RECORDS) { |
| 625 | // The front presentFence must be trapped in pending status in this |
| 626 | // case. Try dequeuing the front one to recover. |
| 627 | ALOGE("GlobalPresentFences is already at its maximum size[%zu]", MAX_NUM_TIME_RECORDS); |
| 628 | mGlobalRecord.prevPresentTime = 0; |
| 629 | mGlobalRecord.presentFences.pop_front(); |
| 630 | } |
| 631 | |
| 632 | mGlobalRecord.presentFences.emplace_back(presentFence); |
| 633 | flushAvailableGlobalRecordsToStatsLocked(); |
| 634 | } |
| 635 | |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 636 | void TimeStats::enable() { |
| 637 | if (mEnabled.load()) return; |
| 638 | |
| 639 | ATRACE_CALL(); |
| 640 | |
| 641 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 642 | mEnabled.store(true); |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 643 | mTimeStats.statsStart = static_cast<int64_t>(std::time(0)); |
Yiwei Zhang | 3a226d2 | 2018-10-16 09:23:03 -0700 | [diff] [blame] | 644 | mPowerTime.prevTime = systemTime(); |
Alec Mouri | 8e2f31b | 2020-01-16 22:04:35 +0000 | [diff] [blame] | 645 | mStatsDelegate->registerStatsPullAtomCallback(android::util::SURFACEFLINGER_STATS_GLOBAL_INFO, |
| 646 | TimeStats::pullGlobalAtomCallback, nullptr, this); |
Yiwei Zhang | e5c49d5 | 2018-10-29 00:15:31 -0700 | [diff] [blame] | 647 | ALOGD("Enabled"); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 648 | } |
| 649 | |
| 650 | void TimeStats::disable() { |
| 651 | if (!mEnabled.load()) return; |
| 652 | |
| 653 | ATRACE_CALL(); |
| 654 | |
| 655 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | e5c49d5 | 2018-10-29 00:15:31 -0700 | [diff] [blame] | 656 | flushPowerTimeLocked(); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 657 | mEnabled.store(false); |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 658 | mTimeStats.statsEnd = static_cast<int64_t>(std::time(0)); |
Alec Mouri | 8e2f31b | 2020-01-16 22:04:35 +0000 | [diff] [blame] | 659 | mStatsDelegate->unregisterStatsPullAtomCallback( |
| 660 | android::util::SURFACEFLINGER_STATS_GLOBAL_INFO); |
Yiwei Zhang | e5c49d5 | 2018-10-29 00:15:31 -0700 | [diff] [blame] | 661 | ALOGD("Disabled"); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 662 | } |
| 663 | |
Alec Mouri | 8e2f31b | 2020-01-16 22:04:35 +0000 | [diff] [blame] | 664 | void TimeStats::clearAll() { |
| 665 | std::lock_guard<std::mutex> lock(mMutex); |
| 666 | clearGlobalLocked(); |
| 667 | clearLayersLocked(); |
| 668 | } |
| 669 | |
| 670 | void TimeStats::clearGlobalLocked() { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 671 | ATRACE_CALL(); |
| 672 | |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 673 | mTimeStats.statsStart = (mEnabled.load() ? static_cast<int64_t>(std::time(0)) : 0); |
| 674 | mTimeStats.statsEnd = 0; |
| 675 | mTimeStats.totalFrames = 0; |
| 676 | mTimeStats.missedFrames = 0; |
| 677 | mTimeStats.clientCompositionFrames = 0; |
Vishnu Nair | 9b079a2 | 2020-01-21 14:36:08 -0800 | [diff] [blame^] | 678 | mTimeStats.clientCompositionReusedFrames = 0; |
Yiwei Zhang | 3a226d2 | 2018-10-16 09:23:03 -0700 | [diff] [blame] | 679 | mTimeStats.displayOnTime = 0; |
Yiwei Zhang | ce6ebc0 | 2018-10-20 12:42:38 -0700 | [diff] [blame] | 680 | mTimeStats.presentToPresent.hist.clear(); |
Alec Mouri | 31ac64a | 2020-01-09 09:26:22 -0800 | [diff] [blame] | 681 | mTimeStats.frameDuration.hist.clear(); |
| 682 | mTimeStats.renderEngineTiming.hist.clear(); |
Alec Mouri | fb571ea | 2019-01-24 18:42:10 -0800 | [diff] [blame] | 683 | mTimeStats.refreshRateStats.clear(); |
Yiwei Zhang | 3a226d2 | 2018-10-16 09:23:03 -0700 | [diff] [blame] | 684 | mPowerTime.prevTime = systemTime(); |
Yiwei Zhang | e5c49d5 | 2018-10-29 00:15:31 -0700 | [diff] [blame] | 685 | mGlobalRecord.prevPresentTime = 0; |
| 686 | mGlobalRecord.presentFences.clear(); |
Alec Mouri | 8e2f31b | 2020-01-16 22:04:35 +0000 | [diff] [blame] | 687 | ALOGD("Cleared global stats"); |
| 688 | } |
| 689 | |
| 690 | void TimeStats::clearLayersLocked() { |
| 691 | ATRACE_CALL(); |
| 692 | |
| 693 | mTimeStatsTracker.clear(); |
| 694 | mTimeStats.stats.clear(); |
| 695 | ALOGD("Cleared layer stats"); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 696 | } |
| 697 | |
| 698 | bool TimeStats::isEnabled() { |
| 699 | return mEnabled.load(); |
| 700 | } |
| 701 | |
Yiwei Zhang | 5434a78 | 2018-12-05 18:06:32 -0800 | [diff] [blame] | 702 | void TimeStats::dump(bool asProto, std::optional<uint32_t> maxLayers, std::string& result) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 703 | ATRACE_CALL(); |
| 704 | |
| 705 | std::lock_guard<std::mutex> lock(mMutex); |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 706 | if (mTimeStats.statsStart == 0) { |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 707 | return; |
| 708 | } |
| 709 | |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 710 | mTimeStats.statsEnd = static_cast<int64_t>(std::time(0)); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 711 | |
Yiwei Zhang | 3a226d2 | 2018-10-16 09:23:03 -0700 | [diff] [blame] | 712 | flushPowerTimeLocked(); |
| 713 | |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 714 | if (asProto) { |
Yiwei Zhang | 8a4015c | 2018-05-08 16:03:47 -0700 | [diff] [blame] | 715 | ALOGD("Dumping TimeStats as proto"); |
Yiwei Zhang | dc22404 | 2018-10-18 15:34:00 -0700 | [diff] [blame] | 716 | SFTimeStatsGlobalProto timeStatsProto = mTimeStats.toProto(maxLayers); |
Dominik Laskowski | 4647011 | 2019-08-02 13:13:11 -0700 | [diff] [blame] | 717 | result.append(timeStatsProto.SerializeAsString()); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 718 | } else { |
Yiwei Zhang | 8a4015c | 2018-05-08 16:03:47 -0700 | [diff] [blame] | 719 | ALOGD("Dumping TimeStats as text"); |
Yiwei Zhang | 5434a78 | 2018-12-05 18:06:32 -0800 | [diff] [blame] | 720 | result.append(mTimeStats.toString(maxLayers)); |
Yiwei Zhang | 8a4015c | 2018-05-08 16:03:47 -0700 | [diff] [blame] | 721 | result.append("\n"); |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 722 | } |
| 723 | } |
| 724 | |
Alec Mouri | fb571ea | 2019-01-24 18:42:10 -0800 | [diff] [blame] | 725 | } // namespace impl |
| 726 | |
Yiwei Zhang | 0102ad2 | 2018-05-02 17:37:17 -0700 | [diff] [blame] | 727 | } // namespace android |
Ady Abraham | b0dbdaa | 2020-01-06 16:19:42 -0800 | [diff] [blame] | 728 | |
| 729 | // TODO(b/129481165): remove the #pragma below and fix conversion issues |
| 730 | #pragma clang diagnostic pop // ignored "-Wconversion" |