Rename VERBOSE log level to TRACE
[babeltrace.git] / src / plugins / ctf / common / metadata / decoder.c
1 /*
2 * Copyright 2016-2017 - Philippe Proulx <pproulx@efficios.com>
3 *
4 * Permission is hereby granted, free of charge, to any person obtaining a copy
5 * of this software and associated documentation files (the "Software"), to deal
6 * in the Software without restriction, including without limitation the rights
7 * to use, copy, modify, merge, publish, distribute, sublicense, and/or sell
8 * copies of the Software, and to permit persons to whom the Software is
9 * furnished to do so, subject to the following conditions:
10 *
11 * The above copyright notice and this permission notice shall be included in
12 * all copies or substantial portions of the Software.
13 */
14
15 #define BT_COMP_LOG_SELF_COMP (mdec->config.self_comp)
16 #define BT_LOG_OUTPUT_LEVEL (mdec->config.log_level)
17 #define BT_LOG_TAG "PLUGIN/CTF/META/DECODER"
18 #include "plugins/comp-logging.h"
19
20 #include <stdio.h>
21 #include <stdbool.h>
22 #include <stdint.h>
23 #include <stdlib.h>
24 #include <inttypes.h>
25 #include "common/assert.h"
26 #include "compat/uuid.h"
27 #include "compat/memstream.h"
28 #include <babeltrace2/babeltrace.h>
29 #include <glib.h>
30 #include <string.h>
31
32 #include "ast.h"
33 #include "decoder.h"
34 #include "scanner.h"
35 #include "logging.h"
36
37 #define TSDL_MAGIC 0x75d11d57
38
39 extern
40 int yydebug;
41
42 struct ctf_metadata_decoder {
43 struct ctf_visitor_generate_ir *visitor;
44 uint8_t uuid[16];
45 bool is_uuid_set;
46 int bo;
47 struct ctf_metadata_decoder_config config;
48 };
49
50 struct packet_header {
51 uint32_t magic;
52 uint8_t uuid[16];
53 uint32_t checksum;
54 uint32_t content_size;
55 uint32_t packet_size;
56 uint8_t compression_scheme;
57 uint8_t encryption_scheme;
58 uint8_t checksum_scheme;
59 uint8_t major;
60 uint8_t minor;
61 } __attribute__((__packed__));
62
63 BT_HIDDEN
64 bool ctf_metadata_decoder_is_packetized(FILE *fp, int *byte_order,
65 bt_logging_level log_level, bt_self_component *self_comp)
66 {
67 uint32_t magic;
68 size_t len;
69 int ret = 0;
70
71 len = fread(&magic, sizeof(magic), 1, fp);
72 if (len != 1) {
73 BT_COMP_LOG_CUR_LVL(BT_LOG_INFO, log_level, self_comp,
74 "Cannot read first metadata packet header: assuming the stream is not packetized.");
75 goto end;
76 }
77
78 if (byte_order) {
79 if (magic == TSDL_MAGIC) {
80 ret = 1;
81 *byte_order = BYTE_ORDER;
82 } else if (magic == GUINT32_SWAP_LE_BE(TSDL_MAGIC)) {
83 ret = 1;
84 *byte_order = BYTE_ORDER == BIG_ENDIAN ?
85 LITTLE_ENDIAN : BIG_ENDIAN;
86 }
87 }
88
89 end:
90 rewind(fp);
91
92 return ret;
93 }
94
95 static
96 bool is_version_valid(unsigned int major, unsigned int minor)
97 {
98 return major == 1 && minor == 8;
99 }
100
101 static
102 int decode_packet(struct ctf_metadata_decoder *mdec, FILE *in_fp, FILE *out_fp,
103 int byte_order)
104 {
105 struct packet_header header;
106 size_t readlen, writelen, toread;
107 uint8_t buf[512 + 1]; /* + 1 for debug-mode \0 */
108 int ret = 0;
109 const long offset = ftell(in_fp);
110
111 if (offset < 0) {
112 BT_COMP_LOGE_ERRNO("Failed to get current metadata file position",
113 ".");
114 goto error;
115 }
116 BT_COMP_LOGD("Decoding metadata packet: mdec-addr=%p, offset=%ld",
117 mdec, offset);
118 readlen = fread(&header, sizeof(header), 1, in_fp);
119 if (feof(in_fp) != 0) {
120 BT_COMP_LOGI("Reached end of file: offset=%ld", ftell(in_fp));
121 goto end;
122 }
123 if (readlen < 1) {
124 BT_COMP_LOGE("Cannot decode metadata packet: offset=%ld", offset);
125 goto error;
126 }
127
128 if (byte_order != BYTE_ORDER) {
129 header.magic = GUINT32_SWAP_LE_BE(header.magic);
130 header.checksum = GUINT32_SWAP_LE_BE(header.checksum);
131 header.content_size = GUINT32_SWAP_LE_BE(header.content_size);
132 header.packet_size = GUINT32_SWAP_LE_BE(header.packet_size);
133 }
134
135 if (header.compression_scheme) {
136 BT_COMP_LOGE("Metadata packet compression is not supported as of this version: "
137 "compression-scheme=%u, offset=%ld",
138 (unsigned int) header.compression_scheme, offset);
139 goto error;
140 }
141
142 if (header.encryption_scheme) {
143 BT_COMP_LOGE("Metadata packet encryption is not supported as of this version: "
144 "encryption-scheme=%u, offset=%ld",
145 (unsigned int) header.encryption_scheme, offset);
146 goto error;
147 }
148
149 if (header.checksum || header.checksum_scheme) {
150 BT_COMP_LOGE("Metadata packet checksum verification is not supported as of this version: "
151 "checksum-scheme=%u, checksum=%x, offset=%ld",
152 (unsigned int) header.checksum_scheme, header.checksum,
153 offset);
154 goto error;
155 }
156
157 if (!is_version_valid(header.major, header.minor)) {
158 BT_COMP_LOGE("Invalid metadata packet version: "
159 "version=%u.%u, offset=%ld",
160 header.major, header.minor, offset);
161 goto error;
162 }
163
164 /* Set expected trace UUID if not set; otherwise validate it */
165 if (mdec) {
166 if (!mdec->is_uuid_set) {
167 memcpy(mdec->uuid, header.uuid, sizeof(header.uuid));
168 mdec->is_uuid_set = true;
169 } else if (bt_uuid_compare(header.uuid, mdec->uuid)) {
170 BT_COMP_LOGE("Metadata UUID mismatch between packets of the same stream: "
171 "packet-uuid=\"%02x%02x%02x%02x-%02x%02x-%02x%02x-%02x%02x-%02x%02x%02x%02x%02x%02x\", "
172 "expected-uuid=\"%02x%02x%02x%02x-%02x%02x-%02x%02x-%02x%02x-%02x%02x%02x%02x%02x%02x\", "
173 "offset=%ld",
174 (unsigned int) header.uuid[0],
175 (unsigned int) header.uuid[1],
176 (unsigned int) header.uuid[2],
177 (unsigned int) header.uuid[3],
178 (unsigned int) header.uuid[4],
179 (unsigned int) header.uuid[5],
180 (unsigned int) header.uuid[6],
181 (unsigned int) header.uuid[7],
182 (unsigned int) header.uuid[8],
183 (unsigned int) header.uuid[9],
184 (unsigned int) header.uuid[10],
185 (unsigned int) header.uuid[11],
186 (unsigned int) header.uuid[12],
187 (unsigned int) header.uuid[13],
188 (unsigned int) header.uuid[14],
189 (unsigned int) header.uuid[15],
190 (unsigned int) mdec->uuid[0],
191 (unsigned int) mdec->uuid[1],
192 (unsigned int) mdec->uuid[2],
193 (unsigned int) mdec->uuid[3],
194 (unsigned int) mdec->uuid[4],
195 (unsigned int) mdec->uuid[5],
196 (unsigned int) mdec->uuid[6],
197 (unsigned int) mdec->uuid[7],
198 (unsigned int) mdec->uuid[8],
199 (unsigned int) mdec->uuid[9],
200 (unsigned int) mdec->uuid[10],
201 (unsigned int) mdec->uuid[11],
202 (unsigned int) mdec->uuid[12],
203 (unsigned int) mdec->uuid[13],
204 (unsigned int) mdec->uuid[14],
205 (unsigned int) mdec->uuid[15],
206 offset);
207 goto error;
208 }
209 }
210
211 if ((header.content_size / CHAR_BIT) < sizeof(header)) {
212 BT_COMP_LOGE("Bad metadata packet content size: content-size=%u, "
213 "offset=%ld", header.content_size, offset);
214 goto error;
215 }
216
217 toread = header.content_size / CHAR_BIT - sizeof(header);
218
219 for (;;) {
220 size_t loop_read;
221
222 loop_read = MIN(sizeof(buf) - 1, toread);
223 readlen = fread(buf, sizeof(uint8_t), loop_read, in_fp);
224 if (ferror(in_fp)) {
225 BT_COMP_LOGE("Cannot read metadata packet buffer: "
226 "offset=%ld, read-size=%zu",
227 ftell(in_fp), loop_read);
228 goto error;
229 }
230 if (readlen > loop_read) {
231 BT_COMP_LOGE("fread returned more byte than expected: "
232 "read-size-asked=%zu, read-size-returned=%zu",
233 loop_read, readlen);
234 goto error;
235 }
236
237 writelen = fwrite(buf, sizeof(uint8_t), readlen, out_fp);
238 if (writelen < readlen || ferror(out_fp)) {
239 BT_COMP_LOGE("Cannot write decoded metadata text to buffer: "
240 "read-offset=%ld, write-size=%zu",
241 ftell(in_fp), readlen);
242 goto error;
243 }
244
245 toread -= readlen;
246 if (toread == 0) {
247 int fseek_ret;
248
249 /* Read leftover padding */
250 toread = (header.packet_size - header.content_size) /
251 CHAR_BIT;
252 fseek_ret = fseek(in_fp, toread, SEEK_CUR);
253 if (fseek_ret < 0) {
254 BT_COMP_LOGW_STR("Missing padding at the end of the metadata stream.");
255 }
256 break;
257 }
258 }
259
260 goto end;
261
262 error:
263 ret = -1;
264
265 end:
266 return ret;
267 }
268
269 static
270 int ctf_metadata_decoder_packetized_file_stream_to_buf_with_mdec(
271 struct ctf_metadata_decoder *mdec, FILE *fp,
272 char **buf, int byte_order, bt_logging_level log_level,
273 bt_self_component *self_comp)
274 {
275 FILE *out_fp;
276 size_t size;
277 int ret = 0;
278 int tret;
279 size_t packet_index = 0;
280
281 out_fp = bt_open_memstream(buf, &size);
282 if (out_fp == NULL) {
283 BT_COMP_LOG_CUR_LVL(BT_LOG_ERROR, log_level, self_comp,
284 "Cannot open memory stream: %s: mdec-addr=%p",
285 strerror(errno), mdec);
286 goto error;
287 }
288
289 for (;;) {
290 if (feof(fp) != 0) {
291 break;
292 }
293
294 tret = decode_packet(mdec, fp, out_fp, byte_order);
295 if (tret) {
296 BT_COMP_LOG_CUR_LVL(BT_LOG_ERROR, log_level, self_comp,
297 "Cannot decode packet: index=%zu, mdec-addr=%p",
298 packet_index, mdec);
299 goto error;
300 }
301
302 packet_index++;
303 }
304
305 /* Make sure the whole string ends with a null character */
306 tret = fputc('\0', out_fp);
307 if (tret == EOF) {
308 BT_COMP_LOG_CUR_LVL(BT_LOG_ERROR, log_level, self_comp,
309 "Cannot append '\\0' to the decoded metadata buffer: "
310 "mdec-addr=%p", mdec);
311 goto error;
312 }
313
314 /* Close stream, which also flushes the buffer */
315 ret = bt_close_memstream(buf, &size, out_fp);
316 /*
317 * See fclose(3). Further access to out_fp after both success
318 * and error, even through another bt_close_memstream(), results
319 * in undefined behavior. Nullify out_fp to ensure we don't
320 * fclose it twice on error.
321 */
322 out_fp = NULL;
323 if (ret < 0) {
324 BT_COMP_LOG_ERRNO_CUR_LVL(BT_LOG_ERROR, log_level, self_comp,
325 "Cannot close memory stream", ": mdec-addr=%p", mdec);
326 goto error;
327 }
328
329 goto end;
330
331 error:
332 ret = -1;
333
334 if (out_fp) {
335 if (bt_close_memstream(buf, &size, out_fp)) {
336 BT_COMP_LOG_ERRNO_CUR_LVL(BT_LOG_ERROR, log_level,
337 self_comp, "Cannot close memory stream",
338 ": mdec-addr=%p", mdec);
339 }
340 }
341
342 if (*buf) {
343 free(*buf);
344 *buf = NULL;
345 }
346
347 end:
348 return ret;
349 }
350
351 BT_HIDDEN
352 int ctf_metadata_decoder_packetized_file_stream_to_buf(
353 FILE *fp, char **buf, int byte_order,
354 bt_logging_level log_level,
355 bt_self_component *self_comp)
356 {
357 return ctf_metadata_decoder_packetized_file_stream_to_buf_with_mdec(
358 NULL, fp, buf, byte_order, log_level, self_comp);
359 }
360
361 BT_HIDDEN
362 struct ctf_metadata_decoder *ctf_metadata_decoder_create(
363 const struct ctf_metadata_decoder_config *config)
364 {
365 struct ctf_metadata_decoder *mdec =
366 g_new0(struct ctf_metadata_decoder, 1);
367
368 BT_ASSERT(config);
369 BT_COMP_LOG_CUR_LVL(BT_LOG_DEBUG, config->log_level, config->self_comp,
370 "Creating CTF metadata decoder: "
371 "clock-class-offset-s=%" PRId64 ", "
372 "clock-class-offset-ns=%" PRId64,
373 config->clock_class_offset_s, config->clock_class_offset_ns);
374
375 if (!mdec) {
376 BT_COMP_LOG_CUR_LVL(BT_LOG_ERROR, config->log_level,
377 config->self_comp,
378 "Failed to allocate one CTF metadata decoder.");
379 goto end;
380 }
381
382 mdec->config = *config;
383 mdec->visitor = ctf_visitor_generate_ir_create(config);
384 if (!mdec->visitor) {
385 BT_COMP_LOGE("Failed to create a CTF IR metadata AST visitor: "
386 "mdec-addr=%p", mdec);
387 ctf_metadata_decoder_destroy(mdec);
388 mdec = NULL;
389 goto end;
390 }
391
392 BT_COMP_LOGD("Creating CTF metadata decoder: "
393 "clock-class-offset-s=%" PRId64 ", "
394 "clock-class-offset-ns=%" PRId64 ", addr=%p",
395 config->clock_class_offset_s, config->clock_class_offset_ns,
396 mdec);
397
398 end:
399 return mdec;
400 }
401
402 BT_HIDDEN
403 void ctf_metadata_decoder_destroy(struct ctf_metadata_decoder *mdec)
404 {
405 if (!mdec) {
406 return;
407 }
408
409 BT_COMP_LOGD("Destroying CTF metadata decoder: addr=%p", mdec);
410 ctf_visitor_generate_ir_destroy(mdec->visitor);
411 g_free(mdec);
412 }
413
414 BT_HIDDEN
415 enum ctf_metadata_decoder_status ctf_metadata_decoder_decode(
416 struct ctf_metadata_decoder *mdec, FILE *fp)
417 {
418 enum ctf_metadata_decoder_status status =
419 CTF_METADATA_DECODER_STATUS_OK;
420 int ret;
421 struct ctf_scanner *scanner = NULL;
422 char *buf = NULL;
423 bool close_fp = false;
424 struct meta_log_config log_cfg;
425
426 BT_ASSERT(mdec);
427 log_cfg.log_level = mdec->config.log_level;
428 log_cfg.self_comp = mdec->config.self_comp;
429
430 if (ctf_metadata_decoder_is_packetized(fp, &mdec->bo,
431 mdec->config.log_level, mdec->config.self_comp)) {
432 BT_COMP_LOGI("Metadata stream is packetized: mdec-addr=%p", mdec);
433 ret = ctf_metadata_decoder_packetized_file_stream_to_buf_with_mdec(
434 mdec, fp, &buf, mdec->bo, mdec->config.log_level,
435 mdec->config.self_comp);
436 if (ret) {
437 BT_COMP_LOGE("Cannot decode packetized metadata packets to metadata text: "
438 "mdec-addr=%p, ret=%d", mdec, ret);
439 status = CTF_METADATA_DECODER_STATUS_ERROR;
440 goto end;
441 }
442
443 if (strlen(buf) == 0) {
444 /* An empty metadata packet is OK. */
445 goto end;
446 }
447
448 /* Convert the real file pointer to a memory file pointer */
449 fp = bt_fmemopen(buf, strlen(buf), "rb");
450 close_fp = true;
451 if (!fp) {
452 BT_COMP_LOGE("Cannot memory-open metadata buffer: %s: "
453 "mdec-addr=%p", strerror(errno), mdec);
454 status = CTF_METADATA_DECODER_STATUS_ERROR;
455 goto end;
456 }
457 } else {
458 unsigned int major, minor;
459 ssize_t nr_items;
460 const long init_pos = ftell(fp);
461
462 BT_COMP_LOGI("Metadata stream is plain text: mdec-addr=%p", mdec);
463
464 if (init_pos < 0) {
465 BT_COMP_LOGE_ERRNO("Failed to get current file position", ".");
466 status = CTF_METADATA_DECODER_STATUS_ERROR;
467 goto end;
468 }
469
470 /* Check text-only metadata header and version */
471 nr_items = fscanf(fp, "/* CTF %10u.%10u", &major, &minor);
472 if (nr_items < 2) {
473 BT_COMP_LOGW("Missing \"/* CTF major.minor\" signature in plain text metadata file stream: "
474 "mdec-addr=%p", mdec);
475 }
476
477 BT_COMP_LOGI("Found metadata stream version in signature: version=%u.%u", major, minor);
478
479 if (!is_version_valid(major, minor)) {
480 BT_COMP_LOGE("Invalid metadata version found in plain text signature: "
481 "version=%u.%u, mdec-addr=%p", major, minor,
482 mdec);
483 status = CTF_METADATA_DECODER_STATUS_INVAL_VERSION;
484 goto end;
485 }
486
487 if (fseek(fp, init_pos, SEEK_SET)) {
488 BT_COMP_LOGE("Cannot seek metadata file stream to initial position: %s: "
489 "mdec-addr=%p", strerror(errno), mdec);
490 status = CTF_METADATA_DECODER_STATUS_ERROR;
491 goto end;
492 }
493 }
494
495 if (BT_LOG_ON_TRACE) {
496 yydebug = 1;
497 }
498
499 /* Allocate a scanner and append the metadata text content */
500 scanner = ctf_scanner_alloc();
501 if (!scanner) {
502 BT_COMP_LOGE("Cannot allocate a metadata lexical scanner: "
503 "mdec-addr=%p", mdec);
504 status = CTF_METADATA_DECODER_STATUS_ERROR;
505 goto end;
506 }
507
508 BT_ASSERT(fp);
509 ret = ctf_scanner_append_ast(scanner, fp);
510 if (ret) {
511 BT_COMP_LOGE("Cannot create the metadata AST out of the metadata text: "
512 "mdec-addr=%p", mdec);
513 status = CTF_METADATA_DECODER_STATUS_INCOMPLETE;
514 goto end;
515 }
516
517 ret = ctf_visitor_semantic_check(0, &scanner->ast->root, &log_cfg);
518 if (ret) {
519 BT_COMP_LOGE("Validation of the metadata semantics failed: "
520 "mdec-addr=%p", mdec);
521 status = CTF_METADATA_DECODER_STATUS_ERROR;
522 goto end;
523 }
524
525 ret = ctf_visitor_generate_ir_visit_node(mdec->visitor,
526 &scanner->ast->root);
527 switch (ret) {
528 case 0:
529 /* Success */
530 break;
531 case -EINCOMPLETE:
532 BT_COMP_LOGD("While visiting metadata AST: incomplete data: "
533 "mdec-addr=%p", mdec);
534 status = CTF_METADATA_DECODER_STATUS_INCOMPLETE;
535 goto end;
536 default:
537 BT_COMP_LOGE("Failed to visit AST node to create CTF IR objects: "
538 "mdec-addr=%p, ret=%d", mdec, ret);
539 status = CTF_METADATA_DECODER_STATUS_IR_VISITOR_ERROR;
540 goto end;
541 }
542
543 end:
544 if (scanner) {
545 ctf_scanner_free(scanner);
546 }
547
548 yydebug = 0;
549
550 if (fp && close_fp) {
551 if (fclose(fp)) {
552 BT_COMP_LOGE("Cannot close metadata file stream: "
553 "mdec-addr=%p", mdec);
554 }
555 }
556
557 if (buf) {
558 free(buf);
559 }
560
561 return status;
562 }
563
564 BT_HIDDEN
565 bt_trace_class *ctf_metadata_decoder_get_ir_trace_class(
566 struct ctf_metadata_decoder *mdec)
567 {
568 return ctf_visitor_generate_ir_get_ir_trace_class(mdec->visitor);
569 }
570
571 BT_HIDDEN
572 struct ctf_trace_class *ctf_metadata_decoder_borrow_ctf_trace_class(
573 struct ctf_metadata_decoder *mdec)
574 {
575 return ctf_visitor_generate_ir_borrow_ctf_trace_class(mdec->visitor);
576 }
This page took 0.040764 seconds and 4 git commands to generate.