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 | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 17 | #include <ctype.h> |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 18 | #include <endian.h> |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 19 | #include <fcntl.h> |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 20 | #include <stdio.h> |
| 21 | #include <string.h> |
| 22 | #include <time.h> |
| 23 | #include <unistd.h> |
| 24 | |
| 25 | #include <log/logger.h> |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 26 | #include <private/android_logger.h> |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 27 | |
Mark Salyzyn | 2ad0bd0 | 2016-02-23 08:55:43 -0800 | [diff] [blame] | 28 | #include "LogBuffer.h" |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 29 | #include "LogBufferElement.h" |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 30 | #include "LogCommand.h" |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 31 | #include "LogReader.h" |
Mark Salyzyn | 2ad0bd0 | 2016-02-23 08:55:43 -0800 | [diff] [blame] | 32 | #include "LogUtils.h" |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 33 | |
Mark Salyzyn | f7c0f75 | 2015-03-03 13:39:37 -0800 | [diff] [blame] | 34 | const uint64_t LogBufferElement::FLUSH_ERROR(0); |
Mark Salyzyn | ee49c6a | 2015-06-12 14:59:42 -0700 | [diff] [blame] | 35 | atomic_int_fast64_t LogBufferElement::sequence(1); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 36 | |
Mark Salyzyn | 7e2f83c | 2014-03-05 07:41:49 -0800 | [diff] [blame] | 37 | LogBufferElement::LogBufferElement(log_id_t log_id, log_time realtime, |
Mark Salyzyn | b992d0d | 2014-03-20 16:09:38 -0700 | [diff] [blame] | 38 | uid_t uid, pid_t pid, pid_t tid, |
Mark Salyzyn | 7718778 | 2015-05-12 15:21:31 -0700 | [diff] [blame] | 39 | const char *msg, unsigned short len) : |
| 40 | mLogId(log_id), |
| 41 | mUid(uid), |
| 42 | mPid(pid), |
| 43 | mTid(tid), |
| 44 | mMsgLen(len), |
| 45 | mSequence(sequence.fetch_add(1, memory_order_relaxed)), |
| 46 | mRealTime(realtime) { |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 47 | mMsg = new char[len]; |
| 48 | memcpy(mMsg, msg, len); |
| 49 | } |
| 50 | |
| 51 | LogBufferElement::~LogBufferElement() { |
| 52 | delete [] mMsg; |
| 53 | } |
| 54 | |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 55 | uint32_t LogBufferElement::getTag() const { |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 56 | if (((mLogId != LOG_ID_EVENTS) && (mLogId != LOG_ID_SECURITY)) || |
| 57 | !mMsg || (mMsgLen < sizeof(uint32_t))) { |
Mark Salyzyn | 344bff4 | 2015-04-13 14:24:45 -0700 | [diff] [blame] | 58 | return 0; |
| 59 | } |
| 60 | return le32toh(reinterpret_cast<android_event_header_t *>(mMsg)->tag); |
| 61 | } |
| 62 | |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 63 | // caller must own and free character string |
Mark Salyzyn | 17ed679 | 2015-04-20 13:35:15 -0700 | [diff] [blame] | 64 | char *android::tidToName(pid_t tid) { |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 65 | char *retval = NULL; |
| 66 | char buffer[256]; |
| 67 | snprintf(buffer, sizeof(buffer), "/proc/%u/comm", tid); |
| 68 | int fd = open(buffer, O_RDONLY); |
| 69 | if (fd >= 0) { |
| 70 | ssize_t ret = read(fd, buffer, sizeof(buffer)); |
| 71 | if (ret >= (ssize_t)sizeof(buffer)) { |
| 72 | ret = sizeof(buffer) - 1; |
| 73 | } |
| 74 | while ((ret > 0) && isspace(buffer[ret - 1])) { |
| 75 | --ret; |
| 76 | } |
| 77 | if (ret > 0) { |
| 78 | buffer[ret] = '\0'; |
| 79 | retval = strdup(buffer); |
| 80 | } |
| 81 | close(fd); |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 82 | } |
| 83 | |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 84 | // if nothing for comm, check out cmdline |
| 85 | char *name = android::pidToName(tid); |
| 86 | if (!retval) { |
| 87 | retval = name; |
| 88 | name = NULL; |
| 89 | } |
| 90 | |
| 91 | // check if comm is truncated, see if cmdline has full representation |
| 92 | if (name) { |
| 93 | // impossible for retval to be NULL if name not NULL |
| 94 | size_t retval_len = strlen(retval); |
| 95 | size_t name_len = strlen(name); |
| 96 | // KISS: ToDo: Only checks prefix truncated, not suffix, or both |
Mark Salyzyn | ddda212 | 2015-10-02 09:22:52 -0700 | [diff] [blame] | 97 | if ((retval_len < name_len) |
| 98 | && !fast<strcmp>(retval, name + name_len - retval_len)) { |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 99 | free(retval); |
| 100 | retval = name; |
| 101 | } else { |
| 102 | free(name); |
| 103 | } |
| 104 | } |
| 105 | return retval; |
| 106 | } |
| 107 | |
| 108 | // assumption: mMsg == NULL |
| 109 | size_t LogBufferElement::populateDroppedMessage(char *&buffer, |
| 110 | LogBuffer *parent) { |
Mark Salyzyn | 047cc07 | 2015-06-04 13:35:30 -0700 | [diff] [blame] | 111 | static const char tag[] = "chatty"; |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 112 | |
Mark Salyzyn | 807e40e | 2016-09-22 09:56:51 -0700 | [diff] [blame^] | 113 | if (!__android_log_is_loggable_len(ANDROID_LOG_INFO, |
| 114 | tag, strlen(tag), |
| 115 | ANDROID_LOG_VERBOSE)) { |
Mark Salyzyn | e59c469 | 2014-10-02 13:07:05 -0700 | [diff] [blame] | 116 | return 0; |
| 117 | } |
| 118 | |
| 119 | static const char format_uid[] = "uid=%u%s%s expire %u line%s"; |
Mark Salyzyn | ed777e9 | 2015-06-24 16:22:54 -0700 | [diff] [blame] | 120 | parent->lock(); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 121 | const char *name = parent->uidToName(mUid); |
Mark Salyzyn | ed777e9 | 2015-06-24 16:22:54 -0700 | [diff] [blame] | 122 | parent->unlock(); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 123 | const char *commName = android::tidToName(mTid); |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 124 | if (!commName && (mTid != mPid)) { |
Mark Salyzyn | 17ed679 | 2015-04-20 13:35:15 -0700 | [diff] [blame] | 125 | commName = android::tidToName(mPid); |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 126 | } |
| 127 | if (!commName) { |
Mark Salyzyn | ed777e9 | 2015-06-24 16:22:54 -0700 | [diff] [blame] | 128 | parent->lock(); |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 129 | commName = parent->pidToName(mPid); |
Mark Salyzyn | ed777e9 | 2015-06-24 16:22:54 -0700 | [diff] [blame] | 130 | parent->unlock(); |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 131 | } |
Mark Salyzyn | ddda212 | 2015-10-02 09:22:52 -0700 | [diff] [blame] | 132 | if (name && name[0] && commName && (name[0] == commName[0])) { |
| 133 | size_t len = strlen(name + 1); |
| 134 | if (!strncmp(name + 1, commName + 1, len)) { |
| 135 | if (commName[len + 1] == '\0') { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 136 | free(const_cast<char *>(commName)); |
Mark Salyzyn | ddda212 | 2015-10-02 09:22:52 -0700 | [diff] [blame] | 137 | commName = NULL; |
| 138 | } else { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 139 | free(const_cast<char *>(name)); |
Mark Salyzyn | ddda212 | 2015-10-02 09:22:52 -0700 | [diff] [blame] | 140 | name = NULL; |
| 141 | } |
Mark Salyzyn | 047cc07 | 2015-06-04 13:35:30 -0700 | [diff] [blame] | 142 | } |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 143 | } |
| 144 | if (name) { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 145 | char *buf = NULL; |
| 146 | asprintf(&buf, "(%s)", name); |
| 147 | if (buf) { |
| 148 | free(const_cast<char *>(name)); |
| 149 | name = buf; |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 150 | } |
| 151 | } |
| 152 | if (commName) { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 153 | char *buf = NULL; |
| 154 | asprintf(&buf, " %s", commName); |
| 155 | if (buf) { |
| 156 | free(const_cast<char *>(commName)); |
| 157 | commName = buf; |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 158 | } |
| 159 | } |
| 160 | // identical to below to calculate the buffer size required |
Mark Salyzyn | ddda212 | 2015-10-02 09:22:52 -0700 | [diff] [blame] | 161 | size_t len = snprintf(NULL, 0, format_uid, mUid, name ? name : "", |
| 162 | commName ? commName : "", |
| 163 | mDropped, (mDropped > 1) ? "s" : ""); |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 164 | |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 165 | size_t hdrLen; |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 166 | // LOG_ID_SECURITY not strictly needed since spam filter not activated, |
| 167 | // but required for accuracy. |
| 168 | if ((mLogId == LOG_ID_EVENTS) || (mLogId == LOG_ID_SECURITY)) { |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 169 | hdrLen = sizeof(android_log_event_string_t); |
| 170 | } else { |
| 171 | hdrLen = 1 + sizeof(tag); |
| 172 | } |
| 173 | |
| 174 | buffer = static_cast<char *>(calloc(1, hdrLen + len + 1)); |
| 175 | if (!buffer) { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 176 | free(const_cast<char *>(name)); |
| 177 | free(const_cast<char *>(commName)); |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 178 | return 0; |
| 179 | } |
| 180 | |
| 181 | size_t retval = hdrLen + len; |
Mark Salyzyn | 083b037 | 2015-12-04 10:59:45 -0800 | [diff] [blame] | 182 | if ((mLogId == LOG_ID_EVENTS) || (mLogId == LOG_ID_SECURITY)) { |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 183 | android_log_event_string_t *event = |
| 184 | reinterpret_cast<android_log_event_string_t *>(buffer); |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 185 | |
Mark Salyzyn | 47684ca | 2016-07-15 10:19:16 -0700 | [diff] [blame] | 186 | event->header.tag = htole32(CHATTY_LOG_TAG); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 187 | event->type = EVENT_TYPE_STRING; |
| 188 | event->length = htole32(len); |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 189 | } else { |
| 190 | ++retval; |
| 191 | buffer[0] = ANDROID_LOG_INFO; |
| 192 | strcpy(buffer + 1, tag); |
| 193 | } |
| 194 | |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 195 | snprintf(buffer + hdrLen, len + 1, format_uid, mUid, name ? name : "", |
| 196 | commName ? commName : "", |
| 197 | mDropped, (mDropped > 1) ? "s" : ""); |
Mark Salyzyn | 758058f | 2015-08-21 16:44:30 -0700 | [diff] [blame] | 198 | free(const_cast<char *>(name)); |
| 199 | free(const_cast<char *>(commName)); |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 200 | |
| 201 | return retval; |
| 202 | } |
| 203 | |
Mark Salyzyn | 7b87365 | 2015-12-03 15:38:35 -0800 | [diff] [blame] | 204 | uint64_t LogBufferElement::flushTo(SocketClient *reader, LogBuffer *parent, |
| 205 | bool privileged) { |
| 206 | struct logger_entry_v4 entry; |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 207 | |
Mark Salyzyn | 7b87365 | 2015-12-03 15:38:35 -0800 | [diff] [blame] | 208 | memset(&entry, 0, sizeof(struct logger_entry_v4)); |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 209 | |
Mark Salyzyn | 7b87365 | 2015-12-03 15:38:35 -0800 | [diff] [blame] | 210 | entry.hdr_size = privileged ? |
| 211 | sizeof(struct logger_entry_v4) : |
| 212 | sizeof(struct logger_entry_v3); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 213 | entry.lid = mLogId; |
| 214 | entry.pid = mPid; |
Mark Salyzyn | b992d0d | 2014-03-20 16:09:38 -0700 | [diff] [blame] | 215 | entry.tid = mTid; |
Mark Salyzyn | 7b87365 | 2015-12-03 15:38:35 -0800 | [diff] [blame] | 216 | entry.uid = mUid; |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 217 | entry.sec = mRealTime.tv_sec; |
| 218 | entry.nsec = mRealTime.tv_nsec; |
| 219 | |
| 220 | struct iovec iovec[2]; |
| 221 | iovec[0].iov_base = &entry; |
Mark Salyzyn | 7b87365 | 2015-12-03 15:38:35 -0800 | [diff] [blame] | 222 | iovec[0].iov_len = entry.hdr_size; |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 223 | |
| 224 | char *buffer = NULL; |
| 225 | |
| 226 | if (!mMsg) { |
Mark Salyzyn | 21fb7e0 | 2015-04-20 07:26:27 -0700 | [diff] [blame] | 227 | entry.len = populateDroppedMessage(buffer, parent); |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 228 | if (!entry.len) { |
| 229 | return mSequence; |
| 230 | } |
| 231 | iovec[1].iov_base = buffer; |
| 232 | } else { |
| 233 | entry.len = mMsgLen; |
| 234 | iovec[1].iov_base = mMsg; |
| 235 | } |
| 236 | iovec[1].iov_len = entry.len; |
| 237 | |
| 238 | uint64_t retval = reader->sendDatav(iovec, 2) ? FLUSH_ERROR : mSequence; |
| 239 | |
| 240 | if (buffer) { |
| 241 | free(buffer); |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 242 | } |
| 243 | |
Mark Salyzyn | ab0dcf6 | 2015-03-16 12:04:09 -0700 | [diff] [blame] | 244 | return retval; |
Mark Salyzyn | 0175b07 | 2014-02-26 09:50:16 -0800 | [diff] [blame] | 245 | } |