Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 1 | /* |
| 2 | * Copyright (C) 2014 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 | */ |
| 16 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 17 | #include "LogStatistics.h" |
| 18 | |
Mark Salyzyn | c4e4823 | 2017-05-04 13:54:46 -0700 | [diff] [blame] | 19 | #include <ctype.h> |
Mark Salyzyn | 9a03863 | 2014-04-07 07:05:40 -0700 | [diff] [blame] | 20 | #include <fcntl.h> |
Mark Salyzyn | 02dd2f4 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 21 | #include <inttypes.h> |
Mark Salyzyn | b8a95bd | 2016-04-07 11:06:31 -0700 | [diff] [blame] | 22 | #include <pwd.h> |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 23 | #include <stdio.h> |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 24 | #include <string.h> |
Mark Salyzyn | b8a95bd | 2016-04-07 11:06:31 -0700 | [diff] [blame] | 25 | #include <sys/types.h> |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 26 | #include <unistd.h> |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 27 | |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 28 | #include <list> |
Tom Cherry | ec39a3f | 2020-07-09 09:51:16 -0700 | [diff] [blame] | 29 | #include <vector> |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 30 | |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 31 | #include <android-base/logging.h> |
Tom Cherry | ec39a3f | 2020-07-09 09:51:16 -0700 | [diff] [blame] | 32 | #include <android-base/strings.h> |
Mark Salyzyn | 03bb759 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 33 | #include <private/android_logger.h> |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 34 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 35 | #include "LogBufferElement.h" |
| 36 | |
Mark Salyzyn | 03bb759 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 37 | static const uint64_t hourSec = 60 * 60; |
| 38 | static const uint64_t monthSec = 31 * 24 * hourSec; |
| 39 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 40 | std::atomic<size_t> LogStatistics::SizesTotal; |
Mark Salyzyn | 3296291 | 2016-09-12 10:29:17 -0700 | [diff] [blame] | 41 | |
Tom Cherry | b0263af | 2020-06-03 15:38:32 -0700 | [diff] [blame] | 42 | static std::string TagNameKey(const LogStatisticsElement& element) { |
| 43 | if (IsBinary(element.log_id)) { |
| 44 | uint32_t tag = element.tag; |
| 45 | if (tag) { |
| 46 | const char* cp = android::tagToName(tag); |
| 47 | if (cp) { |
| 48 | return std::string(cp); |
| 49 | } |
| 50 | } |
| 51 | return android::base::StringPrintf("[%" PRIu32 "]", tag); |
| 52 | } |
| 53 | const char* msg = element.msg; |
| 54 | if (!msg) { |
| 55 | return "chatty"; |
| 56 | } |
| 57 | ++msg; |
| 58 | uint16_t len = element.msg_len; |
| 59 | len = (len <= 1) ? 0 : strnlen(msg, len - 1); |
| 60 | if (!len) { |
| 61 | return "<NULL>"; |
| 62 | } |
| 63 | return std::string(msg, len); |
| 64 | } |
| 65 | |
Tom Cherry | ec39a3f | 2020-07-09 09:51:16 -0700 | [diff] [blame] | 66 | LogStatistics::LogStatistics(bool enable_statistics, bool track_total_size, |
| 67 | std::optional<log_time> start_time) |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 68 | : enable(enable_statistics), track_total_size_(track_total_size) { |
Mark Salyzyn | 03bb759 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 69 | log_time now(CLOCK_REALTIME); |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 70 | log_id_for_each(id) { |
| 71 | mSizes[id] = 0; |
| 72 | mElements[id] = 0; |
Mark Salyzyn | 58b8be8 | 2015-09-30 07:40:09 -0700 | [diff] [blame] | 73 | mDroppedElements[id] = 0; |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 74 | mSizesTotal[id] = 0; |
| 75 | mElementsTotal[id] = 0; |
Tom Cherry | ec39a3f | 2020-07-09 09:51:16 -0700 | [diff] [blame] | 76 | if (start_time) { |
| 77 | mOldest[id] = *start_time; |
| 78 | mNewest[id] = *start_time; |
| 79 | } else { |
| 80 | mOldest[id] = now; |
| 81 | mNewest[id] = now; |
| 82 | } |
Mark Salyzyn | 03bb759 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 83 | mNewestDropped[id] = now; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 84 | } |
| 85 | } |
| 86 | |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 87 | namespace android { |
| 88 | |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 89 | size_t sizesTotal() { |
| 90 | return LogStatistics::sizesTotal(); |
| 91 | } |
Mark Salyzyn | 3296291 | 2016-09-12 10:29:17 -0700 | [diff] [blame] | 92 | |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 93 | // caller must own and free character string |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 94 | char* pidToName(pid_t pid) { |
Yi Kong | c8d09dd | 2018-07-13 17:39:22 -0700 | [diff] [blame] | 95 | char* retval = nullptr; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 96 | if (pid == 0) { // special case from auditd/klogd for kernel |
Mark Salyzyn | ae4d928 | 2014-10-15 08:49:39 -0700 | [diff] [blame] | 97 | retval = strdup("logd"); |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 98 | } else { |
Mark Salyzyn | 9a03863 | 2014-04-07 07:05:40 -0700 | [diff] [blame] | 99 | char buffer[512]; |
| 100 | snprintf(buffer, sizeof(buffer), "/proc/%u/cmdline", pid); |
Tom Cherry | 9b4246d | 2020-06-17 11:40:55 -0700 | [diff] [blame] | 101 | int fd = open(buffer, O_RDONLY | O_CLOEXEC); |
Mark Salyzyn | 9a03863 | 2014-04-07 07:05:40 -0700 | [diff] [blame] | 102 | if (fd >= 0) { |
| 103 | ssize_t ret = read(fd, buffer, sizeof(buffer)); |
| 104 | if (ret > 0) { |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 105 | buffer[sizeof(buffer) - 1] = '\0'; |
Mark Salyzyn | 9a03863 | 2014-04-07 07:05:40 -0700 | [diff] [blame] | 106 | // frameworks intermediate state |
Mark Salyzyn | 0eeb06b | 2016-12-02 10:08:48 -0800 | [diff] [blame] | 107 | if (fastcmp<strcmp>(buffer, "<pre-initialized>")) { |
Mark Salyzyn | 9a03863 | 2014-04-07 07:05:40 -0700 | [diff] [blame] | 108 | retval = strdup(buffer); |
| 109 | } |
| 110 | } |
| 111 | close(fd); |
| 112 | } |
| 113 | } |
| 114 | return retval; |
| 115 | } |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 116 | } |
| 117 | |
Tom Cherry | a26f7df | 2020-05-19 17:48:42 -0700 | [diff] [blame] | 118 | void LogStatistics::AddTotal(log_id_t log_id, uint16_t size) { |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 119 | auto lock = std::lock_guard{lock_}; |
Mark Salyzyn | 02dd2f4 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 120 | |
Mark Salyzyn | 02dd2f4 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 121 | mSizesTotal[log_id] += size; |
| 122 | SizesTotal += size; |
| 123 | ++mElementsTotal[log_id]; |
| 124 | } |
| 125 | |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 126 | void LogStatistics::Add(LogStatisticsElement element) { |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 127 | auto lock = std::lock_guard{lock_}; |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 128 | |
| 129 | if (!track_total_size_) { |
| 130 | element.total_len = element.msg_len; |
| 131 | } |
| 132 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 133 | log_id_t log_id = element.log_id; |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 134 | uint16_t size = element.total_len; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 135 | mSizes[log_id] += size; |
| 136 | ++mElements[log_id]; |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 137 | |
Mark Salyzyn | 02dd2f4 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 138 | // When caller adding a chatty entry, they will have already |
| 139 | // called add() and subtract() for each entry as they are |
| 140 | // evaluated and trimmed, thus recording size and number of |
| 141 | // elements, but we must recognize the manufactured dropped |
| 142 | // entry as not contributing to the lifetime totals. |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 143 | if (element.dropped_count) { |
Mark Salyzyn | a2c0222 | 2016-12-13 10:31:29 -0800 | [diff] [blame] | 144 | ++mDroppedElements[log_id]; |
| 145 | } else { |
Mark Salyzyn | a2c0222 | 2016-12-13 10:31:29 -0800 | [diff] [blame] | 146 | mSizesTotal[log_id] += size; |
Mark Salyzyn | 3296291 | 2016-09-12 10:29:17 -0700 | [diff] [blame] | 147 | SizesTotal += size; |
Mark Salyzyn | a2c0222 | 2016-12-13 10:31:29 -0800 | [diff] [blame] | 148 | ++mElementsTotal[log_id]; |
| 149 | } |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 150 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 151 | log_time stamp(element.realtime); |
Mark Salyzyn | 03bb759 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 152 | if (mNewest[log_id] < stamp) { |
| 153 | // A major time update invalidates the statistics :-( |
| 154 | log_time diff = stamp - mNewest[log_id]; |
| 155 | mNewest[log_id] = stamp; |
| 156 | |
| 157 | if (diff.tv_sec > hourSec) { |
| 158 | // approximate Do-Your-Best fixup |
| 159 | diff += mOldest[log_id]; |
| 160 | if ((diff > stamp) && ((diff - stamp).tv_sec < hourSec)) { |
| 161 | diff = stamp; |
| 162 | } |
| 163 | if (diff <= stamp) { |
| 164 | mOldest[log_id] = diff; |
| 165 | if (mNewestDropped[log_id] < diff) { |
| 166 | mNewestDropped[log_id] = diff; |
| 167 | } |
| 168 | } |
| 169 | } |
| 170 | } |
| 171 | |
Mark Salyzyn | ae4d928 | 2014-10-15 08:49:39 -0700 | [diff] [blame] | 172 | if (log_id == LOG_ID_KERNEL) { |
| 173 | return; |
| 174 | } |
| 175 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 176 | uidTable[log_id].Add(element.uid, element); |
| 177 | if (element.uid == AID_SYSTEM) { |
| 178 | pidSystemTable[log_id].Add(element.pid, element); |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 179 | } |
Mark Salyzyn | ae4d928 | 2014-10-15 08:49:39 -0700 | [diff] [blame] | 180 | |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 181 | if (!enable) { |
| 182 | return; |
| 183 | } |
| 184 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 185 | pidTable.Add(element.pid, element); |
| 186 | tidTable.Add(element.tid, element); |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 187 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 188 | uint32_t tag = element.tag; |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 189 | if (tag) { |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 190 | if (log_id == LOG_ID_SECURITY) { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 191 | securityTagTable.Add(tag, element); |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 192 | } else { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 193 | tagTable.Add(tag, element); |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 194 | } |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 195 | } |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 196 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 197 | if (!element.dropped_count) { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 198 | tagNameTable.Add(TagNameKey(element), element); |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 199 | } |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 200 | } |
| 201 | |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 202 | void LogStatistics::Subtract(LogStatisticsElement element) { |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 203 | auto lock = std::lock_guard{lock_}; |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 204 | |
| 205 | if (!track_total_size_) { |
| 206 | element.total_len = element.msg_len; |
| 207 | } |
| 208 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 209 | log_id_t log_id = element.log_id; |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 210 | uint16_t size = element.total_len; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 211 | mSizes[log_id] -= size; |
| 212 | --mElements[log_id]; |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 213 | if (element.dropped_count) { |
Mark Salyzyn | 58b8be8 | 2015-09-30 07:40:09 -0700 | [diff] [blame] | 214 | --mDroppedElements[log_id]; |
| 215 | } |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 216 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 217 | if (mOldest[log_id] < element.realtime) { |
| 218 | mOldest[log_id] = element.realtime; |
Mark Salyzyn | 03bb759 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 219 | } |
| 220 | |
Mark Salyzyn | ae4d928 | 2014-10-15 08:49:39 -0700 | [diff] [blame] | 221 | if (log_id == LOG_ID_KERNEL) { |
| 222 | return; |
| 223 | } |
| 224 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 225 | uidTable[log_id].Subtract(element.uid, element); |
| 226 | if (element.uid == AID_SYSTEM) { |
| 227 | pidSystemTable[log_id].Subtract(element.pid, element); |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 228 | } |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 229 | |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 230 | if (!enable) { |
| 231 | return; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 232 | } |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 233 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 234 | pidTable.Subtract(element.pid, element); |
| 235 | tidTable.Subtract(element.tid, element); |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 236 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 237 | uint32_t tag = element.tag; |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 238 | if (tag) { |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 239 | if (log_id == LOG_ID_SECURITY) { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 240 | securityTagTable.Subtract(tag, element); |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 241 | } else { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 242 | tagTable.Subtract(tag, element); |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 243 | } |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 244 | } |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 245 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 246 | if (!element.dropped_count) { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 247 | tagNameTable.Subtract(TagNameKey(element), element); |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 248 | } |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 249 | } |
| 250 | |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 251 | // Atomically set an entry to drop |
| 252 | // entry->setDropped(1) must follow this call, caller should do this explicitly. |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 253 | void LogStatistics::Drop(LogStatisticsElement element) { |
| 254 | CHECK_EQ(element.dropped_count, 0U); |
| 255 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 256 | auto lock = std::lock_guard{lock_}; |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 257 | log_id_t log_id = element.log_id; |
| 258 | uint16_t size = element.msg_len; |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 259 | mSizes[log_id] -= size; |
Mark Salyzyn | 58b8be8 | 2015-09-30 07:40:09 -0700 | [diff] [blame] | 260 | ++mDroppedElements[log_id]; |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 261 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 262 | if (mNewestDropped[log_id] < element.realtime) { |
| 263 | mNewestDropped[log_id] = element.realtime; |
Mark Salyzyn | 03bb759 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 264 | } |
| 265 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 266 | uidTable[log_id].Drop(element.uid, element); |
| 267 | if (element.uid == AID_SYSTEM) { |
| 268 | pidSystemTable[log_id].Drop(element.pid, element); |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 269 | } |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 270 | |
| 271 | if (!enable) { |
| 272 | return; |
| 273 | } |
| 274 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 275 | pidTable.Drop(element.pid, element); |
| 276 | tidTable.Drop(element.tid, element); |
Mark Salyzyn | 6a06694 | 2016-07-14 15:34:30 -0700 | [diff] [blame] | 277 | |
Tom Cherry | 3dd3ec3 | 2020-06-02 15:39:21 -0700 | [diff] [blame] | 278 | uint32_t tag = element.tag; |
Mark Salyzyn | 6a06694 | 2016-07-14 15:34:30 -0700 | [diff] [blame] | 279 | if (tag) { |
| 280 | if (log_id == LOG_ID_SECURITY) { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 281 | securityTagTable.Drop(tag, element); |
Mark Salyzyn | 6a06694 | 2016-07-14 15:34:30 -0700 | [diff] [blame] | 282 | } else { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 283 | tagTable.Drop(tag, element); |
Mark Salyzyn | 6a06694 | 2016-07-14 15:34:30 -0700 | [diff] [blame] | 284 | } |
| 285 | } |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 286 | |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 287 | tagNameTable.Subtract(TagNameKey(element), element); |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 288 | } |
| 289 | |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 290 | void LogStatistics::Erase(LogStatisticsElement element) { |
| 291 | CHECK_GT(element.dropped_count, 0U); |
| 292 | CHECK_EQ(element.msg_len, 0U); |
| 293 | |
| 294 | auto lock = std::lock_guard{lock_}; |
| 295 | |
| 296 | if (!track_total_size_) { |
| 297 | element.total_len = 0; |
| 298 | } |
| 299 | |
| 300 | log_id_t log_id = element.log_id; |
| 301 | --mElements[log_id]; |
| 302 | --mDroppedElements[log_id]; |
| 303 | mSizes[log_id] -= element.total_len; |
| 304 | |
| 305 | uidTable[log_id].Erase(element.uid, element); |
| 306 | if (element.uid == AID_SYSTEM) { |
| 307 | pidSystemTable[log_id].Erase(element.pid, element); |
| 308 | } |
| 309 | |
| 310 | if (!enable) { |
| 311 | return; |
| 312 | } |
| 313 | |
| 314 | pidTable.Erase(element.pid, element); |
| 315 | tidTable.Erase(element.tid, element); |
| 316 | |
| 317 | uint32_t tag = element.tag; |
| 318 | if (tag) { |
| 319 | if (log_id == LOG_ID_SECURITY) { |
| 320 | securityTagTable.Erase(tag, element); |
| 321 | } else { |
| 322 | tagTable.Erase(tag, element); |
| 323 | } |
| 324 | } |
| 325 | } |
| 326 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 327 | const char* LogStatistics::UidToName(uid_t uid) const { |
| 328 | auto lock = std::lock_guard{lock_}; |
| 329 | return UidToNameLocked(uid); |
| 330 | } |
| 331 | |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 332 | // caller must own and free character string |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 333 | const char* LogStatistics::UidToNameLocked(uid_t uid) const { |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 334 | // Local hard coded favourites |
| 335 | if (uid == AID_LOGD) { |
| 336 | return strdup("auditd"); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 337 | } |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 338 | |
Mark Salyzyn | b8a95bd | 2016-04-07 11:06:31 -0700 | [diff] [blame] | 339 | // Android system |
| 340 | if (uid < AID_APP) { |
| 341 | // in bionic, thread safe as long as we copy the results |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 342 | struct passwd* pwd = getpwuid(uid); |
Mark Salyzyn | b8a95bd | 2016-04-07 11:06:31 -0700 | [diff] [blame] | 343 | if (pwd) { |
| 344 | return strdup(pwd->pw_name); |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 345 | } |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 346 | } |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 347 | |
Mark Salyzyn | 08739ba | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 348 | // Parse /data/system/packages.list |
Jeff Sharkey | dff4470 | 2016-12-13 11:55:19 -0700 | [diff] [blame] | 349 | uid_t userId = uid % AID_USER_OFFSET; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 350 | const char* name = android::uidToName(userId); |
Mark Salyzyn | 023f51f | 2015-04-29 12:48:45 -0700 | [diff] [blame] | 351 | if (!name && (userId > (AID_SHARED_GID_START - AID_APP))) { |
| 352 | name = android::uidToName(userId - (AID_SHARED_GID_START - AID_APP)); |
| 353 | } |
Mark Salyzyn | 08739ba | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 354 | if (name) { |
| 355 | return name; |
| 356 | } |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 357 | |
Mark Salyzyn | b8a95bd | 2016-04-07 11:06:31 -0700 | [diff] [blame] | 358 | // Android application |
| 359 | if (uid >= AID_APP) { |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 360 | struct passwd* pwd = getpwuid(uid); |
Mark Salyzyn | b8a95bd | 2016-04-07 11:06:31 -0700 | [diff] [blame] | 361 | if (pwd) { |
| 362 | return strdup(pwd->pw_name); |
| 363 | } |
| 364 | } |
| 365 | |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 366 | // report uid -> pid(s) -> pidToName if unique |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 367 | for (pidTable_t::const_iterator it = pidTable.begin(); it != pidTable.end(); |
| 368 | ++it) { |
| 369 | const PidEntry& entry = it->second; |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 370 | |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 371 | if (entry.uid() == uid) { |
| 372 | const char* nameTmp = entry.name(); |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 373 | |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 374 | if (nameTmp) { |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 375 | if (!name) { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 376 | name = strdup(nameTmp); |
Mark Salyzyn | 0eeb06b | 2016-12-02 10:08:48 -0800 | [diff] [blame] | 377 | } else if (fastcmp<strcmp>(name, nameTmp)) { |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 378 | free(const_cast<char*>(name)); |
Yi Kong | c8d09dd | 2018-07-13 17:39:22 -0700 | [diff] [blame] | 379 | name = nullptr; |
Mark Salyzyn | 023f51f | 2015-04-29 12:48:45 -0700 | [diff] [blame] | 380 | break; |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 381 | } |
| 382 | } |
| 383 | } |
| 384 | } |
| 385 | |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 386 | // No one |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 387 | return name; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 388 | } |
| 389 | |
Tom Cherry | b6b78e9 | 2020-05-07 09:13:12 -0700 | [diff] [blame] | 390 | template <typename TKey, typename TEntry> |
| 391 | void LogStatistics::WorstTwoWithThreshold(const LogHashtable<TKey, TEntry>& table, size_t threshold, |
| 392 | int* worst, size_t* worst_sizes, |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 393 | size_t* second_worst_sizes) const { |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 394 | std::array<const TKey*, 2> max_keys; |
Tom Cherry | b6b78e9 | 2020-05-07 09:13:12 -0700 | [diff] [blame] | 395 | std::array<const TEntry*, 2> max_entries; |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 396 | table.MaxEntries(AID_ROOT, 0, max_keys, max_entries); |
Tom Cherry | b6b78e9 | 2020-05-07 09:13:12 -0700 | [diff] [blame] | 397 | if (max_entries[0] == nullptr || max_entries[1] == nullptr) { |
| 398 | return; |
| 399 | } |
| 400 | *worst_sizes = max_entries[0]->getSizes(); |
| 401 | // b/24782000: Allow time horizon to extend roughly tenfold, assume average entry length is |
| 402 | // 100 characters. |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 403 | if (*worst_sizes > threshold && *worst_sizes > (10 * max_entries[0]->dropped_count())) { |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 404 | *worst = *max_keys[0]; |
Tom Cherry | b6b78e9 | 2020-05-07 09:13:12 -0700 | [diff] [blame] | 405 | *second_worst_sizes = max_entries[1]->getSizes(); |
| 406 | if (*second_worst_sizes < threshold) { |
| 407 | *second_worst_sizes = threshold; |
| 408 | } |
| 409 | } |
| 410 | } |
| 411 | |
| 412 | void LogStatistics::WorstTwoUids(log_id id, size_t threshold, int* worst, size_t* worst_sizes, |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 413 | size_t* second_worst_sizes) const { |
| 414 | auto lock = std::lock_guard{lock_}; |
Tom Cherry | b6b78e9 | 2020-05-07 09:13:12 -0700 | [diff] [blame] | 415 | WorstTwoWithThreshold(uidTable[id], threshold, worst, worst_sizes, second_worst_sizes); |
| 416 | } |
| 417 | |
| 418 | void LogStatistics::WorstTwoTags(size_t threshold, int* worst, size_t* worst_sizes, |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 419 | size_t* second_worst_sizes) const { |
| 420 | auto lock = std::lock_guard{lock_}; |
Tom Cherry | b6b78e9 | 2020-05-07 09:13:12 -0700 | [diff] [blame] | 421 | WorstTwoWithThreshold(tagTable, threshold, worst, worst_sizes, second_worst_sizes); |
| 422 | } |
| 423 | |
| 424 | void LogStatistics::WorstTwoSystemPids(log_id id, size_t worst_uid_sizes, int* worst, |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 425 | size_t* second_worst_sizes) const { |
| 426 | auto lock = std::lock_guard{lock_}; |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 427 | std::array<const pid_t*, 2> max_keys; |
Tom Cherry | b6b78e9 | 2020-05-07 09:13:12 -0700 | [diff] [blame] | 428 | std::array<const PidEntry*, 2> max_entries; |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 429 | pidSystemTable[id].MaxEntries(AID_SYSTEM, 0, max_keys, max_entries); |
Tom Cherry | b6b78e9 | 2020-05-07 09:13:12 -0700 | [diff] [blame] | 430 | if (max_entries[0] == nullptr || max_entries[1] == nullptr) { |
| 431 | return; |
| 432 | } |
| 433 | |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 434 | *worst = *max_keys[0]; |
Tom Cherry | b6b78e9 | 2020-05-07 09:13:12 -0700 | [diff] [blame] | 435 | *second_worst_sizes = worst_uid_sizes - max_entries[0]->getSizes() + max_entries[1]->getSizes(); |
| 436 | } |
| 437 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 438 | // Prune at most 10% of the log entries or maxPrune, whichever is less. |
| 439 | bool LogStatistics::ShouldPrune(log_id id, unsigned long max_size, |
| 440 | unsigned long* prune_rows) const { |
| 441 | static constexpr size_t kMinPrune = 4; |
| 442 | static constexpr size_t kMaxPrune = 256; |
| 443 | |
| 444 | auto lock = std::lock_guard{lock_}; |
| 445 | size_t sizes = mSizes[id]; |
| 446 | if (sizes <= max_size) { |
| 447 | return false; |
| 448 | } |
| 449 | size_t size_over = sizes - ((max_size * 9) / 10); |
| 450 | size_t elements = mElements[id] - mDroppedElements[id]; |
| 451 | size_t min_elements = elements / 100; |
| 452 | if (min_elements < kMinPrune) { |
| 453 | min_elements = kMinPrune; |
| 454 | } |
| 455 | *prune_rows = elements * size_over / sizes; |
| 456 | if (*prune_rows < min_elements) { |
| 457 | *prune_rows = min_elements; |
| 458 | } |
| 459 | if (*prune_rows > kMaxPrune) { |
| 460 | *prune_rows = kMaxPrune; |
| 461 | } |
| 462 | |
| 463 | return true; |
| 464 | } |
| 465 | |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 466 | std::string UidEntry::formatHeader(const std::string& name, log_id_t id) const { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 467 | bool isprune = worstUidEnabledForLogid(id); |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 468 | return formatLine(android::base::StringPrintf(name.c_str(), |
| 469 | android_log_id_to_name(id)), |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 470 | std::string("Size"), |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 471 | std::string(isprune ? "+/- Pruned" : "")) + |
| 472 | formatLine(std::string("UID PACKAGE"), std::string("BYTES"), |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 473 | std::string(isprune ? "NUM" : "")); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 474 | } |
| 475 | |
Mark Salyzyn | f31ae3d | 2017-04-13 15:35:22 -0700 | [diff] [blame] | 476 | // Helper to truncate name, if too long, and add name dressings |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 477 | void LogStatistics::FormatTmp(const char* nameTmp, uid_t uid, std::string& name, std::string& size, |
| 478 | size_t nameLen) const { |
Mark Salyzyn | f31ae3d | 2017-04-13 15:35:22 -0700 | [diff] [blame] | 479 | const char* allocNameTmp = nullptr; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 480 | if (!nameTmp) nameTmp = allocNameTmp = UidToNameLocked(uid); |
Mark Salyzyn | f31ae3d | 2017-04-13 15:35:22 -0700 | [diff] [blame] | 481 | if (nameTmp) { |
| 482 | size_t lenSpace = std::max(nameLen - name.length(), (size_t)1); |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 483 | size_t len = EntryBase::TOTAL_LEN - EntryBase::PRUNED_LEN - size.length() - name.length() - |
| 484 | lenSpace - 2; |
Mark Salyzyn | f31ae3d | 2017-04-13 15:35:22 -0700 | [diff] [blame] | 485 | size_t lenNameTmp = strlen(nameTmp); |
| 486 | while ((len < lenNameTmp) && (lenSpace > 1)) { |
| 487 | ++len; |
| 488 | --lenSpace; |
| 489 | } |
| 490 | name += android::base::StringPrintf("%*s", (int)lenSpace, ""); |
| 491 | if (len < lenNameTmp) { |
| 492 | name += "..."; |
| 493 | nameTmp += lenNameTmp - std::max(len - 3, (size_t)1); |
| 494 | } |
| 495 | name += nameTmp; |
| 496 | free(const_cast<char*>(allocNameTmp)); |
| 497 | } |
| 498 | } |
| 499 | |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 500 | std::string UidEntry::format(const LogStatistics& stat, log_id_t id, uid_t uid) const |
| 501 | REQUIRES(stat.lock_) { |
| 502 | std::string name = android::base::StringPrintf("%u", uid); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 503 | std::string size = android::base::StringPrintf("%zu", getSizes()); |
| 504 | |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 505 | stat.FormatTmp(nullptr, uid, name, size, 6); |
Mark Salyzyn | f31ae3d | 2017-04-13 15:35:22 -0700 | [diff] [blame] | 506 | |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 507 | std::string pruned = ""; |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 508 | if (worstUidEnabledForLogid(id)) { |
| 509 | size_t totalDropped = 0; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 510 | for (LogStatistics::uidTable_t::const_iterator it = |
| 511 | stat.uidTable[id].begin(); |
| 512 | it != stat.uidTable[id].end(); ++it) { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 513 | totalDropped += it->second.dropped_count(); |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 514 | } |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 515 | size_t sizes = stat.mSizes[id]; |
| 516 | size_t totalSize = stat.mSizesTotal[id]; |
| 517 | size_t totalElements = stat.mElementsTotal[id]; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 518 | float totalVirtualSize = |
| 519 | (float)sizes + (float)totalDropped * totalSize / totalElements; |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 520 | size_t entrySize = getSizes(); |
| 521 | float virtualEntrySize = entrySize; |
| 522 | int realPermille = virtualEntrySize * 1000.0 / sizes; |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 523 | size_t dropped = dropped_count(); |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 524 | if (dropped) { |
| 525 | pruned = android::base::StringPrintf("%zu", dropped); |
| 526 | virtualEntrySize += (float)dropped * totalSize / totalElements; |
| 527 | } |
| 528 | int virtualPermille = virtualEntrySize * 1000.0 / totalVirtualSize; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 529 | int permille = |
| 530 | (realPermille - virtualPermille) * 1000L / (virtualPermille ?: 1); |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 531 | if ((permille < -1) || (1 < permille)) { |
| 532 | std::string change; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 533 | const char* units = "%"; |
| 534 | const char* prefix = (permille > 0) ? "+" : ""; |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 535 | |
| 536 | if (permille > 999) { |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 537 | permille = (permille + 1000) / 100; // Now tenths fold |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 538 | units = "X"; |
| 539 | prefix = ""; |
| 540 | } |
| 541 | if ((-99 < permille) && (permille < 99)) { |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 542 | change = android::base::StringPrintf( |
| 543 | "%s%d.%u%s", prefix, permille / 10, |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 544 | ((permille < 0) ? (-permille % 10) : (permille % 10)), |
| 545 | units); |
| 546 | } else { |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 547 | change = android::base::StringPrintf( |
| 548 | "%s%d%s", prefix, (permille + 5) / 10, units); |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 549 | } |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 550 | ssize_t spaces = EntryBase::PRUNED_LEN - 2 - pruned.length() - change.length(); |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 551 | if ((spaces <= 0) && pruned.length()) { |
| 552 | spaces = 1; |
| 553 | } |
Mark Salyzyn | d966e22 | 2016-12-19 22:23:03 +0000 | [diff] [blame] | 554 | if (spaces > 0) { |
Mark Salyzyn | c723df8 | 2015-08-24 11:08:00 -0700 | [diff] [blame] | 555 | change += android::base::StringPrintf("%*s", (int)spaces, ""); |
| 556 | } |
| 557 | pruned = change + pruned; |
| 558 | } |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 559 | } |
| 560 | |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 561 | std::string output = formatLine(name, size, pruned); |
| 562 | |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 563 | if (uid != AID_SYSTEM) { |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 564 | return output; |
| 565 | } |
| 566 | |
| 567 | static const size_t maximum_sorted_entries = 32; |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 568 | std::array<const pid_t*, maximum_sorted_entries> sorted_pids; |
| 569 | std::array<const PidEntry*, maximum_sorted_entries> sorted_entries; |
| 570 | stat.pidSystemTable[id].MaxEntries(uid, 0, sorted_pids, sorted_entries); |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 571 | |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 572 | std::string byPid; |
| 573 | size_t index; |
| 574 | bool hasDropped = false; |
| 575 | for (index = 0; index < maximum_sorted_entries; ++index) { |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 576 | const PidEntry* entry = sorted_entries[index]; |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 577 | if (!entry) { |
| 578 | break; |
| 579 | } |
| 580 | if (entry->getSizes() <= (getSizes() / 100)) { |
| 581 | break; |
| 582 | } |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 583 | if (entry->dropped_count()) { |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 584 | hasDropped = true; |
| 585 | } |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 586 | byPid += entry->format(stat, id, *sorted_pids[index]); |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 587 | } |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 588 | if (index > 1) { // print this only if interesting |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 589 | std::string ditto("\" "); |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 590 | output += formatLine(std::string(" PID/UID COMMAND LINE"), ditto, |
| 591 | hasDropped ? ditto : std::string("")); |
Mark Salyzyn | bec3c3d | 2015-08-28 08:02:59 -0700 | [diff] [blame] | 592 | output += byPid; |
| 593 | } |
| 594 | |
| 595 | return output; |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 596 | } |
| 597 | |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 598 | std::string PidEntry::formatHeader(const std::string& name, |
| 599 | log_id_t /* id */) const { |
| 600 | return formatLine(name, std::string("Size"), std::string("Pruned")) + |
| 601 | formatLine(std::string(" PID/UID COMMAND LINE"), |
| 602 | std::string("BYTES"), std::string("NUM")); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 603 | } |
| 604 | |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 605 | std::string PidEntry::format(const LogStatistics& stat, log_id_t, pid_t pid) const |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 606 | REQUIRES(stat.lock_) { |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 607 | std::string name = android::base::StringPrintf("%5u/%u", pid, uid_); |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 608 | std::string size = android::base::StringPrintf("%zu", getSizes()); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 609 | |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 610 | stat.FormatTmp(name_, uid_, name, size, 12); |
Mark Salyzyn | f31ae3d | 2017-04-13 15:35:22 -0700 | [diff] [blame] | 611 | |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 612 | std::string pruned = ""; |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 613 | size_t dropped = dropped_count(); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 614 | if (dropped) { |
| 615 | pruned = android::base::StringPrintf("%zu", dropped); |
| 616 | } |
| 617 | |
| 618 | return formatLine(name, size, pruned); |
| 619 | } |
| 620 | |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 621 | std::string TidEntry::formatHeader(const std::string& name, |
| 622 | log_id_t /* id */) const { |
| 623 | return formatLine(name, std::string("Size"), std::string("Pruned")) + |
| 624 | formatLine(std::string(" TID/UID COMM"), std::string("BYTES"), |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 625 | std::string("NUM")); |
| 626 | } |
| 627 | |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 628 | std::string TidEntry::format(const LogStatistics& stat, log_id_t, pid_t tid) const |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 629 | REQUIRES(stat.lock_) { |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 630 | std::string name = android::base::StringPrintf("%5u/%u", tid, uid_); |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 631 | std::string size = android::base::StringPrintf("%zu", getSizes()); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 632 | |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 633 | stat.FormatTmp(name_, uid_, name, size, 12); |
Mark Salyzyn | f31ae3d | 2017-04-13 15:35:22 -0700 | [diff] [blame] | 634 | |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 635 | std::string pruned = ""; |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 636 | size_t dropped = dropped_count(); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 637 | if (dropped) { |
| 638 | pruned = android::base::StringPrintf("%zu", dropped); |
| 639 | } |
| 640 | |
| 641 | return formatLine(name, size, pruned); |
| 642 | } |
| 643 | |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 644 | std::string TagEntry::formatHeader(const std::string& name, log_id_t id) const { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 645 | bool isprune = worstUidEnabledForLogid(id); |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 646 | return formatLine(name, std::string("Size"), |
| 647 | std::string(isprune ? "Prune" : "")) + |
| 648 | formatLine(std::string(" TAG/UID TAGNAME"), |
| 649 | std::string("BYTES"), std::string(isprune ? "NUM" : "")); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 650 | } |
| 651 | |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 652 | std::string TagEntry::format(const LogStatistics&, log_id_t, uint32_t) const { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 653 | std::string name; |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 654 | if (uid_ == (uid_t)-1) { |
| 655 | name = android::base::StringPrintf("%7u", key()); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 656 | } else { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 657 | name = android::base::StringPrintf("%7u/%u", key(), uid_); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 658 | } |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 659 | const char* nameTmp = this->name(); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 660 | if (nameTmp) { |
| 661 | name += android::base::StringPrintf( |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 662 | "%*s%s", (int)std::max(14 - name.length(), (size_t)1), "", nameTmp); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 663 | } |
| 664 | |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 665 | std::string size = android::base::StringPrintf("%zu", getSizes()); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 666 | |
| 667 | std::string pruned = ""; |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 668 | size_t dropped = dropped_count(); |
Mark Salyzyn | 6a06694 | 2016-07-14 15:34:30 -0700 | [diff] [blame] | 669 | if (dropped) { |
| 670 | pruned = android::base::StringPrintf("%zu", dropped); |
| 671 | } |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 672 | |
| 673 | return formatLine(name, size, pruned); |
| 674 | } |
| 675 | |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 676 | std::string TagNameEntry::formatHeader(const std::string& name, |
| 677 | log_id_t /* id */) const { |
| 678 | return formatLine(name, std::string("Size"), std::string("")) + |
| 679 | formatLine(std::string(" TID/PID/UID LOG_TAG NAME"), |
| 680 | std::string("BYTES"), std::string("")); |
| 681 | } |
| 682 | |
Tom Cherry | b0263af | 2020-06-03 15:38:32 -0700 | [diff] [blame] | 683 | std::string TagNameEntry::format(const LogStatistics&, log_id_t, |
| 684 | const std::string& key_name) const { |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 685 | std::string name; |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 686 | std::string pidstr; |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 687 | if (pid_ != (pid_t)-1) { |
| 688 | pidstr = android::base::StringPrintf("%u", pid_); |
| 689 | if (tid_ != (pid_t)-1 && tid_ != pid_) pidstr = "/" + pidstr; |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 690 | } |
| 691 | int len = 9 - pidstr.length(); |
| 692 | if (len < 0) len = 0; |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 693 | if (tid_ == (pid_t)-1 || tid_ == pid_) { |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 694 | name = android::base::StringPrintf("%*s", len, ""); |
| 695 | } else { |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 696 | name = android::base::StringPrintf("%*u", len, tid_); |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 697 | } |
| 698 | name += pidstr; |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 699 | if (uid_ != (uid_t)-1) { |
| 700 | name += android::base::StringPrintf("/%u", uid_); |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 701 | } |
| 702 | |
| 703 | std::string size = android::base::StringPrintf("%zu", getSizes()); |
| 704 | |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 705 | const char* nameTmp = key_name.data(); |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 706 | if (nameTmp) { |
| 707 | size_t lenSpace = std::max(16 - name.length(), (size_t)1); |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 708 | size_t len = EntryBase::TOTAL_LEN - EntryBase::PRUNED_LEN - size.length() - name.length() - |
| 709 | lenSpace - 2; |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 710 | size_t lenNameTmp = strlen(nameTmp); |
| 711 | while ((len < lenNameTmp) && (lenSpace > 1)) { |
| 712 | ++len; |
| 713 | --lenSpace; |
| 714 | } |
| 715 | name += android::base::StringPrintf("%*s", (int)lenSpace, ""); |
| 716 | if (len < lenNameTmp) { |
| 717 | name += "..."; |
| 718 | nameTmp += lenNameTmp - std::max(len - 3, (size_t)1); |
| 719 | } |
| 720 | name += nameTmp; |
| 721 | } |
| 722 | |
| 723 | std::string pruned = ""; |
| 724 | |
| 725 | return formatLine(name, size, pruned); |
| 726 | } |
| 727 | |
Mark Salyzyn | 03bb759 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 728 | static std::string formatMsec(uint64_t val) { |
| 729 | static const unsigned subsecDigits = 3; |
| 730 | static const uint64_t sec = MS_PER_SEC; |
| 731 | |
| 732 | static const uint64_t minute = 60 * sec; |
| 733 | static const uint64_t hour = 60 * minute; |
| 734 | static const uint64_t day = 24 * hour; |
| 735 | |
| 736 | std::string output; |
| 737 | if (val < sec) return output; |
| 738 | |
| 739 | if (val >= day) { |
| 740 | output = android::base::StringPrintf("%" PRIu64 "d ", val / day); |
| 741 | val = (val % day) + day; |
| 742 | } |
| 743 | if (val >= minute) { |
| 744 | if (val >= hour) { |
| 745 | output += android::base::StringPrintf("%" PRIu64 ":", |
| 746 | (val / hour) % (day / hour)); |
| 747 | } |
| 748 | output += android::base::StringPrintf( |
| 749 | (val >= hour) ? "%02" PRIu64 ":" : "%" PRIu64 ":", |
| 750 | (val / minute) % (hour / minute)); |
| 751 | } |
| 752 | output += |
| 753 | android::base::StringPrintf((val >= minute) ? "%02" PRIu64 : "%" PRIu64, |
| 754 | (val / sec) % (minute / sec)); |
| 755 | val %= sec; |
| 756 | unsigned digits = subsecDigits; |
| 757 | while (digits && ((val % 10) == 0)) { |
| 758 | val /= 10; |
| 759 | --digits; |
| 760 | } |
| 761 | if (digits) { |
| 762 | output += android::base::StringPrintf(".%0*" PRIu64, digits, val); |
| 763 | } |
| 764 | return output; |
| 765 | } |
| 766 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 767 | template <typename TKey, typename TEntry> |
| 768 | std::string LogStatistics::FormatTable(const LogHashtable<TKey, TEntry>& table, uid_t uid, |
| 769 | pid_t pid, const std::string& name, log_id_t id) const |
| 770 | REQUIRES(lock_) { |
| 771 | static const size_t maximum_sorted_entries = 32; |
| 772 | std::string output; |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 773 | std::array<const TKey*, maximum_sorted_entries> sorted_keys; |
| 774 | std::array<const TEntry*, maximum_sorted_entries> sorted_entries; |
| 775 | table.MaxEntries(uid, pid, sorted_keys, sorted_entries); |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 776 | bool header_printed = false; |
| 777 | for (size_t index = 0; index < maximum_sorted_entries; ++index) { |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 778 | const TEntry* entry = sorted_entries[index]; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 779 | if (!entry) { |
| 780 | break; |
| 781 | } |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 782 | if (entry->getSizes() <= (sorted_entries[0]->getSizes() / 100)) { |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 783 | break; |
| 784 | } |
| 785 | if (!header_printed) { |
| 786 | output += "\n\n"; |
| 787 | output += entry->formatHeader(name, id); |
| 788 | header_printed = true; |
| 789 | } |
Tom Cherry | 4596b78 | 2020-06-03 13:49:24 -0700 | [diff] [blame] | 790 | output += entry->format(*this, id, *sorted_keys[index]); |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 791 | } |
| 792 | return output; |
| 793 | } |
| 794 | |
Tom Cherry | ec39a3f | 2020-07-09 09:51:16 -0700 | [diff] [blame] | 795 | std::string LogStatistics::ReportInteresting() const { |
| 796 | auto lock = std::lock_guard{lock_}; |
| 797 | |
| 798 | std::vector<std::string> items; |
| 799 | |
| 800 | log_id_for_each(i) { items.emplace_back(std::to_string(mElements[i])); } |
| 801 | |
| 802 | log_id_for_each(i) { items.emplace_back(std::to_string(mSizes[i])); } |
| 803 | |
| 804 | log_id_for_each(i) { |
| 805 | items.emplace_back(std::to_string(overhead_[i] ? *overhead_[i] : mSizes[i])); |
| 806 | } |
| 807 | |
| 808 | log_id_for_each(i) { |
| 809 | uint64_t oldest = mOldest[i].msec() / 1000; |
| 810 | uint64_t newest = mNewest[i].msec() / 1000; |
| 811 | |
| 812 | int span = newest - oldest; |
| 813 | |
| 814 | items.emplace_back(std::to_string(span)); |
| 815 | } |
| 816 | |
| 817 | return android::base::Join(items, ","); |
| 818 | } |
| 819 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 820 | std::string LogStatistics::Format(uid_t uid, pid_t pid, unsigned int logMask) const { |
| 821 | auto lock = std::lock_guard{lock_}; |
| 822 | |
Chih-Hung Hsieh | 08d470b | 2018-08-13 14:22:56 -0700 | [diff] [blame] | 823 | static const uint16_t spaces_total = 19; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 824 | |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 825 | // Report on total logging, current and for all time |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 826 | |
Mark Salyzyn | decbcd9 | 2015-08-19 15:33:01 -0700 | [diff] [blame] | 827 | std::string output = "size/num"; |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 828 | size_t oldLength; |
Chih-Hung Hsieh | 08d470b | 2018-08-13 14:22:56 -0700 | [diff] [blame] | 829 | int16_t spaces = 1; |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 830 | |
| 831 | log_id_for_each(id) { |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 832 | if (!(logMask & (1 << id))) continue; |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 833 | oldLength = output.length(); |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 834 | if (spaces < 0) spaces = 0; |
Mark Salyzyn | decbcd9 | 2015-08-19 15:33:01 -0700 | [diff] [blame] | 835 | output += android::base::StringPrintf("%*s%s", spaces, "", |
| 836 | android_log_id_to_name(id)); |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 837 | spaces += spaces_total + oldLength - output.length(); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 838 | } |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 839 | if (spaces < 0) spaces = 0; |
| 840 | output += android::base::StringPrintf("%*sTotal", spaces, ""); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 841 | |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 842 | static const char TotalStr[] = "\nTotal"; |
| 843 | spaces = 10 - strlen(TotalStr); |
| 844 | output += TotalStr; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 845 | |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 846 | size_t totalSize = 0; |
| 847 | size_t totalEls = 0; |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 848 | log_id_for_each(id) { |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 849 | if (!(logMask & (1 << id))) continue; |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 850 | oldLength = output.length(); |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 851 | if (spaces < 0) spaces = 0; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 852 | size_t szs = mSizesTotal[id]; |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 853 | totalSize += szs; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 854 | size_t els = mElementsTotal[id]; |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 855 | totalEls += els; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 856 | output += |
| 857 | android::base::StringPrintf("%*s%zu/%zu", spaces, "", szs, els); |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 858 | spaces += spaces_total + oldLength - output.length(); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 859 | } |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 860 | if (spaces < 0) spaces = 0; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 861 | output += android::base::StringPrintf("%*s%zu/%zu", spaces, "", totalSize, |
| 862 | totalEls); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 863 | |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 864 | static const char NowStr[] = "\nNow"; |
| 865 | spaces = 10 - strlen(NowStr); |
| 866 | output += NowStr; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 867 | |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 868 | totalSize = 0; |
| 869 | totalEls = 0; |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 870 | log_id_for_each(id) { |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 871 | if (!(logMask & (1 << id))) continue; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 872 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 873 | size_t els = mElements[id]; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 874 | if (els) { |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 875 | oldLength = output.length(); |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 876 | if (spaces < 0) spaces = 0; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 877 | size_t szs = mSizes[id]; |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 878 | totalSize += szs; |
| 879 | totalEls += els; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 880 | output += |
| 881 | android::base::StringPrintf("%*s%zu/%zu", spaces, "", szs, els); |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 882 | spaces -= output.length() - oldLength; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 883 | } |
| 884 | spaces += spaces_total; |
| 885 | } |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 886 | if (spaces < 0) spaces = 0; |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 887 | output += android::base::StringPrintf("%*s%zu/%zu", spaces, "", totalSize, |
| 888 | totalEls); |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 889 | |
Mark Salyzyn | 03bb759 | 2017-04-14 09:46:57 -0700 | [diff] [blame] | 890 | static const char SpanStr[] = "\nLogspan"; |
| 891 | spaces = 10 - strlen(SpanStr); |
| 892 | output += SpanStr; |
| 893 | |
| 894 | // Total reports the greater of the individual maximum time span, or the |
| 895 | // validated minimum start and maximum end time span if it makes sense. |
| 896 | uint64_t minTime = UINT64_MAX; |
| 897 | uint64_t maxTime = 0; |
| 898 | uint64_t maxSpan = 0; |
| 899 | totalSize = 0; |
| 900 | |
| 901 | log_id_for_each(id) { |
| 902 | if (!(logMask & (1 << id))) continue; |
| 903 | |
| 904 | // validity checking |
| 905 | uint64_t oldest = mOldest[id].msec(); |
| 906 | uint64_t newest = mNewest[id].msec(); |
| 907 | if (newest <= oldest) { |
| 908 | spaces += spaces_total; |
| 909 | continue; |
| 910 | } |
| 911 | |
| 912 | uint64_t span = newest - oldest; |
| 913 | if (span > (monthSec * MS_PER_SEC)) { |
| 914 | spaces += spaces_total; |
| 915 | continue; |
| 916 | } |
| 917 | |
| 918 | // total span |
| 919 | if (minTime > oldest) minTime = oldest; |
| 920 | if (maxTime < newest) maxTime = newest; |
| 921 | if (span > maxSpan) maxSpan = span; |
| 922 | totalSize += span; |
| 923 | |
| 924 | uint64_t dropped = mNewestDropped[id].msec(); |
| 925 | if (dropped < oldest) dropped = oldest; |
| 926 | if (dropped > newest) dropped = newest; |
| 927 | |
| 928 | oldLength = output.length(); |
| 929 | output += android::base::StringPrintf("%*s%s", spaces, "", |
| 930 | formatMsec(span).c_str()); |
| 931 | unsigned permille = ((newest - dropped) * 1000 + (span / 2)) / span; |
| 932 | if ((permille > 1) && (permille < 999)) { |
| 933 | output += android::base::StringPrintf("(%u", permille / 10); |
| 934 | permille %= 10; |
| 935 | if (permille) { |
| 936 | output += android::base::StringPrintf(".%u", permille); |
| 937 | } |
| 938 | output += android::base::StringPrintf("%%)"); |
| 939 | } |
| 940 | spaces -= output.length() - oldLength; |
| 941 | spaces += spaces_total; |
| 942 | } |
| 943 | if ((maxTime > minTime) && ((maxTime -= minTime) < totalSize) && |
| 944 | (maxTime > maxSpan)) { |
| 945 | maxSpan = maxTime; |
| 946 | } |
| 947 | if (spaces < 0) spaces = 0; |
| 948 | output += android::base::StringPrintf("%*s%s", spaces, "", |
| 949 | formatMsec(maxSpan).c_str()); |
| 950 | |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 951 | static const char OverheadStr[] = "\nOverhead"; |
| 952 | spaces = 10 - strlen(OverheadStr); |
| 953 | output += OverheadStr; |
| 954 | |
| 955 | totalSize = 0; |
| 956 | log_id_for_each(id) { |
| 957 | if (!(logMask & (1 << id))) continue; |
| 958 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 959 | size_t els = mElements[id]; |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 960 | if (els) { |
| 961 | oldLength = output.length(); |
| 962 | if (spaces < 0) spaces = 0; |
Tom Cherry | f74503d | 2020-06-19 12:21:21 -0700 | [diff] [blame] | 963 | size_t szs = 0; |
| 964 | if (overhead_[id]) { |
| 965 | szs = *overhead_[id]; |
| 966 | } else if (track_total_size_) { |
| 967 | szs = mSizes[id]; |
| 968 | } else { |
| 969 | // Legacy fallback for Chatty without track_total_size_ |
| 970 | // Estimate the size of this element in the parent std::list<> by adding two void*'s |
| 971 | // corresponding to the next/prev pointers and aligning to 64 bit. |
| 972 | static const size_t overhead = |
| 973 | (sizeof(LogBufferElement) + 2 * sizeof(void*) + sizeof(uint64_t) - 1) & |
| 974 | -sizeof(uint64_t); |
| 975 | szs = mSizes[id] + els * overhead; |
| 976 | } |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 977 | totalSize += szs; |
| 978 | output += android::base::StringPrintf("%*s%zu", spaces, "", szs); |
| 979 | spaces -= output.length() - oldLength; |
| 980 | } |
| 981 | spaces += spaces_total; |
| 982 | } |
Mark Salyzyn | 6d981af | 2016-10-06 09:55:21 -0700 | [diff] [blame] | 983 | totalSize += sizeOf(); |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 984 | if (spaces < 0) spaces = 0; |
| 985 | output += android::base::StringPrintf("%*s%zu", spaces, "", totalSize); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 986 | |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 987 | // Report on Chattiest |
Mark Salyzyn | 8e72c53 | 2014-03-26 10:46:39 -0700 | [diff] [blame] | 988 | |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 989 | std::string name; |
| 990 | |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 991 | // Chattiest by application (UID) |
| 992 | log_id_for_each(id) { |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 993 | if (!(logMask & (1 << id))) continue; |
Mark Salyzyn | 8e72c53 | 2014-03-26 10:46:39 -0700 | [diff] [blame] | 994 | |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 995 | name = (uid == AID_ROOT) ? "Chattiest UIDs in %s log buffer:" |
| 996 | : "Logging for your UID in %s log buffer:"; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 997 | output += FormatTable(uidTable[id], uid, pid, name, id); |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 998 | } |
Mark Salyzyn | 8e72c53 | 2014-03-26 10:46:39 -0700 | [diff] [blame] | 999 | |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 1000 | if (enable) { |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 1001 | name = ((uid == AID_ROOT) && !pid) ? "Chattiest PIDs:" |
| 1002 | : "Logging for this PID:"; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 1003 | output += FormatTable(pidTable, uid, pid, name); |
Mark Salyzyn | ee3b838 | 2015-12-17 09:58:43 -0800 | [diff] [blame] | 1004 | name = "Chattiest TIDs"; |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 1005 | if (pid) name += android::base::StringPrintf(" for PID %d", pid); |
Mark Salyzyn | ee3b838 | 2015-12-17 09:58:43 -0800 | [diff] [blame] | 1006 | name += ":"; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 1007 | output += FormatTable(tidTable, uid, pid, name); |
Mark Salyzyn | 17ed679 | 2015-04-20 13:35:15 -0700 | [diff] [blame] | 1008 | } |
| 1009 | |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 1010 | if (enable && (logMask & (1 << LOG_ID_EVENTS))) { |
Mark Salyzyn | ee3b838 | 2015-12-17 09:58:43 -0800 | [diff] [blame] | 1011 | name = "Chattiest events log buffer TAGs"; |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 1012 | if (pid) name += android::base::StringPrintf(" for PID %d", pid); |
Mark Salyzyn | ee3b838 | 2015-12-17 09:58:43 -0800 | [diff] [blame] | 1013 | name += ":"; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 1014 | output += FormatTable(tagTable, uid, pid, name, LOG_ID_EVENTS); |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 1015 | } |
| 1016 | |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 1017 | if (enable && (logMask & (1 << LOG_ID_SECURITY))) { |
Mark Salyzyn | ee3b838 | 2015-12-17 09:58:43 -0800 | [diff] [blame] | 1018 | name = "Chattiest security log buffer TAGs"; |
Mark Salyzyn | 9af33ee | 2016-10-05 12:34:37 -0700 | [diff] [blame] | 1019 | if (pid) name += android::base::StringPrintf(" for PID %d", pid); |
Mark Salyzyn | ee3b838 | 2015-12-17 09:58:43 -0800 | [diff] [blame] | 1020 | name += ":"; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 1021 | output += FormatTable(securityTagTable, uid, pid, name, LOG_ID_SECURITY); |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 1022 | } |
| 1023 | |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 1024 | if (enable) { |
| 1025 | name = "Chattiest TAGs"; |
| 1026 | if (pid) name += android::base::StringPrintf(" for PID %d", pid); |
| 1027 | name += ":"; |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 1028 | output += FormatTable(tagNameTable, uid, pid, name); |
Mark Salyzyn | f99a7d6 | 2017-04-19 14:39:21 -0700 | [diff] [blame] | 1029 | } |
| 1030 | |
Mark Salyzyn | 73160ac | 2015-08-20 10:01:44 -0700 | [diff] [blame] | 1031 | return output; |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 1032 | } |
Mark Salyzyn | 4ba0387 | 2014-04-07 07:15:33 -0700 | [diff] [blame] | 1033 | |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 1034 | namespace android { |
| 1035 | |
| 1036 | uid_t pidToUid(pid_t pid) { |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 1037 | char buffer[512]; |
| 1038 | snprintf(buffer, sizeof(buffer), "/proc/%u/status", pid); |
Tom Cherry | 9b4246d | 2020-06-17 11:40:55 -0700 | [diff] [blame] | 1039 | FILE* fp = fopen(buffer, "re"); |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 1040 | if (fp) { |
| 1041 | while (fgets(buffer, sizeof(buffer), fp)) { |
Mark Salyzyn | c4e4823 | 2017-05-04 13:54:46 -0700 | [diff] [blame] | 1042 | int uid = AID_LOGD; |
| 1043 | char space = 0; |
| 1044 | if ((sscanf(buffer, "Uid: %d%c", &uid, &space) == 2) && |
| 1045 | isspace(space)) { |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 1046 | fclose(fp); |
| 1047 | return uid; |
Mark Salyzyn | 4ba0387 | 2014-04-07 07:15:33 -0700 | [diff] [blame] | 1048 | } |
| 1049 | } |
Mark Salyzyn | 97c1c2b | 2015-03-10 13:51:35 -0700 | [diff] [blame] | 1050 | fclose(fp); |
Mark Salyzyn | 4ba0387 | 2014-04-07 07:15:33 -0700 | [diff] [blame] | 1051 | } |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 1052 | return AID_LOGD; // associate this with the logger |
Mark Salyzyn | 4ba0387 | 2014-04-07 07:15:33 -0700 | [diff] [blame] | 1053 | } |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 1054 | } |
| 1055 | |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 1056 | uid_t LogStatistics::PidToUid(pid_t pid) { |
| 1057 | auto lock = std::lock_guard{lock_}; |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 1058 | return pidTable.Add(pid)->second.uid(); |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 1059 | } |
| 1060 | |
| 1061 | // caller must free character string |
Tom Cherry | 64e9016 | 2020-05-07 14:44:43 -0700 | [diff] [blame] | 1062 | const char* LogStatistics::PidToName(pid_t pid) const { |
| 1063 | auto lock = std::lock_guard{lock_}; |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 1064 | // An inconvenient truth ... getName() can alter the object |
Mark Salyzyn | 501c373 | 2017-03-10 14:31:54 -0800 | [diff] [blame] | 1065 | pidTable_t& writablePidTable = const_cast<pidTable_t&>(pidTable); |
Tom Cherry | 9787f9a | 2020-05-19 19:01:16 -0700 | [diff] [blame] | 1066 | const char* name = writablePidTable.Add(pid)->second.name(); |
Mark Salyzyn | 81b3eab | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 1067 | if (!name) { |
Yi Kong | c8d09dd | 2018-07-13 17:39:22 -0700 | [diff] [blame] | 1068 | return nullptr; |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 1069 | } |
Mark Salyzyn | 81b3eab | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 1070 | return strdup(name); |
Mark Salyzyn | 720f6d1 | 2015-03-16 08:26:05 -0700 | [diff] [blame] | 1071 | } |