Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 1 | /* |
| 2 | * Copyright (C) 2018 The Android Open Source Project |
| 3 | * |
| 4 | * Licensed under the Apache License, Version 2.0 (the "License"); |
| 5 | * you may not use this file except in compliance with the License. |
| 6 | * You may obtain a copy of the License at |
| 7 | * |
| 8 | * http://www.apache.org/licenses/LICENSE-2.0 |
| 9 | * |
| 10 | * Unless required by applicable law or agreed to in writing, software |
| 11 | * distributed under the License is distributed on an "AS IS" BASIS, |
| 12 | * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. |
| 13 | * See the License for the specific language governing permissions and |
| 14 | * limitations under the License. |
| 15 | */ |
| 16 | |
| 17 | #include "src/trace_processor/proto_trace_tokenizer.h" |
| 18 | |
| 19 | #include <string> |
| 20 | |
Hector Dearman | 33610d2 | 2019-08-07 13:00:20 +0100 | [diff] [blame] | 21 | #include <zlib.h> |
| 22 | |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 23 | #include "perfetto/base/logging.h" |
Hector Dearman | 33610d2 | 2019-08-07 13:00:20 +0100 | [diff] [blame] | 24 | #include "perfetto/ext/base/optional.h" |
Primiano Tucci | 2c5488f | 2019-06-01 03:27:28 +0100 | [diff] [blame] | 25 | #include "perfetto/ext/base/utils.h" |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 26 | #include "perfetto/protozero/proto_decoder.h" |
| 27 | #include "perfetto/protozero/proto_utils.h" |
Primiano Tucci | 6756fb0 | 2019-08-14 15:49:18 +0200 | [diff] [blame^] | 28 | #include "src/trace_processor/clock_tracker.h" |
Isabelle Taylor | a97c5f5 | 2018-10-23 17:36:12 +0100 | [diff] [blame] | 29 | #include "src/trace_processor/event_tracker.h" |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 30 | #include "src/trace_processor/process_tracker.h" |
Primiano Tucci | 0e38a14 | 2019-01-07 20:51:09 +0000 | [diff] [blame] | 31 | #include "src/trace_processor/stats.h" |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 32 | #include "src/trace_processor/trace_blob_view.h" |
| 33 | #include "src/trace_processor/trace_sorter.h" |
Primiano Tucci | 0e38a14 | 2019-01-07 20:51:09 +0000 | [diff] [blame] | 34 | #include "src/trace_processor/trace_storage.h" |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 35 | |
Lalit Maganti | 295a861 | 2019-05-21 13:57:42 +0100 | [diff] [blame] | 36 | #include "perfetto/config/trace_config.pbzero.h" |
Primiano Tucci | 6756fb0 | 2019-08-14 15:49:18 +0200 | [diff] [blame^] | 37 | #include "perfetto/trace/clock_snapshot.pbzero.h" |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 38 | #include "perfetto/trace/ftrace/ftrace_event.pbzero.h" |
| 39 | #include "perfetto/trace/ftrace/ftrace_event_bundle.pbzero.h" |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 40 | #include "perfetto/trace/interned_data/interned_data.pbzero.h" |
Florian Mayer | 5716fc1 | 2019-06-24 11:50:51 -0700 | [diff] [blame] | 41 | #include "perfetto/trace/profiling/profile_common.pbzero.h" |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 42 | #include "perfetto/trace/trace.pbzero.h" |
Nicolò Mazzucato | 85940b7 | 2019-07-18 10:32:39 +0100 | [diff] [blame] | 43 | #include "perfetto/trace/track_event/source_location.pbzero.h" |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 44 | #include "perfetto/trace/track_event/task_execution.pbzero.h" |
| 45 | #include "perfetto/trace/track_event/thread_descriptor.pbzero.h" |
| 46 | #include "perfetto/trace/track_event/track_event.pbzero.h" |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 47 | |
| 48 | namespace perfetto { |
| 49 | namespace trace_processor { |
| 50 | |
| 51 | using protozero::ProtoDecoder; |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 52 | using protozero::proto_utils::MakeTagLengthDelimited; |
| 53 | using protozero::proto_utils::MakeTagVarInt; |
| 54 | using protozero::proto_utils::ParseVarInt; |
| 55 | |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 56 | namespace { |
| 57 | |
Hector Dearman | 33610d2 | 2019-08-07 13:00:20 +0100 | [diff] [blame] | 58 | constexpr uint8_t kTracePacketTag = |
| 59 | MakeTagLengthDelimited(protos::pbzero::Trace::kPacketFieldNumber); |
| 60 | |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 61 | template <typename MessageType> |
| 62 | void InternMessage(TraceProcessorContext* context, |
| 63 | ProtoIncrementalState::PacketSequenceState* state, |
| 64 | TraceBlobView message) { |
| 65 | constexpr auto kIidFieldNumber = MessageType::kIidFieldNumber; |
| 66 | |
Florian Mayer | 5716fc1 | 2019-06-24 11:50:51 -0700 | [diff] [blame] | 67 | uint64_t iid = 0; |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 68 | auto message_start = message.data(); |
| 69 | auto message_size = message.length(); |
| 70 | protozero::ProtoDecoder decoder(message_start, message_size); |
| 71 | |
| 72 | auto field = decoder.FindField(kIidFieldNumber); |
| 73 | if (PERFETTO_UNLIKELY(!field)) { |
| 74 | PERFETTO_ELOG("Interned message without interning_id"); |
| 75 | context->storage->IncrementStats(stats::interned_data_tokenizer_errors); |
| 76 | return; |
| 77 | } |
Florian Mayer | 5716fc1 | 2019-06-24 11:50:51 -0700 | [diff] [blame] | 78 | iid = field.as_uint64(); |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 79 | |
| 80 | auto res = state->GetInternedDataMap<MessageType>()->emplace( |
| 81 | iid, |
Eric Seckler | 56a007d | 2019-05-02 16:25:14 +0100 | [diff] [blame] | 82 | ProtoIncrementalState::InternedDataView<MessageType>(std::move(message))); |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 83 | // If a message with this ID is already interned, its data should not have |
| 84 | // changed (this is forbidden by the InternedData proto). |
| 85 | // TODO(eseckler): This DCHECK assumes that the message is encoded the |
| 86 | // same way whenever it is re-emitted. |
| 87 | PERFETTO_DCHECK(res.second || |
| 88 | (res.first->second.message.length() == message_size && |
| 89 | memcmp(res.first->second.message.data(), message_start, |
| 90 | message_size) == 0)); |
| 91 | } |
| 92 | |
Hector Dearman | 33610d2 | 2019-08-07 13:00:20 +0100 | [diff] [blame] | 93 | TraceBlobView Decompress(TraceBlobView input) { |
| 94 | uint8_t out[4096]; |
| 95 | std::string s; |
| 96 | |
| 97 | z_stream stream{}; |
| 98 | stream.next_in = const_cast<uint8_t*>(input.data()); |
| 99 | stream.avail_in = static_cast<unsigned int>(input.length()); |
| 100 | |
| 101 | if (inflateInit(&stream) != Z_OK) |
| 102 | return TraceBlobView(nullptr, 0, 0); |
| 103 | |
| 104 | int ret; |
| 105 | do { |
| 106 | stream.next_out = out; |
| 107 | stream.avail_out = sizeof(out); |
| 108 | ret = inflate(&stream, Z_NO_FLUSH); |
| 109 | if (ret != Z_STREAM_END && ret != Z_OK) |
| 110 | return TraceBlobView(nullptr, 0, 0); |
| 111 | s.append(reinterpret_cast<char*>(out), sizeof(out) - stream.avail_out); |
| 112 | } while (ret != Z_STREAM_END); |
| 113 | inflateEnd(&stream); |
| 114 | |
| 115 | std::unique_ptr<uint8_t[]> output(new uint8_t[s.size()]); |
| 116 | memcpy(output.get(), s.data(), s.size()); |
| 117 | return TraceBlobView(std::move(output), 0, s.size()); |
| 118 | } |
| 119 | |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 120 | } // namespace |
| 121 | |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 122 | ProtoTraceTokenizer::ProtoTraceTokenizer(TraceProcessorContext* ctx) |
Deepanjan Roy | 01994ca | 2019-04-02 11:05:34 -0700 | [diff] [blame] | 123 | : context_(ctx) {} |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 124 | ProtoTraceTokenizer::~ProtoTraceTokenizer() = default; |
| 125 | |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 126 | util::Status ProtoTraceTokenizer::Parse(std::unique_ptr<uint8_t[]> owned_buf, |
| 127 | size_t size) { |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 128 | uint8_t* data = &owned_buf[0]; |
| 129 | if (!partial_buf_.empty()) { |
| 130 | // It takes ~5 bytes for a proto preamble + the varint size. |
| 131 | const size_t kHeaderBytes = 5; |
| 132 | if (PERFETTO_UNLIKELY(partial_buf_.size() < kHeaderBytes)) { |
| 133 | size_t missing_len = std::min(kHeaderBytes - partial_buf_.size(), size); |
| 134 | partial_buf_.insert(partial_buf_.end(), &data[0], &data[missing_len]); |
| 135 | if (partial_buf_.size() < kHeaderBytes) |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 136 | return util::OkStatus(); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 137 | data += missing_len; |
| 138 | size -= missing_len; |
| 139 | } |
| 140 | |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 141 | // At this point we have enough data in |partial_buf_| to read at least the |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 142 | // field header and know the size of the next TracePacket. |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 143 | const uint8_t* pos = &partial_buf_[0]; |
| 144 | uint8_t proto_field_tag = *pos; |
| 145 | uint64_t field_size = 0; |
| 146 | const uint8_t* next = ParseVarInt(++pos, &*partial_buf_.end(), &field_size); |
| 147 | bool parse_failed = next == pos; |
| 148 | pos = next; |
| 149 | if (proto_field_tag != kTracePacketTag || field_size == 0 || parse_failed) { |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 150 | return util::ErrStatus( |
| 151 | "Failed parsing a TracePacket from the partial buffer"); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 152 | } |
| 153 | |
| 154 | // At this point we know how big the TracePacket is. |
| 155 | size_t hdr_size = static_cast<size_t>(pos - &partial_buf_[0]); |
| 156 | size_t size_incl_header = static_cast<size_t>(field_size + hdr_size); |
| 157 | PERFETTO_DCHECK(size_incl_header > partial_buf_.size()); |
| 158 | |
| 159 | // There is a good chance that between the |partial_buf_| and the new |data| |
| 160 | // of the current call we have enough bytes to parse a TracePacket. |
| 161 | if (partial_buf_.size() + size >= size_incl_header) { |
| 162 | // Create a new buffer for the whole TracePacket and copy into that: |
| 163 | // 1) The beginning of the TracePacket (including the proto header) from |
| 164 | // the partial buffer. |
| 165 | // 2) The rest of the TracePacket from the current |data| buffer (note |
| 166 | // that we might have consumed already a few bytes form |data| earlier |
| 167 | // in this function, hence we need to keep |off| into account). |
| 168 | std::unique_ptr<uint8_t[]> buf(new uint8_t[size_incl_header]); |
| 169 | memcpy(&buf[0], partial_buf_.data(), partial_buf_.size()); |
| 170 | // |size_missing| is the number of bytes for the rest of the TracePacket |
| 171 | // in |data|. |
| 172 | size_t size_missing = size_incl_header - partial_buf_.size(); |
| 173 | memcpy(&buf[partial_buf_.size()], &data[0], size_missing); |
| 174 | data += size_missing; |
| 175 | size -= size_missing; |
| 176 | partial_buf_.clear(); |
| 177 | uint8_t* buf_start = &buf[0]; // Note that buf is std::moved below. |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 178 | util::Status status = |
| 179 | ParseInternal(std::move(buf), buf_start, size_incl_header); |
| 180 | if (PERFETTO_UNLIKELY(!status.ok())) |
| 181 | return status; |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 182 | } else { |
| 183 | partial_buf_.insert(partial_buf_.end(), data, &data[size]); |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 184 | return util::OkStatus(); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 185 | } |
| 186 | } |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 187 | return ParseInternal(std::move(owned_buf), data, size); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 188 | } |
| 189 | |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 190 | util::Status ProtoTraceTokenizer::ParseInternal( |
| 191 | std::unique_ptr<uint8_t[]> owned_buf, |
| 192 | uint8_t* data, |
| 193 | size_t size) { |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 194 | PERFETTO_DCHECK(data >= &owned_buf[0]); |
| 195 | const uint8_t* start = &owned_buf[0]; |
| 196 | const size_t data_off = static_cast<size_t>(data - start); |
| 197 | TraceBlobView whole_buf(std::move(owned_buf), data_off, size); |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 198 | |
| 199 | protos::pbzero::Trace::Decoder decoder(data, size); |
| 200 | for (auto it = decoder.packet(); it; ++it) { |
| 201 | size_t field_offset = whole_buf.offset_of(it->data()); |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 202 | util::Status status = |
| 203 | ParsePacket(whole_buf.slice(field_offset, it->size())); |
| 204 | if (PERFETTO_UNLIKELY(!status.ok())) |
| 205 | return status; |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 206 | } |
| 207 | |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 208 | const size_t bytes_left = decoder.bytes_left(); |
| 209 | if (bytes_left > 0) { |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 210 | PERFETTO_DCHECK(partial_buf_.empty()); |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 211 | partial_buf_.insert(partial_buf_.end(), &data[decoder.read_offset()], |
| 212 | &data[decoder.read_offset() + bytes_left]); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 213 | } |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 214 | return util::OkStatus(); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 215 | } |
| 216 | |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 217 | util::Status ProtoTraceTokenizer::ParsePacket(TraceBlobView packet) { |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 218 | protos::pbzero::TracePacket::Decoder decoder(packet.data(), packet.length()); |
Lalit Maganti | 4b2b253 | 2019-05-09 11:03:23 +0100 | [diff] [blame] | 219 | if (PERFETTO_UNLIKELY(decoder.bytes_left())) |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 220 | return util::ErrStatus( |
| 221 | "Failed to parse proto packet fully; the trace is probably corrupt."); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 222 | |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 223 | auto timestamp = decoder.has_timestamp() |
| 224 | ? static_cast<int64_t>(decoder.timestamp()) |
| 225 | : latest_timestamp_; |
Primiano Tucci | 6756fb0 | 2019-08-14 15:49:18 +0200 | [diff] [blame^] | 226 | |
| 227 | // If the TracePacket specifies a non-zero clock-id, translate the timestamp |
| 228 | // into the trace-time clock domain. |
| 229 | if (decoder.timestamp_clock_id()) { |
| 230 | PERFETTO_DCHECK(decoder.has_timestamp()); |
| 231 | ClockTracker::ClockId clock_id = decoder.timestamp_clock_id(); |
| 232 | const uint32_t seq_id = decoder.trusted_packet_sequence_id(); |
| 233 | bool is_seq_scoped = ClockTracker::IsReservedSeqScopedClockId(clock_id); |
| 234 | if (is_seq_scoped) { |
| 235 | if (!seq_id) { |
| 236 | return util::ErrStatus( |
| 237 | "TracePacket specified a sequence-local clock id (%" PRIu32 |
| 238 | ") but the TraceWriter's sequence_id is zero (the service is " |
| 239 | "probably too old)", |
| 240 | seq_id); |
| 241 | } |
| 242 | clock_id = ClockTracker::SeqScopedClockIdToGlobal( |
| 243 | seq_id, decoder.timestamp_clock_id()); |
| 244 | } |
| 245 | auto trace_ts = context_->clock_tracker->ToTraceTime(clock_id, timestamp); |
| 246 | if (!trace_ts.has_value()) { |
| 247 | // ToTraceTime() will increase the |clock_sync_failure| stat on failure. |
| 248 | static const char seq_extra_err[] = |
| 249 | " Because the clock id is sequence-scoped, the ClockSnapshot must be " |
| 250 | "emitted on the same TraceWriter sequence of the packet that refers " |
| 251 | "to that clock id."; |
| 252 | return util::ErrStatus( |
| 253 | "Failed to convert TracePacket's timestamp from clock_id=%" PRIu32 |
| 254 | " seq_id=%" PRIu32 |
| 255 | ". This is usually due to the lack of a prior ClockSnapshot proto.%s", |
| 256 | decoder.timestamp_clock_id(), seq_id, |
| 257 | is_seq_scoped ? seq_extra_err : ""); |
| 258 | } |
| 259 | timestamp = trace_ts.value(); |
| 260 | } |
Lalit Maganti | fa21a28 | 2019-01-17 19:03:04 +0000 | [diff] [blame] | 261 | latest_timestamp_ = std::max(timestamp, latest_timestamp_); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 262 | |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 263 | if (decoder.incremental_state_cleared()) { |
| 264 | HandleIncrementalStateCleared(decoder); |
| 265 | } else if (decoder.previous_packet_dropped()) { |
| 266 | HandlePreviousPacketDropped(decoder); |
| 267 | } |
| 268 | |
Primiano Tucci | 6756fb0 | 2019-08-14 15:49:18 +0200 | [diff] [blame^] | 269 | if (decoder.has_clock_snapshot()) { |
| 270 | return ParseClockSnapshot(decoder.clock_snapshot(), |
| 271 | decoder.trusted_packet_sequence_id()); |
| 272 | } |
| 273 | |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 274 | if (decoder.has_interned_data()) { |
| 275 | auto field = decoder.interned_data(); |
| 276 | const size_t offset = packet.offset_of(field.data); |
| 277 | ParseInternedData(decoder, packet.slice(offset, field.size)); |
| 278 | } |
| 279 | |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 280 | if (decoder.has_ftrace_events()) { |
| 281 | auto ftrace_field = decoder.ftrace_events(); |
| 282 | const size_t fld_off = packet.offset_of(ftrace_field.data); |
| 283 | ParseFtraceBundle(packet.slice(fld_off, ftrace_field.size)); |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 284 | return util::OkStatus(); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 285 | } |
| 286 | |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 287 | if (decoder.has_track_event()) { |
| 288 | ParseTrackEventPacket(decoder, std::move(packet)); |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 289 | return util::OkStatus(); |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 290 | } |
| 291 | |
| 292 | if (decoder.has_thread_descriptor()) { |
| 293 | ParseThreadDescriptorPacket(decoder); |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 294 | return util::OkStatus(); |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 295 | } |
| 296 | |
Hector Dearman | 33610d2 | 2019-08-07 13:00:20 +0100 | [diff] [blame] | 297 | if (decoder.has_compressed_packets()) { |
| 298 | protozero::ConstBytes field = decoder.compressed_packets(); |
| 299 | const size_t field_off = packet.offset_of(field.data); |
| 300 | TraceBlobView compressed_packets = packet.slice(field_off, field.size); |
| 301 | TraceBlobView packets = Decompress(std::move(compressed_packets)); |
| 302 | |
| 303 | const uint8_t* start = packets.data(); |
| 304 | const uint8_t* end = packets.data() + packets.length(); |
| 305 | const uint8_t* ptr = start; |
| 306 | while ((end - ptr) > 2) { |
| 307 | const uint8_t* packet_start = ptr; |
| 308 | if (PERFETTO_UNLIKELY(*ptr != kTracePacketTag)) |
| 309 | return util::ErrStatus("Expected TracePacket tag"); |
| 310 | uint64_t packet_size = 0; |
| 311 | ptr = ParseVarInt(++ptr, end, &packet_size); |
| 312 | size_t packet_offset = static_cast<size_t>(ptr - start); |
| 313 | ptr += packet_size; |
| 314 | if (PERFETTO_UNLIKELY((ptr - packet_start) < 2 || ptr > end)) |
| 315 | return util::ErrStatus("Invalid packet size"); |
| 316 | util::Status status = ParsePacket( |
| 317 | packets.slice(packet_offset, static_cast<size_t>(packet_size))); |
| 318 | if (PERFETTO_UNLIKELY(!status.ok())) |
| 319 | return status; |
| 320 | } |
| 321 | |
| 322 | return util::OkStatus(); |
| 323 | } |
| 324 | |
Lalit Maganti | 295a861 | 2019-05-21 13:57:42 +0100 | [diff] [blame] | 325 | if (decoder.has_trace_config()) { |
| 326 | auto config = decoder.trace_config(); |
| 327 | protos::pbzero::TraceConfig::Decoder trace_config(config.data, config.size); |
| 328 | |
| 329 | if (trace_config.write_into_file()) { |
| 330 | int64_t window_size_ns; |
| 331 | if (trace_config.has_flush_period_ms() && |
| 332 | trace_config.flush_period_ms() > 0) { |
| 333 | // We use 2x the flush period as a margin of error to allow for any |
| 334 | // late flush responses to still be sorted correctly. |
Lalit Maganti | b2c2f56 | 2019-05-22 18:53:46 +0100 | [diff] [blame] | 335 | window_size_ns = static_cast<int64_t>(trace_config.flush_period_ms()) * |
| 336 | 2 * 1000 * 1000; |
Lalit Maganti | 295a861 | 2019-05-21 13:57:42 +0100 | [diff] [blame] | 337 | } else { |
| 338 | constexpr uint64_t kDefaultWindowNs = |
| 339 | 180 * 1000 * 1000 * 1000ULL; // 3 minutes. |
| 340 | PERFETTO_ELOG( |
| 341 | "It is strongly recommended to have flush_period_ms set when " |
| 342 | "write_into_file is turned on. You will likely have many dropped " |
| 343 | "events because of inability to sort the events correctly."); |
| 344 | window_size_ns = static_cast<int64_t>(kDefaultWindowNs); |
| 345 | } |
| 346 | context_->sorter->SetWindowSizeNs(window_size_ns); |
| 347 | } |
| 348 | } |
| 349 | |
Florian Mayer | 5716fc1 | 2019-06-24 11:50:51 -0700 | [diff] [blame] | 350 | auto* state = GetIncrementalStateForPacketSequence( |
| 351 | decoder.trusted_packet_sequence_id()); |
| 352 | |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 353 | // Use parent data and length because we want to parse this again |
| 354 | // later to get the exact type of the packet. |
Florian Mayer | 5716fc1 | 2019-06-24 11:50:51 -0700 | [diff] [blame] | 355 | context_->sorter->PushTracePacket(timestamp, state, std::move(packet)); |
Lalit Maganti | 4b2b253 | 2019-05-09 11:03:23 +0100 | [diff] [blame] | 356 | |
Lalit Maganti | d71a945 | 2019-05-09 15:13:24 +0100 | [diff] [blame] | 357 | return util::OkStatus(); |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 358 | } |
| 359 | |
| 360 | void ProtoTraceTokenizer::HandleIncrementalStateCleared( |
| 361 | const protos::pbzero::TracePacket::Decoder& packet_decoder) { |
| 362 | if (PERFETTO_UNLIKELY(!packet_decoder.has_trusted_packet_sequence_id())) { |
| 363 | PERFETTO_ELOG( |
| 364 | "incremental_state_cleared without trusted_packet_sequence_id"); |
| 365 | context_->storage->IncrementStats(stats::interned_data_tokenizer_errors); |
| 366 | return; |
| 367 | } |
| 368 | GetIncrementalStateForPacketSequence( |
| 369 | packet_decoder.trusted_packet_sequence_id()) |
| 370 | ->OnIncrementalStateCleared(); |
| 371 | } |
| 372 | |
| 373 | void ProtoTraceTokenizer::HandlePreviousPacketDropped( |
| 374 | const protos::pbzero::TracePacket::Decoder& packet_decoder) { |
| 375 | if (PERFETTO_UNLIKELY(!packet_decoder.has_trusted_packet_sequence_id())) { |
| 376 | PERFETTO_ELOG("previous_packet_dropped without trusted_packet_sequence_id"); |
| 377 | context_->storage->IncrementStats(stats::interned_data_tokenizer_errors); |
| 378 | return; |
| 379 | } |
| 380 | GetIncrementalStateForPacketSequence( |
| 381 | packet_decoder.trusted_packet_sequence_id()) |
| 382 | ->OnPacketLoss(); |
| 383 | } |
| 384 | |
| 385 | void ProtoTraceTokenizer::ParseInternedData( |
| 386 | const protos::pbzero::TracePacket::Decoder& packet_decoder, |
| 387 | TraceBlobView interned_data) { |
| 388 | if (PERFETTO_UNLIKELY(!packet_decoder.has_trusted_packet_sequence_id())) { |
| 389 | PERFETTO_ELOG("InternedData packet without trusted_packet_sequence_id"); |
| 390 | context_->storage->IncrementStats(stats::interned_data_tokenizer_errors); |
| 391 | return; |
| 392 | } |
| 393 | |
| 394 | auto* state = GetIncrementalStateForPacketSequence( |
| 395 | packet_decoder.trusted_packet_sequence_id()); |
| 396 | |
| 397 | protos::pbzero::InternedData::Decoder interned_data_decoder( |
| 398 | interned_data.data(), interned_data.length()); |
| 399 | |
| 400 | // Store references to interned data submessages into the sequence's state. |
| 401 | for (auto it = interned_data_decoder.event_categories(); it; ++it) { |
| 402 | size_t offset = interned_data.offset_of(it->data()); |
| 403 | InternMessage<protos::pbzero::EventCategory>( |
| 404 | context_, state, interned_data.slice(offset, it->size())); |
| 405 | } |
| 406 | |
Eric Seckler | 0c460ef | 2019-08-14 15:42:36 +0100 | [diff] [blame] | 407 | for (auto it = interned_data_decoder.event_names(); it; ++it) { |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 408 | size_t offset = interned_data.offset_of(it->data()); |
Eric Seckler | 0c460ef | 2019-08-14 15:42:36 +0100 | [diff] [blame] | 409 | InternMessage<protos::pbzero::EventName>( |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 410 | context_, state, interned_data.slice(offset, it->size())); |
| 411 | } |
| 412 | |
| 413 | for (auto it = interned_data_decoder.debug_annotation_names(); it; ++it) { |
| 414 | size_t offset = interned_data.offset_of(it->data()); |
| 415 | InternMessage<protos::pbzero::DebugAnnotationName>( |
| 416 | context_, state, interned_data.slice(offset, it->size())); |
| 417 | } |
| 418 | |
| 419 | for (auto it = interned_data_decoder.source_locations(); it; ++it) { |
| 420 | size_t offset = interned_data.offset_of(it->data()); |
| 421 | InternMessage<protos::pbzero::SourceLocation>( |
| 422 | context_, state, interned_data.slice(offset, it->size())); |
| 423 | } |
Florian Mayer | 5716fc1 | 2019-06-24 11:50:51 -0700 | [diff] [blame] | 424 | |
| 425 | for (auto it = interned_data_decoder.build_ids(); it; ++it) { |
| 426 | size_t offset = interned_data.offset_of(it->data()); |
| 427 | InternMessage<protos::pbzero::InternedString>( |
| 428 | context_, state, interned_data.slice(offset, it->size())); |
| 429 | } |
| 430 | for (auto it = interned_data_decoder.mapping_paths(); it; ++it) { |
| 431 | size_t offset = interned_data.offset_of(it->data()); |
| 432 | InternMessage<protos::pbzero::InternedString>( |
| 433 | context_, state, interned_data.slice(offset, it->size())); |
| 434 | } |
| 435 | for (auto it = interned_data_decoder.function_names(); it; ++it) { |
| 436 | size_t offset = interned_data.offset_of(it->data()); |
| 437 | InternMessage<protos::pbzero::InternedString>( |
| 438 | context_, state, interned_data.slice(offset, it->size())); |
| 439 | } |
| 440 | |
| 441 | for (auto it = interned_data_decoder.mappings(); it; ++it) { |
| 442 | size_t offset = interned_data.offset_of(it->data()); |
| 443 | InternMessage<protos::pbzero::Mapping>( |
| 444 | context_, state, interned_data.slice(offset, it->size())); |
| 445 | } |
| 446 | for (auto it = interned_data_decoder.frames(); it; ++it) { |
| 447 | size_t offset = interned_data.offset_of(it->data()); |
| 448 | InternMessage<protos::pbzero::Frame>( |
| 449 | context_, state, interned_data.slice(offset, it->size())); |
| 450 | } |
| 451 | for (auto it = interned_data_decoder.callstacks(); it; ++it) { |
| 452 | size_t offset = interned_data.offset_of(it->data()); |
| 453 | InternMessage<protos::pbzero::Callstack>( |
| 454 | context_, state, interned_data.slice(offset, it->size())); |
| 455 | } |
Nicolò Mazzucato | 8f75ede | 2019-08-12 17:36:36 +0100 | [diff] [blame] | 456 | |
| 457 | for (auto it = interned_data_decoder.log_message_body(); it; ++it) { |
| 458 | size_t offset = interned_data.offset_of(it->data()); |
| 459 | InternMessage<protos::pbzero::LogMessageBody>( |
| 460 | context_, state, interned_data.slice(offset, it->size())); |
| 461 | } |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 462 | } |
| 463 | |
| 464 | void ProtoTraceTokenizer::ParseThreadDescriptorPacket( |
| 465 | const protos::pbzero::TracePacket::Decoder& packet_decoder) { |
| 466 | if (PERFETTO_UNLIKELY(!packet_decoder.has_trusted_packet_sequence_id())) { |
| 467 | PERFETTO_ELOG("ThreadDescriptor packet without trusted_packet_sequence_id"); |
| 468 | context_->storage->IncrementStats(stats::track_event_tokenizer_errors); |
| 469 | return; |
| 470 | } |
| 471 | |
| 472 | auto* state = GetIncrementalStateForPacketSequence( |
| 473 | packet_decoder.trusted_packet_sequence_id()); |
| 474 | |
| 475 | // TrackEvents will be ignored while incremental state is invalid. As a |
| 476 | // consequence, we should also ignore any ThreadDescriptors received in this |
| 477 | // state. Otherwise, any delta-encoded timestamps would be calculated |
| 478 | // incorrectly once we move out of the packet loss state. Instead, wait until |
| 479 | // the first subsequent descriptor after incremental state is cleared. |
| 480 | if (!state->IsIncrementalStateValid()) { |
| 481 | context_->storage->IncrementStats( |
| 482 | stats::track_event_tokenizer_skipped_packets); |
| 483 | return; |
| 484 | } |
| 485 | |
| 486 | auto thread_descriptor_field = packet_decoder.thread_descriptor(); |
| 487 | protos::pbzero::ThreadDescriptor::Decoder thread_descriptor_decoder( |
| 488 | thread_descriptor_field.data, thread_descriptor_field.size); |
| 489 | |
| 490 | state->SetThreadDescriptor( |
| 491 | thread_descriptor_decoder.pid(), thread_descriptor_decoder.tid(), |
| 492 | thread_descriptor_decoder.reference_timestamp_us() * 1000, |
Eric Seckler | 54f30a3 | 2019-07-19 15:10:29 +0100 | [diff] [blame] | 493 | thread_descriptor_decoder.reference_thread_time_us() * 1000, |
| 494 | thread_descriptor_decoder.reference_thread_instruction_count()); |
Siddhartha S | d37e566 | 2019-06-13 18:27:42 -0700 | [diff] [blame] | 495 | |
| 496 | base::StringView name; |
| 497 | if (thread_descriptor_decoder.has_thread_name()) { |
| 498 | name = thread_descriptor_decoder.thread_name(); |
| 499 | } else if (thread_descriptor_decoder.has_chrome_thread_type()) { |
| 500 | using protos::pbzero::ThreadDescriptor; |
| 501 | switch (thread_descriptor_decoder.chrome_thread_type()) { |
| 502 | case ThreadDescriptor::CHROME_THREAD_MAIN: |
| 503 | name = "CrProcessMain"; |
| 504 | break; |
| 505 | case ThreadDescriptor::CHROME_THREAD_IO: |
| 506 | name = "ChromeIOThread"; |
| 507 | break; |
| 508 | case ThreadDescriptor::CHROME_THREAD_POOL_FG_WORKER: |
| 509 | name = "ThreadPoolForegroundWorker&"; |
| 510 | break; |
| 511 | case ThreadDescriptor::CHROME_THREAD_POOL_BG_WORKER: |
| 512 | name = "ThreadPoolBackgroundWorker&"; |
| 513 | break; |
| 514 | case ThreadDescriptor::CHROME_THREAD_POOL_FB_BLOCKING: |
| 515 | name = "ThreadPoolSingleThreadForegroundBlocking&"; |
| 516 | break; |
| 517 | case ThreadDescriptor::CHROME_THREAD_POOL_BG_BLOCKING: |
| 518 | name = "ThreadPoolSingleThreadBackgroundBlocking&"; |
| 519 | break; |
| 520 | case ThreadDescriptor::CHROME_THREAD_POOL_SERVICE: |
| 521 | name = "ThreadPoolService"; |
| 522 | break; |
| 523 | case ThreadDescriptor::CHROME_THREAD_COMPOSITOR_WORKER: |
| 524 | name = "CompositorTileWorker&"; |
| 525 | break; |
| 526 | case ThreadDescriptor::CHROME_THREAD_COMPOSITOR: |
| 527 | name = "Compositor"; |
| 528 | break; |
| 529 | case ThreadDescriptor::CHROME_THREAD_VIZ_COMPOSITOR: |
| 530 | name = "VizCompositorThread"; |
| 531 | break; |
| 532 | case ThreadDescriptor::CHROME_THREAD_SERVICE_WORKER: |
| 533 | name = "ServiceWorkerThread&"; |
| 534 | break; |
| 535 | case ThreadDescriptor::CHROME_THREAD_MEMORY_INFRA: |
| 536 | name = "MemoryInfra"; |
| 537 | break; |
| 538 | case ThreadDescriptor::CHROME_THREAD_SAMPLING_PROFILER: |
| 539 | name = "StackSamplingProfiler"; |
| 540 | break; |
| 541 | case ThreadDescriptor::CHROME_THREAD_UNSPECIFIED: |
| 542 | name = "ChromeUnspecified"; |
| 543 | break; |
| 544 | } |
| 545 | } |
| 546 | |
| 547 | if (!name.empty()) { |
| 548 | auto thread_name_id = context_->storage->InternString(name); |
| 549 | ProcessTracker* procs = context_->process_tracker.get(); |
| 550 | procs->UpdateThreadName( |
| 551 | static_cast<uint32_t>(thread_descriptor_decoder.tid()), thread_name_id); |
| 552 | } |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 553 | } |
| 554 | |
Primiano Tucci | 6756fb0 | 2019-08-14 15:49:18 +0200 | [diff] [blame^] | 555 | util::Status ProtoTraceTokenizer::ParseClockSnapshot(ConstBytes blob, |
| 556 | uint32_t seq_id) { |
| 557 | std::map<ClockTracker::ClockId, int64_t> clock_map; |
| 558 | protos::pbzero::ClockSnapshot::Decoder evt(blob.data, blob.size); |
| 559 | for (auto it = evt.clocks(); it; ++it) { |
| 560 | protos::pbzero::ClockSnapshot::Clock::Decoder clk(it->data(), it->size()); |
| 561 | ClockTracker::ClockId clock_id = clk.clock_id(); |
| 562 | if (ClockTracker::IsReservedSeqScopedClockId(clk.clock_id())) { |
| 563 | if (!seq_id) { |
| 564 | return util::ErrStatus( |
| 565 | "ClockSnapshot packet is specifying a sequence-scoped clock id " |
| 566 | "(%" PRIu64 ") but the TracePacket sequence_id is zero", |
| 567 | clock_id); |
| 568 | } |
| 569 | clock_id = ClockTracker::SeqScopedClockIdToGlobal(seq_id, clk.clock_id()); |
| 570 | } |
| 571 | clock_map[clock_id] = static_cast<int64_t>(clk.timestamp()); |
| 572 | } |
| 573 | context_->clock_tracker->AddSnapshot(clock_map); |
| 574 | return util::OkStatus(); |
| 575 | } |
| 576 | |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 577 | void ProtoTraceTokenizer::ParseTrackEventPacket( |
| 578 | const protos::pbzero::TracePacket::Decoder& packet_decoder, |
| 579 | TraceBlobView packet) { |
| 580 | constexpr auto kTimestampDeltaUsFieldNumber = |
| 581 | protos::pbzero::TrackEvent::kTimestampDeltaUsFieldNumber; |
| 582 | constexpr auto kTimestampAbsoluteUsFieldNumber = |
| 583 | protos::pbzero::TrackEvent::kTimestampAbsoluteUsFieldNumber; |
| 584 | constexpr auto kThreadTimeDeltaUsFieldNumber = |
| 585 | protos::pbzero::TrackEvent::kThreadTimeDeltaUsFieldNumber; |
| 586 | constexpr auto kThreadTimeAbsoluteUsFieldNumber = |
| 587 | protos::pbzero::TrackEvent::kThreadTimeAbsoluteUsFieldNumber; |
Eric Seckler | 54f30a3 | 2019-07-19 15:10:29 +0100 | [diff] [blame] | 588 | constexpr auto kThreadInstructionCountDeltaFieldNumber = |
| 589 | protos::pbzero::TrackEvent::kThreadInstructionCountDeltaFieldNumber; |
| 590 | constexpr auto kThreadInstructionCountAbsoluteFieldNumber = |
| 591 | protos::pbzero::TrackEvent::kThreadInstructionCountAbsoluteFieldNumber; |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 592 | |
| 593 | if (PERFETTO_UNLIKELY(!packet_decoder.has_trusted_packet_sequence_id())) { |
| 594 | PERFETTO_ELOG("TrackEvent packet without trusted_packet_sequence_id"); |
| 595 | context_->storage->IncrementStats(stats::track_event_tokenizer_errors); |
| 596 | return; |
| 597 | } |
| 598 | |
| 599 | auto* state = GetIncrementalStateForPacketSequence( |
| 600 | packet_decoder.trusted_packet_sequence_id()); |
| 601 | |
| 602 | // TrackEvents can only be parsed correctly while incremental state for their |
| 603 | // sequence is valid and after a ThreadDescriptor has been parsed. |
| 604 | if (!state->IsTrackEventStateValid()) { |
| 605 | context_->storage->IncrementStats( |
| 606 | stats::track_event_tokenizer_skipped_packets); |
| 607 | return; |
| 608 | } |
| 609 | |
| 610 | auto field = packet_decoder.track_event(); |
| 611 | ProtoDecoder event_decoder(field.data, field.size); |
| 612 | |
| 613 | int64_t timestamp; |
| 614 | int64_t thread_timestamp = 0; |
Eric Seckler | 54f30a3 | 2019-07-19 15:10:29 +0100 | [diff] [blame] | 615 | int64_t thread_instructions = 0; |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 616 | |
| 617 | if (auto ts_delta_field = |
| 618 | event_decoder.FindField(kTimestampDeltaUsFieldNumber)) { |
| 619 | timestamp = state->IncrementAndGetTrackEventTimeNs( |
| 620 | ts_delta_field.as_int64() * 1000); |
| 621 | } else if (auto ts_absolute_field = |
| 622 | event_decoder.FindField(kTimestampAbsoluteUsFieldNumber)) { |
| 623 | // One-off absolute timestamps don't affect delta computation. |
| 624 | timestamp = ts_absolute_field.as_int64() * 1000; |
| 625 | } else { |
| 626 | PERFETTO_ELOG("TrackEvent without timestamp"); |
| 627 | context_->storage->IncrementStats(stats::track_event_tokenizer_errors); |
| 628 | return; |
| 629 | } |
| 630 | |
| 631 | if (auto tt_delta_field = |
| 632 | event_decoder.FindField(kThreadTimeDeltaUsFieldNumber)) { |
| 633 | thread_timestamp = state->IncrementAndGetTrackEventThreadTimeNs( |
| 634 | tt_delta_field.as_int64() * 1000); |
| 635 | } else if (auto tt_absolute_field = |
| 636 | event_decoder.FindField(kThreadTimeAbsoluteUsFieldNumber)) { |
| 637 | // One-off absolute timestamps don't affect delta computation. |
| 638 | thread_timestamp = tt_absolute_field.as_int64() * 1000; |
| 639 | } |
| 640 | |
Eric Seckler | 54f30a3 | 2019-07-19 15:10:29 +0100 | [diff] [blame] | 641 | if (auto ti_delta_field = |
| 642 | event_decoder.FindField(kThreadInstructionCountDeltaFieldNumber)) { |
| 643 | thread_instructions = |
| 644 | state->IncrementAndGetTrackEventThreadInstructionCount( |
| 645 | ti_delta_field.as_int64()); |
| 646 | } else if (auto ti_absolute_field = event_decoder.FindField( |
| 647 | kThreadInstructionCountAbsoluteFieldNumber)) { |
| 648 | // One-off absolute timestamps don't affect delta computation. |
| 649 | thread_instructions = ti_absolute_field.as_int64(); |
| 650 | } |
| 651 | |
| 652 | context_->sorter->PushTrackEventPacket(timestamp, thread_timestamp, |
| 653 | thread_instructions, state, |
Eric Seckler | 684a4f7 | 2019-04-26 14:34:07 +0100 | [diff] [blame] | 654 | std::move(packet)); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 655 | } |
| 656 | |
| 657 | PERFETTO_ALWAYS_INLINE |
| 658 | void ProtoTraceTokenizer::ParseFtraceBundle(TraceBlobView bundle) { |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 659 | protos::pbzero::FtraceEventBundle::Decoder decoder(bundle.data(), |
| 660 | bundle.length()); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 661 | |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 662 | if (PERFETTO_UNLIKELY(!decoder.has_cpu())) { |
| 663 | PERFETTO_ELOG("CPU field not found in FtraceEventBundle"); |
Deepanjan Roy | 01994ca | 2019-04-02 11:05:34 -0700 | [diff] [blame] | 664 | context_->storage->IncrementStats(stats::ftrace_bundle_tokenizer_errors); |
Hector Dearman | 52642ce | 2019-03-07 15:31:11 +0000 | [diff] [blame] | 665 | return; |
| 666 | } |
| 667 | |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 668 | uint32_t cpu = decoder.cpu(); |
| 669 | if (PERFETTO_UNLIKELY(cpu > base::kMaxCpus)) { |
| 670 | PERFETTO_ELOG("CPU larger than kMaxCpus (%u > %zu)", cpu, base::kMaxCpus); |
| 671 | return; |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 672 | } |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 673 | |
| 674 | for (auto it = decoder.event(); it; ++it) { |
| 675 | size_t off = bundle.offset_of(it->data()); |
| 676 | ParseFtraceEvent(cpu, bundle.slice(off, it->size())); |
| 677 | } |
Deepanjan Roy | 01994ca | 2019-04-02 11:05:34 -0700 | [diff] [blame] | 678 | context_->sorter->FinalizeFtraceEventBatch(cpu); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 679 | } |
| 680 | |
| 681 | PERFETTO_ALWAYS_INLINE |
| 682 | void ProtoTraceTokenizer::ParseFtraceEvent(uint32_t cpu, TraceBlobView event) { |
| 683 | constexpr auto kTimestampFieldNumber = |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 684 | protos::pbzero::FtraceEvent::kTimestampFieldNumber; |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 685 | const uint8_t* data = event.data(); |
| 686 | const size_t length = event.length(); |
| 687 | ProtoDecoder decoder(data, length); |
Lalit Maganti | fa21a28 | 2019-01-17 19:03:04 +0000 | [diff] [blame] | 688 | uint64_t raw_timestamp = 0; |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 689 | bool timestamp_found = false; |
| 690 | |
| 691 | // Speculate on the fact that the timestamp is often the 1st field of the |
| 692 | // event. |
| 693 | constexpr auto timestampFieldTag = MakeTagVarInt(kTimestampFieldNumber); |
| 694 | if (PERFETTO_LIKELY(length > 10 && data[0] == timestampFieldTag)) { |
| 695 | // Fastpath. |
Lalit Maganti | fa21a28 | 2019-01-17 19:03:04 +0000 | [diff] [blame] | 696 | const uint8_t* next = ParseVarInt(data + 1, data + 11, &raw_timestamp); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 697 | timestamp_found = next != data + 1; |
| 698 | decoder.Reset(next); |
| 699 | } else { |
| 700 | // Slowpath. |
Primiano Tucci | c167887 | 2019-03-20 11:30:54 +0000 | [diff] [blame] | 701 | if (auto ts_field = decoder.FindField(kTimestampFieldNumber)) { |
| 702 | timestamp_found = true; |
| 703 | raw_timestamp = ts_field.as_uint64(); |
| 704 | } |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 705 | } |
| 706 | |
| 707 | if (PERFETTO_UNLIKELY(!timestamp_found)) { |
| 708 | PERFETTO_ELOG("Timestamp field not found in FtraceEvent"); |
Deepanjan Roy | 01994ca | 2019-04-02 11:05:34 -0700 | [diff] [blame] | 709 | context_->storage->IncrementStats(stats::ftrace_bundle_tokenizer_errors); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 710 | return; |
| 711 | } |
| 712 | |
Lalit Maganti | fa21a28 | 2019-01-17 19:03:04 +0000 | [diff] [blame] | 713 | int64_t timestamp = static_cast<int64_t>(raw_timestamp); |
| 714 | latest_timestamp_ = std::max(timestamp, latest_timestamp_); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 715 | |
| 716 | // We don't need to parse this packet, just push it to be sorted with |
| 717 | // the timestamp. |
Deepanjan Roy | 01994ca | 2019-04-02 11:05:34 -0700 | [diff] [blame] | 718 | context_->sorter->PushFtraceEvent(cpu, timestamp, std::move(event)); |
Primiano Tucci | d933d91 | 2018-09-04 09:15:07 +0100 | [diff] [blame] | 719 | } |
| 720 | |
| 721 | } // namespace trace_processor |
| 722 | } // namespace perfetto |