| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 1 | /* | 
 | 2 |  * Copyright (C) 2017 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 |  | 
 | 17 | /* | 
 | 18 |  * stderr write handler.  Output is logcat-like, and responds to | 
 | 19 |  * logcat's environment variables ANDROID_PRINTF_LOG and | 
 | 20 |  * ANDROID_LOG_TAGS to filter output. | 
 | 21 |  * | 
 | 22 |  * This transport only provides a writer, that means that it does not | 
 | 23 |  * provide an End-To-End capability as the logs are effectively _lost_ | 
 | 24 |  * to the stderr file stream.  The purpose of this transport is to | 
 | 25 |  * supply a means for command line tools to report their logging | 
 | 26 |  * to the stderr stream, in line with all other activities. | 
 | 27 |  */ | 
 | 28 |  | 
 | 29 | #include <errno.h> | 
 | 30 | #include <stdbool.h> | 
 | 31 | #include <stdio.h> | 
 | 32 | #include <stdlib.h> | 
 | 33 | #include <string.h> | 
 | 34 | #include <sys/types.h> | 
 | 35 | #include <unistd.h> | 
 | 36 |  | 
 | 37 | #include <log/event_tag_map.h> | 
 | 38 | #include <log/log.h> | 
 | 39 | #include <log/logprint.h> | 
 | 40 | #include <log/uio.h> | 
 | 41 |  | 
 | 42 | #include "log_portability.h" | 
 | 43 | #include "logger.h" | 
 | 44 |  | 
 | 45 | static int stderrOpen(); | 
 | 46 | static void stderrClose(); | 
 | 47 | static int stderrAvailable(log_id_t logId); | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 48 | static int stderrWrite(log_id_t logId, struct timespec* ts, struct iovec* vec, | 
 | 49 |                        size_t nr); | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 50 |  | 
 | 51 | struct stderrContext { | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 52 |   AndroidLogFormat* logformat; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 53 | #if defined(__ANDROID__) | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 54 |   EventTagMap* eventTagMap; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 55 | #endif | 
 | 56 | }; | 
 | 57 |  | 
 | 58 | LIBLOG_HIDDEN struct android_log_transport_write stderrLoggerWrite = { | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 59 |   .node = { &stderrLoggerWrite.node, &stderrLoggerWrite.node }, | 
| Mark Salyzyn | 04bbc8e | 2017-03-08 15:03:20 -0800 | [diff] [blame] | 60 |   .context.priv = NULL, | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 61 |   .name = "stderr", | 
 | 62 |   .available = stderrAvailable, | 
 | 63 |   .open = stderrOpen, | 
 | 64 |   .close = stderrClose, | 
 | 65 |   .write = stderrWrite, | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 66 | }; | 
 | 67 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 68 | static int stderrOpen() { | 
 | 69 |   struct stderrContext* ctx; | 
 | 70 |   const char* envStr; | 
 | 71 |   bool setFormat; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 72 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 73 |   if (!stderr || (fileno(stderr) < 0)) { | 
 | 74 |     return -EBADF; | 
 | 75 |   } | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 76 |  | 
| Mark Salyzyn | 04bbc8e | 2017-03-08 15:03:20 -0800 | [diff] [blame] | 77 |   if (stderrLoggerWrite.context.priv) { | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 78 |     return fileno(stderr); | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 79 |   } | 
 | 80 |  | 
 | 81 |   ctx = calloc(1, sizeof(struct stderrContext)); | 
 | 82 |   if (!ctx) { | 
 | 83 |     return -ENOMEM; | 
 | 84 |   } | 
 | 85 |  | 
 | 86 |   ctx->logformat = android_log_format_new(); | 
 | 87 |   if (!ctx->logformat) { | 
 | 88 |     free(ctx); | 
 | 89 |     return -ENOMEM; | 
 | 90 |   } | 
 | 91 |  | 
 | 92 |   envStr = getenv("ANDROID_PRINTF_LOG"); | 
 | 93 |   setFormat = false; | 
 | 94 |  | 
 | 95 |   if (envStr) { | 
 | 96 |     char* formats = strdup(envStr); | 
 | 97 |     char* sv = NULL; | 
 | 98 |     char* arg = formats; | 
 | 99 |     while (!!(arg = strtok_r(arg, ",:; \t\n\r\f", &sv))) { | 
 | 100 |       AndroidLogPrintFormat format = android_log_formatFromString(arg); | 
 | 101 |       arg = NULL; | 
 | 102 |       if (format == FORMAT_OFF) { | 
 | 103 |         continue; | 
 | 104 |       } | 
 | 105 |       if (android_log_setPrintFormat(ctx->logformat, format) <= 0) { | 
 | 106 |         continue; | 
 | 107 |       } | 
 | 108 |       setFormat = true; | 
 | 109 |     } | 
 | 110 |     free(formats); | 
 | 111 |   } | 
 | 112 |   if (!setFormat) { | 
 | 113 |     AndroidLogPrintFormat format = android_log_formatFromString("threadtime"); | 
 | 114 |     android_log_setPrintFormat(ctx->logformat, format); | 
 | 115 |   } | 
 | 116 |   envStr = getenv("ANDROID_LOG_TAGS"); | 
 | 117 |   if (envStr) { | 
 | 118 |     android_log_addFilterString(ctx->logformat, envStr); | 
 | 119 |   } | 
| Mark Salyzyn | 04bbc8e | 2017-03-08 15:03:20 -0800 | [diff] [blame] | 120 |   stderrLoggerWrite.context.priv = ctx; | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 121 |  | 
 | 122 |   return fileno(stderr); | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 123 | } | 
 | 124 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 125 | static void stderrClose() { | 
| Mark Salyzyn | 04bbc8e | 2017-03-08 15:03:20 -0800 | [diff] [blame] | 126 |   struct stderrContext* ctx = stderrLoggerWrite.context.priv; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 127 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 128 |   if (ctx) { | 
| Mark Salyzyn | 04bbc8e | 2017-03-08 15:03:20 -0800 | [diff] [blame] | 129 |     stderrLoggerWrite.context.priv = NULL; | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 130 |     if (ctx->logformat) { | 
 | 131 |       android_log_format_free(ctx->logformat); | 
 | 132 |       ctx->logformat = NULL; | 
 | 133 |     } | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 134 | #if defined(__ANDROID__) | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 135 |     if (ctx->eventTagMap) { | 
 | 136 |       android_closeEventTagMap(ctx->eventTagMap); | 
 | 137 |       ctx->eventTagMap = NULL; | 
 | 138 |     } | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 139 | #endif | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 140 |   } | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 141 | } | 
 | 142 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 143 | static int stderrAvailable(log_id_t logId) { | 
 | 144 |   if ((logId >= LOG_ID_MAX) || (logId == LOG_ID_KERNEL)) { | 
 | 145 |     return -EINVAL; | 
 | 146 |   } | 
 | 147 |   return 1; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 148 | } | 
 | 149 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 150 | static int stderrWrite(log_id_t logId, struct timespec* ts, struct iovec* vec, | 
 | 151 |                        size_t nr) { | 
 | 152 |   struct log_msg log_msg; | 
 | 153 |   AndroidLogEntry entry; | 
 | 154 |   char binaryMsgBuf[1024]; | 
 | 155 |   int err; | 
 | 156 |   size_t i; | 
| Mark Salyzyn | 04bbc8e | 2017-03-08 15:03:20 -0800 | [diff] [blame] | 157 |   struct stderrContext* ctx = stderrLoggerWrite.context.priv; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 158 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 159 |   if (!ctx) return -EBADF; | 
 | 160 |   if (!vec || !nr) return -EINVAL; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 161 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 162 |   log_msg.entry.len = 0; | 
 | 163 |   log_msg.entry.hdr_size = sizeof(log_msg.entry); | 
 | 164 |   log_msg.entry.pid = getpid(); | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 165 | #ifdef __BIONIC__ | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 166 |   log_msg.entry.tid = gettid(); | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 167 | #else | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 168 |   log_msg.entry.tid = getpid(); | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 169 | #endif | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 170 |   log_msg.entry.sec = ts->tv_sec; | 
 | 171 |   log_msg.entry.nsec = ts->tv_nsec; | 
 | 172 |   log_msg.entry.lid = logId; | 
 | 173 |   log_msg.entry.uid = __android_log_uid(); | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 174 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 175 |   for (i = 0; i < nr; ++i) { | 
 | 176 |     size_t len = vec[i].iov_len; | 
 | 177 |     if ((log_msg.entry.len + len) > LOGGER_ENTRY_MAX_PAYLOAD) { | 
 | 178 |       len = LOGGER_ENTRY_MAX_PAYLOAD - log_msg.entry.len; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 179 |     } | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 180 |     if (!len) continue; | 
 | 181 |     memcpy(log_msg.entry.msg + log_msg.entry.len, vec[i].iov_base, len); | 
 | 182 |     log_msg.entry.len += len; | 
 | 183 |   } | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 184 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 185 |   if ((logId == LOG_ID_EVENTS) || (logId == LOG_ID_SECURITY)) { | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 186 | #if defined(__ANDROID__) | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 187 |     if (!ctx->eventTagMap) { | 
 | 188 |       ctx->eventTagMap = android_openEventTagMap(NULL); | 
 | 189 |     } | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 190 | #endif | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 191 |     err = android_log_processBinaryLogBuffer(&log_msg.entry_v1, &entry, | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 192 | #if defined(__ANDROID__) | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 193 |                                              ctx->eventTagMap, | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 194 | #else | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 195 |                                              NULL, | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 196 | #endif | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 197 |                                              binaryMsgBuf, sizeof(binaryMsgBuf)); | 
 | 198 |   } else { | 
 | 199 |     err = android_log_processLogBuffer(&log_msg.entry_v1, &entry); | 
 | 200 |   } | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 201 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 202 |   /* print known truncated data, in essence logcat --debug */ | 
 | 203 |   if ((err < 0) && !entry.message) return -EINVAL; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 204 |  | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 205 |   if (!android_log_shouldPrintLine(ctx->logformat, entry.tag, entry.priority)) { | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 206 |     return log_msg.entry.len; | 
| Mark Salyzyn | 2ed51d7 | 2017-03-09 08:09:43 -0800 | [diff] [blame] | 207 |   } | 
 | 208 |  | 
 | 209 |   err = android_log_printLogLine(ctx->logformat, fileno(stderr), &entry); | 
 | 210 |   if (err < 0) return errno ? -errno : -EINVAL; | 
 | 211 |   return log_msg.entry.len; | 
| Mark Salyzyn | 4d99c98 | 2017-02-28 12:59:01 -0800 | [diff] [blame] | 212 | } |