Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 1 | #ifndef AOS_EVENTS_LOGGING_LOG_NAMER_H_ |
| 2 | #define AOS_EVENTS_LOGGING_LOG_NAMER_H_ |
| 3 | |
| 4 | #include <functional> |
| 5 | #include <map> |
| 6 | #include <memory> |
| 7 | #include <string_view> |
| 8 | #include <vector> |
| 9 | |
Austin Schuh | 58646e2 | 2021-08-23 23:51:46 -0700 | [diff] [blame] | 10 | #include "absl/container/btree_map.h" |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 11 | #include "aos/events/logging/logfile_utils.h" |
| 12 | #include "aos/events/logging/logger_generated.h" |
Austin Schuh | 4385b14 | 2021-03-14 21:31:13 -0700 | [diff] [blame] | 13 | #include "aos/uuid.h" |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 14 | #include "flatbuffers/flatbuffers.h" |
| 15 | |
| 16 | namespace aos { |
| 17 | namespace logger { |
| 18 | |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 19 | class LogNamer; |
| 20 | |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 21 | // TODO(austin): Rename this back to DataWriter once all other callers are of |
| 22 | // the old DataWriter. |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 23 | // |
| 24 | // Class to manage writing data to log files. This lets us track which boot the |
| 25 | // written header has in it, and if the header has been written or not. |
Austin Schuh | 58646e2 | 2021-08-23 23:51:46 -0700 | [diff] [blame] | 26 | // |
| 27 | // The design of this class is that instead of being notified when any of the |
| 28 | // header data changes, it polls and owns that decision. This makes it much |
| 29 | // harder to write corrupted data. If that becomes a performance problem, we |
| 30 | // can DCHECK and take it out of production binaries. |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 31 | class NewDataWriter { |
| 32 | public: |
| 33 | // Constructs a NewDataWriter. |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 34 | // log_namer is the log namer which holds the config and any other data we |
| 35 | // need for our header. |
| 36 | // node is the node whom's prespective we are logging from. |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 37 | // reopen is called whenever a file needs to be reopened. |
| 38 | // close is called to close that file and extract any statistics. |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 39 | NewDataWriter(LogNamer *log_namer, const Node *node, |
| 40 | std::function<void(NewDataWriter *)> reopen, |
| 41 | std::function<void(NewDataWriter *)> close); |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 42 | |
| 43 | NewDataWriter(NewDataWriter &&other) = default; |
| 44 | aos::logger::NewDataWriter &operator=(NewDataWriter &&other) = default; |
| 45 | NewDataWriter(const NewDataWriter &) = delete; |
| 46 | void operator=(const NewDataWriter &) = delete; |
| 47 | |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 48 | ~NewDataWriter(); |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 49 | |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 50 | // Rotates the log file, delaying writing the new header until data arrives. |
| 51 | void Rotate(); |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 52 | |
Austin Schuh | 72211ae | 2021-08-05 14:02:30 -0700 | [diff] [blame] | 53 | void UpdateRemote(size_t remote_node_index, const UUID &remote_node_boot_uuid, |
| 54 | monotonic_clock::time_point monotonic_remote_time, |
| 55 | monotonic_clock::time_point monotonic_event_time, |
| 56 | bool reliable); |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 57 | // Queues up a message with the provided boot UUID. |
Austin Schuh | e46492f | 2021-07-31 19:49:41 -0700 | [diff] [blame] | 58 | void QueueMessage(flatbuffers::FlatBufferBuilder *fbb, |
| 59 | const UUID &node_boot_uuid, |
| 60 | aos::monotonic_clock::time_point now); |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 61 | |
Austin Schuh | 5e14d84 | 2022-01-21 12:02:15 -0800 | [diff] [blame] | 62 | // Updates the current boot for the source node. This is useful when you want |
| 63 | // to queue a message that may trigger a reboot rotation, but then need to |
| 64 | // update the remote timestamps. |
| 65 | void UpdateBoot(const UUID &source_node_boot_uuid); |
| 66 | |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 67 | // Returns the filename of the writer. |
Austin Schuh | 58646e2 | 2021-08-23 23:51:46 -0700 | [diff] [blame] | 68 | std::string_view filename() const { |
| 69 | return writer ? writer->filename() : "(closed)"; |
| 70 | } |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 71 | |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 72 | void Close(); |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 73 | |
| 74 | std::unique_ptr<DetachedBufferWriter> writer = nullptr; |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 75 | |
| 76 | size_t node_index() const { return node_index_; } |
| 77 | const UUID &parts_uuid() const { return parts_uuid_; } |
| 78 | size_t parts_index() const { return parts_index_; } |
| 79 | const Node *node() const { return node_; } |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 80 | |
Austin Schuh | 72211ae | 2021-08-05 14:02:30 -0700 | [diff] [blame] | 81 | // Datastructure used to capture all the information about a remote node. |
| 82 | struct State { |
| 83 | // Boot UUID of the node. |
| 84 | UUID boot_uuid = UUID::Zero(); |
| 85 | // Timestamp on the remote monotonic clock of the oldest message sent to |
| 86 | // node_index_. |
| 87 | monotonic_clock::time_point oldest_remote_monotonic_timestamp = |
| 88 | monotonic_clock::max_time; |
| 89 | // Timestamp on the local monotonic clock of the message in |
| 90 | // oldest_remote_monotonic_timestamp. |
| 91 | monotonic_clock::time_point oldest_local_monotonic_timestamp = |
| 92 | monotonic_clock::max_time; |
| 93 | // Timestamp on the remote monotonic clock of the oldest message sent to |
| 94 | // node_index_, excluding messages forwarded with time_to_live() == 0. |
| 95 | monotonic_clock::time_point oldest_remote_unreliable_monotonic_timestamp = |
| 96 | monotonic_clock::max_time; |
| 97 | // Timestamp on the local monotonic clock of the message in |
| 98 | // oldest_local_unreliable_monotonic_timestamp. |
| 99 | monotonic_clock::time_point oldest_local_unreliable_monotonic_timestamp = |
| 100 | monotonic_clock::max_time; |
| 101 | }; |
| 102 | |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 103 | private: |
Austin Schuh | e46492f | 2021-07-31 19:49:41 -0700 | [diff] [blame] | 104 | // Signals that a node has rebooted. |
Austin Schuh | 5e14d84 | 2022-01-21 12:02:15 -0800 | [diff] [blame] | 105 | void Reboot(const UUID &source_node_boot_uuid); |
Austin Schuh | e46492f | 2021-07-31 19:49:41 -0700 | [diff] [blame] | 106 | |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 107 | void QueueHeader( |
| 108 | aos::SizePrefixedFlatbufferDetachedBuffer<LogFileHeader> &&header); |
| 109 | |
Austin Schuh | e46492f | 2021-07-31 19:49:41 -0700 | [diff] [blame] | 110 | aos::SizePrefixedFlatbufferDetachedBuffer<LogFileHeader> MakeHeader(); |
| 111 | |
Austin Schuh | 58646e2 | 2021-08-23 23:51:46 -0700 | [diff] [blame] | 112 | monotonic_clock::time_point monotonic_start_time_ = monotonic_clock::min_time; |
| 113 | |
Austin Schuh | 577610e | 2021-12-08 12:07:19 -0800 | [diff] [blame] | 114 | const Node *node_ = nullptr; |
| 115 | size_t node_index_ = 0; |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 116 | LogNamer *log_namer_; |
| 117 | UUID parts_uuid_ = UUID::Random(); |
| 118 | size_t parts_index_ = 0; |
| 119 | |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 120 | std::function<void(NewDataWriter *)> reopen_; |
| 121 | std::function<void(NewDataWriter *)> close_; |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 122 | bool header_written_ = false; |
Austin Schuh | e46492f | 2021-07-31 19:49:41 -0700 | [diff] [blame] | 123 | |
Austin Schuh | 72211ae | 2021-08-05 14:02:30 -0700 | [diff] [blame] | 124 | std::vector<State> state_; |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 125 | }; |
| 126 | |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 127 | // Interface describing how to name, track, and add headers to log file parts. |
| 128 | class LogNamer { |
| 129 | public: |
| 130 | // Constructs a LogNamer with the primary node (ie the one the logger runs on) |
| 131 | // being node. |
Austin Schuh | 5b728b7 | 2021-06-16 14:57:15 -0700 | [diff] [blame] | 132 | LogNamer(const aos::Configuration *configuration, EventLoop *event_loop, |
| 133 | const aos::Node *node) |
Austin Schuh | a499cea | 2021-07-31 19:49:53 -0700 | [diff] [blame] | 134 | : event_loop_(event_loop), |
Austin Schuh | 5b728b7 | 2021-06-16 14:57:15 -0700 | [diff] [blame] | 135 | configuration_(configuration), |
| 136 | node_(node), |
Austin Schuh | a499cea | 2021-07-31 19:49:53 -0700 | [diff] [blame] | 137 | logger_node_index_(configuration::GetNodeIndex(configuration_, node_)) { |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 138 | nodes_.emplace_back(node_); |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 139 | } |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 140 | virtual ~LogNamer() {} |
| 141 | |
Austin Schuh | 6bb8a82 | 2021-03-31 23:04:39 -0700 | [diff] [blame] | 142 | virtual std::string_view base_name() const = 0; |
| 143 | |
| 144 | // Rotate should be called at least once in between calls to set_base_name. |
| 145 | // Otherwise temporary files will not be recoverable. |
| 146 | // Rotate is called by Logger::RenameLogBase, which is currently the only user |
| 147 | // of this method. |
| 148 | // Only renaming the folder is supported, not the file base name. |
| 149 | virtual void set_base_name(std::string_view base_name) = 0; |
| 150 | |
Brian Silverman | 87ac040 | 2020-09-17 14:47:01 -0700 | [diff] [blame] | 151 | // Returns a writer for writing data from messages on this channel (on the |
| 152 | // primary node). |
| 153 | // |
| 154 | // The returned pointer will stay valid across rotations, but the object it |
| 155 | // points to will be assigned to. |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 156 | virtual NewDataWriter *MakeWriter(const Channel *channel) = 0; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 157 | |
Brian Silverman | 87ac040 | 2020-09-17 14:47:01 -0700 | [diff] [blame] | 158 | // Returns a writer for writing timestamps from messages on this channel (on |
| 159 | // the primary node). |
| 160 | // |
| 161 | // The returned pointer will stay valid across rotations, but the object it |
| 162 | // points to will be assigned to. |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 163 | virtual NewDataWriter *MakeTimestampWriter(const Channel *channel) = 0; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 164 | |
| 165 | // Returns a writer for writing timestamps delivered over the special |
| 166 | // /aos/remote_timestamps/* channels. node is the node that the timestamps |
Brian Silverman | 87ac040 | 2020-09-17 14:47:01 -0700 | [diff] [blame] | 167 | // are forwarded back from (to the primary node). |
| 168 | // |
| 169 | // The returned pointer will stay valid across rotations, but the object it |
| 170 | // points to will be assigned to. |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 171 | virtual NewDataWriter *MakeForwardedTimestampWriter(const Channel *channel, |
| 172 | const Node *node) = 0; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 173 | |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 174 | // Rotates all log files for the provided node. |
| 175 | virtual void Rotate(const Node *node) = 0; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 176 | |
| 177 | // Returns all the nodes that data is being written for. |
| 178 | const std::vector<const Node *> &nodes() const { return nodes_; } |
| 179 | |
| 180 | // Returns the node the logger is running on. |
| 181 | const Node *node() const { return node_; } |
Austin Schuh | e46492f | 2021-07-31 19:49:41 -0700 | [diff] [blame] | 182 | const UUID &logger_node_boot_uuid() const { return logger_node_boot_uuid_; } |
| 183 | size_t logger_node_index() const { return logger_node_index_; } |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 184 | |
Austin Schuh | 8c39996 | 2020-12-25 21:51:45 -0800 | [diff] [blame] | 185 | // Writes out the nested Configuration object to the config file location. |
| 186 | virtual void WriteConfiguration( |
| 187 | aos::SizePrefixedFlatbufferDetachedBuffer<LogFileHeader> *header, |
| 188 | std::string_view config_sha256) = 0; |
| 189 | |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 190 | void SetHeaderTemplate( |
| 191 | aos::SizePrefixedFlatbufferDetachedBuffer<LogFileHeader> header) { |
| 192 | header_ = std::move(header); |
Austin Schuh | e46492f | 2021-07-31 19:49:41 -0700 | [diff] [blame] | 193 | logger_node_boot_uuid_ = |
| 194 | UUID::FromString(header_.message().logger_node_boot_uuid()); |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 195 | } |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 196 | |
Austin Schuh | 58646e2 | 2021-08-23 23:51:46 -0700 | [diff] [blame] | 197 | void ClearStartTimes() { |
| 198 | node_states_.clear(); |
| 199 | } |
| 200 | |
| 201 | void SetStartTimes(size_t node_index, const UUID &boot_uuid, |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 202 | monotonic_clock::time_point monotonic_start_time, |
| 203 | realtime_clock::time_point realtime_start_time, |
| 204 | monotonic_clock::time_point logger_monotonic_start_time, |
| 205 | realtime_clock::time_point logger_realtime_start_time) { |
Austin Schuh | 58646e2 | 2021-08-23 23:51:46 -0700 | [diff] [blame] | 206 | VLOG(1) << "Setting node " << node_index << " to start time " |
| 207 | << monotonic_start_time << " rt " << realtime_start_time << " UUID " |
| 208 | << boot_uuid; |
| 209 | NodeState *node_state = GetNodeState(node_index, boot_uuid); |
| 210 | node_state->monotonic_start_time = monotonic_start_time; |
| 211 | node_state->realtime_start_time = realtime_start_time; |
| 212 | node_state->logger_monotonic_start_time = logger_monotonic_start_time; |
| 213 | node_state->logger_realtime_start_time = logger_realtime_start_time; |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 214 | } |
| 215 | |
Austin Schuh | 58646e2 | 2021-08-23 23:51:46 -0700 | [diff] [blame] | 216 | monotonic_clock::time_point monotonic_start_time(size_t node_index, |
| 217 | const UUID &boot_uuid) { |
| 218 | DCHECK_NE(boot_uuid, UUID::Zero()); |
| 219 | |
| 220 | NodeState *node_state = GetNodeState(node_index, boot_uuid); |
| 221 | return node_state->monotonic_start_time; |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 222 | } |
| 223 | |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 224 | protected: |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 225 | // Structure with state per node about times and such. |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 226 | struct NodeState { |
| 227 | // Time when this node started logging. |
| 228 | monotonic_clock::time_point monotonic_start_time = |
| 229 | monotonic_clock::min_time; |
| 230 | realtime_clock::time_point realtime_start_time = realtime_clock::min_time; |
| 231 | |
| 232 | // Corresponding time on the logger node when it started logging. |
| 233 | monotonic_clock::time_point logger_monotonic_start_time = |
| 234 | monotonic_clock::min_time; |
| 235 | realtime_clock::time_point logger_realtime_start_time = |
| 236 | realtime_clock::min_time; |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 237 | }; |
Austin Schuh | 58646e2 | 2021-08-23 23:51:46 -0700 | [diff] [blame] | 238 | |
| 239 | // Creates a new header by copying fields out of the template and combining |
| 240 | // them with the arguments provided. |
| 241 | aos::SizePrefixedFlatbufferDetachedBuffer<LogFileHeader> MakeHeader( |
| 242 | size_t node_index, const std::vector<NewDataWriter::State> &state, |
| 243 | const UUID &parts_uuid, int parts_index); |
| 244 | |
| 245 | EventLoop *event_loop_; |
| 246 | const Configuration *const configuration_; |
| 247 | const Node *const node_; |
| 248 | const size_t logger_node_index_; |
| 249 | UUID logger_node_boot_uuid_; |
| 250 | std::vector<const Node *> nodes_; |
| 251 | |
| 252 | friend NewDataWriter; |
| 253 | |
| 254 | // Returns the start/stop time state structure for a node and boot. We can |
| 255 | // have data from multiple boots, and it makes sense to reuse the start/stop |
| 256 | // times if we get data from the same boot again. |
| 257 | NodeState *GetNodeState(size_t node_index, const UUID &boot_uuid); |
| 258 | |
| 259 | absl::btree_map<std::pair<size_t, UUID>, NodeState> node_states_; |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 260 | |
| 261 | aos::SizePrefixedFlatbufferDetachedBuffer<LogFileHeader> header_ = |
| 262 | aos::SizePrefixedFlatbufferDetachedBuffer<LogFileHeader>::Empty(); |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 263 | }; |
| 264 | |
| 265 | // Local log namer is a simple version which only names things |
| 266 | // "base_name.part#.bfbs" and increments the part number. It doesn't support |
| 267 | // any other log type. |
| 268 | class LocalLogNamer : public LogNamer { |
| 269 | public: |
Austin Schuh | 5b728b7 | 2021-06-16 14:57:15 -0700 | [diff] [blame] | 270 | LocalLogNamer(std::string_view base_name, aos::EventLoop *event_loop, |
| 271 | const aos::Node *node) |
| 272 | : LogNamer(event_loop->configuration(), event_loop, node), |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 273 | base_name_(base_name), |
Austin Schuh | 5b728b7 | 2021-06-16 14:57:15 -0700 | [diff] [blame] | 274 | data_writer_(this, node, |
Austin Schuh | 572924a | 2021-07-30 22:32:12 -0700 | [diff] [blame] | 275 | [this](NewDataWriter *writer) { |
| 276 | writer->writer = std::make_unique<DetachedBufferWriter>( |
| 277 | absl::StrCat(base_name_, ".part", |
| 278 | writer->parts_index(), ".bfbs"), |
| 279 | std::make_unique<aos::logger::DummyEncoder>()); |
| 280 | }, |
| 281 | [](NewDataWriter * /*writer*/) {}) {} |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 282 | |
| 283 | LocalLogNamer(const LocalLogNamer &) = delete; |
| 284 | LocalLogNamer(LocalLogNamer &&) = delete; |
| 285 | LocalLogNamer &operator=(const LocalLogNamer &) = delete; |
| 286 | LocalLogNamer &operator=(LocalLogNamer &&) = delete; |
| 287 | |
Brian Silverman | 0465fcf | 2020-09-24 00:29:18 -0700 | [diff] [blame] | 288 | ~LocalLogNamer() override = default; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 289 | |
Austin Schuh | 6bb8a82 | 2021-03-31 23:04:39 -0700 | [diff] [blame] | 290 | std::string_view base_name() const final { return base_name_; } |
| 291 | |
| 292 | void set_base_name(std::string_view base_name) final { |
| 293 | base_name_ = base_name; |
| 294 | } |
| 295 | |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 296 | NewDataWriter *MakeWriter(const Channel *channel) override; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 297 | |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 298 | void Rotate(const Node *node) override; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 299 | |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 300 | NewDataWriter *MakeTimestampWriter(const Channel *channel) override; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 301 | |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 302 | NewDataWriter *MakeForwardedTimestampWriter(const Channel * /*channel*/, |
| 303 | const Node * /*node*/) override; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 304 | |
Austin Schuh | 8c39996 | 2020-12-25 21:51:45 -0800 | [diff] [blame] | 305 | void WriteConfiguration( |
| 306 | aos::SizePrefixedFlatbufferDetachedBuffer<LogFileHeader> *header, |
| 307 | std::string_view config_sha256) override; |
| 308 | |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 309 | private: |
Austin Schuh | 6bb8a82 | 2021-03-31 23:04:39 -0700 | [diff] [blame] | 310 | std::string base_name_; |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 311 | |
| 312 | NewDataWriter data_writer_; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 313 | }; |
| 314 | |
| 315 | // Log namer which uses a config and a base name to name a bunch of files. |
| 316 | class MultiNodeLogNamer : public LogNamer { |
| 317 | public: |
Austin Schuh | a499cea | 2021-07-31 19:49:53 -0700 | [diff] [blame] | 318 | MultiNodeLogNamer(std::string_view base_name, EventLoop *event_loop); |
Austin Schuh | 5b728b7 | 2021-06-16 14:57:15 -0700 | [diff] [blame] | 319 | MultiNodeLogNamer(std::string_view base_name, |
| 320 | const Configuration *configuration, EventLoop *event_loop, |
| 321 | const Node *node); |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 322 | ~MultiNodeLogNamer() override; |
| 323 | |
Austin Schuh | 6bb8a82 | 2021-03-31 23:04:39 -0700 | [diff] [blame] | 324 | std::string_view base_name() const final { return base_name_; } |
| 325 | |
| 326 | void set_base_name(std::string_view base_name) final { |
| 327 | old_base_name_ = base_name_; |
| 328 | base_name_ = base_name; |
| 329 | } |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 330 | |
Brian Silverman | 48deab1 | 2020-09-30 18:39:28 -0700 | [diff] [blame] | 331 | // If temp_suffix is set, then this will write files under names beginning |
| 332 | // with the specified suffix, and then rename them to the desired name after |
| 333 | // they are fully written. |
| 334 | // |
| 335 | // This is useful to enable incremental copying of the log files. |
| 336 | // |
| 337 | // Defaults to writing directly to the final filename. |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 338 | void set_temp_suffix(std::string_view temp_suffix) { |
| 339 | temp_suffix_ = temp_suffix; |
| 340 | } |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 341 | |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 342 | // Sets the function for creating encoders. |
| 343 | // |
| 344 | // Defaults to just creating DummyEncoders. |
| 345 | void set_encoder_factory( |
| 346 | std::function<std::unique_ptr<DetachedBufferEncoder>()> encoder_factory) { |
| 347 | encoder_factory_ = std::move(encoder_factory); |
| 348 | } |
| 349 | |
| 350 | // Sets an additional file extension. |
| 351 | // |
| 352 | // Defaults to nothing. |
| 353 | void set_extension(std::string_view extension) { extension_ = extension; } |
Brian Silverman | 1f34522 | 2020-09-24 21:14:48 -0700 | [diff] [blame] | 354 | |
Brian Silverman | a621f52 | 2020-09-30 16:52:43 -0700 | [diff] [blame] | 355 | // A list of all the filenames we've written. |
| 356 | // |
| 357 | // This only includes the part after base_name(). |
| 358 | const std::vector<std::string> &all_filenames() const { |
| 359 | return all_filenames_; |
| 360 | } |
| 361 | |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 362 | void Rotate(const Node *node) override; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 363 | |
Austin Schuh | 8c39996 | 2020-12-25 21:51:45 -0800 | [diff] [blame] | 364 | void WriteConfiguration( |
| 365 | aos::SizePrefixedFlatbufferDetachedBuffer<LogFileHeader> *header, |
| 366 | std::string_view config_sha256) override; |
| 367 | |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 368 | NewDataWriter *MakeWriter(const Channel *channel) override; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 369 | |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 370 | NewDataWriter *MakeForwardedTimestampWriter(const Channel *channel, |
Austin Schuh | 7334084 | 2021-07-30 22:32:06 -0700 | [diff] [blame] | 371 | const Node *node) override; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 372 | |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 373 | NewDataWriter *MakeTimestampWriter(const Channel *channel) override; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 374 | |
Brian Silverman | 0465fcf | 2020-09-24 00:29:18 -0700 | [diff] [blame] | 375 | // Indicates that at least one file ran out of space. Once this happens, we |
| 376 | // stop trying to open new files, to avoid writing any files with holes from |
| 377 | // previous parts. |
| 378 | // |
| 379 | // Besides this function, this object will silently stop logging data when |
| 380 | // this occurs. If you want to ensure log files are complete, you must call |
| 381 | // this method. |
Brian Silverman | a9f2ec9 | 2020-10-06 18:00:53 -0700 | [diff] [blame] | 382 | bool ran_out_of_space() const { |
| 383 | return accumulate_data_writers<bool>( |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 384 | ran_out_of_space_, [](bool x, const NewDataWriter &data_writer) { |
Brian Silverman | a9f2ec9 | 2020-10-06 18:00:53 -0700 | [diff] [blame] | 385 | return x || |
| 386 | (data_writer.writer && data_writer.writer->ran_out_of_space()); |
| 387 | }); |
| 388 | } |
Brian Silverman | 0465fcf | 2020-09-24 00:29:18 -0700 | [diff] [blame] | 389 | |
Brian Silverman | 1f34522 | 2020-09-24 21:14:48 -0700 | [diff] [blame] | 390 | // Returns the maximum total_bytes() value for all existing |
| 391 | // DetachedBufferWriters. |
| 392 | // |
| 393 | // Returns 0 if no files are open. |
| 394 | size_t maximum_total_bytes() const { |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 395 | return accumulate_data_writers<size_t>( |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 396 | 0, [](size_t x, const NewDataWriter &data_writer) { |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 397 | return std::max(x, data_writer.writer->total_bytes()); |
| 398 | }); |
Brian Silverman | 1f34522 | 2020-09-24 21:14:48 -0700 | [diff] [blame] | 399 | } |
| 400 | |
Brian Silverman | 0465fcf | 2020-09-24 00:29:18 -0700 | [diff] [blame] | 401 | // Closes all existing log files. No more data may be written after this. |
| 402 | // |
| 403 | // This may set ran_out_of_space(). |
| 404 | void Close(); |
| 405 | |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 406 | // Accessors for various statistics. See the identically-named methods in |
| 407 | // DetachedBufferWriter for documentation. These are aggregated across all |
| 408 | // past and present DetachedBufferWriters. |
| 409 | std::chrono::nanoseconds max_write_time() const { |
| 410 | return accumulate_data_writers( |
| 411 | max_write_time_, |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 412 | [](std::chrono::nanoseconds x, const NewDataWriter &data_writer) { |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 413 | return std::max(x, data_writer.writer->max_write_time()); |
| 414 | }); |
| 415 | } |
| 416 | int max_write_time_bytes() const { |
| 417 | return std::get<0>(accumulate_data_writers( |
| 418 | std::make_tuple(max_write_time_bytes_, max_write_time_), |
| 419 | [](std::tuple<int, std::chrono::nanoseconds> x, |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 420 | const NewDataWriter &data_writer) { |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 421 | if (data_writer.writer->max_write_time() > std::get<1>(x)) { |
| 422 | return std::make_tuple(data_writer.writer->max_write_time_bytes(), |
| 423 | data_writer.writer->max_write_time()); |
| 424 | } |
| 425 | return x; |
| 426 | })); |
| 427 | } |
| 428 | int max_write_time_messages() const { |
| 429 | return std::get<0>(accumulate_data_writers( |
| 430 | std::make_tuple(max_write_time_messages_, max_write_time_), |
| 431 | [](std::tuple<int, std::chrono::nanoseconds> x, |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 432 | const NewDataWriter &data_writer) { |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 433 | if (data_writer.writer->max_write_time() > std::get<1>(x)) { |
| 434 | return std::make_tuple( |
| 435 | data_writer.writer->max_write_time_messages(), |
| 436 | data_writer.writer->max_write_time()); |
| 437 | } |
| 438 | return x; |
| 439 | })); |
| 440 | } |
| 441 | std::chrono::nanoseconds total_write_time() const { |
| 442 | return accumulate_data_writers( |
| 443 | total_write_time_, |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 444 | [](std::chrono::nanoseconds x, const NewDataWriter &data_writer) { |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 445 | return x + data_writer.writer->total_write_time(); |
| 446 | }); |
| 447 | } |
| 448 | int total_write_count() const { |
| 449 | return accumulate_data_writers( |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 450 | total_write_count_, [](int x, const NewDataWriter &data_writer) { |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 451 | return x + data_writer.writer->total_write_count(); |
| 452 | }); |
| 453 | } |
| 454 | int total_write_messages() const { |
| 455 | return accumulate_data_writers( |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 456 | total_write_messages_, [](int x, const NewDataWriter &data_writer) { |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 457 | return x + data_writer.writer->total_write_messages(); |
| 458 | }); |
| 459 | } |
| 460 | int total_write_bytes() const { |
| 461 | return accumulate_data_writers( |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 462 | total_write_bytes_, [](int x, const NewDataWriter &data_writer) { |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 463 | return x + data_writer.writer->total_write_bytes(); |
| 464 | }); |
| 465 | } |
| 466 | |
| 467 | void ResetStatistics(); |
| 468 | |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 469 | private: |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 470 | // Opens up a writer for timestamps forwarded back. |
| 471 | void OpenForwardedTimestampWriter(const Channel *channel, |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 472 | NewDataWriter *data_writer); |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 473 | |
| 474 | // Opens up a writer for remote data. |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 475 | void OpenWriter(const Channel *channel, NewDataWriter *data_writer); |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 476 | |
| 477 | // Opens the main data writer file for this node responsible for data_writer_. |
Brian Silverman | a621f52 | 2020-09-30 16:52:43 -0700 | [diff] [blame] | 478 | void OpenDataWriter(); |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 479 | |
Brian Silverman | a621f52 | 2020-09-30 16:52:43 -0700 | [diff] [blame] | 480 | void CreateBufferWriter(std::string_view path, |
Brian Silverman | 0465fcf | 2020-09-24 00:29:18 -0700 | [diff] [blame] | 481 | std::unique_ptr<DetachedBufferWriter> *destination); |
| 482 | |
Brian Silverman | 48deab1 | 2020-09-30 18:39:28 -0700 | [diff] [blame] | 483 | void RenameTempFile(DetachedBufferWriter *destination); |
| 484 | |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 485 | void CloseWriter(std::unique_ptr<DetachedBufferWriter> *writer_pointer); |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 486 | |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 487 | // A version of std::accumulate which operates over all of our DataWriters. |
| 488 | template <typename T, typename BinaryOperation> |
| 489 | T accumulate_data_writers(T t, BinaryOperation op) const { |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 490 | for (const std::pair<const Channel *const, NewDataWriter> &data_writer : |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 491 | data_writers_) { |
Austin Schuh | ad0cfc3 | 2020-12-21 12:34:26 -0800 | [diff] [blame] | 492 | if (!data_writer.second.writer) continue; |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 493 | t = op(std::move(t), data_writer.second); |
| 494 | } |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 495 | if (data_writer_) { |
| 496 | t = op(std::move(t), *data_writer_); |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 497 | } |
| 498 | return t; |
| 499 | } |
| 500 | |
Austin Schuh | 6bb8a82 | 2021-03-31 23:04:39 -0700 | [diff] [blame] | 501 | std::string base_name_; |
| 502 | std::string old_base_name_; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 503 | |
Brian Silverman | 0465fcf | 2020-09-24 00:29:18 -0700 | [diff] [blame] | 504 | bool ran_out_of_space_ = false; |
Brian Silverman | a621f52 | 2020-09-30 16:52:43 -0700 | [diff] [blame] | 505 | std::vector<std::string> all_filenames_; |
Brian Silverman | 0465fcf | 2020-09-24 00:29:18 -0700 | [diff] [blame] | 506 | |
Brian Silverman | cb80582 | 2020-10-06 17:43:35 -0700 | [diff] [blame] | 507 | std::string temp_suffix_; |
| 508 | std::function<std::unique_ptr<DetachedBufferEncoder>()> encoder_factory_ = |
| 509 | []() { return std::make_unique<DummyEncoder>(); }; |
| 510 | std::string extension_; |
| 511 | |
| 512 | // Storage for statistics from previously-rotated DetachedBufferWriters. |
| 513 | std::chrono::nanoseconds max_write_time_ = std::chrono::nanoseconds::zero(); |
| 514 | int max_write_time_bytes_ = -1; |
| 515 | int max_write_time_messages_ = -1; |
| 516 | std::chrono::nanoseconds total_write_time_ = std::chrono::nanoseconds::zero(); |
| 517 | int total_write_count_ = 0; |
| 518 | int total_write_messages_ = 0; |
| 519 | int total_write_bytes_ = 0; |
| 520 | |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 521 | // File to write both delivery timestamps and local data to. |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 522 | std::unique_ptr<NewDataWriter> data_writer_; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 523 | |
Austin Schuh | b8bca73 | 2021-07-30 22:32:00 -0700 | [diff] [blame] | 524 | std::map<const Channel *, NewDataWriter> data_writers_; |
Austin Schuh | cb5601b | 2020-09-10 15:29:59 -0700 | [diff] [blame] | 525 | }; |
| 526 | |
| 527 | } // namespace logger |
| 528 | } // namespace aos |
| 529 | |
| 530 | #endif // AOS_EVENTS_LOGGING_LOG_NAMER_H_ |