//===-- fdr_logging_test.cc -----------------------------------------------===// // // The LLVM Compiler Infrastructure // // This file is distributed under the University of Illinois Open Source // License. See LICENSE.TXT for details. // //===----------------------------------------------------------------------===// // // This file is a part of XRay, a function call tracing system. // //===----------------------------------------------------------------------===// #include "xray_fdr_logging.h" #include "gtest/gtest.h" #include #include #include #include #include #include #include #include #include #include #include "xray/xray_records.h" namespace __xray { namespace { constexpr auto kBufferSize = 16384; constexpr auto kBufferMax = 10; struct ScopedFileCloserAndDeleter { explicit ScopedFileCloserAndDeleter(int Fd, const char *Filename) : Fd(Fd), Filename(Filename) {} ~ScopedFileCloserAndDeleter() { if (Map) munmap(Map, Size); if (Fd) { close(Fd); unlink(Filename); } } void registerMap(void *M, size_t S) { Map = M; Size = S; } int Fd; const char *Filename; void *Map = nullptr; size_t Size = 0; }; TEST(FDRLoggingTest, Simple) { FDRLoggingOptions Options; Options.ReportErrors = true; char TmpFilename[] = "fdr-logging-test.XXXXXX"; Options.Fd = mkstemp(TmpFilename); ASSERT_NE(Options.Fd, -1); ASSERT_EQ(fdrLoggingInit(kBufferSize, kBufferMax, &Options, sizeof(FDRLoggingOptions)), XRayLogInitStatus::XRAY_LOG_INITIALIZED); fdrLoggingHandleArg0(1, XRayEntryType::ENTRY); fdrLoggingHandleArg0(1, XRayEntryType::EXIT); ASSERT_EQ(fdrLoggingFinalize(), XRayLogInitStatus::XRAY_LOG_FINALIZED); ASSERT_EQ(fdrLoggingFlush(), XRayLogFlushStatus::XRAY_LOG_FLUSHED); // To do this properly, we have to close the file descriptor then re-open the // file for reading this time. ASSERT_EQ(close(Options.Fd), 0); int Fd = open(TmpFilename, O_RDONLY); ASSERT_NE(-1, Fd); ScopedFileCloserAndDeleter Guard(Fd, TmpFilename); auto Size = lseek(Fd, 0, SEEK_END); ASSERT_NE(Size, 0); // Map the file contents. void *Map = mmap(NULL, Size, PROT_READ, MAP_PRIVATE, Fd, 0); const char *Contents = static_cast(Map); Guard.registerMap(Map, Size); ASSERT_NE(Contents, nullptr); XRayFileHeader H; memcpy(&H, Contents, sizeof(XRayFileHeader)); ASSERT_EQ(H.Version, 2); ASSERT_EQ(H.Type, FileTypes::FDR_LOG); // We require one buffer at least to have the "extents" metadata record, // followed by the NewBuffer record. MetadataRecord MDR0, MDR1; memcpy(&MDR0, Contents + sizeof(XRayFileHeader), sizeof(MetadataRecord)); memcpy(&MDR1, Contents + sizeof(XRayFileHeader) + sizeof(MetadataRecord), sizeof(MetadataRecord)); ASSERT_EQ(MDR0.RecordKind, uint8_t(MetadataRecord::RecordKinds::BufferExtents)); ASSERT_EQ(MDR1.RecordKind, uint8_t(MetadataRecord::RecordKinds::NewBuffer)); } TEST(FDRLoggingTest, Multiple) { FDRLoggingOptions Options; char TmpFilename[] = "fdr-logging-test.XXXXXX"; Options.Fd = mkstemp(TmpFilename); ASSERT_NE(Options.Fd, -1); ASSERT_EQ(fdrLoggingInit(kBufferSize, kBufferMax, &Options, sizeof(FDRLoggingOptions)), XRayLogInitStatus::XRAY_LOG_INITIALIZED); for (uint64_t I = 0; I < 100; ++I) { fdrLoggingHandleArg0(1, XRayEntryType::ENTRY); fdrLoggingHandleArg0(1, XRayEntryType::EXIT); } ASSERT_EQ(fdrLoggingFinalize(), XRayLogInitStatus::XRAY_LOG_FINALIZED); ASSERT_EQ(fdrLoggingFlush(), XRayLogFlushStatus::XRAY_LOG_FLUSHED); // To do this properly, we have to close the file descriptor then re-open the // file for reading this time. ASSERT_EQ(close(Options.Fd), 0); int Fd = open(TmpFilename, O_RDONLY); ASSERT_NE(-1, Fd); ScopedFileCloserAndDeleter Guard(Fd, TmpFilename); auto Size = lseek(Fd, 0, SEEK_END); ASSERT_NE(Size, 0); // Map the file contents. void *Map = mmap(NULL, Size, PROT_READ, MAP_PRIVATE, Fd, 0); const char *Contents = static_cast(Map); Guard.registerMap(Map, Size); ASSERT_NE(Contents, nullptr); XRayFileHeader H; memcpy(&H, Contents, sizeof(XRayFileHeader)); ASSERT_EQ(H.Version, 2); ASSERT_EQ(H.Type, FileTypes::FDR_LOG); MetadataRecord MDR0, MDR1; memcpy(&MDR0, Contents + sizeof(XRayFileHeader), sizeof(MetadataRecord)); memcpy(&MDR1, Contents + sizeof(XRayFileHeader) + sizeof(MetadataRecord), sizeof(MetadataRecord)); ASSERT_EQ(MDR0.RecordKind, uint8_t(MetadataRecord::RecordKinds::BufferExtents)); ASSERT_EQ(MDR1.RecordKind, uint8_t(MetadataRecord::RecordKinds::NewBuffer)); } TEST(FDRLoggingTest, MultiThreadedCycling) { FDRLoggingOptions Options; char TmpFilename[] = "fdr-logging-test.XXXXXX"; Options.Fd = mkstemp(TmpFilename); ASSERT_NE(Options.Fd, -1); ASSERT_EQ(fdrLoggingInit(kBufferSize, 1, &Options, sizeof(FDRLoggingOptions)), XRayLogInitStatus::XRAY_LOG_INITIALIZED); // Now we want to create one thread, do some logging, then create another one, // in succession and making sure that we're able to get thread records from // the latest thread (effectively being able to recycle buffers). std::array Threads; for (uint64_t I = 0; I < 2; ++I) { std::thread t{[I, &Threads] { fdrLoggingHandleArg0(I + 1, XRayEntryType::ENTRY); fdrLoggingHandleArg0(I + 1, XRayEntryType::EXIT); Threads[I] = syscall(SYS_gettid); }}; t.join(); } ASSERT_EQ(fdrLoggingFinalize(), XRayLogInitStatus::XRAY_LOG_FINALIZED); ASSERT_EQ(fdrLoggingFlush(), XRayLogFlushStatus::XRAY_LOG_FLUSHED); // To do this properly, we have to close the file descriptor then re-open the // file for reading this time. ASSERT_EQ(close(Options.Fd), 0); int Fd = open(TmpFilename, O_RDONLY); ASSERT_NE(-1, Fd); ScopedFileCloserAndDeleter Guard(Fd, TmpFilename); auto Size = lseek(Fd, 0, SEEK_END); ASSERT_NE(Size, 0); // Map the file contents. void *Map = mmap(NULL, Size, PROT_READ, MAP_PRIVATE, Fd, 0); const char *Contents = static_cast(Map); Guard.registerMap(Map, Size); ASSERT_NE(Contents, nullptr); XRayFileHeader H; memcpy(&H, Contents, sizeof(XRayFileHeader)); ASSERT_EQ(H.Version, 2); ASSERT_EQ(H.Type, FileTypes::FDR_LOG); MetadataRecord MDR0, MDR1; memcpy(&MDR0, Contents + sizeof(XRayFileHeader), sizeof(MetadataRecord)); memcpy(&MDR1, Contents + sizeof(XRayFileHeader) + sizeof(MetadataRecord), sizeof(MetadataRecord)); ASSERT_EQ(MDR0.RecordKind, uint8_t(MetadataRecord::RecordKinds::BufferExtents)); ASSERT_EQ(MDR1.RecordKind, uint8_t(MetadataRecord::RecordKinds::NewBuffer)); pid_t Latest = 0; memcpy(&Latest, MDR1.Data, sizeof(pid_t)); ASSERT_EQ(Latest, Threads[1]); } } // namespace } // namespace __xray