Back to build
Raw log | Switch to full mode | Login

Test 'TraceEventDataSourceTest.TimestampedTraceEvent' completed with the following status(es): 'FAILURE_ON_EXIT','SUCCESS' Test 'TraceEventDataSourceTest.TimestampedTraceEvent' had the following logs when run: ================================================================================ [ RUN ] TraceEventDataSourceTest.TimestampedTraceEvent [ OK ] TraceEventDataSourceTest.TimestampedTraceEvent (16 ms) [ RUN ] TraceEventDataSourceTest.InstantTraceEvent ================== WARNING: ThreadSanitizer: data race (pid=6178) Atomic read of size 1 at 0x5568024795e0 by thread T15: #0 pthread_mutex_lock /b/swarming/w/ir/k/src/third_party/llvm/compiler-rt/lib/tsan/../sanitizer_common/sanitizer_common_interceptors.inc:4134:3 (services_unittests+0x1ef6396) #1 base::internal::LockImpl::Lock() base/synchronization/lock_impl_posix.cc:102:12 (services_unittests+0x551d0d3) #2 Acquire base/synchronization/lock.h:50:11 (services_unittests+0x589ccbc) #3 BasicAutoLock base/synchronization/lock_impl.h:84 (services_unittests+0x589ccbc) #4 tracing::TraceEventDataSource::CreateThreadLocalEventSink(bool) services/tracing/public/cpp/perfetto/trace_event_data_source.cc:353 (services_unittests+0x589ccbc) #5 tracing::TraceEventDataSource::OnAddTraceEvent(base::trace_event::TraceEvent*, bool, base::trace_event::TraceEventHandle*) services/tracing/public/cpp/perfetto/trace_event_data_source.cc:431:24 (services_unittests+0x589b910) #6 base::trace_event::TraceLog::AddTraceEventWithThreadIdAndTimestamp(char, unsigned char const*, char const*, char const*, unsigned long long, unsigned long long, int, base::TimeTicks const&, base::trace_event::TraceArguments*, unsigned int) base/trace_event/trace_log.cc:1240:7 (services_unittests+0x54eb204) #7 trace_event_internal::AddTraceEventWithThreadIdAndTimestamp(char, unsigned char const*, char const*, char const*, unsigned long long, unsigned long long, int, base::TimeTicks const&, base::trace_event::TraceArguments*, unsigned int) base/trace_event/trace_log.cc:1775:9 (services_unittests+0x54ed645) #8 AddTraceEventWithThreadIdAndTimestamp base/trace_event/trace_event.h:871:10 (services_unittests+0x54a4b60) #9 AddTraceEvent base/trace_event/trace_event.h:886 (services_unittests+0x54a4b60) #10 base::internal::SchedulerWorker::RunWorker() base/task/task_scheduler/scheduler_worker.cc:306 (services_unittests+0x54a4b60) #11 base::internal::SchedulerWorker::RunPooledWorker() base/task/task_scheduler/scheduler_worker.cc:233:3 (services_unittests+0x54a42b1) #12 base::internal::SchedulerWorker::ThreadMain() base/task/task_scheduler/scheduler_worker.cc:212:7 (services_unittests+0x54a4115) #13 base::(anonymous namespace)::ThreadFunc(void*) base/threading/platform_thread_posix.cc:81:13 (services_unittests+0x5522d71) Previous write of size 1 at 0x5568024795e0 by main thread: #0 pthread_mutex_destroy /b/swarming/w/ir/k/src/third_party/llvm/compiler-rt/lib/tsan/rtl/tsan_interceptors.cc:1251:3 (services_unittests+0x1ed98a6) #1 base::internal::LockImpl::~LockImpl() base/synchronization/lock_impl_posix.cc:79:12 (services_unittests+0x551ce40) #2 base::Lock::~Lock() base/synchronization/lock.cc:20:1 (services_unittests+0x5453df0) #3 tracing::TraceEventDataSource::~TraceEventDataSource() services/tracing/public/cpp/perfetto/trace_event_data_source.cc:175:45 (services_unittests+0x589b6c2) #4 tracing::TraceEventDataSource::ResetForTesting() services/tracing/public/cpp/perfetto/trace_event_data_source.cc:164:42 (services_unittests+0x589b406) #5 tracing::(anonymous namespace)::TraceEventDataSourceTest::CreateTraceEventDataSource() services/tracing/public/cpp/perfetto/trace_event_data_source_unittest.cc:203:5 (services_unittests+0x279a01c) #6 tracing::(anonymous namespace)::TraceEventDataSourceTest_InstantTraceEvent_Test::TestBody() services/tracing/public/cpp/perfetto/trace_event_data_source_unittest.cc:532:3 (services_unittests+0x27a0964) #7 HandleExceptionsInMethodIfSupported<testing::Test, void> third_party/googletest/src/googletest/src/gtest.cc (services_unittests+0x33c396f) #8 testing::Test::Run() third_party/googletest/src/googletest/src/gtest.cc:2512 (services_unittests+0x33c396f) #9 testing::TestInfo::Run() third_party/googletest/src/googletest/src/gtest.cc:2688:11 (services_unittests+0x33c4ae8) #10 testing::TestSuite::Run() third_party/googletest/src/googletest/src/gtest.cc:2818:28 (services_unittests+0x33c56c6) #11 testing::internal::UnitTestImpl::RunAllTests() third_party/googletest/src/googletest/src/gtest.cc:5262:44 (services_unittests+0x33d77c6) #12 HandleExceptionsInMethodIfSupported<testing::internal::UnitTestImpl, bool> third_party/googletest/src/googletest/src/gtest.cc (services_unittests+0x33d6fa9) #13 testing::UnitTest::Run() third_party/googletest/src/googletest/src/gtest.cc:4864 (services_unittests+0x33d6fa9) #14 RUN_ALL_TESTS third_party/googletest/src/googletest/include/gtest/gtest.h:2448:46 (services_unittests+0x72efce2) #15 base::TestSuite::Run() base/test/test_suite.cc:316 (services_unittests+0x72efce2) #16 Invoke<int (base::TestSuite::*)(), (anonymous namespace)::ServiceTestSuite *> base/bind_internal.h:499:12 (services_unittests+0x23fdbf8) #17 MakeItSo<int (base::TestSuite::*)(), (anonymous namespace)::ServiceTestSuite *> base/bind_internal.h:599 (services_unittests+0x23fdbf8) #18 RunImpl<int (base::TestSuite::*)(), std::__1::tuple<base::internal::UnretainedWrapper<(anonymous namespace)::ServiceTestSuite> >, 0> base/bind_internal.h:672 (services_unittests+0x23fdbf8) #19 base::internal::Invoker<base::internal::BindState<int (base::TestSuite::*)(), base::internal::UnretainedWrapper<(anonymous namespace)::ServiceTestSuite> >, int ()>::RunOnce(base::internal::BindStateBase*) base/bind_internal.h:641 (services_unittests+0x23fdbf8) #20 Run base/callback.h:97:12 (services_unittests+0x72fb0e8) #21 base::(anonymous namespace)::LaunchUnitTestsInternal(base::OnceCallback<int ()>, unsigned long, int, bool, base::OnceCallback<void ()>) base/test/launcher/unit_test_launcher.cc:225 (services_unittests+0x72fb0e8) #22 base::LaunchUnitTests(int, char**, base::OnceCallback<int ()>) base/test/launcher/unit_test_launcher.cc:575:10 (services_unittests+0x72faef7) #23 main services/test/run_all_unittests.cc:97:10 (services_unittests+0x23fd82e) Location is global 'tracing::TraceEventDataSource::GetInstance()::instance' of size 160 at 0x556802479598 (services_unittests+0x00000b9325e0) Thread T15 'TaskSchedulerFo' (tid=6263, running) created by main thread at: #0 pthread_create /b/swarming/w/ir/k/src/third_party/llvm/compiler-rt/lib/tsan/rtl/tsan_interceptors.cc:976:3 (services_unittests+0x1ed805b) #1 base::(anonymous namespace)::CreateThread(unsigned long, bool, base::PlatformThread::Delegate*, base::PlatformThreadHandle*, base::ThreadPriority) base/threading/platform_thread_posix.cc:120:13 (services_unittests+0x5522816) #2 base::PlatformThread::CreateWithPriority(unsigned long, base::PlatformThread::Delegate*, base::PlatformThreadHandle*, base::ThreadPriority) base/threading/platform_thread_posix.cc:246:10 (services_unittests+0x55226d5) #3 base::internal::SchedulerWorker::Start(base::SchedulerWorkerObserver*) base/task/task_scheduler/scheduler_worker.cc:79:3 (services_unittests+0x54a393d) #4 operator() base/task/task_scheduler/scheduler_worker_pool_impl.cc:187:15 (services_unittests+0x54a21fe) #5 void base::internal::SchedulerWorkerPoolImpl::ScopedWorkersExecutor::WorkerContainer::ForEachWorker<base::internal::SchedulerWorkerPoolImpl::ScopedWorkersExecutor::FlushImpl()::'lambda0'(base::internal::SchedulerWorker*)>(base::internal::SchedulerWorkerPoolImpl::ScopedWorkersExecutor::FlushImpl()::'lambda0'(base::internal::SchedulerWorker*)) base/task/task_scheduler/scheduler_worker_pool_impl.cc:152 (services_unittests+0x54a21fe) #6 FlushImpl base/task/task_scheduler/scheduler_worker_pool_impl.cc:186:23 (services_unittests+0x549bedd) #7 base::internal::SchedulerWorkerPoolImpl::ScopedWorkersExecutor::~ScopedWorkersExecutor() base/task/task_scheduler/scheduler_worker_pool_impl.cc:105 (services_unittests+0x549bedd) #8 base::internal::SchedulerWorkerPoolImpl::Start(base::SchedulerWorkerPoolParams const&, int, scoped_refptr<base::TaskRunner>, base::SchedulerWorkerObserver*, base::internal::SchedulerWorkerPoolImpl::WorkerEnvironment, base::Optional<base::TimeDelta>) base/task/task_scheduler/scheduler_worker_pool_impl.cc:437:1 (services_unittests+0x549be6b) #9 base::internal::TaskSchedulerImpl::Start(base::TaskScheduler::InitParams const&, base::SchedulerWorkerObserver*) base/task/task_scheduler/task_scheduler_impl.cc:162:23 (services_unittests+0x548f33c) #10 base::test::ScopedTaskEnvironment::ScopedTaskEnvironment(base::test::ScopedTaskEnvironment::MainThreadType, base::test::ScopedTaskEnvironment::ExecutionMode, base::test::ScopedTaskEnvironment::NowSource, bool, base::trait_helpers::NotATraitTag) base/test/scoped_task_environment.cc:377:33 (services_unittests+0x72e8ba6) #11 base::test::ScopedTaskEnvironment::ScopedTaskEnvironment<void>() base/test/scoped_task_environment.h:143:9 (services_unittests+0x1f49a05) #12 TraceEventDataSourceTest services/tracing/public/cpp/perfetto/trace_event_data_source_unittest.cc:175:7 (services_unittests+0x27a0883) #13 TraceEventDataSourceTest_InstantTraceEvent_Test services/tracing/public/cpp/perfetto/trace_event_data_source_unittest.cc:531 (services_unittests+0x27a0883) #14 testing::internal::TestFactoryImpl<tracing::(anonymous namespace)::TraceEventDataSourceTest_InstantTraceEvent_Test>::CreateTest() third_party/googletest/src/googletest/include/gtest/internal/gtest-internal.h:460 (services_unittests+0x27a0883) #15 HandleExceptionsInMethodIfSupported<testing::internal::TestFactoryBase, testing::Test *> third_party/googletest/src/googletest/src/gtest.cc (services_unittests+0x33c493f) #16 testing::TestInfo::Run() third_party/googletest/src/googletest/src/gtest.cc:2678 (services_unittests+0x33c493f) #17 testing::TestSuite::Run() third_party/googletest/src/googletest/src/gtest.cc:2818:28 (services_unittests+0x33c56c6) #18 testing::internal::UnitTestImpl::RunAllTests() third_party/googletest/src/googletest/src/gtest.cc:5262:44 (services_unittests+0x33d77c6) #19 HandleExceptionsInMethodIfSupported<testing::internal::UnitTestImpl, bool> third_party/googletest/src/googletest/src/gtest.cc (services_unittests+0x33d6fa9) #20 testing::UnitTest::Run() third_party/googletest/src/googletest/src/gtest.cc:4864 (services_unittests+0x33d6fa9) #21 RUN_ALL_TESTS third_party/googletest/src/googletest/include/gtest/gtest.h:2448:46 (services_unittests+0x72efce2) #22 base::TestSuite::Run() base/test/test_suite.cc:316 (services_unittests+0x72efce2) #23 Invoke<int (base::TestSuite::*)(), (anonymous namespace)::ServiceTestSuite *> base/bind_internal.h:499:12 (services_unittests+0x23fdbf8) #24 MakeItSo<int (base::TestSuite::*)(), (anonymous namespace)::ServiceTestSuite *> base/bind_internal.h:599 (services_unittests+0x23fdbf8) #25 RunImpl<int (base::TestSuite::*)(), std::__1::tuple<base::internal::UnretainedWrapper<(anonymous namespace)::ServiceTestSuite> >, 0> base/bind_internal.h:672 (services_unittests+0x23fdbf8) #26 base::internal::Invoker<base::internal::BindState<int (base::TestSuite::*)(), base::internal::UnretainedWrapper<(anonymous namespace)::ServiceTestSuite> >, int ()>::RunOnce(base::internal::BindStateBase*) base/bind_internal.h:641 (services_unittests+0x23fdbf8) #27 Run base/callback.h:97:12 (services_unittests+0x72fb0e8) #28 base::(anonymous namespace)::LaunchUnitTestsInternal(base::OnceCallback<int ()>, unsigned long, int, bool, base::OnceCallback<void ()>) base/test/launcher/unit_test_launcher.cc:225 (services_unittests+0x72fb0e8) #29 base::LaunchUnitTests(int, char**, base::OnceCallback<int ()>) base/test/launcher/unit_test_launcher.cc:575:10 (services_unittests+0x72faef7) #30 main services/test/run_all_unittests.cc:97:10 (services_unittests+0x23fd82e) SUMMARY: ThreadSanitizer: data race base/synchronization/lock_impl_posix.cc:102:12 in base::internal::LockImpl::Lock() ================== [ OK ] TraceEventDataSourceTest.InstantTraceEvent (2136 ms) [ RUN ] TraceEventDataSourceTest.EventWithStringArgs [ OK ] TraceEventDataSourceTest.EventWithStringArgs (8 ms) [ RUN ] TraceEventDataSourceTest.EventWithCopiedStrings [ OK ] TraceEventDataSourceTest.EventWithCopiedStrings (9 ms) [ RUN ] TraceEventDataSourceTest.EventWithUIntArgs [ OK ] TraceEventDataSourceTest.EventWithUIntArgs (8 ms) [ RUN ] TraceEventDataSourceTest.EventWithIntArgs [ OK ] TraceEventDataSourceTest.EventWithIntArgs (8 ms) [ RUN ] TraceEventDataSourceTest.EventWithBoolArgs [ OK ] TraceEventDataSourceTest.EventWithBoolArgs (8 ms) [----------] 10 tests from TraceEventDataSourceTest (2234 ms total) [----------] Global test environment tear-down [==========] 10 tests from 1 test suite ran. (2235 ms total) [ PASSED ] 10 tests. ThreadSanitizer: reported 1 warnings [ RUN ] TraceEventDataSourceTest.TimestampedTraceEvent [ OK ] TraceEventDataSourceTest.TimestampedTraceEvent (13 ms) ================================================================================