blob: 62646ea5e4574653683d593dfd27f097b6b33167 [file] [log] [blame]
Elly Jones03cd6d72012-06-11 13:04:28 -04001// Copyright (c) 2012 The Chromium OS Authors. All rights reserved.
2// Use of this source code is governed by a BSD-style license that can be
3// found in the LICENSE file.
4
Alex Vakulenko262be3f2014-07-30 15:25:50 -07005#include "debugd/src/log_tool.h"
Elly Jones03cd6d72012-06-11 13:04:28 -04006
Mike Frysinger020c2402020-12-16 05:40:53 -05007#include <glob.h>
Fletcher Woodruff07c28532019-01-24 11:08:53 -07008#include <grp.h>
9#include <inttypes.h>
Chinglin Yuaeb4ec72018-12-10 18:53:30 +080010#include <lzma.h>
Fletcher Woodruff07c28532019-01-24 11:08:53 -070011#include <pwd.h>
12#include <stdint.h>
13#include <sys/types.h>
14#include <unistd.h>
Ben Chan8e9f6d02017-09-26 23:04:21 -070015#include <memory>
Luis Hector Chavezfc2566f2018-09-13 15:00:36 -070016#include <string>
Fletcher Woodruff07c28532019-01-24 11:08:53 -070017#include <utility>
Ben Chana0011d82014-05-13 00:19:29 -070018#include <vector>
19
Ben Chanab93abf2017-01-24 13:32:51 -080020#include <base/base64.h>
Qijiang Fan713061e2021-03-08 15:45:12 +090021#include <base/check.h>
22#include <base/check_op.h>
Fletcher Woodruff07c28532019-01-24 11:08:53 -070023#include <base/files/file.h>
24#include <base/files/file_path.h>
Ben Chancd8fda42014-09-05 08:21:06 -070025#include <base/files/file_util.h>
Ahmed Fakhry21140cf2016-03-04 17:15:19 -080026#include <base/json/json_writer.h>
Elly Jones03cd6d72012-06-11 13:04:28 -040027#include <base/logging.h>
Ben Chan9953a592014-02-05 23:32:00 -080028#include <base/strings/string_split.h>
29#include <base/strings/string_util.h>
Luis Hector Chavezfc2566f2018-09-13 15:00:36 -070030#include <base/strings/stringprintf.h>
31#include <base/strings/utf_string_conversion_utils.h>
Ahmed Fakhry21140cf2016-03-04 17:15:19 -080032#include <base/values.h>
Elly Jones03cd6d72012-06-11 13:04:28 -040033
Rebecca Silbersteine78af402014-10-02 10:55:04 -070034#include <chromeos/dbus/service_constants.h>
Eric Carusocc7106c2017-04-27 14:22:42 -070035#include <shill/dbus-proxies.h>
Rebecca Silbersteine78af402014-10-02 10:55:04 -070036
Ben Chanaf125862017-02-08 23:11:18 -080037#include "debugd/src/constants.h"
Chinglin Yuaeb4ec72018-12-10 18:53:30 +080038#include "debugd/src/perf_tool.h"
Alex Vakulenko262be3f2014-07-30 15:25:50 -070039#include "debugd/src/process_with_output.h"
Elly Jones03cd6d72012-06-11 13:04:28 -040040
Ereth McKnight-MacNeilca3fff22021-01-21 23:31:40 -080041#include <brillo/files/safe_fd.h>
42#include <brillo/files/file_util.h>
Kartik Hegde1c4b97b2018-09-09 19:09:34 -060043#include "brillo/key_value_store.h"
44#include <brillo/osrelease_reader.h>
mhasank86c46c72020-08-13 15:36:29 -070045#include <brillo/cryptohome.h>
Kartik Hegde1c4b97b2018-09-09 19:09:34 -060046
Elly Jones03cd6d72012-06-11 13:04:28 -040047namespace debugd {
48
Elly Jones03cd6d72012-06-11 13:04:28 -040049using std::string;
Elly Jones03cd6d72012-06-11 13:04:28 -040050
Eric Caruso96d03d32017-04-25 18:01:17 -070051using Strings = std::vector<string>;
Elly Jones03cd6d72012-06-11 13:04:28 -040052
Ahmed Fakhry21140cf2016-03-04 17:15:19 -080053namespace {
54
Ben Chanaf125862017-02-08 23:11:18 -080055const char kRoot[] = "root";
56const char kShell[] = "/bin/sh";
Kartik Hegde1c4b97b2018-09-09 19:09:34 -060057constexpr char kLsbReleasePath[] = "/etc/lsb-release";
mhasank80cbe4d2020-04-02 22:46:08 -070058constexpr char kArcBugReportBackupFileName[] = "arc-bugreport.log";
mhasankd2b84882020-05-04 17:02:19 -070059constexpr char kArcBugReportBackupKey[] = "arc-bugreport-backup";
mhasank80cbe4d2020-04-02 22:46:08 -070060constexpr char kDaemonStoreBaseDir[] = "/run/daemon-store/debugd/";
Ahmed Fakhry21140cf2016-03-04 17:15:19 -080061
62// Minimum time in seconds needed to allow shill to test active connections.
63const int kConnectionTesterTimeoutSeconds = 5;
Ben Chanf6cd93a2012-10-14 19:37:00 -070064
Chinglin Yuaeb4ec72018-12-10 18:53:30 +080065// Default running perf for 2 seconds.
66constexpr const int kPerfDurationSecs = 2;
Chinglin Yu3c8d0a22019-02-20 11:32:52 +080067// TODO(chinglinyu) Remove after crbug/934702 is fixed.
68// The following description is added to 'perf-data' as a temporary solution
69// before the update of feedback disclosure to users is done in crbug/934702.
70constexpr const char kPerfDataDescription[] =
71 "perf-data contains performance profiling information about how much time "
72 "the system spends on various activities (program execution stack traces). "
73 "This might reveal some information about what system features and "
74 "resources are being used. The full detail of perf-data can be found in "
75 "the PerfDataProto protocol buffer message type in the chromium source "
76 "repository.\n";
Chinglin Yuaeb4ec72018-12-10 18:53:30 +080077
Eric Carusoa879fd92017-10-11 12:57:10 -070078#define CMD_KERNEL_MODULE_PARAMS(module_name) \
Tom Hughesd6c2d392020-08-24 18:12:11 -070079 "cd /sys/module/" #module_name "/parameters 2>/dev/null && grep -sH ^ *"
Eric Carusoa879fd92017-10-11 12:57:10 -070080
Fletcher Woodruff07c28532019-01-24 11:08:53 -070081using Log = LogTool::Log;
82constexpr Log::LogType kCommand = Log::kCommand;
83constexpr Log::LogType kFile = Log::kFile;
Mike Frysinger020c2402020-12-16 05:40:53 -050084constexpr Log::LogType kGlob = Log::kGlob;
mhasankaf5251d2020-04-29 18:53:03 -070085
86class ArcBugReportLog : public LogTool::Log {
87 public:
88 ArcBugReportLog()
89 : Log(kCommand,
90 "arc-bugreport",
91 "/usr/bin/nsenter -t1 -m /usr/sbin/android-sh -c "
92 "/system/bin/arc-bugreport",
93 kRoot,
94 kRoot,
95 10 * 1024 * 1024 /*10 MiB*/,
96 LogTool::Encoding::kUtf8) {}
97
98 virtual ~ArcBugReportLog() = default;
99};
mhasank80cbe4d2020-04-02 22:46:08 -0700100
Miriam Zimmermand91d8e72019-06-27 12:24:04 -0700101// NOTE: IF YOU ADD AN ENTRY TO THIS LIST, PLEASE:
102// * add a row to http://go/cros-feedback-audit and fill it out
Miriam Zimmerman4f142ba2020-06-01 14:15:21 -0700103// * email cros-telemetry@
Miriam Zimmermand91d8e72019-06-27 12:24:04 -0700104// (Eventually we'll have a better process, but for now please do this.)
Tom Hughesd6c2d392020-08-24 18:12:11 -0700105// clang-format off
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700106const std::vector<Log> kCommandLogs {
Mike Frysingerb0350992018-09-14 13:45:35 -0400107 // We need to enter init's mount namespace because it has /home/chronos
108 // mounted which is where the consent knob lives. We don't have that mount
109 // in our own mount namespace (by design). https://crbug.com/884249
Chris Morin853d3442019-04-01 21:35:13 -0700110 {kCommand, "CLIENT_ID", "/usr/bin/nsenter -t1 -m /usr/bin/metrics_client -i",
111 kRoot, kDebugfsGroup},
Kimiyuki Onaka3aab14e2021-02-22 13:13:07 +0900112 // We consistently use UTC in feedback reports.
113 {kCommand, "LOGDATE", "/bin/date --utc; /bin/date"},
Yusuke Sato27a31672019-04-29 15:26:37 -0700114 // We need to enter init's mount namespace to access /home/root. Also, we use
115 // neither ARC container's mount namespace (with android-sh) nor
116 // /opt/google/containers/android/rootfs/android-data/ so that we can get
117 // results even when the container is down.
118 {kCommand, "android_app_storage", "/usr/bin/nsenter -t1 -m "
119 "/bin/sh -c \"/usr/bin/du -h /home/root/*/android-data/data/\"",
120 kRoot, kDebugfsGroup},
Chris Morin853d3442019-04-01 21:35:13 -0700121 {kFile, "atrus_logs", "/var/log/atrus.log"},
122 {kFile, "authpolicy", "/var/log/authpolicy.log"},
Kimiyuki Onakafc372892020-11-24 23:28:20 +0900123#if USE_ARCVM
124 {kCommand, "arcvm_console_output", "/usr/bin/vm_pstore_dump", "crosvm",
Junichi Uekawace96c6d2021-03-12 09:34:10 +0900125 "crosvm", Log::kDefaultMaxBytes, LogTool::Encoding::kAutodetect,
126 true /* access_root_mount_ns */},
Kimiyuki Onakafc372892020-11-24 23:28:20 +0900127#endif // USE_ARCVM
Brian Norrisafc9f632019-05-09 14:08:28 -0700128 {kCommand, "bootstat_summary", "/usr/bin/bootstat_summary",
129 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
Junichi Uekawace96c6d2021-03-12 09:34:10 +0900130 Log::kDefaultMaxBytes, LogTool::Encoding::kAutodetect,
131 true /* access_root_mount_ns */},
Craig Hesling5c384b52019-04-20 15:18:06 -0700132 {kFile, "bio_crypto_init.LATEST",
133 "/var/log/bio_crypto_init/bio_crypto_init.LATEST"},
134 {kFile, "bio_crypto_init.PREVIOUS",
135 "/var/log/bio_crypto_init/bio_crypto_init.PREVIOUS"},
Chris Morin853d3442019-04-01 21:35:13 -0700136 {kFile, "biod.LATEST", "/var/log/biod/biod.LATEST"},
137 {kFile, "biod.PREVIOUS", "/var/log/biod/biod.PREVIOUS"},
Craig Hesling4c3891e2019-04-20 12:53:54 -0700138 {kFile, "bio_fw_updater.LATEST", "/var/log/biod/bio_fw_updater.LATEST"},
139 {kFile, "bio_fw_updater.PREVIOUS", "/var/log/biod/bio_fw_updater.PREVIOUS"},
Chris Morin853d3442019-04-01 21:35:13 -0700140 {kFile, "bios_info", "/var/log/bios_info.txt"},
141 {kCommand, "bios_log", "cat /sys/firmware/log "
142 "/proc/device-tree/chosen/ap-console-buffer 2>/dev/null"},
143 {kFile, "bios_times", "/var/log/bios_times.txt"},
Anand K Mistryccceb1e2020-01-16 14:00:49 +1100144 // Slow or non-responsive block devices could cause this command to stall. Use
145 // a timeout to prevent this command from blocking log fetching. This command
146 // is expected to take O(100ms) in the normal case.
147 {kCommand, "blkid", "timeout -s KILL 5s /sbin/blkid", kRoot, kRoot},
Chris Morin853d3442019-04-01 21:35:13 -0700148 {kFile, "buddyinfo", "/proc/buddyinfo"},
149 {kCommand, "cbi_info", "/usr/share/userfeedback/scripts/cbi_info", kRoot,
150 kRoot},
151 {kFile, "cheets_log", "/var/log/arc.log"},
152 {kFile, "clobber.log", "/var/log/clobber.log"},
153 {kFile, "clobber-state.log", "/var/log/clobber-state.log"},
Sonny Raobd3dc002020-05-27 21:40:35 -0700154 {kCommand, "chromeos-pgmem", "/usr/bin/chromeos-pgmem", kRoot, kRoot},
Chris Morin853d3442019-04-01 21:35:13 -0700155 {kFile, "chrome_system_log", "/var/log/chrome/chrome"},
156 {kFile, "chrome_system_log.PREVIOUS", "/var/log/chrome/chrome.PREVIOUS"},
Mike Frysinger32cdf3e2017-08-14 18:17:06 -0400157 // There might be more than one record, so grab them all.
158 // Plus, for <linux-3.19, it's named "console-ramoops", but for newer
159 // versions, it's named "console-ramoops-#".
Mike Frysinger020c2402020-12-16 05:40:53 -0500160 {kGlob, "console-ramoops", "/sys/fs/pstore/console-ramoops*"},
Chris Morin853d3442019-04-01 21:35:13 -0700161 {kFile, "cpuinfo", "/proc/cpuinfo"},
162 {kFile, "cr50_version", "/var/cache/cr50-version"},
Nicolas Boichatf3dd82d2020-09-07 15:11:15 +0800163 {kFile, "cros_ec.log", "/var/log/cros_ec.log",
164 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
165 Log::kDefaultMaxBytes, LogTool::Encoding::kUtf8},
166 {kFile, "cros_ec.previous", "/var/log/cros_ec.previous",
167 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
168 Log::kDefaultMaxBytes, LogTool::Encoding::kUtf8},
Chris Morin853d3442019-04-01 21:35:13 -0700169 {kFile, "cros_ec_panicinfo", "/sys/kernel/debug/cros_ec/panicinfo",
Nicolas Boichat28272d72020-09-03 09:10:44 +0800170 SandboxedProcess::kDefaultUser, kDebugfsGroup, Log::kDefaultMaxBytes,
171 LogTool::Encoding::kBase64},
Stephen Boydf00c5a02020-09-10 19:11:35 -0700172 {kCommand, "cros_ec_pdinfo",
173 "for port in 0 1 2 3 4 5 6 7 8; do "
174 "echo \"-----------\"; "
175 // stderr output just tells us it failed
176 "ectool usbpd \"${port}\" 2>/dev/null || break; "
177 "done", kRoot, kRoot},
Nicolas Boichatf3dd82d2020-09-07 15:11:15 +0800178 {kFile, "cros_fp.previous", "/var/log/cros_fp.previous",
179 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
180 Log::kDefaultMaxBytes, LogTool::Encoding::kUtf8},
181 {kFile, "cros_fp.log", "/var/log/cros_fp.log",
182 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
183 Log::kDefaultMaxBytes, LogTool::Encoding::kUtf8},
184 {kFile, "cros_ish.previous", "/var/log/cros_ish.previous",
185 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
186 Log::kDefaultMaxBytes, LogTool::Encoding::kUtf8},
187 {kFile, "cros_ish.log", "/var/log/cros_ish.log",
188 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
189 Log::kDefaultMaxBytes, LogTool::Encoding::kUtf8},
Nicolas Boichat579ccf32020-08-31 11:17:21 +0800190 {kFile, "cros_scp.previous", "/var/log/cros_scp.previous",
191 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
192 64 * 1024, LogTool::Encoding::kUtf8},
193 {kFile, "cros_scp.log", "/var/log/cros_scp.log",
194 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
195 64 * 1024, LogTool::Encoding::kUtf8},
David Munro0eb1ac32020-08-19 22:22:56 +1000196 {kCommand, "crosvm.log", "nsenter -t1 -m /bin/sh -c 'tail -n+1"
197 " /run/daemon-store/crosvm/*/log/*.log.1"
198 " /run/daemon-store/crosvm/*/log/*.log'", kRoot, kRoot},
Steven 'Steve' Kendall33f72192021-02-16 16:36:16 -0500199 // dmesg: add full timestamps to dmesg to match other logs.
Jorge Lucangeli Obesaf8f79c2020-11-13 08:54:55 -0500200 // 'dmesg' needs CAP_SYSLOG.
Steven 'Steve' Kendall33f72192021-02-16 16:36:16 -0500201 {kCommand, "dmesg", "TZ=UTC /bin/dmesg --time-format iso", kRoot, kRoot},
Mike Frysinger020c2402020-12-16 05:40:53 -0500202 {kGlob, "drm_gem_objects", "/sys/kernel/debug/dri/?/gem",
Stephen Boyda8287162020-07-30 18:07:53 -0700203 SandboxedProcess::kDefaultUser, kDebugfsGroup},
Mike Frysinger020c2402020-12-16 05:40:53 -0500204 {kGlob, "drm_state", "/sys/kernel/debug/dri/?/state",
Stephen Boyda8287162020-07-30 18:07:53 -0700205 SandboxedProcess::kDefaultUser, kDebugfsGroup},
Chris Morin853d3442019-04-01 21:35:13 -0700206 {kFile, "ec_info", "/var/log/ec_info.txt"},
Chris Morin853d3442019-04-01 21:35:13 -0700207 {kCommand, "edid-decode",
Stephen Boydaf3118b2020-08-11 11:42:22 -0700208 "for f in /sys/class/drm/card?-*/edid; do "
Chris Morin853d3442019-04-01 21:35:13 -0700209 "echo \"----------- ${f}\"; "
Chris Morin853d3442019-04-01 21:35:13 -0700210 // edid-decode's stderr output is redundant, so silence it.
Jeffrey Kardatzke9ba9f322019-08-29 10:23:14 -0700211 "edid-decode \"${f}\" 2>/dev/null; "
Chris Morin853d3442019-04-01 21:35:13 -0700212 "done"},
213 {kFile, "eventlog", "/var/log/eventlog.txt"},
Chris Morin853d3442019-04-01 21:35:13 -0700214 {kCommand, "font_info", "/usr/share/userfeedback/scripts/font_info"},
Mike Frysinger020c2402020-12-16 05:40:53 -0500215 {kGlob, "framebuffer", "/sys/kernel/debug/dri/?/framebuffer",
Kuo-Hsin Yang95296e12020-03-06 17:52:35 +0800216 SandboxedProcess::kDefaultUser, kDebugfsGroup},
Daniel Campello9f0b2b82020-10-23 11:01:01 -0600217 {kCommand, "fwupd_state", "/sbin/initctl emit fwupdtool-getdevices;"
218 "cat /var/lib/fwupd/state.json", kRoot, kRoot},
Chris Morin853d3442019-04-01 21:35:13 -0700219 {kCommand, "sensor_info", "/usr/share/userfeedback/scripts/sensor_info"},
220 {kFile, "hammerd", "/var/log/hammerd.log"},
221 {kCommand, "hardware_class", "/usr/bin/crossystem hwid"},
Yong Hong15e4b032020-03-05 15:41:31 +0800222 {kFile, "hardware_verification_report",
223 "/var/cache/hardware_verifier.result"},
Chris Morin853d3442019-04-01 21:35:13 -0700224 {kCommand, "hostname", "/bin/hostname"},
225 {kFile, "i915_gem_gtt", "/sys/kernel/debug/dri/0/i915_gem_gtt",
226 SandboxedProcess::kDefaultUser, kDebugfsGroup},
227 {kFile, "i915_gem_objects", "/sys/kernel/debug/dri/0/i915_gem_objects",
228 SandboxedProcess::kDefaultUser, kDebugfsGroup},
Chris Morin853d3442019-04-01 21:35:13 -0700229 {kCommand, "i915_error_state",
230 "/usr/bin/xz -c /sys/kernel/debug/dri/0/i915_error_state 2>/dev/null",
231 SandboxedProcess::kDefaultUser, kDebugfsGroup, Log::kDefaultMaxBytes,
Chris Morin790fd262019-04-03 20:29:36 -0700232 LogTool::Encoding::kBase64},
Evan Benn0e373cc2021-01-07 15:10:26 +1100233 {kFile, "amdgpu_gem_info", "/sys/kernel/debug/dri/0/amdgpu_gem_info",
234 SandboxedProcess::kDefaultUser, kDebugfsGroup},
235 {kFile, "amdgpu_gtt_mm", "/sys/kernel/debug/dri/0/amdgpu_gtt_mm",
236 SandboxedProcess::kDefaultUser, kDebugfsGroup},
237 {kFile, "amdgpu_vram_mm", "/sys/kernel/debug/dri/0/amdgpu_vram_mm",
238 SandboxedProcess::kDefaultUser, kDebugfsGroup},
Chris Morin853d3442019-04-01 21:35:13 -0700239 {kCommand, "ifconfig", "/bin/ifconfig -a"},
240 {kFile, "input_devices", "/proc/bus/input/devices"},
Eric Carusob1820c02017-08-24 15:39:56 -0700241 // Hardware capabilities of the wiphy device.
Alex Levine1c6d572019-09-17 14:45:33 -0700242 {kFile, "interrupts", "/proc/interrupts"},
Chris Morin853d3442019-04-01 21:35:13 -0700243 {kCommand, "iw_list", "/usr/sbin/iw list"},
Eric Carusoa879fd92017-10-11 12:57:10 -0700244#if USE_IWLWIFI_DUMP
Chris Morin853d3442019-04-01 21:35:13 -0700245 {kCommand, "iwlmvm_module_params", CMD_KERNEL_MODULE_PARAMS(iwlmvm)},
246 {kCommand, "iwlwifi_module_params", CMD_KERNEL_MODULE_PARAMS(iwlwifi)},
Eric Carusoa879fd92017-10-11 12:57:10 -0700247#endif // USE_IWLWIFI_DUMP
Mike Frysinger020c2402020-12-16 05:40:53 -0500248 {kGlob, "kernel-crashes", "/var/spool/crash/kernel.*.kcrash",
Mike Frysinger2abf7a12020-12-16 05:39:02 -0500249 SandboxedProcess::kDefaultUser, "crash-access"},
Anand K Mistryccceb1e2020-01-16 14:00:49 +1100250 {kCommand, "lsblk", "timeout -s KILL 5s lsblk -a", kRoot, kRoot,
Junichi Uekawace96c6d2021-03-12 09:34:10 +0900251 Log::kDefaultMaxBytes, LogTool::Encoding::kAutodetect,
252 true /* access_root_mount_ns */},
Chris Morin853d3442019-04-01 21:35:13 -0700253 {kCommand, "lsmod", "lsmod"},
Chris Morin853d3442019-04-01 21:35:13 -0700254 {kCommand, "lsusb", "lsusb && lsusb -t"},
Kuo-Hsin Yang66d89832020-02-10 17:22:28 +0800255 {kFile, "mali_memory", "/sys/kernel/debug/mali0/gpu_memory",
256 SandboxedProcess::kDefaultUser, kDebugfsGroup},
Chris Morin853d3442019-04-01 21:35:13 -0700257 {kFile, "memd.parameters", "/var/log/memd/memd.parameters"},
Mike Frysinger020c2402020-12-16 05:40:53 -0500258 {kGlob, "memd clips", "/var/log/memd/memd.clip*"},
Chris Morin853d3442019-04-01 21:35:13 -0700259 {kFile, "meminfo", "/proc/meminfo"},
260 {kCommand, "memory_spd_info",
261 // mosys may use 'i2c-dev', which may not be loaded yet.
262 "modprobe i2c-dev 2>/dev/null && mosys -l memory spd print all 2>/dev/null",
263 kRoot, kDebugfsGroup},
Simon Quecb63b9c2017-06-19 14:53:31 -0400264 // The sed command finds the EDID blob (starting the line after "value:") and
265 // replaces the serial number with all zeroes.
266 //
267 // The EDID is printed as a hex dump over several lines, each line containing
268 // the contents of 16 bytes. The first 16 bytes are broken down as follows:
269 // uint64_t fixed_pattern; // Always 00 FF FF FF FF FF FF 00.
270 // uint16_t manufacturer_id; // Manufacturer ID, encoded as PNP IDs.
271 // uint16_t product_code; // Manufacturer product code, little-endian.
272 // uint32_t serial_number; // Serial number, little-endian.
273 // Source: https://en.wikipedia.org/wiki/EDID#EDID_1.3_data_format
274 //
275 // The subsequent substitution command looks for the fixed pattern followed by
276 // two 32-bit fields (manufacturer + product, serial number). It replaces the
277 // latter field with 8 bytes of zeroes.
278 //
279 // TODO(crbug.com/731133): Remove the sed command once modetest itself can
280 // remove serial numbers.
Chris Morin853d3442019-04-01 21:35:13 -0700281 {kCommand, "modetest",
282 "(modetest; modetest -M evdi; modetest -M udl) | "
283 "sed -E '/EDID/ {:a;n;/value:/!ba;n;"
284 "s/(00f{12}00)([0-9a-f]{8})([0-9a-f]{8})/\\1\\200000000/}'",
285 kRoot, kRoot},
286 {kFile, "mount-encrypted", "/var/log/mount-encrypted.log"},
287 {kFile, "mountinfo", "/proc/1/mountinfo"},
288 {kCommand, "netlog",
Brian Norris72588462021-01-25 19:32:31 -0800289 "/usr/share/userfeedback/scripts/getmsgs /var/log/net.log",
290 SandboxedProcess::kDefaultUser, SandboxedProcess::kDefaultGroup,
291 Log::kDefaultMaxBytes, LogTool::Encoding::kUtf8},
Chris Morin853d3442019-04-01 21:35:13 -0700292 {kFile, "nvmap_iovmm", "/sys/kernel/debug/nvmap/iovmm/allocations",
293 SandboxedProcess::kDefaultUser, kDebugfsGroup},
294 {kCommand, "oemdata", "/usr/share/cros/oemdata.sh", kRoot, kRoot},
Kuo-Hsin Yanga69ecc62020-03-11 17:37:11 +0800295 {kFile, "pagetypeinfo", "/proc/pagetypeinfo", kRoot},
Jack Rosenthal3cf794a2020-02-19 13:32:56 -0700296 {kFile, "platform_identity_name",
297 "/run/chromeos-config/v1/identity/platform-name"},
298 {kFile, "platform_identity_model", "/run/chromeos-config/v1/name"},
299 {kFile, "platform_identity_sku", "/run/chromeos-config/v1/identity/sku-id"},
300 {kFile, "platform_identity_whitelabel_tag",
301 "/run/chromeos-config/v1/identity/whitelabel-tag"},
302 {kFile, "platform_identity_customization_id",
303 "/run/chromeos-config/v1/identity/customization-id"},
Chris Morin853d3442019-04-01 21:35:13 -0700304 {kCommand, "power_supply_info", "/usr/bin/power_supply_info"},
305 {kCommand, "power_supply_sysfs", "/usr/bin/print_sysfs_power_supply_data"},
306 {kFile, "powerd.LATEST", "/var/log/power_manager/powerd.LATEST"},
307 {kFile, "powerd.PREVIOUS", "/var/log/power_manager/powerd.PREVIOUS"},
308 {kFile, "powerd.out", "/var/log/powerd.out"},
309 {kFile, "powerwash_count", "/var/log/powerwash_count"},
Brian Norris4cde3d12019-04-16 10:10:34 -0700310 {kCommand, "ps", "/bin/ps auxZ"},
Mike Frysinger020c2402020-12-16 05:40:53 -0500311 {kGlob, "qcom_fw_info", "/sys/kernel/debug/qcom_socinfo/*/*",
Stephen Boyd57779f92020-12-15 23:24:31 -0800312 SandboxedProcess::kDefaultUser, kDebugfsGroup},
yusukes34171ba2017-04-27 15:46:01 -0700313 // /proc/slabinfo is owned by root and has 0400 permission.
Chris Morin853d3442019-04-01 21:35:13 -0700314 {kFile, "slabinfo", "/proc/slabinfo", kRoot, kRoot},
315 {kFile, "storage_info", "/var/log/storage_info.txt"},
316 {kCommand, "swap_info", "/usr/share/cros/init/swap.sh status 2>/dev/null",
317 SandboxedProcess::kDefaultUser, kDebugfsGroup},
318 {kCommand, "syslog",
319 "/usr/share/userfeedback/scripts/getmsgs /var/log/messages"},
320 {kCommand, "system_log_stats",
321 "echo 'BLOCK_SIZE=1024'; "
322 "find /var/log/ -type f -exec du --block-size=1024 {} + | sort -n -r",
323 kRoot, kRoot},
324 {kCommand, "threads", "/bin/ps -T axo pid,ppid,spid,pcpu,ni,stat,time,comm"},
325 {kFile, "tlsdate", "/var/log/tlsdate.log"},
Nick Sandersad5dc132019-11-15 15:59:42 -0800326 {kCommand, "top thread", "/usr/bin/top -Hbc -w128 -n 1 | head -n 40"},
327 {kCommand, "top memory",
328 "/usr/bin/top -o \"+%MEM\" -w128 -bcn 1 | head -n 57"},
Chris Morin853d3442019-04-01 21:35:13 -0700329 {kCommand, "touch_fw_version",
Stephen Boyddb9eb2f2020-08-11 11:25:41 -0700330 "grep -aE"
Chris Morin853d3442019-04-01 21:35:13 -0700331 " -e 'synaptics: Touchpad model'"
332 " -e 'chromeos-[a-z]*-touch-[a-z]*-update'"
333 " /var/log/messages | tail -n 20"},
334 {kCommand, "tpm-firmware-updater", "/usr/share/userfeedback/scripts/getmsgs "
335 "/var/log/tpm-firmware-updater.log"},
Mattias Nissler887dce22017-07-03 14:44:35 +0200336 // TODO(jorgelo,mnissler): Don't run this as root.
337 // On TPM 1.2 devices this will likely require adding a new user to the 'tss'
338 // group.
339 // On TPM 2.0 devices 'get_version_info' uses D-Bus and therefore can run as
340 // any user.
Chris Morin853d3442019-04-01 21:35:13 -0700341 {kCommand, "tpm_version", "/usr/sbin/tpm-manager get_version_info", kRoot,
342 kRoot},
Prashant Malani39ddecd2021-02-25 17:11:39 -0800343 // typecd logs average around 56K. VID/PIDs are obfuscated from the printed
344 // PD identity information.
345 {kFile, "typecd", "/var/log/typecd.log"},
Chris Morin853d3442019-04-01 21:35:13 -0700346 {kCommand, "atmel_ts_refs",
347 "/opt/google/touch/scripts/atmel_tools.sh ts r", kRoot, kRoot},
348 {kCommand, "atmel_tp_refs",
349 "/opt/google/touch/scripts/atmel_tools.sh tp r", kRoot, kRoot},
350 {kCommand, "atmel_ts_deltas",
351 "/opt/google/touch/scripts/atmel_tools.sh ts d", kRoot, kRoot},
352 {kCommand, "atmel_tp_deltas",
353 "/opt/google/touch/scripts/atmel_tools.sh tp d", kRoot, kRoot},
354 {kFile, "stateful_trim_state", "/var/lib/trim/stateful_trim_state"},
355 {kFile, "stateful_trim_data", "/var/lib/trim/stateful_trim_data"},
356 {kFile, "ui_log", "/var/log/ui/ui.LATEST"},
357 {kCommand, "uname", "/bin/uname -a"},
358 {kCommand, "update_engine.log",
359 "cat $(ls -1tr /var/log/update_engine | tail -5 | sed"
360 " s.^./var/log/update_engine/.)"},
Chris Morinca152712019-05-03 13:17:28 -0700361 {kFile, "upstart", "/var/log/upstart.log"},
Chris Morin853d3442019-04-01 21:35:13 -0700362 {kCommand, "uptime", "/usr/bin/cut -d' ' -f1 /proc/uptime"},
Prashant Malani9704ed02021-02-10 16:19:22 -0800363 {kCommand, "usb4 devices", "/usr/libexec/debugd/helpers/usb4_devinfo_helper",
364 SandboxedProcess::kDefaultUser, kDebugfsGroup},
Chris Morin853d3442019-04-01 21:35:13 -0700365 {kFile, "verified boot", "/var/log/debug_vboot_noisy.log"},
366 {kFile, "vmlog.1.LATEST", "/var/log/vmlog/vmlog.1.LATEST"},
367 {kFile, "vmlog.1.PREVIOUS", "/var/log/vmlog/vmlog.1.PREVIOUS"},
368 {kFile, "vmlog.LATEST", "/var/log/vmlog/vmlog.LATEST"},
369 {kFile, "vmlog.PREVIOUS", "/var/log/vmlog/vmlog.PREVIOUS"},
370 {kFile, "vmstat", "/proc/vmstat"},
371 {kFile, "vpd_2.0", "/var/log/vpd_2.0.txt"},
Chris Morin853d3442019-04-01 21:35:13 -0700372 {kFile, "zram compressed data size", "/sys/block/zram0/compr_data_size"},
373 {kFile, "zram original data size", "/sys/block/zram0/orig_data_size"},
374 {kFile, "zram total memory used", "/sys/block/zram0/mem_used_total"},
375 {kFile, "zram total reads", "/sys/block/zram0/num_reads"},
376 {kFile, "zram total writes", "/sys/block/zram0/num_writes"},
377 {kCommand, "zram new stats names",
378 "echo orig_size compr_size used_total limit used_max zero_pages migrated"},
379 {kFile, "zram new stats values", "/sys/block/zram0/mm_stat"},
380 {kFile, "cros_tp version", "/sys/class/chromeos/cros_tp/version"},
381 {kCommand, "cros_tp console", "/usr/sbin/ectool --name=cros_tp console",
382 kRoot, kRoot},
383 {kCommand, "cros_tp frame", "/usr/sbin/ectool --name=cros_tp tpframeget",
384 kRoot, kRoot},
385 {kCommand, "crostini", "/usr/bin/cicerone_client --get_info"},
Sean Paulbfc5c422020-07-24 11:03:51 -0400386 // TODO(seanpaul): Once we've finished moving over to the upstream tracefs
387 // implementation, remove drm_trace_legacy. Tracked in
388 // b/163580546.
389 {kFile, "drm_trace_legacy", "/sys/kernel/debug/dri/trace",
390 SandboxedProcess::kDefaultUser, kDebugfsGroup},
391 {kFile, "drm_trace", "/sys/kernel/debug/tracing/instances/drm/trace",
Sean Paul5ce118f2019-12-05 08:41:32 -0500392 SandboxedProcess::kDefaultUser, kDebugfsGroup},
Elly Jones03cd6d72012-06-11 13:04:28 -0400393 // Stuff pulled out of the original list. These need access to the running X
394 // session, which we'd rather not give to debugd, or return info specific to
395 // the current session (in the setsid(2) sense), which is not useful for
396 // debugd
Chris Morin853d3442019-04-01 21:35:13 -0700397 // {kCommand, "env", "set"},
398 // {kCommand, "setxkbmap", "/usr/bin/setxkbmap -print -query"},
399 // {kCommand, "xrandr", "/usr/bin/xrandr --verbose}
Elly Jones533c7c42012-08-10 15:07:05 -0400400};
Tom Hughesd6c2d392020-08-24 18:12:11 -0700401// clang-format on
Elly Jones533c7c42012-08-10 15:07:05 -0400402
Arowa Suliman3bc729c2021-03-09 13:25:00 -0800403const std::vector<Log> kCommandLogsVerbose{
404 {kCommand, "lspci_verbose", "/usr/sbin/lspci -vvvnn"},
405};
406
407const std::vector<Log> kCommandLogsShort{
408 {kCommand, "lspci", "/usr/sbin/lspci"},
409};
410
Junichi Uekawaba686c82020-09-01 16:24:24 +0900411// Extra logs are logs such as netstat and logcat which should appear in
412// chrome://system but not in feedback reports. Open sockets may have privacy
413// implications, and logcat is already incorporated via arc-bugreport.
414//
Tom Hughesd6c2d392020-08-24 18:12:11 -0700415// clang-format off
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700416const std::vector<Log> kExtraLogs {
Ben Chan36e42282014-02-12 22:32:34 -0800417#if USE_CELLULAR
Chris Morin853d3442019-04-01 21:35:13 -0700418 {kCommand, "mm-status", "/usr/bin/modem status"},
Ben Chan36e42282014-02-12 22:32:34 -0800419#endif // USE_CELLULAR
Chris Morin853d3442019-04-01 21:35:13 -0700420 {kCommand, "network-devices", "/usr/bin/connectivity show devices"},
421 {kCommand, "network-services", "/usr/bin/connectivity show services"},
Jeffrey Kardatzke36791f22019-07-11 11:53:22 -0700422 {kCommand, "wifi_status_no_anonymize",
423 "/usr/bin/network_diag --wifi-internal --no-log"},
Chris Morin253a2b02019-04-12 16:04:25 -0700424 // --processes requires root.
425 {kCommand, "netstat",
426 "/sbin/ss --all --query inet --numeric --processes", kRoot, kRoot},
Kansho Nishida6ae546f2019-08-13 17:14:58 +0900427 {kCommand, "logcat",
428 "/usr/bin/nsenter -t1 -m /usr/sbin/android-sh -c '/system/bin/logcat -d'",
Chris Morin253a2b02019-04-12 16:04:25 -0700429 kRoot, kRoot, Log::kDefaultMaxBytes, LogTool::Encoding::kUtf8},
Elly Jones533c7c42012-08-10 15:07:05 -0400430};
Tom Hughesd6c2d392020-08-24 18:12:11 -0700431// clang-format on
Elly Jones533c7c42012-08-10 15:07:05 -0400432
Tom Hughesd6c2d392020-08-24 18:12:11 -0700433// clang-format off
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700434const std::vector<Log> kFeedbackLogs {
Ben Chan36e42282014-02-12 22:32:34 -0800435#if USE_CELLULAR
Chris Morin853d3442019-04-01 21:35:13 -0700436 {kCommand, "mm-status", "/usr/bin/modem status-feedback"},
Ben Chan36e42282014-02-12 22:32:34 -0800437#endif // USE_CELLULAR
Chris Morin853d3442019-04-01 21:35:13 -0700438 {kCommand, "network-devices",
439 "/usr/bin/connectivity show-feedback devices"},
440 {kCommand, "network-services",
441 "/usr/bin/connectivity show-feedback services"},
Jeffrey Kardatzke36791f22019-07-11 11:53:22 -0700442 {kCommand, "wifi_status",
443 "/usr/bin/network_diag --wifi-internal --no-log --anonymize"},
Elly Jones03cd6d72012-06-11 13:04:28 -0400444};
Tom Hughesd6c2d392020-08-24 18:12:11 -0700445// clang-format on
Elly Jones03cd6d72012-06-11 13:04:28 -0400446
Jeffrey Kardatzkee3ec6fd2019-08-05 12:25:17 -0700447// Fills |dictionary| with the contents of the logs in |logs|.
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700448void GetLogsInDictionary(const std::vector<Log>& logs,
hscham2311cc22020-10-28 12:13:10 +0900449 base::Value* dictionary) {
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700450 for (const Log& log : logs) {
hscham2311cc22020-10-28 12:13:10 +0900451 dictionary->SetStringKey(log.GetName(), log.GetLogData());
Ahmed Fakhry21140cf2016-03-04 17:15:19 -0800452 }
453}
454
455// Serializes the |dictionary| into the file with the given |fd| in a JSON
456// format.
hscham2311cc22020-10-28 12:13:10 +0900457void SerializeLogsAsJSON(const base::Value& dictionary,
Eric Caruso0b241882018-04-04 13:43:46 -0700458 const base::ScopedFD& fd) {
Eric Caruso96d03d32017-04-25 18:01:17 -0700459 string logs_json;
Tom Hughesd6c2d392020-08-24 18:12:11 -0700460 base::JSONWriter::WriteWithOptions(
461 dictionary, base::JSONWriter::OPTIONS_PRETTY_PRINT, &logs_json);
Eric Caruso0b241882018-04-04 13:43:46 -0700462 base::WriteFileDescriptor(fd.get(), logs_json.c_str(), logs_json.size());
Ahmed Fakhry21140cf2016-03-04 17:15:19 -0800463}
464
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700465bool GetNamedLogFrom(const string& name,
466 const std::vector<Log>& logs,
Elly Jones533c7c42012-08-10 15:07:05 -0400467 string* result) {
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700468 for (const Log& log : logs) {
469 if (name == log.GetName()) {
470 *result = log.GetLogData();
Elly Jones533c7c42012-08-10 15:07:05 -0400471 return true;
472 }
473 }
474 *result = "<invalid log name>";
475 return false;
Elly Jones03cd6d72012-06-11 13:04:28 -0400476}
477
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700478void GetLogsFrom(const std::vector<Log>& logs, LogTool::LogMap* map) {
479 for (const Log& log : logs)
480 (*map)[log.GetName()] = log.GetLogData();
Ahmed Fakhry21140cf2016-03-04 17:15:19 -0800481}
482
Kartik Hegde1c4b97b2018-09-09 19:09:34 -0600483void GetLsbReleaseInfo(LogTool::LogMap* map) {
484 const base::FilePath lsb_release(kLsbReleasePath);
485 brillo::KeyValueStore store;
486 if (!store.Load(lsb_release)) {
487 // /etc/lsb-release might not be present (cros deploying a new
488 // configuration or no fields set at all). Just print a debug
489 // message and continue.
490 DLOG(INFO) << "Could not load fields from " << lsb_release.value();
491 } else {
492 for (const auto& key : store.GetKeys()) {
493 std::string value;
494 store.GetString(key, &value);
495 (*map)[key] = value;
496 }
497 }
498}
499
500void GetOsReleaseInfo(LogTool::LogMap* map) {
501 brillo::OsReleaseReader reader;
502 reader.Load();
503 for (const auto& key : reader.GetKeys()) {
504 std::string value;
505 reader.GetString(key, &value);
506 (*map)["os-release " + key] = value;
507 }
508}
509
510void PopulateDictionaryValue(const LogTool::LogMap& map,
hscham2311cc22020-10-28 12:13:10 +0900511 base::Value* dictionary) {
Kartik Hegde1c4b97b2018-09-09 19:09:34 -0600512 for (const auto& kv : map) {
hscham2311cc22020-10-28 12:13:10 +0900513 dictionary->SetStringKey(kv.first, kv.second);
Kartik Hegde1c4b97b2018-09-09 19:09:34 -0600514 }
515}
516
Chinglin Yuaeb4ec72018-12-10 18:53:30 +0800517bool CompressXzBuffer(const std::vector<uint8_t>& in_buffer,
518 std::vector<uint8_t>* out_buffer) {
519 size_t out_size = lzma_stream_buffer_bound(in_buffer.size());
520 out_buffer->resize(out_size);
521 size_t out_pos = 0;
522
523 lzma_ret ret = lzma_easy_buffer_encode(
524 LZMA_PRESET_DEFAULT, LZMA_CHECK_CRC64, nullptr, in_buffer.data(),
525 in_buffer.size(), out_buffer->data(), &out_pos, out_size);
526
527 if (ret != LZMA_OK) {
528 out_buffer->clear();
529 return false;
530 }
531
532 out_buffer->resize(out_pos);
533 return true;
534}
535
536void GetPerfData(LogTool::LogMap* map) {
537 // Run perf to collect system-wide performance profile when user triggers
538 // feedback report. Perf runs at sampling frequency of ~500 hz (499 is used
539 // to avoid sampling periodic system activities), with callstack in each
540 // sample (-g).
541 std::vector<std::string> perf_args = {
Tom Hughesd6c2d392020-08-24 18:12:11 -0700542 "perf", "record", "-a", "-g", "-F", "499",
Chinglin Yuaeb4ec72018-12-10 18:53:30 +0800543 };
544 std::vector<uint8_t> perf_data;
545 int32_t status;
546
547 debugd::PerfTool perf_tool;
548 if (!perf_tool.GetPerfOutput(kPerfDurationSecs, perf_args, &perf_data,
549 nullptr, &status, nullptr))
550 return;
551
552 // XZ compress the profile data.
553 std::vector<uint8_t> perf_data_xz;
554 if (!CompressXzBuffer(perf_data, &perf_data_xz))
555 return;
556
557 // Base64 encode the compressed data.
558 std::string perf_data_str(reinterpret_cast<const char*>(perf_data_xz.data()),
559 perf_data_xz.size());
Tom Hughesd6c2d392020-08-24 18:12:11 -0700560 (*map)["perf-data"] = std::string(kPerfDataDescription) +
561 LogTool::EncodeString(std::move(perf_data_str),
562 LogTool::Encoding::kBase64);
Chinglin Yuaeb4ec72018-12-10 18:53:30 +0800563}
564
Ahmed Fakhry21140cf2016-03-04 17:15:19 -0800565} // namespace
566
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700567Log::Log(Log::LogType type,
568 std::string name,
569 std::string data,
570 std::string user,
571 std::string group,
572 int64_t max_bytes,
Brian Norrisafc9f632019-05-09 14:08:28 -0700573 LogTool::Encoding encoding,
574 bool access_root_mount_ns)
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700575 : type_(type),
576 name_(name),
577 data_(data),
578 user_(user),
579 group_(group),
580 max_bytes_(max_bytes),
Brian Norrisafc9f632019-05-09 14:08:28 -0700581 encoding_(encoding),
582 access_root_mount_ns_(access_root_mount_ns) {}
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700583
584std::string Log::GetName() const {
585 return name_;
586}
587
588std::string Log::GetLogData() const {
589 // The reason this code uses a switch statement on a type enum rather than
590 // using inheritance/virtual dispatch is so that all of the Log objects can
591 // be constructed statically. Switching to heap allocated subclasses of Log
592 // makes the code that declares all of the log entries much more verbose
593 // and harder to understand.
Chris Morin790fd262019-04-03 20:29:36 -0700594 std::string output;
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700595 switch (type_) {
596 case kCommand:
Chris Morin790fd262019-04-03 20:29:36 -0700597 output = GetCommandLogData();
598 break;
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700599 case kFile:
Chris Morin790fd262019-04-03 20:29:36 -0700600 output = GetFileLogData();
601 break;
Mike Frysinger020c2402020-12-16 05:40:53 -0500602 case kGlob:
603 output = GetGlobLogData();
604 break;
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700605 default:
Mike Frysingerdf3be7f2020-12-16 03:17:42 -0500606 DCHECK(false) << "unknown log type";
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700607 return "<unknown log type>";
608 }
Chris Morin790fd262019-04-03 20:29:36 -0700609
610 if (output.empty())
611 return "<empty>";
612
613 return LogTool::EncodeString(std::move(output), encoding_);
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700614}
615
616// TODO(ellyjones): sandbox. crosbug.com/35122
617std::string Log::GetCommandLogData() const {
Mike Frysingerdf3be7f2020-12-16 03:17:42 -0500618 DCHECK_EQ(type_, kCommand);
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700619 if (type_ != kCommand)
620 return "<log type mismatch>";
621 std::string tailed_cmdline =
622 base::StringPrintf("%s | tail -c %" PRId64, data_.c_str(), max_bytes_);
623 ProcessWithOutput p;
624 if (minijail_disabled_for_test_)
625 p.set_use_minijail(false);
626 if (!user_.empty() && !group_.empty())
627 p.SandboxAs(user_, group_);
Brian Norrisafc9f632019-05-09 14:08:28 -0700628 if (access_root_mount_ns_)
629 p.AllowAccessRootMountNamespace();
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700630 if (!p.Init())
631 return "<not available>";
632 p.AddArg(kShell);
633 p.AddStringOption("-c", tailed_cmdline);
634 if (p.Run())
635 return "<not available>";
636 std::string output;
637 p.GetOutput(&output);
Chris Morin790fd262019-04-03 20:29:36 -0700638 return output;
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700639}
640
Mike Frysinger020c2402020-12-16 05:40:53 -0500641// static
642std::string Log::GetFileData(const base::FilePath& path,
643 int64_t max_bytes,
644 const std::string& user,
645 const std::string& group) {
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700646 uid_t old_euid = geteuid();
Mike Frysinger020c2402020-12-16 05:40:53 -0500647 uid_t new_euid = UidForUser(user);
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700648 gid_t old_egid = getegid();
Mike Frysinger020c2402020-12-16 05:40:53 -0500649 gid_t new_egid = GidForGroup(group);
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700650
651 if (new_euid == -1 || new_egid == -1) {
652 return "<not available>";
653 }
654
655 // Make sure to set group first, since if we set user first we lose root
656 // and therefore the ability to set our effective gid to arbitrary gids.
657 if (setegid(new_egid)) {
658 PLOG(ERROR) << "Failed to set effective group id to " << new_egid;
659 return "<not available>";
660 }
661 if (seteuid(new_euid)) {
662 PLOG(ERROR) << "Failed to set effective user id to " << new_euid;
663 if (setegid(old_egid))
664 PLOG(ERROR) << "Failed to restore effective group id to " << old_egid;
665 return "<not available>";
666 }
667
668 std::string contents;
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700669 // Handle special files that don't properly report length/allow lseek.
670 if (base::FilePath("/dev").IsParent(path) ||
671 base::FilePath("/proc").IsParent(path) ||
672 base::FilePath("/sys").IsParent(path)) {
673 if (!base::ReadFileToString(path, &contents))
674 contents = "<not available>";
Mike Frysinger020c2402020-12-16 05:40:53 -0500675 if (contents.size() > max_bytes)
676 contents.erase(0, contents.size() - max_bytes);
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700677 } else {
678 base::File file(path, base::File::FLAG_OPEN | base::File::FLAG_READ);
679 if (!file.IsValid()) {
680 contents = "<not available>";
681 } else {
682 int64_t length = file.GetLength();
Mike Frysinger020c2402020-12-16 05:40:53 -0500683 if (length > max_bytes) {
684 file.Seek(base::File::FROM_END, -max_bytes);
685 length = max_bytes;
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700686 }
687 std::vector<char> buf(length);
688 int read = file.ReadAtCurrentPos(buf.data(), buf.size());
689 if (read < 0) {
690 PLOG(ERROR) << "Could not read from file " << path.value();
691 } else {
692 contents = std::string(buf.begin(), buf.begin() + read);
693 }
694 }
695 }
696
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700697 // Make sure we restore our old euid/egid before returning.
698 if (seteuid(old_euid))
699 PLOG(ERROR) << "Failed to restore effective user id to " << old_euid;
700
701 if (setegid(old_egid))
702 PLOG(ERROR) << "Failed to restore effective group id to " << old_egid;
703
Chris Morin790fd262019-04-03 20:29:36 -0700704 return contents;
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700705}
706
Mike Frysinger020c2402020-12-16 05:40:53 -0500707std::string Log::GetFileLogData() const {
708 DCHECK_EQ(type_, kFile);
709 if (type_ != kFile)
710 return "<log type mismatch>";
711
712 return GetFileData(base::FilePath(data_), max_bytes_, user_, group_);
713}
714
715std::string Log::GetGlobLogData() const {
716 DCHECK_EQ(type_, kGlob);
717 if (type_ != kGlob)
718 return "<log type mismatch>";
719
720 // NB: base::FileEnumerator requires a directory to walk, and a pattern to
721 // match against each result. Here we accept full paths with globs in them.
722 glob_t g;
723 // NB: Feel free to add GLOB_BRACE if a user comes up.
724 int gret = glob(data_.c_str(), 0, nullptr, &g);
725 if (gret == GLOB_NOMATCH) {
726 globfree(&g);
727 return "<no matches>";
728 } else if (gret) {
729 globfree(&g);
730 PLOG(ERROR) << "glob " << data_ << " failed";
731 return "<not available>";
732 }
733
734 // The results array will hold 2 entries per file: the filename, and the
735 // results of reading that file.
736 size_t output_size = 0;
737 std::vector<std::string> results;
738 results.reserve(g.gl_pathc * 2);
739
740 for (size_t pathc = 0; pathc < g.gl_pathc; ++pathc) {
741 const base::FilePath path(g.gl_pathv[pathc]);
742 std::string contents = GetFileData(path, max_bytes_, user_, group_);
743 // NB: The 3 represents the bytes we add in the output string below.
744 output_size += path.value().size() + contents.size() + 3;
745 results.push_back(path.value());
746 results.push_back(contents);
747 }
748 globfree(&g);
749
750 // Combine the results into a single string. We have a header with the
751 // filename followed by that file's contents. Very basic format.
752 std::string output;
753 output.reserve(output_size);
754 for (auto iter = results.begin(); iter != results.end(); ++iter) {
755 output += *iter + ":\n";
756 ++iter;
757 output += *iter + "\n";
758 }
759
760 return output;
761}
762
Fletcher Woodruff07c28532019-01-24 11:08:53 -0700763void Log::DisableMinijailForTest() {
764 minijail_disabled_for_test_ = true;
765}
766
767// static
768uid_t Log::UidForUser(const std::string& user) {
769 struct passwd entry;
770 struct passwd* result;
771 std::vector<char> buf(1024);
772 getpwnam_r(user.c_str(), &entry, &buf[0], buf.size(), &result);
773 if (!result) {
774 LOG(ERROR) << "User not found: " << user;
775 return -1;
776 }
777 return entry.pw_uid;
778}
779
780// static
781gid_t Log::GidForGroup(const std::string& group) {
782 struct group entry;
783 struct group* result;
784 std::vector<char> buf(1024);
785 getgrnam_r(group.c_str(), &entry, &buf[0], buf.size(), &result);
786 if (!result) {
787 LOG(ERROR) << "Group not found: " << group;
788 return -1;
789 }
790 return entry.gr_gid;
791}
792
mhasankaf5251d2020-04-29 18:53:03 -0700793LogTool::LogTool(
794 scoped_refptr<dbus::Bus> bus,
795 std::unique_ptr<org::chromium::CryptohomeInterfaceProxyInterface>
796 cryptohome_proxy,
797 std::unique_ptr<LogTool::Log> arc_bug_report_log,
798 const base::FilePath& daemon_store_base_dir)
799 : bus_(bus),
800 cryptohome_proxy_(std::move(cryptohome_proxy)),
801 arc_bug_report_log_(std::move(arc_bug_report_log)),
802 daemon_store_base_dir_(daemon_store_base_dir) {}
mhasank80cbe4d2020-04-02 22:46:08 -0700803
mhasank4f599d32020-04-09 22:07:35 -0700804LogTool::LogTool(scoped_refptr<dbus::Bus> bus)
mhasankaf5251d2020-04-29 18:53:03 -0700805 : LogTool(bus,
806 std::make_unique<org::chromium::CryptohomeInterfaceProxy>(bus),
807 std::make_unique<ArcBugReportLog>(),
808 base::FilePath(kDaemonStoreBaseDir)) {}
mhasank4f599d32020-04-09 22:07:35 -0700809
mhasank7186aae2020-09-16 20:06:00 -0700810bool LogTool::IsUserHashValid(const std::string& userhash) {
811 return brillo::cryptohome::home::IsSanitizedUserName(userhash) &&
812 base::PathExists(daemon_store_base_dir_.Append(userhash));
813}
814
Fletcher Woodruff70f27232019-01-24 11:41:34 -0700815void LogTool::CreateConnectivityReport(bool wait_for_results) {
Rebecca Silbersteine78af402014-10-02 10:55:04 -0700816 // Perform ConnectivityTrial to report connection state in feedback log.
Ben Chan8e9f6d02017-09-26 23:04:21 -0700817 auto shill = std::make_unique<org::chromium::flimflam::ManagerProxy>(bus_);
Rebecca Silbersteine78af402014-10-02 10:55:04 -0700818 // Give the connection trial time to test the connection and log the results
819 // before collecting the logs for feedback.
820 // TODO(silberst): Replace the simple approach of a single timeout with a more
821 // coordinated effort.
Fletcher Woodruff70f27232019-01-24 11:41:34 -0700822 if (shill && shill->CreateConnectivityReport(nullptr) && wait_for_results)
Eric Carusocc7106c2017-04-27 14:22:42 -0700823 sleep(kConnectionTesterTimeoutSeconds);
Rebecca Silbersteine78af402014-10-02 10:55:04 -0700824}
825
Eric Carusoc93a15c2017-04-24 16:15:12 -0700826string LogTool::GetLog(const string& name) {
Elly Jones533c7c42012-08-10 15:07:05 -0400827 string result;
Tom Hughesd6c2d392020-08-24 18:12:11 -0700828 GetNamedLogFrom(name, kCommandLogs, &result) ||
Arowa Suliman3bc729c2021-03-09 13:25:00 -0800829 GetNamedLogFrom(name, kCommandLogsShort, &result) ||
Tom Hughesd6c2d392020-08-24 18:12:11 -0700830 GetNamedLogFrom(name, kExtraLogs, &result) ||
831 GetNamedLogFrom(name, kFeedbackLogs, &result);
Elly Jones533c7c42012-08-10 15:07:05 -0400832 return result;
833}
834
Eric Carusof9091f82017-04-28 14:18:59 -0700835LogTool::LogMap LogTool::GetAllLogs() {
Fletcher Woodruff70f27232019-01-24 11:41:34 -0700836 CreateConnectivityReport(false);
Elly Jones533c7c42012-08-10 15:07:05 -0400837 LogMap result;
Arowa Suliman3bc729c2021-03-09 13:25:00 -0800838 GetLogsFrom(kCommandLogsShort, &result);
Ben Chancf7d6412017-08-10 22:30:09 -0700839 GetLogsFrom(kCommandLogs, &result);
840 GetLogsFrom(kExtraLogs, &result);
Kartik Hegde1c4b97b2018-09-09 19:09:34 -0600841 GetLsbReleaseInfo(&result);
842 GetOsReleaseInfo(&result);
Elly Jones533c7c42012-08-10 15:07:05 -0400843 return result;
844}
845
Brian Norrisca4fc042018-04-03 00:24:26 -0700846LogTool::LogMap LogTool::GetAllDebugLogs() {
Fletcher Woodruff70f27232019-01-24 11:41:34 -0700847 CreateConnectivityReport(true);
Brian Norrisca4fc042018-04-03 00:24:26 -0700848 LogMap result;
Arowa Suliman3bc729c2021-03-09 13:25:00 -0800849 GetLogsFrom(kCommandLogsShort, &result);
Brian Norrisca4fc042018-04-03 00:24:26 -0700850 GetLogsFrom(kCommandLogs, &result);
851 GetLogsFrom(kExtraLogs, &result);
mhasankd2b84882020-05-04 17:02:19 -0700852 result[arc_bug_report_log_->GetName()] = GetArcBugReport("", nullptr);
Kartik Hegde1c4b97b2018-09-09 19:09:34 -0600853 GetLsbReleaseInfo(&result);
854 GetOsReleaseInfo(&result);
Brian Norrisca4fc042018-04-03 00:24:26 -0700855 return result;
856}
857
mhasank4f599d32020-04-09 22:07:35 -0700858void LogTool::GetBigFeedbackLogs(const base::ScopedFD& fd,
859 const std::string& username) {
Fletcher Woodruff70f27232019-01-24 11:41:34 -0700860 CreateConnectivityReport(true);
Chinglin Yuaeb4ec72018-12-10 18:53:30 +0800861 LogMap map;
862 GetPerfData(&map);
hscham2311cc22020-10-28 12:13:10 +0900863 base::Value dictionary(base::Value::Type::DICTIONARY);
Arowa Suliman3bc729c2021-03-09 13:25:00 -0800864 GetLogsInDictionary(kCommandLogsVerbose, &dictionary);
Jeffrey Kardatzkee3ec6fd2019-08-05 12:25:17 -0700865 GetLogsInDictionary(kCommandLogs, &dictionary);
866 GetLogsInDictionary(kFeedbackLogs, &dictionary);
mhasankd2b84882020-05-04 17:02:19 -0700867 bool is_backup;
868 std::string arc_bug_report = GetArcBugReport(username, &is_backup);
hscham2311cc22020-10-28 12:13:10 +0900869 dictionary.SetStringKey(kArcBugReportBackupKey,
870 (is_backup ? "true" : "false"));
871 dictionary.SetStringKey(arc_bug_report_log_->GetName(), arc_bug_report);
Kartik Hegde1c4b97b2018-09-09 19:09:34 -0600872 GetLsbReleaseInfo(&map);
873 GetOsReleaseInfo(&map);
874 PopulateDictionaryValue(map, &dictionary);
Ahmed Fakhry21140cf2016-03-04 17:15:19 -0800875 SerializeLogsAsJSON(dictionary, fd);
876}
877
mhasank4f599d32020-04-09 22:07:35 -0700878std::string GetSanitizedUsername(
879 org::chromium::CryptohomeInterfaceProxyInterface* cryptohome_proxy,
mhasankaf5251d2020-04-29 18:53:03 -0700880 const std::string& username) {
mhasank4f599d32020-04-09 22:07:35 -0700881 if (username.empty()) {
882 return std::string();
883 }
884
885 std::string sanitized_username;
886 brillo::ErrorPtr error;
887 if (!cryptohome_proxy->GetSanitizedUsername(username, &sanitized_username,
888 &error)) {
889 LOG(ERROR) << "Failed to call GetSanitizedUsername, error: "
890 << error->GetMessage();
891 return std::string();
892 }
893
894 return sanitized_username;
895}
896
mhasankd2b84882020-05-04 17:02:19 -0700897std::string LogTool::GetArcBugReport(const std::string& username,
898 bool* is_backup) {
899 if (is_backup) {
900 *is_backup = true;
901 }
mhasank4f599d32020-04-09 22:07:35 -0700902 std::string userhash =
903 GetSanitizedUsername(cryptohome_proxy_.get(), username);
904
905 std::string contents;
906 if (userhash.empty() ||
907 arc_bug_report_backups_.find(userhash) == arc_bug_report_backups_.end() ||
Ereth McKnight-MacNeilca3fff22021-01-21 23:31:40 -0800908 !base::ReadFileToString(daemon_store_base_dir_.Append(userhash).Append(
909 kArcBugReportBackupFileName),
mhasank4f599d32020-04-09 22:07:35 -0700910 &contents)) {
911 // If |userhash| was not empty, but was not found in the backup set
912 // or the file did not exist, attempt to delete the file.
913 if (!userhash.empty()) {
mhasank40a80482020-09-09 17:44:24 -0700914 DeleteArcBugReportBackup(username);
mhasank4f599d32020-04-09 22:07:35 -0700915 }
mhasankd2b84882020-05-04 17:02:19 -0700916 if (is_backup) {
917 *is_backup = false;
918 }
mhasankaf5251d2020-04-29 18:53:03 -0700919 contents = arc_bug_report_log_->GetLogData();
mhasank4f599d32020-04-09 22:07:35 -0700920 }
921
922 return contents;
923}
924
mhasank7186aae2020-09-16 20:06:00 -0700925void LogTool::BackupArcBugReport(const std::string& username) {
mhasank80cbe4d2020-04-02 22:46:08 -0700926 DLOG(INFO) << "Backing up ARC bug report";
927
mhasank40a80482020-09-09 17:44:24 -0700928 const std::string userhash =
mhasank7186aae2020-09-16 20:06:00 -0700929 GetSanitizedUsername(cryptohome_proxy_.get(), username);
930 if (!IsUserHashValid(userhash)) {
931 LOG(ERROR) << "Invalid userhash '" << userhash << "'";
932 return;
933 }
mhasank40a80482020-09-09 17:44:24 -0700934
Ereth McKnight-MacNeilca3fff22021-01-21 23:31:40 -0800935 brillo::SafeFD backupDir(
936 brillo::SafeFD::Root()
937 .first.OpenExistingDir(daemon_store_base_dir_.Append(userhash))
938 .first);
939 if (!backupDir.is_valid()) {
940 LOG(ERROR) << "Failed to open ARC bug report backup dir at "
941 << daemon_store_base_dir_.Append(userhash).value();
942 return;
943 }
944
945 brillo::SafeFD backupFile(
946 brillo::OpenOrRemakeFile(&backupDir, kArcBugReportBackupFileName).first);
947 if (!backupFile.is_valid()) {
948 LOG(ERROR) << "Failed to open ARC bug report file at "
949 << daemon_store_base_dir_.Append(userhash)
950 .Append(kArcBugReportBackupFileName)
951 .value();
952 return;
953 }
954
mhasankaf5251d2020-04-29 18:53:03 -0700955 const std::string logData = arc_bug_report_log_->GetLogData();
Ereth McKnight-MacNeilca3fff22021-01-21 23:31:40 -0800956
957 if (backupFile.Write(logData.c_str(), logData.length()) ==
958 brillo::SafeFD::Error::kNoError) {
mhasank4f599d32020-04-09 22:07:35 -0700959 arc_bug_report_backups_.insert(userhash);
960 } else {
mhasank7186aae2020-09-16 20:06:00 -0700961 PLOG(ERROR) << "Failed to back up ARC bug report";
mhasank80cbe4d2020-04-02 22:46:08 -0700962 }
963}
964
mhasank7186aae2020-09-16 20:06:00 -0700965void LogTool::DeleteArcBugReportBackup(const std::string& username) {
mhasank80cbe4d2020-04-02 22:46:08 -0700966 DLOG(INFO) << "Deleting the ARC bug report backup";
967
mhasank40a80482020-09-09 17:44:24 -0700968 const std::string userhash =
mhasank7186aae2020-09-16 20:06:00 -0700969 GetSanitizedUsername(cryptohome_proxy_.get(), username);
970 if (!IsUserHashValid(userhash)) {
971 LOG(ERROR) << "Invalid userhash '" << userhash << "'";
972 return;
973 }
mhasank40a80482020-09-09 17:44:24 -0700974
Ereth McKnight-MacNeilca3fff22021-01-21 23:31:40 -0800975 brillo::SafeFD backupDir(
976 brillo::SafeFD::Root()
977 .first.OpenExistingDir(daemon_store_base_dir_.Append(userhash))
978 .first);
979 if (!backupDir.is_valid()) {
980 LOG(ERROR) << "Failed to open ARC bug report backup dir at "
981 << daemon_store_base_dir_.Append(userhash).value();
982 return;
983 }
984
mhasank4f599d32020-04-09 22:07:35 -0700985 arc_bug_report_backups_.erase(userhash);
Ereth McKnight-MacNeilca3fff22021-01-21 23:31:40 -0800986
987 if (backupDir.Unlink(kArcBugReportBackupFileName) !=
988 brillo::SafeFD::Error::kNoError) {
989 PLOG(ERROR) << "Failed to delete ARC bug report backup at "
990 << daemon_store_base_dir_.Append(userhash)
991 .Append(kArcBugReportBackupFileName)
992 .value();
mhasank80cbe4d2020-04-02 22:46:08 -0700993 }
994}
995
Jeffrey Kardatzkee3ec6fd2019-08-05 12:25:17 -0700996void LogTool::GetJournalLog(const base::ScopedFD& fd) {
Chris Morin790fd262019-04-03 20:29:36 -0700997 Log journal(kCommand, "journal.export", "journalctl -n 10000 -o export",
998 "syslog", "syslog", 10 * 1024 * 1024, LogTool::Encoding::kBinary);
Jeffrey Kardatzkee3ec6fd2019-08-05 12:25:17 -0700999 std::string output = journal.GetLogData();
Chris Morin790fd262019-04-03 20:29:36 -07001000 base::WriteFileDescriptor(fd.get(), output.data(), output.size());
1001}
1002
Luis Hector Chavezfc2566f2018-09-13 15:00:36 -07001003// static
Tom Hughesd6c2d392020-08-24 18:12:11 -07001004string LogTool::EncodeString(string value, LogTool::Encoding source_encoding) {
Chris Morin790fd262019-04-03 20:29:36 -07001005 if (source_encoding == LogTool::Encoding::kBinary)
1006 return value;
1007
Luis Hector Chavezfc2566f2018-09-13 15:00:36 -07001008 if (source_encoding == LogTool::Encoding::kAutodetect) {
1009 if (base::IsStringUTF8(value))
1010 return value;
Chris Morin790fd262019-04-03 20:29:36 -07001011 source_encoding = LogTool::Encoding::kBase64;
Luis Hector Chavezfc2566f2018-09-13 15:00:36 -07001012 }
1013
1014 if (source_encoding == LogTool::Encoding::kUtf8) {
1015 string output;
1016 const char* src = value.data();
1017 int32_t src_len = static_cast<int32_t>(value.length());
1018
1019 output.reserve(value.size());
1020 for (int32_t char_index = 0; char_index < src_len; char_index++) {
1021 uint32_t code_point;
1022 if (!base::ReadUnicodeCharacter(src, src_len, &char_index, &code_point) ||
1023 !base::IsValidCharacter(code_point)) {
1024 // Replace invalid characters with U+FFFD REPLACEMENT CHARACTER.
1025 code_point = 0xFFFD;
1026 }
1027 base::WriteUnicodeCharacter(code_point, &output);
1028 }
1029 return output;
Luis Hector Chavezfc2566f2018-09-13 15:00:36 -07001030 }
Chris Morin853d3442019-04-01 21:35:13 -07001031
1032 base::Base64Encode(value, &value);
1033 return "<base64>: " + value;
Luis Hector Chavezfc2566f2018-09-13 15:00:36 -07001034}
1035
Ben Chana0011d82014-05-13 00:19:29 -07001036} // namespace debugd