blob: d6ebb0d5475e60aac20e4aa058c8568d98407223 [file] [log] [blame]
James Hawkinsabd73e62016-01-19 15:10:38 -08001/*
2 * Copyright (C) 2016 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// The bootstat command provides options to persist boot events with the current
18// timestamp, dump the persisted events, and log all events to EventLog to be
19// uploaded to Android log storage via Tron.
20
James Hawkinsa4a1a4a2016-02-09 15:32:38 -080021#include <getopt.h>
Mark Salyzynb304f6d2017-08-04 13:35:51 -070022#include <sys/klog.h>
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -070023#include <unistd.h>
Mark Salyzynff2dcd92016-09-28 15:54:45 -070024
James Hawkinse78ea772017-03-24 11:43:02 -070025#include <chrono>
James Hawkins0660b302016-03-08 16:18:15 -080026#include <cmath>
James Hawkinsabd73e62016-01-19 15:10:38 -080027#include <cstddef>
28#include <cstdio>
James Hawkins500d7152016-02-16 15:05:54 -080029#include <ctime>
Mark Salyzyn5c58c9d2018-06-28 09:21:55 -070030#include <iterator>
James Hawkinsa4a1a4a2016-02-09 15:32:38 -080031#include <map>
James Hawkinsabd73e62016-01-19 15:10:38 -080032#include <memory>
Mark Salyzyn25900dd2018-03-16 09:05:59 -070033#include <regex>
James Hawkinsabd73e62016-01-19 15:10:38 -080034#include <string>
Keun young Park606af6d2020-01-15 10:09:03 -080035#include <string_view>
36#include <unordered_map>
Mark Salyzyn853bb802018-03-16 08:44:56 -070037#include <utility>
James Hawkinsbe46fd12017-02-02 16:21:25 -080038#include <vector>
Mark Salyzynff2dcd92016-09-28 15:54:45 -070039
James Hawkinse78ea772017-03-24 11:43:02 -070040#include <android-base/chrono_utils.h>
Mark Salyzynb304f6d2017-08-04 13:35:51 -070041#include <android-base/file.h>
James Hawkinseabe08b2016-01-19 16:54:35 -080042#include <android-base/logging.h>
James Hawkins4dded612016-07-28 11:50:23 -070043#include <android-base/parseint.h>
Luis Hector Chavez583d34c2018-04-12 15:25:15 -070044#include <android-base/properties.h>
James Hawkinsbe46fd12017-02-02 16:21:25 -080045#include <android-base/strings.h>
James Hawkinse78ea772017-03-24 11:43:02 -070046#include <android/log.h>
Mark Salyzynb304f6d2017-08-04 13:35:51 -070047#include <cutils/android_reboot.h>
James Hawkinsa4a1a4a2016-02-09 15:32:38 -080048#include <cutils/properties.h>
Tej Singh4eacd382018-01-25 17:59:57 -080049#include <statslog.h>
Mark Salyzynff2dcd92016-09-28 15:54:45 -070050
James Hawkinsabd73e62016-01-19 15:10:38 -080051#include "boot_event_record_store.h"
James Hawkinsabd73e62016-01-19 15:10:38 -080052
53namespace {
54
Keun young Park606af6d2020-01-15 10:09:03 -080055struct AtomInfo {
56 int32_t atom;
57 int32_t event;
58};
59
60// Maps BootEvent used inside bootstat into statsd atom defined in
61// frameworks/base/cmds/statsd/src/atoms.proto.
62const std::unordered_map<std::string_view, AtomInfo> kBootEventToAtomInfo = {
63 // ELAPSED_TIME
64 {"ro.boottime.init",
65 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
66 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__ANDROID_INIT_STAGE_1}},
67 {"boot_complete",
68 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
69 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__BOOT_COMPLETE}},
70 {"boot_decryption_complete",
71 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
72 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__BOOT_COMPLETE_ENCRYPTION}},
73 {"boot_complete_no_encryption",
74 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
75 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__BOOT_COMPLETE_NO_ENCRYPTION}},
76 {"boot_complete_post_decrypt",
77 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
78 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__BOOT_COMPLETE_POST_DECRYPT}},
79 {"factory_reset_boot_complete",
80 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
81 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__FACTORY_RESET_BOOT_COMPLETE}},
82 {"factory_reset_boot_complete_no_encryption",
83 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
84 android::util::
85 BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__FACTORY_RESET_BOOT_COMPLETE_NO_ENCRYPTION}},
86 {"factory_reset_boot_complete_post_decrypt",
87 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
88 android::util::
89 BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__FACTORY_RESET_BOOT_COMPLETE_POST_DECRYPT}},
90 {"ota_boot_complete",
91 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
92 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__OTA_BOOT_COMPLETE}},
93 {"ota_boot_complete_no_encryption",
94 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
95 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__OTA_BOOT_COMPLETE_NO_ENCRYPTION}},
96 {"ota_boot_complete_post_decrypt",
97 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
98 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__OTA_BOOT_COMPLETE_POST_DECRYPT}},
99 {"post_decrypt_time_elapsed",
100 {android::util::BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
101 android::util::BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__POST_DECRYPT}},
102 // DURATION
103 {"absolute_boot_time",
104 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
105 android::util::BOOT_TIME_EVENT_DURATION__EVENT__ABSOLUTE_BOOT_TIME}},
106 {"boottime.bootloader.1BLE",
107 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
108 android::util::BOOT_TIME_EVENT_DURATION__EVENT__BOOTLOADER_FIRST_STAGE_EXEC}},
109 {"boottime.bootloader.1BLL",
110 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
111 android::util::BOOT_TIME_EVENT_DURATION__EVENT__BOOTLOADER_FIRST_STAGE_LOAD}},
112 {"boottime.bootloader.KL",
113 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
114 android::util::BOOT_TIME_EVENT_DURATION__EVENT__BOOTLOADER_KERNEL_LOAD}},
115 {"boottime.bootloader.2BLE",
116 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
117 android::util::BOOT_TIME_EVENT_DURATION__EVENT__BOOTLOADER_SECOND_STAGE_EXEC}},
118 {"boottime.bootloader.2BLL",
119 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
120 android::util::BOOT_TIME_EVENT_DURATION__EVENT__BOOTLOADER_SECOND_STAGE_LOAD}},
121 {"boottime.bootloader.SW",
122 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
123 android::util::BOOT_TIME_EVENT_DURATION__EVENT__BOOTLOADER_UI_WAIT}},
124 {"boottime.bootloader.total",
125 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
126 android::util::BOOT_TIME_EVENT_DURATION__EVENT__BOOTLOADER_TOTAL}},
127 {"boottime.init.cold_boot_wait",
128 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
129 android::util::BOOT_TIME_EVENT_DURATION__EVENT__COLDBOOT_WAIT}},
130 {"time_since_factory_reset",
131 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
132 android::util::BOOT_TIME_EVENT_DURATION__EVENT__FACTORY_RESET_TIME_SINCE_RESET}},
133 {"ro.boottime.init.first_stage",
134 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
135 android::util::BOOT_TIME_EVENT_DURATION__EVENT__ANDROID_INIT_STAGE_1}},
136 {"ro.boottime.init.selinux",
137 {android::util::BOOT_TIME_EVENT_DURATION_REPORTED,
138 android::util::BOOT_TIME_EVENT_DURATION__EVENT__SELINUX_INIT}},
139 // UTC_TIME
140 {"factory_reset",
141 {android::util::BOOT_TIME_EVENT_UTC_TIME_REPORTED,
142 android::util::BOOT_TIME_EVENT_UTC_TIME__EVENT__FACTORY_RESET_RESET_TIME}},
143 {"factory_reset_current_time",
144 {android::util::BOOT_TIME_EVENT_UTC_TIME_REPORTED,
145 android::util::BOOT_TIME_EVENT_UTC_TIME__EVENT__FACTORY_RESET_CURRENT_TIME}},
146 {"factory_reset_record_value",
147 {android::util::BOOT_TIME_EVENT_UTC_TIME_REPORTED,
148 android::util::BOOT_TIME_EVENT_UTC_TIME__EVENT__FACTORY_RESET_RECORD_VALUE}},
149 // ERROR_CODE
150 {"factory_reset_current_time_failure",
151 {android::util::BOOT_TIME_EVENT_ERROR_CODE_REPORTED,
152 android::util::BOOT_TIME_EVENT_ERROR_CODE__EVENT__FACTORY_RESET_CURRENT_TIME_FAILURE}},
153};
154
James Hawkinsabd73e62016-01-19 15:10:38 -0800155// Scans the boot event record store for record files and logs each boot event
156// via EventLog.
157void LogBootEvents() {
158 BootEventRecordStore boot_event_store;
James Hawkinsabd73e62016-01-19 15:10:38 -0800159 auto events = boot_event_store.GetAllBootEvents();
Keun young Park606af6d2020-01-15 10:09:03 -0800160 std::vector<std::string_view> notSupportedEvents;
161 for (const auto& event : events) {
162 const auto& name = event.first;
163 const auto& info = kBootEventToAtomInfo.find(name);
164 if (info != kBootEventToAtomInfo.end()) {
165 if (info->second.atom == android::util::BOOT_TIME_EVENT_ERROR_CODE_REPORTED) {
166 android::util::stats_write(static_cast<int32_t>(info->second.atom),
167 static_cast<int32_t>(info->second.event),
168 static_cast<int32_t>(event.second));
169 } else {
170 android::util::stats_write(static_cast<int32_t>(info->second.atom),
171 static_cast<int32_t>(info->second.event),
172 static_cast<int64_t>(event.second));
173 }
174 } else {
175 notSupportedEvents.push_back(name);
176 }
177 }
178 if (!notSupportedEvents.empty()) {
179 LOG(WARNING) << "LogBootEvents, atomInfo not defined for events:"
180 << android::base::Join(notSupportedEvents, ',');
181 }
James Hawkinsabd73e62016-01-19 15:10:38 -0800182}
183
James Hawkinsc6275582016-03-22 10:47:44 -0700184// Records the named boot |event| to the record store. If |value| is non-empty
185// and is a proper string representation of an integer value, the converted
186// integer value is associated with the boot event.
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700187void RecordBootEventFromCommandLine(const std::string& event, const std::string& value_str) {
James Hawkinsc6275582016-03-22 10:47:44 -0700188 BootEventRecordStore boot_event_store;
189 if (!value_str.empty()) {
190 int32_t value = 0;
Elliott Hughesda46b392016-10-11 17:09:00 -0700191 if (android::base::ParseInt(value_str, &value)) {
James Hawkins4dded612016-07-28 11:50:23 -0700192 boot_event_store.AddBootEventWithValue(event, value);
193 }
James Hawkinsc6275582016-03-22 10:47:44 -0700194 } else {
195 boot_event_store.AddBootEvent(event);
196 }
197}
198
James Hawkinsabd73e62016-01-19 15:10:38 -0800199void PrintBootEvents() {
200 printf("Boot events:\n");
201 printf("------------\n");
202
203 BootEventRecordStore boot_event_store;
204 auto events = boot_event_store.GetAllBootEvents();
205 for (auto i = events.cbegin(); i != events.cend(); ++i) {
206 printf("%s\t%d\n", i->first.c_str(), i->second);
207 }
208}
209
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700210void ShowHelp(const char* cmd) {
Mark Salyzyn67ee8a82019-04-18 12:41:29 -0700211 fprintf(stderr, "Usage: %s [options]...\n", cmd);
James Hawkinsabd73e62016-01-19 15:10:38 -0800212 fprintf(stderr,
213 "options include:\n"
Yongqin Liu78b2b942017-07-07 13:26:49 +0800214 " -h, --help Show this help\n"
215 " -l, --log Log all metrics to logstorage\n"
216 " -p, --print Dump the boot event records to the console\n"
217 " -r, --record Record the timestamp of a named boot event\n"
218 " --value Optional value to associate with the boot event\n"
219 " --record_boot_complete Record metrics related to the time for the device boot\n"
220 " --record_boot_reason Record the reason why the device booted\n"
Mark Salyzyn67ee8a82019-04-18 12:41:29 -0700221 " --record_time_since_factory_reset Record the time since the device was reset\n"
222 " --boot_reason_enum=<reason> Report the match to the kBootReasonMap table\n");
James Hawkinsabd73e62016-01-19 15:10:38 -0800223}
224
225// Constructs a readable, printable string from the givencommand line
226// arguments.
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700227std::string GetCommandLine(int argc, char** argv) {
James Hawkinsabd73e62016-01-19 15:10:38 -0800228 std::string cmd;
229 for (int i = 0; i < argc; ++i) {
230 cmd += argv[i];
231 cmd += " ";
232 }
233
234 return cmd;
235}
236
James Hawkins25f71222017-10-10 16:37:05 -0700237constexpr int32_t kEmptyBootReason = 0;
James Hawkins6f74c0b2016-02-12 15:49:16 -0800238constexpr int32_t kUnknownBootReason = 1;
239
James Hawkinsa4a1a4a2016-02-09 15:32:38 -0800240// A mapping from boot reason string, as read from the ro.boot.bootreason
241// system property, to a unique integer ID. Viewers of log data dashboards for
242// the boot_reason metric may refer to this mapping to discern the histogram
Mark Salyzyn67ee8a82019-04-18 12:41:29 -0700243// values. Regex matching, to manage the scale, as a minimum require either
244// [, \ or * to be present in the string to switch to checking.
James Hawkins6f74c0b2016-02-12 15:49:16 -0800245const std::map<std::string, int32_t> kBootReasonMap = {
Mark Salyzyn67ee8a82019-04-18 12:41:29 -0700246 {"reboot,[empty]", kEmptyBootReason},
Mark Salyzyn2b820532018-03-16 08:53:34 -0700247 {"__BOOTSTAT_UNKNOWN__", kUnknownBootReason},
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700248 {"normal", 2},
249 {"recovery", 3},
250 {"reboot", 4},
251 {"PowerKey", 5},
252 {"hard_reset", 6},
253 {"kernel_panic", 7},
254 {"rpm_err", 8},
255 {"hw_reset", 9},
256 {"tz_err", 10},
257 {"adsp_err", 11},
258 {"modem_err", 12},
259 {"mba_err", 13},
260 {"Watchdog", 14},
261 {"Panic", 15},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700262 {"power_key", 16}, // aliasReasons to cold,powerkey (Mediatek)
263 {"power_on", 17}, // aliasReasons to cold,powerkey
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700264 {"Reboot", 18},
265 {"rtc", 19},
266 {"edl", 20},
267 {"oem_pon1", 21},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700268 {"oem_powerkey", 22}, // aliasReasons to cold,powerkey
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700269 {"oem_unknown_reset", 23},
270 {"srto: HWWDT reset SC", 24},
271 {"srto: HWWDT reset platform", 25},
272 {"srto: bootloader", 26},
273 {"srto: kernel panic", 27},
274 {"srto: kernel watchdog reset", 28},
275 {"srto: normal", 29},
276 {"srto: reboot", 30},
277 {"srto: reboot-bootloader", 31},
278 {"srto: security watchdog reset", 32},
279 {"srto: wakesrc", 33},
280 {"srto: watchdog", 34},
281 {"srto:1-1", 35},
282 {"srto:omap_hsmm", 36},
283 {"srto:phy0", 37},
284 {"srto:rtc0", 38},
285 {"srto:touchpad", 39},
286 {"watchdog", 40},
287 {"watchdogr", 41},
288 {"wdog_bark", 42},
289 {"wdog_bite", 43},
290 {"wdog_reset", 44},
Mark Salyzyn274b5442018-08-07 08:45:13 -0700291 {"shutdown,", 45}, // Trailing comma is intentional. Do NOT use.
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700292 {"shutdown,userrequested", 46},
293 {"reboot,bootloader", 47},
294 {"reboot,cold", 48},
295 {"reboot,recovery", 49},
296 {"thermal_shutdown", 50},
297 {"s3_wakeup", 51},
298 {"kernel_panic,sysrq", 52},
299 {"kernel_panic,NULL", 53},
Mark Salyzyn853bb802018-03-16 08:44:56 -0700300 {"kernel_panic,null", 53},
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700301 {"kernel_panic,BUG", 54},
Mark Salyzyn853bb802018-03-16 08:44:56 -0700302 {"kernel_panic,bug", 54},
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700303 {"bootloader", 55},
304 {"cold", 56},
305 {"hard", 57},
306 {"warm", 58},
Mark Salyzyn15199252018-03-16 09:26:05 -0700307 {"reboot,kernel_power_off_charging__reboot_system", 59}, // Can not happen
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700308 {"thermal-shutdown", 60},
309 {"shutdown,thermal", 61},
310 {"shutdown,battery", 62},
311 {"reboot,ota", 63},
312 {"reboot,factory_reset", 64},
313 {"reboot,", 65},
314 {"reboot,shell", 66},
315 {"reboot,adb", 67},
Mark Salyzyn9033bf52017-09-21 11:30:29 -0700316 {"reboot,userrequested", 68},
Mark Salyzyn161b8622017-09-26 08:26:12 -0700317 {"shutdown,container", 69}, // Host OS asking Android Container to shutdown
Mark Salyzyn243fa292017-10-11 09:02:04 -0700318 {"cold,powerkey", 70},
319 {"warm,s3_wakeup", 71},
320 {"hard,hw_reset", 72},
321 {"shutdown,suspend", 73}, // Suspend to RAM
322 {"shutdown,hibernate", 74}, // Suspend to DISK
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700323 {"power_on_key", 75}, // aliasReasons to cold,powerkey
324 {"reboot_by_key", 76}, // translated to reboot,by_key
325 {"wdt_by_pass_pwk", 77}, // Mediatek
326 {"reboot_longkey", 78}, // translated to reboot,longkey
327 {"powerkey", 79}, // aliasReasons to cold,powerkey
328 {"usb", 80}, // aliasReasons to cold,charger (Mediatek)
329 {"wdt", 81}, // Mediatek
330 {"tool_by_pass_pwk", 82}, // aliasReasons to reboot,tool (Mediatek)
331 {"2sec_reboot", 83}, // aliasReasons to cold,rtc,2sec (Mediatek)
James Hawkins34073b52017-10-17 15:53:27 -0700332 {"reboot,by_key", 84},
333 {"reboot,longkey", 85},
Mark Salyzyn186f6762018-03-16 11:00:26 -0700334 {"reboot,2sec", 86}, // Deprecate in two years, replaced with cold,rtc,2sec
Mark Salyzync89f9da2017-10-24 15:35:34 -0700335 {"shutdown,thermal,battery", 87},
Mark Salyzyn72a8ea32017-10-25 09:23:19 -0700336 {"reboot,its_just_so_hard", 88}, // produced by boot_reason_test
337 {"reboot,Its Just So Hard", 89}, // produced by boot_reason_test
Mark Salyzyn75046892018-05-03 13:11:15 -0700338 {"reboot,rescueparty", 90},
James Hawkins74b17582017-11-20 14:13:41 -0800339 {"charge", 91},
340 {"oem_tz_crash", 92},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700341 {"uvlo", 93}, // aliasReasons to reboot,undervoltage
James Hawkins74b17582017-11-20 14:13:41 -0800342 {"oem_ps_hold", 94},
343 {"abnormal_reset", 95},
344 {"oemerr_unknown", 96},
345 {"reboot_fastboot_mode", 97},
James Hawkins5f85f832017-11-29 14:30:06 -0800346 {"watchdog_apps_bite", 98},
347 {"xpu_err", 99},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700348 {"power_on_usb", 100}, // aliasReasons to cold,charger
James Hawkinsf4444f02017-11-30 15:01:40 -0800349 {"watchdog_rpm", 101},
350 {"watchdog_nonsec", 102},
351 {"watchdog_apps_bark", 103},
352 {"reboot_dmverity_corrupted", 104},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700353 {"reboot_smpl", 105}, // aliasReasons to reboot,powerloss
James Hawkins00433a22017-12-04 14:20:21 -0800354 {"watchdog_sdi_apps_reset", 106},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700355 {"smpl", 107}, // aliasReasons to reboot,powerloss
James Hawkins00433a22017-12-04 14:20:21 -0800356 {"oem_modem_failed_to_powerup", 108},
James Hawkinse2c27242017-12-18 13:40:27 -0800357 {"reboot_normal", 109},
358 {"oem_lpass_cfg", 110},
359 {"oem_xpu_ns_error", 111},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700360 {"power_key_press", 112}, // aliasReasons to cold,powerkey
James Hawkinse2c27242017-12-18 13:40:27 -0800361 {"hardware_reset", 113},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700362 {"reboot_by_powerkey", 114}, // aliasReasons to cold,powerkey (is this correct?)
James Hawkinse2c27242017-12-18 13:40:27 -0800363 {"reboot_verity", 115},
364 {"oem_rpm_undef_error", 116},
365 {"oem_crash_on_the_lk", 117},
366 {"oem_rpm_reset", 118},
Mark Salyzynf62983a2018-09-26 09:55:25 -0700367 {"reboot,powerloss", 119},
Mark Salyzynec7bafe2018-09-26 08:01:04 -0700368 {"reboot,undervoltage", 120},
James Hawkinse2c27242017-12-18 13:40:27 -0800369 {"factory_cable", 121},
370 {"oem_ar6320_failed_to_powerup", 122},
371 {"watchdog_rpm_bite", 123},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700372 {"power_on_cable", 124}, // aliasReasons to cold,charger
James Hawkinse2c27242017-12-18 13:40:27 -0800373 {"reboot_unknown", 125},
374 {"wireless_charger", 126},
375 {"0x776655ff", 127},
376 {"oem_thermal_bite_reset", 128},
377 {"charger", 129},
378 {"pon1", 130},
379 {"unknown", 131},
380 {"reboot_rtc", 132},
381 {"cold_boot", 133},
382 {"hard_rst", 134},
James Hawkinsb607dae2018-01-05 14:42:55 -0800383 {"power-on", 135},
384 {"oem_adsp_resetting_the_soc", 136},
385 {"kpdpwr", 137},
386 {"oem_modem_timeout_waiting", 138},
387 {"usb_chg", 139},
388 {"warm_reset_0x02", 140},
389 {"warm_reset_0x80", 141},
390 {"pon_reason_0xb0", 142},
391 {"reboot_download", 143},
James Hawkins79a4ee22018-01-26 14:31:04 -0800392 {"reboot_recovery_mode", 144},
393 {"oem_sdi_err_fatal", 145},
394 {"pmic_watchdog", 146},
395 {"software_master", 147},
Mark Salyzyn8aa36c62018-03-16 11:00:14 -0700396 {"cold,charger", 148},
397 {"cold,rtc", 149},
Mark Salyzyn4e7acf72018-03-16 11:00:26 -0700398 {"cold,rtc,2sec", 150}, // Mediatek
399 {"reboot,tool", 151}, // Mediatek
400 {"reboot,wdt", 152}, // Mediatek
401 {"reboot,unknown", 153}, // Mediatek
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700402 {"kernel_panic,audit", 154},
403 {"kernel_panic,atomic", 155},
404 {"kernel_panic,hung", 156},
405 {"kernel_panic,hung,rcu", 157},
406 {"kernel_panic,init", 158},
407 {"kernel_panic,oom", 159},
408 {"kernel_panic,stack", 160},
Mark Salyzynafd66f22018-03-19 15:16:29 -0700409 {"kernel_panic,sysrq,livelock,alarm", 161}, // llkd
410 {"kernel_panic,sysrq,livelock,driver", 162}, // llkd
411 {"kernel_panic,sysrq,livelock,zombie", 163}, // llkd
Mark Salyzyn8ad6e672018-06-01 08:59:05 -0700412 {"kernel_panic,modem", 164},
413 {"kernel_panic,adsp", 165},
414 {"kernel_panic,dsps", 166},
415 {"kernel_panic,wcnss", 167},
Mark Salyzyn78e54fd2018-06-08 10:19:16 -0700416 {"kernel_panic,_sde_encoder_phys_cmd_handle_ppdone_timeout", 168},
Mark Salyzyn6fc08292019-03-11 10:06:36 -0700417 {"recovery,quiescent", 169},
418 {"reboot,quiescent", 170},
Jone Choud51036d2019-03-20 19:38:05 +0800419 {"reboot,rtc", 171},
420 {"reboot,dm-verity_device_corrupted", 172},
421 {"reboot,dm-verity_enforcing", 173},
422 {"reboot,keys_clear", 174},
Jone Chou446d6c62019-04-18 15:43:26 +0800423 {"reboot,pmic_off_fault,.*", 175},
424 {"reboot,pmic_off_s3rst,.*", 176},
425 {"reboot,pmic_off_other,.*", 177},
Mark Salyzyn65d8b9b2019-05-23 09:07:54 -0700426 {"reboot,userrequested,fastboot", 178},
427 {"reboot,userrequested,recovery", 179},
428 {"reboot,userrequested,recovery,ui", 180},
429 {"shutdown,userrequested,fastboot", 181},
430 {"shutdown,userrequested,recovery", 182},
Mark Salyzyn8d1be802019-05-21 10:47:55 -0700431 {"reboot,unknown[0-9]*", 183},
Jone Choub9a80332019-06-10 23:24:39 +0800432 {"reboot,longkey,.*", 184},
Tom Cherrya76bfb22019-09-18 09:41:36 -0700433 {"reboot,boringssl-self-check-failed", 185},
Nikita Ioffe4a787d92020-01-15 23:23:13 +0000434 {"reboot,userspace_failed,shutdown_aborted", 186},
435 {"reboot,userspace_failed,watchdog_triggered", 187},
436 {"reboot,userspace_failed,watchdog_fork", 188},
437 {"reboot,userspace_failed,*", 189},
438 {"reboot,mount_userdata_failed", 190},
Jim Kayeb7386a62020-07-01 16:57:01 -0700439 {"reboot,forcedsilent", 191},
440 {"reboot,forcednonsilent", 192},
Lisa Liu82674de2021-03-16 17:43:32 +0800441 {"reboot,thermal,tj", 193},
James Hawkinsa4a1a4a2016-02-09 15:32:38 -0800442};
443
444// Converts a string value representing the reason the system booted to an
445// integer representation. This is necessary for logging the boot_reason metric
446// via Tron, which does not accept non-integer buckets in histograms.
447int32_t BootReasonStrToEnum(const std::string& boot_reason) {
James Hawkinsa4a1a4a2016-02-09 15:32:38 -0800448 auto mapping = kBootReasonMap.find(boot_reason);
449 if (mapping != kBootReasonMap.end()) {
450 return mapping->second;
451 }
452
James Hawkins25f71222017-10-10 16:37:05 -0700453 if (boot_reason.empty()) {
454 return kEmptyBootReason;
455 }
456
Mark Salyzyn67ee8a82019-04-18 12:41:29 -0700457 for (const auto& [match, id] : kBootReasonMap) {
458 // Regex matches as a minimum require either [, \ or * to be present.
459 if (match.find_first_of("[\\*") == match.npos) continue;
460 // enforce match from beginning to end
461 auto exact = match;
462 if (exact[0] != '^') exact = "^" + exact;
463 if (exact[exact.size() - 1] != '$') exact = exact + "$";
464 if (std::regex_search(boot_reason, std::regex(exact))) return id;
465 }
466
James Hawkinsa4a1a4a2016-02-09 15:32:38 -0800467 LOG(INFO) << "Unknown boot reason: " << boot_reason;
468 return kUnknownBootReason;
469}
470
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700471// Canonical list of supported primary reboot reasons.
472const std::vector<const std::string> knownReasons = {
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700473 // clang-format off
474 // kernel
475 "watchdog",
476 "kernel_panic",
477 // strong
478 "recovery", // Should not happen from ro.boot.bootreason
479 "bootloader", // Should not happen from ro.boot.bootreason
480 // blunt
481 "cold",
482 "hard",
483 "warm",
Mark Salyzyn62909822017-10-09 09:27:16 -0700484 // super blunt
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700485 "shutdown", // Can not happen from ro.boot.bootreason
486 "reboot", // Default catch-all for anything unknown
487 // clang-format on
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700488};
489
490// Returns true if the supplied reason prefix is considered detailed enough.
491bool isStrongRebootReason(const std::string& r) {
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700492 for (auto& s : knownReasons) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700493 if (s == "cold") break;
494 // Prefix defined as terminated by a nul or comma (,).
Elliott Hughes579e6822017-12-20 09:41:00 -0800495 if (android::base::StartsWith(r, s) && ((r.length() == s.length()) || (r[s.length()] == ','))) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700496 return true;
497 }
498 }
499 return false;
500}
501
502// Returns true if the supplied reason prefix is associated with the kernel.
503bool isKernelRebootReason(const std::string& r) {
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700504 for (auto& s : knownReasons) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700505 if (s == "recovery") break;
506 // Prefix defined as terminated by a nul or comma (,).
Elliott Hughes579e6822017-12-20 09:41:00 -0800507 if (android::base::StartsWith(r, s) && ((r.length() == s.length()) || (r[s.length()] == ','))) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700508 return true;
509 }
510 }
511 return false;
512}
513
514// Returns true if the supplied reason prefix is considered known.
515bool isKnownRebootReason(const std::string& r) {
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700516 for (auto& s : knownReasons) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700517 // Prefix defined as terminated by a nul or comma (,).
Elliott Hughes579e6822017-12-20 09:41:00 -0800518 if (android::base::StartsWith(r, s) && ((r.length() == s.length()) || (r[s.length()] == ','))) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700519 return true;
520 }
521 }
522 return false;
523}
524
525// If the reboot reason should be improved, report true if is too blunt.
526bool isBluntRebootReason(const std::string& r) {
527 if (isStrongRebootReason(r)) return false;
528
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700529 if (!isKnownRebootReason(r)) return true; // Can not support unknown as detail
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700530
531 size_t pos = 0;
532 while ((pos = r.find(',', pos)) != std::string::npos) {
533 ++pos;
534 std::string next(r.substr(pos));
535 if (next.length() == 0) break;
536 if (next[0] == ',') continue;
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700537 if (!isKnownRebootReason(next)) return false; // Unknown subreason is good.
538 if (isStrongRebootReason(next)) return false; // eg: reboot,reboot
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700539 }
540 return true;
541}
542
Mark Salyzyn64610892017-09-18 10:41:14 -0700543bool readPstoreConsole(std::string& console) {
544 if (android::base::ReadFileToString("/sys/fs/pstore/console-ramoops-0", &console)) {
545 return true;
546 }
547 return android::base::ReadFileToString("/sys/fs/pstore/console-ramoops", &console);
548}
549
Mark Salyzyn293cb3b2017-09-20 08:37:46 -0700550// Implement a variant of std::string::rfind that is resilient to errors in
551// the data stream being inspected.
552class pstoreConsole {
553 private:
554 const size_t kBitErrorRate = 8; // number of bits per error
555 const std::string& console;
556
557 // Number of bits that differ between the two arguments l and r.
558 // Returns zero if the values for l and r are identical.
559 size_t numError(uint8_t l, uint8_t r) const { return std::bitset<8>(l ^ r).count(); }
560
561 // A string comparison function, reports the number of errors discovered
562 // in the match to a maximum of the bitLength / kBitErrorRate, at that
563 // point returning npos to indicate match is too poor.
564 //
565 // Since called in rfind which works backwards, expect cache locality will
566 // help if we check in reverse here as well for performance.
567 //
568 // Assumption: l (from console.c_str() + pos) is long enough to house
569 // _r.length(), checked in rfind caller below.
570 //
571 size_t numError(size_t pos, const std::string& _r) const {
572 const char* l = console.c_str() + pos;
573 const char* r = _r.c_str();
574 size_t n = _r.length();
575 const uint8_t* le = reinterpret_cast<const uint8_t*>(l) + n;
576 const uint8_t* re = reinterpret_cast<const uint8_t*>(r) + n;
577 size_t count = 0;
578 n = 0;
579 do {
580 // individual character bit error rate > threshold + slop
581 size_t num = numError(*--le, *--re);
582 if (num > ((8 + kBitErrorRate) / kBitErrorRate)) return std::string::npos;
583 // total bit error rate > threshold + slop
584 count += num;
585 ++n;
586 if (count > ((n * 8 + kBitErrorRate - (n > 2)) / kBitErrorRate)) {
587 return std::string::npos;
588 }
589 } while (le != reinterpret_cast<const uint8_t*>(l));
590 return count;
591 }
592
593 public:
594 explicit pstoreConsole(const std::string& console) : console(console) {}
595 // scope of argument must be equal to or greater than scope of pstoreConsole
596 explicit pstoreConsole(const std::string&& console) = delete;
597 explicit pstoreConsole(std::string&& console) = delete;
598
599 // Our implementation of rfind, use exact match first, then resort to fuzzy.
600 size_t rfind(const std::string& needle) const {
601 size_t pos = console.rfind(needle); // exact match?
602 if (pos != std::string::npos) return pos;
603
604 // Check to make sure needle fits in console string.
605 pos = console.length();
606 if (needle.length() > pos) return std::string::npos;
607 pos -= needle.length();
608 // fuzzy match to maximum kBitErrorRate
Ivan Lozano44d3cac2017-11-07 13:13:55 -0800609 for (;;) {
Mark Salyzyn293cb3b2017-09-20 08:37:46 -0700610 if (numError(pos, needle) != std::string::npos) return pos;
Ivan Lozano44d3cac2017-11-07 13:13:55 -0800611 if (pos == 0) break;
612 --pos;
613 }
Mark Salyzyn293cb3b2017-09-20 08:37:46 -0700614 return std::string::npos;
615 }
616
617 // Our implementation of find, use only fuzzy match.
618 size_t find(const std::string& needle, size_t start = 0) const {
619 // Check to make sure needle fits in console string.
620 if (needle.length() > console.length()) return std::string::npos;
621 const size_t last_pos = console.length() - needle.length();
622 // fuzzy match to maximum kBitErrorRate
623 for (size_t pos = start; pos <= last_pos; ++pos) {
624 if (numError(pos, needle) != std::string::npos) return pos;
625 }
626 return std::string::npos;
627 }
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700628
629 operator const std::string&() const { return console; }
Mark Salyzyn293cb3b2017-09-20 08:37:46 -0700630};
631
632// If bit error match to needle, correct it.
633// Return true if any corrections were discovered and applied.
Mark Salyzyn1e7d1c72018-03-16 08:57:20 -0700634bool correctForBitError(std::string& reason, const std::string& needle) {
Mark Salyzyn293cb3b2017-09-20 08:37:46 -0700635 bool corrected = false;
636 if (reason.length() < needle.length()) return corrected;
637 const pstoreConsole console(reason);
638 const size_t last_pos = reason.length() - needle.length();
639 for (size_t pos = 0; pos <= last_pos; pos += needle.length()) {
640 pos = console.find(needle, pos);
641 if (pos == std::string::npos) break;
642
643 // exact match has no malice
644 if (needle == reason.substr(pos, needle.length())) continue;
645
646 corrected = true;
647 reason = reason.substr(0, pos) + needle + reason.substr(pos + needle.length());
648 }
649 return corrected;
650}
651
Mark Salyzyn1e7d1c72018-03-16 08:57:20 -0700652// If bit error match to needle, correct it.
653// Return true if any corrections were discovered and applied.
654// Try again if we can replace underline with spaces.
655bool correctForBitErrorOrUnderline(std::string& reason, const std::string& needle) {
656 bool corrected = correctForBitError(reason, needle);
657 std::string _needle(needle);
658 std::transform(_needle.begin(), _needle.end(), _needle.begin(),
659 [](char c) { return (c == '_') ? ' ' : c; });
660 if (needle != _needle) {
661 corrected |= correctForBitError(reason, _needle);
662 }
663 return corrected;
664}
665
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700666// Converts a string value representing the reason the system booted to a
667// string complying with Android system standard reason.
668void transformReason(std::string& reason) {
669 std::transform(reason.begin(), reason.end(), reason.begin(), ::tolower);
670 std::transform(reason.begin(), reason.end(), reason.begin(),
671 [](char c) { return ::isblank(c) ? '_' : c; });
672 std::transform(reason.begin(), reason.end(), reason.begin(),
673 [](char c) { return ::isprint(c) ? c : '?'; });
674}
675
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700676// Check subreasons for reboot,<subreason> kernel_panic,sysrq,<subreason> or
677// kernel_panic,<subreason>.
678//
679// If quoted flag is set, pull out and correct single quoted ('), newline (\n)
680// or unprintable character terminated subreason, pos is supplied just beyond
681// first quote. if quoted false, pull out and correct newline (\n) or
682// unprintable character terminated subreason.
683//
684// Heuristics to find termination is painted into a corner:
685
686// single bit error for quote ' that we can block. It is acceptable for
687// the others 7, g in reason. 2/9 chance will miss the terminating quote,
688// but there is always the terminating newline that usually immediately
689// follows to fortify our chances.
690bool likely_single_quote(char c) {
691 switch (static_cast<uint8_t>(c)) {
692 case '\'': // '\''
693 case '\'' ^ 0x01: // '&'
694 case '\'' ^ 0x02: // '%'
695 case '\'' ^ 0x04: // '#'
696 case '\'' ^ 0x08: // '/'
697 return true;
698 case '\'' ^ 0x10: // '7'
699 break;
700 case '\'' ^ 0x20: // '\a' (unprintable)
701 return true;
702 case '\'' ^ 0x40: // 'g'
703 break;
704 case '\'' ^ 0x80: // 0xA7 (unprintable)
705 return true;
706 }
707 return false;
708}
709
710// ::isprint(c) and likely_space() will prevent us from being called for
711// fundamentally printable entries, except for '\r' and '\b'.
712//
713// Except for * and J, single bit errors for \n, all others are non-
714// printable so easy catch. It is _acceptable_ for *, J or j to exist in
715// the reason string, so 2/9 chance we will miss the terminating newline.
716//
717// NB: J might not be acceptable, except if at the beginning or preceded
718// with a space, '(' or any of the quotes and their BER aliases.
719// NB: * might not be acceptable, except if at the beginning or preceded
720// with a space, another *, or any of the quotes or their BER aliases.
721//
722// To reduce the chances to closer to 1/9 is too complicated for the gain.
723bool likely_newline(char c) {
724 switch (static_cast<uint8_t>(c)) {
725 case '\n': // '\n' (unprintable)
726 case '\n' ^ 0x01: // '\r' (unprintable)
727 case '\n' ^ 0x02: // '\b' (unprintable)
728 case '\n' ^ 0x04: // 0x0E (unprintable)
729 case '\n' ^ 0x08: // 0x02 (unprintable)
730 case '\n' ^ 0x10: // 0x1A (unprintable)
731 return true;
732 case '\n' ^ 0x20: // '*'
733 case '\n' ^ 0x40: // 'J'
734 break;
735 case '\n' ^ 0x80: // 0x8A (unprintable)
736 return true;
737 }
738 return false;
739}
740
741// ::isprint(c) will prevent us from being called for all the printable
742// matches below. If we let unprintables through because of this, they
743// get converted to underscore (_) by the validation phase.
744bool likely_space(char c) {
745 switch (static_cast<uint8_t>(c)) {
746 case ' ': // ' '
747 case ' ' ^ 0x01: // '!'
748 case ' ' ^ 0x02: // '"'
749 case ' ' ^ 0x04: // '$'
750 case ' ' ^ 0x08: // '('
751 case ' ' ^ 0x10: // '0'
752 case ' ' ^ 0x20: // '\0' (unprintable)
753 case ' ' ^ 0x40: // 'P'
754 case ' ' ^ 0x80: // 0xA0 (unprintable)
755 case '\t': // '\t'
756 case '\t' ^ 0x01: // '\b' (unprintable) (likely_newline counters)
757 case '\t' ^ 0x02: // '\v' (unprintable)
758 case '\t' ^ 0x04: // '\r' (unprintable) (likely_newline counters)
759 case '\t' ^ 0x08: // 0x01 (unprintable)
760 case '\t' ^ 0x10: // 0x19 (unprintable)
761 case '\t' ^ 0x20: // ')'
762 case '\t' ^ 0x40: // '1'
763 case '\t' ^ 0x80: // 0x89 (unprintable)
764 return true;
765 }
766 return false;
767}
768
769std::string getSubreason(const std::string& content, size_t pos, bool quoted) {
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700770 static constexpr size_t max_reason_length = 256;
771
772 std::string subReason(content.substr(pos, max_reason_length));
773 // Correct against any known strings that Bit Error Match
774 for (const auto& s : knownReasons) {
775 correctForBitErrorOrUnderline(subReason, s);
776 }
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700777 std::string terminator(quoted ? "'" : "");
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700778 for (const auto& m : kBootReasonMap) {
779 if (m.first.length() <= strlen("cold")) continue; // too short?
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700780 if (correctForBitErrorOrUnderline(subReason, m.first + terminator)) continue;
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700781 if (m.first.length() <= strlen("reboot,cold")) continue; // short?
782 if (android::base::StartsWith(m.first, "reboot,")) {
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700783 correctForBitErrorOrUnderline(subReason, m.first.substr(strlen("reboot,")) + terminator);
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700784 } else if (android::base::StartsWith(m.first, "kernel_panic,sysrq,")) {
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700785 correctForBitErrorOrUnderline(subReason,
786 m.first.substr(strlen("kernel_panic,sysrq,")) + terminator);
787 } else if (android::base::StartsWith(m.first, "kernel_panic,")) {
788 correctForBitErrorOrUnderline(subReason, m.first.substr(strlen("kernel_panic,")) + terminator);
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700789 }
790 }
791 for (pos = 0; pos < subReason.length(); ++pos) {
792 char c = subReason[pos];
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700793 if (!(::isprint(c) || likely_space(c)) || likely_newline(c) ||
794 (quoted && likely_single_quote(c))) {
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700795 subReason.erase(pos);
796 break;
797 }
798 }
799 transformReason(subReason);
800 return subReason;
801}
802
Mark Salyzyn293cb3b2017-09-20 08:37:46 -0700803bool addKernelPanicSubReason(const pstoreConsole& console, std::string& ret) {
Mark Salyzyn64610892017-09-18 10:41:14 -0700804 // Check for kernel panic types to refine information
Mark Salyzyn853bb802018-03-16 08:44:56 -0700805 if ((console.rfind("SysRq : Trigger a crash") != std::string::npos) ||
806 (console.rfind("PC is at sysrq_handle_crash+") != std::string::npos)) {
Mark Salyzyn64610892017-09-18 10:41:14 -0700807 ret = "kernel_panic,sysrq";
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700808 // Invented for Android to allow daemons that specifically trigger sysrq
809 // to communicate more accurate boot subreasons via last console messages.
810 static constexpr char sysrqSubreason[] = "SysRq : Trigger a crash : '";
811 auto pos = console.rfind(sysrqSubreason);
812 if (pos != std::string::npos) {
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700813 ret += "," + getSubreason(console, pos + strlen(sysrqSubreason), /* quoted */ true);
Mark Salyzyn39cc3e72018-03-19 15:16:29 -0700814 }
Mark Salyzyn64610892017-09-18 10:41:14 -0700815 return true;
816 }
817 if (console.rfind("Unable to handle kernel NULL pointer dereference at virtual address") !=
818 std::string::npos) {
Mark Salyzyn853bb802018-03-16 08:44:56 -0700819 ret = "kernel_panic,null";
Mark Salyzyn64610892017-09-18 10:41:14 -0700820 return true;
821 }
822 if (console.rfind("Kernel BUG at ") != std::string::npos) {
Mark Salyzyn853bb802018-03-16 08:44:56 -0700823 ret = "kernel_panic,bug";
Mark Salyzyn64610892017-09-18 10:41:14 -0700824 return true;
825 }
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700826
827 std::string panic("Kernel panic - not syncing: ");
828 auto pos = console.rfind(panic);
829 if (pos != std::string::npos) {
830 static const std::vector<std::pair<const std::string, const std::string>> panicReasons = {
831 {"Out of memory", "oom"},
832 {"out of memory", "oom"},
833 {"Oh boy, that early out of memory", "oom"}, // omg
834 {"BUG!", "bug"},
835 {"hung_task: blocked tasks", "hung"},
836 {"audit: ", "audit"},
837 {"scheduling while atomic", "atomic"},
838 {"Attempted to kill init!", "init"},
839 {"Requested init", "init"},
840 {"No working init", "init"},
841 {"Could not decompress init", "init"},
842 {"RCU Stall", "hung,rcu"},
843 {"stack-protector", "stack"},
844 {"kernel stack overflow", "stack"},
845 {"Corrupt kernel stack", "stack"},
846 {"low stack detected", "stack"},
847 {"corrupted stack end", "stack"},
Mark Salyzyn8ad6e672018-06-01 08:59:05 -0700848 {"subsys-restart: Resetting the SoC - modem crashed.", "modem"},
849 {"subsys-restart: Resetting the SoC - adsp crashed.", "adsp"},
850 {"subsys-restart: Resetting the SoC - dsps crashed.", "dsps"},
851 {"subsys-restart: Resetting the SoC - wcnss crashed.", "wcnss"},
Mark Salyzyn3f48fa92018-03-22 08:41:22 -0700852 };
853
854 ret = "kernel_panic";
855 for (auto& s : panicReasons) {
856 if (console.find(panic + s.first, pos) != std::string::npos) {
857 ret += "," + s.second;
858 return true;
859 }
860 }
861 auto reason = getSubreason(console, pos + panic.length(), /* newline */ false);
862 if (reason.length() > 3) {
863 ret += "," + reason;
864 }
865 return true;
866 }
Mark Salyzyn64610892017-09-18 10:41:14 -0700867 return false;
868}
869
Mark Salyzyn293cb3b2017-09-20 08:37:46 -0700870bool addKernelPanicSubReason(const std::string& content, std::string& ret) {
871 return addKernelPanicSubReason(pstoreConsole(content), ret);
872}
873
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700874const char system_reboot_reason_property[] = "sys.boot.reason";
875const char last_reboot_reason_property[] = LAST_REBOOT_REASON_PROPERTY;
Mark Salyzynee016ce2019-05-23 10:00:34 -0700876const char last_reboot_reason_file[] = LAST_REBOOT_REASON_FILE;
Mark Salyzynadc433d2018-06-05 08:17:35 -0700877const char last_last_reboot_reason_property[] = "sys.boot.reason.last";
Mark Salyzyn5c58c9d2018-06-28 09:21:55 -0700878constexpr size_t history_reboot_reason_size = 4;
879const char history_reboot_reason_property[] = LAST_REBOOT_REASON_PROPERTY ".history";
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700880const char bootloader_reboot_reason_property[] = "ro.boot.bootreason";
881
Mark Salyzyn5c58c9d2018-06-28 09:21:55 -0700882// Land system_boot_reason into system_reboot_reason_property.
883// Shift system_boot_reason into history_reboot_reason_property.
884void BootReasonAddToHistory(const std::string& system_boot_reason) {
885 if (system_boot_reason.empty()) return;
886 LOG(INFO) << "Canonical boot reason: " << system_boot_reason;
Mark Salyzyn88d308d2019-02-08 10:53:18 -0800887 auto old_system_boot_reason = android::base::GetProperty(system_reboot_reason_property, "");
888 if (!android::base::SetProperty(system_reboot_reason_property, system_boot_reason)) {
889 android::base::SetProperty(system_reboot_reason_property,
890 system_boot_reason.substr(0, PROPERTY_VALUE_MAX - 1));
Mark Salyzyn5c58c9d2018-06-28 09:21:55 -0700891 }
Mark Salyzyn88d308d2019-02-08 10:53:18 -0800892 auto reason_history =
893 android::base::Split(android::base::GetProperty(history_reboot_reason_property, ""), "\n");
Mark Salyzyn5c58c9d2018-06-28 09:21:55 -0700894 static auto mark = time(nullptr);
895 auto mark_str = std::string(",") + std::to_string(mark);
896 auto marked_system_boot_reason = system_boot_reason + mark_str;
897 if (!reason_history.empty()) {
898 // delete any entries that we just wrote in a previous
899 // call and leveraging duplicate line handling
900 auto last = old_system_boot_reason + mark_str;
901 // trim the list to (history_reboot_reason_size - 1)
902 ssize_t max = history_reboot_reason_size;
903 for (auto it = reason_history.begin(); it != reason_history.end();) {
904 if (it->empty() || (last == *it) || (marked_system_boot_reason == *it) || (--max <= 0)) {
905 it = reason_history.erase(it);
906 } else {
907 last = *it;
908 ++it;
909 }
910 }
911 }
912 // insert at the front, concatenating mark (<epoch time>) detail to the value.
913 reason_history.insert(reason_history.begin(), marked_system_boot_reason);
914 // If the property string is too long ( > PROPERTY_VALUE_MAX)
915 // we get an error, so trim out last entry and try again.
Mark Salyzyn88d308d2019-02-08 10:53:18 -0800916 while (!android::base::SetProperty(history_reboot_reason_property,
917 android::base::Join(reason_history, '\n'))) {
Mark Salyzyn5c58c9d2018-06-28 09:21:55 -0700918 auto it = std::prev(reason_history.end());
919 if (it == reason_history.end()) break;
920 reason_history.erase(it);
921 }
922}
923
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700924// Scrub, Sanitize, Standardize and Enhance the boot reason string supplied.
925std::string BootReasonStrToReason(const std::string& boot_reason) {
Mark Salyzyn88d308d2019-02-08 10:53:18 -0800926 auto ret = android::base::GetProperty(system_reboot_reason_property, "");
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700927 std::string reason(boot_reason);
928 // If sys.boot.reason == ro.boot.bootreason, let's re-evaluate
929 if (reason == ret) ret = "";
930
Mark Salyzyn88d692c2017-09-20 08:37:46 -0700931 transformReason(reason);
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700932
933 // Is the current system boot reason sys.boot.reason valid?
934 if (!isKnownRebootReason(ret)) ret = "";
935
936 if (ret == "") {
937 // Is the bootloader boot reason ro.boot.bootreason known?
938 std::vector<std::string> words(android::base::Split(reason, ",_-"));
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700939 for (auto& s : knownReasons) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700940 std::string blunt;
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700941 for (auto& r : words) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700942 if (r == s) {
943 if (isBluntRebootReason(s)) {
944 blunt = s;
945 } else {
946 ret = s;
947 break;
948 }
949 }
950 }
951 if (ret == "") ret = blunt;
952 if (ret != "") break;
953 }
954 }
955
956 if (ret == "") {
957 // A series of checks to take some officially unsupported reasons
958 // reported by the bootloader and find some logical and canonical
959 // sense. In an ideal world, we would require those bootloaders
Mark Salyzyn25900dd2018-03-16 09:05:59 -0700960 // to behave and follow our CTS standards.
961 //
962 // first member is the output
963 // second member is an unanchored regex for an alias
964 //
Mark Salyzyn28193282018-03-16 09:05:59 -0700965 // If output has a prefix of <bang> '!', we do not use it as a
966 // match needle (and drop the <bang> prefix when landing in output),
967 // otherwise look for it as well. This helps keep the scale of the
Mark Salyzyn25900dd2018-03-16 09:05:59 -0700968 // following table smaller.
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700969 static const std::vector<std::pair<const std::string, const std::string>> aliasReasons = {
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700970 {"watchdog", "wdog"},
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700971 {"kernel_panic", "panic"},
972 {"shutdown,thermal", "thermal"},
973 {"warm,s3_wakeup", "s3_wakeup"},
974 {"hard,hw_reset", "hw_reset"},
Mark Salyzyn48d03ad2019-07-08 09:26:19 -0700975 {"cold,charger", "usb|power_on_cable"},
976 {"cold,powerkey", "powerkey|power_key|PowerKey|power_on"},
Mark Salyzyn8aa36c62018-03-16 11:00:14 -0700977 {"cold,rtc", "rtc"},
Mark Salyzyn186f6762018-03-16 11:00:26 -0700978 {"cold,rtc,2sec", "2sec_reboot"},
979 {"!warm", "wdt_by_pass_pwk"}, // change flavour of blunt
980 {"!reboot", "^wdt$"}, // change flavour of blunt
981 {"reboot,tool", "tool_by_pass_pwk"},
Mark Salyzyn88d1b4a2018-06-07 09:39:24 -0700982 {"!reboot,longkey", "reboot_longkey"},
983 {"!reboot,longkey", "kpdpwr"},
Mark Salyzynec7bafe2018-09-26 08:01:04 -0700984 {"!reboot,undervoltage", "uvlo"},
Mark Salyzynf62983a2018-09-26 09:55:25 -0700985 {"!reboot,powerloss", "smpl"},
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700986 {"bootloader", ""},
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700987 };
988
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -0700989 for (auto& s : aliasReasons) {
Mark Salyzyn28193282018-03-16 09:05:59 -0700990 size_t firstHasNot = s.first[0] == '!';
991 if (!firstHasNot && (reason.find(s.first) != std::string::npos)) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700992 ret = s.first;
993 break;
994 }
Mark Salyzyn25900dd2018-03-16 09:05:59 -0700995 if (s.second.size() && std::regex_search(reason, std::regex(s.second))) {
Mark Salyzyn28193282018-03-16 09:05:59 -0700996 ret = s.first.substr(firstHasNot);
Mark Salyzynb304f6d2017-08-04 13:35:51 -0700997 break;
998 }
999 }
1000 }
1001
1002 // If watchdog is the reason, see if there is a security angle?
1003 if (ret == "watchdog") {
1004 if (reason.find("sec") != std::string::npos) {
1005 ret += ",security";
1006 }
1007 }
1008
Mark Salyzyn64610892017-09-18 10:41:14 -07001009 if (ret == "kernel_panic") {
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001010 // Check to see if last klog has some refinement hints.
1011 std::string content;
Mark Salyzyn64610892017-09-18 10:41:14 -07001012 if (readPstoreConsole(content)) {
1013 addKernelPanicSubReason(content, ret);
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001014 }
Mark Salyzyn64610892017-09-18 10:41:14 -07001015 } else if (isBluntRebootReason(ret)) {
1016 // Check the other available reason resources if the reason is still blunt.
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001017
Mark Salyzyn64610892017-09-18 10:41:14 -07001018 // Check to see if last klog has some refinement hints.
1019 std::string content;
1020 if (readPstoreConsole(content)) {
Mark Salyzyn293cb3b2017-09-20 08:37:46 -07001021 const pstoreConsole console(content);
Mark Salyzyn64610892017-09-18 10:41:14 -07001022 // The toybox reboot command used directly (unlikely)? But also
1023 // catches init's response to Android's more controlled reboot command.
Mark Salyzyn293cb3b2017-09-20 08:37:46 -07001024 if (console.rfind("reboot: Power down") != std::string::npos) {
Mark Salyzyn64610892017-09-18 10:41:14 -07001025 ret = "shutdown"; // Still too blunt, but more accurate.
1026 // ToDo: init should record the shutdown reason to kernel messages ala:
1027 // init: shutdown system with command 'last_reboot_reason'
1028 // so that if pstore has persistence we can get some details
1029 // that could be missing in last_reboot_reason_property.
1030 }
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001031
Mark Salyzyn64610892017-09-18 10:41:14 -07001032 static const char cmd[] = "reboot: Restarting system with command '";
Mark Salyzyn293cb3b2017-09-20 08:37:46 -07001033 size_t pos = console.rfind(cmd);
Mark Salyzyn64610892017-09-18 10:41:14 -07001034 if (pos != std::string::npos) {
Mark Salyzyn3f48fa92018-03-22 08:41:22 -07001035 std::string subReason(getSubreason(content, pos + strlen(cmd), /* quoted */ true));
Mark Salyzyn64610892017-09-18 10:41:14 -07001036 if (subReason != "") { // Will not land "reboot" as that is too blunt.
1037 if (isKernelRebootReason(subReason)) {
1038 ret = "reboot," + subReason; // User space can't talk kernel reasons.
Mark Salyzyndafced92017-09-20 08:37:46 -07001039 } else if (isKnownRebootReason(subReason)) {
Mark Salyzyn64610892017-09-18 10:41:14 -07001040 ret = subReason;
Mark Salyzyndafced92017-09-20 08:37:46 -07001041 } else {
1042 ret = "reboot," + subReason; // legitimize unknown reasons
Mark Salyzyn64610892017-09-18 10:41:14 -07001043 }
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001044 }
Mark Salyzyn15199252018-03-16 09:26:05 -07001045 // Some bootloaders shutdown results record in last kernel message.
1046 if (!strcmp(ret.c_str(), "reboot,kernel_power_off_charging__reboot_system")) {
1047 ret = "shutdown";
1048 }
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001049 }
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001050
Mark Salyzyn64610892017-09-18 10:41:14 -07001051 // Check for kernel panics, allowed to override reboot command.
Mark Salyzyn293cb3b2017-09-20 08:37:46 -07001052 if (!addKernelPanicSubReason(console, ret) &&
Mark Salyzyn64610892017-09-18 10:41:14 -07001053 // check for long-press power down
Mark Salyzyn293cb3b2017-09-20 08:37:46 -07001054 ((console.rfind("Power held for ") != std::string::npos) ||
1055 (console.rfind("charger: [") != std::string::npos))) {
Mark Salyzyn64610892017-09-18 10:41:14 -07001056 ret = "cold";
1057 }
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001058 }
1059
Elliott Hughes50a24eb2018-06-14 10:59:09 -07001060 // TODO: use the HAL to get battery level (http://b/77725702).
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001061
1062 // Is there a controlled shutdown hint in last_reboot_reason_property?
1063 if (isBluntRebootReason(ret)) {
1064 // Content buffer no longer will have console data. Beware if more
1065 // checks added below, that depend on parsing console content.
Mark Salyzynee016ce2019-05-23 10:00:34 -07001066 if (!android::base::ReadFileToString(last_reboot_reason_file, &content)) {
1067 content = android::base::GetProperty(last_reboot_reason_property, "");
1068 }
Mark Salyzyn88d692c2017-09-20 08:37:46 -07001069 transformReason(content);
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001070
Mark Salyzyn62909822017-10-09 09:27:16 -07001071 // Anything in last is better than 'super-blunt' reboot or shutdown.
1072 if ((ret == "") || (ret == "reboot") || (ret == "shutdown") || !isBluntRebootReason(content)) {
1073 ret = content;
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001074 }
1075 }
1076
1077 // Other System Health HAL reasons?
1078
1079 // ToDo: /proc/sys/kernel/boot_reason needs a HAL interface to
1080 // possibly offer hardware-specific clues from the PMIC.
1081 }
1082
1083 // If unknown left over from above, make it "reboot,<boot_reason>"
1084 if (ret == "") {
1085 ret = "reboot";
1086 if (android::base::StartsWith(reason, "reboot")) {
1087 reason = reason.substr(strlen("reboot"));
Mark Salyzyn0af71a52017-10-05 13:58:04 -07001088 while ((reason[0] == ',') || (reason[0] == '_')) {
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001089 reason = reason.substr(1);
1090 }
1091 }
1092 if (reason != "") {
1093 ret += ",";
1094 ret += reason;
1095 }
1096 }
1097
1098 LOG(INFO) << "Canonical boot reason: " << ret;
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001099 return ret;
1100}
1101
James Hawkinsb9cf7712016-04-08 15:32:19 -07001102// Returns the appropriate metric key prefix for the boot_complete metric such
1103// that boot metrics after a system update are labeled as ota_boot_complete;
1104// otherwise, they are labeled as boot_complete. This method encapsulates the
1105// bookkeeping required to track when a system update has occurred by storing
1106// the UTC timestamp of the system build date and comparing against the current
1107// system build date.
1108std::string CalculateBootCompletePrefix() {
1109 static const std::string kBuildDateKey = "build_date";
1110 std::string boot_complete_prefix = "boot_complete";
1111
Mark Salyzyn88d308d2019-02-08 10:53:18 -08001112 auto build_date_str = android::base::GetProperty("ro.build.date.utc", "");
James Hawkins4dded612016-07-28 11:50:23 -07001113 int32_t build_date;
Elliott Hughesda46b392016-10-11 17:09:00 -07001114 if (!android::base::ParseInt(build_date_str, &build_date)) {
James Hawkins4dded612016-07-28 11:50:23 -07001115 return std::string();
1116 }
James Hawkinsb9cf7712016-04-08 15:32:19 -07001117
1118 BootEventRecordStore boot_event_store;
1119 BootEventRecordStore::BootEventRecord record;
James Hawkins0bc4ad42017-05-30 15:03:15 -07001120 if (!boot_event_store.GetBootEvent(kBuildDateKey, &record)) {
1121 boot_complete_prefix = "factory_reset_" + boot_complete_prefix;
1122 boot_event_store.AddBootEventWithValue(kBuildDateKey, build_date);
Mark Salyzyn5c58c9d2018-06-28 09:21:55 -07001123 BootReasonAddToHistory("reboot,factory_reset");
James Hawkins0bc4ad42017-05-30 15:03:15 -07001124 } else if (build_date != record.second) {
James Hawkinsb9cf7712016-04-08 15:32:19 -07001125 boot_complete_prefix = "ota_" + boot_complete_prefix;
1126 boot_event_store.AddBootEventWithValue(kBuildDateKey, build_date);
Mark Salyzyn5c58c9d2018-06-28 09:21:55 -07001127 BootReasonAddToHistory("reboot,ota");
James Hawkinsb9cf7712016-04-08 15:32:19 -07001128 }
1129
1130 return boot_complete_prefix;
1131}
1132
James Hawkinsef0a0902017-01-06 14:38:23 -08001133// Records the value of a given ro.boottime.init property in milliseconds.
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -07001134void RecordInitBootTimeProp(BootEventRecordStore* boot_event_store, const char* property) {
Mark Salyzyn88d308d2019-02-08 10:53:18 -08001135 auto value = android::base::GetProperty(property, "");
James Hawkinsef0a0902017-01-06 14:38:23 -08001136
James Hawkins27c05222017-01-26 11:55:44 -08001137 int32_t time_in_ms;
1138 if (android::base::ParseInt(value, &time_in_ms)) {
James Hawkinsef0a0902017-01-06 14:38:23 -08001139 boot_event_store->AddBootEventWithValue(property, time_in_ms);
1140 }
1141}
1142
James Hawkins1bfcaec2017-05-19 14:27:27 -07001143// A map from bootloader timing stage to the time that stage took during boot.
1144typedef std::map<std::string, int32_t> BootloaderTimingMap;
1145
1146// Returns a mapping from bootloader stage names to the time those stages
1147// took to boot.
1148const BootloaderTimingMap GetBootLoaderTimings() {
1149 BootloaderTimingMap timings;
1150
1151 // |ro.boot.boottime| is of the form 'stage1:time1,...,stageN:timeN',
1152 // where timeN is in milliseconds.
Mark Salyzyn88d308d2019-02-08 10:53:18 -08001153 auto value = android::base::GetProperty("ro.boot.boottime", "");
James Hawkins6b5c5aa2017-02-16 11:53:03 -08001154 if (value.empty()) {
1155 // ro.boot.boottime is not reported on all devices.
James Hawkins1bfcaec2017-05-19 14:27:27 -07001156 return BootloaderTimingMap();
James Hawkins6b5c5aa2017-02-16 11:53:03 -08001157 }
James Hawkinsbe46fd12017-02-02 16:21:25 -08001158
1159 auto stages = android::base::Split(value, ",");
James Hawkins1bfcaec2017-05-19 14:27:27 -07001160 for (const auto& stageTiming : stages) {
James Hawkinsbe46fd12017-02-02 16:21:25 -08001161 // |stageTiming| is of the form 'stage:time'.
1162 auto stageTimingValues = android::base::Split(stageTiming, ":");
James Hawkins0bc4ad42017-05-30 15:03:15 -07001163 DCHECK_EQ(2U, stageTimingValues.size());
James Hawkinsbe46fd12017-02-02 16:21:25 -08001164
Mark Salyzyn7c721162019-02-08 10:41:15 -08001165 if (stageTimingValues.size() < 2) continue;
James Hawkinsbe46fd12017-02-02 16:21:25 -08001166 std::string stageName = stageTimingValues[0];
1167 int32_t time_ms;
1168 if (android::base::ParseInt(stageTimingValues[1], &time_ms)) {
James Hawkins1bfcaec2017-05-19 14:27:27 -07001169 timings[stageName] = time_ms;
James Hawkinsbe46fd12017-02-02 16:21:25 -08001170 }
1171 }
James Hawkins6b5c5aa2017-02-16 11:53:03 -08001172
James Hawkins1bfcaec2017-05-19 14:27:27 -07001173 return timings;
1174}
1175
Tej Singh4eacd382018-01-25 17:59:57 -08001176// Returns the total bootloader boot time from the ro.boot.boottime system property.
1177int32_t GetBootloaderTime(const BootloaderTimingMap& bootloader_timings) {
1178 int32_t total_time = 0;
1179 for (const auto& timing : bootloader_timings) {
1180 total_time += timing.second;
1181 }
1182
1183 return total_time;
1184}
1185
James Hawkins1bfcaec2017-05-19 14:27:27 -07001186// Parses and records the set of bootloader stages and associated boot times
1187// from the ro.boot.boottime system property.
1188void RecordBootloaderTimings(BootEventRecordStore* boot_event_store,
1189 const BootloaderTimingMap& bootloader_timings) {
1190 int32_t total_time = 0;
1191 for (const auto& timing : bootloader_timings) {
1192 total_time += timing.second;
1193 boot_event_store->AddBootEventWithValue("boottime.bootloader." + timing.first, timing.second);
1194 }
1195
James Hawkins6b5c5aa2017-02-16 11:53:03 -08001196 boot_event_store->AddBootEventWithValue("boottime.bootloader.total", total_time);
James Hawkinsbe46fd12017-02-02 16:21:25 -08001197}
1198
Tej Singh4eacd382018-01-25 17:59:57 -08001199// Returns the closest estimation to the absolute device boot time, i.e.,
James Hawkins1bfcaec2017-05-19 14:27:27 -07001200// from power on to boot_complete, including bootloader times.
Tej Singh4eacd382018-01-25 17:59:57 -08001201std::chrono::milliseconds GetAbsoluteBootTime(const BootloaderTimingMap& bootloader_timings,
1202 std::chrono::milliseconds uptime) {
James Hawkins1bfcaec2017-05-19 14:27:27 -07001203 int32_t bootloader_time_ms = 0;
1204
1205 for (const auto& timing : bootloader_timings) {
1206 if (timing.first.compare("SW") != 0) {
1207 bootloader_time_ms += timing.second;
1208 }
1209 }
1210
1211 auto bootloader_duration = std::chrono::milliseconds(bootloader_time_ms);
Tej Singh4eacd382018-01-25 17:59:57 -08001212 return bootloader_duration + uptime;
1213}
1214
1215// Records the closest estimation to the absolute device boot time in seconds.
1216// i.e. from power on to boot_complete, including bootloader times.
1217void RecordAbsoluteBootTime(BootEventRecordStore* boot_event_store,
1218 std::chrono::milliseconds absolute_total) {
1219 auto absolute_total_sec = std::chrono::duration_cast<std::chrono::seconds>(absolute_total);
1220 boot_event_store->AddBootEventWithValue("absolute_boot_time", absolute_total_sec.count());
1221}
1222
1223// Logs the total boot time and reason to statsd.
1224void LogBootInfoToStatsd(std::chrono::milliseconds end_time,
1225 std::chrono::milliseconds total_duration, int32_t bootloader_duration_ms,
1226 double time_since_last_boot_sec) {
Wei Wang699e3422019-05-22 09:46:02 -07001227 auto reason = android::base::GetProperty(bootloader_reboot_reason_property, "<EMPTY>");
1228 auto system_reason = android::base::GetProperty(system_reboot_reason_property, "<EMPTY>");
Tej Singh4eacd382018-01-25 17:59:57 -08001229 android::util::stats_write(android::util::BOOT_SEQUENCE_REPORTED, reason.c_str(),
1230 system_reason.c_str(), end_time.count(), total_duration.count(),
1231 (int64_t)bootloader_duration_ms,
1232 (int64_t)time_since_last_boot_sec * 1000);
James Hawkins1bfcaec2017-05-19 14:27:27 -07001233}
1234
Tej Singhfe3e7622018-02-06 15:57:38 -08001235void SetSystemBootReason() {
Mark Salyzyn88d308d2019-02-08 10:53:18 -08001236 const auto bootloader_boot_reason =
1237 android::base::GetProperty(bootloader_reboot_reason_property, "");
Tej Singhfe3e7622018-02-06 15:57:38 -08001238 const std::string system_boot_reason(BootReasonStrToReason(bootloader_boot_reason));
1239 // Record the scrubbed system_boot_reason to the property
Mark Salyzyn5c58c9d2018-06-28 09:21:55 -07001240 BootReasonAddToHistory(system_boot_reason);
Mark Salyzynadc433d2018-06-05 08:17:35 -07001241 // Shift last_reboot_reason_property to last_last_reboot_reason_property
Mark Salyzynee016ce2019-05-23 10:00:34 -07001242 std::string last_boot_reason;
1243 if (!android::base::ReadFileToString(last_reboot_reason_file, &last_boot_reason)) {
Nikita Ioffe49062f32020-03-31 23:47:45 +01001244 PLOG(ERROR) << "Failed to read " << last_reboot_reason_file;
Mark Salyzynee016ce2019-05-23 10:00:34 -07001245 last_boot_reason = android::base::GetProperty(last_reboot_reason_property, "");
Nikita Ioffe49062f32020-03-31 23:47:45 +01001246 LOG(INFO) << "Value of " << last_reboot_reason_property << " : " << last_boot_reason;
1247 } else {
1248 LOG(INFO) << "Last reboot reason read from " << last_reboot_reason_file << " : "
1249 << last_boot_reason << ". Last reboot reason read from "
1250 << last_reboot_reason_property << " : "
1251 << android::base::GetProperty(last_reboot_reason_property, "");
Mark Salyzynee016ce2019-05-23 10:00:34 -07001252 }
Mark Salyzynadc433d2018-06-05 08:17:35 -07001253 if (last_boot_reason.empty() || isKernelRebootReason(system_boot_reason)) {
1254 last_boot_reason = system_boot_reason;
1255 } else {
1256 transformReason(last_boot_reason);
1257 }
Nikita Ioffe49062f32020-03-31 23:47:45 +01001258 LOG(INFO) << "Normalized last reboot reason : " << last_boot_reason;
Mark Salyzyn88d308d2019-02-08 10:53:18 -08001259 android::base::SetProperty(last_last_reboot_reason_property, last_boot_reason);
1260 android::base::SetProperty(last_reboot_reason_property, "");
Nikita Ioffe49062f32020-03-31 23:47:45 +01001261 if (unlink(last_reboot_reason_file) != 0) {
1262 PLOG(ERROR) << "Failed to unlink " << last_reboot_reason_file;
1263 }
Tej Singhfe3e7622018-02-06 15:57:38 -08001264}
1265
Luis Hector Chavez583d34c2018-04-12 15:25:15 -07001266// Gets the boot time offset. This is useful when Android is running in a
1267// container, because the boot_clock is not reset when Android reboots.
1268std::chrono::nanoseconds GetBootTimeOffset() {
1269 static const int64_t boottime_offset =
1270 android::base::GetIntProperty<int64_t>("ro.boot.boottime_offset", 0);
1271 return std::chrono::nanoseconds(boottime_offset);
1272}
1273
1274// Returns the current uptime, accounting for any offset in the CLOCK_BOOTTIME
1275// clock.
1276android::base::boot_clock::duration GetUptime() {
1277 return android::base::boot_clock::now().time_since_epoch() - GetBootTimeOffset();
1278}
1279
James Hawkinsc08e9962016-03-11 14:59:50 -08001280// Records several metrics related to the time it takes to boot the device,
1281// including disambiguating boot time on encrypted or non-encrypted devices.
1282void RecordBootComplete() {
1283 BootEventRecordStore boot_event_store;
James Hawkinsb9cf7712016-04-08 15:32:19 -07001284 BootEventRecordStore::BootEventRecord record;
James Hawkins2d8b3e62016-04-14 14:13:20 -07001285
Luis Hector Chavez583d34c2018-04-12 15:25:15 -07001286 auto uptime_ns = GetUptime();
1287 auto uptime_s = std::chrono::duration_cast<std::chrono::seconds>(uptime_ns);
James Hawkins2d8b3e62016-04-14 14:13:20 -07001288 time_t current_time_utc = time(nullptr);
Tej Singh4eacd382018-01-25 17:59:57 -08001289 time_t time_since_last_boot = 0;
James Hawkins2d8b3e62016-04-14 14:13:20 -07001290
1291 if (boot_event_store.GetBootEvent("last_boot_time_utc", &record)) {
1292 time_t last_boot_time_utc = record.second;
Tej Singh4eacd382018-01-25 17:59:57 -08001293 time_since_last_boot = difftime(current_time_utc, last_boot_time_utc);
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -07001294 boot_event_store.AddBootEventWithValue("time_since_last_boot", time_since_last_boot);
James Hawkins2d8b3e62016-04-14 14:13:20 -07001295 }
1296
1297 boot_event_store.AddBootEventWithValue("last_boot_time_utc", current_time_utc);
James Hawkinsc08e9962016-03-11 14:59:50 -08001298
James Hawkinsb9cf7712016-04-08 15:32:19 -07001299 // The boot_complete metric has two variants: boot_complete and
1300 // ota_boot_complete. The latter signifies that the device is booting after
1301 // a system update.
1302 std::string boot_complete_prefix = CalculateBootCompletePrefix();
James Hawkins4dded612016-07-28 11:50:23 -07001303 if (boot_complete_prefix.empty()) {
1304 // The system is hosed because the build date property could not be read.
1305 return;
1306 }
James Hawkinsc08e9962016-03-11 14:59:50 -08001307
1308 // post_decrypt_time_elapsed is only logged on encrypted devices.
1309 if (boot_event_store.GetBootEvent("post_decrypt_time_elapsed", &record)) {
1310 // Log the amount of time elapsed until the device is decrypted, which
1311 // includes the variable amount of time the user takes to enter the
1312 // decryption password.
Luis Hector Chavez583d34c2018-04-12 15:25:15 -07001313 boot_event_store.AddBootEventWithValue("boot_decryption_complete", uptime_s.count());
James Hawkinsc08e9962016-03-11 14:59:50 -08001314
1315 // Subtract the decryption time to normalize the boot cycle timing.
Luis Hector Chavez583d34c2018-04-12 15:25:15 -07001316 std::chrono::seconds boot_complete = std::chrono::seconds(uptime_s.count() - record.second);
James Hawkinsb9cf7712016-04-08 15:32:19 -07001317 boot_event_store.AddBootEventWithValue(boot_complete_prefix + "_post_decrypt",
James Hawkinse78ea772017-03-24 11:43:02 -07001318 boot_complete.count());
James Hawkinsc08e9962016-03-11 14:59:50 -08001319 } else {
Luis Hector Chavez583d34c2018-04-12 15:25:15 -07001320 boot_event_store.AddBootEventWithValue(boot_complete_prefix + "_no_encryption",
1321 uptime_s.count());
James Hawkinsc08e9962016-03-11 14:59:50 -08001322 }
1323
1324 // Record the total time from device startup to boot complete, regardless of
1325 // encryption state.
Yifan Hong08ba15d2021-03-03 16:32:52 -08001326 // Note: we are recording seconds here even though the field in statsd atom specifies
1327 // milliseconds.
Luis Hector Chavez583d34c2018-04-12 15:25:15 -07001328 boot_event_store.AddBootEventWithValue(boot_complete_prefix, uptime_s.count());
James Hawkinsef0a0902017-01-06 14:38:23 -08001329
1330 RecordInitBootTimeProp(&boot_event_store, "ro.boottime.init");
Mark Salyzyn10377df2019-03-27 08:10:41 -07001331 RecordInitBootTimeProp(&boot_event_store, "ro.boottime.init.first_stage");
James Hawkinsef0a0902017-01-06 14:38:23 -08001332 RecordInitBootTimeProp(&boot_event_store, "ro.boottime.init.selinux");
1333 RecordInitBootTimeProp(&boot_event_store, "ro.boottime.init.cold_boot_wait");
James Hawkinsbe46fd12017-02-02 16:21:25 -08001334
James Hawkins1bfcaec2017-05-19 14:27:27 -07001335 const BootloaderTimingMap bootloader_timings = GetBootLoaderTimings();
Tej Singh4eacd382018-01-25 17:59:57 -08001336 int32_t bootloader_boot_duration = GetBootloaderTime(bootloader_timings);
James Hawkins1bfcaec2017-05-19 14:27:27 -07001337 RecordBootloaderTimings(&boot_event_store, bootloader_timings);
1338
Luis Hector Chavez583d34c2018-04-12 15:25:15 -07001339 auto uptime_ms = std::chrono::duration_cast<std::chrono::milliseconds>(uptime_ns);
Tej Singh4eacd382018-01-25 17:59:57 -08001340 auto absolute_boot_time = GetAbsoluteBootTime(bootloader_timings, uptime_ms);
1341 RecordAbsoluteBootTime(&boot_event_store, absolute_boot_time);
1342
1343 auto boot_end_time_point = std::chrono::system_clock::now().time_since_epoch();
1344 auto boot_end_time = std::chrono::duration_cast<std::chrono::milliseconds>(boot_end_time_point);
1345
1346 LogBootInfoToStatsd(boot_end_time, absolute_boot_time, bootloader_boot_duration,
1347 time_since_last_boot);
James Hawkinsc08e9962016-03-11 14:59:50 -08001348}
1349
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001350// Records the boot_reason metric by querying the ro.boot.bootreason system
1351// property.
1352void RecordBootReason() {
Mark Salyzyn88d308d2019-02-08 10:53:18 -08001353 const auto reason = android::base::GetProperty(bootloader_reboot_reason_property, "");
James Hawkins25f71222017-10-10 16:37:05 -07001354
1355 if (reason.empty()) {
Howard Rofe0b3892020-01-31 17:37:32 -08001356 // TODO(b/148575354): Replace with statsd.
James Hawkins25f71222017-10-10 16:37:05 -07001357 // Log an empty boot reason value as '<EMPTY>' to ensure the value is intentional
1358 // (and not corruption anywhere else in the reporting pipeline).
Howard Rofe0b3892020-01-31 17:37:32 -08001359 // android::metricslogger::LogMultiAction(android::metricslogger::ACTION_BOOT,
1360 // android::metricslogger::FIELD_PLATFORM_REASON,
1361 // "<EMPTY>");
James Hawkins25f71222017-10-10 16:37:05 -07001362 } else {
Howard Rofe0b3892020-01-31 17:37:32 -08001363 // TODO(b/148575354): Replace with statsd.
1364 // android::metricslogger::LogMultiAction(android::metricslogger::ACTION_BOOT,
1365 // android::metricslogger::FIELD_PLATFORM_REASON,
1366 // reason);
James Hawkins25f71222017-10-10 16:37:05 -07001367 }
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001368
1369 // Log the raw bootloader_boot_reason property value.
1370 int32_t boot_reason = BootReasonStrToEnum(reason);
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001371 BootEventRecordStore boot_event_store;
1372 boot_event_store.AddBootEventWithValue("boot_reason", boot_reason);
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001373
1374 // Log the scrubbed system_boot_reason.
Mark Salyzyn88d308d2019-02-08 10:53:18 -08001375 const auto system_reason = android::base::GetProperty(system_reboot_reason_property, "");
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001376 int32_t system_boot_reason = BootReasonStrToEnum(system_reason);
1377 boot_event_store.AddBootEventWithValue("system_boot_reason", system_boot_reason);
1378
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001379 if (reason == "") {
Mark Salyzyn88d308d2019-02-08 10:53:18 -08001380 android::base::SetProperty(bootloader_reboot_reason_property, system_reason);
Mark Salyzynb304f6d2017-08-04 13:35:51 -07001381 }
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001382}
1383
James Hawkins500d7152016-02-16 15:05:54 -08001384// Records two metrics related to the user resetting a device: the time at
1385// which the device is reset, and the time since the user last reset the
1386// device. The former is only set once per-factory reset.
1387void RecordFactoryReset() {
1388 BootEventRecordStore boot_event_store;
1389 BootEventRecordStore::BootEventRecord record;
1390
1391 time_t current_time_utc = time(nullptr);
1392
James Hawkins0660b302016-03-08 16:18:15 -08001393 if (current_time_utc < 0) {
1394 // UMA does not display negative values in buckets, so convert to positive.
Keun young Park606af6d2020-01-15 10:09:03 -08001395 // Logging via BootEventRecordStore.
1396 android::util::stats_write(
1397 static_cast<int32_t>(android::util::BOOT_TIME_EVENT_ERROR_CODE_REPORTED),
1398 static_cast<int32_t>(
1399 android::util::BOOT_TIME_EVENT_ERROR_CODE__EVENT__FACTORY_RESET_CURRENT_TIME_FAILURE),
1400 static_cast<int32_t>(std::abs(current_time_utc)));
James Hawkinsfff95ba2016-03-29 16:13:49 -07001401
James Hawkins9aec9262017-01-31 11:42:24 -08001402 // Logging via BootEventRecordStore to see if using android::metricslogger::LogHistogram
James Hawkinsfff95ba2016-03-29 16:13:49 -07001403 // is losing records somehow.
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -07001404 boot_event_store.AddBootEventWithValue("factory_reset_current_time_failure",
1405 std::abs(current_time_utc));
James Hawkins0660b302016-03-08 16:18:15 -08001406 return;
1407 } else {
Keun young Park606af6d2020-01-15 10:09:03 -08001408 android::util::stats_write(
1409 static_cast<int32_t>(android::util::BOOT_TIME_EVENT_UTC_TIME_REPORTED),
1410 static_cast<int32_t>(
1411 android::util::BOOT_TIME_EVENT_UTC_TIME__EVENT__FACTORY_RESET_CURRENT_TIME),
1412 static_cast<int64_t>(current_time_utc));
James Hawkinsfff95ba2016-03-29 16:13:49 -07001413
James Hawkins9aec9262017-01-31 11:42:24 -08001414 // Logging via BootEventRecordStore to see if using android::metricslogger::LogHistogram
James Hawkinsfff95ba2016-03-29 16:13:49 -07001415 // is losing records somehow.
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -07001416 boot_event_store.AddBootEventWithValue("factory_reset_current_time", current_time_utc);
James Hawkins0660b302016-03-08 16:18:15 -08001417 }
1418
James Hawkins500d7152016-02-16 15:05:54 -08001419 // The factory_reset boot event does not exist after the device is reset, so
1420 // use this signal to mark the time of the factory reset.
1421 if (!boot_event_store.GetBootEvent("factory_reset", &record)) {
1422 boot_event_store.AddBootEventWithValue("factory_reset", current_time_utc);
James Hawkins3bf9b142016-03-03 14:50:24 -08001423
1424 // Don't log the time_since_factory_reset until some time has elapsed.
1425 // The data is not meaningful yet and skews the histogram buckets.
James Hawkins500d7152016-02-16 15:05:54 -08001426 return;
1427 }
1428
1429 // Calculate and record the difference in time between now and the
1430 // factory_reset time.
1431 time_t factory_reset_utc = record.second;
Keun young Park606af6d2020-01-15 10:09:03 -08001432 android::util::stats_write(
1433 static_cast<int32_t>(android::util::BOOT_TIME_EVENT_UTC_TIME_REPORTED),
1434 static_cast<int32_t>(
1435 android::util::BOOT_TIME_EVENT_UTC_TIME__EVENT__FACTORY_RESET_RECORD_VALUE),
1436 static_cast<int64_t>(factory_reset_utc));
James Hawkinsfff95ba2016-03-29 16:13:49 -07001437
James Hawkins9aec9262017-01-31 11:42:24 -08001438 // Logging via BootEventRecordStore to see if using android::metricslogger::LogHistogram
James Hawkinsfff95ba2016-03-29 16:13:49 -07001439 // is losing records somehow.
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -07001440 boot_event_store.AddBootEventWithValue("factory_reset_record_value", factory_reset_utc);
James Hawkinsfff95ba2016-03-29 16:13:49 -07001441
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -07001442 time_t time_since_factory_reset = difftime(current_time_utc, factory_reset_utc);
1443 boot_event_store.AddBootEventWithValue("time_since_factory_reset", time_since_factory_reset);
James Hawkins500d7152016-02-16 15:05:54 -08001444}
1445
Mark Salyzyn67ee8a82019-04-18 12:41:29 -07001446// List the associated boot reason(s), if arg is nullptr then all.
1447void PrintBootReasonEnum(const char* arg) {
1448 int value = -1;
1449 if (arg != nullptr) {
1450 value = BootReasonStrToEnum(arg);
1451 }
1452 for (const auto& [match, id] : kBootReasonMap) {
1453 if ((value < 0) || (value == id)) {
1454 printf("%u\t%s\n", id, match.c_str());
1455 }
1456 }
1457}
1458
James Hawkinsabd73e62016-01-19 15:10:38 -08001459} // namespace
1460
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -07001461int main(int argc, char** argv) {
James Hawkinsabd73e62016-01-19 15:10:38 -08001462 android::base::InitLogging(argv);
1463
1464 const std::string cmd_line = GetCommandLine(argc, argv);
1465 LOG(INFO) << "Service started: " << cmd_line;
1466
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001467 int option_index = 0;
James Hawkinsc6275582016-03-22 10:47:44 -07001468 static const char value_str[] = "value";
Tej Singhfe3e7622018-02-06 15:57:38 -08001469 static const char system_boot_reason_str[] = "set_system_boot_reason";
James Hawkinsc08e9962016-03-11 14:59:50 -08001470 static const char boot_complete_str[] = "record_boot_complete";
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001471 static const char boot_reason_str[] = "record_boot_reason";
James Hawkins53684ea2016-02-23 16:18:19 -08001472 static const char factory_reset_str[] = "record_time_since_factory_reset";
Mark Salyzyn67ee8a82019-04-18 12:41:29 -07001473 static const char boot_reason_enum_str[] = "boot_reason_enum";
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001474 static const struct option long_options[] = {
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -07001475 // clang-format off
Tej Singhfe3e7622018-02-06 15:57:38 -08001476 { "help", no_argument, NULL, 'h' },
1477 { "log", no_argument, NULL, 'l' },
1478 { "print", no_argument, NULL, 'p' },
1479 { "record", required_argument, NULL, 'r' },
1480 { value_str, required_argument, NULL, 0 },
1481 { system_boot_reason_str, no_argument, NULL, 0 },
1482 { boot_complete_str, no_argument, NULL, 0 },
1483 { boot_reason_str, no_argument, NULL, 0 },
1484 { factory_reset_str, no_argument, NULL, 0 },
Mark Salyzyn67ee8a82019-04-18 12:41:29 -07001485 { boot_reason_enum_str, optional_argument, NULL, 0 },
Tej Singhfe3e7622018-02-06 15:57:38 -08001486 { NULL, 0, NULL, 0 }
Mark Salyzyn14b1e6d2017-09-18 10:41:14 -07001487 // clang-format on
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001488 };
1489
James Hawkinsc6275582016-03-22 10:47:44 -07001490 std::string boot_event;
1491 std::string value;
James Hawkinsabd73e62016-01-19 15:10:38 -08001492 int opt = 0;
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001493 while ((opt = getopt_long(argc, argv, "hlpr:", long_options, &option_index)) != -1) {
James Hawkinsabd73e62016-01-19 15:10:38 -08001494 switch (opt) {
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001495 // This case handles long options which have no single-character mapping.
1496 case 0: {
1497 const std::string option_name = long_options[option_index].name;
James Hawkinsc6275582016-03-22 10:47:44 -07001498 if (option_name == value_str) {
1499 // |optarg| is an external variable set by getopt representing
1500 // the option argument.
1501 value = optarg;
Tej Singhfe3e7622018-02-06 15:57:38 -08001502 } else if (option_name == system_boot_reason_str) {
1503 SetSystemBootReason();
James Hawkinsc6275582016-03-22 10:47:44 -07001504 } else if (option_name == boot_complete_str) {
James Hawkinsc08e9962016-03-11 14:59:50 -08001505 RecordBootComplete();
1506 } else if (option_name == boot_reason_str) {
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001507 RecordBootReason();
James Hawkins500d7152016-02-16 15:05:54 -08001508 } else if (option_name == factory_reset_str) {
1509 RecordFactoryReset();
Mark Salyzyn67ee8a82019-04-18 12:41:29 -07001510 } else if (option_name == boot_reason_enum_str) {
1511 PrintBootReasonEnum(optarg);
James Hawkinsa4a1a4a2016-02-09 15:32:38 -08001512 } else {
1513 LOG(ERROR) << "Invalid option: " << option_name;
1514 }
1515 break;
1516 }
1517
James Hawkinsabd73e62016-01-19 15:10:38 -08001518 case 'h': {
1519 ShowHelp(argv[0]);
1520 break;
1521 }
1522
1523 case 'l': {
1524 LogBootEvents();
1525 break;
1526 }
1527
1528 case 'p': {
1529 PrintBootEvents();
1530 break;
1531 }
1532
1533 case 'r': {
1534 // |optarg| is an external variable set by getopt representing
1535 // the option argument.
James Hawkinsc6275582016-03-22 10:47:44 -07001536 boot_event = optarg;
James Hawkinsabd73e62016-01-19 15:10:38 -08001537 break;
1538 }
1539
1540 default: {
1541 DCHECK_EQ(opt, '?');
1542
1543 // |optopt| is an external variable set by getopt representing
1544 // the value of the invalid option.
1545 LOG(ERROR) << "Invalid option: " << optopt;
1546 ShowHelp(argv[0]);
1547 return EXIT_FAILURE;
1548 }
1549 }
1550 }
1551
James Hawkinsc6275582016-03-22 10:47:44 -07001552 if (!boot_event.empty()) {
1553 RecordBootEventFromCommandLine(boot_event, value);
1554 }
1555
James Hawkinsabd73e62016-01-19 15:10:38 -08001556 return 0;
1557}