| 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 // Test of classes in the tracked_objects.h classes. | 5 // Test of classes in the tracked_objects.h classes. |
| 6 | 6 |
| 7 #include "base/tracked_objects.h" | 7 #include "base/tracked_objects.h" |
| 8 | 8 |
| 9 #include <stddef.h> | 9 #include <stddef.h> |
| 10 | 10 |
| (...skipping 53 matching lines...) Expand 10 before | Expand all | Expand 10 after Loading... |
| 64 } | 64 } |
| 65 | 65 |
| 66 // Helper function to verify the most common test expectations. | 66 // Helper function to verify the most common test expectations. |
| 67 void ExpectSimpleProcessData(const ProcessDataSnapshot& process_data, | 67 void ExpectSimpleProcessData(const ProcessDataSnapshot& process_data, |
| 68 const std::string& function_name, | 68 const std::string& function_name, |
| 69 const std::string& birth_thread, | 69 const std::string& birth_thread, |
| 70 const std::string& death_thread, | 70 const std::string& death_thread, |
| 71 int count, | 71 int count, |
| 72 int run_ms, | 72 int run_ms, |
| 73 int queue_ms) { | 73 int queue_ms) { |
| 74 ASSERT_EQ(1u, process_data.phased_process_data_snapshots.size()); | 74 ASSERT_EQ(1u, process_data.phased_snapshots.size()); |
| 75 auto it = process_data.phased_process_data_snapshots.find(0); | 75 auto it = process_data.phased_snapshots.find(0); |
| 76 ASSERT_TRUE(it != process_data.phased_process_data_snapshots.end()); | 76 ASSERT_TRUE(it != process_data.phased_snapshots.end()); |
| 77 const ProcessDataPhaseSnapshot& process_data_phase = it->second; | 77 const ProcessDataPhaseSnapshot& process_data_phase = it->second; |
| 78 | 78 |
| 79 ASSERT_EQ(1u, process_data_phase.tasks.size()); | 79 ASSERT_EQ(1u, process_data_phase.tasks.size()); |
| 80 | 80 |
| 81 EXPECT_EQ(kFile, process_data_phase.tasks[0].birth.location.file_name); | 81 EXPECT_EQ(kFile, process_data_phase.tasks[0].birth.location.file_name); |
| 82 EXPECT_EQ(function_name, | 82 EXPECT_EQ(function_name, |
| 83 process_data_phase.tasks[0].birth.location.function_name); | 83 process_data_phase.tasks[0].birth.location.function_name); |
| 84 EXPECT_EQ(kLineNumber, | 84 EXPECT_EQ(kLineNumber, |
| 85 process_data_phase.tasks[0].birth.location.line_number); | 85 process_data_phase.tasks[0].birth.location.line_number); |
| 86 | 86 |
| (...skipping 29 matching lines...) Expand all Loading... |
| 116 // Test time in milliseconds. | 116 // Test time in milliseconds. |
| 117 static unsigned int test_time_; | 117 static unsigned int test_time_; |
| 118 }; | 118 }; |
| 119 | 119 |
| 120 // static | 120 // static |
| 121 unsigned int TrackedObjectsTest::test_time_; | 121 unsigned int TrackedObjectsTest::test_time_; |
| 122 | 122 |
| 123 TEST_F(TrackedObjectsTest, TaskStopwatchNoStartStop) { | 123 TEST_F(TrackedObjectsTest, TaskStopwatchNoStartStop) { |
| 124 if (!ThreadData::InitializeAndSetTrackingStatus( | 124 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 125 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 125 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 126 // Don't run the test if task tracking is not compiled in. |
| 126 return; | 127 return; |
| 127 } | 128 } |
| 128 | 129 |
| 129 // Check that creating and destroying a stopwatch without starting it doesn't | 130 // Check that creating and destroying a stopwatch without starting it doesn't |
| 130 // crash. | 131 // crash. |
| 131 TaskStopwatch stopwatch; | 132 TaskStopwatch stopwatch; |
| 132 } | 133 } |
| 133 | 134 |
| 134 TEST_F(TrackedObjectsTest, MinimalStartupShutdown) { | 135 TEST_F(TrackedObjectsTest, MinimalStartupShutdown) { |
| 135 // Minimal test doesn't even create any tasks. | 136 // Minimal test doesn't even create any tasks. |
| 136 if (!ThreadData::InitializeAndSetTrackingStatus( | 137 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 137 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 138 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 139 // Don't run the test if task tracking is not compiled in. |
| 138 return; | 140 return; |
| 139 } | 141 } |
| 140 | 142 |
| 141 EXPECT_FALSE(ThreadData::first()); // No activity even on this thread. | 143 EXPECT_FALSE(ThreadData::first()); // No activity even on this thread. |
| 142 ThreadData* data = ThreadData::Get(); | 144 ThreadData* data = ThreadData::Get(); |
| 143 EXPECT_TRUE(ThreadData::first()); // Now class was constructed. | 145 EXPECT_TRUE(ThreadData::first()); // Now class was constructed. |
| 144 ASSERT_TRUE(data); | 146 ASSERT_TRUE(data); |
| 145 EXPECT_FALSE(data->next()); | 147 EXPECT_FALSE(data->next()); |
| 146 EXPECT_EQ(data, ThreadData::Get()); | 148 EXPECT_EQ(data, ThreadData::Get()); |
| 147 ThreadData::BirthMap birth_map; | 149 ThreadData::BirthMap birth_map; |
| 148 ThreadData::DeathMap death_map; | 150 ThreadData::DeathsSnapshot deaths; |
| 149 ThreadData::ParentChildSet parent_child_set; | 151 ThreadData::ParentChildSet parent_child_set; |
| 150 data->SnapshotMaps(&birth_map, &death_map, &parent_child_set); | 152 data->SnapshotMaps(0, &birth_map, &deaths, &parent_child_set); |
| 151 EXPECT_EQ(0u, birth_map.size()); | 153 EXPECT_EQ(0u, birth_map.size()); |
| 152 EXPECT_EQ(0u, death_map.size()); | 154 EXPECT_EQ(0u, deaths.size()); |
| 153 EXPECT_EQ(0u, parent_child_set.size()); | 155 EXPECT_EQ(0u, parent_child_set.size()); |
| 154 | 156 |
| 155 // Clean up with no leaking. | 157 // Clean up with no leaking. |
| 156 Reset(); | 158 Reset(); |
| 157 | 159 |
| 158 // Do it again, just to be sure we reset state completely. | 160 // Do it again, just to be sure we reset state completely. |
| 159 EXPECT_TRUE(ThreadData::InitializeAndSetTrackingStatus( | 161 EXPECT_TRUE(ThreadData::InitializeAndSetTrackingStatus( |
| 160 ThreadData::PROFILING_CHILDREN_ACTIVE)); | 162 ThreadData::PROFILING_CHILDREN_ACTIVE)); |
| 161 EXPECT_FALSE(ThreadData::first()); // No activity even on this thread. | 163 EXPECT_FALSE(ThreadData::first()); // No activity even on this thread. |
| 162 data = ThreadData::Get(); | 164 data = ThreadData::Get(); |
| 163 EXPECT_TRUE(ThreadData::first()); // Now class was constructed. | 165 EXPECT_TRUE(ThreadData::first()); // Now class was constructed. |
| 164 ASSERT_TRUE(data); | 166 ASSERT_TRUE(data); |
| 165 EXPECT_FALSE(data->next()); | 167 EXPECT_FALSE(data->next()); |
| 166 EXPECT_EQ(data, ThreadData::Get()); | 168 EXPECT_EQ(data, ThreadData::Get()); |
| 167 birth_map.clear(); | 169 birth_map.clear(); |
| 168 death_map.clear(); | 170 deaths.clear(); |
| 169 parent_child_set.clear(); | 171 parent_child_set.clear(); |
| 170 data->SnapshotMaps(&birth_map, &death_map, &parent_child_set); | 172 data->SnapshotMaps(0, &birth_map, &deaths, &parent_child_set); |
| 171 EXPECT_EQ(0u, birth_map.size()); | 173 EXPECT_EQ(0u, birth_map.size()); |
| 172 EXPECT_EQ(0u, death_map.size()); | 174 EXPECT_EQ(0u, deaths.size()); |
| 173 EXPECT_EQ(0u, parent_child_set.size()); | 175 EXPECT_EQ(0u, parent_child_set.size()); |
| 174 } | 176 } |
| 175 | 177 |
| 176 TEST_F(TrackedObjectsTest, TinyStartupShutdown) { | 178 TEST_F(TrackedObjectsTest, TinyStartupShutdown) { |
| 177 if (!ThreadData::InitializeAndSetTrackingStatus( | 179 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 178 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 180 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 181 // Don't run the test if task tracking is not compiled in. |
| 179 return; | 182 return; |
| 180 } | 183 } |
| 181 | 184 |
| 182 // Instigate tracking on a single tracked object, on our thread. | 185 // Instigate tracking on a single tracked object, on our thread. |
| 183 const char kFunction[] = "TinyStartupShutdown"; | 186 const char kFunction[] = "TinyStartupShutdown"; |
| 184 Location location(kFunction, kFile, kLineNumber, NULL); | 187 Location location(kFunction, kFile, kLineNumber, NULL); |
| 185 Births* first_birth = ThreadData::TallyABirthIfActive(location); | 188 Births* first_birth = ThreadData::TallyABirthIfActive(location); |
| 186 | 189 |
| 187 ThreadData* data = ThreadData::first(); | 190 ThreadData* data = ThreadData::first(); |
| 188 ASSERT_TRUE(data); | 191 ASSERT_TRUE(data); |
| 189 EXPECT_FALSE(data->next()); | 192 EXPECT_FALSE(data->next()); |
| 190 EXPECT_EQ(data, ThreadData::Get()); | 193 EXPECT_EQ(data, ThreadData::Get()); |
| 191 ThreadData::BirthMap birth_map; | 194 ThreadData::BirthMap birth_map; |
| 192 ThreadData::DeathMap death_map; | 195 ThreadData::DeathsSnapshot deaths; |
| 193 ThreadData::ParentChildSet parent_child_set; | 196 ThreadData::ParentChildSet parent_child_set; |
| 194 data->SnapshotMaps(&birth_map, &death_map, &parent_child_set); | 197 data->SnapshotMaps(0, &birth_map, &deaths, &parent_child_set); |
| 195 EXPECT_EQ(1u, birth_map.size()); // 1 birth location. | 198 EXPECT_EQ(1u, birth_map.size()); // 1 birth location. |
| 196 EXPECT_EQ(1, birth_map.begin()->second->birth_count()); // 1 birth. | 199 EXPECT_EQ(1, birth_map.begin()->second->birth_count()); // 1 birth. |
| 197 EXPECT_EQ(0u, death_map.size()); // No deaths. | 200 EXPECT_EQ(0u, deaths.size()); // No deaths. |
| 198 EXPECT_EQ(0u, parent_child_set.size()); // No children. | 201 EXPECT_EQ(0u, parent_child_set.size()); // No children. |
| 199 | 202 |
| 200 | 203 |
| 201 // Now instigate another birth, while we are timing the run of the first | 204 // Now instigate another birth, while we are timing the run of the first |
| 202 // execution. | 205 // execution. |
| 203 ThreadData::PrepareForStartOfRun(first_birth); | 206 ThreadData::PrepareForStartOfRun(first_birth); |
| 204 // Create a child (using the same birth location). | 207 // Create a child (using the same birth location). |
| 205 // TrackingInfo will call TallyABirth() during construction. | 208 // TrackingInfo will call TallyABirth() during construction. |
| 206 const int32 start_time = 1; | 209 const int32 start_time = 1; |
| 207 base::TimeTicks kBogusBirthTime = base::TimeTicks() + | 210 base::TimeTicks kBogusBirthTime = base::TimeTicks() + |
| 208 base::TimeDelta::FromMilliseconds(start_time); | 211 base::TimeDelta::FromMilliseconds(start_time); |
| 209 base::TrackingInfo pending_task(location, kBogusBirthTime); | 212 base::TrackingInfo pending_task(location, kBogusBirthTime); |
| 210 SetTestTime(1); | 213 SetTestTime(1); |
| 211 TaskStopwatch stopwatch; | 214 TaskStopwatch stopwatch; |
| 212 stopwatch.Start(); | 215 stopwatch.Start(); |
| 213 // Finally conclude the outer run. | 216 // Finally conclude the outer run. |
| 214 const int32 time_elapsed = 1000; | 217 const int32 time_elapsed = 1000; |
| 215 SetTestTime(start_time + time_elapsed); | 218 SetTestTime(start_time + time_elapsed); |
| 216 stopwatch.Stop(); | 219 stopwatch.Stop(); |
| 217 | 220 |
| 218 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); | 221 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 219 | 222 |
| 220 birth_map.clear(); | 223 birth_map.clear(); |
| 221 death_map.clear(); | 224 deaths.clear(); |
| 222 parent_child_set.clear(); | 225 parent_child_set.clear(); |
| 223 data->SnapshotMaps(&birth_map, &death_map, &parent_child_set); | 226 data->SnapshotMaps(0, &birth_map, &deaths, &parent_child_set); |
| 224 EXPECT_EQ(1u, birth_map.size()); // 1 birth location. | 227 EXPECT_EQ(1u, birth_map.size()); // 1 birth location. |
| 225 EXPECT_EQ(2, birth_map.begin()->second->birth_count()); // 2 births. | 228 EXPECT_EQ(2, birth_map.begin()->second->birth_count()); // 2 births. |
| 226 EXPECT_EQ(1u, death_map.size()); // 1 location. | 229 EXPECT_EQ(1u, deaths.size()); // 1 location. |
| 227 EXPECT_EQ(1, death_map.begin()->second.count()); // 1 death. | 230 EXPECT_EQ(1, deaths.begin()->second.death_data.count); // 1 death. |
| 228 if (ThreadData::TrackingParentChildStatus()) { | 231 if (ThreadData::TrackingParentChildStatus()) { |
| 229 EXPECT_EQ(1u, parent_child_set.size()); // 1 child. | 232 EXPECT_EQ(1u, parent_child_set.size()); // 1 child. |
| 230 EXPECT_EQ(parent_child_set.begin()->first, | 233 EXPECT_EQ(parent_child_set.begin()->first, |
| 231 parent_child_set.begin()->second); | 234 parent_child_set.begin()->second); |
| 232 } else { | 235 } else { |
| 233 EXPECT_EQ(0u, parent_child_set.size()); // no stats. | 236 EXPECT_EQ(0u, parent_child_set.size()); // no stats. |
| 234 } | 237 } |
| 235 | 238 |
| 236 // The births were at the same location as the one known death. | 239 // The births were at the same location as the one known death. |
| 237 EXPECT_EQ(birth_map.begin()->second, death_map.begin()->first); | 240 EXPECT_EQ(birth_map.begin()->second, deaths.begin()->first); |
| 238 | 241 |
| 239 ProcessDataSnapshot process_data; | 242 ProcessDataSnapshot process_data; |
| 240 ThreadData::Snapshot(&process_data); | 243 ThreadData::Snapshot(0, &process_data); |
| 241 | 244 |
| 242 ASSERT_EQ(1u, process_data.phased_process_data_snapshots.size()); | 245 ASSERT_EQ(1u, process_data.phased_snapshots.size()); |
| 243 auto it = process_data.phased_process_data_snapshots.find(0); | 246 auto it = process_data.phased_snapshots.find(0); |
| 244 ASSERT_TRUE(it != process_data.phased_process_data_snapshots.end()); | 247 ASSERT_TRUE(it != process_data.phased_snapshots.end()); |
| 245 const ProcessDataPhaseSnapshot& process_data_phase = it->second; | 248 const ProcessDataPhaseSnapshot& process_data_phase = it->second; |
| 246 ASSERT_EQ(1u, process_data_phase.tasks.size()); | 249 ASSERT_EQ(1u, process_data_phase.tasks.size()); |
| 247 EXPECT_EQ(kFile, process_data_phase.tasks[0].birth.location.file_name); | 250 EXPECT_EQ(kFile, process_data_phase.tasks[0].birth.location.file_name); |
| 248 EXPECT_EQ(kFunction, | 251 EXPECT_EQ(kFunction, |
| 249 process_data_phase.tasks[0].birth.location.function_name); | 252 process_data_phase.tasks[0].birth.location.function_name); |
| 250 EXPECT_EQ(kLineNumber, | 253 EXPECT_EQ(kLineNumber, |
| 251 process_data_phase.tasks[0].birth.location.line_number); | 254 process_data_phase.tasks[0].birth.location.line_number); |
| 252 EXPECT_EQ(kWorkerThreadName, process_data_phase.tasks[0].birth.thread_name); | 255 EXPECT_EQ(kWorkerThreadName, process_data_phase.tasks[0].birth.thread_name); |
| 253 EXPECT_EQ(1, process_data_phase.tasks[0].death_data.count); | 256 EXPECT_EQ(1, process_data_phase.tasks[0].death_data.count); |
| 254 EXPECT_EQ(time_elapsed, | 257 EXPECT_EQ(time_elapsed, |
| (...skipping 23 matching lines...) Expand all Loading... |
| 278 process_data_phase.descendants[0].child.location.function_name); | 281 process_data_phase.descendants[0].child.location.function_name); |
| 279 EXPECT_EQ(kLineNumber, | 282 EXPECT_EQ(kLineNumber, |
| 280 process_data_phase.descendants[0].child.location.line_number); | 283 process_data_phase.descendants[0].child.location.line_number); |
| 281 EXPECT_EQ(kWorkerThreadName, | 284 EXPECT_EQ(kWorkerThreadName, |
| 282 process_data_phase.descendants[0].child.thread_name); | 285 process_data_phase.descendants[0].child.thread_name); |
| 283 } else { | 286 } else { |
| 284 EXPECT_EQ(0u, process_data_phase.descendants.size()); | 287 EXPECT_EQ(0u, process_data_phase.descendants.size()); |
| 285 } | 288 } |
| 286 } | 289 } |
| 287 | 290 |
| 288 TEST_F(TrackedObjectsTest, DeathDataTest) { | 291 TEST_F(TrackedObjectsTest, DeathDataTestRecordDeath) { |
| 289 if (!ThreadData::InitializeAndSetTrackingStatus( | 292 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 290 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 293 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 294 // Don't run the test if task tracking is not compiled in. |
| 291 return; | 295 return; |
| 292 } | 296 } |
| 293 | 297 |
| 294 scoped_ptr<DeathData> data(new DeathData()); | 298 scoped_ptr<DeathData> data(new DeathData()); |
| 295 ASSERT_NE(data, reinterpret_cast<DeathData*>(NULL)); | 299 ASSERT_NE(data, reinterpret_cast<DeathData*>(NULL)); |
| 296 EXPECT_EQ(data->run_duration_sum(), 0); | 300 EXPECT_EQ(data->run_duration_sum(), 0); |
| 301 EXPECT_EQ(data->run_duration_max(), 0); |
| 297 EXPECT_EQ(data->run_duration_sample(), 0); | 302 EXPECT_EQ(data->run_duration_sample(), 0); |
| 298 EXPECT_EQ(data->queue_duration_sum(), 0); | 303 EXPECT_EQ(data->queue_duration_sum(), 0); |
| 304 EXPECT_EQ(data->queue_duration_max(), 0); |
| 299 EXPECT_EQ(data->queue_duration_sample(), 0); | 305 EXPECT_EQ(data->queue_duration_sample(), 0); |
| 300 EXPECT_EQ(data->count(), 0); | 306 EXPECT_EQ(data->count(), 0); |
| 307 EXPECT_EQ(nullptr, data->last_phase_snapshot()); |
| 301 | 308 |
| 302 int32 run_ms = 42; | 309 int32 run_ms = 42; |
| 303 int32 queue_ms = 8; | 310 int32 queue_ms = 8; |
| 304 | 311 |
| 305 const int kUnrandomInt = 0; // Fake random int that ensure we sample data. | 312 const int kUnrandomInt = 0; // Fake random int that ensure we sample data. |
| 306 data->RecordDeath(queue_ms, run_ms, kUnrandomInt); | 313 data->RecordDeath(queue_ms, run_ms, kUnrandomInt); |
| 307 EXPECT_EQ(data->run_duration_sum(), run_ms); | 314 EXPECT_EQ(data->run_duration_sum(), run_ms); |
| 315 EXPECT_EQ(data->run_duration_max(), run_ms); |
| 308 EXPECT_EQ(data->run_duration_sample(), run_ms); | 316 EXPECT_EQ(data->run_duration_sample(), run_ms); |
| 309 EXPECT_EQ(data->queue_duration_sum(), queue_ms); | 317 EXPECT_EQ(data->queue_duration_sum(), queue_ms); |
| 318 EXPECT_EQ(data->queue_duration_max(), queue_ms); |
| 310 EXPECT_EQ(data->queue_duration_sample(), queue_ms); | 319 EXPECT_EQ(data->queue_duration_sample(), queue_ms); |
| 311 EXPECT_EQ(data->count(), 1); | 320 EXPECT_EQ(data->count(), 1); |
| 321 EXPECT_EQ(nullptr, data->last_phase_snapshot()); |
| 312 | 322 |
| 313 data->RecordDeath(queue_ms, run_ms, kUnrandomInt); | 323 data->RecordDeath(queue_ms, run_ms, kUnrandomInt); |
| 314 EXPECT_EQ(data->run_duration_sum(), run_ms + run_ms); | 324 EXPECT_EQ(data->run_duration_sum(), run_ms + run_ms); |
| 325 EXPECT_EQ(data->run_duration_max(), run_ms); |
| 315 EXPECT_EQ(data->run_duration_sample(), run_ms); | 326 EXPECT_EQ(data->run_duration_sample(), run_ms); |
| 316 EXPECT_EQ(data->queue_duration_sum(), queue_ms + queue_ms); | 327 EXPECT_EQ(data->queue_duration_sum(), queue_ms + queue_ms); |
| 328 EXPECT_EQ(data->queue_duration_max(), queue_ms); |
| 317 EXPECT_EQ(data->queue_duration_sample(), queue_ms); | 329 EXPECT_EQ(data->queue_duration_sample(), queue_ms); |
| 318 EXPECT_EQ(data->count(), 2); | 330 EXPECT_EQ(data->count(), 2); |
| 331 EXPECT_EQ(nullptr, data->last_phase_snapshot()); |
| 332 } |
| 319 | 333 |
| 320 DeathDataSnapshot snapshot(*data); | 334 TEST_F(TrackedObjectsTest, DeathDataTest2Phases) { |
| 321 EXPECT_EQ(2, snapshot.count); | 335 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 322 EXPECT_EQ(2 * run_ms, snapshot.run_duration_sum); | 336 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 323 EXPECT_EQ(run_ms, snapshot.run_duration_max); | 337 // Don't run the test if task tracking is not compiled in. |
| 324 EXPECT_EQ(run_ms, snapshot.run_duration_sample); | 338 return; |
| 325 EXPECT_EQ(2 * queue_ms, snapshot.queue_duration_sum); | 339 } |
| 326 EXPECT_EQ(queue_ms, snapshot.queue_duration_max); | 340 |
| 327 EXPECT_EQ(queue_ms, snapshot.queue_duration_sample); | 341 scoped_ptr<DeathData> data(new DeathData()); |
| 342 ASSERT_NE(data, reinterpret_cast<DeathData*>(NULL)); |
| 343 |
| 344 int32 run_ms = 42; |
| 345 int32 queue_ms = 8; |
| 346 |
| 347 const int kUnrandomInt = 0; // Fake random int that ensure we sample data. |
| 348 data->RecordDeath(queue_ms, run_ms, kUnrandomInt); |
| 349 data->RecordDeath(queue_ms, run_ms, kUnrandomInt); |
| 350 |
| 351 data->OnProfilingPhaseCompleted(123); |
| 352 EXPECT_EQ(data->run_duration_sum(), run_ms + run_ms); |
| 353 EXPECT_EQ(data->run_duration_max(), 0); |
| 354 EXPECT_EQ(data->run_duration_sample(), run_ms); |
| 355 EXPECT_EQ(data->queue_duration_sum(), queue_ms + queue_ms); |
| 356 EXPECT_EQ(data->queue_duration_max(), 0); |
| 357 EXPECT_EQ(data->queue_duration_sample(), queue_ms); |
| 358 EXPECT_EQ(data->count(), 2); |
| 359 ASSERT_NE(nullptr, data->last_phase_snapshot()); |
| 360 EXPECT_EQ(123, data->last_phase_snapshot()->profiling_phase); |
| 361 EXPECT_EQ(2, data->last_phase_snapshot()->death_data.count); |
| 362 EXPECT_EQ(2 * run_ms, |
| 363 data->last_phase_snapshot()->death_data.run_duration_sum); |
| 364 EXPECT_EQ(run_ms, data->last_phase_snapshot()->death_data.run_duration_max); |
| 365 EXPECT_EQ(run_ms, |
| 366 data->last_phase_snapshot()->death_data.run_duration_sample); |
| 367 EXPECT_EQ(2 * queue_ms, |
| 368 data->last_phase_snapshot()->death_data.queue_duration_sum); |
| 369 EXPECT_EQ(queue_ms, |
| 370 data->last_phase_snapshot()->death_data.queue_duration_max); |
| 371 EXPECT_EQ(queue_ms, |
| 372 data->last_phase_snapshot()->death_data.queue_duration_sample); |
| 373 EXPECT_EQ(nullptr, data->last_phase_snapshot()->prev); |
| 374 |
| 375 int32 run_ms1 = 21; |
| 376 int32 queue_ms1 = 4; |
| 377 |
| 378 data->RecordDeath(queue_ms1, run_ms1, kUnrandomInt); |
| 379 EXPECT_EQ(data->run_duration_sum(), run_ms + run_ms + run_ms1); |
| 380 EXPECT_EQ(data->run_duration_max(), run_ms1); |
| 381 EXPECT_EQ(data->run_duration_sample(), run_ms1); |
| 382 EXPECT_EQ(data->queue_duration_sum(), queue_ms + queue_ms + queue_ms1); |
| 383 EXPECT_EQ(data->queue_duration_max(), queue_ms1); |
| 384 EXPECT_EQ(data->queue_duration_sample(), queue_ms1); |
| 385 EXPECT_EQ(data->count(), 3); |
| 386 ASSERT_NE(nullptr, data->last_phase_snapshot()); |
| 387 EXPECT_EQ(123, data->last_phase_snapshot()->profiling_phase); |
| 388 EXPECT_EQ(2, data->last_phase_snapshot()->death_data.count); |
| 389 EXPECT_EQ(2 * run_ms, |
| 390 data->last_phase_snapshot()->death_data.run_duration_sum); |
| 391 EXPECT_EQ(run_ms, data->last_phase_snapshot()->death_data.run_duration_max); |
| 392 EXPECT_EQ(run_ms, |
| 393 data->last_phase_snapshot()->death_data.run_duration_sample); |
| 394 EXPECT_EQ(2 * queue_ms, |
| 395 data->last_phase_snapshot()->death_data.queue_duration_sum); |
| 396 EXPECT_EQ(queue_ms, |
| 397 data->last_phase_snapshot()->death_data.queue_duration_max); |
| 398 EXPECT_EQ(queue_ms, |
| 399 data->last_phase_snapshot()->death_data.queue_duration_sample); |
| 400 EXPECT_EQ(nullptr, data->last_phase_snapshot()->prev); |
| 401 } |
| 402 |
| 403 TEST_F(TrackedObjectsTest, Delta) { |
| 404 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 405 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 406 // Don't run the test if task tracking is not compiled in. |
| 407 return; |
| 408 } |
| 409 |
| 410 DeathDataSnapshot snapshot; |
| 411 snapshot.count = 10; |
| 412 snapshot.run_duration_sum = 100; |
| 413 snapshot.run_duration_max = 50; |
| 414 snapshot.run_duration_sample = 25; |
| 415 snapshot.queue_duration_sum = 200; |
| 416 snapshot.queue_duration_max = 101; |
| 417 snapshot.queue_duration_sample = 26; |
| 418 |
| 419 DeathDataSnapshot older_snapshot; |
| 420 older_snapshot.count = 2; |
| 421 older_snapshot.run_duration_sum = 95; |
| 422 older_snapshot.run_duration_max = 48; |
| 423 older_snapshot.run_duration_sample = 22; |
| 424 older_snapshot.queue_duration_sum = 190; |
| 425 older_snapshot.queue_duration_max = 99; |
| 426 older_snapshot.queue_duration_sample = 21; |
| 427 |
| 428 const DeathDataSnapshot& delta = snapshot.Delta(older_snapshot); |
| 429 EXPECT_EQ(8, delta.count); |
| 430 EXPECT_EQ(5, delta.run_duration_sum); |
| 431 EXPECT_EQ(50, delta.run_duration_max); |
| 432 EXPECT_EQ(25, delta.run_duration_sample); |
| 433 EXPECT_EQ(10, delta.queue_duration_sum); |
| 434 EXPECT_EQ(101, delta.queue_duration_max); |
| 435 EXPECT_EQ(26, delta.queue_duration_sample); |
| 328 } | 436 } |
| 329 | 437 |
| 330 TEST_F(TrackedObjectsTest, DeactivatedBirthOnlyToSnapshotWorkerThread) { | 438 TEST_F(TrackedObjectsTest, DeactivatedBirthOnlyToSnapshotWorkerThread) { |
| 331 // Start in the deactivated state. | 439 // Start in the deactivated state. |
| 332 if (!ThreadData::InitializeAndSetTrackingStatus(ThreadData::DEACTIVATED)) { | 440 if (!ThreadData::InitializeAndSetTrackingStatus(ThreadData::DEACTIVATED)) { |
| 441 // Don't run the test if task tracking is not compiled in. |
| 333 return; | 442 return; |
| 334 } | 443 } |
| 335 | 444 |
| 336 const char kFunction[] = "DeactivatedBirthOnlyToSnapshotWorkerThread"; | 445 const char kFunction[] = "DeactivatedBirthOnlyToSnapshotWorkerThread"; |
| 337 Location location(kFunction, kFile, kLineNumber, NULL); | 446 Location location(kFunction, kFile, kLineNumber, NULL); |
| 338 TallyABirth(location, std::string()); | 447 TallyABirth(location, std::string()); |
| 339 | 448 |
| 340 ProcessDataSnapshot process_data; | 449 ProcessDataSnapshot process_data; |
| 341 ThreadData::Snapshot(&process_data); | 450 ThreadData::Snapshot(0, &process_data); |
| 342 | 451 |
| 343 ASSERT_EQ(1u, process_data.phased_process_data_snapshots.size()); | 452 ASSERT_EQ(1u, process_data.phased_snapshots.size()); |
| 344 auto it = process_data.phased_process_data_snapshots.find(0); | 453 |
| 345 ASSERT_TRUE(it != process_data.phased_process_data_snapshots.end()); | 454 auto it = process_data.phased_snapshots.find(0); |
| 455 ASSERT_TRUE(it != process_data.phased_snapshots.end()); |
| 346 const ProcessDataPhaseSnapshot& process_data_phase = it->second; | 456 const ProcessDataPhaseSnapshot& process_data_phase = it->second; |
| 347 | 457 |
| 348 EXPECT_EQ(0u, process_data_phase.tasks.size()); | 458 ASSERT_EQ(0u, process_data_phase.tasks.size()); |
| 349 EXPECT_EQ(0u, process_data_phase.descendants.size()); | 459 |
| 350 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); | 460 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); |
| 351 } | 461 } |
| 352 | 462 |
| 353 TEST_F(TrackedObjectsTest, DeactivatedBirthOnlyToSnapshotMainThread) { | 463 TEST_F(TrackedObjectsTest, DeactivatedBirthOnlyToSnapshotMainThread) { |
| 354 // Start in the deactivated state. | 464 // Start in the deactivated state. |
| 355 if (!ThreadData::InitializeAndSetTrackingStatus(ThreadData::DEACTIVATED)) { | 465 if (!ThreadData::InitializeAndSetTrackingStatus(ThreadData::DEACTIVATED)) { |
| 466 // Don't run the test if task tracking is not compiled in. |
| 356 return; | 467 return; |
| 357 } | 468 } |
| 358 | 469 |
| 359 const char kFunction[] = "DeactivatedBirthOnlyToSnapshotMainThread"; | 470 const char kFunction[] = "DeactivatedBirthOnlyToSnapshotMainThread"; |
| 360 Location location(kFunction, kFile, kLineNumber, NULL); | 471 Location location(kFunction, kFile, kLineNumber, NULL); |
| 361 TallyABirth(location, kMainThreadName); | 472 TallyABirth(location, kMainThreadName); |
| 362 | 473 |
| 363 ProcessDataSnapshot process_data; | 474 ProcessDataSnapshot process_data; |
| 364 ThreadData::Snapshot(&process_data); | 475 ThreadData::Snapshot(0, &process_data); |
| 365 | 476 |
| 366 ASSERT_EQ(1u, process_data.phased_process_data_snapshots.size()); | 477 ASSERT_EQ(1u, process_data.phased_snapshots.size()); |
| 367 auto it = process_data.phased_process_data_snapshots.find(0); | 478 |
| 368 ASSERT_TRUE(it != process_data.phased_process_data_snapshots.end()); | 479 auto it = process_data.phased_snapshots.find(0); |
| 480 ASSERT_TRUE(it != process_data.phased_snapshots.end()); |
| 369 const ProcessDataPhaseSnapshot& process_data_phase = it->second; | 481 const ProcessDataPhaseSnapshot& process_data_phase = it->second; |
| 370 | 482 |
| 371 EXPECT_EQ(0u, process_data_phase.tasks.size()); | 483 ASSERT_EQ(0u, process_data_phase.tasks.size()); |
| 372 EXPECT_EQ(0u, process_data_phase.descendants.size()); | 484 |
| 373 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); | 485 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); |
| 374 } | 486 } |
| 375 | 487 |
| 376 TEST_F(TrackedObjectsTest, BirthOnlyToSnapshotWorkerThread) { | 488 TEST_F(TrackedObjectsTest, BirthOnlyToSnapshotWorkerThread) { |
| 377 if (!ThreadData::InitializeAndSetTrackingStatus( | 489 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 378 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 490 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 491 // Don't run the test if task tracking is not compiled in. |
| 379 return; | 492 return; |
| 380 } | 493 } |
| 381 | 494 |
| 382 const char kFunction[] = "BirthOnlyToSnapshotWorkerThread"; | 495 const char kFunction[] = "BirthOnlyToSnapshotWorkerThread"; |
| 383 Location location(kFunction, kFile, kLineNumber, NULL); | 496 Location location(kFunction, kFile, kLineNumber, NULL); |
| 384 TallyABirth(location, std::string()); | 497 TallyABirth(location, std::string()); |
| 385 | 498 |
| 386 ProcessDataSnapshot process_data; | 499 ProcessDataSnapshot process_data; |
| 387 ThreadData::Snapshot(&process_data); | 500 ThreadData::Snapshot(0, &process_data); |
| 388 ExpectSimpleProcessData(process_data, kFunction, kWorkerThreadName, | 501 ExpectSimpleProcessData(process_data, kFunction, kWorkerThreadName, |
| 389 kStillAlive, 1, 0, 0); | 502 kStillAlive, 1, 0, 0); |
| 390 } | 503 } |
| 391 | 504 |
| 392 TEST_F(TrackedObjectsTest, BirthOnlyToSnapshotMainThread) { | 505 TEST_F(TrackedObjectsTest, BirthOnlyToSnapshotMainThread) { |
| 393 if (!ThreadData::InitializeAndSetTrackingStatus( | 506 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 394 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 507 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 508 // Don't run the test if task tracking is not compiled in. |
| 395 return; | 509 return; |
| 396 } | 510 } |
| 397 | 511 |
| 398 const char kFunction[] = "BirthOnlyToSnapshotMainThread"; | 512 const char kFunction[] = "BirthOnlyToSnapshotMainThread"; |
| 399 Location location(kFunction, kFile, kLineNumber, NULL); | 513 Location location(kFunction, kFile, kLineNumber, NULL); |
| 400 TallyABirth(location, kMainThreadName); | 514 TallyABirth(location, kMainThreadName); |
| 401 | 515 |
| 402 ProcessDataSnapshot process_data; | 516 ProcessDataSnapshot process_data; |
| 403 ThreadData::Snapshot(&process_data); | 517 ThreadData::Snapshot(0, &process_data); |
| 404 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, kStillAlive, | 518 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, kStillAlive, |
| 405 1, 0, 0); | 519 1, 0, 0); |
| 406 } | 520 } |
| 407 | 521 |
| 408 TEST_F(TrackedObjectsTest, LifeCycleToSnapshotMainThread) { | 522 TEST_F(TrackedObjectsTest, LifeCycleToSnapshotMainThread) { |
| 409 if (!ThreadData::InitializeAndSetTrackingStatus( | 523 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 410 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 524 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 525 // Don't run the test if task tracking is not compiled in. |
| 411 return; | 526 return; |
| 412 } | 527 } |
| 413 | 528 |
| 414 const char kFunction[] = "LifeCycleToSnapshotMainThread"; | 529 const char kFunction[] = "LifeCycleToSnapshotMainThread"; |
| 415 Location location(kFunction, kFile, kLineNumber, NULL); | 530 Location location(kFunction, kFile, kLineNumber, NULL); |
| 416 TallyABirth(location, kMainThreadName); | 531 TallyABirth(location, kMainThreadName); |
| 417 | 532 |
| 418 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); | 533 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 419 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); | 534 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| 420 // TrackingInfo will call TallyABirth() during construction. | 535 // TrackingInfo will call TallyABirth() during construction. |
| 421 base::TrackingInfo pending_task(location, kDelayedStartTime); | 536 base::TrackingInfo pending_task(location, kDelayedStartTime); |
| 422 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). | 537 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). |
| 423 | 538 |
| 424 const unsigned int kStartOfRun = 5; | 539 const unsigned int kStartOfRun = 5; |
| 425 const unsigned int kEndOfRun = 7; | 540 const unsigned int kEndOfRun = 7; |
| 426 SetTestTime(kStartOfRun); | 541 SetTestTime(kStartOfRun); |
| 427 TaskStopwatch stopwatch; | 542 TaskStopwatch stopwatch; |
| 428 stopwatch.Start(); | 543 stopwatch.Start(); |
| 429 SetTestTime(kEndOfRun); | 544 SetTestTime(kEndOfRun); |
| 430 stopwatch.Stop(); | 545 stopwatch.Stop(); |
| 431 | 546 |
| 432 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); | 547 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 433 | 548 |
| 434 ProcessDataSnapshot process_data; | 549 ProcessDataSnapshot process_data; |
| 435 ThreadData::Snapshot(&process_data); | 550 ThreadData::Snapshot(0, &process_data); |
| 436 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, | 551 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, |
| 437 kMainThreadName, 1, 2, 4); | 552 kMainThreadName, 1, 2, 4); |
| 438 } | 553 } |
| 439 | 554 |
| 555 TEST_F(TrackedObjectsTest, TwoPhases) { |
| 556 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 557 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 558 // Don't run the test if task tracking is not compiled in. |
| 559 return; |
| 560 } |
| 561 |
| 562 const char kFunction[] = "TwoPhases"; |
| 563 Location location(kFunction, kFile, kLineNumber, NULL); |
| 564 TallyABirth(location, kMainThreadName); |
| 565 |
| 566 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 567 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| 568 // TrackingInfo will call TallyABirth() during construction. |
| 569 base::TrackingInfo pending_task(location, kDelayedStartTime); |
| 570 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). |
| 571 |
| 572 const unsigned int kStartOfRun = 5; |
| 573 const unsigned int kEndOfRun = 7; |
| 574 SetTestTime(kStartOfRun); |
| 575 TaskStopwatch stopwatch; |
| 576 stopwatch.Start(); |
| 577 SetTestTime(kEndOfRun); |
| 578 stopwatch.Stop(); |
| 579 |
| 580 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 581 |
| 582 ThreadData::OnProfilingPhaseCompleted(0); |
| 583 |
| 584 TallyABirth(location, kMainThreadName); |
| 585 |
| 586 const TrackedTime kTimePosted1 = TrackedTime::FromMilliseconds(9); |
| 587 const base::TimeTicks kDelayedStartTime1 = base::TimeTicks(); |
| 588 // TrackingInfo will call TallyABirth() during construction. |
| 589 base::TrackingInfo pending_task1(location, kDelayedStartTime1); |
| 590 pending_task1.time_posted = kTimePosted1; // Overwrite implied Now(). |
| 591 |
| 592 const unsigned int kStartOfRun1 = 11; |
| 593 const unsigned int kEndOfRun1 = 21; |
| 594 SetTestTime(kStartOfRun1); |
| 595 TaskStopwatch stopwatch1; |
| 596 stopwatch1.Start(); |
| 597 SetTestTime(kEndOfRun1); |
| 598 stopwatch1.Stop(); |
| 599 |
| 600 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task1, stopwatch1); |
| 601 |
| 602 ProcessDataSnapshot process_data; |
| 603 ThreadData::Snapshot(1, &process_data); |
| 604 |
| 605 ASSERT_EQ(2u, process_data.phased_snapshots.size()); |
| 606 |
| 607 auto it0 = process_data.phased_snapshots.find(0); |
| 608 ASSERT_TRUE(it0 != process_data.phased_snapshots.end()); |
| 609 const ProcessDataPhaseSnapshot& process_data_phase0 = it0->second; |
| 610 |
| 611 ASSERT_EQ(1u, process_data_phase0.tasks.size()); |
| 612 |
| 613 EXPECT_EQ(kFile, process_data_phase0.tasks[0].birth.location.file_name); |
| 614 EXPECT_EQ(kFunction, |
| 615 process_data_phase0.tasks[0].birth.location.function_name); |
| 616 EXPECT_EQ(kLineNumber, |
| 617 process_data_phase0.tasks[0].birth.location.line_number); |
| 618 |
| 619 EXPECT_EQ(kMainThreadName, process_data_phase0.tasks[0].birth.thread_name); |
| 620 |
| 621 EXPECT_EQ(1, process_data_phase0.tasks[0].death_data.count); |
| 622 EXPECT_EQ(2, process_data_phase0.tasks[0].death_data.run_duration_sum); |
| 623 EXPECT_EQ(2, process_data_phase0.tasks[0].death_data.run_duration_max); |
| 624 EXPECT_EQ(2, process_data_phase0.tasks[0].death_data.run_duration_sample); |
| 625 EXPECT_EQ(4, process_data_phase0.tasks[0].death_data.queue_duration_sum); |
| 626 EXPECT_EQ(4, process_data_phase0.tasks[0].death_data.queue_duration_max); |
| 627 EXPECT_EQ(4, process_data_phase0.tasks[0].death_data.queue_duration_sample); |
| 628 |
| 629 EXPECT_EQ(kMainThreadName, process_data_phase0.tasks[0].death_thread_name); |
| 630 |
| 631 EXPECT_EQ(0u, process_data_phase0.descendants.size()); |
| 632 |
| 633 auto it1 = process_data.phased_snapshots.find(1); |
| 634 ASSERT_TRUE(it1 != process_data.phased_snapshots.end()); |
| 635 const ProcessDataPhaseSnapshot& process_data_phase1 = it1->second; |
| 636 |
| 637 ASSERT_EQ(1u, process_data_phase1.tasks.size()); |
| 638 |
| 639 EXPECT_EQ(kFile, process_data_phase1.tasks[0].birth.location.file_name); |
| 640 EXPECT_EQ(kFunction, |
| 641 process_data_phase1.tasks[0].birth.location.function_name); |
| 642 EXPECT_EQ(kLineNumber, |
| 643 process_data_phase1.tasks[0].birth.location.line_number); |
| 644 |
| 645 EXPECT_EQ(kMainThreadName, process_data_phase1.tasks[0].birth.thread_name); |
| 646 |
| 647 EXPECT_EQ(1, process_data_phase1.tasks[0].death_data.count); |
| 648 EXPECT_EQ(10, process_data_phase1.tasks[0].death_data.run_duration_sum); |
| 649 EXPECT_EQ(10, process_data_phase1.tasks[0].death_data.run_duration_max); |
| 650 EXPECT_EQ(10, process_data_phase1.tasks[0].death_data.run_duration_sample); |
| 651 EXPECT_EQ(2, process_data_phase1.tasks[0].death_data.queue_duration_sum); |
| 652 EXPECT_EQ(2, process_data_phase1.tasks[0].death_data.queue_duration_max); |
| 653 EXPECT_EQ(2, process_data_phase1.tasks[0].death_data.queue_duration_sample); |
| 654 |
| 655 EXPECT_EQ(kMainThreadName, process_data_phase1.tasks[0].death_thread_name); |
| 656 |
| 657 EXPECT_EQ(0u, process_data_phase1.descendants.size()); |
| 658 |
| 659 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); |
| 660 } |
| 661 |
| 662 TEST_F(TrackedObjectsTest, ThreePhases) { |
| 663 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 664 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 665 // Don't run the test if task tracking is not compiled in. |
| 666 return; |
| 667 } |
| 668 |
| 669 const char kFunction[] = "ThreePhases"; |
| 670 Location location(kFunction, kFile, kLineNumber, NULL); |
| 671 |
| 672 // Phase 0 |
| 673 { |
| 674 TallyABirth(location, kMainThreadName); |
| 675 |
| 676 // TrackingInfo will call TallyABirth() during construction. |
| 677 SetTestTime(10); |
| 678 base::TrackingInfo pending_task(location, base::TimeTicks()); |
| 679 |
| 680 SetTestTime(17); |
| 681 TaskStopwatch stopwatch; |
| 682 stopwatch.Start(); |
| 683 SetTestTime(23); |
| 684 stopwatch.Stop(); |
| 685 |
| 686 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 687 } |
| 688 |
| 689 ThreadData::OnProfilingPhaseCompleted(0); |
| 690 |
| 691 // Phase 1 |
| 692 { |
| 693 TallyABirth(location, kMainThreadName); |
| 694 |
| 695 SetTestTime(30); |
| 696 base::TrackingInfo pending_task(location, base::TimeTicks()); |
| 697 |
| 698 SetTestTime(35); |
| 699 TaskStopwatch stopwatch; |
| 700 stopwatch.Start(); |
| 701 SetTestTime(39); |
| 702 stopwatch.Stop(); |
| 703 |
| 704 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 705 } |
| 706 |
| 707 ThreadData::OnProfilingPhaseCompleted(1); |
| 708 |
| 709 // Phase 2 |
| 710 { |
| 711 TallyABirth(location, kMainThreadName); |
| 712 |
| 713 // TrackingInfo will call TallyABirth() during construction. |
| 714 SetTestTime(40); |
| 715 base::TrackingInfo pending_task(location, base::TimeTicks()); |
| 716 |
| 717 SetTestTime(43); |
| 718 TaskStopwatch stopwatch; |
| 719 stopwatch.Start(); |
| 720 SetTestTime(45); |
| 721 stopwatch.Stop(); |
| 722 |
| 723 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 724 } |
| 725 |
| 726 // Snapshot and check results. |
| 727 ProcessDataSnapshot process_data; |
| 728 ThreadData::Snapshot(2, &process_data); |
| 729 |
| 730 ASSERT_EQ(3u, process_data.phased_snapshots.size()); |
| 731 |
| 732 auto it0 = process_data.phased_snapshots.find(0); |
| 733 ASSERT_TRUE(it0 != process_data.phased_snapshots.end()); |
| 734 const ProcessDataPhaseSnapshot& process_data_phase0 = it0->second; |
| 735 |
| 736 ASSERT_EQ(1u, process_data_phase0.tasks.size()); |
| 737 |
| 738 EXPECT_EQ(kFile, process_data_phase0.tasks[0].birth.location.file_name); |
| 739 EXPECT_EQ(kFunction, |
| 740 process_data_phase0.tasks[0].birth.location.function_name); |
| 741 EXPECT_EQ(kLineNumber, |
| 742 process_data_phase0.tasks[0].birth.location.line_number); |
| 743 |
| 744 EXPECT_EQ(kMainThreadName, process_data_phase0.tasks[0].birth.thread_name); |
| 745 |
| 746 EXPECT_EQ(1, process_data_phase0.tasks[0].death_data.count); |
| 747 EXPECT_EQ(6, process_data_phase0.tasks[0].death_data.run_duration_sum); |
| 748 EXPECT_EQ(6, process_data_phase0.tasks[0].death_data.run_duration_max); |
| 749 EXPECT_EQ(6, process_data_phase0.tasks[0].death_data.run_duration_sample); |
| 750 EXPECT_EQ(7, process_data_phase0.tasks[0].death_data.queue_duration_sum); |
| 751 EXPECT_EQ(7, process_data_phase0.tasks[0].death_data.queue_duration_max); |
| 752 EXPECT_EQ(7, process_data_phase0.tasks[0].death_data.queue_duration_sample); |
| 753 |
| 754 EXPECT_EQ(kMainThreadName, process_data_phase0.tasks[0].death_thread_name); |
| 755 |
| 756 EXPECT_EQ(0u, process_data_phase0.descendants.size()); |
| 757 |
| 758 auto it1 = process_data.phased_snapshots.find(1); |
| 759 ASSERT_TRUE(it1 != process_data.phased_snapshots.end()); |
| 760 const ProcessDataPhaseSnapshot& process_data_phase1 = it1->second; |
| 761 |
| 762 ASSERT_EQ(1u, process_data_phase1.tasks.size()); |
| 763 |
| 764 EXPECT_EQ(kFile, process_data_phase1.tasks[0].birth.location.file_name); |
| 765 EXPECT_EQ(kFunction, |
| 766 process_data_phase1.tasks[0].birth.location.function_name); |
| 767 EXPECT_EQ(kLineNumber, |
| 768 process_data_phase1.tasks[0].birth.location.line_number); |
| 769 |
| 770 EXPECT_EQ(kMainThreadName, process_data_phase1.tasks[0].birth.thread_name); |
| 771 |
| 772 EXPECT_EQ(1, process_data_phase1.tasks[0].death_data.count); |
| 773 EXPECT_EQ(4, process_data_phase1.tasks[0].death_data.run_duration_sum); |
| 774 EXPECT_EQ(4, process_data_phase1.tasks[0].death_data.run_duration_max); |
| 775 EXPECT_EQ(4, process_data_phase1.tasks[0].death_data.run_duration_sample); |
| 776 EXPECT_EQ(5, process_data_phase1.tasks[0].death_data.queue_duration_sum); |
| 777 EXPECT_EQ(5, process_data_phase1.tasks[0].death_data.queue_duration_max); |
| 778 EXPECT_EQ(5, process_data_phase1.tasks[0].death_data.queue_duration_sample); |
| 779 |
| 780 EXPECT_EQ(kMainThreadName, process_data_phase1.tasks[0].death_thread_name); |
| 781 |
| 782 EXPECT_EQ(0u, process_data_phase1.descendants.size()); |
| 783 |
| 784 auto it2 = process_data.phased_snapshots.find(2); |
| 785 ASSERT_TRUE(it2 != process_data.phased_snapshots.end()); |
| 786 const ProcessDataPhaseSnapshot& process_data_phase2 = it2->second; |
| 787 |
| 788 ASSERT_EQ(1u, process_data_phase2.tasks.size()); |
| 789 |
| 790 EXPECT_EQ(kFile, process_data_phase2.tasks[0].birth.location.file_name); |
| 791 EXPECT_EQ(kFunction, |
| 792 process_data_phase2.tasks[0].birth.location.function_name); |
| 793 EXPECT_EQ(kLineNumber, |
| 794 process_data_phase2.tasks[0].birth.location.line_number); |
| 795 |
| 796 EXPECT_EQ(kMainThreadName, process_data_phase2.tasks[0].birth.thread_name); |
| 797 |
| 798 EXPECT_EQ(1, process_data_phase2.tasks[0].death_data.count); |
| 799 EXPECT_EQ(2, process_data_phase2.tasks[0].death_data.run_duration_sum); |
| 800 EXPECT_EQ(2, process_data_phase2.tasks[0].death_data.run_duration_max); |
| 801 EXPECT_EQ(2, process_data_phase2.tasks[0].death_data.run_duration_sample); |
| 802 EXPECT_EQ(3, process_data_phase2.tasks[0].death_data.queue_duration_sum); |
| 803 EXPECT_EQ(3, process_data_phase2.tasks[0].death_data.queue_duration_max); |
| 804 EXPECT_EQ(3, process_data_phase2.tasks[0].death_data.queue_duration_sample); |
| 805 |
| 806 EXPECT_EQ(kMainThreadName, process_data_phase2.tasks[0].death_thread_name); |
| 807 |
| 808 EXPECT_EQ(0u, process_data_phase2.descendants.size()); |
| 809 |
| 810 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); |
| 811 } |
| 812 |
| 813 TEST_F(TrackedObjectsTest, TwoPhasesSecondEmpty) { |
| 814 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 815 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 816 // Don't run the test if task tracking is not compiled in. |
| 817 return; |
| 818 } |
| 819 |
| 820 const char kFunction[] = "TwoPhasesSecondEmpty"; |
| 821 Location location(kFunction, kFile, kLineNumber, NULL); |
| 822 ThreadData::InitializeThreadContext(kMainThreadName); |
| 823 |
| 824 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 825 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| 826 // TrackingInfo will call TallyABirth() during construction. |
| 827 base::TrackingInfo pending_task(location, kDelayedStartTime); |
| 828 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). |
| 829 |
| 830 const unsigned int kStartOfRun = 5; |
| 831 const unsigned int kEndOfRun = 7; |
| 832 SetTestTime(kStartOfRun); |
| 833 TaskStopwatch stopwatch; |
| 834 stopwatch.Start(); |
| 835 SetTestTime(kEndOfRun); |
| 836 stopwatch.Stop(); |
| 837 |
| 838 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 839 |
| 840 ThreadData::OnProfilingPhaseCompleted(0); |
| 841 |
| 842 ProcessDataSnapshot process_data; |
| 843 ThreadData::Snapshot(1, &process_data); |
| 844 |
| 845 ASSERT_EQ(2u, process_data.phased_snapshots.size()); |
| 846 |
| 847 auto it0 = process_data.phased_snapshots.find(0); |
| 848 ASSERT_TRUE(it0 != process_data.phased_snapshots.end()); |
| 849 const ProcessDataPhaseSnapshot& process_data_phase0 = it0->second; |
| 850 |
| 851 ASSERT_EQ(1u, process_data_phase0.tasks.size()); |
| 852 |
| 853 EXPECT_EQ(kFile, process_data_phase0.tasks[0].birth.location.file_name); |
| 854 EXPECT_EQ(kFunction, |
| 855 process_data_phase0.tasks[0].birth.location.function_name); |
| 856 EXPECT_EQ(kLineNumber, |
| 857 process_data_phase0.tasks[0].birth.location.line_number); |
| 858 |
| 859 EXPECT_EQ(kMainThreadName, process_data_phase0.tasks[0].birth.thread_name); |
| 860 |
| 861 EXPECT_EQ(1, process_data_phase0.tasks[0].death_data.count); |
| 862 EXPECT_EQ(2, process_data_phase0.tasks[0].death_data.run_duration_sum); |
| 863 EXPECT_EQ(2, process_data_phase0.tasks[0].death_data.run_duration_max); |
| 864 EXPECT_EQ(2, process_data_phase0.tasks[0].death_data.run_duration_sample); |
| 865 EXPECT_EQ(4, process_data_phase0.tasks[0].death_data.queue_duration_sum); |
| 866 EXPECT_EQ(4, process_data_phase0.tasks[0].death_data.queue_duration_max); |
| 867 EXPECT_EQ(4, process_data_phase0.tasks[0].death_data.queue_duration_sample); |
| 868 |
| 869 EXPECT_EQ(kMainThreadName, process_data_phase0.tasks[0].death_thread_name); |
| 870 |
| 871 auto it1 = process_data.phased_snapshots.find(1); |
| 872 ASSERT_TRUE(it1 != process_data.phased_snapshots.end()); |
| 873 const ProcessDataPhaseSnapshot& process_data_phase1 = it1->second; |
| 874 |
| 875 ASSERT_EQ(0u, process_data_phase1.tasks.size()); |
| 876 |
| 877 EXPECT_EQ(0u, process_data_phase0.descendants.size()); |
| 878 |
| 879 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); |
| 880 } |
| 881 |
| 882 TEST_F(TrackedObjectsTest, TwoPhasesFirstEmpty) { |
| 883 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 884 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 885 // Don't run the test if task tracking is not compiled in. |
| 886 return; |
| 887 } |
| 888 |
| 889 ThreadData::OnProfilingPhaseCompleted(0); |
| 890 |
| 891 const char kFunction[] = "TwoPhasesSecondEmpty"; |
| 892 Location location(kFunction, kFile, kLineNumber, NULL); |
| 893 ThreadData::InitializeThreadContext(kMainThreadName); |
| 894 |
| 895 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 896 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| 897 // TrackingInfo will call TallyABirth() during construction. |
| 898 base::TrackingInfo pending_task(location, kDelayedStartTime); |
| 899 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). |
| 900 |
| 901 const unsigned int kStartOfRun = 5; |
| 902 const unsigned int kEndOfRun = 7; |
| 903 SetTestTime(kStartOfRun); |
| 904 TaskStopwatch stopwatch; |
| 905 stopwatch.Start(); |
| 906 SetTestTime(kEndOfRun); |
| 907 stopwatch.Stop(); |
| 908 |
| 909 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 910 |
| 911 ProcessDataSnapshot process_data; |
| 912 ThreadData::Snapshot(1, &process_data); |
| 913 |
| 914 ASSERT_EQ(1u, process_data.phased_snapshots.size()); |
| 915 |
| 916 auto it1 = process_data.phased_snapshots.find(1); |
| 917 ASSERT_TRUE(it1 != process_data.phased_snapshots.end()); |
| 918 const ProcessDataPhaseSnapshot& process_data_phase1 = it1->second; |
| 919 |
| 920 ASSERT_EQ(1u, process_data_phase1.tasks.size()); |
| 921 |
| 922 EXPECT_EQ(kFile, process_data_phase1.tasks[0].birth.location.file_name); |
| 923 EXPECT_EQ(kFunction, |
| 924 process_data_phase1.tasks[0].birth.location.function_name); |
| 925 EXPECT_EQ(kLineNumber, |
| 926 process_data_phase1.tasks[0].birth.location.line_number); |
| 927 |
| 928 EXPECT_EQ(kMainThreadName, process_data_phase1.tasks[0].birth.thread_name); |
| 929 |
| 930 EXPECT_EQ(1, process_data_phase1.tasks[0].death_data.count); |
| 931 EXPECT_EQ(2, process_data_phase1.tasks[0].death_data.run_duration_sum); |
| 932 EXPECT_EQ(2, process_data_phase1.tasks[0].death_data.run_duration_max); |
| 933 EXPECT_EQ(2, process_data_phase1.tasks[0].death_data.run_duration_sample); |
| 934 EXPECT_EQ(4, process_data_phase1.tasks[0].death_data.queue_duration_sum); |
| 935 EXPECT_EQ(4, process_data_phase1.tasks[0].death_data.queue_duration_max); |
| 936 EXPECT_EQ(4, process_data_phase1.tasks[0].death_data.queue_duration_sample); |
| 937 |
| 938 EXPECT_EQ(kMainThreadName, process_data_phase1.tasks[0].death_thread_name); |
| 939 |
| 940 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); |
| 941 } |
| 942 |
| 440 // We will deactivate tracking after the birth, and before the death, and | 943 // We will deactivate tracking after the birth, and before the death, and |
| 441 // demonstrate that the lifecycle is completely tallied. This ensures that | 944 // demonstrate that the lifecycle is completely tallied. This ensures that |
| 442 // our tallied births are matched by tallied deaths (except for when the | 945 // our tallied births are matched by tallied deaths (except for when the |
| 443 // task is still running, or is queued). | 946 // task is still running, or is queued). |
| 444 TEST_F(TrackedObjectsTest, LifeCycleMidDeactivatedToSnapshotMainThread) { | 947 TEST_F(TrackedObjectsTest, LifeCycleMidDeactivatedToSnapshotMainThread) { |
| 445 if (!ThreadData::InitializeAndSetTrackingStatus( | 948 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 446 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 949 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 950 // Don't run the test if task tracking is not compiled in. |
| 447 return; | 951 return; |
| 448 } | 952 } |
| 449 | 953 |
| 450 const char kFunction[] = "LifeCycleMidDeactivatedToSnapshotMainThread"; | 954 const char kFunction[] = "LifeCycleMidDeactivatedToSnapshotMainThread"; |
| 451 Location location(kFunction, kFile, kLineNumber, NULL); | 955 Location location(kFunction, kFile, kLineNumber, NULL); |
| 452 TallyABirth(location, kMainThreadName); | 956 TallyABirth(location, kMainThreadName); |
| 453 | 957 |
| 454 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); | 958 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 455 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); | 959 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| 456 // TrackingInfo will call TallyABirth() during construction. | 960 // TrackingInfo will call TallyABirth() during construction. |
| 457 base::TrackingInfo pending_task(location, kDelayedStartTime); | 961 base::TrackingInfo pending_task(location, kDelayedStartTime); |
| 458 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). | 962 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). |
| 459 | 963 |
| 460 // Turn off tracking now that we have births. | 964 // Turn off tracking now that we have births. |
| 461 EXPECT_TRUE( | 965 EXPECT_TRUE( |
| 462 ThreadData::InitializeAndSetTrackingStatus(ThreadData::DEACTIVATED)); | 966 ThreadData::InitializeAndSetTrackingStatus(ThreadData::DEACTIVATED)); |
| 463 | 967 |
| 464 const unsigned int kStartOfRun = 5; | 968 const unsigned int kStartOfRun = 5; |
| 465 const unsigned int kEndOfRun = 7; | 969 const unsigned int kEndOfRun = 7; |
| 466 SetTestTime(kStartOfRun); | 970 SetTestTime(kStartOfRun); |
| 467 TaskStopwatch stopwatch; | 971 TaskStopwatch stopwatch; |
| 468 stopwatch.Start(); | 972 stopwatch.Start(); |
| 469 SetTestTime(kEndOfRun); | 973 SetTestTime(kEndOfRun); |
| 470 stopwatch.Stop(); | 974 stopwatch.Stop(); |
| 471 | 975 |
| 472 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); | 976 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 473 | 977 |
| 474 ProcessDataSnapshot process_data; | 978 ProcessDataSnapshot process_data; |
| 475 ThreadData::Snapshot(&process_data); | 979 ThreadData::Snapshot(0, &process_data); |
| 476 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, | 980 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, |
| 477 kMainThreadName, 1, 2, 4); | 981 kMainThreadName, 1, 2, 4); |
| 478 } | 982 } |
| 479 | 983 |
| 480 // We will deactivate tracking before starting a life cycle, and neither | 984 // We will deactivate tracking before starting a life cycle, and neither |
| 481 // the birth nor the death will be recorded. | 985 // the birth nor the death will be recorded. |
| 482 TEST_F(TrackedObjectsTest, LifeCyclePreDeactivatedToSnapshotMainThread) { | 986 TEST_F(TrackedObjectsTest, LifeCyclePreDeactivatedToSnapshotMainThread) { |
| 483 // Start in the deactivated state. | 987 // Start in the deactivated state. |
| 484 if (!ThreadData::InitializeAndSetTrackingStatus(ThreadData::DEACTIVATED)) { | 988 if (!ThreadData::InitializeAndSetTrackingStatus(ThreadData::DEACTIVATED)) { |
| 989 // Don't run the test if task tracking is not compiled in. |
| 485 return; | 990 return; |
| 486 } | 991 } |
| 487 | 992 |
| 488 const char kFunction[] = "LifeCyclePreDeactivatedToSnapshotMainThread"; | 993 const char kFunction[] = "LifeCyclePreDeactivatedToSnapshotMainThread"; |
| 489 Location location(kFunction, kFile, kLineNumber, NULL); | 994 Location location(kFunction, kFile, kLineNumber, NULL); |
| 490 TallyABirth(location, kMainThreadName); | 995 TallyABirth(location, kMainThreadName); |
| 491 | 996 |
| 492 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); | 997 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 493 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); | 998 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| 494 // TrackingInfo will call TallyABirth() during construction. | 999 // TrackingInfo will call TallyABirth() during construction. |
| 495 base::TrackingInfo pending_task(location, kDelayedStartTime); | 1000 base::TrackingInfo pending_task(location, kDelayedStartTime); |
| 496 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). | 1001 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). |
| 497 | 1002 |
| 498 const unsigned int kStartOfRun = 5; | 1003 const unsigned int kStartOfRun = 5; |
| 499 const unsigned int kEndOfRun = 7; | 1004 const unsigned int kEndOfRun = 7; |
| 500 SetTestTime(kStartOfRun); | 1005 SetTestTime(kStartOfRun); |
| 501 TaskStopwatch stopwatch; | 1006 TaskStopwatch stopwatch; |
| 502 stopwatch.Start(); | 1007 stopwatch.Start(); |
| 503 SetTestTime(kEndOfRun); | 1008 SetTestTime(kEndOfRun); |
| 504 stopwatch.Stop(); | 1009 stopwatch.Stop(); |
| 505 | 1010 |
| 506 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); | 1011 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 507 | 1012 |
| 508 ProcessDataSnapshot process_data; | 1013 ProcessDataSnapshot process_data; |
| 509 ThreadData::Snapshot(&process_data); | 1014 ThreadData::Snapshot(0, &process_data); |
| 510 | 1015 |
| 511 ASSERT_EQ(1u, process_data.phased_process_data_snapshots.size()); | 1016 ASSERT_EQ(1u, process_data.phased_snapshots.size()); |
| 512 auto it = process_data.phased_process_data_snapshots.find(0); | 1017 |
| 513 ASSERT_TRUE(it != process_data.phased_process_data_snapshots.end()); | 1018 auto it = process_data.phased_snapshots.find(0); |
| 1019 ASSERT_TRUE(it != process_data.phased_snapshots.end()); |
| 514 const ProcessDataPhaseSnapshot& process_data_phase = it->second; | 1020 const ProcessDataPhaseSnapshot& process_data_phase = it->second; |
| 515 | 1021 |
| 516 EXPECT_EQ(0u, process_data_phase.tasks.size()); | 1022 ASSERT_EQ(0u, process_data_phase.tasks.size()); |
| 517 EXPECT_EQ(0u, process_data_phase.descendants.size()); | 1023 |
| 518 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); | 1024 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); |
| 519 } | 1025 } |
| 520 | 1026 |
| 521 TEST_F(TrackedObjectsTest, TwoLives) { | 1027 TEST_F(TrackedObjectsTest, TwoLives) { |
| 522 if (!ThreadData::InitializeAndSetTrackingStatus( | 1028 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 523 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 1029 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 1030 // Don't run the test if task tracking is not compiled in. |
| 524 return; | 1031 return; |
| 525 } | 1032 } |
| 526 | 1033 |
| 527 const char kFunction[] = "TwoLives"; | 1034 const char kFunction[] = "TwoLives"; |
| 528 Location location(kFunction, kFile, kLineNumber, NULL); | 1035 Location location(kFunction, kFile, kLineNumber, NULL); |
| 529 TallyABirth(location, kMainThreadName); | 1036 TallyABirth(location, kMainThreadName); |
| 530 | 1037 |
| 531 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); | 1038 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 532 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); | 1039 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| 533 // TrackingInfo will call TallyABirth() during construction. | 1040 // TrackingInfo will call TallyABirth() during construction. |
| (...skipping 15 matching lines...) Expand all Loading... |
| 549 pending_task2.time_posted = kTimePosted; // Overwrite implied Now(). | 1056 pending_task2.time_posted = kTimePosted; // Overwrite implied Now(). |
| 550 SetTestTime(kStartOfRun); | 1057 SetTestTime(kStartOfRun); |
| 551 TaskStopwatch stopwatch2; | 1058 TaskStopwatch stopwatch2; |
| 552 stopwatch2.Start(); | 1059 stopwatch2.Start(); |
| 553 SetTestTime(kEndOfRun); | 1060 SetTestTime(kEndOfRun); |
| 554 stopwatch2.Stop(); | 1061 stopwatch2.Stop(); |
| 555 | 1062 |
| 556 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task2, stopwatch2); | 1063 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task2, stopwatch2); |
| 557 | 1064 |
| 558 ProcessDataSnapshot process_data; | 1065 ProcessDataSnapshot process_data; |
| 559 ThreadData::Snapshot(&process_data); | 1066 ThreadData::Snapshot(0, &process_data); |
| 560 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, | 1067 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, |
| 561 kMainThreadName, 2, 2, 4); | 1068 kMainThreadName, 2, 2, 4); |
| 562 } | 1069 } |
| 563 | 1070 |
| 564 TEST_F(TrackedObjectsTest, DifferentLives) { | 1071 TEST_F(TrackedObjectsTest, DifferentLives) { |
| 565 if (!ThreadData::InitializeAndSetTrackingStatus( | 1072 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 566 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 1073 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 1074 // Don't run the test if task tracking is not compiled in. |
| 567 return; | 1075 return; |
| 568 } | 1076 } |
| 569 | 1077 |
| 570 // Use a well named thread. | 1078 // Use a well named thread. |
| 571 ThreadData::InitializeThreadContext(kMainThreadName); | 1079 ThreadData::InitializeThreadContext(kMainThreadName); |
| 572 const char kFunction[] = "DifferentLives"; | 1080 const char kFunction[] = "DifferentLives"; |
| 573 Location location(kFunction, kFile, kLineNumber, NULL); | 1081 Location location(kFunction, kFile, kLineNumber, NULL); |
| 574 | 1082 |
| 575 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); | 1083 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 576 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); | 1084 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| (...skipping 12 matching lines...) Expand all Loading... |
| 589 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); | 1097 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, stopwatch); |
| 590 | 1098 |
| 591 const int kSecondFakeLineNumber = 999; | 1099 const int kSecondFakeLineNumber = 999; |
| 592 Location second_location(kFunction, kFile, kSecondFakeLineNumber, NULL); | 1100 Location second_location(kFunction, kFile, kSecondFakeLineNumber, NULL); |
| 593 | 1101 |
| 594 // TrackingInfo will call TallyABirth() during construction. | 1102 // TrackingInfo will call TallyABirth() during construction. |
| 595 base::TrackingInfo pending_task2(second_location, kDelayedStartTime); | 1103 base::TrackingInfo pending_task2(second_location, kDelayedStartTime); |
| 596 pending_task2.time_posted = kTimePosted; // Overwrite implied Now(). | 1104 pending_task2.time_posted = kTimePosted; // Overwrite implied Now(). |
| 597 | 1105 |
| 598 ProcessDataSnapshot process_data; | 1106 ProcessDataSnapshot process_data; |
| 599 ThreadData::Snapshot(&process_data); | 1107 ThreadData::Snapshot(0, &process_data); |
| 600 | 1108 |
| 601 ASSERT_EQ(1u, process_data.phased_process_data_snapshots.size()); | 1109 ASSERT_EQ(1u, process_data.phased_snapshots.size()); |
| 602 auto it = process_data.phased_process_data_snapshots.find(0); | 1110 auto it = process_data.phased_snapshots.find(0); |
| 603 ASSERT_TRUE(it != process_data.phased_process_data_snapshots.end()); | 1111 ASSERT_TRUE(it != process_data.phased_snapshots.end()); |
| 604 const ProcessDataPhaseSnapshot& process_data_phase = it->second; | 1112 const ProcessDataPhaseSnapshot& process_data_phase = it->second; |
| 605 | 1113 |
| 606 ASSERT_EQ(2u, process_data_phase.tasks.size()); | 1114 ASSERT_EQ(2u, process_data_phase.tasks.size()); |
| 607 | 1115 |
| 608 EXPECT_EQ(kFile, process_data_phase.tasks[0].birth.location.file_name); | 1116 EXPECT_EQ(kFile, process_data_phase.tasks[0].birth.location.file_name); |
| 609 EXPECT_EQ(kFunction, | 1117 EXPECT_EQ(kFunction, |
| 610 process_data_phase.tasks[0].birth.location.function_name); | 1118 process_data_phase.tasks[0].birth.location.function_name); |
| 611 EXPECT_EQ(kLineNumber, | 1119 EXPECT_EQ(kLineNumber, |
| 612 process_data_phase.tasks[0].birth.location.line_number); | 1120 process_data_phase.tasks[0].birth.location.line_number); |
| 613 EXPECT_EQ(kMainThreadName, process_data_phase.tasks[0].birth.thread_name); | 1121 EXPECT_EQ(kMainThreadName, process_data_phase.tasks[0].birth.thread_name); |
| (...skipping 19 matching lines...) Expand all Loading... |
| 633 EXPECT_EQ(0, process_data_phase.tasks[1].death_data.queue_duration_max); | 1141 EXPECT_EQ(0, process_data_phase.tasks[1].death_data.queue_duration_max); |
| 634 EXPECT_EQ(0, process_data_phase.tasks[1].death_data.queue_duration_sample); | 1142 EXPECT_EQ(0, process_data_phase.tasks[1].death_data.queue_duration_sample); |
| 635 EXPECT_EQ(kStillAlive, process_data_phase.tasks[1].death_thread_name); | 1143 EXPECT_EQ(kStillAlive, process_data_phase.tasks[1].death_thread_name); |
| 636 EXPECT_EQ(0u, process_data_phase.descendants.size()); | 1144 EXPECT_EQ(0u, process_data_phase.descendants.size()); |
| 637 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); | 1145 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); |
| 638 } | 1146 } |
| 639 | 1147 |
| 640 TEST_F(TrackedObjectsTest, TaskWithNestedExclusion) { | 1148 TEST_F(TrackedObjectsTest, TaskWithNestedExclusion) { |
| 641 if (!ThreadData::InitializeAndSetTrackingStatus( | 1149 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 642 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 1150 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 1151 // Don't run the test if task tracking is not compiled in. |
| 643 return; | 1152 return; |
| 644 } | 1153 } |
| 645 | 1154 |
| 646 const char kFunction[] = "TaskWithNestedExclusion"; | 1155 const char kFunction[] = "TaskWithNestedExclusion"; |
| 647 Location location(kFunction, kFile, kLineNumber, NULL); | 1156 Location location(kFunction, kFile, kLineNumber, NULL); |
| 648 TallyABirth(location, kMainThreadName); | 1157 TallyABirth(location, kMainThreadName); |
| 649 | 1158 |
| 650 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); | 1159 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 651 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); | 1160 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| 652 // TrackingInfo will call TallyABirth() during construction. | 1161 // TrackingInfo will call TallyABirth() during construction. |
| 653 base::TrackingInfo pending_task(location, kDelayedStartTime); | 1162 base::TrackingInfo pending_task(location, kDelayedStartTime); |
| 654 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). | 1163 pending_task.time_posted = kTimePosted; // Overwrite implied Now(). |
| 655 | 1164 |
| 656 SetTestTime(5); | 1165 SetTestTime(5); |
| 657 TaskStopwatch task_stopwatch; | 1166 TaskStopwatch task_stopwatch; |
| 658 task_stopwatch.Start(); | 1167 task_stopwatch.Start(); |
| 659 { | 1168 { |
| 660 SetTestTime(8); | 1169 SetTestTime(8); |
| 661 TaskStopwatch exclusion_stopwatch; | 1170 TaskStopwatch exclusion_stopwatch; |
| 662 exclusion_stopwatch.Start(); | 1171 exclusion_stopwatch.Start(); |
| 663 SetTestTime(12); | 1172 SetTestTime(12); |
| 664 exclusion_stopwatch.Stop(); | 1173 exclusion_stopwatch.Stop(); |
| 665 } | 1174 } |
| 666 SetTestTime(15); | 1175 SetTestTime(15); |
| 667 task_stopwatch.Stop(); | 1176 task_stopwatch.Stop(); |
| 668 | 1177 |
| 669 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, task_stopwatch); | 1178 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, task_stopwatch); |
| 670 | 1179 |
| 671 ProcessDataSnapshot process_data; | 1180 ProcessDataSnapshot process_data; |
| 672 ThreadData::Snapshot(&process_data); | 1181 ThreadData::Snapshot(0, &process_data); |
| 673 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, | 1182 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, |
| 674 kMainThreadName, 1, 6, 4); | 1183 kMainThreadName, 1, 6, 4); |
| 675 } | 1184 } |
| 676 | 1185 |
| 677 TEST_F(TrackedObjectsTest, TaskWith2NestedExclusions) { | 1186 TEST_F(TrackedObjectsTest, TaskWith2NestedExclusions) { |
| 678 if (!ThreadData::InitializeAndSetTrackingStatus( | 1187 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 679 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 1188 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 1189 // Don't run the test if task tracking is not compiled in. |
| 680 return; | 1190 return; |
| 681 } | 1191 } |
| 682 | 1192 |
| 683 const char kFunction[] = "TaskWith2NestedExclusions"; | 1193 const char kFunction[] = "TaskWith2NestedExclusions"; |
| 684 Location location(kFunction, kFile, kLineNumber, NULL); | 1194 Location location(kFunction, kFile, kLineNumber, NULL); |
| 685 TallyABirth(location, kMainThreadName); | 1195 TallyABirth(location, kMainThreadName); |
| 686 | 1196 |
| 687 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); | 1197 const TrackedTime kTimePosted = TrackedTime::FromMilliseconds(1); |
| 688 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); | 1198 const base::TimeTicks kDelayedStartTime = base::TimeTicks(); |
| 689 // TrackingInfo will call TallyABirth() during construction. | 1199 // TrackingInfo will call TallyABirth() during construction. |
| (...skipping 15 matching lines...) Expand all Loading... |
| 705 exclusion_stopwatch2.Start(); | 1215 exclusion_stopwatch2.Start(); |
| 706 SetTestTime(18); | 1216 SetTestTime(18); |
| 707 exclusion_stopwatch2.Stop(); | 1217 exclusion_stopwatch2.Stop(); |
| 708 } | 1218 } |
| 709 SetTestTime(25); | 1219 SetTestTime(25); |
| 710 task_stopwatch.Stop(); | 1220 task_stopwatch.Stop(); |
| 711 | 1221 |
| 712 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, task_stopwatch); | 1222 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, task_stopwatch); |
| 713 | 1223 |
| 714 ProcessDataSnapshot process_data; | 1224 ProcessDataSnapshot process_data; |
| 715 ThreadData::Snapshot(&process_data); | 1225 ThreadData::Snapshot(0, &process_data); |
| 716 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, | 1226 ExpectSimpleProcessData(process_data, kFunction, kMainThreadName, |
| 717 kMainThreadName, 1, 13, 4); | 1227 kMainThreadName, 1, 13, 4); |
| 718 } | 1228 } |
| 719 | 1229 |
| 720 TEST_F(TrackedObjectsTest, TaskWithNestedExclusionWithNestedTask) { | 1230 TEST_F(TrackedObjectsTest, TaskWithNestedExclusionWithNestedTask) { |
| 721 if (!ThreadData::InitializeAndSetTrackingStatus( | 1231 if (!ThreadData::InitializeAndSetTrackingStatus( |
| 722 ThreadData::PROFILING_CHILDREN_ACTIVE)) { | 1232 ThreadData::PROFILING_CHILDREN_ACTIVE)) { |
| 1233 // Don't run the test if task tracking is not compiled in. |
| 723 return; | 1234 return; |
| 724 } | 1235 } |
| 725 | 1236 |
| 726 const char kFunction[] = "TaskWithNestedExclusionWithNestedTask"; | 1237 const char kFunction[] = "TaskWithNestedExclusionWithNestedTask"; |
| 727 Location location(kFunction, kFile, kLineNumber, NULL); | 1238 Location location(kFunction, kFile, kLineNumber, NULL); |
| 728 | 1239 |
| 729 const int kSecondFakeLineNumber = 999; | 1240 const int kSecondFakeLineNumber = 999; |
| 730 | 1241 |
| 731 TallyABirth(location, kMainThreadName); | 1242 TallyABirth(location, kMainThreadName); |
| 732 | 1243 |
| (...skipping 25 matching lines...) Expand all Loading... |
| 758 } | 1269 } |
| 759 SetTestTime(12); | 1270 SetTestTime(12); |
| 760 exclusion_stopwatch.Stop(); | 1271 exclusion_stopwatch.Stop(); |
| 761 } | 1272 } |
| 762 SetTestTime(15); | 1273 SetTestTime(15); |
| 763 task_stopwatch.Stop(); | 1274 task_stopwatch.Stop(); |
| 764 | 1275 |
| 765 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, task_stopwatch); | 1276 ThreadData::TallyRunOnNamedThreadIfTracking(pending_task, task_stopwatch); |
| 766 | 1277 |
| 767 ProcessDataSnapshot process_data; | 1278 ProcessDataSnapshot process_data; |
| 768 ThreadData::Snapshot(&process_data); | 1279 ThreadData::Snapshot(0, &process_data); |
| 769 | 1280 |
| 770 ASSERT_EQ(1u, process_data.phased_process_data_snapshots.size()); | 1281 ASSERT_EQ(1u, process_data.phased_snapshots.size()); |
| 771 auto it = process_data.phased_process_data_snapshots.find(0); | 1282 auto it = process_data.phased_snapshots.find(0); |
| 772 ASSERT_TRUE(it != process_data.phased_process_data_snapshots.end()); | 1283 ASSERT_TRUE(it != process_data.phased_snapshots.end()); |
| 773 const ProcessDataPhaseSnapshot& process_data_phase = it->second; | 1284 const ProcessDataPhaseSnapshot& process_data_phase = it->second; |
| 774 | 1285 |
| 775 // The order in which the two task follow is platform-dependent. | 1286 // The order in which the two task follow is platform-dependent. |
| 776 int t0 = | 1287 int t0 = |
| 777 (process_data_phase.tasks[0].birth.location.line_number == kLineNumber) | 1288 (process_data_phase.tasks[0].birth.location.line_number == kLineNumber) |
| 778 ? 0 | 1289 ? 0 |
| 779 : 1; | 1290 : 1; |
| 780 int t1 = 1 - t0; | 1291 int t1 = 1 - t0; |
| 781 | 1292 |
| 782 ASSERT_EQ(2u, process_data_phase.tasks.size()); | 1293 ASSERT_EQ(2u, process_data_phase.tasks.size()); |
| (...skipping 23 matching lines...) Expand all Loading... |
| 806 EXPECT_EQ(2, process_data_phase.tasks[t1].death_data.run_duration_sample); | 1317 EXPECT_EQ(2, process_data_phase.tasks[t1].death_data.run_duration_sample); |
| 807 EXPECT_EQ(1, process_data_phase.tasks[t1].death_data.queue_duration_sum); | 1318 EXPECT_EQ(1, process_data_phase.tasks[t1].death_data.queue_duration_sum); |
| 808 EXPECT_EQ(1, process_data_phase.tasks[t1].death_data.queue_duration_max); | 1319 EXPECT_EQ(1, process_data_phase.tasks[t1].death_data.queue_duration_max); |
| 809 EXPECT_EQ(1, process_data_phase.tasks[t1].death_data.queue_duration_sample); | 1320 EXPECT_EQ(1, process_data_phase.tasks[t1].death_data.queue_duration_sample); |
| 810 EXPECT_EQ(kMainThreadName, process_data_phase.tasks[t1].death_thread_name); | 1321 EXPECT_EQ(kMainThreadName, process_data_phase.tasks[t1].death_thread_name); |
| 811 EXPECT_EQ(0u, process_data_phase.descendants.size()); | 1322 EXPECT_EQ(0u, process_data_phase.descendants.size()); |
| 812 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); | 1323 EXPECT_EQ(base::GetCurrentProcId(), process_data.process_id); |
| 813 } | 1324 } |
| 814 | 1325 |
| 815 } // namespace tracked_objects | 1326 } // namespace tracked_objects |
| OLD | NEW |