2017-04-04 19:44:13 +00:00
|
|
|
/* log_monitor.c - New thread to monitor logcat
|
|
|
|
*
|
2017-10-08 21:05:52 +00:00
|
|
|
* A universal logcat monitor for many usages. Add listeners to the list,
|
|
|
|
* and the pointer of the new log line will be sent through pipes to trigger
|
|
|
|
* asynchronous events without polling
|
2017-04-04 19:44:13 +00:00
|
|
|
*/
|
|
|
|
|
|
|
|
#include <stdio.h>
|
|
|
|
#include <pthread.h>
|
2017-05-07 19:11:14 +00:00
|
|
|
#include <unistd.h>
|
2017-06-02 20:31:01 +00:00
|
|
|
#include <sys/wait.h>
|
2018-07-01 10:18:12 +00:00
|
|
|
#include <fcntl.h>
|
2017-04-04 19:44:13 +00:00
|
|
|
|
2017-04-05 22:12:29 +00:00
|
|
|
#include "magisk.h"
|
2017-04-04 19:44:13 +00:00
|
|
|
#include "utils.h"
|
2018-07-02 14:11:28 +00:00
|
|
|
#include "daemon.h"
|
2017-10-08 21:05:52 +00:00
|
|
|
|
2018-04-07 18:12:40 +00:00
|
|
|
int loggable = 1;
|
2018-07-02 14:11:28 +00:00
|
|
|
static int sockfd;
|
|
|
|
static pthread_t thread = -1;
|
|
|
|
static pthread_mutex_t lock = PTHREAD_MUTEX_INITIALIZER;
|
|
|
|
|
|
|
|
enum {
|
|
|
|
HIDE_EVENT,
|
|
|
|
LOG_EVENT,
|
|
|
|
DEBUG_EVENT
|
|
|
|
};
|
|
|
|
|
|
|
|
struct log_listener {
|
|
|
|
int fd;
|
|
|
|
int (*filter) (const char*);
|
|
|
|
};
|
2017-10-08 21:05:52 +00:00
|
|
|
|
2017-12-18 10:17:37 +00:00
|
|
|
static int am_proc_start_filter(const char *log) {
|
|
|
|
return strstr(log, "am_proc_start") != NULL;
|
|
|
|
}
|
|
|
|
|
|
|
|
static int magisk_log_filter(const char *log) {
|
|
|
|
char *ss;
|
|
|
|
return (ss = strstr(log, " Magisk")) && (ss[-1] != 'D') && (ss[-1] != 'V');
|
|
|
|
}
|
|
|
|
|
|
|
|
static int magisk_debug_log_filter(const char *log) {
|
2018-07-01 10:18:12 +00:00
|
|
|
return strstr(log, "am_proc_start") == NULL;
|
2017-12-18 10:17:37 +00:00
|
|
|
}
|
|
|
|
|
2018-07-02 14:11:28 +00:00
|
|
|
static struct log_listener log_events[] = {
|
2017-12-18 10:17:37 +00:00
|
|
|
{ /* HIDE_EVENT */
|
|
|
|
.fd = -1,
|
|
|
|
.filter = am_proc_start_filter
|
|
|
|
},
|
|
|
|
{ /* LOG_EVENT */
|
|
|
|
.fd = -1,
|
|
|
|
.filter = magisk_log_filter
|
|
|
|
},
|
|
|
|
{ /* DEBUG_EVENT */
|
|
|
|
.fd = -1,
|
|
|
|
.filter = magisk_debug_log_filter
|
|
|
|
}
|
|
|
|
};
|
2018-07-01 10:18:12 +00:00
|
|
|
#define EVENT_NUM (sizeof(log_events) / sizeof(struct log_listener))
|
2017-04-04 19:44:13 +00:00
|
|
|
|
2018-04-07 18:12:40 +00:00
|
|
|
static void test_logcat() {
|
|
|
|
int log_fd = -1, log_pid;
|
|
|
|
char buf[1];
|
|
|
|
log_pid = exec_command(0, &log_fd, NULL, "logcat", NULL);
|
|
|
|
if (read(log_fd, buf, sizeof(buf)) != sizeof(buf)) {
|
|
|
|
loggable = 0;
|
|
|
|
LOGD("log_monitor: cannot read from logcat, disable logging");
|
2018-02-11 18:48:15 +00:00
|
|
|
}
|
2018-04-07 18:12:40 +00:00
|
|
|
kill(log_pid, SIGTERM);
|
|
|
|
waitpid(log_pid, NULL, 0);
|
2018-02-11 18:48:15 +00:00
|
|
|
}
|
|
|
|
|
2018-07-02 14:11:28 +00:00
|
|
|
static void sigpipe_handler(int sig) {
|
|
|
|
close(log_events[HIDE_EVENT].fd);
|
|
|
|
log_events[HIDE_EVENT].fd = -1;
|
|
|
|
}
|
|
|
|
|
|
|
|
static void *socket_thread(void *args) {
|
|
|
|
/* This would block, so separate thread */
|
|
|
|
while(1) {
|
|
|
|
int fd = accept4(sockfd, NULL, NULL, SOCK_CLOEXEC);
|
|
|
|
switch(read_int(fd)) {
|
|
|
|
case HIDE_CONNECT:
|
|
|
|
pthread_mutex_lock(&lock);
|
|
|
|
log_events[HIDE_EVENT].fd = fd;
|
|
|
|
pthread_mutex_unlock(&lock);
|
|
|
|
thread = -1;
|
|
|
|
return NULL;
|
|
|
|
default:
|
|
|
|
close(fd);
|
|
|
|
break;
|
|
|
|
}
|
|
|
|
}
|
|
|
|
}
|
|
|
|
|
|
|
|
void log_daemon() {
|
|
|
|
setsid();
|
|
|
|
strcpy(argv0, "magisklogd");
|
|
|
|
|
|
|
|
struct sockaddr_un sun;
|
|
|
|
sockfd = setup_socket(&sun, LOG_DAEMON);
|
|
|
|
if (xbind(sockfd, (struct sockaddr*) &sun, sizeof(sun)))
|
|
|
|
exit(1);
|
|
|
|
xlisten(sockfd, 1);
|
|
|
|
LOGI("Magisk v" xstr(MAGISK_VERSION) "(" xstr(MAGISK_VER_CODE) ") logger started\n");
|
|
|
|
|
|
|
|
struct sigaction act;
|
|
|
|
memset(&act, 0, sizeof(act));
|
|
|
|
act.sa_handler = sigpipe_handler;
|
|
|
|
sigaction(SIGPIPE, &act, NULL);
|
|
|
|
|
|
|
|
// Setup log dumps
|
|
|
|
rename(LOGFILE, LOGFILE ".bak");
|
|
|
|
log_events[LOG_EVENT].fd = xopen(LOGFILE, O_CREAT | O_WRONLY | O_TRUNC | O_CLOEXEC, 0644);
|
|
|
|
#ifdef MAGISK_DEBUG
|
|
|
|
log_events[DEBUG_EVENT].fd = xopen(DEBUG_LOG, O_CREAT | O_WRONLY | O_TRUNC | O_CLOEXEC, 0644);
|
|
|
|
#endif
|
|
|
|
|
2017-10-08 21:05:52 +00:00
|
|
|
int log_fd = -1, log_pid;
|
2018-07-01 10:18:12 +00:00
|
|
|
char line[PIPE_BUF];
|
2017-06-02 20:31:01 +00:00
|
|
|
|
|
|
|
while (1) {
|
2018-04-07 18:12:40 +00:00
|
|
|
if (!loggable) {
|
2018-02-11 18:48:15 +00:00
|
|
|
// Disable all services
|
2018-07-01 10:18:12 +00:00
|
|
|
for (int i = 0; i < EVENT_NUM; ++i) {
|
2018-02-11 18:48:15 +00:00
|
|
|
close(log_events[i].fd);
|
|
|
|
log_events[i].fd = -1;
|
|
|
|
}
|
2018-07-02 14:11:28 +00:00
|
|
|
return;
|
2018-02-11 18:48:15 +00:00
|
|
|
}
|
|
|
|
|
2017-06-02 20:31:01 +00:00
|
|
|
// Start logcat
|
2018-07-01 10:18:12 +00:00
|
|
|
log_pid = exec_command(0, &log_fd, NULL,
|
|
|
|
"/system/bin/logcat",
|
|
|
|
"-b", "events", "-b", "main", "-b", "crash",
|
|
|
|
"-v", "threadtime",
|
|
|
|
"-s", "am_proc_start", "Magisk", "*:F",
|
|
|
|
NULL);
|
|
|
|
FILE *logs = fdopen(log_fd, "r");
|
|
|
|
while (fgets(line, sizeof(line), logs)) {
|
|
|
|
if (line[0] == '-')
|
|
|
|
continue;
|
|
|
|
size_t len = strlen(line);
|
2018-07-02 14:11:28 +00:00
|
|
|
pthread_mutex_lock(&lock);
|
2018-07-01 10:18:12 +00:00
|
|
|
for (int i = 0; i < EVENT_NUM; ++i) {
|
|
|
|
if (log_events[i].fd > 0 && log_events[i].filter(line))
|
2018-07-02 14:11:28 +00:00
|
|
|
write(log_events[i].fd, line, len);
|
2017-10-08 21:05:52 +00:00
|
|
|
}
|
2018-07-02 14:11:28 +00:00
|
|
|
if (thread < 0 && log_events[HIDE_EVENT].fd < 0) {
|
|
|
|
// New thread to handle connection to main daemon
|
|
|
|
xpthread_create(&thread, NULL, socket_thread, NULL);
|
|
|
|
pthread_detach(thread);
|
|
|
|
}
|
|
|
|
pthread_mutex_unlock(&lock);
|
2017-10-08 21:05:52 +00:00
|
|
|
if (kill(log_pid, 0))
|
|
|
|
break;
|
|
|
|
}
|
2017-12-17 18:51:27 +00:00
|
|
|
|
|
|
|
// Cleanup
|
2018-07-01 10:18:12 +00:00
|
|
|
fclose(logs);
|
2017-12-17 18:51:27 +00:00
|
|
|
log_fd = -1;
|
|
|
|
kill(log_pid, SIGTERM);
|
|
|
|
waitpid(log_pid, NULL, 0);
|
2018-04-07 18:12:40 +00:00
|
|
|
test_logcat();
|
2017-04-04 19:44:13 +00:00
|
|
|
}
|
|
|
|
}
|
|
|
|
|
2017-10-08 21:05:52 +00:00
|
|
|
/* Start new threads to monitor logcat and dump to logfile */
|
2017-04-04 19:44:13 +00:00
|
|
|
void monitor_logs() {
|
2018-04-07 18:12:40 +00:00
|
|
|
test_logcat();
|
|
|
|
if (loggable) {
|
2018-07-02 14:11:28 +00:00
|
|
|
int fd;
|
|
|
|
connect_daemon2(LOG_DAEMON, &fd);
|
|
|
|
write_int(fd, DO_NOTHING);
|
|
|
|
close(fd);
|
2018-02-11 18:48:15 +00:00
|
|
|
}
|
2017-04-17 08:36:49 +00:00
|
|
|
}
|