| 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 |