blob: 24debf473bd83408f9e1b4d65693162ce56f7397 [file] [log] [blame]
Jeff Brown053b8652012-06-06 16:25:03 -07001/*
2 * Copyright (C) 2012 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 <stddef.h>
18#include <stdbool.h>
19#include <stdlib.h>
20#include <signal.h>
21#include <string.h>
22#include <stdio.h>
23#include <fcntl.h>
24#include <errno.h>
25#include <dirent.h>
26#include <time.h>
27#include <sys/ptrace.h>
28#include <sys/stat.h>
29
30#include <private/android_filesystem_config.h>
31
32#include <cutils/logger.h>
33#include <cutils/properties.h>
34
Christopher Ferris365e4ae2013-10-02 12:26:48 -070035#include <backtrace/backtrace.h>
Jeff Brown053b8652012-06-06 16:25:03 -070036
Christopher Tateded2e5a2013-03-19 13:12:23 -070037#include <sys/socket.h>
38#include <linux/un.h>
39
rpcraigf1186f32012-07-19 09:38:06 -040040#include <selinux/android.h>
rpcraigf1186f32012-07-19 09:38:06 -040041
Jeff Brown053b8652012-06-06 16:25:03 -070042#include "machine.h"
43#include "tombstone.h"
Christopher Ferris365e4ae2013-10-02 12:26:48 -070044#include "backtrace.h"
Jeff Brown053b8652012-06-06 16:25:03 -070045
Jeff Brown053b8652012-06-06 16:25:03 -070046#define STACK_WORDS 16
47
48#define MAX_TOMBSTONES 10
49#define TOMBSTONE_DIR "/data/tombstones"
50
Christopher Tateded2e5a2013-03-19 13:12:23 -070051/* Must match the path defined in NativeCrashListener.java */
52#define NCRASH_SOCKET_PATH "/data/system/ndebugsocket"
53
Jeff Brown053b8652012-06-06 16:25:03 -070054#define typecheck(x,y) { \
55 typeof(x) __dummy1; \
56 typeof(y) __dummy2; \
57 (void)(&__dummy1 == &__dummy2); }
58
59
60static bool signal_has_address(int sig) {
61 switch (sig) {
62 case SIGILL:
63 case SIGFPE:
64 case SIGSEGV:
65 case SIGBUS:
66 return true;
67 default:
68 return false;
69 }
70}
71
72static const char *get_signame(int sig)
73{
74 switch(sig) {
75 case SIGILL: return "SIGILL";
76 case SIGABRT: return "SIGABRT";
77 case SIGBUS: return "SIGBUS";
78 case SIGFPE: return "SIGFPE";
79 case SIGSEGV: return "SIGSEGV";
80 case SIGPIPE: return "SIGPIPE";
Chris Dearman231e3c82012-08-10 17:06:20 -070081#ifdef SIGSTKFLT
Jeff Brown053b8652012-06-06 16:25:03 -070082 case SIGSTKFLT: return "SIGSTKFLT";
Chris Dearman231e3c82012-08-10 17:06:20 -070083#endif
Jeff Brown053b8652012-06-06 16:25:03 -070084 case SIGSTOP: return "SIGSTOP";
85 default: return "?";
86 }
87}
88
89static const char *get_sigcode(int signo, int code)
90{
Elliott Hughes8f7d4432012-12-10 10:29:05 -080091 // Try the signal-specific codes...
Jeff Brown053b8652012-06-06 16:25:03 -070092 switch (signo) {
93 case SIGILL:
94 switch (code) {
95 case ILL_ILLOPC: return "ILL_ILLOPC";
96 case ILL_ILLOPN: return "ILL_ILLOPN";
97 case ILL_ILLADR: return "ILL_ILLADR";
98 case ILL_ILLTRP: return "ILL_ILLTRP";
99 case ILL_PRVOPC: return "ILL_PRVOPC";
100 case ILL_PRVREG: return "ILL_PRVREG";
101 case ILL_COPROC: return "ILL_COPROC";
102 case ILL_BADSTK: return "ILL_BADSTK";
103 }
104 break;
105 case SIGBUS:
106 switch (code) {
107 case BUS_ADRALN: return "BUS_ADRALN";
108 case BUS_ADRERR: return "BUS_ADRERR";
109 case BUS_OBJERR: return "BUS_OBJERR";
110 }
111 break;
112 case SIGFPE:
113 switch (code) {
114 case FPE_INTDIV: return "FPE_INTDIV";
115 case FPE_INTOVF: return "FPE_INTOVF";
116 case FPE_FLTDIV: return "FPE_FLTDIV";
117 case FPE_FLTOVF: return "FPE_FLTOVF";
118 case FPE_FLTUND: return "FPE_FLTUND";
119 case FPE_FLTRES: return "FPE_FLTRES";
120 case FPE_FLTINV: return "FPE_FLTINV";
121 case FPE_FLTSUB: return "FPE_FLTSUB";
122 }
123 break;
124 case SIGSEGV:
125 switch (code) {
126 case SEGV_MAPERR: return "SEGV_MAPERR";
127 case SEGV_ACCERR: return "SEGV_ACCERR";
128 }
129 break;
Elliott Hughes8f7d4432012-12-10 10:29:05 -0800130 case SIGTRAP:
131 switch (code) {
132 case TRAP_BRKPT: return "TRAP_BRKPT";
133 case TRAP_TRACE: return "TRAP_TRACE";
134 }
135 break;
Jeff Brown053b8652012-06-06 16:25:03 -0700136 }
Elliott Hughes8f7d4432012-12-10 10:29:05 -0800137 // Then the other codes...
138 switch (code) {
139 case SI_USER: return "SI_USER";
140#if defined(SI_KERNEL)
141 case SI_KERNEL: return "SI_KERNEL";
142#endif
143 case SI_QUEUE: return "SI_QUEUE";
144 case SI_TIMER: return "SI_TIMER";
145 case SI_MESGQ: return "SI_MESGQ";
146 case SI_ASYNCIO: return "SI_ASYNCIO";
147#if defined(SI_SIGIO)
148 case SI_SIGIO: return "SI_SIGIO";
149#endif
150#if defined(SI_TKILL)
151 case SI_TKILL: return "SI_TKILL";
152#endif
153 }
154 // Then give up...
Jeff Brown053b8652012-06-06 16:25:03 -0700155 return "?";
156}
157
Ben Chengd7760c12012-09-19 16:04:01 -0700158static void dump_revision_info(log_t* log)
159{
160 char revision[PROPERTY_VALUE_MAX];
161
162 property_get("ro.revision", revision, "unknown");
163
Christopher Tate7716aef2013-04-02 14:00:27 -0700164 _LOG(log, SCOPE_AT_FAULT, "Revision: '%s'\n", revision);
Ben Chengd7760c12012-09-19 16:04:01 -0700165}
166
Jeff Brown053b8652012-06-06 16:25:03 -0700167static void dump_build_info(log_t* log)
168{
169 char fingerprint[PROPERTY_VALUE_MAX];
170
171 property_get("ro.build.fingerprint", fingerprint, "unknown");
172
Christopher Tate7716aef2013-04-02 14:00:27 -0700173 _LOG(log, SCOPE_AT_FAULT, "Build fingerprint: '%s'\n", fingerprint);
Jeff Brown053b8652012-06-06 16:25:03 -0700174}
175
176static void dump_fault_addr(log_t* log, pid_t tid, int sig)
177{
178 siginfo_t si;
179
180 memset(&si, 0, sizeof(si));
Christopher Tate7716aef2013-04-02 14:00:27 -0700181 if(ptrace(PTRACE_GETSIGINFO, tid, 0, &si)){
182 _LOG(log, SCOPE_AT_FAULT, "cannot get siginfo: %s\n", strerror(errno));
Jeff Brown053b8652012-06-06 16:25:03 -0700183 } else if (signal_has_address(sig)) {
Christopher Tate7716aef2013-04-02 14:00:27 -0700184 _LOG(log, SCOPE_AT_FAULT, "signal %d (%s), code %d (%s), fault addr %08x\n",
Jeff Brown053b8652012-06-06 16:25:03 -0700185 sig, get_signame(sig),
186 si.si_code, get_sigcode(sig, si.si_code),
187 (uintptr_t) si.si_addr);
188 } else {
Christopher Tate7716aef2013-04-02 14:00:27 -0700189 _LOG(log, SCOPE_AT_FAULT, "signal %d (%s), code %d (%s), fault addr --------\n",
Jeff Brown053b8652012-06-06 16:25:03 -0700190 sig, get_signame(sig), si.si_code, get_sigcode(sig, si.si_code));
191 }
192}
193
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700194static void dump_thread_info(log_t* log, pid_t pid, pid_t tid, int scope_flags) {
Jeff Brown053b8652012-06-06 16:25:03 -0700195 char path[64];
196 char threadnamebuf[1024];
197 char* threadname = NULL;
198 FILE *fp;
199
200 snprintf(path, sizeof(path), "/proc/%d/comm", tid);
201 if ((fp = fopen(path, "r"))) {
202 threadname = fgets(threadnamebuf, sizeof(threadnamebuf), fp);
203 fclose(fp);
204 if (threadname) {
205 size_t len = strlen(threadname);
206 if (len && threadname[len - 1] == '\n') {
207 threadname[len - 1] = '\0';
208 }
209 }
210 }
211
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700212 if (IS_AT_FAULT(scope_flags)) {
Jeff Brown053b8652012-06-06 16:25:03 -0700213 char procnamebuf[1024];
214 char* procname = NULL;
215
216 snprintf(path, sizeof(path), "/proc/%d/cmdline", pid);
217 if ((fp = fopen(path, "r"))) {
218 procname = fgets(procnamebuf, sizeof(procnamebuf), fp);
219 fclose(fp);
220 }
221
Christopher Tate7716aef2013-04-02 14:00:27 -0700222 _LOG(log, SCOPE_AT_FAULT, "pid: %d, tid: %d, name: %s >>> %s <<<\n", pid, tid,
Jeff Brown053b8652012-06-06 16:25:03 -0700223 threadname ? threadname : "UNKNOWN",
224 procname ? procname : "UNKNOWN");
225 } else {
Christopher Tate7716aef2013-04-02 14:00:27 -0700226 _LOG(log, 0, "pid: %d, tid: %d, name: %s\n",
227 pid, tid, threadname ? threadname : "UNKNOWN");
Jeff Brown053b8652012-06-06 16:25:03 -0700228 }
229}
230
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700231static void dump_stack_segment(const backtrace_t* backtrace, log_t* log,
232 int scope_flags, uintptr_t *sp, size_t words, int label) {
Jeff Brown053b8652012-06-06 16:25:03 -0700233 for (size_t i = 0; i < words; i++) {
234 uint32_t stack_content;
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700235 if (!backtrace_read_word(backtrace, *sp, &stack_content)) {
Jeff Brown053b8652012-06-06 16:25:03 -0700236 break;
237 }
238
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700239 const char* map_name = backtrace_get_map_info(backtrace, stack_content, NULL);
240 if (!map_name) {
241 map_name = "";
242 }
243 uintptr_t offset = 0;
244 char* proc_name = backtrace_get_proc_name(backtrace, stack_content, &offset);
245 if (proc_name) {
Jeff Brown053b8652012-06-06 16:25:03 -0700246 if (!i && label >= 0) {
247 if (offset) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700248 _LOG(log, scope_flags, " #%02d %08x %08x %s (%s+%u)\n",
249 label, *sp, stack_content, map_name, proc_name, offset);
Jeff Brown053b8652012-06-06 16:25:03 -0700250 } else {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700251 _LOG(log, scope_flags, " #%02d %08x %08x %s (%s)\n",
252 label, *sp, stack_content, map_name, proc_name);
Jeff Brown053b8652012-06-06 16:25:03 -0700253 }
254 } else {
255 if (offset) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700256 _LOG(log, scope_flags, " %08x %08x %s (%s+%u)\n",
257 *sp, stack_content, map_name, proc_name, offset);
Jeff Brown053b8652012-06-06 16:25:03 -0700258 } else {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700259 _LOG(log, scope_flags, " %08x %08x %s (%s)\n",
260 *sp, stack_content, map_name, proc_name);
Jeff Brown053b8652012-06-06 16:25:03 -0700261 }
262 }
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700263 free(proc_name);
Jeff Brown053b8652012-06-06 16:25:03 -0700264 } else {
265 if (!i && label >= 0) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700266 _LOG(log, scope_flags, " #%02d %08x %08x %s\n",
267 label, *sp, stack_content, map_name);
Jeff Brown053b8652012-06-06 16:25:03 -0700268 } else {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700269 _LOG(log, scope_flags, " %08x %08x %s\n",
270 *sp, stack_content, map_name);
Jeff Brown053b8652012-06-06 16:25:03 -0700271 }
272 }
273
274 *sp += sizeof(uint32_t);
275 }
276}
277
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700278static void dump_stack(const backtrace_t* backtrace, log_t* log, int scope_flags) {
279 size_t first = 0, last;
280 for (size_t i = 0; i < backtrace->num_frames; i++) {
281 if (backtrace->frames[i].sp) {
282 if (!first) {
283 first = i+1;
Jeff Brown053b8652012-06-06 16:25:03 -0700284 }
285 last = i;
286 }
287 }
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700288 if (!first) {
Jeff Brown053b8652012-06-06 16:25:03 -0700289 return;
290 }
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700291 first--;
Jeff Brown053b8652012-06-06 16:25:03 -0700292
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700293 scope_flags |= SCOPE_SENSITIVE;
Jeff Brown053b8652012-06-06 16:25:03 -0700294
295 // Dump a few words before the first frame.
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700296 uintptr_t sp = backtrace->frames[first].sp - STACK_WORDS * sizeof(uint32_t);
297 dump_stack_segment(backtrace, log, scope_flags, &sp, STACK_WORDS, -1);
Jeff Brown053b8652012-06-06 16:25:03 -0700298
299 // Dump a few words from all successive frames.
300 // Only log the first 3 frames, put the rest in the tombstone.
301 for (size_t i = first; i <= last; i++) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700302 const backtrace_frame_data_t* frame = &backtrace->frames[i];
303 if (sp != frame->sp) {
304 _LOG(log, scope_flags, " ........ ........\n");
305 sp = frame->sp;
Jeff Brown053b8652012-06-06 16:25:03 -0700306 }
307 if (i - first == 3) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700308 scope_flags &= (~SCOPE_AT_FAULT);
Jeff Brown053b8652012-06-06 16:25:03 -0700309 }
310 if (i == last) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700311 dump_stack_segment(backtrace, log, scope_flags, &sp, STACK_WORDS, i);
312 if (sp < frame->sp + frame->stack_size) {
313 _LOG(log, scope_flags, " ........ ........\n");
Jeff Brown053b8652012-06-06 16:25:03 -0700314 }
315 } else {
316 size_t words = frame->stack_size / sizeof(uint32_t);
317 if (words == 0) {
318 words = 1;
319 } else if (words > STACK_WORDS) {
320 words = STACK_WORDS;
321 }
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700322 dump_stack_segment(backtrace, log, scope_flags, &sp, words, i);
Jeff Brown053b8652012-06-06 16:25:03 -0700323 }
324 }
325}
326
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700327static void dump_backtrace_and_stack(const backtrace_t* backtrace, log_t* log,
328 int scope_flags) {
329 if (backtrace->num_frames) {
330 _LOG(log, scope_flags, "\nbacktrace:\n");
331 dump_backtrace_to_log(backtrace, log, scope_flags, " ");
332
333 _LOG(log, scope_flags, "\nstack:\n");
334 dump_stack(backtrace, log, scope_flags);
Jeff Brown053b8652012-06-06 16:25:03 -0700335 }
336}
337
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700338static void dump_map(log_t* log, const backtrace_map_info_t* m, const char* what, int scope_flags) {
Elliott Hughesd1420be2013-01-03 13:39:57 -0800339 if (m != NULL) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700340 _LOG(log, scope_flags, " %08x-%08x %c%c%c %s\n", m->start, m->end,
Elliott Hughesd1420be2013-01-03 13:39:57 -0800341 m->is_readable ? 'r' : '-',
342 m->is_writable ? 'w' : '-',
343 m->is_executable ? 'x' : '-',
344 m->name);
345 } else {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700346 _LOG(log, scope_flags, " (no %s)\n", what);
Elliott Hughesd1420be2013-01-03 13:39:57 -0800347 }
348}
349
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700350static void dump_nearby_maps(const backtrace_map_info_t* map_info_list, log_t* log, pid_t tid, int scope_flags) {
351 scope_flags |= SCOPE_SENSITIVE;
Jeff Brown053b8652012-06-06 16:25:03 -0700352 siginfo_t si;
353 memset(&si, 0, sizeof(si));
354 if (ptrace(PTRACE_GETSIGINFO, tid, 0, &si)) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700355 _LOG(log, scope_flags, "cannot get siginfo for %d: %s\n",
Jeff Brown053b8652012-06-06 16:25:03 -0700356 tid, strerror(errno));
357 return;
358 }
359 if (!signal_has_address(si.si_signo)) {
360 return;
361 }
362
363 uintptr_t addr = (uintptr_t) si.si_addr;
364 addr &= ~0xfff; /* round to 4K page boundary */
365 if (addr == 0) { /* null-pointer deref */
366 return;
367 }
368
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700369 _LOG(log, scope_flags, "\nmemory map around fault addr %08x:\n", (int)si.si_addr);
Jeff Brown053b8652012-06-06 16:25:03 -0700370
371 /*
372 * Search for a match, or for a hole where the match would be. The list
373 * is backward from the file content, so it starts at high addresses.
374 */
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700375 const backtrace_map_info_t* map = map_info_list;
376 const backtrace_map_info_t* next = NULL;
377 const backtrace_map_info_t* prev = NULL;
Jeff Brown053b8652012-06-06 16:25:03 -0700378 while (map != NULL) {
379 if (addr >= map->start && addr < map->end) {
Jeff Brown053b8652012-06-06 16:25:03 -0700380 next = map->next;
381 break;
382 } else if (addr >= map->end) {
383 /* map would be between "prev" and this entry */
384 next = map;
385 map = NULL;
386 break;
387 }
388
389 prev = map;
390 map = map->next;
391 }
392
393 /*
394 * Show "next" then "match" then "prev" so that the addresses appear in
395 * ascending order (like /proc/pid/maps).
396 */
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700397 dump_map(log, next, "map below", scope_flags);
398 dump_map(log, map, "map for address", scope_flags);
399 dump_map(log, prev, "map above", scope_flags);
Jeff Brown053b8652012-06-06 16:25:03 -0700400}
401
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700402static void dump_thread(const backtrace_t* backtrace, log_t* log, int scope_flags,
Jeff Brown053b8652012-06-06 16:25:03 -0700403 int* total_sleep_time_usec) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700404 wait_for_stop(backtrace->tid, total_sleep_time_usec);
Jeff Brown053b8652012-06-06 16:25:03 -0700405
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700406 dump_registers(log, backtrace->tid, scope_flags);
407 dump_backtrace_and_stack(backtrace, log, scope_flags);
408 if (IS_AT_FAULT(scope_flags)) {
409 dump_memory_and_code(log, backtrace->tid, scope_flags);
410 dump_nearby_maps(backtrace->map_info_list, log, backtrace->tid, scope_flags);
Jeff Brown053b8652012-06-06 16:25:03 -0700411 }
412}
413
414/* Return true if some thread is not detached cleanly */
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700415static bool dump_sibling_thread_report(
Jeff Brown053b8652012-06-06 16:25:03 -0700416 log_t* log, pid_t pid, pid_t tid, int* total_sleep_time_usec) {
417 char task_path[64];
418 snprintf(task_path, sizeof(task_path), "/proc/%d/task", pid);
419
420 DIR* d = opendir(task_path);
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700421 /* Bail early if the task directory cannot be opened */
Jeff Brown053b8652012-06-06 16:25:03 -0700422 if (d == NULL) {
423 XLOG("Cannot open /proc/%d/task\n", pid);
424 return false;
425 }
426
427 bool detach_failed = false;
Elliott Hughesc463d2c2012-10-26 16:47:09 -0700428 struct dirent* de;
429 while ((de = readdir(d)) != NULL) {
Jeff Brown053b8652012-06-06 16:25:03 -0700430 /* Ignore "." and ".." */
431 if (!strcmp(de->d_name, ".") || !strcmp(de->d_name, "..")) {
432 continue;
433 }
434
435 /* The main thread at fault has been handled individually */
436 char* end;
437 pid_t new_tid = strtoul(de->d_name, &end, 10);
438 if (*end || new_tid == tid) {
439 continue;
440 }
441
442 /* Skip this thread if cannot ptrace it */
443 if (ptrace(PTRACE_ATTACH, new_tid, 0, 0) < 0) {
444 continue;
445 }
446
Christopher Tate7716aef2013-04-02 14:00:27 -0700447 _LOG(log, 0, "--- --- --- --- --- --- --- --- --- --- --- --- --- --- --- ---\n");
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700448 dump_thread_info(log, pid, new_tid, 0);
449 backtrace_t new_backtrace;
450 if (backtrace_get_data(&new_backtrace, new_tid)) {
451 dump_thread(&new_backtrace, log, 0, total_sleep_time_usec);
452 }
453 backtrace_free_data(&new_backtrace);
Jeff Brown053b8652012-06-06 16:25:03 -0700454
455 if (ptrace(PTRACE_DETACH, new_tid, 0, 0) != 0) {
456 LOG("ptrace detach from %d failed: %s\n", new_tid, strerror(errno));
457 detach_failed = true;
458 }
459 }
460
461 closedir(d);
462 return detach_failed;
463}
464
465/*
466 * Reads the contents of the specified log device, filters out the entries
467 * that don't match the specified pid, and writes them to the tombstone file.
468 *
469 * If "tailOnly" is set, we only print the last few lines.
470 */
471static void dump_log_file(log_t* log, pid_t pid, const char* filename,
472 bool tailOnly)
473{
474 bool first = true;
475
476 /* circular buffer, for "tailOnly" mode */
477 const int kShortLogMaxLines = 5;
478 const int kShortLogLineLen = 256;
479 char shortLog[kShortLogMaxLines][kShortLogLineLen];
480 int shortLogCount = 0;
481 int shortLogNext = 0;
482
483 int logfd = open(filename, O_RDONLY | O_NONBLOCK);
484 if (logfd < 0) {
485 XLOG("Unable to open %s: %s\n", filename, strerror(errno));
486 return;
487 }
488
489 union {
490 unsigned char buf[LOGGER_ENTRY_MAX_LEN + 1];
491 struct logger_entry entry;
492 } log_entry;
493
494 while (true) {
495 ssize_t actual = read(logfd, log_entry.buf, LOGGER_ENTRY_MAX_LEN);
496 if (actual < 0) {
497 if (errno == EINTR) {
498 /* interrupted by signal, retry */
499 continue;
500 } else if (errno == EAGAIN) {
501 /* non-blocking EOF; we're done */
502 break;
503 } else {
Christopher Tate7716aef2013-04-02 14:00:27 -0700504 _LOG(log, 0, "Error while reading log: %s\n",
Jeff Brown053b8652012-06-06 16:25:03 -0700505 strerror(errno));
506 break;
507 }
508 } else if (actual == 0) {
Christopher Tate7716aef2013-04-02 14:00:27 -0700509 _LOG(log, 0, "Got zero bytes while reading log: %s\n",
Jeff Brown053b8652012-06-06 16:25:03 -0700510 strerror(errno));
511 break;
512 }
513
514 /*
515 * NOTE: if you XLOG something here, this will spin forever,
516 * because you will be writing as fast as you're reading. Any
517 * high-frequency debug diagnostics should just be written to
518 * the tombstone file.
519 */
520
521 struct logger_entry* entry = &log_entry.entry;
522
523 if (entry->pid != (int32_t) pid) {
524 /* wrong pid, ignore */
525 continue;
526 }
527
528 if (first) {
Christopher Tate7716aef2013-04-02 14:00:27 -0700529 _LOG(log, 0, "--------- %slog %s\n",
Jeff Brown053b8652012-06-06 16:25:03 -0700530 tailOnly ? "tail end of " : "", filename);
531 first = false;
532 }
533
534 /*
535 * Msg format is: <priority:1><tag:N>\0<message:N>\0
536 *
537 * We want to display it in the same format as "logcat -v threadtime"
538 * (although in this case the pid is redundant).
539 *
540 * TODO: scan for line breaks ('\n') and display each text line
541 * on a separate line, prefixed with the header, like logcat does.
542 */
543 static const char* kPrioChars = "!.VDIWEFS";
544 unsigned char prio = entry->msg[0];
545 char* tag = entry->msg + 1;
546 char* msg = tag + strlen(tag) + 1;
547
548 /* consume any trailing newlines */
549 char* eatnl = msg + strlen(msg) - 1;
550 while (eatnl >= msg && *eatnl == '\n') {
551 *eatnl-- = '\0';
552 }
553
554 char prioChar = (prio < strlen(kPrioChars) ? kPrioChars[prio] : '?');
555
556 char timeBuf[32];
557 time_t sec = (time_t) entry->sec;
558 struct tm tmBuf;
559 struct tm* ptm;
560 ptm = localtime_r(&sec, &tmBuf);
561 strftime(timeBuf, sizeof(timeBuf), "%m-%d %H:%M:%S", ptm);
562
563 if (tailOnly) {
564 snprintf(shortLog[shortLogNext], kShortLogLineLen,
565 "%s.%03d %5d %5d %c %-8s: %s",
566 timeBuf, entry->nsec / 1000000, entry->pid, entry->tid,
567 prioChar, tag, msg);
568 shortLogNext = (shortLogNext + 1) % kShortLogMaxLines;
569 shortLogCount++;
570 } else {
Christopher Tate7716aef2013-04-02 14:00:27 -0700571 _LOG(log, 0, "%s.%03d %5d %5d %c %-8s: %s\n",
Jeff Brown053b8652012-06-06 16:25:03 -0700572 timeBuf, entry->nsec / 1000000, entry->pid, entry->tid,
573 prioChar, tag, msg);
574 }
575 }
576
577 if (tailOnly) {
578 int i;
579
580 /*
581 * If we filled the buffer, we want to start at "next", which has
582 * the oldest entry. If we didn't, we want to start at zero.
583 */
584 if (shortLogCount < kShortLogMaxLines) {
585 shortLogNext = 0;
586 } else {
587 shortLogCount = kShortLogMaxLines; /* cap at window size */
588 }
589
590 for (i = 0; i < shortLogCount; i++) {
Christopher Tate7716aef2013-04-02 14:00:27 -0700591 _LOG(log, 0, "%s\n", shortLog[shortLogNext]);
Jeff Brown053b8652012-06-06 16:25:03 -0700592 shortLogNext = (shortLogNext + 1) % kShortLogMaxLines;
593 }
594 }
595
596 close(logfd);
597}
598
599/*
600 * Dumps the logs generated by the specified pid to the tombstone, from both
601 * "system" and "main" log devices. Ideally we'd interleave the output.
602 */
603static void dump_logs(log_t* log, pid_t pid, bool tailOnly)
604{
605 dump_log_file(log, pid, "/dev/log/system", tailOnly);
606 dump_log_file(log, pid, "/dev/log/main", tailOnly);
607}
608
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700609static void dump_abort_message(const backtrace_t* backtrace, log_t* log, uintptr_t address) {
Elliott Hughese5f8a692013-04-04 13:52:01 -0700610 if (address == 0) {
611 return;
612 }
613
614 address += sizeof(size_t); // Skip the buffer length.
615
616 char msg[512];
617 memset(msg, 0, sizeof(msg));
618 char* p = &msg[0];
619 while (p < &msg[sizeof(msg)]) {
620 uint32_t data;
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700621 if (!backtrace_read_word(backtrace, address, &data)) {
Elliott Hughese5f8a692013-04-04 13:52:01 -0700622 break;
623 }
624 address += sizeof(uint32_t);
625
626 if ((*p++ = (data >> 0) & 0xff) == 0) {
627 break;
628 }
629 if ((*p++ = (data >> 8) & 0xff) == 0) {
630 break;
631 }
632 if ((*p++ = (data >> 16) & 0xff) == 0) {
633 break;
634 }
635 if ((*p++ = (data >> 24) & 0xff) == 0) {
636 break;
637 }
638 }
639 msg[sizeof(msg) - 1] = '\0';
640
Christopher Tate7716aef2013-04-02 14:00:27 -0700641 _LOG(log, SCOPE_AT_FAULT, "Abort message: '%s'\n", msg);
Elliott Hughese5f8a692013-04-04 13:52:01 -0700642}
643
Jeff Brown053b8652012-06-06 16:25:03 -0700644/*
645 * Dumps all information about the specified pid to the tombstone.
646 */
Elliott Hughese5f8a692013-04-04 13:52:01 -0700647static bool dump_crash(log_t* log, pid_t pid, pid_t tid, int signal, uintptr_t abort_msg_address,
648 bool dump_sibling_threads, int* total_sleep_time_usec)
Jeff Brown053b8652012-06-06 16:25:03 -0700649{
650 /* don't copy log messages to tombstone unless this is a dev device */
651 char value[PROPERTY_VALUE_MAX];
652 property_get("ro.debuggable", value, "0");
653 bool want_logs = (value[0] == '1');
654
Christopher Tateded2e5a2013-03-19 13:12:23 -0700655 if (log->amfd >= 0) {
656 /*
657 * Activity Manager protocol: binary 32-bit network-byte-order ints for the
658 * pid and signal number, followed by the raw text of the dump, culminating
659 * in a zero byte that marks end-of-data.
660 */
661 uint32_t datum = htonl(pid);
662 TEMP_FAILURE_RETRY( write(log->amfd, &datum, 4) );
663 datum = htonl(signal);
664 TEMP_FAILURE_RETRY( write(log->amfd, &datum, 4) );
665 }
666
Christopher Tate7716aef2013-04-02 14:00:27 -0700667 _LOG(log, SCOPE_AT_FAULT,
Jeff Brown053b8652012-06-06 16:25:03 -0700668 "*** *** *** *** *** *** *** *** *** *** *** *** *** *** *** ***\n");
669 dump_build_info(log);
Ben Chengd7760c12012-09-19 16:04:01 -0700670 dump_revision_info(log);
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700671 dump_thread_info(log, pid, tid, SCOPE_AT_FAULT);
Elliott Hughese5f8a692013-04-04 13:52:01 -0700672 if (signal) {
Jeff Brown053b8652012-06-06 16:25:03 -0700673 dump_fault_addr(log, tid, signal);
674 }
675
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700676 backtrace_t backtrace;
677 if (backtrace_get_data(&backtrace, tid)) {
678 dump_abort_message(&backtrace, log, abort_msg_address);
679 dump_thread(&backtrace, log, SCOPE_AT_FAULT, total_sleep_time_usec);
680 backtrace_free_data(&backtrace);
681 }
Jeff Brown053b8652012-06-06 16:25:03 -0700682
683 if (want_logs) {
684 dump_logs(log, pid, true);
685 }
686
687 bool detach_failed = false;
688 if (dump_sibling_threads) {
Christopher Ferris365e4ae2013-10-02 12:26:48 -0700689 detach_failed = dump_sibling_thread_report(log, pid, tid, total_sleep_time_usec);
Jeff Brown053b8652012-06-06 16:25:03 -0700690 }
691
Jeff Brown053b8652012-06-06 16:25:03 -0700692 if (want_logs) {
693 dump_logs(log, pid, false);
694 }
Christopher Tateded2e5a2013-03-19 13:12:23 -0700695
696 /* send EOD to the Activity Manager, then wait for its ack to avoid racing ahead
697 * and killing the target out from under it */
698 if (log->amfd >= 0) {
699 uint8_t eodMarker = 0;
700 TEMP_FAILURE_RETRY( write(log->amfd, &eodMarker, 1) );
701 /* 3 sec timeout reading the ack; we're fine if that happens */
702 TEMP_FAILURE_RETRY( read(log->amfd, &eodMarker, 1) );
703 }
704
Jeff Brown053b8652012-06-06 16:25:03 -0700705 return detach_failed;
706}
707
708/*
709 * find_and_open_tombstone - find an available tombstone slot, if any, of the
710 * form tombstone_XX where XX is 00 to MAX_TOMBSTONES-1, inclusive. If no
711 * file is available, we reuse the least-recently-modified file.
712 *
713 * Returns the path of the tombstone file, allocated using malloc(). Caller must free() it.
714 */
715static char* find_and_open_tombstone(int* fd)
716{
717 unsigned long mtime = ULONG_MAX;
718 struct stat sb;
719
720 /*
721 * XXX: Our stat.st_mtime isn't time_t. If it changes, as it probably ought
722 * to, our logic breaks. This check will generate a warning if that happens.
723 */
724 typecheck(mtime, sb.st_mtime);
725
726 /*
727 * In a single wolf-like pass, find an available slot and, in case none
728 * exist, find and record the least-recently-modified file.
729 */
730 char path[128];
731 int oldest = 0;
732 for (int i = 0; i < MAX_TOMBSTONES; i++) {
733 snprintf(path, sizeof(path), TOMBSTONE_DIR"/tombstone_%02d", i);
734
735 if (!stat(path, &sb)) {
736 if (sb.st_mtime < mtime) {
737 oldest = i;
738 mtime = sb.st_mtime;
739 }
740 continue;
741 }
742 if (errno != ENOENT)
743 continue;
744
745 *fd = open(path, O_CREAT | O_EXCL | O_WRONLY, 0600);
746 if (*fd < 0)
747 continue; /* raced ? */
748
749 fchown(*fd, AID_SYSTEM, AID_SYSTEM);
750 return strdup(path);
751 }
752
753 /* we didn't find an available file, so we clobber the oldest one */
754 snprintf(path, sizeof(path), TOMBSTONE_DIR"/tombstone_%02d", oldest);
755 *fd = open(path, O_CREAT | O_TRUNC | O_WRONLY, 0600);
756 if (*fd < 0) {
757 LOG("failed to open tombstone file '%s': %s\n", path, strerror(errno));
758 return NULL;
759 }
760 fchown(*fd, AID_SYSTEM, AID_SYSTEM);
761 return strdup(path);
762}
763
Christopher Tateded2e5a2013-03-19 13:12:23 -0700764static int activity_manager_connect() {
765 int amfd = socket(PF_UNIX, SOCK_STREAM, 0);
766 if (amfd >= 0) {
767 struct sockaddr_un address;
768 int err;
769
770 memset(&address, 0, sizeof(address));
771 address.sun_family = AF_UNIX;
772 strncpy(address.sun_path, NCRASH_SOCKET_PATH, sizeof(address.sun_path));
773 err = TEMP_FAILURE_RETRY( connect(amfd, (struct sockaddr*) &address, sizeof(address)) );
774 if (!err) {
775 struct timeval tv;
776 memset(&tv, 0, sizeof(tv));
777 tv.tv_sec = 1; // tight leash
778 err = setsockopt(amfd, SOL_SOCKET, SO_SNDTIMEO, &tv, sizeof(tv));
779 if (!err) {
780 tv.tv_sec = 3; // 3 seconds on handshake read
781 err = setsockopt(amfd, SOL_SOCKET, SO_RCVTIMEO, &tv, sizeof(tv));
782 }
783 }
784 if (err) {
785 close(amfd);
786 amfd = -1;
787 }
788 }
789
790 return amfd;
791}
792
Elliott Hughese5f8a692013-04-04 13:52:01 -0700793char* engrave_tombstone(pid_t pid, pid_t tid, int signal, uintptr_t abort_msg_address,
Jeff Brown053b8652012-06-06 16:25:03 -0700794 bool dump_sibling_threads, bool quiet, bool* detach_failed,
795 int* total_sleep_time_usec) {
796 mkdir(TOMBSTONE_DIR, 0755);
797 chown(TOMBSTONE_DIR, AID_SYSTEM, AID_SYSTEM);
798
rpcraigf1186f32012-07-19 09:38:06 -0400799 if (selinux_android_restorecon(TOMBSTONE_DIR) == -1) {
800 *detach_failed = false;
801 return NULL;
802 }
rpcraigf1186f32012-07-19 09:38:06 -0400803
Jeff Brown053b8652012-06-06 16:25:03 -0700804 int fd;
805 char* path = find_and_open_tombstone(&fd);
806 if (!path) {
807 *detach_failed = false;
808 return NULL;
809 }
810
811 log_t log;
812 log.tfd = fd;
Christopher Tateded2e5a2013-03-19 13:12:23 -0700813 log.amfd = activity_manager_connect();
Jeff Brown053b8652012-06-06 16:25:03 -0700814 log.quiet = quiet;
Elliott Hughese5f8a692013-04-04 13:52:01 -0700815 *detach_failed = dump_crash(&log, pid, tid, signal, abort_msg_address, dump_sibling_threads,
Jeff Brown053b8652012-06-06 16:25:03 -0700816 total_sleep_time_usec);
817
Christopher Tateded2e5a2013-03-19 13:12:23 -0700818 close(log.amfd);
Jeff Brown053b8652012-06-06 16:25:03 -0700819 close(fd);
820 return path;
821}