blob: dc15c3d46af38e2a23eaf384d7a728a49c3fbc89 [file] [log] [blame]
/*
* Copyright (C) 2018 The Android Open Source Project
*
* Licensed under the Apache License, Version 2.0 (the "License");
* you may not use this file except in compliance with the License.
* You may obtain a copy of the License at
*
* http://www.apache.org/licenses/LICENSE-2.0
*
* Unless required by applicable law or agreed to in writing, software
* distributed under the License is distributed on an "AS IS" BASIS,
* WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
* See the License for the specific language governing permissions and
* limitations under the License.
*/
#include <aio.h>
#include <fcntl.h>
#include <inttypes.h>
#include <sys/stat.h>
#include <unistd.h>
#include <functional>
#include <iostream>
#include "perfetto/base/build_config.h"
#include "perfetto/base/logging.h"
#include "perfetto/base/scoped_file.h"
#include "perfetto/base/time.h"
#include "perfetto/trace_processor/trace_processor.h"
#include "perfetto/trace_processor/raw_query.pb.h"
#if PERFETTO_BUILDFLAG(PERFETTO_OS_LINUX) || \
PERFETTO_BUILDFLAG(PERFETTO_OS_ANDROID) || \
PERFETTO_BUILDFLAG(PERFETTO_OS_MACOSX)
#define PERFETTO_HAS_SIGNAL_H() 1
#else
#define PERFETTO_HAS_SIGNAL_H() 0
#endif
#if PERFETTO_BUILDFLAG(PERFETTO_STANDALONE_BUILD)
#include <linenoise.h>
#include <pwd.h>
#include <sys/types.h>
#endif
#if PERFETTO_HAS_SIGNAL_H()
#include <signal.h>
#endif
namespace perfetto {
namespace trace_processor {
namespace {
TraceProcessor* g_tp;
#if PERFETTO_BUILDFLAG(PERFETTO_STANDALONE_BUILD)
bool EnsureDir(const std::string& path) {
return mkdir(path.c_str(), 0755) != -1 || errno == EEXIST;
}
bool EnsureFile(const std::string& path) {
return base::OpenFile(path, O_RDONLY | O_CREAT, 0644).get() != -1;
}
std::string GetConfigPath() {
const char* homedir = getenv("HOME");
if (homedir == nullptr)
homedir = getpwuid(getuid())->pw_dir;
if (homedir == nullptr)
return "";
return std::string(homedir) + "/.config";
}
std::string GetPerfettoPath() {
std::string config = GetConfigPath();
if (config == "")
return "";
return config + "/perfetto";
}
std::string GetHistoryPath() {
std::string perfetto = GetPerfettoPath();
if (perfetto == "")
return "";
return perfetto + "/.trace_processor_shell_history";
}
void SetupLineEditor() {
linenoiseSetMultiLine(true);
linenoiseHistorySetMaxLen(1000);
bool success = GetHistoryPath() != "";
success = success && EnsureDir(GetConfigPath());
success = success && EnsureDir(GetPerfettoPath());
success = success && EnsureFile(GetHistoryPath());
success = success && linenoiseHistoryLoad(GetHistoryPath().c_str()) != -1;
if (!success) {
PERFETTO_PLOG("Could not load history from %s", GetHistoryPath().c_str());
}
}
void FreeLine(char* line) {
linenoiseHistoryAdd(line);
linenoiseHistorySave(GetHistoryPath().c_str());
linenoiseFree(line);
}
char* GetLine(const char* prompt) {
return linenoise(prompt);
}
#else
void SetupLineEditor() {}
void FreeLine(char* line) {
delete[] line;
}
char* GetLine(const char* prompt) {
printf("\r%80s\r%s", "", prompt);
fflush(stdout);
char* line = new char[1024];
if (!fgets(line, 1024 - 1, stdin)) {
FreeLine(line);
return nullptr;
}
if (strlen(line) > 0)
line[strlen(line) - 1] = 0;
return line;
}
#endif
int ExportTraceToDatabase(const std::string& output_name) {
PERFETTO_CHECK(output_name.find("'") == std::string::npos);
{
base::ScopedFile fd(base::OpenFile(output_name, O_CREAT | O_RDWR, 0600));
if (!fd) {
PERFETTO_PLOG("Failed to create file: %s", output_name.c_str());
return 1;
}
int res = ftruncate(fd.get(), 0);
PERFETTO_CHECK(res == 0);
}
// TODO(skyostil): Use synchronous queries.
std::string attach_sql =
"ATTACH DATABASE '" + output_name + "' AS perfetto_export";
protos::RawQueryArgs attach_query;
attach_query.set_sql_query(attach_sql);
g_tp->ExecuteQuery(
attach_query, [](const protos::RawQueryResult& attach_res) {
if (attach_res.has_error()) {
PERFETTO_ELOG("SQLite error: %s", attach_res.error().c_str());
return;
}
protos::RawQueryArgs list_query;
// Find all the virtual tables we have created internally as well as
// actual tables registered through SQL.
list_query.set_sql_query(
"SELECT name FROM perfetto_tables UNION "
"SELECT name FROM sqlite_master WHERE type='table'");
g_tp->ExecuteQuery(list_query, [](const protos::RawQueryResult& res) {
if (res.has_error()) {
PERFETTO_ELOG("SQLite error: %s", res.error().c_str());
return;
}
PERFETTO_CHECK(res.columns_size() == 1);
for (int r = 0; r < static_cast<int>(res.num_records()); r++) {
std::string table_name = res.columns(0).string_values(r);
PERFETTO_CHECK(table_name.find("'") == std::string::npos);
std::string export_sql = "CREATE TABLE perfetto_export." +
table_name + " AS SELECT * FROM " +
table_name;
protos::RawQueryArgs export_query;
export_query.set_sql_query(export_sql);
g_tp->ExecuteQuery(export_query,
[](const protos::RawQueryResult& export_res) {
if (export_res.has_error())
PERFETTO_ELOG("SQLite error: %s",
export_res.error().c_str());
});
}
});
});
protos::RawQueryArgs detach_query;
detach_query.set_sql_query("DETACH DATABASE perfetto_export");
g_tp->ExecuteQuery(detach_query, [](const protos::RawQueryResult& res) {
if (res.has_error())
PERFETTO_ELOG("SQLite error: %s", res.error().c_str());
});
return 0;
}
void PrintQueryResultInteractively(base::TimeNanos t_start,
const protos::RawQueryResult& res) {
if (res.has_error()) {
PERFETTO_ELOG("SQLite error: %s", res.error().c_str());
return;
}
PERFETTO_CHECK(res.columns_size() == res.column_descriptors_size());
base::TimeNanos t_end = base::GetWallTimeNs();
for (int r = 0; r < static_cast<int>(res.num_records()); r++) {
if (r % 32 == 0) {
if (r > 0) {
fprintf(stderr, "...\nType 'q' to stop, Enter for more records: ");
fflush(stderr);
char input[32];
if (!fgets(input, sizeof(input) - 1, stdin))
exit(0);
if (input[0] == 'q')
break;
}
for (const auto& col : res.column_descriptors())
printf("%20s ", col.name().c_str());
printf("\n");
for (int i = 0; i < res.columns_size(); i++)
printf("%20s ", "--------------------");
printf("\n");
}
using ColumnDesc = protos::RawQueryResult::ColumnDesc;
for (int c = 0; c < res.columns_size(); c++) {
if (res.columns(c).is_nulls(r)) {
printf("%-20.20s", "[NULL]");
} else {
switch (res.column_descriptors(c).type()) {
case ColumnDesc::STRING:
printf("%-20.20s", res.columns(c).string_values(r).c_str());
break;
case ColumnDesc::DOUBLE:
printf("%20f", res.columns(c).double_values(r));
break;
case ColumnDesc::LONG: {
auto value = res.columns(c).long_values(r);
printf("%20lld", value);
break;
}
case ColumnDesc::UNKNOWN:
PERFETTO_FATAL("Row should be null so handled above");
break;
}
}
printf(" ");
}
printf("\n");
}
printf("\nQuery executed in %.3f ms\n\n", (t_end - t_start).count() / 1E6);
}
void PrintShellUsage() {
PERFETTO_ELOG(
"Available commands:\n"
".quit, .q Exit the shell.\n"
".help This text.\n"
".dump FILE Export the trace as a sqlite database.\n");
}
int StartInteractiveShell() {
SetupLineEditor();
for (;;) {
char* line = GetLine("> ");
if (!line)
break;
if (strcmp(line, "") == 0)
continue;
if (line[0] == '.') {
char command[32] = {};
char arg[1024] = {};
sscanf(line + 1, "%31s %1023s", command, arg);
if (strcmp(command, "quit") == 0 || strcmp(command, "q") == 0) {
break;
} else if (strcmp(command, "help") == 0) {
PrintShellUsage();
} else if (strcmp(command, "dump") == 0 && strlen(arg)) {
if (ExportTraceToDatabase(arg) != 0)
PERFETTO_ELOG("Database export failed");
} else {
PrintShellUsage();
}
continue;
}
protos::RawQueryArgs query;
query.set_sql_query(line);
base::TimeNanos t_start = base::GetWallTimeNs();
g_tp->ExecuteQuery(query, [t_start](const protos::RawQueryResult& res) {
PrintQueryResultInteractively(t_start, res);
});
FreeLine(line);
}
return 0;
}
void PrintQueryResultAsCsv(const protos::RawQueryResult& res, FILE* output) {
PERFETTO_CHECK(res.columns_size() == res.column_descriptors_size());
for (int r = 0; r < static_cast<int>(res.num_records()); r++) {
if (r == 0) {
for (int c = 0; c < res.column_descriptors_size(); c++) {
const auto& col = res.column_descriptors(c);
if (c > 0)
fprintf(output, ",");
fprintf(output, "\"%s\"", col.name().c_str());
}
fprintf(output, "\n");
}
for (int c = 0; c < res.columns_size(); c++) {
if (c > 0)
fprintf(output, ",");
using ColumnDesc = protos::RawQueryResult::ColumnDesc;
if (res.columns(c).is_nulls(r)) {
fprintf(output, "\"%s\"", "[NULL]");
} else {
switch (res.column_descriptors(c).type()) {
case ColumnDesc::STRING:
fprintf(output, "\"%s\"", res.columns(c).string_values(r).c_str());
break;
case ColumnDesc::DOUBLE:
fprintf(output, "%f", res.columns(c).double_values(r));
break;
case ColumnDesc::LONG: {
auto value = res.columns(c).long_values(r);
fprintf(output, "%lld", value);
break;
}
case ColumnDesc::UNKNOWN:
PERFETTO_FATAL("Row should be null so handled above");
break;
}
}
}
fprintf(output, "\n");
}
}
int RunQueryAndPrintResult(FILE* input, FILE* output) {
char buffer[4096];
bool is_first_query = true;
bool is_query_error = false;
bool has_output_printed = false;
while (!feof(input) && !ferror(input) && !is_query_error) {
// Add an extra newline separator between query results.
if (!is_first_query)
fprintf(output, "\n");
is_first_query = false;
std::string sql_query;
while (fgets(buffer, sizeof(buffer), input)) {
if (strncmp(buffer, "\n", sizeof(buffer)) == 0)
break;
sql_query.append(buffer);
}
if (sql_query.back() == '\n')
sql_query.resize(sql_query.size() - 1);
// If we have a new line at the end of the file or an extra new line
// somewhere in the file, we'll end up with an empty query which we should
// just ignore.
if (sql_query.empty())
continue;
PERFETTO_ILOG("Executing query: %s", sql_query.c_str());
protos::RawQueryArgs query;
query.set_sql_query(sql_query);
g_tp->ExecuteQuery(query, [output, &is_query_error, &has_output_printed](
const protos::RawQueryResult& res) {
if (res.has_error()) {
PERFETTO_ELOG("SQLite error: %s", res.error().c_str());
is_query_error = true;
return;
} else if (res.num_records() != 0) {
if (has_output_printed) {
PERFETTO_ELOG(
"More than one query generated result rows. This is "
"unsupported.");
is_query_error = true;
return;
}
has_output_printed = true;
}
PrintQueryResultAsCsv(res, output);
});
}
if (ferror(input)) {
PERFETTO_ELOG("Error reading query file");
}
return ferror(input) || is_query_error ? 1 : 0;
}
void PrintUsage(char** argv) {
PERFETTO_ELOG(
"Interactive trace processor shell.\n"
"Usage: %s [OPTIONS] trace_file.pb\n\n"
"Options:\n"
" -d Enable virtual table debugging.\n"
" -q FILE Read and execute an SQL query from a file.\n"
" -e FILE Export the trace into a SQLite database.\n",
argv[0]);
}
int TraceProcessorMain(int argc, char** argv) {
if (argc < 2) {
PrintUsage(argv);
return 1;
}
const char* trace_file_path = nullptr;
const char* query_file_path = nullptr;
const char* sqlite_file_path = nullptr;
for (int i = 1; i < argc; i++) {
if (strcmp(argv[i], "-d") == 0) {
EnableSQLiteVtableDebugging();
continue;
}
if (strcmp(argv[i], "-q") == 0) {
if (++i == argc) {
PrintUsage(argv);
return 1;
}
query_file_path = argv[i];
continue;
} else if (strcmp(argv[i], "-e") == 0) {
if (++i == argc) {
PrintUsage(argv);
return 1;
}
sqlite_file_path = argv[i];
continue;
} else if (strcmp(argv[i], "-h") == 0 || strcmp(argv[i], "--help") == 0) {
PrintUsage(argv);
return 0;
} else if (argv[i][0] == '-') {
PERFETTO_ELOG("Unknown option: %s", argv[i]);
return 1;
}
trace_file_path = argv[i];
}
if (trace_file_path == nullptr) {
PrintUsage(argv);
return 1;
}
// Load the trace file into the trace processor.
Config config;
config.optimization_mode = OptimizationMode::kMaxBandwidth;
std::unique_ptr<TraceProcessor> tp = TraceProcessor::CreateInstance(config);
base::ScopedFile fd(base::OpenFile(trace_file_path, O_RDONLY));
if (!fd) {
PERFETTO_ELOG("Could not open trace file (path: %s)", trace_file_path);
return 1;
}
// Load the trace in chunks using async IO. We create a simple pipeline where,
// at each iteration, we parse the current chunk and asynchronously start
// reading the next chunk.
// 1MB chunk size seems the best tradeoff on a MacBook Pro 2013 - i7 2.8 GHz.
constexpr size_t kChunkSize = 1024 * 1024;
struct aiocb cb {};
cb.aio_nbytes = kChunkSize;
cb.aio_fildes = *fd;
std::unique_ptr<uint8_t[]> aio_buf(new uint8_t[kChunkSize]);
cb.aio_buf = aio_buf.get();
PERFETTO_CHECK(aio_read(&cb) == 0);
struct aiocb* aio_list[1] = {&cb};
uint64_t file_size = 0;
auto t_load_start = base::GetWallTimeMs();
for (int i = 0;; i++) {
if (i % 128 == 0)
fprintf(stderr, "\rLoading trace: %.2f MB\r", file_size / 1E6);
// Block waiting for the pending read to complete.
PERFETTO_CHECK(aio_suspend(aio_list, 1, nullptr) == 0);
auto rsize = aio_return(&cb);
if (rsize <= 0)
break;
file_size += static_cast<uint64_t>(rsize);
// Take ownership of the completed buffer and enqueue a new async read
// with a fresh buffer.
std::unique_ptr<uint8_t[]> buf(std::move(aio_buf));
aio_buf.reset(new uint8_t[kChunkSize]);
cb.aio_buf = aio_buf.get();
cb.aio_offset += rsize;
PERFETTO_CHECK(aio_read(&cb) == 0);
// Parse the completed buffer while the async read is in-flight.
tp->Parse(std::move(buf), static_cast<size_t>(rsize));
}
tp->NotifyEndOfFile();
double t_load = (base::GetWallTimeMs() - t_load_start).count() / 1E3;
double size_mb = file_size / 1E6;
PERFETTO_ILOG("Trace loaded: %.2f MB (%.1f MB/s)", size_mb, size_mb / t_load);
g_tp = tp.get();
#if PERFETTO_HAS_SIGNAL_H()
signal(SIGINT, [](int) { g_tp->InterruptQuery(); });
#endif
int ret = 0;
// If we were given a query file, first load and execute it.
if (query_file_path) {
base::ScopedFstream file(fopen(query_file_path, "r"));
if (!file) {
PERFETTO_ELOG("Could not open query file (path: %s)", query_file_path);
return 1;
}
ret = RunQueryAndPrintResult(file.get(), stdout);
}
// After this we can dump the database and exit if needed.
if (ret == 0 && sqlite_file_path) {
return ExportTraceToDatabase(sqlite_file_path);
}
// If we ran an automated query, exit.
if (query_file_path) {
return ret;
}
// Otherwise start an interactive shell.
return StartInteractiveShell();
}
} // namespace
} // namespace trace_processor
} // namespace perfetto
int main(int argc, char** argv) {
return perfetto::trace_processor::TraceProcessorMain(argc, argv);
}