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" |
(...skipping 31 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
48 if (result.status != WRITE_STATUS_OK) { | 51 if (result.status != WRITE_STATUS_OK) { |
49 dict->SetInteger("net_error", result.error_code); | 52 dict->SetInteger("net_error", result.error_code); |
50 } | 53 } |
51 return dict; | 54 return dict; |
52 } | 55 } |
53 | 56 |
54 base::Value* NetLogQuicPacketRetransmittedCallback( | 57 base::Value* NetLogQuicPacketRetransmittedCallback( |
55 QuicPacketSequenceNumber old_sequence_number, | 58 QuicPacketSequenceNumber old_sequence_number, |
56 QuicPacketSequenceNumber new_sequence_number, | 59 QuicPacketSequenceNumber new_sequence_number, |
57 NetLog::LogLevel /* log_level */) { | 60 NetLog::LogLevel /* log_level */) { |
58 base::DictionaryValue* dict = new base::DictionaryValue(); | 61 base::DictionaryValue* dict = new base::DictionaryValue(); |
59 dict->SetString("old_packet_sequence_number", | 62 dict->SetString("old_packet_sequence_number", |
60 base::Uint64ToString(old_sequence_number)); | 63 base::Uint64ToString(old_sequence_number)); |
61 dict->SetString("new_packet_sequence_number", | 64 dict->SetString("new_packet_sequence_number", |
62 base::Uint64ToString(new_sequence_number)); | 65 base::Uint64ToString(new_sequence_number)); |
63 return dict; | 66 return dict; |
64 } | 67 } |
65 | 68 |
66 base::Value* NetLogQuicPacketHeaderCallback(const QuicPacketHeader* header, | 69 base::Value* NetLogQuicPacketHeaderCallback(const QuicPacketHeader* header, |
67 NetLog::LogLevel /* log_level */) { | 70 NetLog::LogLevel /* log_level */) { |
68 base::DictionaryValue* dict = new base::DictionaryValue(); | 71 base::DictionaryValue* dict = new base::DictionaryValue(); |
69 dict->SetString("guid", | 72 dict->SetString("guid", |
70 base::Uint64ToString(header->public_header.guid)); | 73 base::Uint64ToString(header->public_header.guid)); |
71 dict->SetInteger("reset_flag", header->public_header.reset_flag); | 74 dict->SetInteger("reset_flag", header->public_header.reset_flag); |
72 dict->SetInteger("version_flag", header->public_header.version_flag); | 75 dict->SetInteger("version_flag", header->public_header.version_flag); |
73 dict->SetString("packet_sequence_number", | 76 dict->SetString("packet_sequence_number", |
(...skipping 43 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
117 NetLog::LogLevel /* log_level */) { | 120 NetLog::LogLevel /* log_level */) { |
118 base::DictionaryValue* dict = new base::DictionaryValue(); | 121 base::DictionaryValue* dict = new base::DictionaryValue(); |
119 switch (frame->type) { | 122 switch (frame->type) { |
120 case kInterArrival: { | 123 case kInterArrival: { |
121 dict->SetString("type", "InterArrival"); | 124 dict->SetString("type", "InterArrival"); |
122 base::ListValue* received = new base::ListValue(); | 125 base::ListValue* received = new base::ListValue(); |
123 dict->Set("received_packets", received); | 126 dict->Set("received_packets", received); |
124 for (TimeMap::const_iterator it = | 127 for (TimeMap::const_iterator it = |
125 frame->inter_arrival.received_packet_times.begin(); | 128 frame->inter_arrival.received_packet_times.begin(); |
126 it != frame->inter_arrival.received_packet_times.end(); ++it) { | 129 it != frame->inter_arrival.received_packet_times.end(); ++it) { |
127 std::string value = base::Uint64ToString(it->first) + "@" + | 130 string value = base::Uint64ToString(it->first) + "@" + |
128 base::Uint64ToString(it->second.ToDebuggingValue()); | 131 base::Uint64ToString(it->second.ToDebuggingValue()); |
129 received->AppendString(value); | 132 received->AppendString(value); |
130 } | 133 } |
131 break; | 134 break; |
132 } | 135 } |
133 case kFixRate: | 136 case kFixRate: |
134 dict->SetString("type", "FixRate"); | 137 dict->SetString("type", "FixRate"); |
135 dict->SetInteger("bitrate_in_bytes_per_second", | 138 dict->SetInteger("bitrate_in_bytes_per_second", |
136 frame->fix_rate.bitrate.ToBytesPerSecond()); | 139 frame->fix_rate.bitrate.ToBytesPerSecond()); |
137 break; | 140 break; |
(...skipping 76 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
214 | 217 |
215 } // namespace | 218 } // namespace |
216 | 219 |
217 QuicConnectionLogger::QuicConnectionLogger(const BoundNetLog& net_log) | 220 QuicConnectionLogger::QuicConnectionLogger(const BoundNetLog& net_log) |
218 : net_log_(net_log), | 221 : net_log_(net_log), |
219 last_received_packet_sequence_number_(0), | 222 last_received_packet_sequence_number_(0), |
220 largest_received_packet_sequence_number_(0), | 223 largest_received_packet_sequence_number_(0), |
221 largest_received_missing_packet_sequence_number_(0), | 224 largest_received_missing_packet_sequence_number_(0), |
222 out_of_order_recieved_packet_count_(0), | 225 out_of_order_recieved_packet_count_(0), |
223 num_truncated_acks_sent_(0), | 226 num_truncated_acks_sent_(0), |
224 num_truncated_acks_received_(0) { | 227 num_truncated_acks_received_(0), |
| 228 connection_type_(NetworkChangeNotifier::GetConnectionType()) { |
225 } | 229 } |
226 | 230 |
227 QuicConnectionLogger::~QuicConnectionLogger() { | 231 QuicConnectionLogger::~QuicConnectionLogger() { |
228 UMA_HISTOGRAM_COUNTS("Net.QuicSession.OutOfOrderPacketsReceived", | 232 UMA_HISTOGRAM_COUNTS("Net.QuicSession.OutOfOrderPacketsReceived", |
229 out_of_order_recieved_packet_count_); | 233 out_of_order_recieved_packet_count_); |
230 UMA_HISTOGRAM_COUNTS("Net.QuicSession.TruncatedAcksSent", | 234 UMA_HISTOGRAM_COUNTS("Net.QuicSession.TruncatedAcksSent", |
231 num_truncated_acks_sent_); | 235 num_truncated_acks_sent_); |
232 UMA_HISTOGRAM_COUNTS("Net.QuicSession.TruncatedAcksReceived", | 236 UMA_HISTOGRAM_COUNTS("Net.QuicSession.TruncatedAcksReceived", |
233 num_truncated_acks_received_); | 237 num_truncated_acks_received_); |
| 238 |
| 239 RecordAckNackHistograms(); |
234 } | 240 } |
235 | 241 |
236 void QuicConnectionLogger::OnFrameAddedToPacket(const QuicFrame& frame) { | 242 void QuicConnectionLogger::OnFrameAddedToPacket(const QuicFrame& frame) { |
237 switch (frame.type) { | 243 switch (frame.type) { |
238 case PADDING_FRAME: | 244 case PADDING_FRAME: |
239 break; | 245 break; |
240 case STREAM_FRAME: | 246 case STREAM_FRAME: |
241 net_log_.AddEvent( | 247 net_log_.AddEvent( |
242 NetLog::TYPE_QUIC_SESSION_STREAM_FRAME_SENT, | 248 NetLog::TYPE_QUIC_SESSION_STREAM_FRAME_SENT, |
243 base::Bind(&NetLogQuicStreamFrameCallback, frame.stream_frame)); | 249 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 - | 325 QuicPacketSequenceNumber delta = header.packet_sequence_number - |
320 largest_received_packet_sequence_number_; | 326 largest_received_packet_sequence_number_; |
321 if (delta > 1) { | 327 if (delta > 1) { |
322 // There is a gap between the largest packet previously received and | 328 // There is a gap between the largest packet previously received and |
323 // the current packet. This indicates either loss, or out-of-order | 329 // the current packet. This indicates either loss, or out-of-order |
324 // delivery. | 330 // delivery. |
325 UMA_HISTOGRAM_COUNTS("Net.QuicSession.PacketGapReceived", delta - 1); | 331 UMA_HISTOGRAM_COUNTS("Net.QuicSession.PacketGapReceived", delta - 1); |
326 } | 332 } |
327 largest_received_packet_sequence_number_ = header.packet_sequence_number; | 333 largest_received_packet_sequence_number_ = header.packet_sequence_number; |
328 } | 334 } |
| 335 if (header.packet_sequence_number < packets_received_.size()) |
| 336 packets_received_[header.packet_sequence_number] = true; |
329 if (header.packet_sequence_number < last_received_packet_sequence_number_) { | 337 if (header.packet_sequence_number < last_received_packet_sequence_number_) { |
330 ++out_of_order_recieved_packet_count_; | 338 ++out_of_order_recieved_packet_count_; |
331 UMA_HISTOGRAM_COUNTS("Net.QuicSession.OutOfOrderGapReceived", | 339 UMA_HISTOGRAM_COUNTS("Net.QuicSession.OutOfOrderGapReceived", |
332 last_received_packet_sequence_number_ - | 340 last_received_packet_sequence_number_ - |
333 header.packet_sequence_number); | 341 header.packet_sequence_number); |
334 } | 342 } |
335 last_received_packet_sequence_number_ = header.packet_sequence_number; | 343 last_received_packet_sequence_number_ = header.packet_sequence_number; |
336 } | 344 } |
337 | 345 |
338 void QuicConnectionLogger::OnStreamFrame(const QuicStreamFrame& frame) { | 346 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)); | 467 base::Bind(&NetLogQuicOnConnectionClosedCallback, error, from_peer)); |
460 } | 468 } |
461 | 469 |
462 void QuicConnectionLogger::OnSuccessfulVersionNegotiation( | 470 void QuicConnectionLogger::OnSuccessfulVersionNegotiation( |
463 const QuicVersion& version) { | 471 const QuicVersion& version) { |
464 string quic_version = QuicVersionToString(version); | 472 string quic_version = QuicVersionToString(version); |
465 net_log_.AddEvent(NetLog::TYPE_QUIC_SESSION_VERSION_NEGOTIATED, | 473 net_log_.AddEvent(NetLog::TYPE_QUIC_SESSION_VERSION_NEGOTIATED, |
466 NetLog::StringCallback("version", &quic_version)); | 474 NetLog::StringCallback("version", &quic_version)); |
467 } | 475 } |
468 | 476 |
| 477 base::HistogramBase* QuicConnectionLogger::GetAckHistogram( |
| 478 const char* ack_or_nack) { |
| 479 string prefix("Net.QuicSession.PacketReceived_"); |
| 480 const char* suffix = NetworkChangeNotifier::ConnectionTypeToString( |
| 481 connection_type_); |
| 482 return base::LinearHistogram::FactoryGet(prefix + ack_or_nack + suffix, 1, |
| 483 packets_received_.size(), packets_received_.size() + 1, |
| 484 base::HistogramBase::kUmaTargetedHistogramFlag); |
| 485 } |
| 486 |
| 487 void QuicConnectionLogger::RecordAckNackHistograms() { |
| 488 if (largest_received_packet_sequence_number_ == 0) |
| 489 return; // Connection was never used. |
| 490 base::HistogramBase* packet_ack_histogram = GetAckHistogram("Ack_"); |
| 491 base::HistogramBase* packet_nack_histogram = GetAckHistogram("Nack_"); |
| 492 const QuicPacketSequenceNumber last_index = |
| 493 std::min<QuicPacketSequenceNumber>( |
| 494 packets_received_.size() - 1, |
| 495 largest_received_packet_sequence_number_); |
| 496 // Zero is an invalid packet sequence number. |
| 497 DCHECK(!packets_received_[0]); |
| 498 for (size_t i = 1; i <= last_index; ++i) { |
| 499 if (packets_received_[i]) |
| 500 packet_ack_histogram->Add(i); |
| 501 else |
| 502 packet_nack_histogram->Add(i); |
| 503 } |
| 504 } |
| 505 |
469 } // namespace net | 506 } // namespace net |
OLD | NEW |