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