OLD | NEW |
1 // Copyright (c) 2012 The Chromium Authors. All rights reserved. | 1 // Copyright (c) 2012 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 "base/debug/trace_event_impl.h" | 5 #include "base/debug/trace_event_impl.h" |
6 | 6 |
7 #include <algorithm> | 7 #include <algorithm> |
8 | 8 |
9 #include "base/base_switches.h" | 9 #include "base/base_switches.h" |
10 #include "base/bind.h" | 10 #include "base/bind.h" |
(...skipping 81 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
92 const int g_category_trace_event_overhead = 3; | 92 const int g_category_trace_event_overhead = 3; |
93 const int g_num_builtin_categories = 4; | 93 const int g_num_builtin_categories = 4; |
94 int g_category_index = g_num_builtin_categories; // Skip default categories. | 94 int g_category_index = g_num_builtin_categories; // Skip default categories. |
95 | 95 |
96 // The name of the current thread. This is used to decide if the current | 96 // The name of the current thread. This is used to decide if the current |
97 // thread name has changed. We combine all the seen thread names into the | 97 // thread name has changed. We combine all the seen thread names into the |
98 // output name for the thread. | 98 // output name for the thread. |
99 LazyInstance<ThreadLocalPointer<const char> >::Leaky | 99 LazyInstance<ThreadLocalPointer<const char> >::Leaky |
100 g_current_thread_name = LAZY_INSTANCE_INITIALIZER; | 100 g_current_thread_name = LAZY_INSTANCE_INITIALIZER; |
101 | 101 |
102 const char kRecordUntilFull[] = "record-until-full"; | |
103 const char kRecordContinuously[] = "record-continuously"; | |
104 const char kEnableSampling[] = "enable-sampling"; | |
105 const char kMonitorSampling[] = "monitor-sampling"; | |
106 | |
107 TimeTicks ThreadNow() { | 102 TimeTicks ThreadNow() { |
108 return TimeTicks::IsThreadNowSupported() ? | 103 return TimeTicks::IsThreadNowSupported() ? |
109 TimeTicks::ThreadNow() : TimeTicks(); | 104 TimeTicks::ThreadNow() : TimeTicks(); |
110 } | 105 } |
111 | 106 |
112 class TraceBufferRingBuffer : public TraceBuffer { | 107 class TraceBufferRingBuffer : public TraceBuffer { |
113 public: | 108 public: |
114 TraceBufferRingBuffer(size_t max_chunks) | 109 TraceBufferRingBuffer(size_t max_chunks) |
115 : max_chunks_(max_chunks), | 110 : max_chunks_(max_chunks), |
116 recyclable_chunks_queue_(new size_t[queue_capacity()]), | 111 recyclable_chunks_queue_(new size_t[queue_capacity()]), |
(...skipping 820 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
937 // TraceLog | 932 // TraceLog |
938 // | 933 // |
939 //////////////////////////////////////////////////////////////////////////////// | 934 //////////////////////////////////////////////////////////////////////////////// |
940 | 935 |
941 class TraceLog::ThreadLocalEventBuffer | 936 class TraceLog::ThreadLocalEventBuffer |
942 : public MessageLoop::DestructionObserver { | 937 : public MessageLoop::DestructionObserver { |
943 public: | 938 public: |
944 ThreadLocalEventBuffer(TraceLog* trace_log); | 939 ThreadLocalEventBuffer(TraceLog* trace_log); |
945 virtual ~ThreadLocalEventBuffer(); | 940 virtual ~ThreadLocalEventBuffer(); |
946 | 941 |
947 TraceEvent* AddTraceEvent(NotificationHelper* notifier, | 942 TraceEvent* AddTraceEvent(TraceEventHandle* handle); |
948 TraceEventHandle* handle); | |
949 | 943 |
950 void ReportOverhead(const TimeTicks& event_timestamp, | 944 void ReportOverhead(const TimeTicks& event_timestamp, |
951 const TimeTicks& event_thread_timestamp, | 945 const TimeTicks& event_thread_timestamp); |
952 NotificationHelper* notifier); | |
953 | 946 |
954 TraceEvent* GetEventByHandle(TraceEventHandle handle) { | 947 TraceEvent* GetEventByHandle(TraceEventHandle handle) { |
955 if (!chunk_ || handle.chunk_seq != chunk_->seq() || | 948 if (!chunk_ || handle.chunk_seq != chunk_->seq() || |
956 handle.chunk_index != chunk_index_) | 949 handle.chunk_index != chunk_index_) |
957 return NULL; | 950 return NULL; |
958 | 951 |
959 return chunk_->GetEventAt(handle.event_index); | 952 return chunk_->GetEventAt(handle.event_index); |
960 } | 953 } |
961 | 954 |
962 int generation() const { return generation_; } | 955 int generation() const { return generation_; } |
(...skipping 36 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
999 | 992 |
1000 TraceLog::ThreadLocalEventBuffer::~ThreadLocalEventBuffer() { | 993 TraceLog::ThreadLocalEventBuffer::~ThreadLocalEventBuffer() { |
1001 CheckThisIsCurrentBuffer(); | 994 CheckThisIsCurrentBuffer(); |
1002 MessageLoop::current()->RemoveDestructionObserver(this); | 995 MessageLoop::current()->RemoveDestructionObserver(this); |
1003 | 996 |
1004 // Zero event_count_ happens in either of the following cases: | 997 // Zero event_count_ happens in either of the following cases: |
1005 // - no event generated for the thread; | 998 // - no event generated for the thread; |
1006 // - the thread has no message loop; | 999 // - the thread has no message loop; |
1007 // - trace_event_overhead is disabled. | 1000 // - trace_event_overhead is disabled. |
1008 if (event_count_) { | 1001 if (event_count_) { |
1009 NotificationHelper notifier(trace_log_); | 1002 InitializeMetadataEvent(AddTraceEvent(NULL), |
1010 InitializeMetadataEvent(AddTraceEvent(¬ifier, NULL), | |
1011 static_cast<int>(base::PlatformThread::CurrentId()), | 1003 static_cast<int>(base::PlatformThread::CurrentId()), |
1012 "overhead", "average_overhead", | 1004 "overhead", "average_overhead", |
1013 overhead_.InMillisecondsF() / event_count_); | 1005 overhead_.InMillisecondsF() / event_count_); |
1014 notifier.SendNotificationIfAny(); | |
1015 } | 1006 } |
1016 | 1007 |
1017 { | 1008 { |
1018 AutoLock lock(trace_log_->lock_); | 1009 AutoLock lock(trace_log_->lock_); |
1019 FlushWhileLocked(); | 1010 FlushWhileLocked(); |
1020 trace_log_->thread_message_loops_.erase(MessageLoop::current()); | 1011 trace_log_->thread_message_loops_.erase(MessageLoop::current()); |
1021 } | 1012 } |
1022 trace_log_->thread_local_event_buffer_.Set(NULL); | 1013 trace_log_->thread_local_event_buffer_.Set(NULL); |
1023 } | 1014 } |
1024 | 1015 |
1025 TraceEvent* TraceLog::ThreadLocalEventBuffer::AddTraceEvent( | 1016 TraceEvent* TraceLog::ThreadLocalEventBuffer::AddTraceEvent( |
1026 NotificationHelper* notifier, | |
1027 TraceEventHandle* handle) { | 1017 TraceEventHandle* handle) { |
1028 CheckThisIsCurrentBuffer(); | 1018 CheckThisIsCurrentBuffer(); |
1029 | 1019 |
1030 if (chunk_ && chunk_->IsFull()) { | 1020 if (chunk_ && chunk_->IsFull()) { |
1031 AutoLock lock(trace_log_->lock_); | 1021 AutoLock lock(trace_log_->lock_); |
1032 FlushWhileLocked(); | 1022 FlushWhileLocked(); |
1033 chunk_.reset(); | 1023 chunk_.reset(); |
1034 } | 1024 } |
1035 if (!chunk_) { | 1025 if (!chunk_) { |
1036 AutoLock lock(trace_log_->lock_); | 1026 AutoLock lock(trace_log_->lock_); |
1037 chunk_ = trace_log_->logged_events_->GetChunk(&chunk_index_); | 1027 chunk_ = trace_log_->logged_events_->GetChunk(&chunk_index_); |
1038 trace_log_->CheckIfBufferIsFullWhileLocked(notifier); | 1028 trace_log_->CheckIfBufferIsFullWhileLocked(); |
1039 } | 1029 } |
1040 if (!chunk_) | 1030 if (!chunk_) |
1041 return NULL; | 1031 return NULL; |
1042 | 1032 |
1043 size_t event_index; | 1033 size_t event_index; |
1044 TraceEvent* trace_event = chunk_->AddTraceEvent(&event_index); | 1034 TraceEvent* trace_event = chunk_->AddTraceEvent(&event_index); |
1045 if (trace_event && handle) | 1035 if (trace_event && handle) |
1046 MakeHandle(chunk_->seq(), chunk_index_, event_index, handle); | 1036 MakeHandle(chunk_->seq(), chunk_index_, event_index, handle); |
1047 | 1037 |
1048 return trace_event; | 1038 return trace_event; |
1049 } | 1039 } |
1050 | 1040 |
1051 void TraceLog::ThreadLocalEventBuffer::ReportOverhead( | 1041 void TraceLog::ThreadLocalEventBuffer::ReportOverhead( |
1052 const TimeTicks& event_timestamp, | 1042 const TimeTicks& event_timestamp, |
1053 const TimeTicks& event_thread_timestamp, | 1043 const TimeTicks& event_thread_timestamp) { |
1054 NotificationHelper* notifier) { | |
1055 if (!g_category_group_enabled[g_category_trace_event_overhead]) | 1044 if (!g_category_group_enabled[g_category_trace_event_overhead]) |
1056 return; | 1045 return; |
1057 | 1046 |
1058 CheckThisIsCurrentBuffer(); | 1047 CheckThisIsCurrentBuffer(); |
1059 | 1048 |
1060 event_count_++; | 1049 event_count_++; |
1061 TimeTicks now = trace_log_->OffsetNow(); | 1050 TimeTicks now = trace_log_->OffsetNow(); |
1062 TimeDelta overhead = now - event_timestamp; | 1051 TimeDelta overhead = now - event_timestamp; |
1063 if (overhead.InMicroseconds() >= kOverheadReportThresholdInMicroseconds) { | 1052 if (overhead.InMicroseconds() >= kOverheadReportThresholdInMicroseconds) { |
1064 TraceEvent* trace_event = AddTraceEvent(notifier, NULL); | 1053 TraceEvent* trace_event = AddTraceEvent(NULL); |
1065 if (trace_event) { | 1054 if (trace_event) { |
1066 trace_event->Initialize( | 1055 trace_event->Initialize( |
1067 static_cast<int>(PlatformThread::CurrentId()), | 1056 static_cast<int>(PlatformThread::CurrentId()), |
1068 event_timestamp, event_thread_timestamp, | 1057 event_timestamp, event_thread_timestamp, |
1069 TRACE_EVENT_PHASE_COMPLETE, | 1058 TRACE_EVENT_PHASE_COMPLETE, |
1070 &g_category_group_enabled[g_category_trace_event_overhead], | 1059 &g_category_group_enabled[g_category_trace_event_overhead], |
1071 "overhead", 0, 0, NULL, NULL, NULL, NULL, 0); | 1060 "overhead", 0, 0, NULL, NULL, NULL, NULL, 0); |
1072 trace_event->UpdateDuration(now); | 1061 trace_event->UpdateDuration(now); |
1073 } | 1062 } |
1074 } | 1063 } |
(...skipping 10 matching lines...) Expand all Loading... |
1085 | 1074 |
1086 trace_log_->lock_.AssertAcquired(); | 1075 trace_log_->lock_.AssertAcquired(); |
1087 if (trace_log_->CheckGeneration(generation_)) { | 1076 if (trace_log_->CheckGeneration(generation_)) { |
1088 // Return the chunk to the buffer only if the generation matches, | 1077 // Return the chunk to the buffer only if the generation matches, |
1089 trace_log_->logged_events_->ReturnChunk(chunk_index_, chunk_.Pass()); | 1078 trace_log_->logged_events_->ReturnChunk(chunk_index_, chunk_.Pass()); |
1090 } | 1079 } |
1091 // Otherwise this method may be called from the destructor, or TraceLog will | 1080 // Otherwise this method may be called from the destructor, or TraceLog will |
1092 // find the generation mismatch and delete this buffer soon. | 1081 // find the generation mismatch and delete this buffer soon. |
1093 } | 1082 } |
1094 | 1083 |
1095 TraceLog::NotificationHelper::NotificationHelper(TraceLog* trace_log) | |
1096 : trace_log_(trace_log), | |
1097 notification_(0) { | |
1098 } | |
1099 | |
1100 TraceLog::NotificationHelper::~NotificationHelper() { | |
1101 } | |
1102 | |
1103 void TraceLog::NotificationHelper::AddNotificationWhileLocked( | |
1104 int notification) { | |
1105 trace_log_->lock_.AssertAcquired(); | |
1106 if (trace_log_->notification_callback_.is_null()) | |
1107 return; | |
1108 if (notification_ == 0) | |
1109 callback_copy_ = trace_log_->notification_callback_; | |
1110 notification_ |= notification; | |
1111 } | |
1112 | |
1113 void TraceLog::NotificationHelper::SendNotificationIfAny() { | |
1114 if (notification_) | |
1115 callback_copy_.Run(notification_); | |
1116 } | |
1117 | |
1118 // static | 1084 // static |
1119 TraceLog* TraceLog::GetInstance() { | 1085 TraceLog* TraceLog::GetInstance() { |
1120 return Singleton<TraceLog, LeakySingletonTraits<TraceLog> >::get(); | 1086 return Singleton<TraceLog, LeakySingletonTraits<TraceLog> >::get(); |
1121 } | 1087 } |
1122 | 1088 |
1123 // static | |
1124 // Note, if you add more options here you also need to update: | |
1125 // content/browser/devtools/devtools_tracing_handler:TraceOptionsFromString | |
1126 TraceLog::Options TraceLog::TraceOptionsFromString(const std::string& options) { | |
1127 std::vector<std::string> split; | |
1128 base::SplitString(options, ',', &split); | |
1129 int ret = 0; | |
1130 for (std::vector<std::string>::iterator iter = split.begin(); | |
1131 iter != split.end(); | |
1132 ++iter) { | |
1133 if (*iter == kRecordUntilFull) { | |
1134 ret |= RECORD_UNTIL_FULL; | |
1135 } else if (*iter == kRecordContinuously) { | |
1136 ret |= RECORD_CONTINUOUSLY; | |
1137 } else if (*iter == kEnableSampling) { | |
1138 ret |= ENABLE_SAMPLING; | |
1139 } else if (*iter == kMonitorSampling) { | |
1140 ret |= MONITOR_SAMPLING; | |
1141 } else { | |
1142 NOTREACHED(); // Unknown option provided. | |
1143 } | |
1144 } | |
1145 if (!(ret & RECORD_UNTIL_FULL) && !(ret & RECORD_CONTINUOUSLY)) | |
1146 ret |= RECORD_UNTIL_FULL; // Default when no options are specified. | |
1147 | |
1148 return static_cast<Options>(ret); | |
1149 } | |
1150 | |
1151 TraceLog::TraceLog() | 1089 TraceLog::TraceLog() |
1152 : enable_count_(0), | 1090 : enable_count_(0), |
1153 num_traces_recorded_(0), | 1091 num_traces_recorded_(0), |
1154 buffer_is_full_(0), | |
1155 event_callback_(0), | 1092 event_callback_(0), |
1156 dispatching_to_observer_list_(false), | 1093 dispatching_to_observer_list_(false), |
1157 process_sort_index_(0), | 1094 process_sort_index_(0), |
1158 process_id_hash_(0), | 1095 process_id_hash_(0), |
1159 process_id_(0), | 1096 process_id_(0), |
1160 watch_category_(0), | 1097 watch_category_(0), |
1161 trace_options_(RECORD_UNTIL_FULL), | 1098 trace_options_(RECORD_UNTIL_FULL), |
1162 sampling_thread_handle_(0), | 1099 sampling_thread_handle_(0), |
1163 category_filter_(CategoryFilter::kDefaultCategoryFilterString), | 1100 category_filter_(CategoryFilter::kDefaultCategoryFilterString), |
1164 thread_shared_chunk_index_(0), | 1101 thread_shared_chunk_index_(0), |
(...skipping 142 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
1307 | 1244 |
1308 category_filter_.Merge(category_filter); | 1245 category_filter_.Merge(category_filter); |
1309 UpdateCategoryGroupEnabledFlags(); | 1246 UpdateCategoryGroupEnabledFlags(); |
1310 return; | 1247 return; |
1311 } | 1248 } |
1312 | 1249 |
1313 if (options != old_options) { | 1250 if (options != old_options) { |
1314 subtle::NoBarrier_Store(&trace_options_, options); | 1251 subtle::NoBarrier_Store(&trace_options_, options); |
1315 logged_events_.reset(CreateTraceBuffer()); | 1252 logged_events_.reset(CreateTraceBuffer()); |
1316 NextGeneration(); | 1253 NextGeneration(); |
1317 subtle::NoBarrier_Store(&buffer_is_full_, 0); | |
1318 } | 1254 } |
1319 | 1255 |
1320 if (dispatching_to_observer_list_) { | 1256 if (dispatching_to_observer_list_) { |
1321 DLOG(ERROR) << | 1257 DLOG(ERROR) << |
1322 "Cannot manipulate TraceLog::Enabled state from an observer."; | 1258 "Cannot manipulate TraceLog::Enabled state from an observer."; |
1323 return; | 1259 return; |
1324 } | 1260 } |
1325 | 1261 |
1326 num_traces_recorded_++; | 1262 num_traces_recorded_++; |
1327 | 1263 |
(...skipping 107 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
1435 | 1371 |
1436 bool TraceLog::HasEnabledStateObserver(EnabledStateObserver* listener) const { | 1372 bool TraceLog::HasEnabledStateObserver(EnabledStateObserver* listener) const { |
1437 std::vector<EnabledStateObserver*>::const_iterator it = | 1373 std::vector<EnabledStateObserver*>::const_iterator it = |
1438 std::find(enabled_state_observer_list_.begin(), | 1374 std::find(enabled_state_observer_list_.begin(), |
1439 enabled_state_observer_list_.end(), | 1375 enabled_state_observer_list_.end(), |
1440 listener); | 1376 listener); |
1441 return it != enabled_state_observer_list_.end(); | 1377 return it != enabled_state_observer_list_.end(); |
1442 } | 1378 } |
1443 | 1379 |
1444 float TraceLog::GetBufferPercentFull() const { | 1380 float TraceLog::GetBufferPercentFull() const { |
| 1381 AutoLock lock(lock_); |
1445 return static_cast<float>(static_cast<double>(logged_events_->Size()) / | 1382 return static_cast<float>(static_cast<double>(logged_events_->Size()) / |
1446 logged_events_->Capacity()); | 1383 logged_events_->Capacity()); |
1447 } | 1384 } |
1448 | 1385 |
1449 void TraceLog::SetNotificationCallback( | 1386 bool TraceLog::BufferIsFull() const { |
1450 const TraceLog::NotificationCallback& cb) { | |
1451 AutoLock lock(lock_); | 1387 AutoLock lock(lock_); |
1452 notification_callback_ = cb; | 1388 return logged_events_->IsFull(); |
1453 } | 1389 } |
1454 | 1390 |
1455 TraceBuffer* TraceLog::CreateTraceBuffer() { | 1391 TraceBuffer* TraceLog::CreateTraceBuffer() { |
1456 Options options = trace_options(); | 1392 Options options = trace_options(); |
1457 if (options & RECORD_CONTINUOUSLY) | 1393 if (options & RECORD_CONTINUOUSLY) |
1458 return new TraceBufferRingBuffer(kTraceEventRingBufferChunks); | 1394 return new TraceBufferRingBuffer(kTraceEventRingBufferChunks); |
1459 else if (options & MONITOR_SAMPLING) | 1395 else if (options & MONITOR_SAMPLING) |
1460 return new TraceBufferRingBuffer(kMonitorTraceEventBufferChunks); | 1396 return new TraceBufferRingBuffer(kMonitorTraceEventBufferChunks); |
1461 else if (options & ECHO_TO_CONSOLE) | 1397 else if (options & ECHO_TO_CONSOLE) |
1462 return new TraceBufferRingBuffer(kEchoToConsoleTraceEventBufferChunks); | 1398 return new TraceBufferRingBuffer(kEchoToConsoleTraceEventBufferChunks); |
1463 return new TraceBufferVector(); | 1399 return new TraceBufferVector(); |
1464 } | 1400 } |
1465 | 1401 |
1466 TraceEvent* TraceLog::AddEventToThreadSharedChunkWhileLocked( | 1402 TraceEvent* TraceLog::AddEventToThreadSharedChunkWhileLocked( |
1467 NotificationHelper* notifier, TraceEventHandle* handle) { | 1403 TraceEventHandle* handle) { |
1468 lock_.AssertAcquired(); | 1404 lock_.AssertAcquired(); |
1469 | 1405 |
1470 if (thread_shared_chunk_ && thread_shared_chunk_->IsFull()) { | 1406 if (thread_shared_chunk_ && thread_shared_chunk_->IsFull()) { |
1471 logged_events_->ReturnChunk(thread_shared_chunk_index_, | 1407 logged_events_->ReturnChunk(thread_shared_chunk_index_, |
1472 thread_shared_chunk_.Pass()); | 1408 thread_shared_chunk_.Pass()); |
1473 } | 1409 } |
1474 | 1410 |
1475 if (!thread_shared_chunk_) { | 1411 if (!thread_shared_chunk_) { |
1476 thread_shared_chunk_ = logged_events_->GetChunk( | 1412 thread_shared_chunk_ = logged_events_->GetChunk( |
1477 &thread_shared_chunk_index_); | 1413 &thread_shared_chunk_index_); |
1478 if (notifier) | 1414 CheckIfBufferIsFullWhileLocked(); |
1479 CheckIfBufferIsFullWhileLocked(notifier); | |
1480 } | 1415 } |
1481 if (!thread_shared_chunk_) | 1416 if (!thread_shared_chunk_) |
1482 return NULL; | 1417 return NULL; |
1483 | 1418 |
1484 size_t event_index; | 1419 size_t event_index; |
1485 TraceEvent* trace_event = thread_shared_chunk_->AddTraceEvent(&event_index); | 1420 TraceEvent* trace_event = thread_shared_chunk_->AddTraceEvent(&event_index); |
1486 if (trace_event && handle) { | 1421 if (trace_event && handle) { |
1487 MakeHandle(thread_shared_chunk_->seq(), thread_shared_chunk_index_, | 1422 MakeHandle(thread_shared_chunk_->seq(), thread_shared_chunk_index_, |
1488 event_index, handle); | 1423 event_index, handle); |
1489 } | 1424 } |
1490 return trace_event; | 1425 return trace_event; |
1491 } | 1426 } |
1492 | 1427 |
1493 void TraceLog::CheckIfBufferIsFullWhileLocked(NotificationHelper* notifier) { | 1428 void TraceLog::CheckIfBufferIsFullWhileLocked() { |
1494 lock_.AssertAcquired(); | 1429 lock_.AssertAcquired(); |
1495 if (!subtle::NoBarrier_Load(&buffer_is_full_) && logged_events_->IsFull()) { | 1430 if (logged_events_->IsFull()) |
1496 subtle::NoBarrier_Store(&buffer_is_full_, | 1431 SetDisabled(); |
1497 static_cast<subtle::AtomicWord>(1)); | |
1498 notifier->AddNotificationWhileLocked(TRACE_BUFFER_FULL); | |
1499 } | |
1500 } | 1432 } |
1501 | 1433 |
1502 void TraceLog::SetEventCallback(EventCallback cb) { | 1434 void TraceLog::SetEventCallback(EventCallback cb) { |
1503 subtle::NoBarrier_Store(&event_callback_, | 1435 subtle::NoBarrier_Store(&event_callback_, |
1504 reinterpret_cast<subtle::AtomicWord>(cb)); | 1436 reinterpret_cast<subtle::AtomicWord>(cb)); |
1505 }; | 1437 }; |
1506 | 1438 |
1507 // Flush() works as the following: | 1439 // Flush() works as the following: |
1508 // 1. Flush() is called in threadA whose message loop is saved in | 1440 // 1. Flush() is called in threadA whose message loop is saved in |
1509 // flush_message_loop_proxy_; | 1441 // flush_message_loop_proxy_; |
(...skipping 86 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
1596 | 1528 |
1597 if (!CheckGeneration(generation)) | 1529 if (!CheckGeneration(generation)) |
1598 return; | 1530 return; |
1599 | 1531 |
1600 { | 1532 { |
1601 AutoLock lock(lock_); | 1533 AutoLock lock(lock_); |
1602 | 1534 |
1603 previous_logged_events.swap(logged_events_); | 1535 previous_logged_events.swap(logged_events_); |
1604 logged_events_.reset(CreateTraceBuffer()); | 1536 logged_events_.reset(CreateTraceBuffer()); |
1605 NextGeneration(); | 1537 NextGeneration(); |
1606 subtle::NoBarrier_Store(&buffer_is_full_, 0); | |
1607 thread_message_loops_.clear(); | 1538 thread_message_loops_.clear(); |
1608 | 1539 |
1609 flush_message_loop_proxy_ = NULL; | 1540 flush_message_loop_proxy_ = NULL; |
1610 flush_output_callback = flush_output_callback_; | 1541 flush_output_callback = flush_output_callback_; |
1611 flush_output_callback_.Reset(); | 1542 flush_output_callback_.Reset(); |
1612 } | 1543 } |
1613 | 1544 |
1614 ConvertTraceEventsToTraceFormat(previous_logged_events.Pass(), | 1545 ConvertTraceEventsToTraceFormat(previous_logged_events.Pass(), |
1615 flush_output_callback); | 1546 flush_output_callback); |
1616 } | 1547 } |
(...skipping 102 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
1719 return handle; | 1650 return handle; |
1720 | 1651 |
1721 DCHECK(name); | 1652 DCHECK(name); |
1722 | 1653 |
1723 if (flags & TRACE_EVENT_FLAG_MANGLE_ID) | 1654 if (flags & TRACE_EVENT_FLAG_MANGLE_ID) |
1724 id ^= process_id_hash_; | 1655 id ^= process_id_hash_; |
1725 | 1656 |
1726 TimeTicks now = OffsetTimestamp(timestamp); | 1657 TimeTicks now = OffsetTimestamp(timestamp); |
1727 TimeTicks thread_now = ThreadNow(); | 1658 TimeTicks thread_now = ThreadNow(); |
1728 | 1659 |
1729 NotificationHelper notifier(this); | |
1730 | |
1731 ThreadLocalEventBuffer* thread_local_event_buffer = NULL; | 1660 ThreadLocalEventBuffer* thread_local_event_buffer = NULL; |
1732 // A ThreadLocalEventBuffer needs the message loop | 1661 // A ThreadLocalEventBuffer needs the message loop |
1733 // - to know when the thread exits; | 1662 // - to know when the thread exits; |
1734 // - to handle the final flush. | 1663 // - to handle the final flush. |
1735 // For a thread without a message loop or the message loop may be blocked, the | 1664 // For a thread without a message loop or the message loop may be blocked, the |
1736 // trace events will be added into the main buffer directly. | 1665 // trace events will be added into the main buffer directly. |
1737 if (!thread_blocks_message_loop_.Get() && MessageLoop::current()) { | 1666 if (!thread_blocks_message_loop_.Get() && MessageLoop::current()) { |
1738 thread_local_event_buffer = thread_local_event_buffer_.Get(); | 1667 thread_local_event_buffer = thread_local_event_buffer_.Get(); |
1739 if (thread_local_event_buffer && | 1668 if (thread_local_event_buffer && |
1740 !CheckGeneration(thread_local_event_buffer->generation())) { | 1669 !CheckGeneration(thread_local_event_buffer->generation())) { |
(...skipping 36 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
1777 new_name) != existing_names.end(); | 1706 new_name) != existing_names.end(); |
1778 if (!found) { | 1707 if (!found) { |
1779 existing_name->second.push_back(','); | 1708 existing_name->second.push_back(','); |
1780 existing_name->second.append(new_name); | 1709 existing_name->second.append(new_name); |
1781 } | 1710 } |
1782 } | 1711 } |
1783 } | 1712 } |
1784 } | 1713 } |
1785 | 1714 |
1786 TraceEvent* trace_event = NULL; | 1715 TraceEvent* trace_event = NULL; |
1787 if (!subtle::NoBarrier_Load(&buffer_is_full_)) { | 1716 if (thread_local_event_buffer) { |
1788 if (thread_local_event_buffer) { | 1717 lock.EnsureReleased(); |
1789 lock.EnsureReleased(); | 1718 trace_event = thread_local_event_buffer->AddTraceEvent(&handle); |
1790 trace_event = thread_local_event_buffer->AddTraceEvent(¬ifier, | 1719 } else { |
1791 &handle); | 1720 lock.EnsureAcquired(); |
1792 } else { | 1721 trace_event = AddEventToThreadSharedChunkWhileLocked(&handle); |
1793 lock.EnsureAcquired(); | 1722 } |
1794 trace_event = AddEventToThreadSharedChunkWhileLocked(¬ifier, &handle); | |
1795 } | |
1796 | 1723 |
1797 if (trace_event) { | 1724 if (trace_event) { |
1798 trace_event->Initialize(thread_id, now, thread_now, phase, | 1725 trace_event->Initialize(thread_id, now, thread_now, phase, |
1799 category_group_enabled, name, id, | 1726 category_group_enabled, name, id, |
1800 num_args, arg_names, arg_types, arg_values, | 1727 num_args, arg_names, arg_types, arg_values, |
1801 convertable_values, flags); | 1728 convertable_values, flags); |
1802 | 1729 |
1803 #if defined(OS_ANDROID) | 1730 #if defined(OS_ANDROID) |
1804 trace_event->SendToATrace(); | 1731 trace_event->SendToATrace(); |
1805 #endif | 1732 #endif |
1806 } | |
1807 } | 1733 } |
1808 | 1734 |
1809 if (trace_options() & ECHO_TO_CONSOLE) { | 1735 if (trace_options() & ECHO_TO_CONSOLE) { |
1810 lock.EnsureAcquired(); | 1736 lock.EnsureAcquired(); |
1811 OutputEventToConsoleWhileLocked( | 1737 OutputEventToConsoleWhileLocked( |
1812 phase == TRACE_EVENT_PHASE_COMPLETE ? TRACE_EVENT_PHASE_BEGIN : phase, | 1738 phase == TRACE_EVENT_PHASE_COMPLETE ? TRACE_EVENT_PHASE_BEGIN : phase, |
1813 timestamp, trace_event); | 1739 timestamp, trace_event); |
1814 } | 1740 } |
1815 | 1741 |
1816 if (reinterpret_cast<const unsigned char*>(subtle::NoBarrier_Load( | 1742 if (reinterpret_cast<const unsigned char*>(subtle::NoBarrier_Load( |
1817 &watch_category_)) == category_group_enabled) { | 1743 &watch_category_)) == category_group_enabled) { |
1818 lock.EnsureAcquired(); | 1744 lock.EnsureAcquired(); |
1819 if (watch_event_name_ == name) | 1745 if (watch_event_name_ == name) { |
1820 notifier.AddNotificationWhileLocked(EVENT_WATCH_NOTIFICATION); | 1746 WatchEventCallback watch_event_callback_copy = watch_event_callback_; |
| 1747 lock.EnsureReleased(); |
| 1748 if (!watch_event_callback_copy.is_null()) |
| 1749 watch_event_callback_copy.Run(); |
| 1750 } |
1821 } | 1751 } |
1822 | 1752 |
1823 lock.EnsureReleased(); | 1753 lock.EnsureReleased(); |
1824 EventCallback event_callback = reinterpret_cast<EventCallback>( | 1754 EventCallback event_callback = reinterpret_cast<EventCallback>( |
1825 subtle::NoBarrier_Load(&event_callback_)); | 1755 subtle::NoBarrier_Load(&event_callback_)); |
1826 if (event_callback) { | 1756 if (event_callback) { |
1827 // TODO(wangxianzhu): Should send TRACE_EVENT_PHASE_COMPLETE directly to | 1757 // TODO(wangxianzhu): Should send TRACE_EVENT_PHASE_COMPLETE directly to |
1828 // clients if it is beneficial and feasible. | 1758 // clients if it is beneficial and feasible. |
1829 event_callback(now, | 1759 event_callback(now, |
1830 phase == TRACE_EVENT_PHASE_COMPLETE ? | 1760 phase == TRACE_EVENT_PHASE_COMPLETE ? |
1831 TRACE_EVENT_PHASE_BEGIN : phase, | 1761 TRACE_EVENT_PHASE_BEGIN : phase, |
1832 category_group_enabled, name, id, | 1762 category_group_enabled, name, id, |
1833 num_args, arg_names, arg_types, arg_values, | 1763 num_args, arg_names, arg_types, arg_values, |
1834 flags); | 1764 flags); |
1835 } | 1765 } |
1836 | 1766 |
1837 if (thread_local_event_buffer) | 1767 if (thread_local_event_buffer) |
1838 thread_local_event_buffer->ReportOverhead(now, thread_now, ¬ifier); | 1768 thread_local_event_buffer->ReportOverhead(now, thread_now); |
1839 | |
1840 notifier.SendNotificationIfAny(); | |
1841 | 1769 |
1842 return handle; | 1770 return handle; |
1843 } | 1771 } |
1844 | 1772 |
1845 // May be called when a COMPELETE event ends and the unfinished event has been | 1773 // May be called when a COMPELETE event ends and the unfinished event has been |
1846 // recycled (phase == TRACE_EVENT_PHASE_END and trace_event == NULL). | 1774 // recycled (phase == TRACE_EVENT_PHASE_END and trace_event == NULL). |
1847 void TraceLog::OutputEventToConsoleWhileLocked(unsigned char phase, | 1775 void TraceLog::OutputEventToConsoleWhileLocked(unsigned char phase, |
1848 const TimeTicks& timestamp, | 1776 const TimeTicks& timestamp, |
1849 TraceEvent* trace_event) { | 1777 TraceEvent* trace_event) { |
1850 // The caller should translate TRACE_EVENT_PHASE_COMPLETE to | 1778 // The caller should translate TRACE_EVENT_PHASE_COMPLETE to |
(...skipping 89 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
1940 // TODO(wangxianzhu): Should send TRACE_EVENT_PHASE_COMPLETE directly to | 1868 // TODO(wangxianzhu): Should send TRACE_EVENT_PHASE_COMPLETE directly to |
1941 // clients if it is beneficial and feasible. | 1869 // clients if it is beneficial and feasible. |
1942 event_callback(now, TRACE_EVENT_PHASE_END, | 1870 event_callback(now, TRACE_EVENT_PHASE_END, |
1943 event_copy.category_group_enabled(), | 1871 event_copy.category_group_enabled(), |
1944 event_copy.name(), event_copy.id(), | 1872 event_copy.name(), event_copy.id(), |
1945 0, NULL, NULL, NULL, event_copy.flags()); | 1873 0, NULL, NULL, NULL, event_copy.flags()); |
1946 } | 1874 } |
1947 } | 1875 } |
1948 | 1876 |
1949 void TraceLog::SetWatchEvent(const std::string& category_name, | 1877 void TraceLog::SetWatchEvent(const std::string& category_name, |
1950 const std::string& event_name) { | 1878 const std::string& event_name, |
| 1879 const WatchEventCallback& callback) { |
1951 const unsigned char* category = GetCategoryGroupEnabled( | 1880 const unsigned char* category = GetCategoryGroupEnabled( |
1952 category_name.c_str()); | 1881 category_name.c_str()); |
1953 AutoLock lock(lock_); | 1882 AutoLock lock(lock_); |
1954 subtle::NoBarrier_Store(&watch_category_, | 1883 subtle::NoBarrier_Store(&watch_category_, |
1955 reinterpret_cast<subtle::AtomicWord>(category)); | 1884 reinterpret_cast<subtle::AtomicWord>(category)); |
1956 watch_event_name_ = event_name; | 1885 watch_event_name_ = event_name; |
| 1886 watch_event_callback_ = callback; |
1957 } | 1887 } |
1958 | 1888 |
1959 void TraceLog::CancelWatchEvent() { | 1889 void TraceLog::CancelWatchEvent() { |
1960 AutoLock lock(lock_); | 1890 AutoLock lock(lock_); |
1961 subtle::NoBarrier_Store(&watch_category_, 0); | 1891 subtle::NoBarrier_Store(&watch_category_, 0); |
1962 watch_event_name_ = ""; | 1892 watch_event_name_ = ""; |
| 1893 watch_event_callback_.Reset(); |
1963 } | 1894 } |
1964 | 1895 |
1965 void TraceLog::AddMetadataEventsWhileLocked() { | 1896 void TraceLog::AddMetadataEventsWhileLocked() { |
1966 lock_.AssertAcquired(); | 1897 lock_.AssertAcquired(); |
1967 | 1898 |
1968 int current_thread_id = static_cast<int>(base::PlatformThread::CurrentId()); | 1899 int current_thread_id = static_cast<int>(base::PlatformThread::CurrentId()); |
1969 if (process_sort_index_ != 0) { | 1900 if (process_sort_index_ != 0) { |
1970 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL, NULL), | 1901 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL), |
1971 current_thread_id, | 1902 current_thread_id, |
1972 "process_sort_index", "sort_index", | 1903 "process_sort_index", "sort_index", |
1973 process_sort_index_); | 1904 process_sort_index_); |
1974 } | 1905 } |
1975 | 1906 |
1976 if (process_name_.size()) { | 1907 if (process_name_.size()) { |
1977 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL, NULL), | 1908 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL), |
1978 current_thread_id, | 1909 current_thread_id, |
1979 "process_name", "name", | 1910 "process_name", "name", |
1980 process_name_); | 1911 process_name_); |
1981 } | 1912 } |
1982 | 1913 |
1983 if (process_labels_.size() > 0) { | 1914 if (process_labels_.size() > 0) { |
1984 std::vector<std::string> labels; | 1915 std::vector<std::string> labels; |
1985 for(base::hash_map<int, std::string>::iterator it = process_labels_.begin(); | 1916 for(base::hash_map<int, std::string>::iterator it = process_labels_.begin(); |
1986 it != process_labels_.end(); | 1917 it != process_labels_.end(); |
1987 it++) { | 1918 it++) { |
1988 labels.push_back(it->second); | 1919 labels.push_back(it->second); |
1989 } | 1920 } |
1990 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL, NULL), | 1921 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL), |
1991 current_thread_id, | 1922 current_thread_id, |
1992 "process_labels", "labels", | 1923 "process_labels", "labels", |
1993 JoinString(labels, ',')); | 1924 JoinString(labels, ',')); |
1994 } | 1925 } |
1995 | 1926 |
1996 // Thread sort indices. | 1927 // Thread sort indices. |
1997 for(hash_map<int, int>::iterator it = thread_sort_indices_.begin(); | 1928 for(hash_map<int, int>::iterator it = thread_sort_indices_.begin(); |
1998 it != thread_sort_indices_.end(); | 1929 it != thread_sort_indices_.end(); |
1999 it++) { | 1930 it++) { |
2000 if (it->second == 0) | 1931 if (it->second == 0) |
2001 continue; | 1932 continue; |
2002 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL, NULL), | 1933 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL), |
2003 it->first, | 1934 it->first, |
2004 "thread_sort_index", "sort_index", | 1935 "thread_sort_index", "sort_index", |
2005 it->second); | 1936 it->second); |
2006 } | 1937 } |
2007 | 1938 |
2008 // Thread names. | 1939 // Thread names. |
2009 for(hash_map<int, std::string>::iterator it = thread_names_.begin(); | 1940 for(hash_map<int, std::string>::iterator it = thread_names_.begin(); |
2010 it != thread_names_.end(); | 1941 it != thread_names_.end(); |
2011 it++) { | 1942 it++) { |
2012 if (it->second.empty()) | 1943 if (it->second.empty()) |
2013 continue; | 1944 continue; |
2014 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL, NULL), | 1945 InitializeMetadataEvent(AddEventToThreadSharedChunkWhileLocked(NULL), |
2015 it->first, | 1946 it->first, |
2016 "thread_name", "name", | 1947 "thread_name", "name", |
2017 it->second); | 1948 it->second); |
2018 } | 1949 } |
2019 } | 1950 } |
2020 | 1951 |
2021 void TraceLog::WaitSamplingEventForTesting() { | 1952 void TraceLog::WaitSamplingEventForTesting() { |
2022 if (!sampling_thread_) | 1953 if (!sampling_thread_) |
2023 return; | 1954 return; |
2024 sampling_thread_->WaitSamplingEventForTesting(); | 1955 sampling_thread_->WaitSamplingEventForTesting(); |
(...skipping 271 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
2296 0, NULL, NULL, NULL, NULL, TRACE_EVENT_FLAG_NONE); | 2227 0, NULL, NULL, NULL, NULL, TRACE_EVENT_FLAG_NONE); |
2297 } | 2228 } |
2298 } | 2229 } |
2299 | 2230 |
2300 ScopedTraceBinaryEfficient::~ScopedTraceBinaryEfficient() { | 2231 ScopedTraceBinaryEfficient::~ScopedTraceBinaryEfficient() { |
2301 if (*category_group_enabled_) | 2232 if (*category_group_enabled_) |
2302 TRACE_EVENT_API_UPDATE_TRACE_EVENT_DURATION(event_handle_); | 2233 TRACE_EVENT_API_UPDATE_TRACE_EVENT_DURATION(event_handle_); |
2303 } | 2234 } |
2304 | 2235 |
2305 } // namespace trace_event_internal | 2236 } // namespace trace_event_internal |
OLD | NEW |