Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 1 | /* |
| 2 | * Copyright (C) 2017 The Android Open Source Project |
| 3 | * |
| 4 | * Licensed under the Apache License, Version 2.0 (the "License"); |
| 5 | * you may not use this file except in compliance with the License. |
| 6 | * You may obtain a copy of the License at |
| 7 | * |
| 8 | * http://www.apache.org/licenses/LICENSE-2.0 |
| 9 | * |
| 10 | * Unless required by applicable law or agreed to in writing, software |
| 11 | * distributed under the License is distributed on an "AS IS" BASIS, |
| 12 | * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. |
| 13 | * See the License for the specific language governing permissions and |
| 14 | * limitations under the License. |
| 15 | */ |
| 16 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 17 | #include "src/tracing/core/tracing_service_impl.h" |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 18 | |
| 19 | #include <string.h> |
| 20 | |
| 21 | #include "gmock/gmock.h" |
| 22 | #include "gtest/gtest.h" |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 23 | #include "perfetto/base/file_utils.h" |
| 24 | #include "perfetto/base/temp_file.h" |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 25 | #include "perfetto/base/utils.h" |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 26 | #include "perfetto/tracing/core/consumer.h" |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 27 | #include "perfetto/tracing/core/data_source_config.h" |
| 28 | #include "perfetto/tracing/core/data_source_descriptor.h" |
| 29 | #include "perfetto/tracing/core/producer.h" |
| 30 | #include "perfetto/tracing/core/shared_memory.h" |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 31 | #include "perfetto/tracing/core/trace_packet.h" |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 32 | #include "perfetto/tracing/core/trace_writer.h" |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 33 | #include "src/base/test/test_task_runner.h" |
Eric Seckler | a01e28a | 2019-01-08 11:21:04 +0000 | [diff] [blame] | 34 | #include "src/tracing/core/shared_memory_arbiter_impl.h" |
Eric Seckler | f3f524b | 2018-12-13 09:09:34 +0000 | [diff] [blame] | 35 | #include "src/tracing/core/trace_writer_impl.h" |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 36 | #include "src/tracing/test/mock_consumer.h" |
| 37 | #include "src/tracing/test/mock_producer.h" |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 38 | #include "src/tracing/test/test_shared_memory.h" |
| 39 | |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 40 | #include "perfetto/trace/test_event.pbzero.h" |
| 41 | #include "perfetto/trace/trace.pb.h" |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 42 | #include "perfetto/trace/trace_packet.pb.h" |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 43 | #include "perfetto/trace/trace_packet.pbzero.h" |
| 44 | |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 45 | using ::testing::_; |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 46 | using ::testing::Contains; |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 47 | using ::testing::ElementsAreArray; |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 48 | using ::testing::Eq; |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 49 | using ::testing::InSequence; |
Primiano Tucci | 081d46a | 2018-02-28 11:09:43 +0000 | [diff] [blame] | 50 | using ::testing::Invoke; |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 51 | using ::testing::InvokeWithoutArgs; |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 52 | using ::testing::Mock; |
Eric Seckler | dd0ad10 | 2018-12-06 11:32:04 +0000 | [diff] [blame] | 53 | using ::testing::Not; |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 54 | using ::testing::Property; |
| 55 | using ::testing::StrictMock; |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 56 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 57 | namespace perfetto { |
Sami Kyostila | 32e0b54 | 2018-02-14 08:55:43 +0000 | [diff] [blame] | 58 | |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 59 | namespace { |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 60 | constexpr size_t kDefaultShmSizeKb = TracingServiceImpl::kDefaultShmSize / 1024; |
| 61 | constexpr size_t kMaxShmSizeKb = TracingServiceImpl::kMaxShmSize / 1024; |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 62 | |
| 63 | ::testing::AssertionResult HasTriggerModeInternal( |
| 64 | const std::vector<protos::TracePacket>& packets, |
| 65 | protos::TraceConfig::TriggerConfig::TriggerMode mode) { |
| 66 | ::testing::StringMatchResultListener matcher_result_string; |
| 67 | bool contains = ::testing::ExplainMatchResult( |
| 68 | Contains(Property( |
| 69 | &protos::TracePacket::trace_config, |
| 70 | Property(&protos::TraceConfig::trigger_config, |
| 71 | Property(&protos::TraceConfig::TriggerConfig::trigger_mode, |
| 72 | Eq(mode))))), |
| 73 | packets, &matcher_result_string); |
| 74 | if (contains) { |
| 75 | return ::testing::AssertionSuccess(); |
| 76 | } |
| 77 | return ::testing::AssertionFailure() << matcher_result_string.str(); |
| 78 | } |
| 79 | |
| 80 | MATCHER_P(HasTriggerMode, mode, "") { |
| 81 | return HasTriggerModeInternal(arg, mode); |
| 82 | } |
| 83 | |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 84 | } // namespace |
| 85 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 86 | class TracingServiceImplTest : public testing::Test { |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 87 | public: |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 88 | using DataSourceInstanceState = |
| 89 | TracingServiceImpl::DataSourceInstance::DataSourceInstanceState; |
| 90 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 91 | TracingServiceImplTest() { |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 92 | auto shm_factory = |
| 93 | std::unique_ptr<SharedMemory::Factory>(new TestSharedMemory::Factory()); |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 94 | svc.reset(static_cast<TracingServiceImpl*>( |
| 95 | TracingService::CreateInstance(std::move(shm_factory), &task_runner) |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 96 | .release())); |
Primiano Tucci | 9754d0d | 2018-09-15 12:41:46 +0100 | [diff] [blame] | 97 | svc->min_write_period_ms_ = 1; |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 98 | } |
| 99 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 100 | std::unique_ptr<MockProducer> CreateMockProducer() { |
| 101 | return std::unique_ptr<MockProducer>( |
| 102 | new StrictMock<MockProducer>(&task_runner)); |
| 103 | } |
| 104 | |
| 105 | std::unique_ptr<MockConsumer> CreateMockConsumer() { |
| 106 | return std::unique_ptr<MockConsumer>( |
| 107 | new StrictMock<MockConsumer>(&task_runner)); |
| 108 | } |
| 109 | |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 110 | ProducerID* last_producer_id() { return &svc->last_producer_id_; } |
| 111 | |
| 112 | uid_t GetProducerUid(ProducerID producer_id) { |
| 113 | return svc->GetProducer(producer_id)->uid_; |
| 114 | } |
| 115 | |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 116 | TracingServiceImpl::TracingSession* GetTracingSession(TracingSessionID tsid) { |
| 117 | auto* session = svc->GetTracingSession(tsid); |
Primiano Tucci | 9754d0d | 2018-09-15 12:41:46 +0100 | [diff] [blame] | 118 | EXPECT_NE(nullptr, session); |
| 119 | return session; |
| 120 | } |
| 121 | |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 122 | TracingServiceImpl::TracingSession* tracing_session() { |
| 123 | return GetTracingSession(GetTracingSessionID()); |
| 124 | } |
| 125 | |
| 126 | TracingSessionID GetTracingSessionID() { |
| 127 | return svc->last_tracing_session_id_; |
| 128 | } |
| 129 | |
Eric Seckler | 6dc2359 | 2018-11-30 10:59:06 +0000 | [diff] [blame] | 130 | const std::set<BufferID>& GetAllowedTargetBuffers(ProducerID producer_id) { |
| 131 | return svc->GetProducer(producer_id)->allowed_target_buffers_; |
| 132 | } |
| 133 | |
Eric Seckler | f3f524b | 2018-12-13 09:09:34 +0000 | [diff] [blame] | 134 | const std::map<WriterID, BufferID>& GetWriters(ProducerID producer_id) { |
| 135 | return svc->GetProducer(producer_id)->writers_; |
| 136 | } |
| 137 | |
Eric Seckler | a01e28a | 2019-01-08 11:21:04 +0000 | [diff] [blame] | 138 | std::unique_ptr<SharedMemoryArbiterImpl> TakeShmemArbiterForProducer( |
| 139 | ProducerID producer_id) { |
| 140 | return std::move(svc->GetProducer(producer_id)->inproc_shmem_arbiter_); |
| 141 | } |
| 142 | |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 143 | size_t GetNumPendingFlushes() { |
Primiano Tucci | 9754d0d | 2018-09-15 12:41:46 +0100 | [diff] [blame] | 144 | return tracing_session()->pending_flushes.size(); |
| 145 | } |
| 146 | |
| 147 | void WaitForNextSyncMarker() { |
| 148 | tracing_session()->last_snapshot_time = base::TimeMillis(0); |
| 149 | static int attempt = 0; |
| 150 | while (tracing_session()->last_snapshot_time == base::TimeMillis(0)) { |
| 151 | auto checkpoint_name = "wait_snapshot_" + std::to_string(attempt++); |
| 152 | auto timer_expired = task_runner.CreateCheckpoint(checkpoint_name); |
| 153 | task_runner.PostDelayedTask([timer_expired] { timer_expired(); }, 1); |
| 154 | task_runner.RunUntilCheckpoint(checkpoint_name); |
| 155 | } |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 156 | } |
| 157 | |
Eric Seckler | f3f524b | 2018-12-13 09:09:34 +0000 | [diff] [blame] | 158 | void WaitForTraceWritersChanged(ProducerID producer_id) { |
| 159 | static int i = 0; |
| 160 | auto checkpoint_name = "writers_changed_" + std::to_string(producer_id) + |
| 161 | "_" + std::to_string(i++); |
| 162 | auto writers_changed = task_runner.CreateCheckpoint(checkpoint_name); |
| 163 | auto writers = GetWriters(producer_id); |
| 164 | std::function<void()> task; |
| 165 | task = [&task, writers, writers_changed, producer_id, this]() { |
| 166 | if (writers != GetWriters(producer_id)) { |
| 167 | writers_changed(); |
| 168 | return; |
| 169 | } |
| 170 | task_runner.PostDelayedTask(task, 1); |
| 171 | }; |
| 172 | task_runner.PostDelayedTask(task, 1); |
| 173 | task_runner.RunUntilCheckpoint(checkpoint_name); |
| 174 | } |
| 175 | |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 176 | DataSourceInstanceState GetDataSourceInstanceState(const std::string& name) { |
| 177 | for (const auto& kv : tracing_session()->data_source_instances) { |
| 178 | if (kv.second.data_source_name == name) |
| 179 | return kv.second.state; |
| 180 | } |
| 181 | PERFETTO_FATAL("Can't find data source instance with name %s", |
| 182 | name.c_str()); |
| 183 | } |
| 184 | |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 185 | base::TestTaskRunner task_runner; |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 186 | std::unique_ptr<TracingServiceImpl> svc; |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 187 | }; |
| 188 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 189 | TEST_F(TracingServiceImplTest, RegisterAndUnregister) { |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 190 | std::unique_ptr<MockProducer> mock_producer_1 = CreateMockProducer(); |
| 191 | std::unique_ptr<MockProducer> mock_producer_2 = CreateMockProducer(); |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 192 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 193 | mock_producer_1->Connect(svc.get(), "mock_producer_1", 123u /* uid */); |
| 194 | mock_producer_2->Connect(svc.get(), "mock_producer_2", 456u /* uid */); |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 195 | |
| 196 | ASSERT_EQ(2u, svc->num_producers()); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 197 | ASSERT_EQ(mock_producer_1->endpoint(), svc->GetProducer(1)); |
| 198 | ASSERT_EQ(mock_producer_2->endpoint(), svc->GetProducer(2)); |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 199 | ASSERT_EQ(123u, GetProducerUid(1)); |
| 200 | ASSERT_EQ(456u, GetProducerUid(2)); |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 201 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 202 | mock_producer_1->RegisterDataSource("foo"); |
| 203 | mock_producer_2->RegisterDataSource("bar"); |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 204 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 205 | mock_producer_1->UnregisterDataSource("foo"); |
| 206 | mock_producer_2->UnregisterDataSource("bar"); |
Primiano Tucci | 9daa483 | 2018-03-28 23:28:17 +0100 | [diff] [blame] | 207 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 208 | mock_producer_1.reset(); |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 209 | ASSERT_EQ(1u, svc->num_producers()); |
| 210 | ASSERT_EQ(nullptr, svc->GetProducer(1)); |
| 211 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 212 | mock_producer_2.reset(); |
| 213 | ASSERT_EQ(nullptr, svc->GetProducer(2)); |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 214 | |
| 215 | ASSERT_EQ(0u, svc->num_producers()); |
| 216 | } |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 217 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 218 | TEST_F(TracingServiceImplTest, EnableAndDisableTracing) { |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 219 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 220 | consumer->Connect(svc.get()); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 221 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 222 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 223 | producer->Connect(svc.get(), "mock_producer"); |
| 224 | producer->RegisterDataSource("data_source"); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 225 | |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 226 | TraceConfig trace_config; |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 227 | trace_config.add_buffers()->set_size_kb(128); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 228 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 229 | ds_config->set_name("data_source"); |
| 230 | consumer->EnableTracing(trace_config); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 231 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 232 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 233 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 234 | producer->WaitForDataSourceStart("data_source"); |
| 235 | |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 236 | // Calling StartTracing() should be a noop (% a DLOG statement) because the |
| 237 | // trace config didn't have the |deferred_start| flag set. |
| 238 | consumer->StartTracing(); |
| 239 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 240 | consumer->DisableTracing(); |
| 241 | producer->WaitForDataSourceStop("data_source"); |
| 242 | consumer->WaitForTracingDisabled(); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 243 | } |
| 244 | |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 245 | // Creates a tracing session with a START_TRACING trigger and checks that data |
| 246 | // sources are started only after the service receives a trigger. |
| 247 | TEST_F(TracingServiceImplTest, StartTracingTriggerDeferredStart) { |
| 248 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 249 | consumer->Connect(svc.get()); |
| 250 | |
| 251 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 252 | producer->Connect(svc.get(), "mock_producer"); |
| 253 | |
| 254 | // Create two data sources but enable only one of them. |
| 255 | producer->RegisterDataSource("ds_1"); |
| 256 | producer->RegisterDataSource("ds_2"); |
| 257 | |
| 258 | TraceConfig trace_config; |
| 259 | trace_config.add_buffers()->set_size_kb(128); |
| 260 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 261 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 262 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::START_TRACING); |
| 263 | auto* trigger = trigger_config->add_triggers(); |
| 264 | trigger->set_name("trigger_name"); |
| 265 | trigger->set_stop_delay_ms(1); |
| 266 | |
| 267 | trigger_config->set_trigger_timeout_ms(8.64e+7); |
| 268 | |
| 269 | // Make sure we don't get unexpected DataSourceStart() notifications yet. |
| 270 | EXPECT_CALL(*producer, StartDataSource(_, _)).Times(0); |
| 271 | |
| 272 | consumer->EnableTracing(trace_config); |
| 273 | producer->WaitForTracingSetup(); |
| 274 | |
| 275 | producer->WaitForDataSourceSetup("ds_1"); |
| 276 | |
| 277 | // The trace won't start until we send the trigger. since we have a |
| 278 | // START_TRACING trigger defined. |
| 279 | std::vector<std::string> req; |
| 280 | req.push_back("trigger_name"); |
| 281 | producer->endpoint()->ActivateTriggers(req); |
| 282 | |
| 283 | producer->WaitForDataSourceStart("ds_1"); |
| 284 | |
| 285 | auto writer1 = producer->CreateTraceWriter("ds_1"); |
| 286 | producer->WaitForFlush(writer1.get()); |
| 287 | |
| 288 | producer->WaitForDataSourceStop("ds_1"); |
| 289 | consumer->WaitForTracingDisabled(); |
| 290 | |
| 291 | ASSERT_EQ(1u, tracing_session()->received_triggers.size()); |
| 292 | EXPECT_EQ("trigger_name", |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 293 | tracing_session()->received_triggers[0].trigger_name); |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 294 | |
| 295 | EXPECT_THAT( |
| 296 | consumer->ReadBuffers(), |
| 297 | HasTriggerMode(protos::TraceConfig::TriggerConfig::START_TRACING)); |
| 298 | } |
| 299 | |
| 300 | // Creates a tracing session with a START_TRACING trigger and checks that the |
| 301 | // session is cleaned up when no trigger is received after |trigger_timeout_ms|. |
| 302 | TEST_F(TracingServiceImplTest, StartTracingTriggerTimeOut) { |
| 303 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 304 | consumer->Connect(svc.get()); |
| 305 | |
| 306 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 307 | producer->Connect(svc.get(), "mock_producer"); |
| 308 | |
| 309 | // Create two data sources but enable only one of them. |
| 310 | producer->RegisterDataSource("ds_1"); |
| 311 | producer->RegisterDataSource("ds_2"); |
| 312 | |
| 313 | TraceConfig trace_config; |
| 314 | trace_config.add_buffers()->set_size_kb(128); |
| 315 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 316 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 317 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::START_TRACING); |
| 318 | auto* trigger = trigger_config->add_triggers(); |
| 319 | trigger->set_name("trigger_name"); |
| 320 | trigger->set_stop_delay_ms(8.64e+7); |
| 321 | |
| 322 | trigger_config->set_trigger_timeout_ms(1); |
| 323 | |
| 324 | // Make sure we don't get unexpected DataSourceStart() notifications yet. |
| 325 | EXPECT_CALL(*producer, StartDataSource(_, _)).Times(0); |
| 326 | |
| 327 | consumer->EnableTracing(trace_config); |
| 328 | producer->WaitForTracingSetup(); |
| 329 | |
| 330 | producer->WaitForDataSourceSetup("ds_1"); |
| 331 | |
| 332 | // The trace won't start until we send the trigger. since we have a |
| 333 | // START_TRACING trigger defined. This is where we'd expect to have an |
| 334 | // ActivateTriggers call to the producer->endpoint(). |
| 335 | |
| 336 | producer->WaitForDataSourceStop("ds_1"); |
| 337 | consumer->WaitForTracingDisabled(); |
| 338 | EXPECT_THAT(consumer->ReadBuffers(), ::testing::IsEmpty()); |
| 339 | } |
| 340 | |
| 341 | // Creates a tracing session with a START_TRACING trigger and checks that |
| 342 | // the session is not started when the configured trigger producer is different |
| 343 | // than the producer that sent the trigger. |
| 344 | TEST_F(TracingServiceImplTest, StartTracingTriggerDifferentProducer) { |
| 345 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 346 | consumer->Connect(svc.get()); |
| 347 | |
| 348 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 349 | producer->Connect(svc.get(), "mock_producer"); |
| 350 | |
| 351 | // Create two data sources but enable only one of them. |
| 352 | producer->RegisterDataSource("ds_1"); |
| 353 | producer->RegisterDataSource("ds_2"); |
| 354 | |
| 355 | TraceConfig trace_config; |
| 356 | trace_config.add_buffers()->set_size_kb(128); |
| 357 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 358 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 359 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::START_TRACING); |
| 360 | auto* trigger = trigger_config->add_triggers(); |
| 361 | trigger->set_name("trigger_name"); |
| 362 | trigger->set_stop_delay_ms(8.64e+7); |
| 363 | trigger->set_producer_name_regex("correct_name"); |
| 364 | |
| 365 | trigger_config->set_trigger_timeout_ms(1); |
| 366 | |
| 367 | // Make sure we don't get unexpected DataSourceStart() notifications yet. |
| 368 | EXPECT_CALL(*producer, StartDataSource(_, _)).Times(0); |
| 369 | |
| 370 | consumer->EnableTracing(trace_config); |
| 371 | producer->WaitForTracingSetup(); |
| 372 | |
| 373 | producer->WaitForDataSourceSetup("ds_1"); |
| 374 | |
| 375 | // The trace won't start until we send the trigger called "trigger_name" |
| 376 | // coming from a producer called "correct_name", since we have a |
| 377 | // START_TRACING trigger defined. This is where we'd expect to have an |
| 378 | // ActivateTriggers call to the producer->endpoint(), but we send the trigger |
| 379 | // from a different producer so it is ignored. |
| 380 | std::vector<std::string> req; |
| 381 | req.push_back("trigger_name"); |
| 382 | producer->endpoint()->ActivateTriggers(req); |
| 383 | |
| 384 | producer->WaitForDataSourceStop("ds_1"); |
| 385 | consumer->WaitForTracingDisabled(); |
| 386 | EXPECT_THAT(consumer->ReadBuffers(), ::testing::IsEmpty()); |
| 387 | } |
| 388 | |
| 389 | // Creates a tracing session with a START_TRACING trigger and checks that the |
| 390 | // session is started when the trigger is received from the correct producer. |
| 391 | TEST_F(TracingServiceImplTest, StartTracingTriggerCorrectProducer) { |
| 392 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 393 | consumer->Connect(svc.get()); |
| 394 | |
| 395 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 396 | producer->Connect(svc.get(), "mock_producer"); |
| 397 | |
| 398 | // Create two data sources but enable only one of them. |
| 399 | producer->RegisterDataSource("ds_1"); |
| 400 | producer->RegisterDataSource("ds_2"); |
| 401 | |
| 402 | TraceConfig trace_config; |
| 403 | trace_config.add_buffers()->set_size_kb(128); |
| 404 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 405 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 406 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::START_TRACING); |
| 407 | auto* trigger = trigger_config->add_triggers(); |
| 408 | trigger->set_name("trigger_name"); |
| 409 | trigger->set_stop_delay_ms(1); |
| 410 | trigger->set_producer_name_regex("mock_produc[e-r]+"); |
| 411 | |
| 412 | trigger_config->set_trigger_timeout_ms(8.64e+7); |
| 413 | |
| 414 | consumer->EnableTracing(trace_config); |
| 415 | producer->WaitForTracingSetup(); |
| 416 | |
| 417 | producer->WaitForDataSourceSetup("ds_1"); |
| 418 | |
| 419 | // Start the trace at this point with ActivateTriggers. |
| 420 | std::vector<std::string> req; |
| 421 | req.push_back("trigger_name"); |
| 422 | producer->endpoint()->ActivateTriggers(req); |
| 423 | |
| 424 | producer->WaitForDataSourceStart("ds_1"); |
| 425 | |
| 426 | auto writer = producer->CreateTraceWriter("ds_1"); |
| 427 | producer->WaitForFlush(writer.get()); |
| 428 | |
| 429 | producer->WaitForDataSourceStop("ds_1"); |
| 430 | consumer->WaitForTracingDisabled(); |
| 431 | EXPECT_THAT( |
| 432 | consumer->ReadBuffers(), |
| 433 | HasTriggerMode(protos::TraceConfig::TriggerConfig::START_TRACING)); |
| 434 | } |
| 435 | |
| 436 | // Creates a tracing session with a START_TRACING trigger and checks that the |
| 437 | // session is cleaned up even when a different trigger is received. |
| 438 | TEST_F(TracingServiceImplTest, StartTracingTriggerDifferentTrigger) { |
| 439 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 440 | consumer->Connect(svc.get()); |
| 441 | |
| 442 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 443 | producer->Connect(svc.get(), "mock_producer"); |
| 444 | |
| 445 | // Create two data sources but enable only one of them. |
| 446 | producer->RegisterDataSource("ds_1"); |
| 447 | producer->RegisterDataSource("ds_2"); |
| 448 | |
| 449 | TraceConfig trace_config; |
| 450 | trace_config.add_buffers()->set_size_kb(128); |
| 451 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 452 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 453 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::START_TRACING); |
| 454 | auto* trigger = trigger_config->add_triggers(); |
| 455 | trigger->set_name("trigger_name"); |
| 456 | trigger->set_stop_delay_ms(8.64e+7); |
| 457 | |
| 458 | trigger_config->set_trigger_timeout_ms(1); |
| 459 | |
| 460 | // Make sure we don't get unexpected DataSourceStart() notifications yet. |
| 461 | EXPECT_CALL(*producer, StartDataSource(_, _)).Times(0); |
| 462 | |
| 463 | consumer->EnableTracing(trace_config); |
| 464 | producer->WaitForTracingSetup(); |
| 465 | |
| 466 | producer->WaitForDataSourceSetup("ds_1"); |
| 467 | |
| 468 | // The trace won't start until we send the trigger called "trigger_name", |
| 469 | // since we have a START_TRACING trigger defined. This is where we'd expect to |
| 470 | // have an ActivateTriggers call to the producer->endpoint(), but we send a |
| 471 | // different trigger. |
| 472 | std::vector<std::string> req; |
| 473 | req.push_back("not_correct_trigger"); |
| 474 | producer->endpoint()->ActivateTriggers(req); |
| 475 | |
| 476 | producer->WaitForDataSourceStop("ds_1"); |
| 477 | consumer->WaitForTracingDisabled(); |
| 478 | EXPECT_THAT(consumer->ReadBuffers(), ::testing::IsEmpty()); |
| 479 | } |
| 480 | |
| 481 | // Creates a tracing session with a START_TRACING trigger and checks that any |
| 482 | // trigger can start the TracingSession. |
| 483 | TEST_F(TracingServiceImplTest, StartTracingTriggerMultipleTriggers) { |
| 484 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 485 | consumer->Connect(svc.get()); |
| 486 | |
| 487 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 488 | producer->Connect(svc.get(), "mock_producer"); |
| 489 | |
| 490 | // Create two data sources but enable only one of them. |
| 491 | producer->RegisterDataSource("ds_1"); |
| 492 | producer->RegisterDataSource("ds_2"); |
| 493 | |
| 494 | TraceConfig trace_config; |
| 495 | trace_config.add_buffers()->set_size_kb(128); |
| 496 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 497 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 498 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::START_TRACING); |
| 499 | auto* trigger = trigger_config->add_triggers(); |
| 500 | trigger->set_name("trigger_name"); |
| 501 | trigger->set_stop_delay_ms(1); |
| 502 | |
| 503 | trigger_config->set_trigger_timeout_ms(8.64e+7); |
| 504 | |
| 505 | consumer->EnableTracing(trace_config); |
| 506 | producer->WaitForTracingSetup(); |
| 507 | |
| 508 | producer->WaitForDataSourceSetup("ds_1"); |
| 509 | |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 510 | std::vector<std::string> req; |
| 511 | req.push_back("not_correct_trigger"); |
| 512 | req.push_back("trigger_name"); |
| 513 | producer->endpoint()->ActivateTriggers(req); |
| 514 | |
| 515 | producer->WaitForDataSourceStart("ds_1"); |
| 516 | |
| 517 | auto writer = producer->CreateTraceWriter("ds_1"); |
| 518 | producer->WaitForFlush(writer.get()); |
| 519 | |
| 520 | producer->WaitForDataSourceStop("ds_1"); |
| 521 | consumer->WaitForTracingDisabled(); |
| 522 | EXPECT_THAT( |
| 523 | consumer->ReadBuffers(), |
| 524 | HasTriggerMode(protos::TraceConfig::TriggerConfig::START_TRACING)); |
| 525 | } |
| 526 | |
| 527 | // Creates two tracing sessions with a START_TRACING trigger and checks that |
| 528 | // both are able to be triggered simultaneously. |
| 529 | TEST_F(TracingServiceImplTest, StartTracingTriggerMultipleTraces) { |
| 530 | std::unique_ptr<MockConsumer> consumer_1 = CreateMockConsumer(); |
| 531 | consumer_1->Connect(svc.get()); |
| 532 | std::unique_ptr<MockConsumer> consumer_2 = CreateMockConsumer(); |
| 533 | consumer_2->Connect(svc.get()); |
| 534 | |
| 535 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 536 | producer->Connect(svc.get(), "mock_producer"); |
| 537 | |
| 538 | // Create two data sources but each TracingSession will only enable one of |
| 539 | // them. |
| 540 | producer->RegisterDataSource("ds_1"); |
| 541 | producer->RegisterDataSource("ds_2"); |
| 542 | |
| 543 | TraceConfig trace_config; |
| 544 | trace_config.add_buffers()->set_size_kb(128); |
| 545 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 546 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 547 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::START_TRACING); |
| 548 | auto* trigger = trigger_config->add_triggers(); |
| 549 | trigger->set_name("trigger_name"); |
| 550 | trigger->set_stop_delay_ms(1); |
| 551 | |
| 552 | trigger_config->set_trigger_timeout_ms(8.64e+7); |
| 553 | |
| 554 | consumer_1->EnableTracing(trace_config); |
| 555 | producer->WaitForTracingSetup(); |
| 556 | |
| 557 | producer->WaitForDataSourceSetup("ds_1"); |
| 558 | |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 559 | auto tracing_session_1_id = GetTracingSessionID(); |
| 560 | |
| 561 | (*trace_config.mutable_data_sources())[0].mutable_config()->set_name("ds_2"); |
| 562 | trigger = trace_config.mutable_trigger_config()->add_triggers(); |
| 563 | trigger->set_name("trigger_name_2"); |
| 564 | trigger->set_stop_delay_ms(8.64e+7); |
| 565 | |
| 566 | consumer_2->EnableTracing(trace_config); |
| 567 | |
| 568 | producer->WaitForDataSourceSetup("ds_2"); |
| 569 | |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 570 | auto tracing_session_2_id = GetTracingSessionID(); |
| 571 | EXPECT_NE(tracing_session_1_id, tracing_session_2_id); |
| 572 | |
| 573 | const DataSourceInstanceID id1 = producer->GetDataSourceInstanceId("ds_1"); |
| 574 | const DataSourceInstanceID id2 = producer->GetDataSourceInstanceId("ds_2"); |
| 575 | |
| 576 | std::vector<std::string> req; |
| 577 | req.push_back("not_correct_trigger"); |
| 578 | req.push_back("trigger_name"); |
| 579 | req.push_back("trigger_name_2"); |
| 580 | producer->endpoint()->ActivateTriggers(req); |
| 581 | |
| 582 | // The order has to be the same as the triggers or else we're incorrectly wait |
| 583 | // on the wrong checkpoint in the |task_runner|. |
| 584 | producer->WaitForDataSourceStart("ds_1"); |
| 585 | producer->WaitForDataSourceStart("ds_2"); |
| 586 | |
| 587 | // Now that they've started we can check the triggers they've seen. |
| 588 | auto* tracing_session_1 = GetTracingSession(tracing_session_1_id); |
| 589 | ASSERT_EQ(1u, tracing_session_1->received_triggers.size()); |
| 590 | EXPECT_EQ("trigger_name", |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 591 | tracing_session_1->received_triggers[0].trigger_name); |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 592 | |
| 593 | // This is actually dependent on the order in which the triggers were received |
| 594 | // but there isn't really a better way than iteration order so probably not to |
| 595 | // brittle of a test. And this caught a real bug in implementation. |
| 596 | auto* tracing_session_2 = GetTracingSession(tracing_session_2_id); |
| 597 | ASSERT_EQ(2u, tracing_session_2->received_triggers.size()); |
| 598 | |
| 599 | EXPECT_EQ("trigger_name", |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 600 | tracing_session_2->received_triggers[0].trigger_name); |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 601 | |
| 602 | EXPECT_EQ("trigger_name_2", |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 603 | tracing_session_2->received_triggers[1].trigger_name); |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 604 | |
| 605 | auto writer1 = producer->CreateTraceWriter("ds_1"); |
| 606 | auto writer2 = producer->CreateTraceWriter("ds_2"); |
| 607 | |
| 608 | // We can't use the standard WaitForX in the MockProducer and MockConsumer |
| 609 | // because they assume only a single trace is going on. So we perform our own |
| 610 | // expectations and wait at the end for the two consumers to receive |
| 611 | // OnTracingDisabled. |
| 612 | bool flushed_writer_1 = false; |
| 613 | bool flushed_writer_2 = false; |
| 614 | auto flush_correct_writer = [&](FlushRequestID flush_req_id, |
| 615 | const DataSourceInstanceID* id, size_t) { |
| 616 | if (*id == id1) { |
| 617 | flushed_writer_1 = true; |
| 618 | writer1->Flush(); |
| 619 | producer->endpoint()->NotifyFlushComplete(flush_req_id); |
| 620 | } else if (*id == id2) { |
| 621 | flushed_writer_2 = true; |
| 622 | writer2->Flush(); |
| 623 | producer->endpoint()->NotifyFlushComplete(flush_req_id); |
| 624 | } |
| 625 | }; |
| 626 | EXPECT_CALL(*producer, Flush(_, _, _)) |
| 627 | .WillOnce(Invoke(flush_correct_writer)) |
| 628 | .WillOnce(Invoke(flush_correct_writer)); |
| 629 | |
| 630 | auto checkpoint_name = "on_tracing_disabled_consumer_1_and_2"; |
| 631 | auto on_tracing_disabled = task_runner.CreateCheckpoint(checkpoint_name); |
| 632 | std::atomic<size_t> counter(0); |
| 633 | EXPECT_CALL(*consumer_1, OnTracingDisabled()).WillOnce(Invoke([&]() { |
| 634 | if (++counter == 2u) { |
| 635 | on_tracing_disabled(); |
| 636 | } |
| 637 | })); |
| 638 | EXPECT_CALL(*consumer_2, OnTracingDisabled()).WillOnce(Invoke([&]() { |
| 639 | if (++counter == 2u) { |
| 640 | on_tracing_disabled(); |
| 641 | } |
| 642 | })); |
| 643 | |
| 644 | EXPECT_CALL(*producer, StopDataSource(id1)); |
| 645 | EXPECT_CALL(*producer, StopDataSource(id2)); |
| 646 | |
| 647 | task_runner.RunUntilCheckpoint(checkpoint_name, 1000); |
| 648 | |
| 649 | EXPECT_TRUE(flushed_writer_1); |
| 650 | EXPECT_TRUE(flushed_writer_2); |
| 651 | EXPECT_THAT( |
| 652 | consumer_1->ReadBuffers(), |
| 653 | HasTriggerMode(protos::TraceConfig::TriggerConfig::START_TRACING)); |
| 654 | EXPECT_THAT( |
| 655 | consumer_2->ReadBuffers(), |
| 656 | HasTriggerMode(protos::TraceConfig::TriggerConfig::START_TRACING)); |
| 657 | } |
| 658 | |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 659 | // Creates a tracing session with a START_TRACING trigger and checks that the |
| 660 | // received_triggers are emitted as packets. |
| 661 | TEST_F(TracingServiceImplTest, EmitTriggersWithStartTracingTrigger) { |
| 662 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 663 | consumer->Connect(svc.get()); |
| 664 | |
| 665 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 666 | producer->Connect(svc.get(), "mock_producer", /* uid = */ 123u); |
| 667 | |
| 668 | producer->RegisterDataSource("ds_1"); |
| 669 | |
| 670 | TraceConfig trace_config; |
| 671 | trace_config.add_buffers()->set_size_kb(128); |
| 672 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 673 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 674 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::START_TRACING); |
| 675 | auto* trigger = trigger_config->add_triggers(); |
| 676 | trigger->set_name("trigger_name"); |
| 677 | trigger->set_stop_delay_ms(1); |
| 678 | trigger->set_producer_name_regex("mock_produc[e-r]+"); |
| 679 | |
| 680 | trigger_config->set_trigger_timeout_ms(30000); |
| 681 | |
| 682 | consumer->EnableTracing(trace_config); |
| 683 | producer->WaitForTracingSetup(); |
| 684 | producer->WaitForDataSourceSetup("ds_1"); |
| 685 | |
| 686 | // The trace won't start until we send the trigger since we have a |
| 687 | // START_TRACING trigger defined. |
| 688 | std::vector<std::string> req; |
| 689 | req.push_back("trigger_name"); |
| 690 | req.push_back("trigger_name_2"); |
| 691 | req.push_back("trigger_name_3"); |
| 692 | producer->endpoint()->ActivateTriggers(req); |
| 693 | |
| 694 | producer->WaitForDataSourceStart("ds_1"); |
| 695 | auto writer1 = producer->CreateTraceWriter("ds_1"); |
| 696 | producer->WaitForFlush(writer1.get()); |
| 697 | producer->WaitForDataSourceStop("ds_1"); |
| 698 | consumer->WaitForTracingDisabled(); |
| 699 | |
| 700 | ASSERT_EQ(1u, tracing_session()->received_triggers.size()); |
| 701 | EXPECT_EQ("trigger_name", |
| 702 | tracing_session()->received_triggers[0].trigger_name); |
| 703 | |
| 704 | auto packets = consumer->ReadBuffers(); |
| 705 | EXPECT_THAT( |
| 706 | packets, |
| 707 | Contains(Property( |
| 708 | &protos::TracePacket::trace_config, |
| 709 | Property( |
| 710 | &protos::TraceConfig::trigger_config, |
| 711 | Property( |
| 712 | &protos::TraceConfig::TriggerConfig::trigger_mode, |
| 713 | Eq(protos::TraceConfig::TriggerConfig::START_TRACING)))))); |
| 714 | auto expect_received_trigger = [&](const std::string& name) { |
| 715 | return Contains(AllOf( |
| 716 | Property( |
| 717 | &protos::TracePacket::trigger, |
| 718 | AllOf(Property(&protos::Trigger::trigger_name, Eq(name)), |
Eric Seckler | c4024b2 | 2019-04-02 15:37:08 +0000 | [diff] [blame] | 719 | Property(&protos::Trigger::trusted_producer_uid, Eq(123)), |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 720 | Property(&protos::Trigger::producer_name, |
| 721 | Eq("mock_producer")))), |
| 722 | Property(&protos::TracePacket::trusted_packet_sequence_id, |
| 723 | Eq(kServicePacketSequenceID)))); |
| 724 | }; |
| 725 | EXPECT_THAT(packets, expect_received_trigger("trigger_name")); |
| 726 | EXPECT_THAT(packets, |
| 727 | ::testing::Not(expect_received_trigger("trigger_name_2"))); |
| 728 | EXPECT_THAT(packets, |
| 729 | ::testing::Not(expect_received_trigger("trigger_name_3"))); |
| 730 | } |
| 731 | |
| 732 | // Creates a tracing session with a START_TRACING trigger and checks that the |
| 733 | // received_triggers are emitted as packets. |
| 734 | TEST_F(TracingServiceImplTest, EmitTriggersWithStopTracingTrigger) { |
| 735 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 736 | consumer->Connect(svc.get()); |
| 737 | |
| 738 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 739 | producer->Connect(svc.get(), "mock_producer", /* uid = */ 321u); |
| 740 | |
| 741 | producer->RegisterDataSource("ds_1"); |
| 742 | |
| 743 | TraceConfig trace_config; |
| 744 | trace_config.add_buffers()->set_size_kb(128); |
| 745 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 746 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 747 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::STOP_TRACING); |
| 748 | auto* trigger = trigger_config->add_triggers(); |
| 749 | trigger->set_name("trigger_name"); |
| 750 | trigger->set_stop_delay_ms(1); |
| 751 | trigger = trigger_config->add_triggers(); |
| 752 | trigger->set_name("trigger_name_3"); |
| 753 | trigger->set_stop_delay_ms(30000); |
| 754 | |
| 755 | trigger_config->set_trigger_timeout_ms(30000); |
| 756 | |
| 757 | consumer->EnableTracing(trace_config); |
| 758 | producer->WaitForTracingSetup(); |
| 759 | producer->WaitForDataSourceSetup("ds_1"); |
| 760 | producer->WaitForDataSourceStart("ds_1"); |
| 761 | |
| 762 | // The trace won't start until we send the trigger since we have a |
| 763 | // START_TRACING trigger defined. |
| 764 | std::vector<std::string> req; |
| 765 | req.push_back("trigger_name"); |
| 766 | req.push_back("trigger_name_2"); |
| 767 | req.push_back("trigger_name_3"); |
| 768 | producer->endpoint()->ActivateTriggers(req); |
| 769 | |
| 770 | auto writer1 = producer->CreateTraceWriter("ds_1"); |
| 771 | producer->WaitForFlush(writer1.get()); |
| 772 | producer->WaitForDataSourceStop("ds_1"); |
| 773 | consumer->WaitForTracingDisabled(); |
| 774 | |
| 775 | ASSERT_EQ(2u, tracing_session()->received_triggers.size()); |
| 776 | EXPECT_EQ("trigger_name", |
| 777 | tracing_session()->received_triggers[0].trigger_name); |
| 778 | EXPECT_EQ("trigger_name_3", |
| 779 | tracing_session()->received_triggers[1].trigger_name); |
| 780 | |
| 781 | auto packets = consumer->ReadBuffers(); |
| 782 | EXPECT_THAT( |
| 783 | packets, |
| 784 | Contains(Property( |
| 785 | &protos::TracePacket::trace_config, |
| 786 | Property( |
| 787 | &protos::TraceConfig::trigger_config, |
| 788 | Property( |
| 789 | &protos::TraceConfig::TriggerConfig::trigger_mode, |
| 790 | Eq(protos::TraceConfig::TriggerConfig::STOP_TRACING)))))); |
| 791 | |
| 792 | auto expect_received_trigger = [&](const std::string& name) { |
| 793 | return Contains(AllOf( |
| 794 | Property( |
| 795 | &protos::TracePacket::trigger, |
| 796 | AllOf(Property(&protos::Trigger::trigger_name, Eq(name)), |
Eric Seckler | c4024b2 | 2019-04-02 15:37:08 +0000 | [diff] [blame] | 797 | Property(&protos::Trigger::trusted_producer_uid, Eq(321)), |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 798 | Property(&protos::Trigger::producer_name, |
| 799 | Eq("mock_producer")))), |
| 800 | Property(&protos::TracePacket::trusted_packet_sequence_id, |
| 801 | Eq(kServicePacketSequenceID)))); |
| 802 | }; |
| 803 | EXPECT_THAT(packets, expect_received_trigger("trigger_name")); |
| 804 | EXPECT_THAT(packets, |
| 805 | ::testing::Not(expect_received_trigger("trigger_name_2"))); |
| 806 | EXPECT_THAT(packets, expect_received_trigger("trigger_name_3")); |
| 807 | } |
| 808 | |
| 809 | // Creates a tracing session with a START_TRACING trigger and checks that the |
| 810 | // received_triggers are emitted as packets even ones after the initial |
| 811 | // ReadBuffers() call. |
| 812 | TEST_F(TracingServiceImplTest, EmitTriggersRepeatedly) { |
| 813 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 814 | consumer->Connect(svc.get()); |
| 815 | |
| 816 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 817 | producer->Connect(svc.get(), "mock_producer"); |
| 818 | |
| 819 | // Create two data sources but enable only one of them. |
| 820 | producer->RegisterDataSource("ds_1"); |
| 821 | producer->RegisterDataSource("ds_2"); |
| 822 | |
| 823 | TraceConfig trace_config; |
| 824 | trace_config.add_buffers()->set_size_kb(128); |
| 825 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 826 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 827 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::STOP_TRACING); |
| 828 | auto* trigger = trigger_config->add_triggers(); |
| 829 | trigger->set_name("trigger_name"); |
| 830 | trigger->set_stop_delay_ms(1); |
| 831 | trigger = trigger_config->add_triggers(); |
| 832 | trigger->set_name("trigger_name_2"); |
| 833 | trigger->set_stop_delay_ms(1); |
| 834 | |
| 835 | trigger_config->set_trigger_timeout_ms(30000); |
| 836 | |
| 837 | auto expect_received_trigger = [&](const std::string& name) { |
| 838 | return Contains( |
| 839 | AllOf(Property(&protos::TracePacket::trigger, |
| 840 | AllOf(Property(&protos::Trigger::trigger_name, Eq(name)), |
| 841 | Property(&protos::Trigger::producer_name, |
| 842 | Eq("mock_producer")))), |
| 843 | Property(&protos::TracePacket::trusted_packet_sequence_id, |
| 844 | Eq(kServicePacketSequenceID)))); |
| 845 | }; |
| 846 | |
| 847 | consumer->EnableTracing(trace_config); |
| 848 | producer->WaitForTracingSetup(); |
| 849 | producer->WaitForDataSourceSetup("ds_1"); |
| 850 | producer->WaitForDataSourceStart("ds_1"); |
| 851 | |
| 852 | // The trace won't start until we send the trigger. since we have a |
| 853 | // START_TRACING trigger defined. |
| 854 | producer->endpoint()->ActivateTriggers({"trigger_name"}); |
| 855 | |
| 856 | auto packets = consumer->ReadBuffers(); |
| 857 | EXPECT_THAT( |
| 858 | packets, |
| 859 | Contains(Property( |
| 860 | &protos::TracePacket::trace_config, |
| 861 | Property( |
| 862 | &protos::TraceConfig::trigger_config, |
| 863 | Property( |
| 864 | &protos::TraceConfig::TriggerConfig::trigger_mode, |
| 865 | Eq(protos::TraceConfig::TriggerConfig::STOP_TRACING)))))); |
| 866 | EXPECT_THAT(packets, expect_received_trigger("trigger_name")); |
| 867 | EXPECT_THAT(packets, |
| 868 | ::testing::Not(expect_received_trigger("trigger_name_2"))); |
| 869 | |
| 870 | // Send a new trigger. |
| 871 | producer->endpoint()->ActivateTriggers({"trigger_name_2"}); |
| 872 | |
| 873 | auto writer1 = producer->CreateTraceWriter("ds_1"); |
| 874 | producer->WaitForFlush(writer1.get()); |
| 875 | producer->WaitForDataSourceStop("ds_1"); |
| 876 | consumer->WaitForTracingDisabled(); |
| 877 | |
| 878 | ASSERT_EQ(2u, tracing_session()->received_triggers.size()); |
| 879 | EXPECT_EQ("trigger_name", |
| 880 | tracing_session()->received_triggers[0].trigger_name); |
| 881 | EXPECT_EQ("trigger_name_2", |
| 882 | tracing_session()->received_triggers[1].trigger_name); |
| 883 | |
| 884 | packets = consumer->ReadBuffers(); |
| 885 | // We don't rewrite the old trigger. |
| 886 | EXPECT_THAT(packets, ::testing::Not(expect_received_trigger("trigger_name"))); |
| 887 | EXPECT_THAT(packets, expect_received_trigger("trigger_name_2")); |
| 888 | } |
| 889 | |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 890 | // Creates a tracing session with a STOP_TRACING trigger and checks that the |
| 891 | // session is cleaned up after |trigger_timeout_ms|. |
| 892 | TEST_F(TracingServiceImplTest, StopTracingTriggerTimeout) { |
| 893 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 894 | consumer->Connect(svc.get()); |
| 895 | |
| 896 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 897 | producer->Connect(svc.get(), "mock_producer"); |
| 898 | |
| 899 | // Create two data sources but enable only one of them. |
| 900 | producer->RegisterDataSource("ds_1"); |
| 901 | producer->RegisterDataSource("ds_2"); |
| 902 | |
| 903 | TraceConfig trace_config; |
| 904 | trace_config.add_buffers()->set_size_kb(128); |
| 905 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 906 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 907 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::STOP_TRACING); |
| 908 | auto* trigger = trigger_config->add_triggers(); |
| 909 | trigger->set_name("trigger_name"); |
| 910 | trigger->set_stop_delay_ms(8.64e+7); |
| 911 | |
| 912 | trigger_config->set_trigger_timeout_ms(1); |
| 913 | |
| 914 | // Make sure we don't get unexpected DataSourceStart() notifications yet. |
| 915 | EXPECT_CALL(*producer, StartDataSource(_, _)).Times(0); |
| 916 | |
| 917 | consumer->EnableTracing(trace_config); |
| 918 | producer->WaitForTracingSetup(); |
| 919 | |
| 920 | producer->WaitForDataSourceSetup("ds_1"); |
| 921 | producer->WaitForDataSourceStart("ds_1"); |
| 922 | |
| 923 | // The trace won't return data until unless we send a trigger at this point. |
| 924 | EXPECT_THAT(consumer->ReadBuffers(), ::testing::IsEmpty()); |
| 925 | |
| 926 | auto writer = producer->CreateTraceWriter("ds_1"); |
| 927 | producer->WaitForFlush(writer.get()); |
| 928 | |
| 929 | ASSERT_EQ(0u, tracing_session()->received_triggers.size()); |
| 930 | |
| 931 | producer->WaitForDataSourceStop("ds_1"); |
| 932 | consumer->WaitForTracingDisabled(); |
| 933 | EXPECT_THAT(consumer->ReadBuffers(), ::testing::IsEmpty()); |
| 934 | } |
| 935 | |
| 936 | // Creates a tracing session with a STOP_TRACING trigger and checks that the |
| 937 | // session returns data after a trigger is received, but only what is currently |
| 938 | // in the buffer. |
| 939 | TEST_F(TracingServiceImplTest, StopTracingTriggerRingBuffer) { |
| 940 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 941 | consumer->Connect(svc.get()); |
| 942 | |
| 943 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 944 | producer->Connect(svc.get(), "mock_producer"); |
| 945 | |
| 946 | // Create two data sources but enable only one of them. |
| 947 | producer->RegisterDataSource("ds_1"); |
| 948 | producer->RegisterDataSource("ds_2"); |
| 949 | |
| 950 | TraceConfig trace_config; |
| 951 | trace_config.add_buffers()->set_size_kb(128); |
| 952 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 953 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 954 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::STOP_TRACING); |
| 955 | auto* trigger = trigger_config->add_triggers(); |
| 956 | trigger->set_name("trigger_name"); |
| 957 | trigger->set_stop_delay_ms(1); |
| 958 | |
| 959 | trigger_config->set_trigger_timeout_ms(8.64e+7); |
| 960 | |
| 961 | consumer->EnableTracing(trace_config); |
| 962 | producer->WaitForTracingSetup(); |
| 963 | |
| 964 | producer->WaitForDataSourceSetup("ds_1"); |
| 965 | producer->WaitForDataSourceStart("ds_1"); |
| 966 | |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 967 | // The trace won't return data until unless we send a trigger at this point. |
| 968 | EXPECT_THAT(consumer->ReadBuffers(), ::testing::IsEmpty()); |
| 969 | |
| 970 | // We write into the buffer a large packet which takes up the whole buffer. We |
| 971 | // then add a bunch of smaller ones which causes the larger packet to be |
| 972 | // dropped. After we activate the session we should only see a bunch of the |
| 973 | // smaller ones. |
Eric Seckler | a883e5f | 2019-04-02 22:51:29 +0000 | [diff] [blame] | 974 | static const size_t kNumTestPackets = 10; |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 975 | static const char kPayload[] = "1234567890abcdef-"; |
| 976 | |
| 977 | auto writer = producer->CreateTraceWriter("ds_1"); |
| 978 | // Buffer is 1kb so we write a packet which is slightly smaller so it fits in |
| 979 | // the buffer. |
| 980 | const std::string large_payload(1024 * 128 - 20, 'a'); |
| 981 | { |
| 982 | auto tp = writer->NewTracePacket(); |
| 983 | tp->set_for_testing()->set_str(large_payload.c_str(), large_payload.size()); |
| 984 | } |
| 985 | |
| 986 | // Now we add a bunch of data before the trigger and after. |
Eric Seckler | a883e5f | 2019-04-02 22:51:29 +0000 | [diff] [blame] | 987 | for (size_t i = 0; i < kNumTestPackets; i++) { |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 988 | if (i == kNumTestPackets / 2) { |
| 989 | std::vector<std::string> req; |
| 990 | req.push_back("trigger_name"); |
| 991 | producer->endpoint()->ActivateTriggers(req); |
| 992 | } |
| 993 | auto tp = writer->NewTracePacket(); |
| 994 | std::string payload(kPayload); |
| 995 | payload.append(std::to_string(i)); |
| 996 | tp->set_for_testing()->set_str(payload.c_str(), payload.size()); |
| 997 | } |
| 998 | producer->WaitForFlush(writer.get()); |
| 999 | |
| 1000 | ASSERT_EQ(1u, tracing_session()->received_triggers.size()); |
| 1001 | EXPECT_EQ("trigger_name", |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 1002 | tracing_session()->received_triggers[0].trigger_name); |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 1003 | |
| 1004 | producer->WaitForDataSourceStop("ds_1"); |
| 1005 | consumer->WaitForTracingDisabled(); |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 1006 | |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 1007 | auto packets = consumer->ReadBuffers(); |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 1008 | EXPECT_LT(kNumTestPackets, packets.size()); |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 1009 | // We expect for the TraceConfig preamble packet to be there correctly and |
| 1010 | // then we expect each payload to be there, but not the |large_payload| |
| 1011 | // packet. |
| 1012 | EXPECT_THAT(packets, |
| 1013 | HasTriggerMode(protos::TraceConfig::TriggerConfig::STOP_TRACING)); |
Eric Seckler | a883e5f | 2019-04-02 22:51:29 +0000 | [diff] [blame] | 1014 | for (size_t i = 0; i < kNumTestPackets; i++) { |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 1015 | std::string payload = kPayload; |
| 1016 | payload += std::to_string(i); |
| 1017 | EXPECT_THAT(packets, Contains(Property( |
| 1018 | &protos::TracePacket::for_testing, |
| 1019 | Property(&protos::TestEvent::str, Eq(payload))))); |
| 1020 | } |
| 1021 | |
| 1022 | // The large payload was overwritten before we trigger and ReadBuffers so it |
| 1023 | // should not be in the returned data. |
| 1024 | EXPECT_THAT(packets, |
| 1025 | ::testing::Not(Contains(Property( |
| 1026 | &protos::TracePacket::for_testing, |
| 1027 | Property(&protos::TestEvent::str, Eq(large_payload)))))); |
| 1028 | } |
| 1029 | |
| 1030 | // Creates a tracing session with a STOP_TRACING trigger and checks that the |
| 1031 | // session only cleans up once even with multiple triggers. |
| 1032 | TEST_F(TracingServiceImplTest, StopTracingTriggerMultipleTriggers) { |
| 1033 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1034 | consumer->Connect(svc.get()); |
| 1035 | |
| 1036 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1037 | producer->Connect(svc.get(), "mock_producer"); |
| 1038 | |
| 1039 | // Create two data sources but enable only one of them. |
| 1040 | producer->RegisterDataSource("ds_1"); |
| 1041 | producer->RegisterDataSource("ds_2"); |
| 1042 | |
| 1043 | TraceConfig trace_config; |
| 1044 | trace_config.add_buffers()->set_size_kb(128); |
| 1045 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 1046 | auto* trigger_config = trace_config.mutable_trigger_config(); |
| 1047 | trigger_config->set_trigger_mode(TraceConfig::TriggerConfig::STOP_TRACING); |
| 1048 | auto* trigger = trigger_config->add_triggers(); |
| 1049 | trigger->set_name("trigger_name"); |
| 1050 | trigger->set_stop_delay_ms(1); |
| 1051 | trigger = trigger_config->add_triggers(); |
| 1052 | trigger->set_name("trigger_name_2"); |
| 1053 | trigger->set_stop_delay_ms(8.64e+7); |
| 1054 | |
| 1055 | trigger_config->set_trigger_timeout_ms(8.64e+7); |
| 1056 | |
| 1057 | consumer->EnableTracing(trace_config); |
| 1058 | producer->WaitForTracingSetup(); |
| 1059 | |
| 1060 | producer->WaitForDataSourceSetup("ds_1"); |
| 1061 | producer->WaitForDataSourceStart("ds_1"); |
| 1062 | |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 1063 | // The trace won't return data until unless we send a trigger at this point. |
| 1064 | EXPECT_THAT(consumer->ReadBuffers(), ::testing::IsEmpty()); |
| 1065 | |
| 1066 | std::vector<std::string> req; |
| 1067 | req.push_back("trigger_name"); |
| 1068 | req.push_back("trigger_name_3"); |
| 1069 | req.push_back("trigger_name_2"); |
| 1070 | producer->endpoint()->ActivateTriggers(req); |
| 1071 | |
| 1072 | auto writer = producer->CreateTraceWriter("ds_1"); |
| 1073 | producer->WaitForFlush(writer.get()); |
| 1074 | |
| 1075 | ASSERT_EQ(2u, tracing_session()->received_triggers.size()); |
| 1076 | EXPECT_EQ("trigger_name", |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 1077 | tracing_session()->received_triggers[0].trigger_name); |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 1078 | EXPECT_EQ("trigger_name_2", |
Stephen Nusko | 70ea330 | 2019-04-01 19:44:40 +0100 | [diff] [blame] | 1079 | tracing_session()->received_triggers[1].trigger_name); |
Stephen Nusko | d95a751 | 2019-03-22 13:59:39 +0000 | [diff] [blame] | 1080 | |
| 1081 | producer->WaitForDataSourceStop("ds_1"); |
| 1082 | consumer->WaitForTracingDisabled(); |
| 1083 | EXPECT_THAT(consumer->ReadBuffers(), |
| 1084 | HasTriggerMode(protos::TraceConfig::TriggerConfig::STOP_TRACING)); |
| 1085 | } |
| 1086 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 1087 | TEST_F(TracingServiceImplTest, LockdownMode) { |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1088 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1089 | consumer->Connect(svc.get()); |
| 1090 | |
| 1091 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1092 | producer->Connect(svc.get(), "mock_producer_sameuid", geteuid()); |
| 1093 | producer->RegisterDataSource("data_source"); |
Florian Mayer | 61c5548 | 2018-03-06 14:43:54 +0000 | [diff] [blame] | 1094 | |
| 1095 | TraceConfig trace_config; |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1096 | trace_config.add_buffers()->set_size_kb(128); |
| 1097 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 1098 | ds_config->set_name("data_source"); |
Florian Mayer | 61c5548 | 2018-03-06 14:43:54 +0000 | [diff] [blame] | 1099 | trace_config.set_lockdown_mode( |
| 1100 | TraceConfig::LockdownModeOperation::LOCKDOWN_SET); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1101 | consumer->EnableTracing(trace_config); |
| 1102 | |
| 1103 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1104 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1105 | producer->WaitForDataSourceStart("data_source"); |
| 1106 | |
| 1107 | std::unique_ptr<MockProducer> producer_otheruid = CreateMockProducer(); |
| 1108 | auto x = svc->ConnectProducer(producer_otheruid.get(), geteuid() + 1, |
| 1109 | "mock_producer_ouid"); |
| 1110 | EXPECT_CALL(*producer_otheruid, OnConnect()).Times(0); |
Florian Mayer | 61c5548 | 2018-03-06 14:43:54 +0000 | [diff] [blame] | 1111 | task_runner.RunUntilIdle(); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1112 | Mock::VerifyAndClearExpectations(producer_otheruid.get()); |
Florian Mayer | 61c5548 | 2018-03-06 14:43:54 +0000 | [diff] [blame] | 1113 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1114 | consumer->DisableTracing(); |
| 1115 | consumer->FreeBuffers(); |
| 1116 | producer->WaitForDataSourceStop("data_source"); |
| 1117 | consumer->WaitForTracingDisabled(); |
Florian Mayer | 61c5548 | 2018-03-06 14:43:54 +0000 | [diff] [blame] | 1118 | |
| 1119 | trace_config.set_lockdown_mode( |
| 1120 | TraceConfig::LockdownModeOperation::LOCKDOWN_CLEAR); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1121 | consumer->EnableTracing(trace_config); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1122 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1123 | producer->WaitForDataSourceStart("data_source"); |
Florian Mayer | 61c5548 | 2018-03-06 14:43:54 +0000 | [diff] [blame] | 1124 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1125 | std::unique_ptr<MockProducer> producer_otheruid2 = CreateMockProducer(); |
| 1126 | producer_otheruid->Connect(svc.get(), "mock_producer_ouid2", geteuid() + 1); |
Florian Mayer | 61c5548 | 2018-03-06 14:43:54 +0000 | [diff] [blame] | 1127 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1128 | consumer->DisableTracing(); |
| 1129 | producer->WaitForDataSourceStop("data_source"); |
| 1130 | consumer->WaitForTracingDisabled(); |
Florian Mayer | 61c5548 | 2018-03-06 14:43:54 +0000 | [diff] [blame] | 1131 | } |
| 1132 | |
Oystein Eftevaag | cb6e4c8 | 2019-03-06 15:38:26 -0800 | [diff] [blame] | 1133 | TEST_F(TracingServiceImplTest, ProducerNameFilterChange) { |
| 1134 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1135 | consumer->Connect(svc.get()); |
| 1136 | |
| 1137 | std::unique_ptr<MockProducer> producer1 = CreateMockProducer(); |
| 1138 | producer1->Connect(svc.get(), "mock_producer_1"); |
| 1139 | producer1->RegisterDataSource("data_source"); |
| 1140 | |
| 1141 | std::unique_ptr<MockProducer> producer2 = CreateMockProducer(); |
| 1142 | producer2->Connect(svc.get(), "mock_producer_2"); |
| 1143 | producer2->RegisterDataSource("data_source"); |
| 1144 | |
| 1145 | std::unique_ptr<MockProducer> producer3 = CreateMockProducer(); |
| 1146 | producer3->Connect(svc.get(), "mock_producer_3"); |
| 1147 | producer3->RegisterDataSource("data_source"); |
| 1148 | producer3->RegisterDataSource("unused_data_source"); |
| 1149 | |
| 1150 | TraceConfig trace_config; |
| 1151 | trace_config.add_buffers()->set_size_kb(128); |
| 1152 | auto* data_source = trace_config.add_data_sources(); |
| 1153 | data_source->mutable_config()->set_name("data_source"); |
| 1154 | *data_source->add_producer_name_filter() = "mock_producer_1"; |
| 1155 | |
| 1156 | // Enable tracing with only mock_producer_1 enabled; |
| 1157 | // the rest should not start up. |
| 1158 | consumer->EnableTracing(trace_config); |
| 1159 | |
| 1160 | producer1->WaitForTracingSetup(); |
| 1161 | producer1->WaitForDataSourceSetup("data_source"); |
| 1162 | producer1->WaitForDataSourceStart("data_source"); |
| 1163 | |
| 1164 | EXPECT_CALL(*producer2, OnConnect()).Times(0); |
| 1165 | EXPECT_CALL(*producer3, OnConnect()).Times(0); |
| 1166 | task_runner.RunUntilIdle(); |
| 1167 | Mock::VerifyAndClearExpectations(producer2.get()); |
| 1168 | Mock::VerifyAndClearExpectations(producer3.get()); |
| 1169 | |
| 1170 | // Enable mock_producer_2, the third one should still |
| 1171 | // not get connected. |
| 1172 | *data_source->add_producer_name_filter() = "mock_producer_2"; |
| 1173 | consumer->ChangeTraceConfig(trace_config); |
| 1174 | |
| 1175 | producer2->WaitForTracingSetup(); |
| 1176 | producer2->WaitForDataSourceSetup("data_source"); |
| 1177 | producer2->WaitForDataSourceStart("data_source"); |
| 1178 | |
| 1179 | // Enable mock_producer_3 but also try to do an |
| 1180 | // unsupported change (adding a new data source); |
| 1181 | // mock_producer_3 should get enabled but not |
| 1182 | // for the new data source. |
| 1183 | *data_source->add_producer_name_filter() = "mock_producer_3"; |
| 1184 | auto* dummy_data_source = trace_config.add_data_sources(); |
| 1185 | dummy_data_source->mutable_config()->set_name("unused_data_source"); |
| 1186 | *dummy_data_source->add_producer_name_filter() = "mock_producer_3"; |
| 1187 | |
| 1188 | consumer->ChangeTraceConfig(trace_config); |
| 1189 | |
| 1190 | producer3->WaitForTracingSetup(); |
| 1191 | EXPECT_CALL(*producer3, SetupDataSource(_, _)).Times(1); |
| 1192 | EXPECT_CALL(*producer3, StartDataSource(_, _)).Times(1); |
| 1193 | task_runner.RunUntilIdle(); |
| 1194 | Mock::VerifyAndClearExpectations(producer3.get()); |
| 1195 | |
| 1196 | consumer->DisableTracing(); |
| 1197 | consumer->FreeBuffers(); |
| 1198 | producer1->WaitForDataSourceStop("data_source"); |
| 1199 | producer2->WaitForDataSourceStop("data_source"); |
| 1200 | |
| 1201 | EXPECT_CALL(*producer3, StopDataSource(_)).Times(1); |
| 1202 | |
| 1203 | consumer->WaitForTracingDisabled(); |
| 1204 | |
| 1205 | task_runner.RunUntilIdle(); |
| 1206 | Mock::VerifyAndClearExpectations(producer3.get()); |
| 1207 | } |
| 1208 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 1209 | TEST_F(TracingServiceImplTest, DisconnectConsumerWhileTracing) { |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1210 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1211 | consumer->Connect(svc.get()); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1212 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1213 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1214 | producer->Connect(svc.get(), "mock_producer"); |
| 1215 | producer->RegisterDataSource("data_source"); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1216 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1217 | TraceConfig trace_config; |
| 1218 | trace_config.add_buffers()->set_size_kb(128); |
| 1219 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 1220 | ds_config->set_name("data_source"); |
| 1221 | consumer->EnableTracing(trace_config); |
| 1222 | |
| 1223 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1224 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1225 | producer->WaitForDataSourceStart("data_source"); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1226 | |
| 1227 | // Disconnecting the consumer while tracing should trigger data source |
| 1228 | // teardown. |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1229 | consumer.reset(); |
| 1230 | producer->WaitForDataSourceStop("data_source"); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1231 | } |
| 1232 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 1233 | TEST_F(TracingServiceImplTest, ReconnectProducerWhileTracing) { |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1234 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1235 | consumer->Connect(svc.get()); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1236 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1237 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1238 | producer->Connect(svc.get(), "mock_producer"); |
| 1239 | producer->RegisterDataSource("data_source"); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1240 | |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1241 | TraceConfig trace_config; |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1242 | trace_config.add_buffers()->set_size_kb(128); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1243 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1244 | ds_config->set_name("data_source"); |
| 1245 | consumer->EnableTracing(trace_config); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1246 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1247 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1248 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1249 | producer->WaitForDataSourceStart("data_source"); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1250 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1251 | // Disconnecting and reconnecting a producer with a matching data source. |
| 1252 | // The Producer should see that data source getting enabled again. |
| 1253 | producer.reset(); |
| 1254 | producer = CreateMockProducer(); |
| 1255 | producer->Connect(svc.get(), "mock_producer_2"); |
| 1256 | producer->RegisterDataSource("data_source"); |
| 1257 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1258 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1259 | producer->WaitForDataSourceStart("data_source"); |
Sami Kyostila | 06487a2 | 2018-02-27 13:48:38 +0000 | [diff] [blame] | 1260 | } |
| 1261 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 1262 | TEST_F(TracingServiceImplTest, ProducerIDWrapping) { |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1263 | std::vector<std::unique_ptr<MockProducer>> producers; |
| 1264 | producers.push_back(nullptr); |
Primiano Tucci | 081d46a | 2018-02-28 11:09:43 +0000 | [diff] [blame] | 1265 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1266 | auto connect_producer_and_get_id = [&producers, |
| 1267 | this](const std::string& name) { |
| 1268 | producers.emplace_back(CreateMockProducer()); |
| 1269 | producers.back()->Connect(svc.get(), "mock_producer_" + name); |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 1270 | return *last_producer_id(); |
Primiano Tucci | 081d46a | 2018-02-28 11:09:43 +0000 | [diff] [blame] | 1271 | }; |
| 1272 | |
| 1273 | // Connect producers 1-4. |
| 1274 | for (ProducerID i = 1; i <= 4; i++) |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1275 | ASSERT_EQ(i, connect_producer_and_get_id(std::to_string(i))); |
Primiano Tucci | 081d46a | 2018-02-28 11:09:43 +0000 | [diff] [blame] | 1276 | |
| 1277 | // Disconnect producers 1,3. |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1278 | producers[1].reset(); |
| 1279 | producers[3].reset(); |
Primiano Tucci | 081d46a | 2018-02-28 11:09:43 +0000 | [diff] [blame] | 1280 | |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 1281 | *last_producer_id() = kMaxProducerID - 1; |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1282 | ASSERT_EQ(kMaxProducerID, connect_producer_and_get_id("maxid")); |
| 1283 | ASSERT_EQ(1u, connect_producer_and_get_id("1_again")); |
| 1284 | ASSERT_EQ(3u, connect_producer_and_get_id("3_again")); |
| 1285 | ASSERT_EQ(5u, connect_producer_and_get_id("5")); |
| 1286 | ASSERT_EQ(6u, connect_producer_and_get_id("6")); |
Primiano Tucci | 081d46a | 2018-02-28 11:09:43 +0000 | [diff] [blame] | 1287 | } |
| 1288 | |
Ryan Savitski | cc28cbf | 2018-11-09 22:55:12 +0000 | [diff] [blame] | 1289 | // Note: file_write_period_ms is set to a large enough to have exactly one flush |
| 1290 | // of the tracing buffers (and therefore at most one synchronization section), |
| 1291 | // unless the test runs unrealistically slowly, or the implementation of the |
| 1292 | // tracing snapshot packets changes. |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 1293 | TEST_F(TracingServiceImplTest, WriteIntoFileAndStopOnMaxSize) { |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1294 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1295 | consumer->Connect(svc.get()); |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1296 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1297 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1298 | producer->Connect(svc.get(), "mock_producer"); |
| 1299 | producer->RegisterDataSource("data_source"); |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1300 | |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1301 | TraceConfig trace_config; |
| 1302 | trace_config.add_buffers()->set_size_kb(4096); |
| 1303 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1304 | ds_config->set_name("data_source"); |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1305 | ds_config->set_target_buffer(0); |
| 1306 | trace_config.set_write_into_file(true); |
Ryan Savitski | cc28cbf | 2018-11-09 22:55:12 +0000 | [diff] [blame] | 1307 | trace_config.set_file_write_period_ms(100000); // 100s |
| 1308 | const uint64_t kMaxFileSize = 1024; |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1309 | trace_config.set_max_file_size_bytes(kMaxFileSize); |
| 1310 | base::TempFile tmp_file = base::TempFile::Create(); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1311 | consumer->EnableTracing(trace_config, base::ScopedFile(dup(tmp_file.fd()))); |
| 1312 | |
| 1313 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1314 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1315 | producer->WaitForDataSourceStart("data_source"); |
| 1316 | |
Hector Dearman | 685f752 | 2019-03-12 14:28:56 +0000 | [diff] [blame] | 1317 | // The preamble packets are: |
| 1318 | // Config |
| 1319 | // SystemInfo |
| 1320 | // 3x unknown |
| 1321 | static const int kNumPreamblePackets = 5; |
Ryan Savitski | cc28cbf | 2018-11-09 22:55:12 +0000 | [diff] [blame] | 1322 | static const int kNumTestPackets = 10; |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1323 | static const char kPayload[] = "1234567890abcdef-"; |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1324 | |
| 1325 | std::unique_ptr<TraceWriter> writer = |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1326 | producer->CreateTraceWriter("data_source"); |
Ryan Savitski | cc28cbf | 2018-11-09 22:55:12 +0000 | [diff] [blame] | 1327 | // Tracing service will emit a preamble of packets (a synchronization section, |
| 1328 | // followed by a tracing config packet). The preamble and these test packets |
| 1329 | // should fit within kMaxFileSize. |
| 1330 | for (int i = 0; i < kNumTestPackets; i++) { |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1331 | auto tp = writer->NewTracePacket(); |
| 1332 | std::string payload(kPayload); |
| 1333 | payload.append(std::to_string(i)); |
| 1334 | tp->set_for_testing()->set_str(payload.c_str(), payload.size()); |
| 1335 | } |
| 1336 | |
| 1337 | // Finally add a packet that overflows kMaxFileSize. This should cause the |
| 1338 | // implicit stop of the trace and should *not* be written in the trace. |
| 1339 | { |
| 1340 | auto tp = writer->NewTracePacket(); |
| 1341 | char big_payload[kMaxFileSize] = "BIG!"; |
| 1342 | tp->set_for_testing()->set_str(big_payload, sizeof(big_payload)); |
| 1343 | } |
| 1344 | writer->Flush(); |
| 1345 | writer.reset(); |
| 1346 | |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1347 | consumer->DisableTracing(); |
| 1348 | producer->WaitForDataSourceStop("data_source"); |
| 1349 | consumer->WaitForTracingDisabled(); |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1350 | |
| 1351 | // Verify the contents of the file. |
| 1352 | std::string trace_raw; |
| 1353 | ASSERT_TRUE(base::ReadFile(tmp_file.path().c_str(), &trace_raw)); |
| 1354 | protos::Trace trace; |
| 1355 | ASSERT_TRUE(trace.ParseFromString(trace_raw)); |
Ryan Savitski | cc28cbf | 2018-11-09 22:55:12 +0000 | [diff] [blame] | 1356 | |
| 1357 | ASSERT_EQ(trace.packet_size(), kNumPreamblePackets + kNumTestPackets); |
| 1358 | for (int i = 0; i < kNumTestPackets; i++) { |
| 1359 | const protos::TracePacket& tp = trace.packet(kNumPreamblePackets + i); |
| 1360 | ASSERT_EQ(kPayload + std::to_string(i++), tp.for_testing().str()); |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1361 | } |
Primiano Tucci | dca727d | 2018-04-04 11:31:55 +0200 | [diff] [blame] | 1362 | } |
Primiano Tucci | 2ffd1a5 | 2018-03-27 01:01:30 +0100 | [diff] [blame] | 1363 | |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 1364 | // Test the logic that allows the trace config to set the shm total size and |
| 1365 | // page size from the trace config. Also check that, if the config doesn't |
| 1366 | // specify a value we fall back on the hint provided by the producer. |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 1367 | TEST_F(TracingServiceImplTest, ProducerShmAndPageSizeOverriddenByTraceConfig) { |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 1368 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1369 | consumer->Connect(svc.get()); |
| 1370 | const size_t kConfigPageSizesKb[] = /****/ {16, 16, 4, 0, 16, 8, 3, 4096, 4}; |
| 1371 | const size_t kExpectedPageSizesKb[] = /**/ {16, 16, 4, 4, 16, 8, 4, 64, 4}; |
| 1372 | |
| 1373 | const size_t kConfigSizesKb[] = /**/ {0, 16, 0, 20, 32, 7, 0, 96, 4096000}; |
| 1374 | const size_t kHintSizesKb[] = /****/ {0, 0, 16, 32, 16, 0, 7, 96, 4096000}; |
| 1375 | const size_t kExpectedSizesKb[] = { |
| 1376 | kDefaultShmSizeKb, // Both hint and config are 0, use default. |
| 1377 | 16, // Hint is 0, use config. |
| 1378 | 16, // Config is 0, use hint. |
| 1379 | 20, // Hint is takes precedence over the config. |
| 1380 | 32, // Ditto, even if config is higher than hint. |
| 1381 | kDefaultShmSizeKb, // Config is invalid and hint is 0, use default. |
| 1382 | kDefaultShmSizeKb, // Config is 0 and hint is invalid, use default. |
| 1383 | kDefaultShmSizeKb, // 96 KB isn't a multiple of the page size (64 KB). |
| 1384 | kMaxShmSizeKb // Too big, cap at kMaxShmSize. |
| 1385 | }; |
| 1386 | |
| 1387 | const size_t kNumProducers = base::ArraySize(kHintSizesKb); |
| 1388 | std::unique_ptr<MockProducer> producer[kNumProducers]; |
| 1389 | for (size_t i = 0; i < kNumProducers; i++) { |
| 1390 | auto name = "mock_producer_" + std::to_string(i); |
| 1391 | producer[i] = CreateMockProducer(); |
| 1392 | producer[i]->Connect(svc.get(), name, geteuid(), kHintSizesKb[i] * 1024); |
| 1393 | producer[i]->RegisterDataSource("data_source"); |
| 1394 | } |
| 1395 | |
| 1396 | TraceConfig trace_config; |
| 1397 | trace_config.add_buffers()->set_size_kb(128); |
| 1398 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 1399 | ds_config->set_name("data_source"); |
| 1400 | for (size_t i = 0; i < kNumProducers; i++) { |
| 1401 | auto* producer_config = trace_config.add_producers(); |
| 1402 | producer_config->set_producer_name("mock_producer_" + std::to_string(i)); |
Primiano Tucci | 3cbb10a | 2018-04-10 17:52:40 +0100 | [diff] [blame] | 1403 | producer_config->set_shm_size_kb(static_cast<uint32_t>(kConfigSizesKb[i])); |
| 1404 | producer_config->set_page_size_kb( |
| 1405 | static_cast<uint32_t>(kConfigPageSizesKb[i])); |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 1406 | } |
| 1407 | |
| 1408 | consumer->EnableTracing(trace_config); |
| 1409 | size_t actual_shm_sizes_kb[kNumProducers]{}; |
| 1410 | size_t actual_page_sizes_kb[kNumProducers]{}; |
| 1411 | for (size_t i = 0; i < kNumProducers; i++) { |
| 1412 | producer[i]->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1413 | producer[i]->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 1414 | actual_shm_sizes_kb[i] = |
| 1415 | producer[i]->endpoint()->shared_memory()->size() / 1024; |
| 1416 | actual_page_sizes_kb[i] = |
| 1417 | producer[i]->endpoint()->shared_buffer_page_size_kb(); |
| 1418 | } |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1419 | for (size_t i = 0; i < kNumProducers; i++) { |
| 1420 | producer[i]->WaitForDataSourceStart("data_source"); |
| 1421 | } |
Primiano Tucci | 1a1951d | 2018-04-04 21:08:16 +0200 | [diff] [blame] | 1422 | ASSERT_THAT(actual_page_sizes_kb, ElementsAreArray(kExpectedPageSizesKb)); |
| 1423 | ASSERT_THAT(actual_shm_sizes_kb, ElementsAreArray(kExpectedSizesKb)); |
| 1424 | } |
| 1425 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 1426 | TEST_F(TracingServiceImplTest, ExplicitFlush) { |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 1427 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1428 | consumer->Connect(svc.get()); |
| 1429 | |
| 1430 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1431 | producer->Connect(svc.get(), "mock_producer"); |
| 1432 | producer->RegisterDataSource("data_source"); |
| 1433 | |
| 1434 | TraceConfig trace_config; |
| 1435 | trace_config.add_buffers()->set_size_kb(128); |
| 1436 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 1437 | ds_config->set_name("data_source"); |
| 1438 | |
| 1439 | consumer->EnableTracing(trace_config); |
| 1440 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1441 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 1442 | producer->WaitForDataSourceStart("data_source"); |
| 1443 | |
| 1444 | std::unique_ptr<TraceWriter> writer = |
| 1445 | producer->CreateTraceWriter("data_source"); |
| 1446 | { |
| 1447 | auto tp = writer->NewTracePacket(); |
| 1448 | tp->set_for_testing()->set_str("payload"); |
| 1449 | } |
| 1450 | |
| 1451 | auto flush_request = consumer->Flush(); |
| 1452 | producer->WaitForFlush(writer.get()); |
| 1453 | ASSERT_TRUE(flush_request.WaitForReply()); |
| 1454 | |
| 1455 | consumer->DisableTracing(); |
| 1456 | producer->WaitForDataSourceStop("data_source"); |
| 1457 | consumer->WaitForTracingDisabled(); |
| 1458 | EXPECT_THAT( |
| 1459 | consumer->ReadBuffers(), |
| 1460 | Contains(Property(&protos::TracePacket::for_testing, |
| 1461 | Property(&protos::TestEvent::str, Eq("payload"))))); |
| 1462 | } |
| 1463 | |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 1464 | TEST_F(TracingServiceImplTest, ImplicitFlushOnTimedTraces) { |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 1465 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1466 | consumer->Connect(svc.get()); |
| 1467 | |
| 1468 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1469 | producer->Connect(svc.get(), "mock_producer"); |
| 1470 | producer->RegisterDataSource("data_source"); |
| 1471 | |
| 1472 | TraceConfig trace_config; |
| 1473 | trace_config.add_buffers()->set_size_kb(128); |
| 1474 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 1475 | ds_config->set_name("data_source"); |
| 1476 | trace_config.set_duration_ms(1); |
| 1477 | |
| 1478 | consumer->EnableTracing(trace_config); |
| 1479 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1480 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 1481 | producer->WaitForDataSourceStart("data_source"); |
| 1482 | |
| 1483 | std::unique_ptr<TraceWriter> writer = |
| 1484 | producer->CreateTraceWriter("data_source"); |
| 1485 | { |
| 1486 | auto tp = writer->NewTracePacket(); |
| 1487 | tp->set_for_testing()->set_str("payload"); |
| 1488 | } |
| 1489 | |
| 1490 | producer->WaitForFlush(writer.get()); |
| 1491 | |
| 1492 | producer->WaitForDataSourceStop("data_source"); |
| 1493 | consumer->WaitForTracingDisabled(); |
| 1494 | |
| 1495 | EXPECT_THAT( |
| 1496 | consumer->ReadBuffers(), |
| 1497 | Contains(Property(&protos::TracePacket::for_testing, |
| 1498 | Property(&protos::TestEvent::str, Eq("payload"))))); |
| 1499 | } |
| 1500 | |
| 1501 | // Tests the monotonic semantic of flush request IDs, i.e., once a producer |
| 1502 | // acks flush request N, all flush requests <= N are considered successful and |
| 1503 | // acked to the consumer. |
Florian Mayer | 6a1a4d5 | 2018-06-08 16:47:07 +0100 | [diff] [blame] | 1504 | TEST_F(TracingServiceImplTest, BatchFlushes) { |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 1505 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1506 | consumer->Connect(svc.get()); |
| 1507 | |
| 1508 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1509 | producer->Connect(svc.get(), "mock_producer"); |
| 1510 | producer->RegisterDataSource("data_source"); |
| 1511 | |
| 1512 | TraceConfig trace_config; |
| 1513 | trace_config.add_buffers()->set_size_kb(128); |
| 1514 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 1515 | ds_config->set_name("data_source"); |
| 1516 | |
| 1517 | consumer->EnableTracing(trace_config); |
| 1518 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1519 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 1520 | producer->WaitForDataSourceStart("data_source"); |
| 1521 | |
| 1522 | std::unique_ptr<TraceWriter> writer = |
| 1523 | producer->CreateTraceWriter("data_source"); |
| 1524 | { |
| 1525 | auto tp = writer->NewTracePacket(); |
| 1526 | tp->set_for_testing()->set_str("payload"); |
| 1527 | } |
| 1528 | |
| 1529 | auto flush_req_1 = consumer->Flush(); |
| 1530 | auto flush_req_2 = consumer->Flush(); |
| 1531 | auto flush_req_3 = consumer->Flush(); |
| 1532 | |
| 1533 | // We'll deliberately let the 4th flush request timeout. Use a lower timeout |
| 1534 | // to keep test time short. |
| 1535 | auto flush_req_4 = consumer->Flush(/*timeout_ms=*/10); |
| 1536 | ASSERT_EQ(4u, GetNumPendingFlushes()); |
| 1537 | |
| 1538 | // Make the producer reply only to the 3rd flush request. |
| 1539 | testing::InSequence seq; |
Eric Seckler | a01e28a | 2019-01-08 11:21:04 +0000 | [diff] [blame] | 1540 | producer->WaitForFlush(nullptr, /*reply=*/false); // Do NOT reply to flush 1. |
| 1541 | producer->WaitForFlush(nullptr, /*reply=*/false); // Do NOT reply to flush 2. |
| 1542 | producer->WaitForFlush(writer.get()); // Reply only to flush 3. |
| 1543 | producer->WaitForFlush(nullptr, /*reply=*/false); // Do NOT reply to flush 4. |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 1544 | |
| 1545 | // Even if the producer explicily replied only to flush ID == 3, all the |
| 1546 | // previous flushed < 3 should be implicitly acked. |
| 1547 | ASSERT_TRUE(flush_req_1.WaitForReply()); |
| 1548 | ASSERT_TRUE(flush_req_2.WaitForReply()); |
| 1549 | ASSERT_TRUE(flush_req_3.WaitForReply()); |
| 1550 | |
| 1551 | // At this point flush id == 4 should still be pending and should fail because |
| 1552 | // of reaching its timeout. |
Primiano Tucci | d52e627 | 2018-04-06 19:06:53 +0200 | [diff] [blame] | 1553 | ASSERT_FALSE(flush_req_4.WaitForReply()); |
| 1554 | |
| 1555 | consumer->DisableTracing(); |
| 1556 | producer->WaitForDataSourceStop("data_source"); |
| 1557 | consumer->WaitForTracingDisabled(); |
| 1558 | EXPECT_THAT( |
| 1559 | consumer->ReadBuffers(), |
| 1560 | Contains(Property(&protos::TracePacket::for_testing, |
| 1561 | Property(&protos::TestEvent::str, Eq("payload"))))); |
| 1562 | } |
| 1563 | |
Primiano Tucci | caa5780 | 2018-11-25 11:07:07 +0000 | [diff] [blame] | 1564 | TEST_F(TracingServiceImplTest, PeriodicFlush) { |
| 1565 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1566 | consumer->Connect(svc.get()); |
| 1567 | |
| 1568 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1569 | producer->Connect(svc.get(), "mock_producer"); |
| 1570 | producer->RegisterDataSource("data_source"); |
| 1571 | |
| 1572 | TraceConfig trace_config; |
| 1573 | trace_config.add_buffers()->set_size_kb(128); |
| 1574 | trace_config.set_flush_period_ms(1); |
| 1575 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 1576 | ds_config->set_name("data_source"); |
| 1577 | |
| 1578 | consumer->EnableTracing(trace_config); |
| 1579 | producer->WaitForTracingSetup(); |
| 1580 | producer->WaitForDataSourceSetup("data_source"); |
| 1581 | producer->WaitForDataSourceStart("data_source"); |
| 1582 | |
| 1583 | std::unique_ptr<TraceWriter> writer = |
| 1584 | producer->CreateTraceWriter("data_source"); |
| 1585 | |
| 1586 | const int kNumFlushes = 3; |
| 1587 | auto checkpoint = task_runner.CreateCheckpoint("all_flushes_done"); |
| 1588 | int flushes_seen = 0; |
| 1589 | EXPECT_CALL(*producer, Flush(_, _, _)) |
| 1590 | .WillRepeatedly(Invoke([&producer, &writer, &flushes_seen, checkpoint]( |
| 1591 | FlushRequestID flush_req_id, |
| 1592 | const DataSourceInstanceID*, size_t) { |
| 1593 | { |
| 1594 | auto tp = writer->NewTracePacket(); |
| 1595 | char payload[32]; |
| 1596 | sprintf(payload, "f_%d", flushes_seen); |
| 1597 | tp->set_for_testing()->set_str(payload); |
| 1598 | } |
| 1599 | writer->Flush(); |
| 1600 | producer->endpoint()->NotifyFlushComplete(flush_req_id); |
| 1601 | if (++flushes_seen == kNumFlushes) |
| 1602 | checkpoint(); |
| 1603 | })); |
| 1604 | task_runner.RunUntilCheckpoint("all_flushes_done"); |
| 1605 | |
| 1606 | consumer->DisableTracing(); |
| 1607 | producer->WaitForDataSourceStop("data_source"); |
| 1608 | consumer->WaitForTracingDisabled(); |
| 1609 | auto trace_packets = consumer->ReadBuffers(); |
| 1610 | for (int i = 0; i < kNumFlushes; i++) { |
| 1611 | EXPECT_THAT(trace_packets, |
| 1612 | Contains(Property(&protos::TracePacket::for_testing, |
| 1613 | Property(&protos::TestEvent::str, |
| 1614 | Eq("f_" + std::to_string(i)))))); |
| 1615 | } |
| 1616 | } |
| 1617 | |
Ryan Savitski | 33868d5 | 2019-05-13 10:56:14 +0100 | [diff] [blame] | 1618 | TEST_F(TracingServiceImplTest, PeriodicClearIncrementalState) { |
| 1619 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1620 | consumer->Connect(svc.get()); |
| 1621 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1622 | producer->Connect(svc.get(), "mock_producer"); |
| 1623 | |
| 1624 | // Incremental data source that expects to receive the clear. |
| 1625 | producer->RegisterDataSource("ds_incremental1", false, false, |
| 1626 | /*handles_incremental_state_clear=*/true); |
| 1627 | |
| 1628 | // Incremental data source that expects to receive the clear. |
| 1629 | producer->RegisterDataSource("ds_incremental2", false, false, |
| 1630 | /*handles_incremental_state_clear=*/true); |
| 1631 | |
Ryan Savitski | 0b4008a | 2019-05-13 17:55:53 +0100 | [diff] [blame^] | 1632 | // Data source that does *not* advertise itself as supporting incremental |
| 1633 | // state clears. |
Ryan Savitski | 33868d5 | 2019-05-13 10:56:14 +0100 | [diff] [blame] | 1634 | producer->RegisterDataSource("ds_selfcontained", false, false, |
| 1635 | /*handles_incremental_state_clear=*/false); |
| 1636 | |
| 1637 | // Incremental data source that is registered, but won't be active within the |
| 1638 | // test's tracing session. |
| 1639 | producer->RegisterDataSource("ds_inactive", false, false, |
| 1640 | /*handles_incremental_state_clear=*/true); |
| 1641 | |
| 1642 | TraceConfig trace_config; |
| 1643 | trace_config.add_buffers()->set_size_kb(128); |
| 1644 | trace_config.mutable_incremental_state_config()->set_clear_period_ms(1); |
| 1645 | trace_config.add_data_sources()->mutable_config()->set_name( |
| 1646 | "ds_selfcontained"); |
| 1647 | trace_config.add_data_sources()->mutable_config()->set_name( |
| 1648 | "ds_incremental1"); |
| 1649 | trace_config.add_data_sources()->mutable_config()->set_name( |
| 1650 | "ds_incremental2"); |
| 1651 | |
| 1652 | // note: the mocking is very brittle, and has to assume a specific order of |
| 1653 | // the data sources' setup/start. |
| 1654 | consumer->EnableTracing(trace_config); |
| 1655 | producer->WaitForTracingSetup(); |
| 1656 | producer->WaitForDataSourceSetup("ds_selfcontained"); |
| 1657 | producer->WaitForDataSourceSetup("ds_incremental1"); |
| 1658 | producer->WaitForDataSourceSetup("ds_incremental2"); |
| 1659 | producer->WaitForDataSourceStart("ds_selfcontained"); |
| 1660 | producer->WaitForDataSourceStart("ds_incremental1"); |
| 1661 | producer->WaitForDataSourceStart("ds_incremental2"); |
| 1662 | |
| 1663 | DataSourceInstanceID ds_incremental1 = |
| 1664 | producer->GetDataSourceInstanceId("ds_incremental1"); |
| 1665 | DataSourceInstanceID ds_incremental2 = |
| 1666 | producer->GetDataSourceInstanceId("ds_incremental2"); |
| 1667 | |
| 1668 | const int kNumClears = 3; |
| 1669 | std::function<void()> checkpoint = |
| 1670 | task_runner.CreateCheckpoint("clears_received"); |
| 1671 | std::vector<std::vector<DataSourceInstanceID>> clears_seen; |
| 1672 | EXPECT_CALL(*producer, ClearIncrementalState(_, _)) |
| 1673 | .WillRepeatedly(Invoke([&clears_seen, &checkpoint]( |
| 1674 | const DataSourceInstanceID* data_source_ids, |
| 1675 | size_t num_data_sources) { |
| 1676 | std::vector<DataSourceInstanceID> ds_ids; |
| 1677 | for (size_t i = 0; i < num_data_sources; i++) { |
| 1678 | ds_ids.push_back(*data_source_ids++); |
| 1679 | } |
| 1680 | clears_seen.push_back(ds_ids); |
| 1681 | if (clears_seen.size() >= kNumClears) |
| 1682 | checkpoint(); |
| 1683 | })); |
| 1684 | task_runner.RunUntilCheckpoint("clears_received"); |
| 1685 | |
| 1686 | consumer->DisableTracing(); |
| 1687 | |
| 1688 | // Assert that the clears were only for the active incremental data sources. |
| 1689 | ASSERT_EQ(clears_seen.size(), kNumClears); |
| 1690 | for (const std::vector<DataSourceInstanceID>& ds_ids : clears_seen) { |
| 1691 | ASSERT_THAT(ds_ids, ElementsAreArray({ds_incremental1, ds_incremental2})); |
| 1692 | } |
| 1693 | } |
| 1694 | |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1695 | // Creates a tracing session where some of the data sources set the |
| 1696 | // |will_notify_on_stop| flag and checks that the OnTracingDisabled notification |
| 1697 | // to the consumer is delayed until the acks are received. |
| 1698 | TEST_F(TracingServiceImplTest, OnTracingDisabledWaitsForDataSourceStopAcks) { |
| 1699 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1700 | consumer->Connect(svc.get()); |
| 1701 | |
| 1702 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1703 | producer->Connect(svc.get(), "mock_producer"); |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 1704 | producer->RegisterDataSource("ds_will_ack_1", /*ack_stop=*/true, |
| 1705 | /*ack_start=*/true); |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1706 | producer->RegisterDataSource("ds_wont_ack"); |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 1707 | producer->RegisterDataSource("ds_will_ack_2", /*ack_stop=*/true, |
| 1708 | /*ack_start=*/false); |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1709 | |
| 1710 | TraceConfig trace_config; |
| 1711 | trace_config.add_buffers()->set_size_kb(128); |
| 1712 | trace_config.add_data_sources()->mutable_config()->set_name("ds_will_ack_1"); |
| 1713 | trace_config.add_data_sources()->mutable_config()->set_name("ds_wont_ack"); |
| 1714 | trace_config.add_data_sources()->mutable_config()->set_name("ds_will_ack_2"); |
| 1715 | trace_config.set_duration_ms(1); |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 1716 | trace_config.set_deferred_start(true); |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1717 | |
| 1718 | consumer->EnableTracing(trace_config); |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 1719 | |
| 1720 | EXPECT_EQ(GetDataSourceInstanceState("ds_will_ack_1"), |
| 1721 | DataSourceInstanceState::CONFIGURED); |
| 1722 | EXPECT_EQ(GetDataSourceInstanceState("ds_wont_ack"), |
| 1723 | DataSourceInstanceState::CONFIGURED); |
| 1724 | EXPECT_EQ(GetDataSourceInstanceState("ds_will_ack_2"), |
| 1725 | DataSourceInstanceState::CONFIGURED); |
| 1726 | |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1727 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1728 | |
| 1729 | producer->WaitForDataSourceSetup("ds_will_ack_1"); |
| 1730 | producer->WaitForDataSourceSetup("ds_wont_ack"); |
| 1731 | producer->WaitForDataSourceSetup("ds_will_ack_2"); |
| 1732 | |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 1733 | DataSourceInstanceID id1 = producer->GetDataSourceInstanceId("ds_will_ack_1"); |
| 1734 | DataSourceInstanceID id2 = producer->GetDataSourceInstanceId("ds_will_ack_2"); |
| 1735 | |
| 1736 | consumer->StartTracing(); |
| 1737 | |
| 1738 | EXPECT_EQ(GetDataSourceInstanceState("ds_will_ack_1"), |
| 1739 | DataSourceInstanceState::STARTING); |
| 1740 | EXPECT_EQ(GetDataSourceInstanceState("ds_wont_ack"), |
| 1741 | DataSourceInstanceState::STARTED); |
| 1742 | EXPECT_EQ(GetDataSourceInstanceState("ds_will_ack_2"), |
| 1743 | DataSourceInstanceState::STARTED); |
| 1744 | |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1745 | producer->WaitForDataSourceStart("ds_will_ack_1"); |
| 1746 | producer->WaitForDataSourceStart("ds_wont_ack"); |
| 1747 | producer->WaitForDataSourceStart("ds_will_ack_2"); |
| 1748 | |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 1749 | producer->endpoint()->NotifyDataSourceStarted(id1); |
| 1750 | |
| 1751 | EXPECT_EQ(GetDataSourceInstanceState("ds_will_ack_1"), |
| 1752 | DataSourceInstanceState::STARTED); |
| 1753 | |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1754 | std::unique_ptr<TraceWriter> writer = |
| 1755 | producer->CreateTraceWriter("ds_wont_ack"); |
| 1756 | producer->WaitForFlush(writer.get()); |
| 1757 | |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1758 | producer->WaitForDataSourceStop("ds_will_ack_1"); |
| 1759 | producer->WaitForDataSourceStop("ds_wont_ack"); |
| 1760 | producer->WaitForDataSourceStop("ds_will_ack_2"); |
| 1761 | |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 1762 | EXPECT_EQ(GetDataSourceInstanceState("ds_will_ack_1"), |
| 1763 | DataSourceInstanceState::STOPPING); |
| 1764 | EXPECT_EQ(GetDataSourceInstanceState("ds_wont_ack"), |
| 1765 | DataSourceInstanceState::STOPPED); |
| 1766 | EXPECT_EQ(GetDataSourceInstanceState("ds_will_ack_2"), |
| 1767 | DataSourceInstanceState::STOPPING); |
| 1768 | |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1769 | producer->endpoint()->NotifyDataSourceStopped(id1); |
| 1770 | producer->endpoint()->NotifyDataSourceStopped(id2); |
| 1771 | |
Eric Seckler | 4ff03e5 | 2019-03-15 10:10:30 +0000 | [diff] [blame] | 1772 | EXPECT_EQ(GetDataSourceInstanceState("ds_will_ack_1"), |
| 1773 | DataSourceInstanceState::STOPPED); |
| 1774 | EXPECT_EQ(GetDataSourceInstanceState("ds_will_ack_2"), |
| 1775 | DataSourceInstanceState::STOPPED); |
| 1776 | |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1777 | // Wait for at most half of the service timeout, so that this test fails if |
| 1778 | // the service falls back on calling the OnTracingDisabled() because some of |
| 1779 | // the expected acks weren't received. |
| 1780 | consumer->WaitForTracingDisabled( |
| 1781 | TracingServiceImpl::kDataSourceStopTimeoutMs / 2); |
| 1782 | } |
| 1783 | |
Oystein Eftevaag | f250e1c | 2018-08-23 16:10:52 -0700 | [diff] [blame] | 1784 | // Creates a tracing session where a second data source |
| 1785 | // is added while the service is waiting for DisableTracing |
| 1786 | // acks; the service should not enable the new datasource |
| 1787 | // and should not hit any asserts when the consumer is |
| 1788 | // subsequently destroyed. |
| 1789 | TEST_F(TracingServiceImplTest, OnDataSourceAddedWhilePendingDisableAcks) { |
| 1790 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1791 | consumer->Connect(svc.get()); |
| 1792 | |
| 1793 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1794 | producer->Connect(svc.get(), "mock_producer"); |
| 1795 | producer->RegisterDataSource("ds_will_ack", /*ack_stop=*/true); |
| 1796 | |
| 1797 | TraceConfig trace_config; |
| 1798 | trace_config.add_buffers()->set_size_kb(128); |
| 1799 | trace_config.add_data_sources()->mutable_config()->set_name("ds_will_ack"); |
| 1800 | trace_config.add_data_sources()->mutable_config()->set_name("ds_wont_ack"); |
| 1801 | |
| 1802 | consumer->EnableTracing(trace_config); |
| 1803 | producer->WaitForTracingSetup(); |
| 1804 | |
| 1805 | consumer->DisableTracing(); |
| 1806 | |
| 1807 | producer->RegisterDataSource("ds_wont_ack"); |
| 1808 | |
| 1809 | consumer.reset(); |
| 1810 | } |
| 1811 | |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1812 | // Similar to OnTracingDisabledWaitsForDataSourceStopAcks, but deliberately |
| 1813 | // skips the ack and checks that the service invokes the OnTracingDisabled() |
| 1814 | // after the timeout. |
| 1815 | TEST_F(TracingServiceImplTest, OnTracingDisabledCalledAnywaysInCaseOfTimeout) { |
| 1816 | svc->override_data_source_test_timeout_ms_for_testing = 1; |
| 1817 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1818 | consumer->Connect(svc.get()); |
| 1819 | |
| 1820 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1821 | producer->Connect(svc.get(), "mock_producer"); |
| 1822 | producer->RegisterDataSource("data_source", /*ack_stop=*/true); |
| 1823 | |
| 1824 | TraceConfig trace_config; |
| 1825 | trace_config.add_buffers()->set_size_kb(128); |
| 1826 | trace_config.add_data_sources()->mutable_config()->set_name("data_source"); |
| 1827 | trace_config.set_duration_ms(1); |
| 1828 | |
| 1829 | consumer->EnableTracing(trace_config); |
| 1830 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1831 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | baeecf1 | 2018-07-25 12:02:20 +0100 | [diff] [blame] | 1832 | producer->WaitForDataSourceStart("data_source"); |
| 1833 | |
| 1834 | std::unique_ptr<TraceWriter> writer = |
| 1835 | producer->CreateTraceWriter("data_source"); |
| 1836 | producer->WaitForFlush(writer.get()); |
| 1837 | |
| 1838 | producer->WaitForDataSourceStop("data_source"); |
| 1839 | consumer->WaitForTracingDisabled(); |
| 1840 | } |
| 1841 | |
Primiano Tucci | 03de28f | 2018-08-01 11:29:46 +0100 | [diff] [blame] | 1842 | // Tests the session_id logic. Two data sources in the same tracing session |
| 1843 | // should see the same session id. |
| 1844 | TEST_F(TracingServiceImplTest, SessionId) { |
| 1845 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1846 | consumer->Connect(svc.get()); |
| 1847 | |
| 1848 | std::unique_ptr<MockProducer> producer1 = CreateMockProducer(); |
| 1849 | producer1->Connect(svc.get(), "mock_producer1"); |
| 1850 | producer1->RegisterDataSource("ds_1A"); |
| 1851 | producer1->RegisterDataSource("ds_1B"); |
| 1852 | |
| 1853 | std::unique_ptr<MockProducer> producer2 = CreateMockProducer(); |
| 1854 | producer2->Connect(svc.get(), "mock_producer2"); |
| 1855 | producer2->RegisterDataSource("ds_2A"); |
| 1856 | |
| 1857 | testing::InSequence seq; |
| 1858 | TracingSessionID last_session_id = 0; |
| 1859 | for (int i = 0; i < 3; i++) { |
| 1860 | TraceConfig trace_config; |
| 1861 | trace_config.add_buffers()->set_size_kb(128); |
| 1862 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1A"); |
| 1863 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1B"); |
| 1864 | trace_config.add_data_sources()->mutable_config()->set_name("ds_2A"); |
| 1865 | trace_config.set_duration_ms(1); |
| 1866 | |
| 1867 | consumer->EnableTracing(trace_config); |
| 1868 | |
| 1869 | if (i == 0) |
| 1870 | producer1->WaitForTracingSetup(); |
Primiano Tucci | 03de28f | 2018-08-01 11:29:46 +0100 | [diff] [blame] | 1871 | |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1872 | producer1->WaitForDataSourceSetup("ds_1A"); |
| 1873 | producer1->WaitForDataSourceSetup("ds_1B"); |
Primiano Tucci | 03de28f | 2018-08-01 11:29:46 +0100 | [diff] [blame] | 1874 | if (i == 0) |
| 1875 | producer2->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1876 | producer2->WaitForDataSourceSetup("ds_2A"); |
| 1877 | |
| 1878 | producer1->WaitForDataSourceStart("ds_1A"); |
| 1879 | producer1->WaitForDataSourceStart("ds_1B"); |
Primiano Tucci | 03de28f | 2018-08-01 11:29:46 +0100 | [diff] [blame] | 1880 | producer2->WaitForDataSourceStart("ds_2A"); |
| 1881 | |
| 1882 | auto* ds1 = producer1->GetDataSourceInstance("ds_1A"); |
| 1883 | auto* ds2 = producer1->GetDataSourceInstance("ds_1B"); |
| 1884 | auto* ds3 = producer2->GetDataSourceInstance("ds_2A"); |
| 1885 | ASSERT_EQ(ds1->session_id, ds2->session_id); |
| 1886 | ASSERT_EQ(ds1->session_id, ds3->session_id); |
| 1887 | ASSERT_NE(ds1->session_id, last_session_id); |
| 1888 | last_session_id = ds1->session_id; |
| 1889 | |
| 1890 | auto writer1 = producer1->CreateTraceWriter("ds_1A"); |
| 1891 | producer1->WaitForFlush(writer1.get()); |
| 1892 | |
| 1893 | auto writer2 = producer2->CreateTraceWriter("ds_2A"); |
| 1894 | producer2->WaitForFlush(writer2.get()); |
| 1895 | |
| 1896 | producer1->WaitForDataSourceStop("ds_1A"); |
| 1897 | producer1->WaitForDataSourceStop("ds_1B"); |
| 1898 | producer2->WaitForDataSourceStop("ds_2A"); |
| 1899 | consumer->WaitForTracingDisabled(); |
| 1900 | consumer->FreeBuffers(); |
| 1901 | } |
| 1902 | } |
Primiano Tucci | 9754d0d | 2018-09-15 12:41:46 +0100 | [diff] [blame] | 1903 | |
| 1904 | // Writes a long trace and then tests that the trace parsed in partitions |
| 1905 | // derived by the synchronization markers is identical to the whole trace parsed |
| 1906 | // in one go. |
| 1907 | TEST_F(TracingServiceImplTest, ResynchronizeTraceStreamUsingSyncMarker) { |
| 1908 | // Setup tracing. |
| 1909 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1910 | consumer->Connect(svc.get()); |
| 1911 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1912 | producer->Connect(svc.get(), "mock_producer"); |
| 1913 | producer->RegisterDataSource("data_source"); |
| 1914 | TraceConfig trace_config; |
| 1915 | trace_config.add_buffers()->set_size_kb(4096); |
| 1916 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 1917 | ds_config->set_name("data_source"); |
| 1918 | trace_config.set_write_into_file(true); |
| 1919 | trace_config.set_file_write_period_ms(1); |
| 1920 | base::TempFile tmp_file = base::TempFile::Create(); |
| 1921 | consumer->EnableTracing(trace_config, base::ScopedFile(dup(tmp_file.fd()))); |
| 1922 | producer->WaitForTracingSetup(); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1923 | producer->WaitForDataSourceSetup("data_source"); |
Primiano Tucci | 9754d0d | 2018-09-15 12:41:46 +0100 | [diff] [blame] | 1924 | producer->WaitForDataSourceStart("data_source"); |
| 1925 | |
| 1926 | // Write some variable length payload, waiting for sync markers every now |
| 1927 | // and then. |
| 1928 | const int kNumMarkers = 5; |
| 1929 | auto writer = producer->CreateTraceWriter("data_source"); |
| 1930 | for (int i = 1; i <= 100; i++) { |
Florian Mayer | eff9804 | 2018-12-10 17:44:44 +0000 | [diff] [blame] | 1931 | std::string payload(static_cast<size_t>(i), 'A' + (i % 25)); |
Primiano Tucci | 9754d0d | 2018-09-15 12:41:46 +0100 | [diff] [blame] | 1932 | writer->NewTracePacket()->set_for_testing()->set_str(payload.c_str()); |
| 1933 | if (i % (100 / kNumMarkers) == 0) { |
| 1934 | writer->Flush(); |
| 1935 | WaitForNextSyncMarker(); |
| 1936 | } |
| 1937 | } |
| 1938 | writer->Flush(); |
| 1939 | writer.reset(); |
| 1940 | consumer->DisableTracing(); |
| 1941 | producer->WaitForDataSourceStop("data_source"); |
| 1942 | consumer->WaitForTracingDisabled(); |
| 1943 | |
| 1944 | std::string trace_raw; |
| 1945 | ASSERT_TRUE(base::ReadFile(tmp_file.path().c_str(), &trace_raw)); |
| 1946 | |
| 1947 | const auto kMarkerSize = sizeof(TracingServiceImpl::kSyncMarker); |
| 1948 | const std::string kSyncMarkerStr( |
| 1949 | reinterpret_cast<const char*>(TracingServiceImpl::kSyncMarker), |
| 1950 | kMarkerSize); |
| 1951 | |
| 1952 | // Read back the trace in partitions derived from the marker. |
| 1953 | // The trace should look like this: |
| 1954 | // [uid, marker] [event] [event] [uid, marker] [event] [event] |
| 1955 | size_t num_markers = 0; |
| 1956 | size_t start = 0; |
| 1957 | size_t end = 0; |
| 1958 | protos::Trace merged_trace; |
| 1959 | for (size_t pos = 0; pos != std::string::npos; start = end) { |
| 1960 | pos = trace_raw.find(kSyncMarkerStr, pos + 1); |
| 1961 | num_markers++; |
| 1962 | end = (pos == std::string::npos) ? trace_raw.size() : pos + kMarkerSize; |
| 1963 | int size = static_cast<int>(end - start); |
| 1964 | ASSERT_GT(size, 0); |
| 1965 | protos::Trace trace_partition; |
| 1966 | ASSERT_TRUE(trace_partition.ParseFromArray(trace_raw.data() + start, size)); |
| 1967 | merged_trace.MergeFrom(trace_partition); |
| 1968 | } |
Lalit Maganti | 9bdc7ce | 2018-09-17 15:25:11 +0100 | [diff] [blame] | 1969 | EXPECT_GE(num_markers, static_cast<size_t>(kNumMarkers)); |
Primiano Tucci | 9754d0d | 2018-09-15 12:41:46 +0100 | [diff] [blame] | 1970 | |
| 1971 | protos::Trace whole_trace; |
| 1972 | ASSERT_TRUE(whole_trace.ParseFromString(trace_raw)); |
| 1973 | |
| 1974 | ASSERT_EQ(whole_trace.packet_size(), merged_trace.packet_size()); |
| 1975 | EXPECT_EQ(whole_trace.SerializeAsString(), merged_trace.SerializeAsString()); |
| 1976 | } |
| 1977 | |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 1978 | // Creates a tracing session with |deferred_start| and checks that data sources |
| 1979 | // are started only after calling StartTracing(). |
| 1980 | TEST_F(TracingServiceImplTest, DeferredStart) { |
| 1981 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 1982 | consumer->Connect(svc.get()); |
| 1983 | |
| 1984 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 1985 | producer->Connect(svc.get(), "mock_producer"); |
| 1986 | |
| 1987 | // Create two data sources but enable only one of them. |
| 1988 | producer->RegisterDataSource("ds_1"); |
| 1989 | producer->RegisterDataSource("ds_2"); |
| 1990 | |
| 1991 | TraceConfig trace_config; |
| 1992 | trace_config.add_buffers()->set_size_kb(128); |
| 1993 | trace_config.add_data_sources()->mutable_config()->set_name("ds_1"); |
| 1994 | trace_config.set_deferred_start(true); |
| 1995 | trace_config.set_duration_ms(1); |
| 1996 | |
| 1997 | consumer->EnableTracing(trace_config); |
| 1998 | producer->WaitForTracingSetup(); |
| 1999 | |
| 2000 | producer->WaitForDataSourceSetup("ds_1"); |
| 2001 | |
| 2002 | // Make sure we don't get unexpected DataSourceStart() notifications yet. |
| 2003 | task_runner.RunUntilIdle(); |
| 2004 | |
| 2005 | consumer->StartTracing(); |
| 2006 | |
| 2007 | producer->WaitForDataSourceStart("ds_1"); |
| 2008 | |
Stephen Nusko | 1393ffd | 2019-03-22 13:54:58 +0000 | [diff] [blame] | 2009 | auto writer = producer->CreateTraceWriter("ds_1"); |
| 2010 | producer->WaitForFlush(writer.get()); |
Primiano Tucci | 674076d | 2018-10-01 10:41:09 +0100 | [diff] [blame] | 2011 | |
| 2012 | producer->WaitForDataSourceStop("ds_1"); |
| 2013 | consumer->WaitForTracingDisabled(); |
| 2014 | } |
| 2015 | |
Eric Seckler | d0ac7ca | 2019-02-06 09:13:45 +0000 | [diff] [blame] | 2016 | TEST_F(TracingServiceImplTest, ProducerUIDsAndPacketSequenceIDs) { |
| 2017 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2018 | consumer->Connect(svc.get()); |
| 2019 | |
| 2020 | std::unique_ptr<MockProducer> producer1 = CreateMockProducer(); |
| 2021 | producer1->Connect(svc.get(), "mock_producer1", 123u /* uid */); |
| 2022 | producer1->RegisterDataSource("data_source"); |
| 2023 | |
| 2024 | std::unique_ptr<MockProducer> producer2 = CreateMockProducer(); |
| 2025 | producer2->Connect(svc.get(), "mock_producer2", 456u /* uid */); |
| 2026 | producer2->RegisterDataSource("data_source"); |
| 2027 | |
| 2028 | TraceConfig trace_config; |
| 2029 | trace_config.add_buffers()->set_size_kb(128); |
| 2030 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 2031 | ds_config->set_name("data_source"); |
| 2032 | |
| 2033 | consumer->EnableTracing(trace_config); |
| 2034 | producer1->WaitForTracingSetup(); |
| 2035 | producer1->WaitForDataSourceSetup("data_source"); |
| 2036 | producer2->WaitForTracingSetup(); |
| 2037 | producer2->WaitForDataSourceSetup("data_source"); |
| 2038 | producer1->WaitForDataSourceStart("data_source"); |
| 2039 | producer2->WaitForDataSourceStart("data_source"); |
| 2040 | |
| 2041 | std::unique_ptr<TraceWriter> writer1a = |
| 2042 | producer1->CreateTraceWriter("data_source"); |
| 2043 | std::unique_ptr<TraceWriter> writer1b = |
| 2044 | producer1->CreateTraceWriter("data_source"); |
| 2045 | std::unique_ptr<TraceWriter> writer2a = |
| 2046 | producer2->CreateTraceWriter("data_source"); |
| 2047 | { |
| 2048 | auto tp = writer1a->NewTracePacket(); |
| 2049 | tp->set_for_testing()->set_str("payload1a1"); |
| 2050 | tp = writer1b->NewTracePacket(); |
| 2051 | tp->set_for_testing()->set_str("payload1b1"); |
| 2052 | tp = writer1a->NewTracePacket(); |
| 2053 | tp->set_for_testing()->set_str("payload1a2"); |
| 2054 | tp = writer2a->NewTracePacket(); |
| 2055 | tp->set_for_testing()->set_str("payload2a1"); |
| 2056 | tp = writer1b->NewTracePacket(); |
| 2057 | tp->set_for_testing()->set_str("payload1b2"); |
| 2058 | } |
| 2059 | |
| 2060 | auto flush_request = consumer->Flush(); |
| 2061 | producer1->WaitForFlush({writer1a.get(), writer1b.get()}); |
| 2062 | producer2->WaitForFlush(writer2a.get()); |
| 2063 | ASSERT_TRUE(flush_request.WaitForReply()); |
| 2064 | |
| 2065 | consumer->DisableTracing(); |
| 2066 | producer1->WaitForDataSourceStop("data_source"); |
| 2067 | producer2->WaitForDataSourceStop("data_source"); |
| 2068 | consumer->WaitForTracingDisabled(); |
| 2069 | auto packets = consumer->ReadBuffers(); |
| 2070 | EXPECT_THAT( |
| 2071 | packets, |
| 2072 | Contains(AllOf( |
| 2073 | Property(&protos::TracePacket::for_testing, |
| 2074 | Property(&protos::TestEvent::str, Eq("payload1a1"))), |
| 2075 | Property(&protos::TracePacket::trusted_uid, Eq(123)), |
| 2076 | Property(&protos::TracePacket::trusted_packet_sequence_id, Eq(2u))))); |
| 2077 | EXPECT_THAT( |
| 2078 | packets, |
| 2079 | Contains(AllOf( |
| 2080 | Property(&protos::TracePacket::for_testing, |
| 2081 | Property(&protos::TestEvent::str, Eq("payload1a2"))), |
| 2082 | Property(&protos::TracePacket::trusted_uid, Eq(123)), |
| 2083 | Property(&protos::TracePacket::trusted_packet_sequence_id, Eq(2u))))); |
| 2084 | EXPECT_THAT( |
| 2085 | packets, |
| 2086 | Contains(AllOf( |
| 2087 | Property(&protos::TracePacket::for_testing, |
| 2088 | Property(&protos::TestEvent::str, Eq("payload1b1"))), |
| 2089 | Property(&protos::TracePacket::trusted_uid, Eq(123)), |
| 2090 | Property(&protos::TracePacket::trusted_packet_sequence_id, Eq(3u))))); |
| 2091 | EXPECT_THAT( |
| 2092 | packets, |
| 2093 | Contains(AllOf( |
| 2094 | Property(&protos::TracePacket::for_testing, |
| 2095 | Property(&protos::TestEvent::str, Eq("payload1b2"))), |
| 2096 | Property(&protos::TracePacket::trusted_uid, Eq(123)), |
| 2097 | Property(&protos::TracePacket::trusted_packet_sequence_id, Eq(3u))))); |
| 2098 | EXPECT_THAT( |
| 2099 | packets, |
| 2100 | Contains(AllOf( |
| 2101 | Property(&protos::TracePacket::for_testing, |
| 2102 | Property(&protos::TestEvent::str, Eq("payload2a1"))), |
| 2103 | Property(&protos::TracePacket::trusted_uid, Eq(456)), |
| 2104 | Property(&protos::TracePacket::trusted_packet_sequence_id, Eq(4u))))); |
| 2105 | } |
| 2106 | |
Eric Seckler | 6dc2359 | 2018-11-30 10:59:06 +0000 | [diff] [blame] | 2107 | TEST_F(TracingServiceImplTest, AllowedBuffers) { |
| 2108 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2109 | consumer->Connect(svc.get()); |
| 2110 | |
| 2111 | std::unique_ptr<MockProducer> producer1 = CreateMockProducer(); |
| 2112 | producer1->Connect(svc.get(), "mock_producer1"); |
| 2113 | ProducerID producer1_id = *last_producer_id(); |
| 2114 | producer1->RegisterDataSource("data_source1"); |
| 2115 | std::unique_ptr<MockProducer> producer2 = CreateMockProducer(); |
| 2116 | producer2->Connect(svc.get(), "mock_producer2"); |
| 2117 | ProducerID producer2_id = *last_producer_id(); |
| 2118 | producer2->RegisterDataSource("data_source2.1"); |
| 2119 | producer2->RegisterDataSource("data_source2.2"); |
| 2120 | producer2->RegisterDataSource("data_source2.3"); |
| 2121 | |
| 2122 | EXPECT_EQ(std::set<BufferID>(), GetAllowedTargetBuffers(producer1_id)); |
| 2123 | EXPECT_EQ(std::set<BufferID>(), GetAllowedTargetBuffers(producer2_id)); |
| 2124 | |
| 2125 | TraceConfig trace_config; |
| 2126 | trace_config.add_buffers()->set_size_kb(128); |
| 2127 | trace_config.add_buffers()->set_size_kb(128); |
| 2128 | trace_config.add_buffers()->set_size_kb(128); |
| 2129 | auto* ds_config1 = trace_config.add_data_sources()->mutable_config(); |
| 2130 | ds_config1->set_name("data_source1"); |
| 2131 | ds_config1->set_target_buffer(0); |
| 2132 | auto* ds_config21 = trace_config.add_data_sources()->mutable_config(); |
| 2133 | ds_config21->set_name("data_source2.1"); |
| 2134 | ds_config21->set_target_buffer(1); |
| 2135 | auto* ds_config22 = trace_config.add_data_sources()->mutable_config(); |
| 2136 | ds_config22->set_name("data_source2.2"); |
| 2137 | ds_config22->set_target_buffer(2); |
| 2138 | auto* ds_config23 = trace_config.add_data_sources()->mutable_config(); |
| 2139 | ds_config23->set_name("data_source2.3"); |
| 2140 | ds_config23->set_target_buffer(2); // same buffer as data_source2.2. |
| 2141 | consumer->EnableTracing(trace_config); |
| 2142 | |
Primiano Tucci | 2abd115 | 2018-12-03 17:00:02 +0100 | [diff] [blame] | 2143 | ASSERT_EQ(3u, tracing_session()->num_buffers()); |
Eric Seckler | 6dc2359 | 2018-11-30 10:59:06 +0000 | [diff] [blame] | 2144 | std::set<BufferID> expected_buffers_producer1 = { |
| 2145 | tracing_session()->buffers_index[0]}; |
| 2146 | std::set<BufferID> expected_buffers_producer2 = { |
| 2147 | tracing_session()->buffers_index[1], tracing_session()->buffers_index[2]}; |
| 2148 | EXPECT_EQ(expected_buffers_producer1, GetAllowedTargetBuffers(producer1_id)); |
| 2149 | EXPECT_EQ(expected_buffers_producer2, GetAllowedTargetBuffers(producer2_id)); |
| 2150 | |
| 2151 | producer1->WaitForTracingSetup(); |
| 2152 | producer1->WaitForDataSourceSetup("data_source1"); |
| 2153 | |
| 2154 | producer2->WaitForTracingSetup(); |
| 2155 | producer2->WaitForDataSourceSetup("data_source2.1"); |
| 2156 | producer2->WaitForDataSourceSetup("data_source2.2"); |
| 2157 | producer2->WaitForDataSourceSetup("data_source2.3"); |
| 2158 | |
| 2159 | producer1->WaitForDataSourceStart("data_source1"); |
| 2160 | producer2->WaitForDataSourceStart("data_source2.1"); |
| 2161 | producer2->WaitForDataSourceStart("data_source2.2"); |
| 2162 | producer2->WaitForDataSourceStart("data_source2.3"); |
| 2163 | |
| 2164 | producer2->UnregisterDataSource("data_source2.3"); |
| 2165 | producer2->WaitForDataSourceStop("data_source2.3"); |
| 2166 | |
| 2167 | // Should still be allowed to write to buffers 1 (data_source2.1) and 2 |
| 2168 | // (data_source2.2). |
| 2169 | EXPECT_EQ(expected_buffers_producer2, GetAllowedTargetBuffers(producer2_id)); |
| 2170 | |
| 2171 | // Calling StartTracing() should be a noop (% a DLOG statement) because the |
| 2172 | // trace config didn't have the |deferred_start| flag set. |
| 2173 | consumer->StartTracing(); |
| 2174 | |
| 2175 | consumer->DisableTracing(); |
| 2176 | producer1->WaitForDataSourceStop("data_source1"); |
| 2177 | producer2->WaitForDataSourceStop("data_source2.1"); |
| 2178 | producer2->WaitForDataSourceStop("data_source2.2"); |
| 2179 | consumer->WaitForTracingDisabled(); |
| 2180 | |
| 2181 | consumer->FreeBuffers(); |
| 2182 | EXPECT_EQ(std::set<BufferID>(), GetAllowedTargetBuffers(producer1_id)); |
| 2183 | EXPECT_EQ(std::set<BufferID>(), GetAllowedTargetBuffers(producer2_id)); |
| 2184 | } |
| 2185 | |
Eric Seckler | 6aa9ece | 2018-12-06 16:40:12 +0000 | [diff] [blame] | 2186 | #if !PERFETTO_DCHECK_IS_ON() |
Eric Seckler | dd0ad10 | 2018-12-06 11:32:04 +0000 | [diff] [blame] | 2187 | TEST_F(TracingServiceImplTest, CommitToForbiddenBufferIsDiscarded) { |
| 2188 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2189 | consumer->Connect(svc.get()); |
| 2190 | |
| 2191 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 2192 | producer->Connect(svc.get(), "mock_producer"); |
| 2193 | ProducerID producer_id = *last_producer_id(); |
| 2194 | producer->RegisterDataSource("data_source"); |
| 2195 | |
| 2196 | EXPECT_EQ(std::set<BufferID>(), GetAllowedTargetBuffers(producer_id)); |
| 2197 | |
| 2198 | TraceConfig trace_config; |
| 2199 | trace_config.add_buffers()->set_size_kb(128); |
| 2200 | trace_config.add_buffers()->set_size_kb(128); |
| 2201 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 2202 | ds_config->set_name("data_source"); |
| 2203 | ds_config->set_target_buffer(0); |
| 2204 | consumer->EnableTracing(trace_config); |
| 2205 | |
| 2206 | ASSERT_EQ(2u, tracing_session()->num_buffers()); |
| 2207 | std::set<BufferID> expected_buffers = {tracing_session()->buffers_index[0]}; |
| 2208 | EXPECT_EQ(expected_buffers, GetAllowedTargetBuffers(producer_id)); |
| 2209 | |
| 2210 | producer->WaitForTracingSetup(); |
| 2211 | producer->WaitForDataSourceSetup("data_source"); |
| 2212 | producer->WaitForDataSourceStart("data_source"); |
| 2213 | |
| 2214 | // Calling StartTracing() should be a noop (% a DLOG statement) because the |
| 2215 | // trace config didn't have the |deferred_start| flag set. |
| 2216 | consumer->StartTracing(); |
| 2217 | |
| 2218 | // Try to write to the correct buffer. |
| 2219 | std::unique_ptr<TraceWriter> writer = producer->endpoint()->CreateTraceWriter( |
| 2220 | tracing_session()->buffers_index[0]); |
| 2221 | { |
| 2222 | auto tp = writer->NewTracePacket(); |
| 2223 | tp->set_for_testing()->set_str("good_payload"); |
| 2224 | } |
| 2225 | |
| 2226 | auto flush_request = consumer->Flush(); |
| 2227 | producer->WaitForFlush(writer.get()); |
| 2228 | ASSERT_TRUE(flush_request.WaitForReply()); |
| 2229 | |
| 2230 | // Try to write to the wrong buffer. |
| 2231 | writer = producer->endpoint()->CreateTraceWriter( |
| 2232 | tracing_session()->buffers_index[1]); |
| 2233 | { |
| 2234 | auto tp = writer->NewTracePacket(); |
| 2235 | tp->set_for_testing()->set_str("bad_payload"); |
| 2236 | } |
| 2237 | |
| 2238 | flush_request = consumer->Flush(); |
| 2239 | producer->WaitForFlush(writer.get()); |
| 2240 | ASSERT_TRUE(flush_request.WaitForReply()); |
| 2241 | |
| 2242 | consumer->DisableTracing(); |
| 2243 | producer->WaitForDataSourceStop("data_source"); |
| 2244 | consumer->WaitForTracingDisabled(); |
| 2245 | |
| 2246 | auto packets = consumer->ReadBuffers(); |
| 2247 | EXPECT_THAT(packets, Contains(Property(&protos::TracePacket::for_testing, |
| 2248 | Property(&protos::TestEvent::str, |
| 2249 | Eq("good_payload"))))); |
| 2250 | EXPECT_THAT(packets, Not(Contains(Property(&protos::TracePacket::for_testing, |
| 2251 | Property(&protos::TestEvent::str, |
| 2252 | Eq("bad_payload")))))); |
| 2253 | |
| 2254 | consumer->FreeBuffers(); |
| 2255 | EXPECT_EQ(std::set<BufferID>(), GetAllowedTargetBuffers(producer_id)); |
| 2256 | } |
Eric Seckler | 6aa9ece | 2018-12-06 16:40:12 +0000 | [diff] [blame] | 2257 | #endif // !PERFETTO_DCHECK_IS_ON() |
Eric Seckler | dd0ad10 | 2018-12-06 11:32:04 +0000 | [diff] [blame] | 2258 | |
Eric Seckler | f3f524b | 2018-12-13 09:09:34 +0000 | [diff] [blame] | 2259 | TEST_F(TracingServiceImplTest, RegisterAndUnregisterTraceWriter) { |
| 2260 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2261 | consumer->Connect(svc.get()); |
| 2262 | |
| 2263 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 2264 | producer->Connect(svc.get(), "mock_producer"); |
| 2265 | ProducerID producer_id = *last_producer_id(); |
| 2266 | producer->RegisterDataSource("data_source"); |
| 2267 | |
| 2268 | EXPECT_TRUE(GetWriters(producer_id).empty()); |
| 2269 | |
| 2270 | TraceConfig trace_config; |
| 2271 | trace_config.add_buffers()->set_size_kb(128); |
| 2272 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 2273 | ds_config->set_name("data_source"); |
| 2274 | ds_config->set_target_buffer(0); |
| 2275 | consumer->EnableTracing(trace_config); |
| 2276 | |
| 2277 | producer->WaitForTracingSetup(); |
| 2278 | producer->WaitForDataSourceSetup("data_source"); |
| 2279 | producer->WaitForDataSourceStart("data_source"); |
| 2280 | |
| 2281 | // Calling StartTracing() should be a noop (% a DLOG statement) because the |
| 2282 | // trace config didn't have the |deferred_start| flag set. |
| 2283 | consumer->StartTracing(); |
| 2284 | |
| 2285 | // Creating the trace writer should register it with the service. |
| 2286 | std::unique_ptr<TraceWriter> writer = producer->endpoint()->CreateTraceWriter( |
| 2287 | tracing_session()->buffers_index[0]); |
| 2288 | |
| 2289 | WaitForTraceWritersChanged(producer_id); |
| 2290 | |
| 2291 | std::map<WriterID, BufferID> expected_writers; |
| 2292 | expected_writers[writer->writer_id()] = tracing_session()->buffers_index[0]; |
| 2293 | EXPECT_EQ(expected_writers, GetWriters(producer_id)); |
| 2294 | |
| 2295 | // Verify writing works. |
| 2296 | { |
| 2297 | auto tp = writer->NewTracePacket(); |
| 2298 | tp->set_for_testing()->set_str("payload"); |
| 2299 | } |
| 2300 | |
| 2301 | auto flush_request = consumer->Flush(); |
| 2302 | producer->WaitForFlush(writer.get()); |
| 2303 | ASSERT_TRUE(flush_request.WaitForReply()); |
| 2304 | |
| 2305 | // Destroying the writer should unregister it. |
| 2306 | writer.reset(); |
| 2307 | WaitForTraceWritersChanged(producer_id); |
| 2308 | EXPECT_TRUE(GetWriters(producer_id).empty()); |
| 2309 | |
| 2310 | consumer->DisableTracing(); |
| 2311 | producer->WaitForDataSourceStop("data_source"); |
| 2312 | consumer->WaitForTracingDisabled(); |
| 2313 | |
| 2314 | auto packets = consumer->ReadBuffers(); |
| 2315 | EXPECT_THAT(packets, Contains(Property( |
| 2316 | &protos::TracePacket::for_testing, |
| 2317 | Property(&protos::TestEvent::str, Eq("payload"))))); |
| 2318 | } |
| 2319 | |
Eric Seckler | a01e28a | 2019-01-08 11:21:04 +0000 | [diff] [blame] | 2320 | TEST_F(TracingServiceImplTest, ScrapeBuffersOnFlush) { |
| 2321 | svc->SetSMBScrapingEnabled(true); |
| 2322 | |
| 2323 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2324 | consumer->Connect(svc.get()); |
| 2325 | |
| 2326 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 2327 | producer->Connect(svc.get(), "mock_producer"); |
| 2328 | ProducerID producer_id = *last_producer_id(); |
| 2329 | producer->RegisterDataSource("data_source"); |
| 2330 | |
| 2331 | TraceConfig trace_config; |
| 2332 | trace_config.add_buffers()->set_size_kb(128); |
| 2333 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 2334 | ds_config->set_name("data_source"); |
| 2335 | ds_config->set_target_buffer(0); |
| 2336 | consumer->EnableTracing(trace_config); |
| 2337 | |
| 2338 | producer->WaitForTracingSetup(); |
| 2339 | producer->WaitForDataSourceSetup("data_source"); |
| 2340 | producer->WaitForDataSourceStart("data_source"); |
| 2341 | |
| 2342 | // Calling StartTracing() should be a noop (% a DLOG statement) because the |
| 2343 | // trace config didn't have the |deferred_start| flag set. |
| 2344 | consumer->StartTracing(); |
| 2345 | |
| 2346 | std::unique_ptr<TraceWriter> writer = producer->endpoint()->CreateTraceWriter( |
| 2347 | tracing_session()->buffers_index[0]); |
| 2348 | WaitForTraceWritersChanged(producer_id); |
| 2349 | |
| 2350 | // Write a few trace packets. |
| 2351 | writer->NewTracePacket()->set_for_testing()->set_str("payload1"); |
| 2352 | writer->NewTracePacket()->set_for_testing()->set_str("payload2"); |
| 2353 | writer->NewTracePacket()->set_for_testing()->set_str("payload3"); |
| 2354 | |
| 2355 | // Flush but don't actually flush the chunk from TraceWriter. |
| 2356 | auto flush_request = consumer->Flush(); |
| 2357 | producer->WaitForFlush(nullptr, /*reply=*/true); |
| 2358 | ASSERT_TRUE(flush_request.WaitForReply()); |
| 2359 | |
| 2360 | // Chunk with the packets should have been scraped. The service can't know |
| 2361 | // whether the last packet was completed, so shouldn't read it. |
| 2362 | auto packets = consumer->ReadBuffers(); |
| 2363 | EXPECT_THAT(packets, Contains(Property( |
| 2364 | &protos::TracePacket::for_testing, |
| 2365 | Property(&protos::TestEvent::str, Eq("payload1"))))); |
| 2366 | EXPECT_THAT(packets, Contains(Property( |
| 2367 | &protos::TracePacket::for_testing, |
| 2368 | Property(&protos::TestEvent::str, Eq("payload2"))))); |
| 2369 | EXPECT_THAT(packets, Not(Contains(Property(&protos::TracePacket::for_testing, |
| 2370 | Property(&protos::TestEvent::str, |
| 2371 | Eq("payload3")))))); |
| 2372 | |
| 2373 | // Write some more packets. |
| 2374 | writer->NewTracePacket()->set_for_testing()->set_str("payload4"); |
| 2375 | writer->NewTracePacket()->set_for_testing()->set_str("payload5"); |
| 2376 | |
| 2377 | // Don't reply to flush, causing a timeout. This should scrape again. |
| 2378 | flush_request = consumer->Flush(/*timeout=*/100); |
| 2379 | producer->WaitForFlush(nullptr, /*reply=*/false); |
| 2380 | ASSERT_FALSE(flush_request.WaitForReply()); |
| 2381 | |
| 2382 | // Chunk with the packets should have been scraped again, overriding the |
| 2383 | // original one. Again, the last packet should be ignored and the first two |
| 2384 | // should not be read twice. |
| 2385 | packets = consumer->ReadBuffers(); |
| 2386 | EXPECT_THAT(packets, Not(Contains(Property(&protos::TracePacket::for_testing, |
| 2387 | Property(&protos::TestEvent::str, |
| 2388 | Eq("payload1")))))); |
| 2389 | EXPECT_THAT(packets, Not(Contains(Property(&protos::TracePacket::for_testing, |
| 2390 | Property(&protos::TestEvent::str, |
| 2391 | Eq("payload2")))))); |
| 2392 | EXPECT_THAT(packets, Contains(Property( |
| 2393 | &protos::TracePacket::for_testing, |
| 2394 | Property(&protos::TestEvent::str, Eq("payload3"))))); |
| 2395 | EXPECT_THAT(packets, Contains(Property( |
| 2396 | &protos::TracePacket::for_testing, |
| 2397 | Property(&protos::TestEvent::str, Eq("payload4"))))); |
| 2398 | EXPECT_THAT(packets, Not(Contains(Property(&protos::TracePacket::for_testing, |
| 2399 | Property(&protos::TestEvent::str, |
| 2400 | Eq("payload5")))))); |
| 2401 | |
| 2402 | consumer->DisableTracing(); |
| 2403 | producer->WaitForDataSourceStop("data_source"); |
| 2404 | consumer->WaitForTracingDisabled(); |
| 2405 | } |
| 2406 | |
| 2407 | // Test scraping on producer disconnect. |
| 2408 | TEST_F(TracingServiceImplTest, ScrapeBuffersOnProducerDisconnect) { |
| 2409 | svc->SetSMBScrapingEnabled(true); |
| 2410 | |
| 2411 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2412 | consumer->Connect(svc.get()); |
| 2413 | |
| 2414 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 2415 | producer->Connect(svc.get(), "mock_producer"); |
| 2416 | ProducerID producer_id = *last_producer_id(); |
| 2417 | producer->RegisterDataSource("data_source"); |
| 2418 | |
| 2419 | TraceConfig trace_config; |
| 2420 | trace_config.add_buffers()->set_size_kb(128); |
| 2421 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 2422 | ds_config->set_name("data_source"); |
| 2423 | ds_config->set_target_buffer(0); |
| 2424 | consumer->EnableTracing(trace_config); |
| 2425 | |
| 2426 | producer->WaitForTracingSetup(); |
| 2427 | producer->WaitForDataSourceSetup("data_source"); |
| 2428 | producer->WaitForDataSourceStart("data_source"); |
| 2429 | |
| 2430 | // Calling StartTracing() should be a noop (% a DLOG statement) because the |
| 2431 | // trace config didn't have the |deferred_start| flag set. |
| 2432 | consumer->StartTracing(); |
| 2433 | |
| 2434 | std::unique_ptr<TraceWriter> writer = producer->endpoint()->CreateTraceWriter( |
| 2435 | tracing_session()->buffers_index[0]); |
| 2436 | WaitForTraceWritersChanged(producer_id); |
| 2437 | |
| 2438 | // Write a few trace packets. |
| 2439 | writer->NewTracePacket()->set_for_testing()->set_str("payload1"); |
| 2440 | writer->NewTracePacket()->set_for_testing()->set_str("payload2"); |
| 2441 | writer->NewTracePacket()->set_for_testing()->set_str("payload3"); |
| 2442 | |
| 2443 | // Disconnect the producer without committing the chunk. This should cause a |
| 2444 | // scrape of the SMB. Avoid destroying the ShmemArbiter until writer is |
| 2445 | // destroyed. |
| 2446 | auto shmem_arbiter = TakeShmemArbiterForProducer(producer_id); |
| 2447 | producer.reset(); |
| 2448 | |
| 2449 | // Chunk with the packets should have been scraped. The service can't know |
| 2450 | // whether the last packet was completed, so shouldn't read it. |
| 2451 | auto packets = consumer->ReadBuffers(); |
| 2452 | EXPECT_THAT(packets, Contains(Property( |
| 2453 | &protos::TracePacket::for_testing, |
| 2454 | Property(&protos::TestEvent::str, Eq("payload1"))))); |
| 2455 | EXPECT_THAT(packets, Contains(Property( |
| 2456 | &protos::TracePacket::for_testing, |
| 2457 | Property(&protos::TestEvent::str, Eq("payload2"))))); |
| 2458 | EXPECT_THAT(packets, Not(Contains(Property(&protos::TracePacket::for_testing, |
| 2459 | Property(&protos::TestEvent::str, |
| 2460 | Eq("payload3")))))); |
| 2461 | |
| 2462 | // Cleanup writer without causing a crash because the producer already went |
| 2463 | // away. |
| 2464 | static_cast<TraceWriterImpl*>(writer.get())->ResetChunkForTesting(); |
| 2465 | writer.reset(); |
| 2466 | shmem_arbiter.reset(); |
| 2467 | |
| 2468 | consumer->DisableTracing(); |
| 2469 | consumer->WaitForTracingDisabled(); |
| 2470 | } |
| 2471 | |
| 2472 | TEST_F(TracingServiceImplTest, ScrapeBuffersOnDisable) { |
| 2473 | svc->SetSMBScrapingEnabled(true); |
| 2474 | |
| 2475 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2476 | consumer->Connect(svc.get()); |
| 2477 | |
| 2478 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 2479 | producer->Connect(svc.get(), "mock_producer"); |
| 2480 | ProducerID producer_id = *last_producer_id(); |
| 2481 | producer->RegisterDataSource("data_source"); |
| 2482 | |
| 2483 | TraceConfig trace_config; |
| 2484 | trace_config.add_buffers()->set_size_kb(128); |
| 2485 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 2486 | ds_config->set_name("data_source"); |
| 2487 | ds_config->set_target_buffer(0); |
| 2488 | consumer->EnableTracing(trace_config); |
| 2489 | |
| 2490 | producer->WaitForTracingSetup(); |
| 2491 | producer->WaitForDataSourceSetup("data_source"); |
| 2492 | producer->WaitForDataSourceStart("data_source"); |
| 2493 | |
| 2494 | // Calling StartTracing() should be a noop (% a DLOG statement) because the |
| 2495 | // trace config didn't have the |deferred_start| flag set. |
| 2496 | consumer->StartTracing(); |
| 2497 | |
| 2498 | std::unique_ptr<TraceWriter> writer = producer->endpoint()->CreateTraceWriter( |
| 2499 | tracing_session()->buffers_index[0]); |
| 2500 | WaitForTraceWritersChanged(producer_id); |
| 2501 | |
| 2502 | // Write a few trace packets. |
| 2503 | writer->NewTracePacket()->set_for_testing()->set_str("payload1"); |
| 2504 | writer->NewTracePacket()->set_for_testing()->set_str("payload2"); |
| 2505 | writer->NewTracePacket()->set_for_testing()->set_str("payload3"); |
| 2506 | |
| 2507 | consumer->DisableTracing(); |
| 2508 | producer->WaitForDataSourceStop("data_source"); |
| 2509 | consumer->WaitForTracingDisabled(); |
| 2510 | |
| 2511 | // Chunk with the packets should have been scraped. The service can't know |
| 2512 | // whether the last packet was completed, so shouldn't read it. |
| 2513 | auto packets = consumer->ReadBuffers(); |
| 2514 | EXPECT_THAT(packets, Contains(Property( |
| 2515 | &protos::TracePacket::for_testing, |
| 2516 | Property(&protos::TestEvent::str, Eq("payload1"))))); |
| 2517 | EXPECT_THAT(packets, Contains(Property( |
| 2518 | &protos::TracePacket::for_testing, |
| 2519 | Property(&protos::TestEvent::str, Eq("payload2"))))); |
| 2520 | EXPECT_THAT(packets, Not(Contains(Property(&protos::TracePacket::for_testing, |
| 2521 | Property(&protos::TestEvent::str, |
| 2522 | Eq("payload3")))))); |
| 2523 | } |
| 2524 | |
Primiano Tucci | ff7beab | 2019-01-09 21:49:20 +0000 | [diff] [blame] | 2525 | TEST_F(TracingServiceImplTest, AbortIfTraceDurationIsTooLong) { |
| 2526 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2527 | consumer->Connect(svc.get()); |
| 2528 | |
| 2529 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 2530 | producer->Connect(svc.get(), "mock_producer"); |
| 2531 | producer->RegisterDataSource("datasource"); |
| 2532 | |
| 2533 | TraceConfig trace_config; |
| 2534 | trace_config.add_buffers()->set_size_kb(128); |
| 2535 | trace_config.add_data_sources()->mutable_config()->set_name("datasource"); |
| 2536 | trace_config.set_duration_ms(0x7fffffff); |
| 2537 | |
| 2538 | EXPECT_CALL(*producer, SetupDataSource(_, _)).Times(0); |
| 2539 | consumer->EnableTracing(trace_config); |
| 2540 | |
| 2541 | // The trace is aborted immediately, 5s here is just some slack for the thread |
| 2542 | // ping-pongs for slow devices. |
| 2543 | consumer->WaitForTracingDisabled(5000); |
| 2544 | } |
| 2545 | |
Eric Seckler | eaf29ed | 2019-01-23 09:53:55 +0000 | [diff] [blame] | 2546 | TEST_F(TracingServiceImplTest, GetTraceStats) { |
| 2547 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2548 | consumer->Connect(svc.get()); |
| 2549 | |
| 2550 | consumer->GetTraceStats(); |
| 2551 | consumer->WaitForTraceStats(false); |
| 2552 | |
| 2553 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 2554 | producer->Connect(svc.get(), "mock_producer"); |
| 2555 | producer->RegisterDataSource("data_source"); |
| 2556 | |
| 2557 | TraceConfig trace_config; |
| 2558 | trace_config.add_buffers()->set_size_kb(128); |
| 2559 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 2560 | ds_config->set_name("data_source"); |
| 2561 | |
| 2562 | consumer->EnableTracing(trace_config); |
| 2563 | producer->WaitForTracingSetup(); |
| 2564 | producer->WaitForDataSourceSetup("data_source"); |
| 2565 | producer->WaitForDataSourceStart("data_source"); |
| 2566 | |
| 2567 | consumer->GetTraceStats(); |
| 2568 | consumer->WaitForTraceStats(true); |
| 2569 | |
| 2570 | consumer->DisableTracing(); |
| 2571 | producer->WaitForDataSourceStop("data_source"); |
| 2572 | consumer->WaitForTracingDisabled(); |
| 2573 | } |
| 2574 | |
Eric Seckler | 7b0c945 | 2019-03-18 13:14:36 +0000 | [diff] [blame] | 2575 | TEST_F(TracingServiceImplTest, ObserveEventsDataSourceInstances) { |
| 2576 | std::unique_ptr<MockConsumer> consumer = CreateMockConsumer(); |
| 2577 | consumer->Connect(svc.get()); |
| 2578 | |
| 2579 | std::unique_ptr<MockProducer> producer = CreateMockProducer(); |
| 2580 | producer->Connect(svc.get(), "mock_producer"); |
| 2581 | producer->RegisterDataSource("data_source"); |
| 2582 | |
| 2583 | TraceConfig trace_config; |
| 2584 | trace_config.add_buffers()->set_size_kb(128); |
| 2585 | auto* ds_config = trace_config.add_data_sources()->mutable_config(); |
| 2586 | ds_config->set_name("data_source"); |
| 2587 | |
| 2588 | // Start tracing before the consumer is interested in events. The consumer's |
| 2589 | // OnObservableEvents() should not be called yet. |
| 2590 | consumer->EnableTracing(trace_config); |
| 2591 | producer->WaitForTracingSetup(); |
| 2592 | producer->WaitForDataSourceSetup("data_source"); |
| 2593 | producer->WaitForDataSourceStart("data_source"); |
| 2594 | |
| 2595 | // Calling ObserveEvents should cause an event for the initial instance state. |
| 2596 | consumer->ObserveEvents(TracingService::ConsumerEndpoint:: |
| 2597 | ObservableEventType::kDataSourceInstances); |
| 2598 | { |
| 2599 | auto events = consumer->WaitForObservableEvents(); |
| 2600 | |
| 2601 | ObservableEvents::DataSourceInstanceStateChange change; |
| 2602 | change.set_producer_name("mock_producer"); |
| 2603 | change.set_data_source_name("data_source"); |
| 2604 | change.set_state(ObservableEvents::DataSourceInstanceStateChange:: |
| 2605 | DATA_SOURCE_INSTANCE_STATE_STARTED); |
| 2606 | EXPECT_EQ(events.instance_state_changes_size(), 1); |
| 2607 | EXPECT_THAT(events.instance_state_changes(), Contains(Eq(change))); |
| 2608 | } |
| 2609 | |
| 2610 | // Disabling should cause an instance state change to STOPPED. |
| 2611 | consumer->DisableTracing(); |
| 2612 | |
| 2613 | { |
| 2614 | auto events = consumer->WaitForObservableEvents(); |
| 2615 | |
| 2616 | ObservableEvents::DataSourceInstanceStateChange change; |
| 2617 | change.set_producer_name("mock_producer"); |
| 2618 | change.set_data_source_name("data_source"); |
| 2619 | change.set_state(ObservableEvents::DataSourceInstanceStateChange:: |
| 2620 | DATA_SOURCE_INSTANCE_STATE_STOPPED); |
| 2621 | EXPECT_EQ(events.instance_state_changes_size(), 1); |
| 2622 | EXPECT_THAT(events.instance_state_changes(), Contains(Eq(change))); |
| 2623 | } |
| 2624 | |
| 2625 | producer->WaitForDataSourceStop("data_source"); |
| 2626 | consumer->WaitForTracingDisabled(); |
| 2627 | consumer->FreeBuffers(); |
| 2628 | |
| 2629 | // Enable again, this should cause a state change for a new instance to |
| 2630 | // its initial state STOPPED. |
| 2631 | trace_config.set_deferred_start(true); |
| 2632 | consumer->EnableTracing(trace_config); |
| 2633 | |
| 2634 | { |
| 2635 | auto events = consumer->WaitForObservableEvents(); |
| 2636 | |
| 2637 | ObservableEvents::DataSourceInstanceStateChange change; |
| 2638 | change.set_producer_name("mock_producer"); |
| 2639 | change.set_data_source_name("data_source"); |
| 2640 | change.set_state(ObservableEvents::DataSourceInstanceStateChange:: |
| 2641 | DATA_SOURCE_INSTANCE_STATE_STOPPED); |
| 2642 | EXPECT_EQ(events.instance_state_changes_size(), 1); |
| 2643 | EXPECT_THAT(events.instance_state_changes(), Contains(Eq(change))); |
| 2644 | } |
| 2645 | |
| 2646 | producer->WaitForDataSourceSetup("data_source"); |
| 2647 | |
| 2648 | // Should move the instance into STARTED state and thus cause an event. |
| 2649 | consumer->StartTracing(); |
| 2650 | |
| 2651 | { |
| 2652 | auto events = consumer->WaitForObservableEvents(); |
| 2653 | |
| 2654 | ObservableEvents::DataSourceInstanceStateChange change; |
| 2655 | change.set_producer_name("mock_producer"); |
| 2656 | change.set_data_source_name("data_source"); |
| 2657 | change.set_state(ObservableEvents::DataSourceInstanceStateChange:: |
| 2658 | DATA_SOURCE_INSTANCE_STATE_STARTED); |
| 2659 | EXPECT_EQ(events.instance_state_changes_size(), 1); |
| 2660 | EXPECT_THAT(events.instance_state_changes(), Contains(Eq(change))); |
| 2661 | } |
| 2662 | |
| 2663 | producer->WaitForDataSourceStart("data_source"); |
| 2664 | |
| 2665 | // Stop observing events. |
| 2666 | consumer->ObserveEvents( |
| 2667 | TracingService::ConsumerEndpoint::ObservableEventType::kNone); |
| 2668 | |
| 2669 | // Disabling should now no longer cause events to be sent to the consumer. |
| 2670 | consumer->DisableTracing(); |
| 2671 | producer->WaitForDataSourceStop("data_source"); |
| 2672 | consumer->WaitForTracingDisabled(); |
| 2673 | } |
| 2674 | |
Primiano Tucci | 4f9b6d7 | 2017-12-05 20:59:16 +0000 | [diff] [blame] | 2675 | } // namespace perfetto |