Chromium Code Reviews| 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 |