/*- * BSD LICENSE * * Copyright (c) Intel Corporation. * All rights reserved. * * Redistribution and use in source and binary forms, with or without * modification, are permitted provided that the following conditions * are met: * * * Redistributions of source code must retain the above copyright * notice, this list of conditions and the following disclaimer. * * Redistributions in binary form must reproduce the above copyright * notice, this list of conditions and the following disclaimer in * the documentation and/or other materials provided with the * distribution. * * Neither the name of Intel Corporation nor the names of its * contributors may be used to endorse or promote products derived * from this software without specific prior written permission. * * THIS SOFTWARE IS PROVIDED BY THE COPYRIGHT HOLDERS AND CONTRIBUTORS * "AS IS" AND ANY EXPRESS OR IMPLIED WARRANTIES, INCLUDING, BUT NOT * LIMITED TO, THE IMPLIED WARRANTIES OF MERCHANTABILITY AND FITNESS FOR * A PARTICULAR PURPOSE ARE DISCLAIMED. IN NO EVENT SHALL THE COPYRIGHT * OWNER OR CONTRIBUTORS BE LIABLE FOR ANY DIRECT, INDIRECT, INCIDENTAL, * SPECIAL, EXEMPLARY, OR CONSEQUENTIAL DAMAGES (INCLUDING, BUT NOT * LIMITED TO, PROCUREMENT OF SUBSTITUTE GOODS OR SERVICES; LOSS OF USE, * DATA, OR PROFITS; OR BUSINESS INTERRUPTION) HOWEVER CAUSED AND ON ANY * THEORY OF LIABILITY, WHETHER IN CONTRACT, STRICT LIABILITY, OR TORT * (INCLUDING NEGLIGENCE OR OTHERWISE) ARISING IN ANY WAY OUT OF THE USE * OF THIS SOFTWARE, EVEN IF ADVISED OF THE POSSIBILITY OF SUCH DAMAGE. */ #include "spdk/stdinc.h" #include extern "C" { #include "spdk/trace.h" #include "spdk/util.h" } static struct spdk_trace_histories *g_histories; static bool g_print_tsc = false; static void usage(void); struct entry_key { entry_key(uint16_t _lcore, uint64_t _tsc) : lcore(_lcore), tsc(_tsc) {} uint16_t lcore; uint64_t tsc; }; class compare_entry_key { public: bool operator()(const entry_key &first, const entry_key &second) const { if (first.tsc == second.tsc) { return first.lcore < second.lcore; } else { return first.tsc < second.tsc; } } }; typedef std::map entry_map; entry_map g_entry_map; struct object_stats { std::map start; std::map index; std::map size; std::map tpoint_id; uint64_t counter; object_stats() : start(), index(), size(), tpoint_id(), counter(0) {} }; struct object_stats g_stats[SPDK_TRACE_MAX_OBJECT]; static char *g_exe_name; static int g_verbose = 1; static uint64_t g_tsc_rate; static uint64_t g_first_tsc = 0x0; static float get_us_from_tsc(uint64_t tsc, uint64_t tsc_rate) { return ((float)tsc) * 1000 * 1000 / tsc_rate; } static void print_ptr(const char *arg_string, uint64_t arg) { printf("%-7.7s0x%-14jx ", arg_string, arg); } static void print_uint64(const char *arg_string, uint64_t arg) { /* * Print arg as signed, since -1 is a common value especially * for FLUSH WRITEBUF when writev() returns -1 due to full * socket buffer. */ printf("%-7.7s%-16jd ", arg_string, arg); } static void print_string(const char *arg_string, uint64_t arg) { char *str = (char *)&arg; printf("%-7.7s%.8s ", arg_string, str); } static void print_size(uint32_t size) { if (size > 0) { printf("size: %6u ", size); } else { printf("%13s", " "); } } static void print_object_id(uint8_t type, uint64_t id) { printf("id: %c%-15jd ", g_histories->flags.object[type].id_prefix, id); } static void print_float(const char *arg_string, float arg) { printf("%-7s%-16.3f ", arg_string, arg); } static void print_arg(uint8_t arg_type, const char *arg_string, uint64_t arg) { if (arg_string[0] == 0) { printf("%24s", ""); return; } switch (arg_type) { case SPDK_TRACE_ARG_TYPE_PTR: print_ptr(arg_string, arg); break; case SPDK_TRACE_ARG_TYPE_INT: print_uint64(arg_string, arg); break; case SPDK_TRACE_ARG_TYPE_STR: print_string(arg_string, arg); break; } } static void print_event(struct spdk_trace_entry *e, uint64_t tsc_rate, uint64_t tsc_offset, uint16_t lcore) { struct spdk_trace_tpoint *d; struct object_stats *stats; float us; d = &g_histories->flags.tpoint[e->tpoint_id]; stats = &g_stats[d->object_type]; if (d->new_object) { stats->index[e->object_id] = stats->counter++; stats->tpoint_id[e->object_id] = e->tpoint_id; stats->start[e->object_id] = e->tsc; stats->size[e->object_id] = e->size; } us = get_us_from_tsc(e->tsc - tsc_offset, tsc_rate); printf("%2d: %10.3f ", lcore, us); if (g_print_tsc) { printf("(%9ju) ", e->tsc - tsc_offset); } if (g_histories->flags.owner[d->owner_type].id_prefix) { printf("%c%02d ", g_histories->flags.owner[d->owner_type].id_prefix, e->poller_id); } else { printf("%4s", " "); } printf("%-*s ", (int)sizeof(d->name), d->name); print_size(e->size); print_arg(d->arg1_type, d->arg1_name, e->arg1); if (d->new_object) { print_object_id(d->object_type, stats->index[e->object_id]); } else if (d->object_type != OBJECT_NONE) { if (stats->start.find(e->object_id) != stats->start.end()) { us = get_us_from_tsc(e->tsc - stats->start[e->object_id], tsc_rate); print_object_id(d->object_type, stats->index[e->object_id]); print_float("time:", us); } else { printf("id: N/A"); } } else if (e->object_id != 0) { print_arg(SPDK_TRACE_ARG_TYPE_PTR, "object: ", e->object_id); } printf("\n"); } static void process_event(struct spdk_trace_entry *e, uint64_t tsc_rate, uint64_t tsc_offset, uint16_t lcore) { if (g_verbose) { print_event(e, tsc_rate, tsc_offset, lcore); } } static int populate_events(struct spdk_trace_history *history, int num_entries) { int i, num_entries_filled; struct spdk_trace_entry *e; int first, last, lcore; lcore = history->lcore; e = history->entries; num_entries_filled = num_entries; while (e[num_entries_filled - 1].tsc == 0) { num_entries_filled--; } if (num_entries == num_entries_filled) { first = last = 0; for (i = 1; i < num_entries; i++) { if (e[i].tsc < e[first].tsc) { first = i; } if (e[i].tsc > e[last].tsc) { last = i; } } } else { first = 0; last = num_entries_filled - 1; } /* * We keep track of the highest first TSC out of all reactors. * We will ignore any events that occured before this TSC on any * other reactors. This will ensure we only print data for the * subset of time where we have data across all reactors. */ if (e[first].tsc > g_first_tsc) { g_first_tsc = e[first].tsc; } i = first; while (1) { g_entry_map[entry_key(lcore, e[i].tsc)] = &e[i]; if (i == last) { break; } i++; if (i == num_entries_filled) { i = 0; } } return (0); } static void usage(void) { fprintf(stderr, "usage:\n"); fprintf(stderr, " %s