* SOFTWARE.
*/
+#define BT_LOG_TAG "PLUGIN-CTF-NOTIF-ITER"
+#include "logging.h"
+
#include <stdint.h>
#include <inttypes.h>
#include <stdio.h>
#include <assert.h>
#include <string.h>
#include <babeltrace/ctf-ir/field-types.h>
+#include <babeltrace/ctf-ir/field-types-internal.h>
#include <babeltrace/ctf-ir/field-path.h>
+#include <babeltrace/ctf-ir/field-path-internal.h>
#include <babeltrace/ctf-ir/fields.h>
#include <babeltrace/ctf-ir/stream-class.h>
#include <babeltrace/ctf-ir/packet.h>
#include <glib.h>
#include <stdlib.h>
-#define PRINT_ERR_STREAM (notit ? notit->err_stream : stderr)
-#define PRINT_PREFIX "ctf-notif-iter"
-#include "../print.h"
-
#include "notif-iter.h"
#include "../btr/btr.h"
-#define BYTES_TO_BITS(x) ((x) * 8)
-
struct bt_ctf_notif_iter;
/* A visit stack entry */
GHashTable *sc_field_path_caches;
};
+static inline
+const char *state_string(enum state state)
+{
+ switch (state) {
+ case STATE_INIT:
+ return "STATE_INIT";
+ case STATE_DSCOPE_TRACE_PACKET_HEADER_BEGIN:
+ return "STATE_DSCOPE_TRACE_PACKET_HEADER_BEGIN";
+ case STATE_DSCOPE_TRACE_PACKET_HEADER_CONTINUE:
+ return "STATE_DSCOPE_TRACE_PACKET_HEADER_CONTINUE";
+ case STATE_AFTER_TRACE_PACKET_HEADER:
+ return "STATE_AFTER_TRACE_PACKET_HEADER";
+ case STATE_DSCOPE_STREAM_PACKET_CONTEXT_BEGIN:
+ return "STATE_DSCOPE_STREAM_PACKET_CONTEXT_BEGIN";
+ case STATE_DSCOPE_STREAM_PACKET_CONTEXT_CONTINUE:
+ return "STATE_DSCOPE_STREAM_PACKET_CONTEXT_CONTINUE";
+ case STATE_AFTER_STREAM_PACKET_CONTEXT:
+ return "STATE_AFTER_STREAM_PACKET_CONTEXT";
+ case STATE_EMIT_NOTIF_NEW_PACKET:
+ return "STATE_EMIT_NOTIF_NEW_PACKET";
+ case STATE_DSCOPE_STREAM_EVENT_HEADER_BEGIN:
+ return "STATE_DSCOPE_STREAM_EVENT_HEADER_BEGIN";
+ case STATE_DSCOPE_STREAM_EVENT_HEADER_CONTINUE:
+ return "STATE_DSCOPE_STREAM_EVENT_HEADER_CONTINUE";
+ case STATE_AFTER_STREAM_EVENT_HEADER:
+ return "STATE_AFTER_STREAM_EVENT_HEADER";
+ case STATE_DSCOPE_STREAM_EVENT_CONTEXT_BEGIN:
+ return "STATE_DSCOPE_STREAM_EVENT_CONTEXT_BEGIN";
+ case STATE_DSCOPE_STREAM_EVENT_CONTEXT_CONTINUE:
+ return "STATE_DSCOPE_STREAM_EVENT_CONTEXT_CONTINUE";
+ case STATE_DSCOPE_EVENT_CONTEXT_BEGIN:
+ return "STATE_DSCOPE_EVENT_CONTEXT_BEGIN";
+ case STATE_DSCOPE_EVENT_CONTEXT_CONTINUE:
+ return "STATE_DSCOPE_EVENT_CONTEXT_CONTINUE";
+ case STATE_DSCOPE_EVENT_PAYLOAD_BEGIN:
+ return "STATE_DSCOPE_EVENT_PAYLOAD_BEGIN";
+ case STATE_DSCOPE_EVENT_PAYLOAD_CONTINUE:
+ return "STATE_DSCOPE_EVENT_PAYLOAD_CONTINUE";
+ case STATE_EMIT_NOTIF_EVENT:
+ return "STATE_EMIT_NOTIF_EVENT";
+ case STATE_EMIT_NOTIF_END_OF_PACKET:
+ return "STATE_EMIT_NOTIF_END_OF_PACKET";
+ case STATE_SKIP_PACKET_PADDING:
+ return "STATE_SKIP_PACKET_PADDING";
+ default:
+ return "(unknown)";
+ }
+}
+
static
int bt_ctf_notif_iter_switch_packet(struct bt_ctf_notif_iter *notit);
stack = g_new0(struct stack, 1);
if (!stack) {
+ BT_LOGE_STR("Failed to allocate one stack.");
goto error;
}
stack->entries = g_ptr_array_new_with_free_func(stack_entry_free_func);
if (!stack->entries) {
+ BT_LOGE_STR("Failed to allocate a GPtrArray.");
goto error;
}
+ BT_LOGD("Created stack: notit-addr=%p, stack-addr=%p", notit, stack);
return stack;
+
error:
g_free(stack);
return NULL;
void stack_destroy(struct stack *stack)
{
assert(stack);
+ BT_LOGD("Destroying stack: addr=%p", stack);
g_ptr_array_free(stack->entries, TRUE);
g_free(stack);
}
assert(stack);
assert(base);
+ BT_LOGV("Pushing base field on stack: stack-addr=%p, "
+ "stack-size-before=%u, stack-size-after=%u",
+ stack, stack->entries->len, stack->entries->len + 1);
entry = g_new0(struct stack_entry, 1);
if (!entry) {
+ BT_LOGE_STR("Failed to allocate one stack entry.");
ret = -1;
goto end;
}
{
assert(stack);
assert(stack_size(stack));
+ BT_LOGV("Popping from stack: "
+ "stack-addr=%p, stack-size-before=%u, stack-size-after=%u",
+ stack, stack->entries->len, stack->entries->len - 1);
g_ptr_array_remove_index(stack->entries, stack->entries->len - 1);
}
assert(stack);
if (!stack_empty(stack)) {
+ BT_LOGV("Clearing stack: stack-addr=%p, stack-size=%u",
+ stack, stack->entries->len);
g_ptr_array_remove_range(stack->entries, 0, stack_size(stack));
}
static inline
size_t buf_size_bits(struct bt_ctf_notif_iter *notit)
{
- return BYTES_TO_BITS(notit->buf.sz);
+ return notit->buf.sz * 8;
}
static inline
static inline
void buf_consume_bits(struct bt_ctf_notif_iter *notit, size_t incr)
{
+ BT_LOGV("Advancing cursor: notit-addr=%p, cur-before=%zu, cur-after=%zu",
+ notit, notit->buf.at, notit->buf.at + incr);
notit->buf.at += incr;
}
enum bt_ctf_notif_iter_status request_medium_bytes(
struct bt_ctf_notif_iter *notit)
{
- uint8_t *buffer_addr;
- size_t buffer_sz;
+ uint8_t *buffer_addr = NULL;
+ size_t buffer_sz = 0;
enum bt_ctf_notif_iter_medium_status m_status;
+ BT_LOGV("Calling user function (request bytes): notit-addr=%p, "
+ "request-size=%zu", notit, notit->medium.max_request_sz);
m_status = notit->medium.medops.request_bytes(
notit->medium.max_request_sz, &buffer_addr,
&buffer_sz, notit->medium.data);
+ BT_LOGV("User function returned: status=%s, buf-addr=%p, buf-size=%zu",
+ bt_ctf_notif_iter_medium_status_string(m_status),
+ buffer_addr, buffer_sz);
if (m_status == BT_CTF_NOTIF_ITER_MEDIUM_STATUS_OK) {
assert(buffer_sz != 0);
/* New medium buffer address */
notit->buf.addr = buffer_addr;
+
+ BT_LOGV("User function returned new bytes: "
+ "packet-offset=%zu, cur=%zu, size=%zu, addr=%p",
+ notit->buf.packet_offset, notit->buf.at,
+ notit->buf.sz, notit->buf.addr);
+ BT_LOGV_MEM(buffer_addr, buffer_sz, "Returned bytes at %p:",
+ buffer_addr);
+ } else if (m_status < 0) {
+ BT_LOGW("User function failed: status=%s",
+ bt_ctf_notif_iter_medium_status_string(m_status));
}
return notif_iter_status_from_m_status(m_status);
status = buf_ensure_available_bits(notit);
if (status != BT_CTF_NOTIF_ITER_STATUS_OK) {
+ if (status < 0) {
+ BT_LOGW("Cannot ensure that buffer has at least one byte: "
+ "notif-addr=%p, status=%s",
+ notit, bt_ctf_notif_iter_status_string(status));
+ } else {
+ BT_LOGV("Cannot ensure that buffer has at least one byte: "
+ "notif-addr=%p, status=%s",
+ notit, bt_ctf_notif_iter_status_string(status));
+ }
+
goto end;
}
bt_put(*dscope_field);
notit->cur_dscope_field = dscope_field;
+ BT_LOGV("Starting BTR: notit-addr=%p, btr-addr=%p, ft-addr=%p",
+ notit, notit->btr, dscope_field_type);
consumed_bits = bt_ctf_btr_start(notit->btr, dscope_field_type,
notit->buf.addr, notit->buf.at, packet_at(notit),
notit->buf.sz, &btr_status);
+ BT_LOGV("BTR consumed bits: size=%zu", consumed_bits);
switch (btr_status) {
case BT_CTF_BTR_STATUS_OK:
/* type was read completely */
+ BT_LOGV_STR("Field was completely decoded.");
notit->state = done_state;
break;
case BT_CTF_BTR_STATUS_EOF:
+ BT_LOGV_STR("BTR needs more data to decode field completely.");
notit->state = continue_state;
break;
default:
- PERR("Binary type reader failed to start\n");
+ BT_LOGW("BTR failed to start: notit-addr=%p, btr-addr=%p, "
+ "status=%s", notit, notit->btr,
+ bt_ctf_btr_status_string(btr_status));
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
}
status = buf_ensure_available_bits(notit);
if (status != BT_CTF_NOTIF_ITER_STATUS_OK) {
+ if (status < 0) {
+ BT_LOGW("Cannot ensure that buffer has at least one byte: "
+ "notif-addr=%p, status=%s",
+ notit, bt_ctf_notif_iter_status_string(status));
+ } else {
+ BT_LOGV("Cannot ensure that buffer has at least one byte: "
+ "notif-addr=%p, status=%s",
+ notit, bt_ctf_notif_iter_status_string(status));
+ }
+
goto end;
}
+ BT_LOGV("Continuing BTR: notit-addr=%p, btr-addr=%p",
+ notit, notit->btr);
consumed_bits = bt_ctf_btr_continue(notit->btr, notit->buf.addr,
- notit->buf.sz, &btr_status);
+ notit->buf.sz, &btr_status);
+ BT_LOGV("BTR consumed bits: size=%zu", consumed_bits);
switch (btr_status) {
case BT_CTF_BTR_STATUS_OK:
/* Type was read completely. */
+ BT_LOGV_STR("Field was completely decoded.");
notit->state = done_state;
break;
case BT_CTF_BTR_STATUS_EOF:
/* Stay in this continue state. */
+ BT_LOGV_STR("BTR needs more data to decode field completely.");
break;
default:
- PERR("Binary type reader failed to continue\n");
+ BT_LOGW("BTR failed to continue: notit-addr=%p, btr-addr=%p, "
+ "status=%s", notit, notit->btr,
+ bt_ctf_btr_status_string(btr_status));
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
}
static
void put_event_dscopes(struct bt_ctf_notif_iter *notit)
{
+ BT_LOGV_STR("Putting event header field.");
BT_PUT(notit->dscopes.stream_event_header);
+ BT_LOGV_STR("Putting stream event context field.");
BT_PUT(notit->dscopes.stream_event_context);
+ BT_LOGV_STR("Putting event context field.");
BT_PUT(notit->dscopes.event_context);
+ BT_LOGV_STR("Putting event payload field.");
BT_PUT(notit->dscopes.event_payload);
}
static
void put_all_dscopes(struct bt_ctf_notif_iter *notit)
{
+ BT_LOGV_STR("Putting packet header field.");
BT_PUT(notit->dscopes.trace_packet_header);
+ BT_LOGV_STR("Putting packet context field.");
BT_PUT(notit->dscopes.stream_packet_context);
put_event_dscopes(notit);
}
enum bt_ctf_notif_iter_status ret = BT_CTF_NOTIF_ITER_STATUS_OK;
if (bt_ctf_notif_iter_switch_packet(notit)) {
+ BT_LOGW("Cannot switch packet: notit-addr=%p", notit);
ret = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
}
goto end;
}
+ BT_LOGV("Decoding packet header field:"
+ "notit-addr=%p, trace-addr=%p, trace-name=\"%s\", ft-addr=%p",
+ notit, notit->meta.trace,
+ bt_ctf_trace_get_name(notit->meta.trace), packet_header_type);
ret = read_dscope_begin_state(notit, packet_header_type,
- STATE_AFTER_TRACE_PACKET_HEADER,
- STATE_DSCOPE_TRACE_PACKET_HEADER_CONTINUE,
- ¬it->dscopes.trace_packet_header);
+ STATE_AFTER_TRACE_PACKET_HEADER,
+ STATE_DSCOPE_TRACE_PACKET_HEADER_CONTINUE,
+ ¬it->dscopes.trace_packet_header);
+ if (ret < 0) {
+ BT_LOGW("Cannot decode packet header field: "
+ "notit-addr=%p, trace-addr=%p, "
+ "trace-name=\"%s\", ft-addr=%p",
+ notit, notit->meta.trace,
+ bt_ctf_trace_get_name(notit->meta.trace),
+ packet_header_type);
+ }
end:
BT_PUT(packet_header_type);
return ret;
struct bt_ctf_field_type *event_header = NULL, *packet_context = NULL;
if (!cache_entry) {
+ BT_LOGE_STR("Failed to allocate one stream class field path cache.");
goto end;
}
int i, count;
count = bt_ctf_field_type_structure_get_field_count(
- event_header);
- if (count < 0) {
- goto error;
- }
+ event_header);
+ assert(count >= 0);
+
for (i = 0; i < count; i++) {
int ret;
const char *name;
ret = bt_ctf_field_type_structure_get_field(
event_header, &name, NULL, i);
if (ret) {
+ BT_LOGE("Cannot get event header structure field type's field: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64 ", "
+ "ft-addr=%p, index=%d",
+ notit, stream_class,
+ bt_ctf_stream_class_get_name(stream_class),
+ bt_ctf_stream_class_get_id(stream_class),
+ event_header, i);
goto error;
}
if (v != -1 && id != -1) {
break;
}
- if (v == -1 && !strcmp(name, "v")) {
+
+ if (v == -1 && strcmp(name, "v") == 0) {
v = i;
} else if (id == -1 && !strcmp(name, "id")) {
id = i;
int i, count;
count = bt_ctf_field_type_structure_get_field_count(
- packet_context);
- if (count < 0) {
- goto error;
- }
+ packet_context);
+ assert(count >= 0);
+
for (i = 0; i < count; i++) {
int ret;
const char *name;
ret = bt_ctf_field_type_structure_get_field(
packet_context, &name, &field_type, i);
if (ret) {
+ BT_LOGE("Cannot get packet context structure field type's field: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64 ", "
+ "ft-addr=%p, index=%d",
+ notit, stream_class,
+ bt_ctf_stream_class_get_name(stream_class),
+ bt_ctf_stream_class_get_id(stream_class),
+ event_header, i);
goto error;
}
if (timestamp_end == -1 &&
- !strcmp(name, "timestamp_end")) {
+ strcmp(name, "timestamp_end") == 0) {
struct field_cb_override *override = g_new0(
struct field_cb_override, 1);
override->func = btr_timestamp_end_cb;
override->data = notit;
-
g_hash_table_insert(notit->field_overrides,
- bt_get(field_type), override);
-
+ bt_get(field_type), override);
timestamp_end = i;
} else if (packet_size == -1 &&
!strcmp(name, "packet_size")) {
stream_class, NULL, (gpointer) &cache_entry);
if (unlikely(!cache_entry_found)) {
cache_entry = create_stream_class_field_path_cache_entry(notit,
- stream_class);
+ stream_class);
g_hash_table_insert(notit->sc_field_path_caches,
- bt_get(stream_class), (gpointer) cache_entry);
+ bt_get(stream_class), (gpointer) cache_entry);
}
return cache_entry;
/* Is there any "stream_id" field in the packet header? */
packet_header_type = bt_ctf_trace_get_packet_header_type(
- notit->meta.trace);
+ notit->meta.trace);
if (!packet_header_type) {
- PERR("Failed to retrieve trace's packet header type\n");
- status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
- goto end;
+ /*
+ * No packet header, therefore no `stream_id` field,
+ * therefore only one stream class.
+ */
+ goto single_stream_class;
}
assert(is_struct_type(packet_header_type));
// TODO: optimalize!
stream_id_field_type =
- bt_ctf_field_type_structure_get_field_type_by_name(
- packet_header_type, "stream_id");
+ bt_ctf_field_type_structure_get_field_type_by_name(
+ packet_header_type, "stream_id");
if (stream_id_field_type) {
/* Find appropriate stream class using current stream ID */
int ret;
assert(!ret);
BT_PUT(stream_id_field);
} else {
+single_stream_class:
/* Only one stream: pick the first stream class */
assert(bt_ctf_trace_get_stream_class_count(
notit->meta.trace) == 1);
stream_id = 0;
}
+ BT_LOGV("Found stream class ID to use: notit-addr=%p, "
+ "stream-class-id=%" PRIu64 ", "
+ "trace-addr=%p, trace-name=\"%s\"",
+ notit, stream_id, notit->meta.trace,
+ bt_ctf_trace_get_name(notit->meta.trace));
+
BT_PUT(notit->meta.stream_class);
notit->meta.stream_class = bt_ctf_trace_get_stream_class_by_id(
- notit->meta.trace, stream_id);
+ notit->meta.trace, stream_id);
if (!notit->meta.stream_class) {
- PERR("Cannot find stream class with ID %" PRIu64 "\n",
- stream_id);
+ BT_LOGW("No stream class with ID of stream class ID to use in trace: "
+ "notit-addr=%p, stream-class-id=%" PRIu64 ", "
+ "trace-addr=%p, trace-name=\"%s\"",
+ notit, stream_id, notit->meta.trace,
+ bt_ctf_trace_get_name(notit->meta.trace));
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
}
+ BT_LOGV("Set current stream class: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", stream-class-id=%" PRId64,
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class));
+
/*
* Retrieve (or lazily create) the current stream class field path
* cache.
*/
notit->cur_sc_field_path_cache = get_stream_class_field_path_cache(
- notit, notit->meta.stream_class);
+ notit, notit->meta.stream_class);
if (!notit->cur_sc_field_path_cache) {
- PERR("Failed to retrieve stream class field path cache\n");
+ BT_LOGW("Cannot retrieve stream class field path from cache: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", stream-class-id=%" PRId64,
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class));
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
}
assert(notit->meta.stream_class);
packet_context_type = bt_ctf_stream_class_get_packet_context_type(
- notit->meta.stream_class);
+ notit->meta.stream_class);
if (!packet_context_type) {
+ BT_LOGV("No packet packet context field type in stream class: continuing: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", stream-class-id=%" PRId64,
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class));
notit->state = STATE_AFTER_STREAM_PACKET_CONTEXT;
goto end;
}
+ BT_LOGV("Decoding packet context field: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", stream-class-id=%" PRId64 ", "
+ "ft-addr=%p",
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ packet_context_type);
status = read_dscope_begin_state(notit, packet_context_type,
- STATE_AFTER_STREAM_PACKET_CONTEXT,
- STATE_DSCOPE_STREAM_PACKET_CONTEXT_CONTINUE,
- ¬it->dscopes.stream_packet_context);
+ STATE_AFTER_STREAM_PACKET_CONTEXT,
+ STATE_DSCOPE_STREAM_PACKET_CONTEXT_CONTINUE,
+ ¬it->dscopes.stream_packet_context);
+ if (status < 0) {
+ BT_LOGW("Cannot decode packet context field: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64 ", ft-addr=%p",
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ packet_context_type);
+ }
end:
BT_PUT(packet_context_type);
assert(notit->dscopes.stream_packet_context);
packet_size_field = bt_ctf_field_structure_get_field(
- notit->dscopes.stream_packet_context, "packet_size");
+ notit->dscopes.stream_packet_context, "packet_size");
content_size_field = bt_ctf_field_structure_get_field(
- notit->dscopes.stream_packet_context, "content_size");
+ notit->dscopes.stream_packet_context, "content_size");
if (packet_size_field) {
int ret = bt_ctf_field_unsigned_integer_get_value(
- packet_size_field, &packet_size);
+ packet_size_field, &packet_size);
- assert(!ret);
+ assert(ret == 0);
if (packet_size == 0) {
- PERR("Decoded packet size is 0\n");
+ BT_LOGW("Invalid packet size: packet context field indicates packet size is zero: "
+ "notit-addr=%p, packet-context-field-addr=%p",
+ notit, notit->dscopes.stream_packet_context);
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
} else if ((packet_size % 8) != 0) {
- PERR("Decoded packet size is not a multiple of 8\n");
+ BT_LOGW("Invalid packet size: packet context field indicates packet size is not a multiple of 8: "
+ "notit-addr=%p, packet-context-field-addr=%p, "
+ "packet-size=%" PRIu64,
+ notit, notit->dscopes.stream_packet_context,
+ packet_size);
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
}
int ret = bt_ctf_field_unsigned_integer_get_value(
content_size_field, &content_size);
- assert(!ret);
+ assert(ret == 0);
} else {
content_size = packet_size;
}
+ if (content_size > packet_size) {
+ BT_LOGW("Invalid packet or content size: packet context field indicates content size is greater than packet size: "
+ "notit-addr=%p, packet-context-field-addr=%p, "
+ "packet-size=%" PRIu64 ", content-size=%" PRIu64,
+ notit, notit->dscopes.stream_packet_context,
+ packet_size, content_size);
+ status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
+ goto end;
+ }
+
notit->cur_packet_size = packet_size;
notit->cur_content_size = content_size;
+ BT_LOGV("Set current packet and content sizes: "
+ "notit-addr=%p, packet-size=%" PRIu64 ", content-size=%" PRIu64,
+ notit, packet_size, content_size);
end:
BT_PUT(packet_size_field);
BT_PUT(content_size_field);
if (notit->cur_content_size >= 0) {
if (packet_at(notit) == notit->cur_content_size) {
/* No more events! */
+ BT_LOGV("Reached end of packet: notit-addr=%p, "
+ "cur=%zu", notit, packet_at(notit));
notit->state = STATE_EMIT_NOTIF_END_OF_PACKET;
goto end;
} else if (packet_at(notit) > notit->cur_content_size) {
/* That's not supposed to happen */
- PERR("Cursor passed packet's content size:\n");
- PERR("\tDecoded content size: %zu\n",
- notit->cur_content_size);
- PERR("\tCursor position: %zu\n", packet_at(notit));
+ BT_LOGV("Before decoding event header field: cursor is passed the packet's content: "
+ "notit-addr=%p, content-size=%zu, "
+ "cur=%zu", notit, notit->cur_content_size,
+ packet_at(notit));
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
}
}
put_event_dscopes(notit);
+ BT_LOGV("Decoding event header field: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", stream-class-id=%" PRId64 ", "
+ "ft-addr=%p",
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ event_header_type);
status = read_dscope_begin_state(notit, event_header_type,
STATE_AFTER_STREAM_EVENT_HEADER,
STATE_DSCOPE_STREAM_EVENT_HEADER_CONTINUE,
¬it->dscopes.stream_event_header);
+ if (status < 0) {
+ BT_LOGW("Cannot decode event header field: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64 ", ft-addr=%p",
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ event_header_type);
+ }
end:
BT_PUT(event_header_type);
event_header_type = bt_ctf_stream_class_get_event_header_type(
notit->meta.stream_class);
if (!event_header_type) {
- PERR("Failed to retrieve stream class's event header type\n");
- status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
- goto end;
+ /*
+ * No event header, therefore no event class ID field,
+ * therefore only one event class.
+ */
+ goto single_event_class;
}
/* Is there any "id"/"v" field in the event header? */
goto end_v_field_type;
}
- ret = bt_ctf_field_unsigned_integer_get_value(
- v_struct_id_field, &event_id);
- if (ret) {
- event_id = -1ULL;
+ if (bt_ctf_field_is_integer(v_struct_id_field)) {
+ ret = bt_ctf_field_unsigned_integer_get_value(
+ v_struct_id_field, &event_id);
+ if (ret) {
+ BT_LOGV("Cannot get value of unsigned integer field (`id`): continuing: "
+ "notit=%p, field-addr=%p",
+ notit, v_struct_id_field);
+ event_id = -1ULL;
+ }
}
end_v_field_type:
if (id_field_type && event_id == -1ULL) {
/* Check "id" field */
struct bt_ctf_field *id_field = NULL;
- int ret;
+ int ret = 0;
// TODO: optimalize!
id_field = bt_ctf_field_structure_get_field(
notit->dscopes.stream_event_header, "id");
- assert(id_field);
- assert(bt_ctf_field_is_integer(id_field) ||
- bt_ctf_field_is_enumeration(id_field));
+ if (!id_field) {
+ goto check_event_id;
+ }
if (bt_ctf_field_is_integer(id_field)) {
ret = bt_ctf_field_unsigned_integer_get_value(
id_field, &event_id);
- } else {
+ } else if (bt_ctf_field_is_enumeration(id_field)) {
struct bt_ctf_field *container;
container = bt_ctf_field_enumeration_get_container(
container, &event_id);
BT_PUT(container);
}
- assert(!ret);
+
+ assert(ret == 0);
BT_PUT(id_field);
}
+check_event_id:
if (event_id == -1ULL) {
+single_event_class:
/* Event ID not found: single event? */
assert(bt_ctf_stream_class_get_event_class_count(
notit->meta.stream_class) == 1);
event_id = 0;
}
+ BT_LOGV("Found event class ID to use: notit-addr=%p, "
+ "stream-class-addr=%p, stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64 ", "
+ "event-class-id=%" PRIu64,
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ event_id);
BT_PUT(notit->meta.event_class);
notit->meta.event_class = bt_ctf_stream_class_get_event_class_by_id(
notit->meta.stream_class, event_id);
if (!notit->meta.event_class) {
- PERR("Cannot find event class with ID %" PRIu64 "\n", event_id);
+ BT_LOGW("No event class with ID of event class ID to use in stream class: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64 ", "
+ "event-class-id=%" PRIu64,
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ event_id);
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
}
+ BT_LOGV("Set current event class: "
+ "notit-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", event-class-id=%" PRId64,
+ notit, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class));
+
end:
BT_PUT(event_header_type);
BT_PUT(id_field_type);
status = set_current_event_class(notit);
if (status != BT_CTF_NOTIF_ITER_STATUS_OK) {
- PERR("Failed to set current event class\n");
goto end;
}
goto end;
}
+ BT_LOGV("Decoding stream event context field: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", stream-class-id=%" PRId64 ", "
+ "ft-addr=%p",
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ stream_event_context_type);
status = read_dscope_begin_state(notit, stream_event_context_type,
STATE_DSCOPE_EVENT_CONTEXT_BEGIN,
STATE_DSCOPE_STREAM_EVENT_CONTEXT_CONTINUE,
¬it->dscopes.stream_event_context);
+ if (status < 0) {
+ BT_LOGW("Cannot decode stream event context field: "
+ "notit-addr=%p, stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64 ", ft-addr=%p",
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ stream_event_context_type);
+ }
end:
BT_PUT(stream_event_context_type);
notit->state = STATE_DSCOPE_EVENT_PAYLOAD_BEGIN;
goto end;
}
+
+ BT_LOGV("Decoding event context field: "
+ "notit-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", event-class-id=%" PRId64 ", "
+ "ft-addr=%p",
+ notit, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class),
+ event_context_type);
status = read_dscope_begin_state(notit, event_context_type,
STATE_DSCOPE_EVENT_PAYLOAD_BEGIN,
STATE_DSCOPE_EVENT_CONTEXT_CONTINUE,
¬it->dscopes.event_context);
+ if (status < 0) {
+ BT_LOGW("Cannot decode event context field: "
+ "notit-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64 ", ft-addr=%p",
+ notit, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class),
+ event_context_type);
+ }
end:
BT_PUT(event_context_type);
goto end;
}
+ BT_LOGV("Decoding event payload field: "
+ "notit-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", event-class-id=%" PRId64 ", "
+ "ft-addr=%p",
+ notit, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class),
+ event_payload_type);
status = read_dscope_begin_state(notit, event_payload_type,
STATE_EMIT_NOTIF_EVENT,
STATE_DSCOPE_EVENT_PAYLOAD_CONTINUE,
¬it->dscopes.event_payload);
+ if (status < 0) {
+ BT_LOGW("Cannot decode event payload field: "
+ "notit-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64 ", ft-addr=%p",
+ notit, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class),
+ event_payload_type);
+ }
end:
BT_PUT(event_payload_type);
goto end;
} else {
size_t bits_to_consume;
+
+ BT_LOGV("Trying to skip %zu bits of padding: notit-addr=%p, size=%zu",
+ bits_to_skip, notit, bits_to_skip);
status = buf_ensure_available_bits(notit);
if (status != BT_CTF_NOTIF_ITER_STATUS_OK) {
goto end;
}
bits_to_consume = MIN(buf_available_bits(notit), bits_to_skip);
+ BT_LOGV("Skipping %zu bits of padding: notit-addr=%p, size=%zu",
+ bits_to_consume, notit, bits_to_consume);
buf_consume_bits(notit, bits_to_consume);
bits_to_skip = notit->cur_packet_size - packet_at(notit);
if (bits_to_skip == 0) {
enum bt_ctf_notif_iter_status handle_state(struct bt_ctf_notif_iter *notit)
{
enum bt_ctf_notif_iter_status status = BT_CTF_NOTIF_ITER_STATUS_OK;
+ const enum state state = notit->state;
- PDBG("Handling state %d\n", notit->state);
+ BT_LOGV("Handling state: notit-addr=%p, state=%s",
+ notit, state_string(state));
// TODO: optimalize!
- switch (notit->state) {
+ switch (state) {
case STATE_INIT:
notit->state = STATE_DSCOPE_TRACE_PACKET_HEADER_BEGIN;
break;
case STATE_EMIT_NOTIF_END_OF_PACKET:
notit->state = STATE_SKIP_PACKET_PADDING;
break;
+ default:
+ BT_LOGD("Unknown CTF plugin notification iterator state: "
+ "notit-addr=%p, state=%d", notit, notit->state);
+ abort();
}
+ BT_LOGV("Handled state: notit-addr=%p, status=%s, "
+ "prev-state=%s, cur-state=%s",
+ notit, bt_ctf_notif_iter_status_string(status),
+ state_string(state), state_string(notit->state));
return status;
}
void bt_ctf_notif_iter_reset(struct bt_ctf_notif_iter *notit)
{
assert(notit);
+ BT_LOGD("Resetting notification iterator: addr=%p", notit);
stack_clear(notit->stack);
BT_PUT(notit->meta.stream_class);
BT_PUT(notit->meta.event_class);
int ret = 0;
assert(notit);
+ BT_LOGV("Switching packet: notit-addr=%p, cur=%zu",
+ notit, notit->buf.at);
stack_clear(notit->stack);
BT_PUT(notit->meta.stream_class);
BT_PUT(notit->meta.event_class);
/* Packets are assumed to start on a byte frontier. */
if (notit->buf.at % CHAR_BIT) {
+ BT_LOGW("Cannot switch packet: current position is not a multiple of 8: "
+ "notit-addr=%p, cur=%zu", notit, notit->buf.at);
ret = -1;
goto end;
}
notit->buf.sz -= consumed_bytes;
notit->buf.at = 0;
notit->buf.packet_offset = 0;
+ BT_LOGV("Adjusted buffer: addr=%p, size=%zu",
+ notit->buf.addr, notit->buf.sz);
}
notit->cur_content_size = -1;
notit->cur_packet_size = -1;
notit->cur_sc_field_path_cache = NULL;
+
end:
return ret;
}
assert(!stack_empty(notit->stack));
index = stack_top(notit->stack)->index;
base_field = stack_top(notit->stack)->base;
+ assert(base_field);
base_type = bt_ctf_field_get_type(base_field);
- if (!base_type) {
- PERR("Failed to get base field's type\n");
- goto end;
- }
+ assert(base_type);
switch (bt_ctf_field_type_get_type_id(base_type)) {
case BT_CTF_FIELD_TYPE_ID_STRUCT:
next_field = bt_ctf_field_variant_get_current_field(base_field);
break;
default:
+ BT_LOGF("Unknown base field type ID: "
+ "notit-addr=%p, ft-addr=%p, ft-id=%s",
+ notit, base_type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(base_type)));
abort();
}
-end:
BT_PUT(base_type);
-
return next_field;
}
value_type = bt_ctf_field_get_type(value_field);
assert(value_type);
-
+ assert(bt_ctf_field_type_is_integer(value_type));
requested_new_value_size =
bt_ctf_field_type_integer_get_size(value_type);
assert(requested_new_value_size > 0);
-
ret = bt_ctf_field_unsigned_integer_get_value(value_field,
&requested_new_value);
assert(!ret);
/* Set the low bits of the current clock value. */
*state |= requested_new_value;
end:
+ BT_LOGV("Updated clock's value from integer field's value: "
+ "value=%" PRIu64, *state);
bt_put(value_type);
}
struct bt_ctf_clock_class *clock_class = NULL;
int_field_type = bt_ctf_field_get_type(int_field);
- if (unlikely(!int_field_type)) {
- goto end;
- }
-
+ assert(int_field_type);
clock_class = bt_ctf_field_type_integer_get_mapped_clock_class(
int_field_type);
if (likely(!clock_class)) {
}
clock_class_found = g_hash_table_lookup_extended(notit->clock_states,
- clock_class, NULL, (gpointer) &clock_state);
+ clock_class, NULL, (gpointer) &clock_state);
if (unlikely(!clock_class_found)) {
- const char *clock_class_name =
- bt_ctf_clock_class_get_name(clock_class);
-
- PERR("Unknown clock class %s mapped to integer encountered in stream\n",
- clock_class_name ? : "NULL");
ret = BT_CTF_BTR_STATUS_ERROR;
goto end;
}
if (unlikely(!clock_state)) {
clock_state = g_new0(uint64_t, 1);
if (!clock_state) {
+ BT_LOGE_STR("Failed to allocate a uint64_t.");
ret = BT_CTF_BTR_STATUS_ENOMEM;
goto end;
}
+
g_hash_table_insert(notit->clock_states, bt_get(clock_class),
- clock_state);
+ clock_state);
}
/* Update the clock's state. */
+ BT_LOGV("Updating notification iterator's clock's value from integer field: "
+ "notit-addr=%p, clock-class-addr=%p, "
+ "clock-class-name=\"%s\", value=%" PRIu64,
+ notit, clock_class,
+ bt_ctf_clock_class_get_name(clock_class), *clock_state);
update_clock_state(clock_state, int_field);
end:
bt_put(int_field_type);
struct bt_ctf_notif_iter *notit = data;
int ret;
+ BT_LOGV("Common unsigned integer function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s, value=%" PRIu64,
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)),
+ value);
+
/* Create next field */
field = get_next_field(notit);
if (!field) {
- PERR("Failed to get next field (unsigned int)\n");
+ BT_LOGW("Cannot get next field: notit-addr=%p", notit);
status = BT_CTF_BTR_STATUS_ERROR;
goto end_no_put;
}
break;
case BT_CTF_FIELD_TYPE_ID_ENUM:
int_field = bt_ctf_field_enumeration_get_container(field);
+ assert(int_field);
type = bt_ctf_field_get_type(int_field);
+ assert(type);
break;
default:
+ BT_LOGF("Unexpected field type ID: "
+ "notit-addr=%p, ft-addr=%p, ft-id=%s",
+ notit, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)));
abort();
}
- if (!int_field) {
- PERR("Failed to get integer field\n");
- status = BT_CTF_BTR_STATUS_ERROR;
- goto end;
- }
-
+ assert(int_field);
ret = bt_ctf_field_unsigned_integer_set_value(int_field, value);
- assert(!ret);
+ assert(ret == 0);
stack_top(notit->stack)->index++;
*out_int_field = int_field;
-
-end:
BT_PUT(field);
BT_PUT(type);
+
end_no_put:
return status;
}
struct bt_ctf_field *field = NULL;
struct bt_ctf_notif_iter *notit = data;
+ BT_LOGV("`timestamp_end` unsigned integer function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s",
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)));
status = btr_unsigned_int_common(*((uint64_t *) value), type, data,
&field);
struct bt_ctf_field *field = NULL;
struct field_cb_override *override;
- override = g_hash_table_lookup(notit->field_overrides,
- type);
+ BT_LOGV("Unsigned integer function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s, value=%" PRIu64,
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)),
+ value);
+ override = g_hash_table_lookup(notit->field_overrides, type);
if (unlikely(override)) {
+ /* Override function logs errors */
status = override->func(&value, type, override->data);
goto end;
}
status = btr_unsigned_int_common(value, type, data, &field);
if (status != BT_CTF_BTR_STATUS_OK) {
+ /* btr_unsigned_int_common() logs errors */
goto end;
}
struct bt_ctf_notif_iter *notit = data;
int ret;
+ BT_LOGV("Signed integer function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s, value=%" PRId64,
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)),
+ value);
+
/* create next field */
field = get_next_field(notit);
if (!field) {
- PERR("Failed to get next field (signed int)\n");
+ BT_LOGW("Cannot get next field: notit-addr=%p", notit);
status = BT_CTF_BTR_STATUS_ERROR;
goto end_no_put;
}
break;
case BT_CTF_FIELD_TYPE_ID_ENUM:
int_field = bt_ctf_field_enumeration_get_container(field);
+ assert(int_field);
type = bt_ctf_field_get_type(int_field);
+ assert(type);
break;
default:
+ BT_LOGF("Unexpected field type ID: "
+ "notit-addr=%p, ft-addr=%p, ft-id=%s",
+ notit, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)));
abort();
}
- if (!int_field) {
- PERR("Failed to get integer field\n");
- status = BT_CTF_BTR_STATUS_ERROR;
- goto end;
- }
-
+ assert(int_field);
ret = bt_ctf_field_signed_integer_set_value(int_field, value);
assert(!ret);
stack_top(notit->stack)->index++;
status = update_clock(notit, int_field);
-end:
BT_PUT(field);
BT_PUT(int_field);
BT_PUT(type);
+
end_no_put:
return status;
}
struct bt_ctf_notif_iter *notit = data;
int ret;
+ BT_LOGV("Floating point number function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s, value=%f",
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)),
+ value);
+
/* Create next field */
field = get_next_field(notit);
if (!field) {
- PERR("Failed to get next field (floating point number)\n");
+ BT_LOGW("Cannot get next field: notit-addr=%p", notit);
status = BT_CTF_BTR_STATUS_ERROR;
goto end;
}
end:
BT_PUT(field);
-
return status;
}
struct bt_ctf_notif_iter *notit = data;
int ret;
+ BT_LOGV("String (beginning) function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s",
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)));
+
/* Create next field */
field = get_next_field(notit);
if (!field) {
- PERR("Failed to get next field (string)\n");
+ BT_LOGW("Cannot get next field: notit-addr=%p", notit);
status = BT_CTF_BTR_STATUS_ERROR;
goto end;
}
*/
ret = stack_push(notit->stack, field);
if (ret) {
- PERR("Failed to push string field onto the stack\n");
+ BT_LOGE("Cannot push string field on stack: "
+ "notit-addr=%p, field-addr=%p", notit, field);
status = BT_CTF_BTR_STATUS_ERROR;
goto end;
}
*/
ret = bt_ctf_field_string_set_value(field, "");
if (ret) {
- PERR("Failed to initialize string field\n");
+ BT_LOGE("Cannot initialize string field's value to an empty string: "
+ "notit-addr=%p, field-addr=%p, ret=%d",
+ notit, field, ret);
status = BT_CTF_BTR_STATUS_ERROR;
goto end;
}
struct bt_ctf_notif_iter *notit = data;
int ret;
+ BT_LOGV("String (substring) function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s, string-length=%zu",
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)),
+ len);
+
/* Get string field */
field = stack_top(notit->stack)->base;
assert(field);
/* Append current string */
ret = bt_ctf_field_string_append_len(field, value, len);
if (ret) {
- PERR("Failed to append a string to a string field\n");
+ BT_LOGE("Cannot append substring to string field's value: "
+ "notit-addr=%p, field-addr=%p, string-length=%zu, "
+ "ret=%d", notit, field, len, ret);
status = BT_CTF_BTR_STATUS_ERROR;
goto end;
}
{
struct bt_ctf_notif_iter *notit = data;
+ BT_LOGV("String (end) function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s",
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)));
+
/* Pop string field */
stack_pop(notit->stack);
/* Go to next field */
stack_top(notit->stack)->index++;
-
return BT_CTF_BTR_STATUS_OK;
}
struct bt_ctf_field *field;
int ret;
+ BT_LOGV("Compound (beginning) function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s",
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)));
+
/* Create field */
if (stack_empty(notit->stack)) {
/* Root: create dynamic scope field */
* later), so also get it for our context to own it.
*/
bt_get(*notit->cur_dscope_field);
+
+ if (!field) {
+ BT_LOGE("Cannot create compound field: "
+ "notit-addr=%p, ft-addr=%p, ft-id=%s",
+ notit, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)));
+ status = BT_CTF_BTR_STATUS_ERROR;
+ goto end;
+ }
} else {
field = get_next_field(notit);
- }
-
- if (!field) {
- PERR("Failed to get next field or create dynamic scope field\n");
- status = BT_CTF_BTR_STATUS_ERROR;
- goto end;
+ if (!field) {
+ BT_LOGW("Cannot get next field: notit-addr=%p", notit);
+ status = BT_CTF_BTR_STATUS_ERROR;
+ goto end;
+ }
}
/* Push field */
+ assert(field);
ret = stack_push(notit->stack, field);
if (ret) {
- PERR("Failed to push compound field onto the stack\n");
+ BT_LOGE("Cannot push compound field onto the stack: "
+ "notit-addr=%p, ft-addr=%p, ft-id=%s, ret=%d",
+ notit, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)),
+ ret);
status = BT_CTF_BTR_STATUS_ERROR;
goto end;
}
{
struct bt_ctf_notif_iter *notit = data;
+ BT_LOGV("Compound (end) function called from BTR: "
+ "notit-addr=%p, btr-addr=%p, ft-addr=%p, "
+ "ft-id=%s",
+ notit, notit->btr, type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(type)));
assert(!stack_empty(notit->stack));
/* Pop stack */
struct bt_ctf_field *field = NULL;
unsigned int i;
+ if (BT_LOG_ON_VERBOSE) {
+ GString *gstr = bt_ctf_field_path_string(path);
+
+ BT_LOGV("Resolving field path: notit-addr=%p, field-path=\"%s\"",
+ notit, gstr ? gstr->str : NULL);
+
+ if (gstr) {
+ g_string_free(gstr, TRUE);
+ }
+ }
+
switch (bt_ctf_field_path_get_root_scope(path)) {
case BT_CTF_SCOPE_TRACE_PACKET_HEADER:
field = notit->dscopes.trace_packet_header;
field = notit->dscopes.event_payload;
break;
default:
- break;
+ BT_LOGF("Cannot resolve field path: unknown scope: "
+ "notit-addr=%p, root-scope=%s",
+ notit, bt_ctf_scope_string(
+ bt_ctf_field_path_get_root_scope(path)));
+ abort();
}
if (!field) {
+ BT_LOGW("Cannot resolve field path: root field not found: "
+ "notit-addr=%p, root-scope=%s",
+ notit, bt_ctf_scope_string(
+ bt_ctf_field_path_get_root_scope(path)));
goto end;
}
int index = bt_ctf_field_path_get_index(path, i);
field_type = bt_ctf_field_get_type(field);
- if (!field_type) {
- BT_PUT(field);
- goto end;
- }
+ assert(field_type);
if (is_struct_type(field_type)) {
next_field = bt_ctf_field_structure_get_field_by_index(
BT_PUT(field_type);
if (!next_field) {
+ BT_LOGW("Cannot find next field: "
+ "notit-addr=%p, ft-addr=%p, ft-id=%s, index=%d",
+ notit, field_type,
+ bt_ctf_field_type_id_string(
+ bt_ctf_field_type_get_type_id(field_type)),
+ index);
goto end;
}
{
int64_t ret = -1;
int iret;
+ struct bt_ctf_field *seq_field;
struct bt_ctf_field_path *field_path;
struct bt_ctf_notif_iter *notit = data;
struct bt_ctf_field *length_field = NULL;
uint64_t length;
field_path = bt_ctf_field_type_sequence_get_length_field_path(type);
- if (!field_path) {
- goto end;
- }
-
+ assert(field_path);
length_field = resolve_field(notit, field_path);
if (!length_field) {
+ BT_LOGW("Cannot resolve sequence field type's length field path: "
+ "notit-addr=%p, ft-addr=%p",
+ notit, type);
goto end;
}
iret = bt_ctf_field_unsigned_integer_get_value(length_field, &length);
if (iret) {
+ BT_LOGE("Cannot get value of sequence length field: "
+ "notit-addr=%p, field-addr=%p",
+ notit, length_field);
goto end;
}
- iret = bt_ctf_field_sequence_set_length(stack_top(notit->stack)->base,
- length_field);
+ seq_field = stack_top(notit->stack)->base;
+ iret = bt_ctf_field_sequence_set_length(seq_field, length_field);
if (iret) {
+ BT_LOGE("Cannot set sequence field's length field: "
+ "notit-addr=%p, seq-field-addr=%p, "
+ "length-field-addr=%p, ",
+ notit, seq_field, length_field);
goto end;
}
+
ret = (int64_t) length;
end:
{
struct bt_ctf_field_path *path;
struct bt_ctf_notif_iter *notit = data;
+ struct bt_ctf_field *var_field;
struct bt_ctf_field *tag_field = NULL;
struct bt_ctf_field *selected_field = NULL;
struct bt_ctf_field_type *selected_field_type = NULL;
path = bt_ctf_field_type_variant_get_tag_field_path(type);
- if (!path) {
- goto end;
- }
-
+ assert(path);
tag_field = resolve_field(notit, path);
if (!tag_field) {
+ BT_LOGW("Cannot resolve variant field type's tag field path: "
+ "notit-addr=%p, ft-addr=%p",
+ notit, type);
goto end;
}
* function call which is used to fill the current selected
* field.
*/
- selected_field = bt_ctf_field_variant_get_field(
- stack_top(notit->stack)->base, tag_field);
+ var_field = stack_top(notit->stack)->base;
+ selected_field = bt_ctf_field_variant_get_field(var_field, tag_field);
if (!selected_field) {
+ BT_LOGW("Cannot get variant field's selection using tag field: "
+ "notit-addr=%p, var-field-addr=%p, tag-field-addr=%p",
+ notit, var_field, tag_field);
goto end;
}
clock_value = bt_ctf_clock_value_create(clock_class,
*clock_state);
if (!clock_value) {
+ BT_LOGE("Cannot create clock value from clock class: "
+ "notit-addr=%p, clock-class-addr=%p, "
+ "clock-class-name=\"%s\"",
+ notit, clock_class,
+ bt_ctf_clock_class_get_name(clock_class));
ret = -1;
goto end;
}
ret = bt_ctf_event_set_clock_value(event, clock_value);
bt_put(clock_value);
if (ret) {
+ struct bt_ctf_event_class *event_class =
+ bt_ctf_event_get_class(event);
+
+ assert(event_class);
+ BT_LOGE("Cannot set event's clock value: "
+ "notit-addr=%p, event-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64 ", "
+ "clock-class-addr=%p, "
+ "clock-class-name=\"%s\", "
+ "clock-value-addr=%p",
+ notit, event,
+ bt_ctf_event_class_get_name(event_class),
+ bt_ctf_event_class_get_id(event_class),
+ clock_class,
+ bt_ctf_clock_class_get_name(clock_class),
+ clock_value);
+ bt_put(event_class);
goto end;
}
}
+
ret = 0;
end:
return ret;
int ret;
struct bt_ctf_event *event;
+ BT_LOGV("Creating event for event notification: "
+ "notit-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64,
+ notit, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class));
+
/* Create event object. */
event = bt_ctf_event_create(notit->meta.event_class);
if (!event) {
+ BT_LOGE("Cannot create event: "
+ "notit-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64,
+ notit, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class));
goto error;
}
ret = bt_ctf_event_set_header(event,
notit->dscopes.stream_event_header);
if (ret) {
+ BT_LOGE("Cannot set event's header field: "
+ "notit-addr=%p, event-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64 ", field-addr=%p",
+ notit, event, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class),
+ notit->dscopes.stream_event_header);
goto error;
}
ret = bt_ctf_event_set_stream_event_context(event,
notit->dscopes.stream_event_context);
if (ret) {
+ BT_LOGE("Cannot set event's context field: "
+ "notit-addr=%p, event-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64 ", field-addr=%p",
+ notit, event, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class),
+ notit->dscopes.stream_event_context);
goto error;
}
ret = bt_ctf_event_set_event_context(event,
notit->dscopes.event_context);
if (ret) {
+ BT_LOGE("Cannot set event's stream event context field: "
+ "notit-addr=%p, event-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64 ", field-addr=%p",
+ notit, event, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class),
+ notit->dscopes.event_context);
goto error;
}
ret = bt_ctf_event_set_event_payload(event,
notit->dscopes.event_payload);
if (ret) {
+ BT_LOGE("Cannot set event's payload field: "
+ "notit-addr=%p, event-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64 ", field-addr=%p",
+ notit, event, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class),
+ notit->dscopes.event_payload);
goto error;
}
ret = set_event_clocks(event, notit);
if (ret) {
+ BT_LOGE("Cannot set event's clock values: "
+ "notit-addr=%p, event-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64,
+ notit, event, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class));
goto error;
}
assert(notit->packet);
ret = bt_ctf_event_set_packet(event, notit->packet);
if (ret) {
+ BT_LOGE("Cannot set event's header field: "
+ "notit-addr=%p, event-addr=%p, event-class-addr=%p, "
+ "event-class-name=\"%s\", "
+ "event-class-id=%" PRId64 ", packet-addr=%p",
+ notit, event, notit->meta.event_class,
+ bt_ctf_event_class_get_name(notit->meta.event_class),
+ bt_ctf_event_class_get_id(notit->meta.event_class),
+ notit->packet);
goto error;
}
goto end;
+
error:
BT_PUT(event);
+
end:
return event;
}
struct bt_ctf_stream *stream = NULL;
struct bt_ctf_packet *packet = NULL;
+ BT_LOGV("Creating packet for packet notification: "
+ "notit-addr=%p", notit);
+
/* Ask the user for the stream */
+ BT_LOGV("Calling user function (get stream): notit-addr=%p, "
+ "stream-class-addr=%p, stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64,
+ notit, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class));
stream = notit->medium.medops.get_stream(notit->meta.stream_class,
notit->medium.data);
+ BT_LOGV("User function returned: stream-addr=%p",
+ stream);
if (!stream) {
+ BT_LOGW_STR("User function failed to return a stream object for the given stream class.");
goto error;
}
+ BT_LOGV("Creating packet from stream: "
+ "notit-addr=%p, stream-addr=%p, "
+ "stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64,
+ notit, stream, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class));
+
/* Create packet */
packet = bt_ctf_packet_create(stream);
if (!packet) {
+ BT_LOGE("Cannot create packet from stream: "
+ "notit-addr=%p, stream-addr=%p, "
+ "stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64,
+ notit, stream, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class));
goto error;
}
/* Set packet's context and header fields */
if (notit->dscopes.trace_packet_header) {
ret = bt_ctf_packet_set_header(packet,
- notit->dscopes.trace_packet_header);
+ notit->dscopes.trace_packet_header);
if (ret) {
+ BT_LOGE("Cannot set packet's header field: "
+ "notit-addr=%p, packet-addr=%p, "
+ "stream-addr=%p, "
+ "stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64 ", "
+ "field-addr=%p",
+ notit, packet, stream, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ notit->dscopes.trace_packet_header);
goto error;
}
}
if (notit->dscopes.stream_packet_context) {
ret = bt_ctf_packet_set_context(packet,
- notit->dscopes.stream_packet_context);
+ notit->dscopes.stream_packet_context);
if (ret) {
+ BT_LOGE("Cannot set packet's context field: "
+ "notit-addr=%p, packet-addr=%p, "
+ "stream-addr=%p, "
+ "stream-class-addr=%p, "
+ "stream-class-name=\"%s\", "
+ "stream-class-id=%" PRId64 ", "
+ "field-addr=%p",
+ notit, packet, stream, notit->meta.stream_class,
+ bt_ctf_stream_class_get_name(notit->meta.stream_class),
+ bt_ctf_stream_class_get_id(notit->meta.stream_class),
+ notit->dscopes.trace_packet_header);
goto error;
}
}
goto end;
+
error:
BT_PUT(packet);
+
end:
BT_MOVE(notit->packet, packet);
}
/* Initialize the iterator's current packet */
create_packet(notit);
if (!notit->packet) {
+ BT_LOGE("Cannot create packet for packet notification: "
+ "notit-addr=%p", notit);
return;
}
ret = bt_notification_packet_begin_create(notit->packet);
if (!ret) {
+ BT_LOGE("Cannot create packet beginning notification: "
+ "notit-addr=%p, packet-addr=%p",
+ notit, notit->packet);
return;
}
*notification = ret;
ret = bt_notification_packet_end_create(notit->packet);
if (!ret) {
+ BT_LOGE("Cannot create packet end notification: "
+ "notit-addr=%p, packet-addr=%p",
+ notit, notit->packet);
return;
}
BT_PUT(notit->packet);
/* Create event */
event = create_event(notit);
if (!event) {
+ BT_LOGE("Cannot create event for event notification: "
+ "notit-addr=%p", notit);
goto end;
}
ret = bt_notification_event_create(event, cc_prio_map);
if (!ret) {
+ BT_LOGE("Cannot create event notification: "
+ "notit-addr=%p, event-addr=%p, "
+ "cc-prio-map-addr=%p",
+ notit, event, cc_prio_map);
goto end;
}
*notification = ret;
{
int clock_class_count, i, ret = 0;
+ assert(trace);
clock_class_count = bt_ctf_trace_get_clock_class_count(trace);
- if (clock_class_count <= 0) {
- ret = -1;
- goto end;
- }
+ assert(clock_class_count >= 0);
for (i = 0; i < clock_class_count; i++) {
struct bt_ctf_clock_class *clock_class;
clock_class = bt_ctf_trace_get_clock_class_by_index(trace, i);
- if (!clock_class) {
- ret = -1;
- goto end;
- }
-
+ assert(clock_class);
g_hash_table_insert(clock_states, bt_get(clock_class), NULL);
bt_put(clock_class);
}
-end:
+
return ret;
}
}
count = bt_ctf_field_type_structure_get_field_count(packet_header);
- if (count < 0) {
- goto end;
- }
+ assert(count >= 0);
for (i = 0; (i < count && (stream_id == -1 || stream_instance_id == -1)); i++) {
int ret;
ret = bt_ctf_field_type_structure_get_field(packet_header,
&field_name, NULL, i);
if (ret) {
+ BT_LOGE("Cannot get structure field's field: "
+ "field-addr=%p, index=%d",
+ packet_header, i);
goto end;
}
stream_instance_id = i;
}
}
+
end:
trace_field_path_cache->stream_id = stream_id;
trace_field_path_cache->stream_instance_id = stream_instance_id;
assert(trace);
assert(medops.request_bytes);
assert(medops.get_stream);
+ BT_LOGD("Creating CTF plugin notification iterator: "
+ "trace-addr=%p, trace-name=\"%s\", max-request-size=%zu, "
+ "data=%p",
+ trace, bt_ctf_trace_get_name(trace), max_request_sz, data);
notit = g_new0(struct bt_ctf_notif_iter, 1);
if (!notit) {
- PERR("Failed to allocate memory for CTF notification iterator\n");
+ BT_LOGE_STR("Failed to allocate one CTF plugin notification iterator.");
goto end;
}
notit->clock_states = g_hash_table_new_full(g_direct_hash,
- g_direct_equal, bt_put, g_free);
+ g_direct_equal, bt_put, g_free);
if (!notit->clock_states) {
- PERR("Failed to create hash table\n");
+ BT_LOGE_STR("Failed to allocate a GHashTable.");
goto error;
}
ret = init_clock_states(notit->clock_states, trace);
if (ret) {
- PERR("Failed to initialize stream clock states\n");
+ BT_LOGW("Cannot initialize clock values.");
goto error;
}
notit->meta.trace = bt_get(trace);
notit->err_stream = err_stream;
notit->stack = stack_new(notit);
if (!notit->stack) {
- PERR("Failed to create stack\n");
+ BT_LOGE_STR("Failed to create field stack.");
goto error;
}
notit->btr = bt_ctf_btr_create(cbs, notit, err_stream);
if (!notit->btr) {
- PERR("Failed to create binary type reader\n");
+ BT_LOGE_STR("Failed to create binary type reader (BTR).");
goto error;
}
bt_ctf_notif_iter_reset(notit);
-
init_trace_field_path_cache(trace, ¬it->trace_field_path_cache);
notit->sc_field_path_caches = g_hash_table_new_full(g_direct_hash,
- g_direct_equal, bt_put, g_free);
+ g_direct_equal, bt_put, g_free);
if (!notit->sc_field_path_caches) {
- PERR("Failed to create stream class field path caches\n");
+ BT_LOGE_STR("Failed to allocate a GHashTable.");
goto error;
}
notit->field_overrides = g_hash_table_new_full(g_direct_hash,
- g_direct_equal, bt_put, g_free);
+ g_direct_equal, bt_put, g_free);
if (!notit->field_overrides) {
+ BT_LOGE_STR("Failed to allocate a GHashTable.");
goto error;
}
+ BT_LOGD("Created CTF plugin notification iterator: "
+ "trace-addr=%p, trace-name=\"%s\", max-request-size=%zu, "
+ "data=%p, notit-addr=%p",
+ trace, bt_ctf_trace_get_name(trace), max_request_sz, data,
+ notit);
+
end:
return notit;
+
error:
bt_ctf_notif_iter_destroy(notit);
notit = NULL;
BT_PUT(notit->cur_timestamp_end);
put_all_dscopes(notit);
+ BT_LOGD("Destroying CTF plugin notification iterator: addr=%p", notit);
+
if (notit->stack) {
+ BT_LOGD_STR("Destroying field stack.");
stack_destroy(notit->stack);
}
if (notit->btr) {
+ BT_LOGD("Destroying BTR: btr-addr=%p", notit->btr);
bt_ctf_btr_destroy(notit->btr);
}
if (notit->field_overrides) {
g_hash_table_destroy(notit->field_overrides);
}
+
g_free(notit);
}
assert(notit);
assert(notification);
+ BT_LOGV("Getting next notification: notit-addr=%p, cc-prio-map-addr=%p",
+ notit, cc_prio_map);
+
while (true) {
status = handle_state(notit);
if (status == BT_CTF_NOTIF_ITER_STATUS_AGAIN) {
- PDBG("Medium operation reported \"try again later\"");
+ BT_LOGV_STR("Medium returned BT_CTF_NOTIF_ITER_STATUS_AGAIN.");
goto end;
}
if (status != BT_CTF_NOTIF_ITER_STATUS_OK) {
if (status == BT_CTF_NOTIF_ITER_STATUS_EOF) {
- PDBG("Medium operation reported end of stream\n");
+ BT_LOGV_STR("Medium returned BT_CTF_NOTIF_ITER_STATUS_EOF.");
} else {
- PERR("Failed to handle state:\n");
- PERR("\tState: %d\n", notit->state);
+ BT_LOGW("Cannot handle state: "
+ "notit-addr=%p, state=%s",
+ notit, state_string(notit->state));
}
goto end;
}
switch (notit->state) {
case STATE_EMIT_NOTIF_NEW_PACKET:
- PDBG("Emitting new packet notification\n");
+ /* notify_new_packet() logs errors */
notify_new_packet(notit, notification);
if (!*notification) {
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
}
goto end;
case STATE_EMIT_NOTIF_EVENT:
- PDBG("Emitting event notification\n");
+ /* notify_event() logs errors */
notify_event(notit, cc_prio_map, notification);
if (!*notification) {
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
bt_ctf_field_get_type(
notit->cur_timestamp_end);
+ assert(field_type);
btr_status = update_clock(notit,
notit->cur_timestamp_end);
BT_PUT(field_type);
if (btr_status != BT_CTF_BTR_STATUS_OK) {
+ BT_LOGW("Cannot update stream's clock value: "
+ "notit-addr=%p", notit);
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
goto end;
}
}
- PDBG("Emitting end of packet notification\n");
+ /* notify_end_of_packet() logs errors */
notify_end_of_packet(notit, notification);
if (!*notification) {
status = BT_CTF_NOTIF_ITER_STATUS_ERROR;
while (true) {
status = handle_state(notit);
if (status == BT_CTF_NOTIF_ITER_STATUS_AGAIN) {
- PDBG("Medium operation reported \"try again later\"");
+ BT_LOGV_STR("Medium returned BT_CTF_NOTIF_ITER_STATUS_AGAIN.");
goto end;
}
if (status != BT_CTF_NOTIF_ITER_STATUS_OK) {
if (status == BT_CTF_NOTIF_ITER_STATUS_EOF) {
- PDBG("Medium operation reported end of stream\n");
+ BT_LOGV_STR("Medium returned BT_CTF_NOTIF_ITER_STATUS_EOF.");
} else {
- PERR("Failed to handle state:\n");
- PERR("\tState: %d\n", notit->state);
+ BT_LOGW("Cannot handle state: "
+ "notit-addr=%p, state=%s",
+ notit, state_string(notit->state));
}
goto end;
}
* We should never get past the
* STATE_EMIT_NOTIF_NEW_PACKET state.
*/
+ BT_LOGF("Unexpected state: notit-addr=%p, state=%s",
+ notit, state_string(notit->state));
abort();
}
}
projects / babeltrace.git / commitdiff
