blob: 9c270a0e88026f6d469d1d9f977aca850af7c99c [file] [log] [blame]
Mark Salyzyn12bac902014-02-26 09:50:16 -08001/*
2 * Copyright (C) 2012-2013 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#include <dirent.h>
18#include <errno.h>
19#include <fcntl.h>
Tom Cherry024aa2e2019-06-06 13:41:20 -070020#include <linux/capability.h>
Mark Salyzyn3fe25932015-03-10 16:45:17 -070021#include <poll.h>
Mark Salyzyn25ffdd52013-12-26 15:13:36 -080022#include <sched.h>
Mark Salyzyn3fe25932015-03-10 16:45:17 -070023#include <semaphore.h>
24#include <signal.h>
Mark Salyzyn12bac902014-02-26 09:50:16 -080025#include <stdio.h>
26#include <stdlib.h>
27#include <string.h>
28#include <sys/capability.h>
Mark Salyzyn78956ab2014-10-13 09:59:37 -070029#include <sys/klog.h>
Elliott Hughesae8c5e52014-07-18 17:39:41 -070030#include <sys/prctl.h>
Riley Andrews98b580b2015-06-08 23:36:34 -070031#include <sys/resource.h>
Mark Salyzyn12bac902014-02-26 09:50:16 -080032#include <sys/stat.h>
33#include <sys/types.h>
Mark Salyzyne0f1c5a2015-03-12 12:25:35 -070034#include <syslog.h>
Mark Salyzynfe639a02014-02-19 17:18:31 -080035#include <unistd.h>
Mark Salyzyn12bac902014-02-26 09:50:16 -080036
Mark Salyzynb56ed322015-06-12 14:59:42 -070037#include <memory>
38
Tom Cherry01d8ce72020-06-03 09:23:49 -070039#include <android-base/logging.h>
Jorge Lucangeli Obes88e835f2016-07-15 13:57:08 -040040#include <android-base/macros.h>
Tom Cherry3429ee22020-07-14 09:52:24 -070041#include <android-base/properties.h>
Tom Cherry01d8ce72020-06-03 09:23:49 -070042#include <android-base/stringprintf.h>
Mark Salyzyn11895882016-11-07 09:39:30 -080043#include <cutils/android_get_control_file.h>
Mark Salyzyn3fe25932015-03-10 16:45:17 -070044#include <cutils/sockets.h>
Mark Salyzyn3fb3a102015-04-13 14:24:45 -070045#include <log/event_tag_map.h>
Mark Salyzynb4853952015-03-17 07:56:32 -070046#include <private/android_filesystem_config.h>
Mark Salyzyn486d1742016-03-28 15:42:08 -070047#include <private/android_logger.h>
Suren Baghdasaryane8229ca2019-01-25 05:32:52 +000048#include <processgroup/sched_policy.h>
Riley Andrews98b580b2015-06-08 23:36:34 -070049#include <utils/threads.h>
Mark Salyzynfe639a02014-02-19 17:18:31 -080050
Mark Salyzyn12bac902014-02-26 09:50:16 -080051#include "CommandListener.h"
William Roberts210c5842013-02-08 09:45:26 +090052#include "LogAudit.h"
Mark Salyzynda65bcb2017-03-10 14:31:54 -080053#include "LogBuffer.h"
Mark Salyzynfee9e2d2014-10-15 08:49:39 -070054#include "LogKlog.h"
Mark Salyzynda65bcb2017-03-10 14:31:54 -080055#include "LogListener.h"
Tom Cherry1371b082020-05-14 19:25:05 -070056#include "LogReader.h"
Tom Cherry523026f2020-05-07 14:44:43 -070057#include "LogStatistics.h"
Tom Cherryfed20c92020-05-01 16:13:18 -070058#include "LogTags.h"
Mark Salyzynf777ac72015-08-28 08:02:59 -070059#include "LogUtils.h"
Tom Cherry47fdbce2020-05-13 09:28:37 -070060#include "SerializedLogBuffer.h"
Tom Cherry29a65d82020-05-12 12:46:43 -070061#include "SimpleLogBuffer.h"
Marco Nelissen9357be42021-12-02 10:49:52 -080062#include "TrustyLog.h"
Mark Salyzyn12bac902014-02-26 09:50:16 -080063
Tom Cherry55552652020-07-28 09:51:54 -070064using android::base::GetBoolProperty;
Tom Cherry3429ee22020-07-14 09:52:24 -070065using android::base::GetProperty;
Tom Cherry55552652020-07-28 09:51:54 -070066using android::base::SetProperty;
Tom Cherry3429ee22020-07-14 09:52:24 -070067
Mark Salyzynda65bcb2017-03-10 14:31:54 -080068#define KMSG_PRIORITY(PRI) \
69 '<', '0' + LOG_MAKEPRI(LOG_DAEMON, LOG_PRI(PRI)) / 10, \
70 '0' + LOG_MAKEPRI(LOG_DAEMON, LOG_PRI(PRI)) % 10, '>'
Mark Salyzyne0f1c5a2015-03-12 12:25:35 -070071
Tom Cherry024aa2e2019-06-06 13:41:20 -070072// The service is designed to be run by init, it does not respond well to starting up manually. Init
73// has a 'sigstop' feature that sends SIGSTOP to a service immediately before calling exec(). This
74// allows debuggers, etc to be attached to logd at the very beginning, while still having init
75// handle the user, groups, capabilities, files, etc setup.
Tom Cherry2e8c4db2020-06-22 10:15:04 -070076static void DropPrivs(bool klogd, bool auditd) {
Mark Salyzyn90787fc2015-01-30 15:19:48 -080077 if (set_sched_policy(0, SP_BACKGROUND) < 0) {
Tom Cherry2e8c4db2020-06-22 10:15:04 -070078 PLOG(FATAL) << "failed to set background scheduling policy";
Mark Salyzyn90787fc2015-01-30 15:19:48 -080079 }
80
Tom Cherry55552652020-07-28 09:51:54 -070081 if (!GetBoolProperty("ro.debuggable", false)) {
82 if (prctl(PR_SET_DUMPABLE, 0) == -1) {
83 PLOG(FATAL) << "failed to clear PR_SET_DUMPABLE";
84 }
Mark Salyzyne8ee9092016-10-28 14:49:53 -070085 }
86
Tom Cherry024aa2e2019-06-06 13:41:20 -070087 std::unique_ptr<struct _cap_struct, int (*)(void*)> caps(cap_init(), cap_free);
88 if (cap_clear(caps.get()) < 0) {
Tom Cherry2e8c4db2020-06-22 10:15:04 -070089 PLOG(FATAL) << "cap_clear() failed";
Mark Salyzynda65bcb2017-03-10 14:31:54 -080090 }
Tom Cherry024aa2e2019-06-06 13:41:20 -070091 if (klogd) {
Tom Cherry2e8c4db2020-06-22 10:15:04 -070092 cap_value_t cap_syslog = CAP_SYSLOG;
93 if (cap_set_flag(caps.get(), CAP_PERMITTED, 1, &cap_syslog, CAP_SET) < 0 ||
94 cap_set_flag(caps.get(), CAP_EFFECTIVE, 1, &cap_syslog, CAP_SET) < 0) {
95 PLOG(FATAL) << "Failed to set CAP_SYSLOG";
96 }
Tom Cherry024aa2e2019-06-06 13:41:20 -070097 }
98 if (auditd) {
Tom Cherry2e8c4db2020-06-22 10:15:04 -070099 cap_value_t cap_audit_control = CAP_AUDIT_CONTROL;
100 if (cap_set_flag(caps.get(), CAP_PERMITTED, 1, &cap_audit_control, CAP_SET) < 0 ||
101 cap_set_flag(caps.get(), CAP_EFFECTIVE, 1, &cap_audit_control, CAP_SET) < 0) {
102 PLOG(FATAL) << "Failed to set CAP_AUDIT_CONTROL";
103 }
Mark Salyzynda65bcb2017-03-10 14:31:54 -0800104 }
Mark Salyzynd0c59e22016-10-28 14:49:53 -0700105 if (cap_set_proc(caps.get()) < 0) {
Tom Cherry2e8c4db2020-06-22 10:15:04 -0700106 PLOG(FATAL) << "cap_set_proc() failed";
Mark Salyzynd0c59e22016-10-28 14:49:53 -0700107 }
Mark Salyzyn12bac902014-02-26 09:50:16 -0800108}
109
Tom Cherry55552652020-07-28 09:51:54 -0700110// GetBoolProperty that defaults to true if `ro.debuggable == true && ro.config.low_rawm == false`.
111static bool GetBoolPropertyEngSvelteDefault(const std::string& name) {
112 bool default_value =
113 GetBoolProperty("ro.debuggable", false) && !GetBoolProperty("ro.config.low_ram", false);
114
115 return GetBoolProperty(name, default_value);
116}
117
Mark Salyzynda65bcb2017-03-10 14:31:54 -0800118static void readDmesg(LogAudit* al, LogKlog* kl) {
Mark Salyzynb56ed322015-06-12 14:59:42 -0700119 if (!al && !kl) {
120 return;
121 }
122
Mark Salyzyn4a1a09f2016-08-11 08:02:06 -0700123 int rc = klogctl(KLOG_SIZE_BUFFER, nullptr, 0);
Mark Salyzynb56ed322015-06-12 14:59:42 -0700124 if (rc <= 0) {
125 return;
126 }
127
Mark Salyzyn4a1a09f2016-08-11 08:02:06 -0700128 // Margin for additional input race or trailing nul
129 ssize_t len = rc + 1024;
Mark Salyzynda65bcb2017-03-10 14:31:54 -0800130 std::unique_ptr<char[]> buf(new char[len]);
Mark Salyzyn46ab1bf2015-09-02 07:39:53 -0700131
LongPing Weifffa6882023-07-14 16:54:15 +0800132 // Drop old logs in /proc/kmsg to avoid duplicate print.
133 rc = klogctl(KLOG_SIZE_UNREAD, nullptr, 0);
134 if (rc > 0)
135 rc = klogctl(KLOG_READ, buf.get(), rc);
136
137
Mark Salyzyn46ab1bf2015-09-02 07:39:53 -0700138 rc = klogctl(KLOG_READ_ALL, buf.get(), len);
139 if (rc <= 0) {
140 return;
141 }
142
Mark Salyzyn4a1a09f2016-08-11 08:02:06 -0700143 if (rc < len) {
Mark Salyzynb56ed322015-06-12 14:59:42 -0700144 len = rc + 1;
145 }
Mark Salyzyn46ab1bf2015-09-02 07:39:53 -0700146 buf[--len] = '\0';
Mark Salyzynb56ed322015-06-12 14:59:42 -0700147
Mark Salyzyn4a1a09f2016-08-11 08:02:06 -0700148 ssize_t sublen;
149 for (char *ptr = nullptr, *tok = buf.get();
150 (rc >= 0) && !!(tok = android::log_strntok_r(tok, len, ptr, sublen));
151 tok = nullptr) {
152 if ((sublen <= 0) || !*tok) continue;
Mark Salyzynb56ed322015-06-12 14:59:42 -0700153 if (al) {
Mark Salyzyn16f0c0d2015-09-04 11:37:42 -0700154 rc = al->log(tok, sublen);
Mark Salyzynb56ed322015-06-12 14:59:42 -0700155 }
156 if (kl) {
Mark Salyzyn16f0c0d2015-09-04 11:37:42 -0700157 rc = kl->log(tok, sublen);
Mark Salyzynb56ed322015-06-12 14:59:42 -0700158 }
159 }
160}
161
Mark Salyzynd7eb08c2016-10-31 13:49:44 -0700162static int issueReinit() {
Mark Salyzynda65bcb2017-03-10 14:31:54 -0800163 int sock = TEMP_FAILURE_RETRY(socket_local_client(
164 "logd", ANDROID_SOCKET_NAMESPACE_RESERVED, SOCK_STREAM));
Mark Salyzynd7eb08c2016-10-31 13:49:44 -0700165 if (sock < 0) return -errno;
166
167 static const char reinitStr[] = "reinit";
168 ssize_t ret = TEMP_FAILURE_RETRY(write(sock, reinitStr, sizeof(reinitStr)));
169 if (ret < 0) return -errno;
170
Elliott Hughesa94e7072021-11-17 15:07:55 -0800171 struct pollfd p = {.fd = sock, .events = POLLIN};
Mark Salyzynd7eb08c2016-10-31 13:49:44 -0700172 ret = TEMP_FAILURE_RETRY(poll(&p, 1, 1000));
173 if (ret < 0) return -errno;
174 if ((ret == 0) || !(p.revents & POLLIN)) return -ETIME;
175
176 static const char success[] = "success";
Elliott Hughesa94e7072021-11-17 15:07:55 -0800177 char buffer[sizeof(success) - 1] = {};
Mark Salyzynd7eb08c2016-10-31 13:49:44 -0700178 ret = TEMP_FAILURE_RETRY(read(sock, buffer, sizeof(buffer)));
179 if (ret < 0) return -errno;
180
181 return strncmp(buffer, success, sizeof(success) - 1) != 0;
182}
183
Mark Salyzyn3fe25932015-03-10 16:45:17 -0700184// Foreground waits for exit of the main persistent threads
185// that are started here. The threads are created to manage
186// UNIX domain client sockets for writing, reading and
187// controlling the user space logger, and for any additional
188// logging plugins like auditd and restart control. Additional
189// transitory per-client threads are created for each reader.
Mark Salyzynda65bcb2017-03-10 14:31:54 -0800190int main(int argc, char* argv[]) {
Tom Cherrye4134f82020-07-31 15:21:54 -0700191 // We want EPIPE when a reader disconnects, not to terminate logd.
192 signal(SIGPIPE, SIG_IGN);
Hidehiko Abe3ecf0722017-03-29 17:41:17 +0900193 // logd is written under the assumption that the timezone is UTC.
194 // If TZ is not set, persist.sys.timezone is looked up in some time utility
195 // libc functions, including mktime. It confuses the logd time handling,
196 // so here explicitly set TZ to UTC, which overrides the property.
197 setenv("TZ", "UTC", 1);
Mark Salyzyn3fe25932015-03-10 16:45:17 -0700198 // issue reinit command. KISS argument parsing.
199 if ((argc > 1) && argv[1] && !strcmp(argv[1], "--reinit")) {
Mark Salyzynd7eb08c2016-10-31 13:49:44 -0700200 return issueReinit();
Mark Salyzyn3fe25932015-03-10 16:45:17 -0700201 }
202
Tom Cherry01d8ce72020-06-03 09:23:49 -0700203 android::base::InitLogging(
204 argv, [](android::base::LogId log_id, android::base::LogSeverity severity,
205 const char* tag, const char* file, unsigned int line, const char* message) {
206 if (tag && strcmp(tag, "logd") != 0) {
207 auto prefixed_message = android::base::StringPrintf("%s: %s", tag, message);
208 android::base::KernelLogger(log_id, severity, "logd", file, line,
209 prefixed_message.c_str());
210 } else {
211 android::base::KernelLogger(log_id, severity, "logd", file, line, message);
212 }
213 });
214
Mark Salyzyn8cd27852016-10-27 08:21:35 -0700215 static const char dev_kmsg[] = "/dev/kmsg";
Tom Cherry01d8ce72020-06-03 09:23:49 -0700216 int fdDmesg = android_get_control_file(dev_kmsg);
Mark Salyzyn8cd27852016-10-27 08:21:35 -0700217 if (fdDmesg < 0) {
218 fdDmesg = TEMP_FAILURE_RETRY(open(dev_kmsg, O_WRONLY | O_CLOEXEC));
219 }
220
221 int fdPmesg = -1;
Tom Cherry55552652020-07-28 09:51:54 -0700222 bool klogd = GetBoolPropertyEngSvelteDefault("ro.logd.kernel");
Mark Salyzyn8cd27852016-10-27 08:21:35 -0700223 if (klogd) {
Tom Cherry55552652020-07-28 09:51:54 -0700224 SetProperty("ro.logd.kernel", "true");
Mark Salyzyn8cd27852016-10-27 08:21:35 -0700225 static const char proc_kmsg[] = "/proc/kmsg";
226 fdPmesg = android_get_control_file(proc_kmsg);
227 if (fdPmesg < 0) {
Mark Salyzynda65bcb2017-03-10 14:31:54 -0800228 fdPmesg = TEMP_FAILURE_RETRY(
229 open(proc_kmsg, O_RDONLY | O_NDELAY | O_CLOEXEC));
Mark Salyzyn8cd27852016-10-27 08:21:35 -0700230 }
Tom Cherry01d8ce72020-06-03 09:23:49 -0700231 if (fdPmesg < 0) PLOG(ERROR) << "Failed to open " << proc_kmsg;
Mark Salyzyn8cd27852016-10-27 08:21:35 -0700232 }
233
Tom Cherry55552652020-07-28 09:51:54 -0700234 bool auditd = GetBoolProperty("ro.logd.auditd", true);
Tom Cherry2e8c4db2020-06-22 10:15:04 -0700235 DropPrivs(klogd, auditd);
Tom Cherry024aa2e2019-06-06 13:41:20 -0700236
Tom Cherryfed20c92020-05-01 16:13:18 -0700237 // A cache of event log tags
238 LogTags log_tags;
Tom Cherry7e8f9102020-05-11 16:29:29 -0700239
Tom Cherrydcef3b52020-05-01 17:03:20 -0700240 // Pruning configuration.
241 PruneList prune_list;
Tom Cherry7e8f9102020-05-11 16:29:29 -0700242
Tom Cherry729d9332020-07-22 15:30:02 -0700243 std::string buffer_type = GetProperty("logd.buffer_type", "serialized");
Tom Cherry3429ee22020-07-14 09:52:24 -0700244
Tom Cherry55552652020-07-28 09:51:54 -0700245 LogStatistics log_statistics(GetBoolPropertyEngSvelteDefault("logd.statistics"),
246 buffer_type == "serialized");
Tom Cherryfed20c92020-05-01 16:13:18 -0700247
Tom Cherry3429ee22020-07-14 09:52:24 -0700248 // Serves the purpose of managing the last logs times read on a socket connection, and as a
249 // reader lock on a range of log entries.
Tom Cherry7e8f9102020-05-11 16:29:29 -0700250 LogReaderList reader_list;
Mark Salyzyn12bac902014-02-26 09:50:16 -0800251
Tom Cherry29a65d82020-05-12 12:46:43 -0700252 // LogBuffer is the object which is responsible for holding all log entries.
Tom Cherry3429ee22020-07-14 09:52:24 -0700253 LogBuffer* log_buffer = nullptr;
Elliott Hughes225e5c62022-09-16 23:01:59 +0000254 if (buffer_type == "serialized") {
Tom Cherry3429ee22020-07-14 09:52:24 -0700255 log_buffer = new SerializedLogBuffer(&reader_list, &log_tags, &log_statistics);
256 } else if (buffer_type == "simple") {
257 log_buffer = new SimpleLogBuffer(&reader_list, &log_tags, &log_statistics);
Tom Cherry29a65d82020-05-12 12:46:43 -0700258 } else {
Elliott Hughes225e5c62022-09-16 23:01:59 +0000259 LOG(FATAL) << "buffer_type must be one of 'serialized' or 'simple'";
Tom Cherry29a65d82020-05-12 12:46:43 -0700260 }
Mark Salyzynfe639a02014-02-19 17:18:31 -0800261
Mark Salyzyn12bac902014-02-26 09:50:16 -0800262 // LogReader listens on /dev/socket/logdr. When a client
263 // connects, log entries in the LogBuffer are written to the client.
Tom Cherry3429ee22020-07-14 09:52:24 -0700264 LogReader* reader = new LogReader(log_buffer, &reader_list);
Mark Salyzyn12bac902014-02-26 09:50:16 -0800265 if (reader->startListener()) {
Elliott Hughes7c3a50e2018-06-13 10:33:45 -0700266 return EXIT_FAILURE;
Mark Salyzyn12bac902014-02-26 09:50:16 -0800267 }
268
269 // LogListener listens on /dev/socket/logdw for client
270 // initiated log messages. New log entries are added to LogBuffer
271 // and LogReader is notified to send updates to connected clients.
Tom Cherry3429ee22020-07-14 09:52:24 -0700272 LogListener* swl = new LogListener(log_buffer);
Tom Cherry346f1e42020-05-06 12:04:09 -0700273 if (!swl->StartListener()) {
Elliott Hughes7c3a50e2018-06-13 10:33:45 -0700274 return EXIT_FAILURE;
Mark Salyzyn12bac902014-02-26 09:50:16 -0800275 }
276
277 // Command listener listens on /dev/socket/logd for incoming logd
278 // administrative commands.
Tom Cherry3429ee22020-07-14 09:52:24 -0700279 CommandListener* cl = new CommandListener(log_buffer, &log_tags, &prune_list, &log_statistics);
Mark Salyzyn12bac902014-02-26 09:50:16 -0800280 if (cl->startListener()) {
Elliott Hughes7c3a50e2018-06-13 10:33:45 -0700281 return EXIT_FAILURE;
Mark Salyzyn12bac902014-02-26 09:50:16 -0800282 }
283
Jiyong Park7ceed102021-11-22 14:21:30 +0900284 // Notify that others can now interact with logd
285 SetProperty("logd.ready", "true");
286
William Roberts210c5842013-02-08 09:45:26 +0900287 // LogAudit listens on NETLINK_AUDIT socket for selinux
288 // initiated log messages. New log entries are added to LogBuffer
289 // and LogReader is notified to send updates to connected clients.
Mark Salyzyn4a1a09f2016-08-11 08:02:06 -0700290 LogAudit* al = nullptr;
Sami Tolvanen7a586892016-06-14 18:04:43 +0000291 if (auditd) {
Tom Cherry55552652020-07-28 09:51:54 -0700292 int dmesg_fd = GetBoolProperty("ro.logd.auditd.dmesg", true) ? fdDmesg : -1;
Tom Cherry3429ee22020-07-14 09:52:24 -0700293 al = new LogAudit(log_buffer, dmesg_fd, &log_statistics);
Sami Tolvanen7a586892016-06-14 18:04:43 +0000294 }
Mark Salyzyn3fe25932015-03-10 16:45:17 -0700295
Mark Salyzyn4a1a09f2016-08-11 08:02:06 -0700296 LogKlog* kl = nullptr;
Mark Salyzynfee9e2d2014-10-15 08:49:39 -0700297 if (klogd) {
Tom Cherry3429ee22020-07-14 09:52:24 -0700298 kl = new LogKlog(log_buffer, fdDmesg, fdPmesg, al != nullptr, &log_statistics);
Mark Salyzynfee9e2d2014-10-15 08:49:39 -0700299 }
Mark Salyzyn78956ab2014-10-13 09:59:37 -0700300
Sami Tolvanen7a586892016-06-14 18:04:43 +0000301 readDmesg(al, kl);
Mark Salyzyn78956ab2014-10-13 09:59:37 -0700302
Mark Salyzynb56ed322015-06-12 14:59:42 -0700303 // failure is an option ... messages are in dmesg (required by standard)
Mark Salyzynb56ed322015-06-12 14:59:42 -0700304 if (kl && kl->startListener()) {
305 delete kl;
306 }
Mark Salyzyn78956ab2014-10-13 09:59:37 -0700307
Sami Tolvanen7a586892016-06-14 18:04:43 +0000308 if (al && al->startListener()) {
309 delete al;
William Roberts210c5842013-02-08 09:45:26 +0900310 }
311
Marco Nelissen0e6014f2022-06-07 08:25:32 -0700312 TrustyLog::create(log_buffer);
313
Mark Salyzyn3fe25932015-03-10 16:45:17 -0700314 TEMP_FAILURE_RETRY(pause());
315
Elliott Hughes7c3a50e2018-06-13 10:33:45 -0700316 return EXIT_SUCCESS;
Mark Salyzyn12bac902014-02-26 09:50:16 -0800317}