1 | //===-- fdr_log_writer_test.cpp -------------------------------------------===// |
2 | // |
3 | // Part of the LLVM Project, under the Apache License v2.0 with LLVM Exceptions. |
4 | // See https://llvm.org/LICENSE.txt for license information. |
5 | // SPDX-License-Identifier: Apache-2.0 WITH LLVM-exception |
6 | // |
7 | //===----------------------------------------------------------------------===// |
8 | // |
9 | // This file is a part of XRay, a function call tracing system. |
10 | // |
11 | //===----------------------------------------------------------------------===// |
12 | #include <time.h> |
13 | |
14 | #include "test_helpers.h" |
15 | #include "xray/xray_records.h" |
16 | #include "xray_fdr_log_writer.h" |
17 | #include "llvm/Support/DataExtractor.h" |
18 | #include "llvm/Testing/Support/Error.h" |
19 | #include "llvm/XRay/Trace.h" |
20 | #include "gmock/gmock.h" |
21 | #include "gtest/gtest.h" |
22 | |
23 | namespace __xray { |
24 | namespace { |
25 | |
26 | static constexpr size_t kSize = 4096; |
27 | |
28 | using ::llvm::HasValue; |
29 | using ::llvm::xray::testing::FuncId; |
30 | using ::llvm::xray::testing::RecordType; |
31 | using ::testing::AllOf; |
32 | using ::testing::ElementsAre; |
33 | using ::testing::Eq; |
34 | using ::testing::IsEmpty; |
35 | using ::testing::IsNull; |
36 | |
37 | // Exercise the common code path where we initialize a buffer and are able to |
38 | // write some records successfully. |
39 | TEST(FdrLogWriterTest, WriteSomeRecords) { |
40 | bool Success = false; |
41 | BufferQueue Buffers(kSize, 1, Success); |
42 | BufferQueue::Buffer B; |
43 | ASSERT_EQ(Buffers.getBuffer(Buf&: B), BufferQueue::ErrorCode::Ok); |
44 | |
45 | FDRLogWriter Writer(B); |
46 | MetadataRecord Preamble[] = { |
47 | createMetadataRecord<MetadataRecord::RecordKinds::NewBuffer>(Ds: int32_t{1}), |
48 | createMetadataRecord<MetadataRecord::RecordKinds::WalltimeMarker>( |
49 | Ds: int64_t{1}, Ds: int32_t{2}), |
50 | createMetadataRecord<MetadataRecord::RecordKinds::Pid>(Ds: int32_t{1}), |
51 | }; |
52 | ASSERT_THAT(Writer.writeMetadataRecords(Recs&: Preamble), |
53 | Eq(sizeof(MetadataRecord) * 3)); |
54 | ASSERT_TRUE(Writer.writeMetadata<MetadataRecord::RecordKinds::NewCPUId>(Ds: 1)); |
55 | ASSERT_TRUE( |
56 | Writer.writeFunction(Kind: FDRLogWriter::FunctionRecordKind::Enter, FuncId: 1, Delta: 1)); |
57 | ASSERT_TRUE( |
58 | Writer.writeFunction(Kind: FDRLogWriter::FunctionRecordKind::Exit, FuncId: 1, Delta: 1)); |
59 | ASSERT_EQ(Buffers.releaseBuffer(Buf&: B), BufferQueue::ErrorCode::Ok); |
60 | ASSERT_EQ(B.Data, nullptr); |
61 | ASSERT_EQ(Buffers.finalize(), BufferQueue::ErrorCode::Ok); |
62 | |
63 | // We then need to go through each element of the Buffers, and re-create a |
64 | // flat buffer that we would see if they were laid out in a file. This also |
65 | // means we need to write out the header manually. |
66 | std::string Serialized = serialize(Buffers, Version: 3); |
67 | llvm::DataExtractor DE(Serialized, true, 8); |
68 | auto TraceOrErr = llvm::xray::loadTrace(Extractor: DE); |
69 | EXPECT_THAT_EXPECTED( |
70 | TraceOrErr, |
71 | HasValue(ElementsAre( |
72 | AllOf(FuncId(1), RecordType(llvm::xray::RecordTypes::ENTER)), |
73 | AllOf(FuncId(1), RecordType(llvm::xray::RecordTypes::EXIT))))); |
74 | } |
75 | |
76 | // Ensure that we can handle buffer re-use. |
77 | TEST(FdrLogWriterTest, ReuseBuffers) { |
78 | bool Success = false; |
79 | BufferQueue Buffers(kSize, 1, Success); |
80 | BufferQueue::Buffer B; |
81 | ASSERT_EQ(Buffers.getBuffer(Buf&: B), BufferQueue::ErrorCode::Ok); |
82 | |
83 | FDRLogWriter Writer(B); |
84 | MetadataRecord Preamble[] = { |
85 | createMetadataRecord<MetadataRecord::RecordKinds::NewBuffer>(Ds: int32_t{1}), |
86 | createMetadataRecord<MetadataRecord::RecordKinds::WalltimeMarker>( |
87 | Ds: int64_t{1}, Ds: int32_t{2}), |
88 | createMetadataRecord<MetadataRecord::RecordKinds::Pid>(Ds: int32_t{1}), |
89 | }; |
90 | |
91 | // First we write the first set of records into the single buffer in the |
92 | // queue which includes one enter and one exit record. |
93 | ASSERT_THAT(Writer.writeMetadataRecords(Recs&: Preamble), |
94 | Eq(sizeof(MetadataRecord) * 3)); |
95 | ASSERT_TRUE(Writer.writeMetadata<MetadataRecord::RecordKinds::NewCPUId>( |
96 | Ds: uint16_t{1}, Ds: uint64_t{1})); |
97 | uint64_t TSC = 1; |
98 | ASSERT_TRUE( |
99 | Writer.writeFunction(Kind: FDRLogWriter::FunctionRecordKind::Enter, FuncId: 1, Delta: TSC++)); |
100 | ASSERT_TRUE( |
101 | Writer.writeFunction(Kind: FDRLogWriter::FunctionRecordKind::Exit, FuncId: 1, Delta: TSC++)); |
102 | ASSERT_EQ(Buffers.releaseBuffer(Buf&: B), BufferQueue::ErrorCode::Ok); |
103 | ASSERT_THAT(B.Data, IsNull()); |
104 | |
105 | // Then we re-use the buffer, but only write one record. |
106 | ASSERT_EQ(Buffers.getBuffer(Buf&: B), BufferQueue::ErrorCode::Ok); |
107 | Writer.resetRecord(); |
108 | ASSERT_THAT(Writer.writeMetadataRecords(Recs&: Preamble), |
109 | Eq(sizeof(MetadataRecord) * 3)); |
110 | ASSERT_TRUE(Writer.writeMetadata<MetadataRecord::RecordKinds::NewCPUId>( |
111 | Ds: uint16_t{1}, Ds: uint64_t{1})); |
112 | ASSERT_TRUE( |
113 | Writer.writeFunction(Kind: FDRLogWriter::FunctionRecordKind::Enter, FuncId: 1, Delta: TSC++)); |
114 | ASSERT_EQ(Buffers.releaseBuffer(Buf&: B), BufferQueue::ErrorCode::Ok); |
115 | ASSERT_THAT(B.Data, IsNull()); |
116 | ASSERT_EQ(Buffers.finalize(), BufferQueue::ErrorCode::Ok); |
117 | |
118 | // Then we validate that we only see the single enter record. |
119 | std::string Serialized = serialize(Buffers, Version: 3); |
120 | llvm::DataExtractor DE(Serialized, true, 8); |
121 | auto TraceOrErr = llvm::xray::loadTrace(Extractor: DE); |
122 | EXPECT_THAT_EXPECTED( |
123 | TraceOrErr, HasValue(ElementsAre(AllOf( |
124 | FuncId(1), RecordType(llvm::xray::RecordTypes::ENTER))))); |
125 | } |
126 | |
127 | TEST(FdrLogWriterTest, UnwriteRecords) { |
128 | bool Success = false; |
129 | BufferQueue Buffers(kSize, 1, Success); |
130 | BufferQueue::Buffer B; |
131 | ASSERT_EQ(Buffers.getBuffer(Buf&: B), BufferQueue::ErrorCode::Ok); |
132 | |
133 | FDRLogWriter Writer(B); |
134 | MetadataRecord Preamble[] = { |
135 | createMetadataRecord<MetadataRecord::RecordKinds::NewBuffer>(Ds: int32_t{1}), |
136 | createMetadataRecord<MetadataRecord::RecordKinds::WalltimeMarker>( |
137 | Ds: int64_t{1}, Ds: int32_t{2}), |
138 | createMetadataRecord<MetadataRecord::RecordKinds::Pid>(Ds: int32_t{1}), |
139 | }; |
140 | ASSERT_THAT(Writer.writeMetadataRecords(Recs&: Preamble), |
141 | Eq(sizeof(MetadataRecord) * 3)); |
142 | ASSERT_TRUE(Writer.writeMetadata<MetadataRecord::RecordKinds::NewCPUId>(Ds: 1)); |
143 | ASSERT_TRUE( |
144 | Writer.writeFunction(Kind: FDRLogWriter::FunctionRecordKind::Enter, FuncId: 1, Delta: 1)); |
145 | ASSERT_TRUE( |
146 | Writer.writeFunction(Kind: FDRLogWriter::FunctionRecordKind::Exit, FuncId: 1, Delta: 1)); |
147 | Writer.undoWrites(B: sizeof(FunctionRecord) * 2); |
148 | ASSERT_EQ(Buffers.releaseBuffer(Buf&: B), BufferQueue::ErrorCode::Ok); |
149 | ASSERT_EQ(B.Data, nullptr); |
150 | ASSERT_EQ(Buffers.finalize(), BufferQueue::ErrorCode::Ok); |
151 | |
152 | // We've un-done the two function records we've written, and now we expect |
153 | // that we don't have any function records in the trace. |
154 | std::string Serialized = serialize(Buffers, Version: 3); |
155 | llvm::DataExtractor DE(Serialized, true, 8); |
156 | auto TraceOrErr = llvm::xray::loadTrace(Extractor: DE); |
157 | EXPECT_THAT_EXPECTED(TraceOrErr, HasValue(IsEmpty())); |
158 | } |
159 | |
160 | } // namespace |
161 | } // namespace __xray |
162 | |