OLD | NEW |
---|---|
1 // Copyright (c) 2013 The Chromium Authors. All rights reserved. | 1 // Copyright (c) 2013 The Chromium Authors. All rights reserved. |
2 // Use of this source code is governed by a BSD-style license that can be | 2 // Use of this source code is governed by a BSD-style license that can be |
3 // found in the LICENSE file. | 3 // found in the LICENSE file. |
4 | 4 |
5 #include "net/quic/quic_connection_logger.h" | 5 #include "net/quic/quic_connection_logger.h" |
6 | 6 |
7 #include <algorithm> | |
8 #include <string> | |
9 | |
7 #include "base/bind.h" | 10 #include "base/bind.h" |
8 #include "base/callback.h" | 11 #include "base/callback.h" |
9 #include "base/metrics/histogram.h" | 12 #include "base/metrics/histogram.h" |
10 #include "base/metrics/sparse_histogram.h" | 13 #include "base/metrics/sparse_histogram.h" |
11 #include "base/strings/string_number_conversions.h" | 14 #include "base/strings/string_number_conversions.h" |
12 #include "base/values.h" | 15 #include "base/values.h" |
13 #include "net/base/net_log.h" | 16 #include "net/base/net_log.h" |
14 #include "net/quic/crypto/crypto_handshake_message.h" | 17 #include "net/quic/crypto/crypto_handshake_message.h" |
15 #include "net/quic/crypto/crypto_protocol.h" | 18 #include "net/quic/crypto/crypto_protocol.h" |
16 #include "net/quic/quic_address_mismatch.h" | 19 #include "net/quic/quic_address_mismatch.h" |
17 #include "net/quic/quic_socket_address_coder.h" | 20 #include "net/quic/quic_socket_address_coder.h" |
18 | 21 |
19 using base::StringPiece; | 22 using base::StringPiece; |
20 using std::string; | 23 using std::string; |
21 | 24 |
22 namespace net { | 25 namespace net { |
23 | 26 |
24 namespace { | 27 namespace { |
25 | |
Ryan Hamilton
2014/03/07 17:47:05
nit: can you add this blank line back?
jar (doing other things)
2014/03/07 18:01:11
Done.
| |
26 base::Value* NetLogQuicPacketCallback(const IPEndPoint* self_address, | 28 base::Value* NetLogQuicPacketCallback(const IPEndPoint* self_address, |
27 const IPEndPoint* peer_address, | 29 const IPEndPoint* peer_address, |
28 size_t packet_size, | 30 size_t packet_size, |
29 NetLog::LogLevel /* log_level */) { | 31 NetLog::LogLevel /* log_level */) { |
30 base::DictionaryValue* dict = new base::DictionaryValue(); | 32 base::DictionaryValue* dict = new base::DictionaryValue(); |
31 dict->SetString("self_address", self_address->ToString()); | 33 dict->SetString("self_address", self_address->ToString()); |
32 dict->SetString("peer_address", peer_address->ToString()); | 34 dict->SetString("peer_address", peer_address->ToString()); |
33 dict->SetInteger("size", packet_size); | 35 dict->SetInteger("size", packet_size); |
34 return dict; | 36 return dict; |
35 } | 37 } |
(...skipping 12 matching lines...) Expand all Loading... | |
48 if (result.status != WRITE_STATUS_OK) { | 50 if (result.status != WRITE_STATUS_OK) { |
49 dict->SetInteger("net_error", result.error_code); | 51 dict->SetInteger("net_error", result.error_code); |
50 } | 52 } |
51 return dict; | 53 return dict; |
52 } | 54 } |
53 | 55 |
54 base::Value* NetLogQuicPacketRetransmittedCallback( | 56 base::Value* NetLogQuicPacketRetransmittedCallback( |
55 QuicPacketSequenceNumber old_sequence_number, | 57 QuicPacketSequenceNumber old_sequence_number, |
56 QuicPacketSequenceNumber new_sequence_number, | 58 QuicPacketSequenceNumber new_sequence_number, |
57 NetLog::LogLevel /* log_level */) { | 59 NetLog::LogLevel /* log_level */) { |
58 base::DictionaryValue* dict = new base::DictionaryValue(); | 60 base::DictionaryValue* dict = new base::DictionaryValue(); |
59 dict->SetString("old_packet_sequence_number", | 61 dict->SetString("old_packet_sequence_number", |
60 base::Uint64ToString(old_sequence_number)); | 62 base::Uint64ToString(old_sequence_number)); |
61 dict->SetString("new_packet_sequence_number", | 63 dict->SetString("new_packet_sequence_number", |
62 base::Uint64ToString(new_sequence_number)); | 64 base::Uint64ToString(new_sequence_number)); |
63 return dict; | 65 return dict; |
64 } | 66 } |
65 | 67 |
66 base::Value* NetLogQuicPacketHeaderCallback(const QuicPacketHeader* header, | 68 base::Value* NetLogQuicPacketHeaderCallback(const QuicPacketHeader* header, |
67 NetLog::LogLevel /* log_level */) { | 69 NetLog::LogLevel /* log_level */) { |
68 base::DictionaryValue* dict = new base::DictionaryValue(); | 70 base::DictionaryValue* dict = new base::DictionaryValue(); |
69 dict->SetString("guid", | 71 dict->SetString("guid", |
70 base::Uint64ToString(header->public_header.guid)); | 72 base::Uint64ToString(header->public_header.guid)); |
71 dict->SetInteger("reset_flag", header->public_header.reset_flag); | 73 dict->SetInteger("reset_flag", header->public_header.reset_flag); |
72 dict->SetInteger("version_flag", header->public_header.version_flag); | 74 dict->SetInteger("version_flag", header->public_header.version_flag); |
73 dict->SetString("packet_sequence_number", | 75 dict->SetString("packet_sequence_number", |
(...skipping 43 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... | |
117 NetLog::LogLevel /* log_level */) { | 119 NetLog::LogLevel /* log_level */) { |
118 base::DictionaryValue* dict = new base::DictionaryValue(); | 120 base::DictionaryValue* dict = new base::DictionaryValue(); |
119 switch (frame->type) { | 121 switch (frame->type) { |
120 case kInterArrival: { | 122 case kInterArrival: { |
121 dict->SetString("type", "InterArrival"); | 123 dict->SetString("type", "InterArrival"); |
122 base::ListValue* received = new base::ListValue(); | 124 base::ListValue* received = new base::ListValue(); |
123 dict->Set("received_packets", received); | 125 dict->Set("received_packets", received); |
124 for (TimeMap::const_iterator it = | 126 for (TimeMap::const_iterator it = |
125 frame->inter_arrival.received_packet_times.begin(); | 127 frame->inter_arrival.received_packet_times.begin(); |
126 it != frame->inter_arrival.received_packet_times.end(); ++it) { | 128 it != frame->inter_arrival.received_packet_times.end(); ++it) { |
127 std::string value = base::Uint64ToString(it->first) + "@" + | 129 string value = base::Uint64ToString(it->first) + "@" + |
128 base::Uint64ToString(it->second.ToDebuggingValue()); | 130 base::Uint64ToString(it->second.ToDebuggingValue()); |
129 received->AppendString(value); | 131 received->AppendString(value); |
130 } | 132 } |
131 break; | 133 break; |
132 } | 134 } |
133 case kFixRate: | 135 case kFixRate: |
134 dict->SetString("type", "FixRate"); | 136 dict->SetString("type", "FixRate"); |
135 dict->SetInteger("bitrate_in_bytes_per_second", | 137 dict->SetInteger("bitrate_in_bytes_per_second", |
136 frame->fix_rate.bitrate.ToBytesPerSecond()); | 138 frame->fix_rate.bitrate.ToBytesPerSecond()); |
137 break; | 139 break; |
(...skipping 76 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... | |
214 | 216 |
215 } // namespace | 217 } // namespace |
216 | 218 |
217 QuicConnectionLogger::QuicConnectionLogger(const BoundNetLog& net_log) | 219 QuicConnectionLogger::QuicConnectionLogger(const BoundNetLog& net_log) |
218 : net_log_(net_log), | 220 : net_log_(net_log), |
219 last_received_packet_sequence_number_(0), | 221 last_received_packet_sequence_number_(0), |
220 largest_received_packet_sequence_number_(0), | 222 largest_received_packet_sequence_number_(0), |
221 largest_received_missing_packet_sequence_number_(0), | 223 largest_received_missing_packet_sequence_number_(0), |
222 out_of_order_recieved_packet_count_(0), | 224 out_of_order_recieved_packet_count_(0), |
223 num_truncated_acks_sent_(0), | 225 num_truncated_acks_sent_(0), |
224 num_truncated_acks_received_(0) { | 226 num_truncated_acks_received_(0), |
227 connection_type_(NetworkChangeNotifier::GetConnectionType()) { | |
225 } | 228 } |
226 | 229 |
227 QuicConnectionLogger::~QuicConnectionLogger() { | 230 QuicConnectionLogger::~QuicConnectionLogger() { |
228 UMA_HISTOGRAM_COUNTS("Net.QuicSession.OutOfOrderPacketsReceived", | 231 UMA_HISTOGRAM_COUNTS("Net.QuicSession.OutOfOrderPacketsReceived", |
229 out_of_order_recieved_packet_count_); | 232 out_of_order_recieved_packet_count_); |
230 UMA_HISTOGRAM_COUNTS("Net.QuicSession.TruncatedAcksSent", | 233 UMA_HISTOGRAM_COUNTS("Net.QuicSession.TruncatedAcksSent", |
231 num_truncated_acks_sent_); | 234 num_truncated_acks_sent_); |
232 UMA_HISTOGRAM_COUNTS("Net.QuicSession.TruncatedAcksReceived", | 235 UMA_HISTOGRAM_COUNTS("Net.QuicSession.TruncatedAcksReceived", |
233 num_truncated_acks_received_); | 236 num_truncated_acks_received_); |
237 | |
238 RecordAckNackHistograms(); | |
234 } | 239 } |
235 | 240 |
236 void QuicConnectionLogger::OnFrameAddedToPacket(const QuicFrame& frame) { | 241 void QuicConnectionLogger::OnFrameAddedToPacket(const QuicFrame& frame) { |
237 switch (frame.type) { | 242 switch (frame.type) { |
238 case PADDING_FRAME: | 243 case PADDING_FRAME: |
239 break; | 244 break; |
240 case STREAM_FRAME: | 245 case STREAM_FRAME: |
241 net_log_.AddEvent( | 246 net_log_.AddEvent( |
242 NetLog::TYPE_QUIC_SESSION_STREAM_FRAME_SENT, | 247 NetLog::TYPE_QUIC_SESSION_STREAM_FRAME_SENT, |
243 base::Bind(&NetLogQuicStreamFrameCallback, frame.stream_frame)); | 248 base::Bind(&NetLogQuicStreamFrameCallback, frame.stream_frame)); |
(...skipping 75 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... | |
319 QuicPacketSequenceNumber delta = header.packet_sequence_number - | 324 QuicPacketSequenceNumber delta = header.packet_sequence_number - |
320 largest_received_packet_sequence_number_; | 325 largest_received_packet_sequence_number_; |
321 if (delta > 1) { | 326 if (delta > 1) { |
322 // There is a gap between the largest packet previously received and | 327 // There is a gap between the largest packet previously received and |
323 // the current packet. This indicates either loss, or out-of-order | 328 // the current packet. This indicates either loss, or out-of-order |
324 // delivery. | 329 // delivery. |
325 UMA_HISTOGRAM_COUNTS("Net.QuicSession.PacketGapReceived", delta - 1); | 330 UMA_HISTOGRAM_COUNTS("Net.QuicSession.PacketGapReceived", delta - 1); |
326 } | 331 } |
327 largest_received_packet_sequence_number_ = header.packet_sequence_number; | 332 largest_received_packet_sequence_number_ = header.packet_sequence_number; |
328 } | 333 } |
334 if (header.packet_sequence_number <= kMaxPacketStatus) | |
335 packets_received_[header.packet_sequence_number] = true; | |
329 if (header.packet_sequence_number < last_received_packet_sequence_number_) { | 336 if (header.packet_sequence_number < last_received_packet_sequence_number_) { |
330 ++out_of_order_recieved_packet_count_; | 337 ++out_of_order_recieved_packet_count_; |
331 UMA_HISTOGRAM_COUNTS("Net.QuicSession.OutOfOrderGapReceived", | 338 UMA_HISTOGRAM_COUNTS("Net.QuicSession.OutOfOrderGapReceived", |
332 last_received_packet_sequence_number_ - | 339 last_received_packet_sequence_number_ - |
333 header.packet_sequence_number); | 340 header.packet_sequence_number); |
334 } | 341 } |
335 last_received_packet_sequence_number_ = header.packet_sequence_number; | 342 last_received_packet_sequence_number_ = header.packet_sequence_number; |
336 } | 343 } |
337 | 344 |
338 void QuicConnectionLogger::OnStreamFrame(const QuicStreamFrame& frame) { | 345 void QuicConnectionLogger::OnStreamFrame(const QuicStreamFrame& frame) { |
(...skipping 120 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... | |
459 base::Bind(&NetLogQuicOnConnectionClosedCallback, error, from_peer)); | 466 base::Bind(&NetLogQuicOnConnectionClosedCallback, error, from_peer)); |
460 } | 467 } |
461 | 468 |
462 void QuicConnectionLogger::OnSuccessfulVersionNegotiation( | 469 void QuicConnectionLogger::OnSuccessfulVersionNegotiation( |
463 const QuicVersion& version) { | 470 const QuicVersion& version) { |
464 string quic_version = QuicVersionToString(version); | 471 string quic_version = QuicVersionToString(version); |
465 net_log_.AddEvent(NetLog::TYPE_QUIC_SESSION_VERSION_NEGOTIATED, | 472 net_log_.AddEvent(NetLog::TYPE_QUIC_SESSION_VERSION_NEGOTIATED, |
466 NetLog::StringCallback("version", &quic_version)); | 473 NetLog::StringCallback("version", &quic_version)); |
467 } | 474 } |
468 | 475 |
476 base::HistogramBase* QuicConnectionLogger::GetAckHistogram( | |
477 const char* ack_or_nack) { | |
478 std::string prefix("Net.QuicSession.PacketReceived_"); | |
Ryan Hamilton
2014/03/07 17:47:05
nit: since we're "using std::string", I think you
jar (doing other things)
2014/03/07 18:01:11
Done.
| |
479 const char* suffix = NetworkChangeNotifier::ConnectionTypeToString( | |
480 connection_type_); | |
481 return base::LinearHistogram::FactoryGet(prefix + ack_or_nack + suffix, 1, | |
482 kMaxPacketStatus + 1, kMaxPacketStatus + 2, | |
483 base::HistogramBase::kUmaTargetedHistogramFlag); | |
484 } | |
485 | |
486 void QuicConnectionLogger::RecordAckNackHistograms() { | |
487 if (largest_received_packet_sequence_number_ == 0) | |
488 return; // Connection was never used. | |
489 base::HistogramBase* packet_ack_histogram = GetAckHistogram("Ack_"); | |
490 base::HistogramBase* packet_nack_histogram = GetAckHistogram("Nack_"); | |
491 const QuicPacketSequenceNumber last_index = | |
492 std::min<QuicPacketSequenceNumber>( | |
493 kMaxPacketStatus, largest_received_packet_sequence_number_); | |
494 // Zero is an invalid packet sequence number. | |
495 DCHECK(!packets_received_[0]); | |
496 for (size_t i = 1; i <= last_index; ++i) { | |
497 if (packets_received_[i]) | |
498 packet_ack_histogram->Add(i); | |
499 else | |
500 packet_nack_histogram->Add(i); | |
501 } | |
502 } | |
503 | |
469 } // namespace net | 504 } // namespace net |
OLD | NEW |