mirror of
https://git.haproxy.org/git/haproxy.git/
synced 2025-08-06 15:17:01 +02:00
Introduce log_header struct to easily pass log header data between functions and use that to simplify the logic around log header handling. While at it, some outdated comments were updated as well. No change in behavior should be expected.
848 lines
29 KiB
C
848 lines
29 KiB
C
/*
|
|
* Runtime tracing API
|
|
*
|
|
* Copyright (C) 2000-2019 Willy Tarreau - w@1wt.eu
|
|
*
|
|
* This library is free software; you can redistribute it and/or
|
|
* modify it under the terms of the GNU Lesser General Public
|
|
* License as published by the Free Software Foundation, version 2.1
|
|
* exclusively.
|
|
*
|
|
* This library is distributed in the hope that it will be useful,
|
|
* but WITHOUT ANY WARRANTY; without even the implied warranty of
|
|
* MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU
|
|
* Lesser General Public License for more details.
|
|
*
|
|
* You should have received a copy of the GNU Lesser General Public
|
|
* License along with this library; if not, write to the Free Software
|
|
* Foundation, Inc., 51 Franklin Street, Fifth Floor, Boston, MA 02110-1301 USA
|
|
*/
|
|
|
|
#include <import/ist.h>
|
|
#include <haproxy/api.h>
|
|
#include <haproxy/buf.h>
|
|
#include <haproxy/cfgparse.h>
|
|
#include <haproxy/cli.h>
|
|
#include <haproxy/errors.h>
|
|
#include <haproxy/istbuf.h>
|
|
#include <haproxy/list.h>
|
|
#include <haproxy/log.h>
|
|
#include <haproxy/sink.h>
|
|
#include <haproxy/trace.h>
|
|
|
|
struct list trace_sources = LIST_HEAD_INIT(trace_sources);
|
|
THREAD_LOCAL struct buffer trace_buf = { };
|
|
|
|
/* allocates the trace buffers. Returns 0 in case of failure. It is safe to
|
|
* call to call this function multiple times if the size changes.
|
|
*/
|
|
static int alloc_trace_buffers_per_thread()
|
|
{
|
|
chunk_init(&trace_buf, my_realloc2(trace_buf.area, global.tune.bufsize), global.tune.bufsize);
|
|
return !!trace_buf.area;
|
|
}
|
|
|
|
static void free_trace_buffers_per_thread()
|
|
{
|
|
chunk_destroy(&trace_buf);
|
|
}
|
|
|
|
REGISTER_PER_THREAD_ALLOC(alloc_trace_buffers_per_thread);
|
|
REGISTER_PER_THREAD_FREE(free_trace_buffers_per_thread);
|
|
|
|
/* pick the lowest non-null argument with a non-null arg_def mask */
|
|
static inline const void *trace_pick_arg(uint32_t arg_def, const void *a1, const void *a2, const void *a3, const void *a4)
|
|
{
|
|
if (arg_def & 0x0000FFFF) {
|
|
if ((arg_def & 0x000000FF) && a1)
|
|
return a1;
|
|
if ((arg_def & 0x0000FF00) && a2)
|
|
return a2;
|
|
}
|
|
|
|
if (arg_def & 0xFFFF0000) {
|
|
if ((arg_def & 0x00FF0000) && a3)
|
|
return a3;
|
|
if ((arg_def & 0xFF000000) && a4)
|
|
return a4;
|
|
}
|
|
|
|
return NULL;
|
|
}
|
|
|
|
/* Reports whether the trace is enabled for the specified arguments, needs to enable
|
|
* or disable tracking. It gets the same API as __trace() except for <cb> and <msg>
|
|
* which are not used and were dropped, and plockptr which is an optional pointer to
|
|
* the lockptr to be updated (or NULL) for tracking. The function returns:
|
|
* 0 if the trace is not enabled for the module or these values
|
|
* <0 if the trace matches some locking criteria but don't have the proper level.
|
|
* In this case the interested caller might have to consider disabling tracking.
|
|
* >0 if the trace is enabled for the given criteria.
|
|
* In all cases, <plockptr> will only be set if non-null and if a locking criterion
|
|
* matched. It will be up to the caller to enable tracking if desired. A casual
|
|
* tester not interested in adjusting tracking (i.e. calling the function before
|
|
* deciding so prepare a buffer to be dumped) will only need to pass 0 for plockptr
|
|
* and check if the result is >0.
|
|
*/
|
|
int __trace_enabled(enum trace_level level, uint64_t mask, struct trace_source *src,
|
|
const struct ist where, const char *func,
|
|
const void *a1, const void *a2, const void *a3, const void *a4,
|
|
const void **plockptr)
|
|
{
|
|
const struct listener *li = NULL;
|
|
const struct proxy *fe = NULL;
|
|
const struct proxy *be = NULL;
|
|
const struct server *srv = NULL;
|
|
const struct session *sess = NULL;
|
|
const struct stream *strm = NULL;
|
|
const struct connection *conn = NULL;
|
|
const struct check *check = NULL;
|
|
const struct quic_conn *qc = NULL;
|
|
const struct appctx *appctx = NULL;
|
|
const void *lockon_ptr = NULL;
|
|
|
|
if (likely(src->state == TRACE_STATE_STOPPED))
|
|
return 0;
|
|
|
|
/* check that at least one action is interested by this event */
|
|
if (((src->report_events | src->start_events | src->pause_events | src->stop_events) & mask) == 0)
|
|
return 0;
|
|
|
|
/* retrieve available information from the caller's arguments */
|
|
if (src->arg_def & TRC_ARGS_CONN)
|
|
conn = trace_pick_arg(src->arg_def & TRC_ARGS_CONN, a1, a2, a3, a4);
|
|
|
|
if (src->arg_def & TRC_ARGS_SESS)
|
|
sess = trace_pick_arg(src->arg_def & TRC_ARGS_SESS, a1, a2, a3, a4);
|
|
|
|
if (src->arg_def & TRC_ARGS_STRM)
|
|
strm = trace_pick_arg(src->arg_def & TRC_ARGS_STRM, a1, a2, a3, a4);
|
|
|
|
if (src->arg_def & TRC_ARGS_CHK)
|
|
check = trace_pick_arg(src->arg_def & TRC_ARGS_CHK, a1, a2, a3, a4);
|
|
|
|
if (src->arg_def & TRC_ARGS_QCON)
|
|
qc = trace_pick_arg(src->arg_def & TRC_ARGS_QCON, a1, a2, a3, a4);
|
|
|
|
if (src->arg_def & TRC_ARGS_APPCTX)
|
|
appctx = trace_pick_arg(src->arg_def & TRC_ARGS_APPCTX, a1, a2, a3, a4);
|
|
|
|
if (!sess && strm)
|
|
sess = strm->sess;
|
|
else if (!sess && conn && LIST_INLIST(&conn->session_list))
|
|
sess = conn->owner;
|
|
else if (!sess && check)
|
|
sess = check->sess;
|
|
else if (!sess && appctx)
|
|
sess = appctx->sess;
|
|
|
|
if (sess) {
|
|
fe = sess->fe;
|
|
li = sess->listener;
|
|
}
|
|
|
|
if (!li && conn)
|
|
li = objt_listener(conn->target);
|
|
|
|
if (li && !fe)
|
|
fe = li->bind_conf->frontend;
|
|
|
|
if (strm) {
|
|
be = strm->be;
|
|
srv = strm->srv_conn;
|
|
}
|
|
if (check) {
|
|
srv = check->server;
|
|
be = (srv ? srv->proxy : NULL);
|
|
}
|
|
|
|
if (!srv && conn)
|
|
srv = objt_server(conn->target);
|
|
|
|
if (srv && !be)
|
|
be = srv->proxy;
|
|
|
|
if (!be && conn)
|
|
be = objt_proxy(conn->target);
|
|
|
|
/* TODO: add handling of filters here, return if no match (not even update states) */
|
|
|
|
/* check if we need to start the trace now */
|
|
if (src->state == TRACE_STATE_WAITING) {
|
|
if ((src->start_events & mask) == 0)
|
|
return 0;
|
|
|
|
/* TODO: add update of lockon+lockon_ptr here */
|
|
HA_ATOMIC_STORE(&src->state, TRACE_STATE_RUNNING);
|
|
}
|
|
|
|
/* we may want to lock on a particular object */
|
|
if (src->lockon != TRACE_LOCKON_NOTHING) {
|
|
switch (src->lockon) {
|
|
case TRACE_LOCKON_BACKEND: lockon_ptr = be; break;
|
|
case TRACE_LOCKON_CONNECTION: lockon_ptr = conn; break;
|
|
case TRACE_LOCKON_FRONTEND: lockon_ptr = fe; break;
|
|
case TRACE_LOCKON_LISTENER: lockon_ptr = li; break;
|
|
case TRACE_LOCKON_SERVER: lockon_ptr = srv; break;
|
|
case TRACE_LOCKON_SESSION: lockon_ptr = sess; break;
|
|
case TRACE_LOCKON_STREAM: lockon_ptr = strm; break;
|
|
case TRACE_LOCKON_CHECK: lockon_ptr = check; break;
|
|
case TRACE_LOCKON_THREAD: lockon_ptr = ti; break;
|
|
case TRACE_LOCKON_QCON: lockon_ptr = qc; break;
|
|
case TRACE_LOCKON_APPCTX: lockon_ptr = appctx; break;
|
|
case TRACE_LOCKON_ARG1: lockon_ptr = a1; break;
|
|
case TRACE_LOCKON_ARG2: lockon_ptr = a2; break;
|
|
case TRACE_LOCKON_ARG3: lockon_ptr = a3; break;
|
|
case TRACE_LOCKON_ARG4: lockon_ptr = a4; break;
|
|
default: break; // silence stupid gcc -Wswitch
|
|
}
|
|
|
|
if (src->lockon_ptr && src->lockon_ptr != lockon_ptr)
|
|
return 0;
|
|
|
|
if (*plockptr && !src->lockon_ptr && lockon_ptr && src->state == TRACE_STATE_RUNNING)
|
|
*plockptr = lockon_ptr;
|
|
}
|
|
|
|
/* here the trace is running and is tracking a desired item */
|
|
if ((src->report_events & mask) == 0 || level > src->level) {
|
|
/* tracking did match, and might have to be disabled */
|
|
return -1;
|
|
}
|
|
|
|
/* OK trace still enabled */
|
|
return 1;
|
|
}
|
|
|
|
/* write a message for the given trace source */
|
|
void __trace(enum trace_level level, uint64_t mask, struct trace_source *src,
|
|
const struct ist where, const char *func,
|
|
const void *a1, const void *a2, const void *a3, const void *a4,
|
|
void (*cb)(enum trace_level level, uint64_t mask, const struct trace_source *src,
|
|
const struct ist where, const struct ist func,
|
|
const void *a1, const void *a2, const void *a3, const void *a4),
|
|
const struct ist msg)
|
|
{
|
|
const void *lockon_ptr;
|
|
struct ist ist_func = ist(func);
|
|
char tnum[4];
|
|
struct ist line[12];
|
|
int words = 0;
|
|
int ret;
|
|
|
|
lockon_ptr = NULL;
|
|
ret = __trace_enabled(level, mask, src, where, func, a1, a2, a3, a4, &lockon_ptr);
|
|
if (lockon_ptr)
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, lockon_ptr);
|
|
|
|
if (ret <= 0) {
|
|
if (ret < 0) // may have to disable tracking
|
|
goto end;
|
|
return;
|
|
}
|
|
|
|
/* log the logging location truncated to 10 chars from the right so that
|
|
* the line number and the end of the file name are there.
|
|
*/
|
|
line[words++] = ist("[");
|
|
tnum[0] = '0' + tid / 10;
|
|
tnum[1] = '0' + tid % 10;
|
|
tnum[2] = '|';
|
|
tnum[3] = 0;
|
|
line[words++] = ist(tnum);
|
|
line[words++] = src->name;
|
|
line[words++] = ist("|");
|
|
line[words++] = ist2("012345" + level, 1); // "0" to "5"
|
|
line[words++] = ist("|");
|
|
line[words] = where;
|
|
if (line[words].len > 13) {
|
|
line[words].ptr += (line[words].len - 13);
|
|
line[words].len = 13;
|
|
}
|
|
words++;
|
|
line[words++] = ist("] ");
|
|
|
|
if (isttest(ist_func)) {
|
|
line[words++] = ist_func;
|
|
line[words++] = ist("(): ");
|
|
}
|
|
|
|
if (!cb)
|
|
cb = src->default_cb;
|
|
|
|
if (cb && src->verbosity) {
|
|
/* decode function passed, we want to pre-fill the
|
|
* buffer with the message and let the decode function
|
|
* do its job, possibly even overwriting it.
|
|
*/
|
|
b_reset(&trace_buf);
|
|
b_istput(&trace_buf, msg);
|
|
cb(level, mask, src, where, ist_func, a1, a2, a3, a4);
|
|
line[words] = ist2(trace_buf.area, trace_buf.data);
|
|
words++;
|
|
}
|
|
else {
|
|
/* Note that here we could decide to print some args whose type
|
|
* is known, when verbosity is above the quiet level, and even
|
|
* to print the name and values of those which are declared for
|
|
* lock-on.
|
|
*/
|
|
line[words++] = msg;
|
|
}
|
|
|
|
if (src->sink)
|
|
sink_write(src->sink, LOG_HEADER_NONE, 0, line, words);
|
|
|
|
end:
|
|
/* check if we need to stop the trace now */
|
|
if ((src->stop_events & mask) != 0) {
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
HA_ATOMIC_STORE(&src->state, TRACE_STATE_STOPPED);
|
|
}
|
|
else if ((src->pause_events & mask) != 0) {
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
HA_ATOMIC_STORE(&src->state, TRACE_STATE_WAITING);
|
|
}
|
|
}
|
|
|
|
/* this callback may be used when no output modification is desired */
|
|
void trace_no_cb(enum trace_level level, uint64_t mask, const struct trace_source *src,
|
|
const struct ist where, const struct ist func,
|
|
const void *a1, const void *a2, const void *a3, const void *a4)
|
|
{
|
|
/* do nothing */
|
|
}
|
|
|
|
/* registers trace source <source>. Modifies the list element!
|
|
* The {start,pause,stop,report} events are not changed so the source may
|
|
* preset them.
|
|
*/
|
|
void trace_register_source(struct trace_source *source)
|
|
{
|
|
source->lockon = TRACE_LOCKON_NOTHING;
|
|
source->level = TRACE_LEVEL_USER;
|
|
source->verbosity = 1;
|
|
source->sink = NULL;
|
|
source->state = TRACE_STATE_STOPPED;
|
|
source->lockon_ptr = NULL;
|
|
LIST_APPEND(&trace_sources, &source->source_link);
|
|
}
|
|
|
|
struct trace_source *trace_find_source(const char *name)
|
|
{
|
|
struct trace_source *src;
|
|
const struct ist iname = ist(name);
|
|
|
|
list_for_each_entry(src, &trace_sources, source_link)
|
|
if (isteq(src->name, iname))
|
|
return src;
|
|
return NULL;
|
|
}
|
|
|
|
const struct trace_event *trace_find_event(const struct trace_event *ev, const char *name)
|
|
{
|
|
for (; ev && ev->mask; ev++)
|
|
if (strcmp(ev->name, name) == 0)
|
|
return ev;
|
|
return NULL;
|
|
}
|
|
|
|
/* Parse a "trace" statement. Returns a severity as a LOG_* level and a status
|
|
* message that may be delivered to the user, in <msg>. The message will be
|
|
* nulled first and msg must be a valid pointer. A null status message output
|
|
* indicates no error. Be careful not to use the return value as a boolean, as
|
|
* LOG_* values are not ordered as one could imagine (LOG_EMERG is zero). The
|
|
* function may/will use the trash buffer as the storage for the response
|
|
* message so that the caller never needs to release anything.
|
|
*/
|
|
static int trace_parse_statement(char **args, const char **msg)
|
|
{
|
|
struct trace_source *src;
|
|
uint64_t *ev_ptr = NULL;
|
|
|
|
/* no error by default */
|
|
*msg = NULL;
|
|
|
|
if (!*args[1]) {
|
|
/* no arg => report the list of supported sources as a warning */
|
|
chunk_printf(&trash,
|
|
"Supported trace sources and states (.=stopped, w=waiting, R=running) :\n"
|
|
" [.] 0 : not a source, will immediately stop all traces\n"
|
|
);
|
|
|
|
list_for_each_entry(src, &trace_sources, source_link)
|
|
chunk_appendf(&trash, " [%c] %-10s : %s\n", trace_state_char(src->state), src->name.ptr, src->desc);
|
|
|
|
trash.area[trash.data] = 0;
|
|
*msg = trash.area;
|
|
return LOG_WARNING;
|
|
}
|
|
|
|
if (strcmp(args[1], "0") == 0) {
|
|
/* emergency stop of all traces */
|
|
list_for_each_entry(src, &trace_sources, source_link)
|
|
HA_ATOMIC_STORE(&src->state, TRACE_STATE_STOPPED);
|
|
*msg = "All traces now stopped";
|
|
return LOG_NOTICE;
|
|
}
|
|
|
|
src = trace_find_source(args[1]);
|
|
if (!src) {
|
|
*msg = "No such trace source";
|
|
return LOG_ERR;
|
|
}
|
|
|
|
if (!*args[2]) {
|
|
*msg = "Supported commands:\n"
|
|
" event : list/enable/disable source-specific event reporting\n"
|
|
//" filter : list/enable/disable generic filters\n"
|
|
" level : list/set trace reporting level\n"
|
|
" lock : automatic lock on thread/connection/stream/...\n"
|
|
" pause : pause and automatically restart after a specific event\n"
|
|
" sink : list/set event sinks\n"
|
|
" start : start immediately or after a specific event\n"
|
|
" stop : stop immediately or after a specific event\n"
|
|
" verbosity : list/set trace output verbosity\n";
|
|
return LOG_WARNING;
|
|
}
|
|
else if ((strcmp(args[2], "event") == 0 && (ev_ptr = &src->report_events)) ||
|
|
(strcmp(args[2], "pause") == 0 && (ev_ptr = &src->pause_events)) ||
|
|
(strcmp(args[2], "start") == 0 && (ev_ptr = &src->start_events)) ||
|
|
(strcmp(args[2], "stop") == 0 && (ev_ptr = &src->stop_events))) {
|
|
const struct trace_event *ev;
|
|
const char *name = args[3];
|
|
int neg = 0;
|
|
int i;
|
|
|
|
/* skip prefix '!', '-', '+' and remind negation */
|
|
while (*name) {
|
|
if (*name == '!' || *name == '-')
|
|
neg = 1;
|
|
else if (*name == '+')
|
|
neg = 0;
|
|
else
|
|
break;
|
|
name++;
|
|
}
|
|
|
|
if (!*name) {
|
|
chunk_printf(&trash, "Supported events for source %s (+=enabled, -=disabled):\n", src->name.ptr);
|
|
if (ev_ptr != &src->report_events)
|
|
chunk_appendf(&trash, " - now : don't wait for events, immediately change the state\n");
|
|
chunk_appendf(&trash, " - none : disable all event types\n");
|
|
chunk_appendf(&trash, " - any : enable all event types\n");
|
|
for (i = 0; src->known_events && src->known_events[i].mask; i++) {
|
|
chunk_appendf(&trash, " %c %-12s : %s\n",
|
|
trace_event_char(*ev_ptr, src->known_events[i].mask),
|
|
src->known_events[i].name, src->known_events[i].desc);
|
|
}
|
|
trash.area[trash.data] = 0;
|
|
*msg = trash.area;
|
|
return LOG_WARNING;
|
|
}
|
|
|
|
if (strcmp(name, "now") == 0 && ev_ptr != &src->report_events) {
|
|
HA_ATOMIC_STORE(ev_ptr, 0);
|
|
if (ev_ptr == &src->pause_events) {
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
HA_ATOMIC_STORE(&src->state, TRACE_STATE_WAITING);
|
|
}
|
|
else if (ev_ptr == &src->start_events) {
|
|
HA_ATOMIC_STORE(&src->state, TRACE_STATE_RUNNING);
|
|
}
|
|
else if (ev_ptr == &src->stop_events) {
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
HA_ATOMIC_STORE(&src->state, TRACE_STATE_STOPPED);
|
|
}
|
|
*msg = NULL;
|
|
return 0;
|
|
}
|
|
|
|
if (strcmp(name, "none") == 0)
|
|
HA_ATOMIC_STORE(ev_ptr, 0);
|
|
else if (strcmp(name, "any") == 0)
|
|
HA_ATOMIC_STORE(ev_ptr, ~0);
|
|
else {
|
|
ev = trace_find_event(src->known_events, name);
|
|
if (!ev) {
|
|
*msg = "No such trace event";
|
|
return LOG_ERR;
|
|
}
|
|
|
|
if (!neg)
|
|
HA_ATOMIC_OR(ev_ptr, ev->mask);
|
|
else
|
|
HA_ATOMIC_AND(ev_ptr, ~ev->mask);
|
|
}
|
|
}
|
|
else if (strcmp(args[2], "sink") == 0) {
|
|
const char *name = args[3];
|
|
struct sink *sink;
|
|
|
|
if (!*name) {
|
|
chunk_printf(&trash, "Supported sinks for source %s (*=current):\n", src->name.ptr);
|
|
chunk_appendf(&trash, " %c none : no sink\n", src->sink ? ' ' : '*');
|
|
list_for_each_entry(sink, &sink_list, sink_list) {
|
|
chunk_appendf(&trash, " %c %-10s : %s\n",
|
|
src->sink == sink ? '*' : ' ',
|
|
sink->name, sink->desc);
|
|
}
|
|
trash.area[trash.data] = 0;
|
|
*msg = trash.area;
|
|
return LOG_WARNING;
|
|
}
|
|
|
|
if (strcmp(name, "none") == 0)
|
|
sink = NULL;
|
|
else {
|
|
sink = sink_find(name);
|
|
if (!sink) {
|
|
*msg = "No such sink";
|
|
return LOG_ERR;
|
|
}
|
|
}
|
|
|
|
HA_ATOMIC_STORE(&src->sink, sink);
|
|
}
|
|
else if (strcmp(args[2], "level") == 0) {
|
|
const char *name = args[3];
|
|
|
|
if (!*name) {
|
|
chunk_printf(&trash, "Supported trace levels for source %s:\n", src->name.ptr);
|
|
chunk_appendf(&trash, " %c error : report errors\n",
|
|
src->level == TRACE_LEVEL_ERROR ? '*' : ' ');
|
|
chunk_appendf(&trash, " %c user : also information useful to the end user\n",
|
|
src->level == TRACE_LEVEL_USER ? '*' : ' ');
|
|
chunk_appendf(&trash, " %c proto : also protocol-level updates\n",
|
|
src->level == TRACE_LEVEL_PROTO ? '*' : ' ');
|
|
chunk_appendf(&trash, " %c state : also report internal state changes\n",
|
|
src->level == TRACE_LEVEL_STATE ? '*' : ' ');
|
|
chunk_appendf(&trash, " %c data : also report data transfers\n",
|
|
src->level == TRACE_LEVEL_DATA ? '*' : ' ');
|
|
chunk_appendf(&trash, " %c developer : also report information useful only to the developer\n",
|
|
src->level == TRACE_LEVEL_DEVELOPER ? '*' : ' ');
|
|
trash.area[trash.data] = 0;
|
|
*msg = trash.area;
|
|
return LOG_WARNING;
|
|
}
|
|
|
|
if (strcmp(name, "error") == 0)
|
|
HA_ATOMIC_STORE(&src->level, TRACE_LEVEL_ERROR);
|
|
else if (strcmp(name, "user") == 0)
|
|
HA_ATOMIC_STORE(&src->level, TRACE_LEVEL_USER);
|
|
else if (strcmp(name, "proto") == 0)
|
|
HA_ATOMIC_STORE(&src->level, TRACE_LEVEL_PROTO);
|
|
else if (strcmp(name, "state") == 0)
|
|
HA_ATOMIC_STORE(&src->level, TRACE_LEVEL_STATE);
|
|
else if (strcmp(name, "data") == 0)
|
|
HA_ATOMIC_STORE(&src->level, TRACE_LEVEL_DATA);
|
|
else if (strcmp(name, "developer") == 0)
|
|
HA_ATOMIC_STORE(&src->level, TRACE_LEVEL_DEVELOPER);
|
|
else {
|
|
*msg = "No such trace level";
|
|
return LOG_ERR;
|
|
}
|
|
}
|
|
else if (strcmp(args[2], "lock") == 0) {
|
|
const char *name = args[3];
|
|
|
|
if (!*name) {
|
|
chunk_printf(&trash, "Supported lock-on criteria for source %s:\n", src->name.ptr);
|
|
if (src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_STRM))
|
|
chunk_appendf(&trash, " %c backend : lock on the backend that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_BACKEND ? '*' : ' ');
|
|
|
|
if (src->arg_def & TRC_ARGS_CHK)
|
|
chunk_appendf(&trash, " %c check : lock on the check that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_CHECK ? '*' : ' ');
|
|
|
|
if (src->arg_def & TRC_ARGS_CONN)
|
|
chunk_appendf(&trash, " %c connection : lock on the connection that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_CONNECTION ? '*' : ' ');
|
|
|
|
if (src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_SESS|TRC_ARGS_STRM))
|
|
chunk_appendf(&trash, " %c frontend : lock on the frontend that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_FRONTEND ? '*' : ' ');
|
|
|
|
if (src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_SESS|TRC_ARGS_STRM))
|
|
chunk_appendf(&trash, " %c listener : lock on the listener that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_LISTENER ? '*' : ' ');
|
|
|
|
chunk_appendf(&trash, " %c nothing : do not lock on anything\n",
|
|
src->lockon == TRACE_LOCKON_NOTHING ? '*' : ' ');
|
|
|
|
if (src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_STRM))
|
|
chunk_appendf(&trash, " %c server : lock on the server that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_SERVER ? '*' : ' ');
|
|
|
|
if (src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_SESS|TRC_ARGS_STRM))
|
|
chunk_appendf(&trash, " %c session : lock on the session that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_SESSION ? '*' : ' ');
|
|
|
|
if (src->arg_def & TRC_ARGS_STRM)
|
|
chunk_appendf(&trash, " %c stream : lock on the stream that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_STREAM ? '*' : ' ');
|
|
|
|
if (src->arg_def & TRC_ARGS_APPCTX)
|
|
chunk_appendf(&trash, " %c applet : lock on the applet that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_APPCTX ? '*' : ' ');
|
|
|
|
chunk_appendf(&trash, " %c thread : lock on the thread that started the trace\n",
|
|
src->lockon == TRACE_LOCKON_THREAD ? '*' : ' ');
|
|
|
|
if (src->lockon_args && src->lockon_args[0].name)
|
|
chunk_appendf(&trash, " %c %-10s : %s\n",
|
|
src->lockon == TRACE_LOCKON_ARG1 ? '*' : ' ',
|
|
src->lockon_args[0].name, src->lockon_args[0].desc);
|
|
|
|
if (src->lockon_args && src->lockon_args[1].name)
|
|
chunk_appendf(&trash, " %c %-10s : %s\n",
|
|
src->lockon == TRACE_LOCKON_ARG2 ? '*' : ' ',
|
|
src->lockon_args[1].name, src->lockon_args[1].desc);
|
|
|
|
if (src->lockon_args && src->lockon_args[2].name)
|
|
chunk_appendf(&trash, " %c %-10s : %s\n",
|
|
src->lockon == TRACE_LOCKON_ARG3 ? '*' : ' ',
|
|
src->lockon_args[2].name, src->lockon_args[2].desc);
|
|
|
|
if (src->lockon_args && src->lockon_args[3].name)
|
|
chunk_appendf(&trash, " %c %-10s : %s\n",
|
|
src->lockon == TRACE_LOCKON_ARG4 ? '*' : ' ',
|
|
src->lockon_args[3].name, src->lockon_args[3].desc);
|
|
|
|
trash.area[trash.data] = 0;
|
|
*msg = trash.area;
|
|
return LOG_WARNING;
|
|
}
|
|
else if ((src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_STRM)) && strcmp(name, "backend") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_BACKEND);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if ((src->arg_def & TRC_ARGS_CHK) && strcmp(name, "check") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_CHECK);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if ((src->arg_def & TRC_ARGS_CONN) && strcmp(name, "connection") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_CONNECTION);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if ((src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_SESS|TRC_ARGS_STRM)) && strcmp(name, "frontend") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_FRONTEND);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if ((src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_SESS|TRC_ARGS_STRM)) && strcmp(name, "listener") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_LISTENER);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if (strcmp(name, "nothing") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_NOTHING);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if ((src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_STRM)) && strcmp(name, "server") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_SERVER);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if ((src->arg_def & (TRC_ARGS_CONN|TRC_ARGS_SESS|TRC_ARGS_STRM)) && strcmp(name, "session") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_SESSION);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if ((src->arg_def & TRC_ARGS_STRM) && strcmp(name, "stream") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_STREAM);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if ((src->arg_def & TRC_ARGS_APPCTX) && strcmp(name, "appctx") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_APPCTX);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if (strcmp(name, "thread") == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_THREAD);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if (src->lockon_args && src->lockon_args[0].name && strcmp(name, src->lockon_args[0].name) == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_ARG1);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if (src->lockon_args && src->lockon_args[1].name && strcmp(name, src->lockon_args[1].name) == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_ARG2);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if (src->lockon_args && src->lockon_args[2].name && strcmp(name, src->lockon_args[2].name) == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_ARG3);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else if (src->lockon_args && src->lockon_args[3].name && strcmp(name, src->lockon_args[3].name) == 0) {
|
|
HA_ATOMIC_STORE(&src->lockon, TRACE_LOCKON_ARG4);
|
|
HA_ATOMIC_STORE(&src->lockon_ptr, NULL);
|
|
}
|
|
else {
|
|
*msg = "Unsupported lock-on criterion";
|
|
return LOG_ERR;
|
|
}
|
|
}
|
|
else if (strcmp(args[2], "verbosity") == 0) {
|
|
const char *name = args[3];
|
|
const struct name_desc *nd;
|
|
|
|
if (!*name) {
|
|
chunk_printf(&trash, "Supported trace verbosities for source %s:\n", src->name.ptr);
|
|
chunk_appendf(&trash, " %c quiet : only report basic information with no decoding\n",
|
|
src->verbosity == 0 ? '*' : ' ');
|
|
if (!src->decoding || !src->decoding[0].name) {
|
|
chunk_appendf(&trash, " %c default : report extra information when available\n",
|
|
src->verbosity > 0 ? '*' : ' ');
|
|
} else {
|
|
for (nd = src->decoding; nd->name && nd->desc; nd++)
|
|
chunk_appendf(&trash, " %c %-10s : %s\n",
|
|
nd == (src->decoding + src->verbosity - 1) ? '*' : ' ',
|
|
nd->name, nd->desc);
|
|
}
|
|
trash.area[trash.data] = 0;
|
|
*msg = trash.area;
|
|
return LOG_WARNING;
|
|
}
|
|
|
|
if (strcmp(name, "quiet") == 0)
|
|
HA_ATOMIC_STORE(&src->verbosity, 0);
|
|
else if (!src->decoding || !src->decoding[0].name) {
|
|
if (strcmp(name, "default") == 0)
|
|
HA_ATOMIC_STORE(&src->verbosity, 1);
|
|
else {
|
|
*msg = "No such verbosity level";
|
|
return LOG_ERR;
|
|
}
|
|
} else {
|
|
for (nd = src->decoding; nd->name && nd->desc; nd++)
|
|
if (strcmp(name, nd->name) == 0)
|
|
break;
|
|
|
|
if (!nd->name || !nd->desc) {
|
|
*msg = "No such verbosiry level";
|
|
return LOG_ERR;
|
|
}
|
|
|
|
HA_ATOMIC_STORE(&src->verbosity, (nd - src->decoding) + 1);
|
|
}
|
|
}
|
|
else {
|
|
*msg = "Unknown trace keyword";
|
|
return LOG_ERR;
|
|
}
|
|
return 0;
|
|
|
|
}
|
|
|
|
/* parse a "trace" statement in the "global" section, returns 1 if a message is returned, otherwise zero */
|
|
static int cfg_parse_trace(char **args, int section_type, struct proxy *curpx,
|
|
const struct proxy *defpx, const char *file, int line,
|
|
char **err)
|
|
{
|
|
const char *msg;
|
|
int severity;
|
|
|
|
severity = trace_parse_statement(args, &msg);
|
|
if (msg) {
|
|
if (severity >= LOG_NOTICE)
|
|
ha_notice("parsing [%s:%d] : '%s': %s\n", file, line, args[0], msg);
|
|
else if (severity >= LOG_WARNING)
|
|
ha_warning("parsing [%s:%d] : '%s': %s\n", file, line, args[0], msg);
|
|
else {
|
|
ha_alert("parsing [%s:%d] : '%s': %s\n", file, line, args[0], msg);
|
|
return -1;
|
|
}
|
|
}
|
|
return 0;
|
|
}
|
|
|
|
/* parse the command, returns 1 if a message is returned, otherwise zero */
|
|
static int cli_parse_trace(char **args, char *payload, struct appctx *appctx, void *private)
|
|
{
|
|
const char *msg;
|
|
int severity;
|
|
|
|
if (!cli_has_level(appctx, ACCESS_LVL_OPER))
|
|
return 1;
|
|
|
|
severity = trace_parse_statement(args, &msg);
|
|
if (msg)
|
|
return cli_msg(appctx, severity, msg);
|
|
|
|
/* total success */
|
|
return 0;
|
|
}
|
|
|
|
/* parse the command, returns 1 if a message is returned, otherwise zero */
|
|
static int cli_parse_show_trace(char **args, char *payload, struct appctx *appctx, void *private)
|
|
{
|
|
struct trace_source *src;
|
|
const struct sink *sink;
|
|
int i;
|
|
|
|
args++; // make args[1] the 1st arg
|
|
|
|
if (!*args[1]) {
|
|
/* no arg => report the list of supported sources */
|
|
chunk_printf(&trash,
|
|
"Supported trace sources and states (.=stopped, w=waiting, R=running) :\n"
|
|
);
|
|
|
|
list_for_each_entry(src, &trace_sources, source_link) {
|
|
sink = src->sink;
|
|
chunk_appendf(&trash, " [%c] %-10s -> %s [drp %u] [%s]\n",
|
|
trace_state_char(src->state), src->name.ptr,
|
|
sink ? sink->name : "none",
|
|
sink ? sink->ctx.dropped : 0,
|
|
src->desc);
|
|
}
|
|
|
|
trash.area[trash.data] = 0;
|
|
return cli_msg(appctx, LOG_INFO, trash.area);
|
|
}
|
|
|
|
if (!cli_has_level(appctx, ACCESS_LVL_OPER))
|
|
return 1;
|
|
|
|
src = trace_find_source(args[1]);
|
|
if (!src)
|
|
return cli_err(appctx, "No such trace source");
|
|
|
|
sink = src->sink;
|
|
chunk_printf(&trash, "Trace status for %s:\n", src->name.ptr);
|
|
chunk_appendf(&trash, " - sink: %s [%u dropped]\n",
|
|
sink ? sink->name : "none", sink ? sink->ctx.dropped : 0);
|
|
|
|
chunk_appendf(&trash, " - event name : report start stop pause\n");
|
|
for (i = 0; src->known_events && src->known_events[i].mask; i++) {
|
|
chunk_appendf(&trash, " %-12s : %c %c %c %c\n",
|
|
src->known_events[i].name,
|
|
trace_event_char(src->report_events, src->known_events[i].mask),
|
|
trace_event_char(src->start_events, src->known_events[i].mask),
|
|
trace_event_char(src->stop_events, src->known_events[i].mask),
|
|
trace_event_char(src->pause_events, src->known_events[i].mask));
|
|
}
|
|
|
|
trash.area[trash.data] = 0;
|
|
return cli_msg(appctx, LOG_WARNING, trash.area);
|
|
}
|
|
|
|
static struct cli_kw_list cli_kws = {{ },{
|
|
{ { "trace", NULL }, "trace [<module>|0] [cmd [args...]] : manage live tracing (empty to list, 0 to stop all)", cli_parse_trace, NULL, NULL },
|
|
{ { "show", "trace", NULL }, "show trace [<module>] : show live tracing state", cli_parse_show_trace, NULL, NULL },
|
|
{{},}
|
|
}};
|
|
|
|
INITCALL1(STG_REGISTER, cli_register_kw, &cli_kws);
|
|
|
|
static struct cfg_kw_list cfg_kws = {ILH, {
|
|
{ CFG_GLOBAL, "trace", cfg_parse_trace, KWF_EXPERIMENTAL },
|
|
{ /* END */ },
|
|
}};
|
|
|
|
INITCALL1(STG_REGISTER, cfg_register_keywords, &cfg_kws);
|
|
|
|
/*
|
|
* Local variables:
|
|
* c-indent-level: 8
|
|
* c-basic-offset: 8
|
|
* End:
|
|
*/
|