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

Test 'FtlMessagingClientTest.TestStreamOpener_StreamsTwoMessagesThenCloseByClient' completed with the following status(es): 'CRASH','SUCCESS' Test 'FtlMessagingClientTest.TestStreamOpener_StreamsTwoMessagesThenCloseByClient' had the following logs when run: ================================================================================ [ RUN ] FtlMessagingClientTest.TestStreamOpener_StreamsTwoMessagesThenCloseByClient [26273:26273:0324/121307.536342:15246184226:INFO:grpc_async_dispatcher.cc(58)] Enqueuing RPC: 0x612000018040 [26273:26336:0324/121307.536691:15246184512:INFO:grpc_async_server_streaming_call_data.cc(46)] Streaming call started: 0x612000018040 [26273:26336:0324/121307.536947:15246184769:INFO:grpc_async_dispatcher.cc(50)] Re-enqueuing RPC: 0x612000018040 [26273:26336:0324/121307.538073:15246185890:INFO:grpc_async_server_streaming_call_data.cc(52)] Streaming call received message: 0x612000018040 [26273:26336:0324/121307.538205:15246186022:INFO:grpc_async_dispatcher.cc(50)] Re-enqueuing RPC: 0x612000018040 [26273:26336:0324/121307.538262:15246186076:INFO:grpc_async_server_streaming_call_data.cc(52)] Streaming call received message: 0x612000018040 [26273:26336:0324/121307.538316:15246186132:INFO:grpc_async_dispatcher.cc(50)] Re-enqueuing RPC: 0x612000018040 [26273:26273:0324/121307.538605:15246186419:INFO:grpc_async_call_data.cc(22)] Canceling request: 0x612000018040 [26273:26336:0324/121307.538728:15246186554:INFO:grpc_async_dispatcher.cc(45)] Dequeuing RPC: 0x612000018040 [26273:26273:0324/121307.538988:15246186802:INFO:grpc_async_dispatcher.cc(24)] # of pending RPCs at destruction: 0 ================================================================= ==26273==ERROR: AddressSanitizer: heap-use-after-free on address 0x61d000010100 at pc 0x564cff376d75 bp 0x7fa0cbdc14c0 sp 0x7fa0cbdc14b8 READ of size 8 at 0x61d000010100 thread T3 (TaskSchedulerFo) #0 0x564cff376d74 in grpc::CompletionQueue::AsyncNextInternal(void**, bool*, gpr_timespec) ./../../third_party/grpc/src/src/cpp/common/completion_queue_cc.cc:58:26 #1 0x564cfc5984f4 in Next ./../../third_party/grpc/src/include/grpcpp/impl/codegen/completion_queue.h:173:13 #2 0x564cfc5984f4 in remoting::test::WaitForCompletion(base::Location const&, grpc::CompletionQueue*, void*) ./../../remoting/signaling/grpc_support/grpc_test_util.cc:19:0 #3 0x564cf6714078 in SendMessage ./../../remoting/signaling/grpc_support/grpc_test_util.h:79:12 #4 0x564cf6714078 in operator() ./../../remoting/signaling/ftl_messaging_client_unittest.cc:398:0 #5 0x564cf6714078 in base::internal::BindLambdaHelper<remoting::FtlMessagingClientTest_TestStreamOpener_StreamsTwoMessagesThenCloseByClient_Test::TestBody()::$_3, void ()>::Run(remoting::FtlMessagingClientTest_TestStreamOpener_StreamsTwoMessagesThenCloseByClient_Test::TestBody()::$_3 const&) ./../../base/test/bind_test_util.h:23:0 #6 0x564cfc8e1d75 in Run ./../../base/callback.h:97:12 #7 0x564cfc8e1d75 in base::TaskAnnotator::RunTask(char const*, base::PendingTask*) ./../../base/task/common/task_annotator.cc:119:0 #8 0x564cfc994022 in base::internal::TaskTracker::RunSkipOnShutdown(base::internal::Task*) ./../../base/task/task_scheduler/task_tracker.cc:938:19 #9 0x564cfc992464 in RunTaskWithShutdownBehavior ./../../base/task/task_scheduler/task_tracker.cc:956:7 #10 0x564cfc992464 in base::internal::TaskTracker::RunOrSkipTask(base::internal::Task, base::internal::Sequence*, base::TaskTraits const&, bool) ./../../base/task/task_scheduler/task_tracker.cc:643:0 #11 0x564cfca812d4 in base::internal::TaskTrackerPosix::RunOrSkipTask(base::internal::Task, base::internal::Sequence*, base::TaskTraits const&, bool) ./../../base/task/task_scheduler/task_tracker_posix.cc:24:16 #12 0x564cfcaa86fd in base::test::ScopedTaskEnvironment::TestTaskTracker::RunOrSkipTask(base::internal::Task, base::internal::Sequence*, base::TaskTraits const&, bool) ./../../base/test/scoped_task_environment.cc:663:49 #13 0x564cfc98f2f1 in base::internal::TaskTracker::RunAndPopNextTask(scoped_refptr<base::internal::Sequence>, base::internal::CanScheduleSequenceObserver*) ./../../base/task/task_scheduler/task_tracker.cc:494:3 #14 0x564cfc980254 in base::internal::SchedulerWorker::RunWorker() ./../../base/task/task_scheduler/scheduler_worker.cc:341:24 #15 0x564cfc97f73f in base::internal::SchedulerWorker::RunPooledWorker() ./../../base/task/task_scheduler/scheduler_worker.cc:233:3 #16 0x564cfca82c8b in base::(anonymous namespace)::ThreadFunc(void*) ./../../base/threading/platform_thread_posix.cc:81:13 #17 0x7fa0d7ae1183 in start_thread ??:0:0 0x61d000010100 is located 1664 bytes inside of 2136-byte region [0x61d00000fa80,0x61d0000102d8) freed by thread T0 here: #0 0x564cf62b6d1d in operator delete(void*) _asan_rtl_:3 #1 0x564cf6708f5f in operator() ./../../buildtools/third_party/libc++/trunk/include/memory:2338:5 #2 0x564cf6708f5f in reset ./../../buildtools/third_party/libc++/trunk/include/memory:2651:0 #3 0x564cf6708f5f in ~unique_ptr ./../../buildtools/third_party/libc++/trunk/include/memory:2605:0 #4 0x564cf6708f5f in remoting::FtlMessagingClientTest_TestStreamOpener_StreamsTwoMessagesThenCloseByClient_Test::TestBody() ./../../remoting/signaling/ftl_messaging_client_unittest.cc:424:0 #5 0x564cf7299110 in HandleExceptionsInMethodIfSupported<testing::Test, void> ./../../third_party/googletest/src/googletest/src/gtest.cc:0:0 #6 0x564cf7299110 in testing::Test::Run() ./../../third_party/googletest/src/googletest/src/gtest.cc:2499:0 #7 0x564cf729b1a9 in testing::TestInfo::Run() ./../../third_party/googletest/src/googletest/src/gtest.cc:2675:11 #8 0x564cf729c686 in testing::TestSuite::Run() ./../../third_party/googletest/src/googletest/src/gtest.cc:2805:28 #9 0x564cf72c4b46 in testing::internal::UnitTestImpl::RunAllTests() ./../../third_party/googletest/src/googletest/src/gtest.cc:5243:44 #10 0x564cf72c3c54 in HandleExceptionsInMethodIfSupported<testing::internal::UnitTestImpl, bool> ./../../third_party/googletest/src/googletest/src/gtest.cc:0:0 #11 0x564cf72c3c54 in testing::UnitTest::Run() ./../../third_party/googletest/src/googletest/src/gtest.cc:4845:0 #12 0x564cfcab5c39 in RUN_ALL_TESTS ./../../third_party/googletest/src/googletest/include/gtest/gtest.h:2469:46 #13 0x564cfcab5c39 in base::TestSuite::Run() ./../../base/test/test_suite.cc:316:0 #14 0x564cfcabf1dc in Run ./../../base/callback.h:97:12 #15 0x564cfcabf1dc in base::(anonymous namespace)::LaunchUnitTestsInternal(base::OnceCallback<int ()>, unsigned long, int, bool, base::OnceCallback<void ()>) ./../../base/test/launcher/unit_test_launcher.cc:225:0 #16 0x564cfcabeca0 in base::LaunchUnitTests(int, char**, base::OnceCallback<int ()>) ./../../base/test/launcher/unit_test_launcher.cc:575:10 #17 0x564cf63698e4 in main ./../../remoting/base/run_all_unittests.cc:25:10 #18 0x7fa0d32cdf44 in __libc_start_main ??:0:0 previously allocated by thread T3 (TaskSchedulerFo) here: #0 0x564cf62b64bd in operator new(unsigned long) _asan_rtl_:3 #1 0x564cf67139f1 in make_unique<remoting::test::GrpcServerStreamResponder<remoting::ftl::ReceiveMessagesResponse>, grpc::ServerCompletionQueue *> ./../../buildtools/third_party/libc++/trunk/include/memory:3131:28 #2 0x564cf67139f1 in HandleStreamRequest<google::internal::communications::instantmessaging::v1::Messaging::WithAsyncMethod_ReceiveMessages<google::internal::communications::instantmessaging::v1::Messaging::WithAsyncMethod_SendMessage<google::internal::communications::instantmessaging::v1::Messaging::Service> >, remoting::ftl::ReceiveMessagesRequest, remoting::ftl::ReceiveMessagesResponse> ./../../remoting/signaling/grpc_support/grpc_async_test_server.h:87:0 #3 0x564cf67139f1 in operator() ./../../remoting/signaling/ftl_messaging_client_unittest.cc:395:0 #4 0x564cf67139f1 in base::internal::BindLambdaHelper<remoting::FtlMessagingClientTest_TestStreamOpener_StreamsTwoMessagesThenCloseByClient_Test::TestBody()::$_3, void ()>::Run(remoting::FtlMessagingClientTest_TestStreamOpener_StreamsTwoMessagesThenCloseByClient_Test::TestBody()::$_3 const&) ./../../base/test/bind_test_util.h:23:0 #5 0x564cfc8e1d75 in Run ./../../base/callback.h:97:12 #6 0x564cfc8e1d75 in base::TaskAnnotator::RunTask(char const*, base::PendingTask*) ./../../base/task/common/task_annotator.cc:119:0 #7 0x564cfc994022 in base::internal::TaskTracker::RunSkipOnShutdown(base::internal::Task*) ./../../base/task/task_scheduler/task_tracker.cc:938:19 #8 0x564cfc992464 in RunTaskWithShutdownBehavior ./../../base/task/task_scheduler/task_tracker.cc:956:7 #9 0x564cfc992464 in base::internal::TaskTracker::RunOrSkipTask(base::internal::Task, base::internal::Sequence*, base::TaskTraits const&, bool) ./../../base/task/task_scheduler/task_tracker.cc:643:0 #10 0x564cfca812d4 in base::internal::TaskTrackerPosix::RunOrSkipTask(base::internal::Task, base::internal::Sequence*, base::TaskTraits const&, bool) ./../../base/task/task_scheduler/task_tracker_posix.cc:24:16 #11 0x564cfcaa86fd in base::test::ScopedTaskEnvironment::TestTaskTracker::RunOrSkipTask(base::internal::Task, base::internal::Sequence*, base::TaskTraits const&, bool) ./../../base/test/scoped_task_environment.cc:663:49 #12 0x564cfc98f2f1 in base::internal::TaskTracker::RunAndPopNextTask(scoped_refptr<base::internal::Sequence>, base::internal::CanScheduleSequenceObserver*) ./../../base/task/task_scheduler/task_tracker.cc:494:3 #13 0x564cfc980254 in base::internal::SchedulerWorker::RunWorker() ./../../base/task/task_scheduler/scheduler_worker.cc:341:24 #14 0x564cfc97f73f in base::internal::SchedulerWorker::RunPooledWorker() ./../../base/task/task_scheduler/scheduler_worker.cc:233:3 #15 0x564cfca82c8b in base::(anonymous namespace)::ThreadFunc(void*) ./../../base/threading/platform_thread_posix.cc:81:13 #16 0x7fa0d7ae1183 in start_thread ??:0:0 Thread T3 (TaskSchedulerFo) created by T0 here: #0 0x564cf6273f6a in __interceptor_pthread_create _asan_rtl_:3 #1 0x564cfca81f8f in base::(anonymous namespace)::CreateThread(unsigned long, bool, base::PlatformThread::Delegate*, base::PlatformThreadHandle*, base::ThreadPriority) ./../../base/threading/platform_thread_posix.cc:120:13 #2 0x564cfc97e00e in base::internal::SchedulerWorker::Start(base::SchedulerWorkerObserver*) ./../../base/task/task_scheduler/scheduler_worker.cc:79:3 #3 0x564cfc97aff2 in operator() ./../../base/task/task_scheduler/scheduler_worker_pool_impl.cc:187:15 #4 0x564cfc97aff2 in 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:0 #5 0x564cfc96c883 in FlushImpl ./../../base/task/task_scheduler/scheduler_worker_pool_impl.cc:186:23 #6 0x564cfc96c883 in base::internal::SchedulerWorkerPoolImpl::ScopedWorkersExecutor::~ScopedWorkersExecutor() ./../../base/task/task_scheduler/scheduler_worker_pool_impl.cc:105:0 #7 0x564cfc96c53f in 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:435:1 #8 0x564cfc94f48c in base::internal::TaskSchedulerImpl::Start(base::TaskScheduler::InitParams const&, base::SchedulerWorkerObserver*) ./../../base/task/task_scheduler/task_scheduler_impl.cc:142:21 #9 0x564cfcaa6112 in 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 #10 0x564cf6713814 in FtlMessagingClientTest ./../../remoting/signaling/ftl_messaging_client_unittest.cc:103:7 #11 0x564cf6713814 in FtlMessagingClientTest_TestStreamOpener_StreamsTwoMessagesThenCloseByClient_Test ./../../remoting/signaling/ftl_messaging_client_unittest.cc:378:0 #12 0x564cf6713814 in testing::internal::TestFactoryImpl<remoting::FtlMessagingClientTest_TestStreamOpener_StreamsTwoMessagesThenCloseByClient_Test>::CreateTest() ./../../third_party/googletest/src/googletest/include/gtest/internal/gtest-internal.h:460:0 #13 0x564cf729af40 in HandleExceptionsInMethodIfSupported<testing::internal::TestFactoryBase, testing::Test *> ./../../third_party/googletest/src/googletest/src/gtest.cc:0:0 #14 0x564cf729af40 in testing::TestInfo::Run() ./../../third_party/googletest/src/googletest/src/gtest.cc:2665:0 #15 0x564cf729c686 in testing::TestSuite::Run() ./../../third_party/googletest/src/googletest/src/gtest.cc:2805:28 #16 0x564cf72c4b46 in testing::internal::UnitTestImpl::RunAllTests() ./../../third_party/googletest/src/googletest/src/gtest.cc:5243:44 #17 0x564cf72c3c54 in HandleExceptionsInMethodIfSupported<testing::internal::UnitTestImpl, bool> ./../../third_party/googletest/src/googletest/src/gtest.cc:0:0 #18 0x564cf72c3c54 in testing::UnitTest::Run() ./../../third_party/googletest/src/googletest/src/gtest.cc:4845:0 #19 0x564cfcab5c39 in RUN_ALL_TESTS ./../../third_party/googletest/src/googletest/include/gtest/gtest.h:2469:46 #20 0x564cfcab5c39 in base::TestSuite::Run() ./../../base/test/test_suite.cc:316:0 #21 0x564cfcabf1dc in Run ./../../base/callback.h:97:12 #22 0x564cfcabf1dc in base::(anonymous namespace)::LaunchUnitTestsInternal(base::OnceCallback<int ()>, unsigned long, int, bool, base::OnceCallback<void ()>) ./../../base/test/launcher/unit_test_launcher.cc:225:0 #23 0x564cfcabeca0 in base::LaunchUnitTests(int, char**, base::OnceCallback<int ()>) ./../../base/test/launcher/unit_test_launcher.cc:575:10 #24 0x564cf63698e4 in main ./../../remoting/base/run_all_unittests.cc:25:10 #25 0x7fa0d32cdf44 in __libc_start_main ??:0:0 SUMMARY: AddressSanitizer: heap-use-after-free (/b/s/w/ir/out/Release/remoting_unittests+0xeb95d74) Shadow bytes around the buggy address: 0x0c3a7fff9fd0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c3a7fff9fe0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c3a7fff9ff0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c3a7fffa000: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c3a7fffa010: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd =>0x0c3a7fffa020:[fd]fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c3a7fffa030: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c3a7fffa040: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c3a7fffa050: fd fd fd fd fd fd fd fd fd fd fd fa fa fa fa fa 0x0c3a7fffa060: fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa 0x0c3a7fffa070: fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa Shadow byte legend (one shadow byte represents 8 application bytes): Addressable: 00 Partially addressable: 01 02 03 04 05 06 07 Heap left redzone: fa Freed heap region: fd Stack left redzone: f1 Stack mid redzone: f2 Stack right redzone: f3 Stack after return: f5 Stack use after scope: f8 Global redzone: f9 Global init order: f6 Poisoned by user: f7 Container overflow: fc Array cookie: ac Intra object redzone: bb ASan internal: fe Left alloca redzone: ca Right alloca redzone: cb Shadow gap: cc ==26273==ABORTING [ RUN ] FtlMessagingClientTest.TestStreamOpener_StreamsTwoMessagesThenCloseByClient [27595:27595:0324/121317.521740:15256170553:INFO:grpc_async_dispatcher.cc(58)] Enqueuing RPC: 0x612000018040 [27595:27602:0324/121317.523749:15256171571:INFO:grpc_async_server_streaming_call_data.cc(46)] Streaming call started: 0x612000018040 [27595:27602:0324/121317.524014:15256171836:INFO:grpc_async_dispatcher.cc(50)] Re-enqueuing RPC: 0x612000018040 [27595:27602:0324/121317.524097:15256171911:INFO:grpc_async_server_streaming_call_data.cc(52)] Streaming call received message: 0x612000018040 [27595:27602:0324/121317.524174:15256171996:INFO:grpc_async_dispatcher.cc(50)] Re-enqueuing RPC: 0x612000018040 [27595:27602:0324/121317.524261:15256172078:INFO:grpc_async_server_streaming_call_data.cc(52)] Streaming call received message: 0x612000018040 [27595:27602:0324/121317.524383:15256172200:INFO:grpc_async_dispatcher.cc(50)] Re-enqueuing RPC: 0x612000018040 [27595:27595:0324/121317.524502:15256172317:INFO:grpc_async_call_data.cc(22)] Canceling request: 0x612000018040 [27595:27602:0324/121317.524608:15256172424:INFO:grpc_async_dispatcher.cc(45)] Dequeuing RPC: 0x612000018040 [27595:27595:0324/121317.524748:15256172562:WARNING:grpc_test_util.h(89)] Failed to finish stream. Connection might be dropped. [27595:27595:0324/121317.524874:15256172686:INFO:grpc_async_dispatcher.cc(24)] # of pending RPCs at destruction: 0 [ OK ] FtlMessagingClientTest.TestStreamOpener_StreamsTwoMessagesThenCloseByClient (17 ms) ================================================================================