Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 1 | /* |
| 2 | * Copyright (C) 2012-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 | |
Mark Salyzyn | 671e343 | 2014-05-06 07:34:59 -0700 | [diff] [blame] | 17 | #include <ctype.h> |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 18 | #include <stdio.h> |
Mark Salyzyn | 671e343 | 2014-05-06 07:34:59 -0700 | [diff] [blame] | 19 | #include <stdlib.h> |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 20 | #include <string.h> |
Mark Salyzyn | 57a0af9 | 2014-05-09 17:44:18 -0700 | [diff] [blame] | 21 | #include <sys/user.h> |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 22 | #include <time.h> |
| 23 | #include <unistd.h> |
| 24 | |
Mark Salyzyn | 671e343 | 2014-05-06 07:34:59 -0700 | [diff] [blame] | 25 | #include <cutils/properties.h> |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 26 | #include <log/logger.h> |
| 27 | |
| 28 | #include "LogBuffer.h" |
Mark Salyzyn | 671e343 | 2014-05-06 07:34:59 -0700 | [diff] [blame] | 29 | #include "LogReader.h" |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 30 | #include "LogStatistics.h" |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 31 | #include "LogWhiteBlackList.h" |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 32 | |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 33 | // Default |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 34 | #define LOG_BUFFER_SIZE (256 * 1024) // Tuned on a per-platform basis here? |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 35 | #define log_buffer_size(id) mMaxSize[id] |
Mark Salyzyn | 57a0af9 | 2014-05-09 17:44:18 -0700 | [diff] [blame] | 36 | #define LOG_BUFFER_MIN_SIZE (64 * 1024UL) |
| 37 | #define LOG_BUFFER_MAX_SIZE (256 * 1024 * 1024UL) |
| 38 | |
| 39 | static bool valid_size(unsigned long value) { |
| 40 | if ((value < LOG_BUFFER_MIN_SIZE) || (LOG_BUFFER_MAX_SIZE < value)) { |
| 41 | return false; |
| 42 | } |
| 43 | |
| 44 | long pages = sysconf(_SC_PHYS_PAGES); |
| 45 | if (pages < 1) { |
| 46 | return true; |
| 47 | } |
| 48 | |
| 49 | long pagesize = sysconf(_SC_PAGESIZE); |
| 50 | if (pagesize <= 1) { |
| 51 | pagesize = PAGE_SIZE; |
| 52 | } |
| 53 | |
| 54 | // maximum memory impact a somewhat arbitrary ~3% |
| 55 | pages = (pages + 31) / 32; |
| 56 | unsigned long maximum = pages * pagesize; |
| 57 | |
| 58 | if ((maximum < LOG_BUFFER_MIN_SIZE) || (LOG_BUFFER_MAX_SIZE < maximum)) { |
| 59 | return true; |
| 60 | } |
| 61 | |
| 62 | return value <= maximum; |
| 63 | } |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 64 | |
Mark Salyzyn | 671e343 | 2014-05-06 07:34:59 -0700 | [diff] [blame] | 65 | static unsigned long property_get_size(const char *key) { |
| 66 | char property[PROPERTY_VALUE_MAX]; |
| 67 | property_get(key, property, ""); |
| 68 | |
| 69 | char *cp; |
| 70 | unsigned long value = strtoul(property, &cp, 10); |
| 71 | |
| 72 | switch(*cp) { |
| 73 | case 'm': |
| 74 | case 'M': |
| 75 | value *= 1024; |
| 76 | /* FALLTHRU */ |
| 77 | case 'k': |
| 78 | case 'K': |
| 79 | value *= 1024; |
| 80 | /* FALLTHRU */ |
| 81 | case '\0': |
| 82 | break; |
| 83 | |
| 84 | default: |
| 85 | value = 0; |
| 86 | } |
| 87 | |
Mark Salyzyn | 57a0af9 | 2014-05-09 17:44:18 -0700 | [diff] [blame] | 88 | if (!valid_size(value)) { |
| 89 | value = 0; |
| 90 | } |
| 91 | |
Mark Salyzyn | 671e343 | 2014-05-06 07:34:59 -0700 | [diff] [blame] | 92 | return value; |
| 93 | } |
| 94 | |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 95 | LogBuffer::LogBuffer(LastLogTimes *times) |
Mark Salyzyn | 4ed16b4 | 2015-03-03 11:05:06 -0800 | [diff] [blame] | 96 | : mTimes(*times) { |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 97 | pthread_mutex_init(&mLogElementsLock, NULL); |
Mark Salyzyn | e457b74 | 2014-02-19 17:18:31 -0800 | [diff] [blame] | 98 | |
Mark Salyzyn | 57a0af9 | 2014-05-09 17:44:18 -0700 | [diff] [blame] | 99 | static const char global_tuneable[] = "persist.logd.size"; // Settings App |
| 100 | static const char global_default[] = "ro.logd.size"; // BoardConfig.mk |
| 101 | |
| 102 | unsigned long default_size = property_get_size(global_tuneable); |
| 103 | if (!default_size) { |
| 104 | default_size = property_get_size(global_default); |
| 105 | } |
Mark Salyzyn | 671e343 | 2014-05-06 07:34:59 -0700 | [diff] [blame] | 106 | |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 107 | log_id_for_each(i) { |
Mark Salyzyn | 671e343 | 2014-05-06 07:34:59 -0700 | [diff] [blame] | 108 | char key[PROP_NAME_MAX]; |
Mark Salyzyn | 671e343 | 2014-05-06 07:34:59 -0700 | [diff] [blame] | 109 | |
Mark Salyzyn | 57a0af9 | 2014-05-09 17:44:18 -0700 | [diff] [blame] | 110 | snprintf(key, sizeof(key), "%s.%s", |
| 111 | global_tuneable, android_log_id_to_name(i)); |
| 112 | unsigned long property_size = property_get_size(key); |
| 113 | |
| 114 | if (!property_size) { |
| 115 | snprintf(key, sizeof(key), "%s.%s", |
| 116 | global_default, android_log_id_to_name(i)); |
| 117 | property_size = property_get_size(key); |
| 118 | } |
| 119 | |
| 120 | if (!property_size) { |
| 121 | property_size = default_size; |
| 122 | } |
| 123 | |
| 124 | if (!property_size) { |
| 125 | property_size = LOG_BUFFER_SIZE; |
| 126 | } |
| 127 | |
| 128 | if (setSize(i, property_size)) { |
| 129 | setSize(i, LOG_BUFFER_MIN_SIZE); |
| 130 | } |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 131 | } |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 132 | } |
| 133 | |
Mark Salyzyn | 7e2f83c | 2014-03-05 07:41:49 -0800 | [diff] [blame] | 134 | void LogBuffer::log(log_id_t log_id, log_time realtime, |
Mark Salyzyn | b992d0d | 2014-03-20 16:09:38 -0700 | [diff] [blame] | 135 | uid_t uid, pid_t pid, pid_t tid, |
| 136 | const char *msg, unsigned short len) { |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 137 | if ((log_id >= LOG_ID_MAX) || (log_id < 0)) { |
| 138 | return; |
| 139 | } |
| 140 | LogBufferElement *elem = new LogBufferElement(log_id, realtime, |
Mark Salyzyn | b992d0d | 2014-03-20 16:09:38 -0700 | [diff] [blame] | 141 | uid, pid, tid, msg, len); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 142 | |
| 143 | pthread_mutex_lock(&mLogElementsLock); |
| 144 | |
| 145 | // Insert elements in time sorted order if possible |
| 146 | // NB: if end is region locked, place element at end of list |
| 147 | LogBufferElementCollection::iterator it = mLogElements.end(); |
| 148 | LogBufferElementCollection::iterator last = it; |
Mark Salyzyn | eae155e | 2014-10-13 16:49:47 -0700 | [diff] [blame] | 149 | while (last != mLogElements.begin()) { |
| 150 | --it; |
Mark Salyzyn | c03e72c | 2014-02-18 11:23:53 -0800 | [diff] [blame] | 151 | if ((*it)->getRealTime() <= realtime) { |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 152 | break; |
| 153 | } |
| 154 | last = it; |
| 155 | } |
Mark Salyzyn | c03e72c | 2014-02-18 11:23:53 -0800 | [diff] [blame] | 156 | |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 157 | if (last == mLogElements.end()) { |
| 158 | mLogElements.push_back(elem); |
| 159 | } else { |
Mark Salyzyn | ab4b730 | 2014-05-22 16:07:31 -0700 | [diff] [blame] | 160 | log_time end = log_time::EPOCH; |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 161 | bool end_set = false; |
| 162 | bool end_always = false; |
| 163 | |
| 164 | LogTimeEntry::lock(); |
| 165 | |
| 166 | LastLogTimes::iterator t = mTimes.begin(); |
| 167 | while(t != mTimes.end()) { |
| 168 | LogTimeEntry *entry = (*t); |
| 169 | if (entry->owned_Locked()) { |
| 170 | if (!entry->mNonBlock) { |
| 171 | end_always = true; |
| 172 | break; |
| 173 | } |
| 174 | if (!end_set || (end <= entry->mEnd)) { |
| 175 | end = entry->mEnd; |
| 176 | end_set = true; |
| 177 | } |
| 178 | } |
| 179 | t++; |
| 180 | } |
| 181 | |
| 182 | if (end_always |
Mark Salyzyn | c03e72c | 2014-02-18 11:23:53 -0800 | [diff] [blame] | 183 | || (end_set && (end >= (*last)->getMonotonicTime()))) { |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 184 | mLogElements.push_back(elem); |
| 185 | } else { |
| 186 | mLogElements.insert(last,elem); |
| 187 | } |
| 188 | |
| 189 | LogTimeEntry::unlock(); |
| 190 | } |
| 191 | |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 192 | stats.add(len, log_id, uid, pid); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 193 | maybePrune(log_id); |
| 194 | pthread_mutex_unlock(&mLogElementsLock); |
| 195 | } |
| 196 | |
| 197 | // If we're using more than 256K of memory for log entries, prune |
Mark Salyzyn | 740f9b4 | 2014-01-13 16:37:51 -0800 | [diff] [blame] | 198 | // at least 10% of the log entries. |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 199 | // |
| 200 | // mLogElementsLock must be held when this function is called. |
| 201 | void LogBuffer::maybePrune(log_id_t id) { |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 202 | size_t sizes = stats.sizes(id); |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 203 | if (sizes > log_buffer_size(id)) { |
| 204 | size_t sizeOver90Percent = sizes - ((log_buffer_size(id) * 9) / 10); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 205 | size_t elements = stats.elements(id); |
Mark Salyzyn | 740f9b4 | 2014-01-13 16:37:51 -0800 | [diff] [blame] | 206 | unsigned long pruneRows = elements * sizeOver90Percent / sizes; |
| 207 | elements /= 10; |
| 208 | if (pruneRows <= elements) { |
| 209 | pruneRows = elements; |
| 210 | } |
| 211 | prune(id, pruneRows); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 212 | } |
| 213 | } |
| 214 | |
| 215 | // prune "pruneRows" of type "id" from the buffer. |
| 216 | // |
| 217 | // mLogElementsLock must be held when this function is called. |
Mark Salyzyn | 1a240b4 | 2014-06-12 11:16:16 -0700 | [diff] [blame] | 218 | void LogBuffer::prune(log_id_t id, unsigned long pruneRows, uid_t caller_uid) { |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 219 | LogTimeEntry *oldest = NULL; |
| 220 | |
| 221 | LogTimeEntry::lock(); |
| 222 | |
| 223 | // Region locked? |
| 224 | LastLogTimes::iterator t = mTimes.begin(); |
| 225 | while(t != mTimes.end()) { |
| 226 | LogTimeEntry *entry = (*t); |
TraianX Schiau | da6495d | 2014-12-17 10:53:41 +0200 | [diff] [blame] | 227 | if (entry->owned_Locked() && entry->isWatching(id) |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 228 | && (!oldest || (oldest->mStart > entry->mStart))) { |
| 229 | oldest = entry; |
| 230 | } |
| 231 | t++; |
| 232 | } |
| 233 | |
Mark Salyzyn | 64d6fe9 | 2014-02-06 18:11:13 -0800 | [diff] [blame] | 234 | LogBufferElementCollection::iterator it; |
| 235 | |
Mark Salyzyn | 1a240b4 | 2014-06-12 11:16:16 -0700 | [diff] [blame] | 236 | if (caller_uid != AID_ROOT) { |
| 237 | for(it = mLogElements.begin(); it != mLogElements.end();) { |
| 238 | LogBufferElement *e = *it; |
| 239 | |
| 240 | if (oldest && (oldest->mStart <= e->getMonotonicTime())) { |
| 241 | break; |
| 242 | } |
| 243 | |
| 244 | if (e->getLogId() != id) { |
| 245 | ++it; |
| 246 | continue; |
| 247 | } |
| 248 | |
| 249 | uid_t uid = e->getUid(); |
| 250 | |
| 251 | if (uid == caller_uid) { |
| 252 | it = mLogElements.erase(it); |
Mark Salyzyn | e72c6e4 | 2014-09-21 14:22:18 -0700 | [diff] [blame] | 253 | stats.subtract(e->getMsgLen(), id, uid, e->getPid()); |
Mark Salyzyn | 1a240b4 | 2014-06-12 11:16:16 -0700 | [diff] [blame] | 254 | delete e; |
| 255 | pruneRows--; |
| 256 | if (pruneRows == 0) { |
| 257 | break; |
| 258 | } |
| 259 | } else { |
| 260 | ++it; |
| 261 | } |
| 262 | } |
| 263 | LogTimeEntry::unlock(); |
| 264 | return; |
| 265 | } |
| 266 | |
Mark Salyzyn | 64d6fe9 | 2014-02-06 18:11:13 -0800 | [diff] [blame] | 267 | // prune by worst offender by uid |
| 268 | while (pruneRows > 0) { |
| 269 | // recalculate the worst offender on every batched pass |
| 270 | uid_t worst = (uid_t) -1; |
| 271 | size_t worst_sizes = 0; |
| 272 | size_t second_worst_sizes = 0; |
| 273 | |
Mark Salyzyn | 99f47a9 | 2014-04-07 14:58:08 -0700 | [diff] [blame] | 274 | if ((id != LOG_ID_CRASH) && mPrune.worstUidEnabled()) { |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 275 | LidStatistics &l = stats.id(id); |
Mark Salyzyn | c8a576c | 2014-04-04 16:35:59 -0700 | [diff] [blame] | 276 | l.sort(); |
| 277 | UidStatisticsCollection::iterator iu = l.begin(); |
| 278 | if (iu != l.end()) { |
| 279 | UidStatistics *u = *iu; |
| 280 | worst = u->getUid(); |
| 281 | worst_sizes = u->sizes(); |
| 282 | if (++iu != l.end()) { |
| 283 | second_worst_sizes = (*iu)->sizes(); |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 284 | } |
Mark Salyzyn | 64d6fe9 | 2014-02-06 18:11:13 -0800 | [diff] [blame] | 285 | } |
| 286 | } |
| 287 | |
| 288 | bool kick = false; |
| 289 | for(it = mLogElements.begin(); it != mLogElements.end();) { |
| 290 | LogBufferElement *e = *it; |
| 291 | |
| 292 | if (oldest && (oldest->mStart <= e->getMonotonicTime())) { |
| 293 | break; |
| 294 | } |
| 295 | |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 296 | if (e->getLogId() != id) { |
| 297 | ++it; |
| 298 | continue; |
| 299 | } |
| 300 | |
| 301 | uid_t uid = e->getUid(); |
| 302 | |
Mark Salyzyn | e72c6e4 | 2014-09-21 14:22:18 -0700 | [diff] [blame] | 303 | if ((uid == worst) || mPrune.naughty(e)) { // Worst or BlackListed |
Mark Salyzyn | 64d6fe9 | 2014-02-06 18:11:13 -0800 | [diff] [blame] | 304 | it = mLogElements.erase(it); |
| 305 | unsigned short len = e->getMsgLen(); |
Mark Salyzyn | e72c6e4 | 2014-09-21 14:22:18 -0700 | [diff] [blame] | 306 | stats.subtract(len, id, uid, e->getPid()); |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 307 | delete e; |
| 308 | pruneRows--; |
Mark Salyzyn | e72c6e4 | 2014-09-21 14:22:18 -0700 | [diff] [blame] | 309 | if (uid == worst) { |
| 310 | kick = true; |
| 311 | if ((pruneRows == 0) || (worst_sizes < second_worst_sizes)) { |
| 312 | break; |
| 313 | } |
| 314 | worst_sizes -= len; |
| 315 | } else if (pruneRows == 0) { |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 316 | break; |
| 317 | } |
Mark Salyzyn | 1c95047 | 2014-04-01 17:19:47 -0700 | [diff] [blame] | 318 | } else { |
Mark Salyzyn | 64d6fe9 | 2014-02-06 18:11:13 -0800 | [diff] [blame] | 319 | ++it; |
| 320 | } |
| 321 | } |
| 322 | |
Mark Salyzyn | 1c95047 | 2014-04-01 17:19:47 -0700 | [diff] [blame] | 323 | if (!kick || !mPrune.worstUidEnabled()) { |
Mark Salyzyn | 64d6fe9 | 2014-02-06 18:11:13 -0800 | [diff] [blame] | 324 | break; // the following loop will ask bad clients to skip/drop |
| 325 | } |
| 326 | } |
| 327 | |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 328 | bool whitelist = false; |
Mark Salyzyn | 64d6fe9 | 2014-02-06 18:11:13 -0800 | [diff] [blame] | 329 | it = mLogElements.begin(); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 330 | while((pruneRows > 0) && (it != mLogElements.end())) { |
| 331 | LogBufferElement *e = *it; |
| 332 | if (e->getLogId() == id) { |
| 333 | if (oldest && (oldest->mStart <= e->getMonotonicTime())) { |
Mark Salyzyn | 1c95047 | 2014-04-01 17:19:47 -0700 | [diff] [blame] | 334 | if (!whitelist) { |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 335 | if (stats.sizes(id) > (2 * log_buffer_size(id))) { |
| 336 | // kick a misbehaving log reader client off the island |
| 337 | oldest->release_Locked(); |
| 338 | } else { |
TraianX Schiau | da6495d | 2014-12-17 10:53:41 +0200 | [diff] [blame] | 339 | oldest->triggerSkip_Locked(id, pruneRows); |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 340 | } |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 341 | } |
| 342 | break; |
| 343 | } |
Mark Salyzyn | 1c95047 | 2014-04-01 17:19:47 -0700 | [diff] [blame] | 344 | |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 345 | if (mPrune.nice(e)) { // WhiteListed |
| 346 | whitelist = true; |
| 347 | it++; |
| 348 | continue; |
| 349 | } |
Mark Salyzyn | 1c95047 | 2014-04-01 17:19:47 -0700 | [diff] [blame] | 350 | |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 351 | it = mLogElements.erase(it); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 352 | stats.subtract(e->getMsgLen(), id, e->getUid(), e->getPid()); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 353 | delete e; |
| 354 | pruneRows--; |
| 355 | } else { |
| 356 | it++; |
| 357 | } |
| 358 | } |
| 359 | |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 360 | if (whitelist && (pruneRows > 0)) { |
| 361 | it = mLogElements.begin(); |
| 362 | while((it != mLogElements.end()) && (pruneRows > 0)) { |
| 363 | LogBufferElement *e = *it; |
| 364 | if (e->getLogId() == id) { |
| 365 | if (oldest && (oldest->mStart <= e->getMonotonicTime())) { |
| 366 | if (stats.sizes(id) > (2 * log_buffer_size(id))) { |
| 367 | // kick a misbehaving log reader client off the island |
| 368 | oldest->release_Locked(); |
| 369 | } else { |
TraianX Schiau | da6495d | 2014-12-17 10:53:41 +0200 | [diff] [blame] | 370 | oldest->triggerSkip_Locked(id, pruneRows); |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 371 | } |
| 372 | break; |
| 373 | } |
| 374 | it = mLogElements.erase(it); |
| 375 | stats.subtract(e->getMsgLen(), id, e->getUid(), e->getPid()); |
| 376 | delete e; |
| 377 | pruneRows--; |
| 378 | } else { |
| 379 | it++; |
| 380 | } |
| 381 | } |
| 382 | } |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 383 | |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 384 | LogTimeEntry::unlock(); |
| 385 | } |
| 386 | |
| 387 | // clear all rows of type "id" from the buffer. |
Mark Salyzyn | 1a240b4 | 2014-06-12 11:16:16 -0700 | [diff] [blame] | 388 | void LogBuffer::clear(log_id_t id, uid_t uid) { |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 389 | pthread_mutex_lock(&mLogElementsLock); |
Mark Salyzyn | 1a240b4 | 2014-06-12 11:16:16 -0700 | [diff] [blame] | 390 | prune(id, ULONG_MAX, uid); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 391 | pthread_mutex_unlock(&mLogElementsLock); |
| 392 | } |
| 393 | |
| 394 | // get the used space associated with "id". |
| 395 | unsigned long LogBuffer::getSizeUsed(log_id_t id) { |
| 396 | pthread_mutex_lock(&mLogElementsLock); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 397 | size_t retval = stats.sizes(id); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 398 | pthread_mutex_unlock(&mLogElementsLock); |
| 399 | return retval; |
| 400 | } |
| 401 | |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 402 | // set the total space allocated to "id" |
| 403 | int LogBuffer::setSize(log_id_t id, unsigned long size) { |
| 404 | // Reasonable limits ... |
Mark Salyzyn | 57a0af9 | 2014-05-09 17:44:18 -0700 | [diff] [blame] | 405 | if (!valid_size(size)) { |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 406 | return -1; |
| 407 | } |
| 408 | pthread_mutex_lock(&mLogElementsLock); |
| 409 | log_buffer_size(id) = size; |
| 410 | pthread_mutex_unlock(&mLogElementsLock); |
| 411 | return 0; |
| 412 | } |
| 413 | |
| 414 | // get the total space allocated to "id" |
| 415 | unsigned long LogBuffer::getSize(log_id_t id) { |
| 416 | pthread_mutex_lock(&mLogElementsLock); |
| 417 | size_t retval = log_buffer_size(id); |
| 418 | pthread_mutex_unlock(&mLogElementsLock); |
| 419 | return retval; |
| 420 | } |
| 421 | |
Mark Salyzyn | 7e2f83c | 2014-03-05 07:41:49 -0800 | [diff] [blame] | 422 | log_time LogBuffer::flushTo( |
| 423 | SocketClient *reader, const log_time start, bool privileged, |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 424 | bool (*filter)(const LogBufferElement *element, void *arg), void *arg) { |
| 425 | LogBufferElementCollection::iterator it; |
| 426 | log_time max = start; |
| 427 | uid_t uid = reader->getUid(); |
| 428 | |
| 429 | pthread_mutex_lock(&mLogElementsLock); |
Dragoslav Mitrinovic | 8e8e8db | 2015-01-15 09:29:43 -0600 | [diff] [blame] | 430 | |
| 431 | if (start == LogTimeEntry::EPOCH) { |
| 432 | // client wants to start from the beginning |
| 433 | it = mLogElements.begin(); |
| 434 | } else { |
| 435 | // Client wants to start from some specified time. Chances are |
| 436 | // we are better off starting from the end of the time sorted list. |
| 437 | for (it = mLogElements.end(); it != mLogElements.begin(); /* do nothing */) { |
| 438 | --it; |
| 439 | LogBufferElement *element = *it; |
| 440 | if (element->getMonotonicTime() <= start) { |
| 441 | it++; |
| 442 | break; |
| 443 | } |
| 444 | } |
| 445 | } |
| 446 | |
| 447 | for (; it != mLogElements.end(); ++it) { |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 448 | LogBufferElement *element = *it; |
| 449 | |
| 450 | if (!privileged && (element->getUid() != uid)) { |
| 451 | continue; |
| 452 | } |
| 453 | |
| 454 | if (element->getMonotonicTime() <= start) { |
| 455 | continue; |
| 456 | } |
| 457 | |
| 458 | // NB: calling out to another object with mLogElementsLock held (safe) |
| 459 | if (filter && !(*filter)(element, arg)) { |
| 460 | continue; |
| 461 | } |
| 462 | |
| 463 | pthread_mutex_unlock(&mLogElementsLock); |
| 464 | |
| 465 | // range locking in LastLogTimes looks after us |
| 466 | max = element->flushTo(reader); |
| 467 | |
| 468 | if (max == element->FLUSH_ERROR) { |
| 469 | return max; |
| 470 | } |
| 471 | |
| 472 | pthread_mutex_lock(&mLogElementsLock); |
| 473 | } |
| 474 | pthread_mutex_unlock(&mLogElementsLock); |
| 475 | |
| 476 | return max; |
| 477 | } |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 478 | |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 479 | void LogBuffer::formatStatistics(char **strp, uid_t uid, unsigned int logMask) { |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 480 | log_time oldest(CLOCK_MONOTONIC); |
| 481 | |
| 482 | pthread_mutex_lock(&mLogElementsLock); |
| 483 | |
| 484 | // Find oldest element in the log(s) |
| 485 | LogBufferElementCollection::iterator it; |
| 486 | for (it = mLogElements.begin(); it != mLogElements.end(); ++it) { |
| 487 | LogBufferElement *element = *it; |
| 488 | |
| 489 | if ((logMask & (1 << element->getLogId()))) { |
| 490 | oldest = element->getMonotonicTime(); |
| 491 | break; |
| 492 | } |
| 493 | } |
| 494 | |
Mark Salyzyn | dfa7a07 | 2014-02-11 12:29:31 -0800 | [diff] [blame] | 495 | stats.format(strp, uid, logMask, oldest); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 496 | |
| 497 | pthread_mutex_unlock(&mLogElementsLock); |
Mark Salyzyn | 34facab | 2014-02-06 14:48:50 -0800 | [diff] [blame] | 498 | } |