Scope Trace: Add some new tracing macros and an ast_str helper

Created new SCOPE_ functions that don't depend on RAII_VAR.  Besides
generating less code, the use of the explicit SCOPE_EXIT macros
capture the line number where the scope exited.  The RAII_VAR
versions can't do that.

 * SCOPE_ENTER(level, ...): Like SCOPE_TRACE but doesn't use
   RAII_VAR and therefore needs needs one of...

 * SCOPE_EXIT(...): Decrements the trace stack counter and optionally
   prints a message.

 * SCOPE_EXIT_EXPR(__expr, ...): Decrements the trace stack counter,
   optionally prints a message, then executes the expression.
   SCOPE_EXIT_EXPR(break, "My while got broken\n");

 * SCOPE_EXIT_RTN(, ...): Decrements the trace stack counter,
   optionally prints a message, then returns without a value.
   SCOPE_EXIT_RTN("Bye\n");

 * SCOPE_EXIT_RTN_VALUE(__return_value, ...): Decrements the trace
   stack counter, optionally prints a message, then returns the value
   specified.
   SCOPE_EXIT_RTN_VALUE(rc, "Returning with RC: %d\n", rc);

Create an ast_str helper ast_str_tmp() that allocates a temporary
ast_str that can be passed to a function that needs it, then frees
it.  This makes using the above macros easier.  Example:

   SCOPE_ENTER(1, Format Caps 1: %s  Format Caps 2: %s\n",
       ast_str_tmp(32, ast_format_cap_get_names(cap1, &STR_TMP),
       ast_str_tmp(32, ast_format_cap_get_names(cap2, &STR_TMP));

The calls to ast_str_tmp create an ast_str of the specified initial
length which can be referenced as STR_TMP.  It then calls the
expression, which must return a char *, ast_strdupa's it, frees
STR_TMP, then returns the ast_strdupa'd string.  That string is
freed when the function returns.

Change-Id: I44059b20d55a889aa91440d2f8a590865998be51
This commit is contained in:
George Joseph 2020-06-30 07:56:34 -06:00
parent 4f86118bd8
commit 7440fd0397
4 changed files with 232 additions and 24 deletions

View File

@ -715,10 +715,107 @@ void __attribute__((format (printf, 5, 6))) __ast_trace(const char *file, int li
} \
int __scopevar ## __LINE__ ## __RETURN __attribute__((unused)) = __scopevar ## __LINE__ ## __ENTER()
/*!
* \brief Non RAII_VAR Scope Trace macros
* The advantage of these macros is that the EXITs will have the actual
* line number where the scope exited. Much less code is required as well.
*/
/*!
* \brief Scope Enter
*
* \param level The trace level
* \param (optional) A printf style format string
* \param (optional) Arguments
*/
#define SCOPE_ENTER(level, ...) \
int __scope_level = level; \
if (TRACE_ATLEAST(level)) { \
__ast_trace(__FILE__, __LINE__, __PRETTY_FUNCTION__, AST_TRACE_INDENT_INC_AFTER, " " __VA_ARGS__); \
} \
/*!
* \brief Scope Exit
*
* \param (optional) A printf style format string
* \param (optional) Arguments
*
* \details
* This macro can be used at the exit points of a statement block since it just prints the message.
*/
#define SCOPE_EXIT(...) \
if (TRACE_ATLEAST(__scope_level)) { \
__ast_trace(__FILE__, __LINE__, __PRETTY_FUNCTION__, AST_TRACE_INDENT_DEC_BEFORE, " " __VA_ARGS__); \
} \
/*!
* \brief Scope Exit with expression
*
* \param __expr An expression to execute after printing the message
* \param (optional) A printf style format string
* \param (optional) Arguments
*
* \details
* Handy for getting out of or continuing loops.
*
* \example
* while(something) {
* SCOPE_ENTER(2, "In a while\n");
* if (something) {
* SCOPE_EXIT_EXPR(break, "Somethiung broke me\n");
* } else {
* SCOPE_EXIT_EXPR(contniue, "Somethiung continued me\n");
* }
* }
*/
#define SCOPE_EXIT_EXPR(__expr, ...) \
if (TRACE_ATLEAST(__scope_level)) { \
__ast_trace(__FILE__, __LINE__, __PRETTY_FUNCTION__, AST_TRACE_INDENT_DEC_BEFORE, " " __VA_ARGS__); \
} \
__expr
/*!
* \brief Scope Exit with return
*
* \param (optional) A printf style format string
* \param (optional) Arguments
*
* \details
* This macro can be used at the exit points of a function when no value
* needs to be returned.
*/
#define SCOPE_EXIT_RTN(...) \
if (TRACE_ATLEAST(__scope_level)) { \
__ast_trace(__FILE__, __LINE__, __PRETTY_FUNCTION__, AST_TRACE_INDENT_DEC_BEFORE, " " __VA_ARGS__); \
} \
return
/*!
* \brief Scope Exit with return value
*
* \param __return_value The return value
* \param (optional) A printf style format string
* \param (optional) Arguments
*
* \details
* This macro can be used at the exit points of a function when a value
* needs to be returned.
*/
#define SCOPE_EXIT_RTN_VALUE(__return_value, ...) \
if (TRACE_ATLEAST(__scope_level)) { \
__ast_trace(__FILE__, __LINE__, __PRETTY_FUNCTION__, AST_TRACE_INDENT_DEC_BEFORE, " " __VA_ARGS__); \
} \
return(__return_value)
#else
#define ast_trace_raw(__level, __indent_type, __fmt, ...)
#define ast_trace(__level)
#define SCOPE_TRACE(__level)
#define ast_trace_raw(__level, __indent_type, ...)
#define ast_trace(__level, ...)
#define SCOPE_TRACE(__level, ...)
#define SCOPE_ENTER(level, ...)
#define SCOPE_EXIT(...)
#define SCOPE_EXIT_EXPR(__expr, ...)
#define SCOPE_EXIT_RTN(...)
#define SCOPE_EXIT_RTN_VALUE(__return_value, ...)
#endif
#if defined(__cplusplus) || defined(c_plusplus)

View File

@ -1090,6 +1090,59 @@ int __attribute__((format(printf, 3, 4))) ast_str_append(
}
)
/*!
* \brief Provides a temporary ast_str and returns a copy of its buffer
* \since 16.12
* \since 17.6
* \since 18.0
*
* \param init_len The initial length of the temporary ast_str needed.
* \param __expr An expression that needs the temporary ast_str and returns a char *.
*
* \returns A copy of __expr's return buffer allocated on the stack.
*
* \details
* There are a few query functions scattered around that need an ast_str in which
* to assemble the results but it's not always convenient to create an ast_str
* and ensure it's freed just to print a log message. For example...
*
* struct ast_str *temp = ast_str_create(128);
* ast_log(LOG_INFO, "Format caps: %s\n", ast_format_cap_get_names(caps, &temp));
* ast_free(temp);
*
* That's not bad if you only have to do it once but some of our code that deals
* with streams and codecs is pretty complex and good instrumentation is essential.
* The aim of this function is to make that easier.
*
* With this macro, the above code can be simplified as follows...
* \example
* ast_log(LOG_INFO, "Format caps: %s\n",
* ast_str_tmp(128, ast_format_cap_get_names(caps, &STR_TMP));
*
* STR_TMP will always be a reference to the temporary ast_str created
* by the macro. Its scope is limited by the macro so you can use it multiple
* times without conflict.
*
* \example
* ast_log(LOG_INFO, "Format caps in: %s Format caps out: %s\n",
* ast_str_tmp(128, ast_format_cap_get_names(caps_in, &STR_TMP),
* ast_str_tmp(128, ast_format_cap_get_names(caps_out, &STR_TMP)
* );
*
* \warning
* The returned string is stack allocated so don't go overboard.
*
*/
#define ast_str_tmp(init_len, __expr) \
({ \
struct ast_str *STR_TMP = ast_str_create(init_len); \
char *ret = ast_strdupa(__expr); \
ast_free(STR_TMP); \
ret; \
})
/*!
* \brief Check if a string is only digits
*

View File

@ -38,58 +38,77 @@
#include "asterisk/test.h"
#include "asterisk/logger.h"
static void test_scope2(void)
static const char *str_appender(struct ast_str**buf, char *a)
{
SCOPE_TRACE(1);
ast_str_append(buf, 0, "<append %s>", a);
return ast_str_buffer(*buf);
}
static void test_scope(void)
static void test_scope_trace(void)
{
SCOPE_TRACE(1, "nested function: %d * %d = %d\n", 6, 7, (6 * 7));
SCOPE_ENTER(1, "subfunction\n");
SCOPE_EXIT_RTN("got out\n");
}
test_scope2();
static int test_scope_enter_function(void)
{
SCOPE_ENTER(1, "%s %s %s %s %s %s %s\n",
ast_str_tmp(12, str_appender(&STR_TMP, "str1")),
ast_str_tmp(12, str_appender(&STR_TMP, "str2")),
ast_str_tmp(32, str_appender(&STR_TMP, "AAAAAAAAAAAAAAAAAAAAAAAA")),
ast_str_tmp(12, str_appender(&STR_TMP, "B")),
"ccccccccccccc",
ast_str_tmp(12, str_appender(&STR_TMP, "DDDDD")),
ast_str_tmp(12, str_appender(&STR_TMP, "ww"))
);
ast_trace(1, "test no variables\n");
test_scope_trace();
SCOPE_EXIT_RTN_VALUE(AST_TEST_PASS, "test no variables\n");
}
AST_TEST_DEFINE(scope_test)
{
SCOPE_TRACE(1, "top %s function\n", "scope_test");
SCOPE_ENTER(1, "top %s function\n", "scope_test");
ast_trace(1, "%s\n", "test outer");
switch (cmd) {
case TEST_INIT:
{
SCOPE_ENTER(1, "TEST_INIT\n");
info->name = "scope_test";
info->category = "/main/logging/";
info->summary = "Scope Trace Tests";
info->description = "Scope Trace Tests";
return AST_TEST_NOT_RUN;
/* need to exit the case scope */
SCOPE_EXIT("TEST_INIT\n");
/* need to exit the function */
SCOPE_EXIT_RTN_VALUE(AST_TEST_NOT_RUN, "BYE\n");
}
case TEST_EXECUTE:
{
SCOPE_TRACE(1, "CASE statement\n");
ast_trace(1, "%s\n", "test case");
}
break;
{
SCOPE_ENTER(1, "TEST_EXECUTE\n");
ast_trace(1, "%s\n", "test execute");
SCOPE_EXIT_EXPR(break, "TEST_EXECUTE\n");
}
default:
ast_test_status_update(test, "Shouldn't have gotten here\n");
return AST_TEST_FAIL;
}
if (1) {
SCOPE_TRACE(1, "IF block\n");
test_scope();
test_scope_enter_function();
}
ast_trace(1);
ast_trace(1, "test no variables\n");
ast_trace(1, "%s\n", "test variable");
return AST_TEST_PASS;
SCOPE_EXIT_RTN_VALUE(AST_TEST_PASS, "Something: %d\n", AST_TEST_PASS);
}
static int unload_module(void)

View File

@ -583,6 +583,43 @@ AST_TEST_DEFINE(strings_match)
return AST_TEST_PASS;
}
/*!
* \brief Function that needs a temporary ast_str
*/
static const char *str_appender(struct ast_str**buf, char *a)
{
ast_str_append(buf, 0, "<%s>", a);
return ast_str_buffer(*buf);
}
AST_TEST_DEFINE(temp_strings)
{
char *return_buffer = ast_malloc(128);
switch (cmd) {
case TEST_INIT:
info->name = "temp_strings";
info->category = "/main/strings/";
info->summary = "Test ast_str_temp_buffer";
info->description = "Test ast_str_temp_buffer";
return AST_TEST_NOT_RUN;
case TEST_EXECUTE:
break;
}
snprintf(return_buffer, 128, "%s %s %s %s %s",
ast_str_tmp(12, str_appender(&STR_TMP, "str1")),
ast_str_tmp(12, str_appender(&STR_TMP, "str2")),
ast_str_tmp(12, str_appender(&STR_TMP, "B")),
"ccccccccccccc",
ast_str_tmp(12, str_appender(&STR_TMP, "ww"))
);
ast_test_validate(test, ast_strings_match(return_buffer, "=", "<str1> <str2> <B> ccccccccccccc <ww>"));
ast_free(return_buffer);
return AST_TEST_PASS;
}
static int unload_module(void)
{
AST_TEST_UNREGISTER(str_test);
@ -592,6 +629,7 @@ static int unload_module(void)
AST_TEST_UNREGISTER(escape_semicolons_test);
AST_TEST_UNREGISTER(escape_test);
AST_TEST_UNREGISTER(strings_match);
AST_TEST_UNREGISTER(temp_strings);
return 0;
}
@ -604,6 +642,7 @@ static int load_module(void)
AST_TEST_REGISTER(escape_semicolons_test);
AST_TEST_REGISTER(escape_test);
AST_TEST_REGISTER(strings_match);
AST_TEST_REGISTER(temp_strings);
return AST_MODULE_LOAD_SUCCESS;
}