log_core.h

Go to the documentation of this file.

/*
 * Copyright (c) 2018 Nordic Semiconductor ASA
 *
 * SPDX-License-Identifier: Apache-2.0
 */
#ifndef ZEPHYR_INCLUDE_LOGGING_LOG_CORE_H_
#define ZEPHYR_INCLUDE_LOGGING_LOG_CORE_H_
 
#include <zephyr/logging/log_msg.h>
#include <zephyr/logging/log_instance.h>
#include <stdbool.h>
#include <stdint.h>
#include <stdarg.h>
#include <zephyr/sys/util.h>
 
/* This header file keeps all macros and functions needed for creating logging
 * messages (macros like @ref LOG_ERR).
 */
#define LOG_LEVEL_NONE 0U
#define LOG_LEVEL_ERR  1U
#define LOG_LEVEL_WRN  2U
#define LOG_LEVEL_INF  3U
#define LOG_LEVEL_DBG  4U
 
#ifdef __cplusplus
extern "C" {
#endif
 
#ifndef CONFIG_LOG
#define CONFIG_LOG_DEFAULT_LEVEL 0U
#define CONFIG_LOG_MAX_LEVEL 0U
#endif
 
/* Id of local domain. */
#define Z_LOG_LOCAL_DOMAIN_ID 0
 
#define LOG_FUNCTION_PREFIX_MASK \
        (((uint32_t)IS_ENABLED(CONFIG_LOG_FUNC_NAME_PREFIX_ERR) << \
          LOG_LEVEL_ERR) | \
         ((uint32_t)IS_ENABLED(CONFIG_LOG_FUNC_NAME_PREFIX_WRN) << \
          LOG_LEVEL_WRN) | \
         ((uint32_t)IS_ENABLED(CONFIG_LOG_FUNC_NAME_PREFIX_INF) << \
          LOG_LEVEL_INF) | \
         ((uint32_t)IS_ENABLED(CONFIG_LOG_FUNC_NAME_PREFIX_DBG) << LOG_LEVEL_DBG))
 
#define Z_LOG_RESOLVED_LEVEL(_level, _default) \
        Z_LOG_RESOLVED_LEVEL1(_level, _default)
 
#define Z_LOG_RESOLVED_LEVEL1(_level, _default) \
        __COND_CODE(_LOG_XXXX##_level, (_level), (_default))
 
#define _LOG_XXXX0  _LOG_YYYY,
#define _LOG_XXXX0U _LOG_YYYY,
#define _LOG_XXXX1  _LOG_YYYY,
#define _LOG_XXXX1U _LOG_YYYY,
#define _LOG_XXXX2  _LOG_YYYY,
#define _LOG_XXXX2U _LOG_YYYY,
#define _LOG_XXXX3  _LOG_YYYY,
#define _LOG_XXXX3U _LOG_YYYY,
#define _LOG_XXXX4  _LOG_YYYY,
#define _LOG_XXXX4U _LOG_YYYY,
 
#define Z_LOG_EVAL(_eval_level, _iftrue, _iffalse) \
        Z_LOG_EVAL1(_eval_level, _iftrue, _iffalse)
 
#define Z_LOG_EVAL1(_eval_level, _iftrue, _iffalse) \
        __COND_CODE(_LOG_ZZZZ##_eval_level, _iftrue, _iffalse)
 
#define _LOG_ZZZZ1  _LOG_YYYY,
#define _LOG_ZZZZ1U _LOG_YYYY,
#define _LOG_ZZZZ2  _LOG_YYYY,
#define _LOG_ZZZZ2U _LOG_YYYY,
#define _LOG_ZZZZ3  _LOG_YYYY,
#define _LOG_ZZZZ3U _LOG_YYYY,
#define _LOG_ZZZZ4  _LOG_YYYY,
#define _LOG_ZZZZ4U _LOG_YYYY,
 
#define LOG_CURRENT_MODULE_ID() (__log_level != 0 ? \
        log_const_source_id(__log_current_const_data) : 0U)
 
/* Set of defines that are set to 1 if function name prefix is enabled for given level. */
#define Z_LOG_FUNC_PREFIX_0U 0
#define Z_LOG_FUNC_PREFIX_1U COND_CODE_1(CONFIG_LOG_FUNC_NAME_PREFIX_ERR, (1), (0))
#define Z_LOG_FUNC_PREFIX_2U COND_CODE_1(CONFIG_LOG_FUNC_NAME_PREFIX_WRN, (1), (0))
#define Z_LOG_FUNC_PREFIX_3U COND_CODE_1(CONFIG_LOG_FUNC_NAME_PREFIX_INF, (1), (0))
#define Z_LOG_FUNC_PREFIX_4U COND_CODE_1(CONFIG_LOG_FUNC_NAME_PREFIX_DBG, (1), (0))
 
#define Z_LOG_STR_WITH_PREFIX2(...) \
        "%s: " GET_ARG_N(1, __VA_ARGS__), (const char *)__func__\
                COND_CODE_0(NUM_VA_ARGS_LESS_1(__VA_ARGS__),\
                            (),\
                            (, GET_ARGS_LESS_N(1, __VA_ARGS__))\
                           )
 
/* Macro handles case when no format string is provided: e.g. LOG_DBG().
 * Handling of format string is deferred to the next level macro.
 */
#define Z_LOG_STR_WITH_PREFIX(...) \
        COND_CODE_0(NUM_VA_ARGS_LESS_1(_, ##__VA_ARGS__), \
                ("%s", (const char *)__func__), \
                (Z_LOG_STR_WITH_PREFIX2(__VA_ARGS__)))
 
#define Z_LOG_STR(_level, ...) \
        COND_CODE_1(UTIL_CAT(Z_LOG_FUNC_PREFIX_##_level), \
                (Z_LOG_STR_WITH_PREFIX(__VA_ARGS__)), (__VA_ARGS__))
 
#define Z_LOG_LEVEL_CHECK(_level, _check_level, _default_level) \
        ((_level) <= Z_LOG_RESOLVED_LEVEL(_check_level, _default_level))
 
#define Z_LOG_CONST_LEVEL_CHECK(_level)                                     \
        (IS_ENABLED(CONFIG_LOG) &&                                          \
        (Z_LOG_LEVEL_CHECK(_level, CONFIG_LOG_OVERRIDE_LEVEL, LOG_LEVEL_NONE) \
        ||                                                                  \
        ((IS_ENABLED(CONFIG_LOG_OVERRIDE_LEVEL) == false) &&                \
        ((_level) <= __log_level) &&                                        \
        ((_level) <= CONFIG_LOG_MAX_LEVEL)                                  \
        )                                                                   \
        ))
 
/*****************************************************************************/
/****************** Definitions used by minimal logging *********************/
/*****************************************************************************/
void z_log_minimal_hexdump_print(int level, const void *data, size_t size);
void z_log_minimal_vprintk(const char *fmt, va_list ap);
void z_log_minimal_printk(const char *fmt, ...);
 
#define Z_LOG_TO_PRINTK(_level, fmt, ...) do { \
        z_log_minimal_printk("%c: " fmt "\n", \
                             z_log_minimal_level_to_char(_level), \
                             ##__VA_ARGS__); \
} while (false)
 
#define Z_LOG_TO_VPRINTK(_level, fmt, valist) do { \
        z_log_minimal_printk("%c: ", z_log_minimal_level_to_char(_level)); \
        z_log_minimal_vprintk(fmt, valist); \
        z_log_minimal_printk("\n"); \
} while (false)
 
static inline char z_log_minimal_level_to_char(int level)
{
        switch (level) {
        case LOG_LEVEL_ERR:
                return 'E';
        case LOG_LEVEL_WRN:
                return 'W';
        case LOG_LEVEL_INF:
                return 'I';
        case LOG_LEVEL_DBG:
                return 'D';
        default:
                return '?';
        }
}
 
#define Z_LOG_INST(_inst) COND_CODE_1(CONFIG_LOG, (_inst), NULL)
 
/* If strings are removed from the binary then there is a risk of creating invalid
 * cbprintf package if %p is used with character pointer which is interpreted as
 * string. A compile time check is performed (since format string is known at
 * compile time) and check fails logging message is not created but error is
 * emitted instead. String check may increase compilation time so it is not
 * always performed (could significantly increase CI time).
 */
#ifdef CONFIG_LOG_FMT_STRING_VALIDATE
#define LOG_STRING_WARNING(_mode, _src, ...) \
            Z_LOG_MSG_CREATE(UTIL_NOT(IS_ENABLED(CONFIG_USERSPACE)), _mode, \
                             Z_LOG_LOCAL_DOMAIN_ID, _src, LOG_LEVEL_ERR, NULL, 0, \
                             "char pointer used for %%p, cast to void *:\"%s\"", \
                             GET_ARG_N(1, __VA_ARGS__))
 
#define LOG_POINTERS_VALIDATE(string_ok, ...) \
        _Pragma("GCC diagnostic push") \
        _Pragma("GCC diagnostic ignored \"-Wpointer-arith\"") \
        string_ok = Z_CBPRINTF_POINTERS_VALIDATE(__VA_ARGS__); \
        _Pragma("GCC diagnostic pop")
#else
#define LOG_POINTERS_VALIDATE(string_ok, ...) string_ok = true
#define LOG_STRING_WARNING(_mode, _src, ...)
#endif
 
/*****************************************************************************/
/****************** Macros for standard logging ******************************/
/*****************************************************************************/
#define Z_LOG2(_level, _inst, _source, _dsource, ...) do { \
        if (!Z_LOG_CONST_LEVEL_CHECK(_level)) { \
                break; \
        } \
        if (IS_ENABLED(CONFIG_LOG_MODE_MINIMAL)) { \
                Z_LOG_TO_PRINTK(_level, __VA_ARGS__); \
                break; \
        } \
        /* For instance logging check instance specific static level */ \
        if (_inst != 0 && !IS_ENABLED(CONFIG_LOG_RUNTIME_FILTERING)) { \
                if (_level > ((struct log_source_const_data *)_source)->level) { \
                        break; \
                } \
        } \
        \
        bool is_user_context = k_is_user_context(); \
        if (IS_ENABLED(CONFIG_LOG_RUNTIME_FILTERING) && \
            !is_user_context && _level > Z_LOG_RUNTIME_FILTER((_dsource)->filters)) { \
                break; \
        } \
        int _mode; \
        void *_src = IS_ENABLED(CONFIG_LOG_RUNTIME_FILTERING) ? \
                (void *)(_dsource) : (void *)(_source); \
        bool string_ok; \
        LOG_POINTERS_VALIDATE(string_ok, __VA_ARGS__); \
        if (!string_ok) { \
                LOG_STRING_WARNING(_mode, _src, __VA_ARGS__); \
                break; \
        } \
        Z_LOG_MSG_CREATE(UTIL_NOT(IS_ENABLED(CONFIG_USERSPACE)), _mode, \
                                  Z_LOG_LOCAL_DOMAIN_ID, _src, _level, NULL,\
                          0, __VA_ARGS__); \
        (void)_mode; \
        if (false) { \
                /* Arguments checker present but never evaluated.*/ \
                /* Placed here to ensure that __VA_ARGS__ are*/ \
                /* evaluated once when log is enabled.*/ \
                z_log_printf_arg_checker(__VA_ARGS__); \
        } \
} while (false)
 
#define Z_LOG(_level, ...) \
        Z_LOG2(_level, 0, __log_current_const_data, __log_current_dynamic_data, __VA_ARGS__)
 
#define Z_LOG_INSTANCE(_level, _inst, ...) do { \
        (void)_inst; \
        Z_LOG2(_level, 1, \
                COND_CODE_1(CONFIG_LOG_RUNTIME_FILTERING, (NULL), (Z_LOG_INST(_inst))), \
                (struct log_source_dynamic_data *)COND_CODE_1( \
                                                CONFIG_LOG_RUNTIME_FILTERING, \
                                                (Z_LOG_INST(_inst)), (NULL)), \
                __VA_ARGS__); \
} while (0)
 
/*****************************************************************************/
/****************** Macros for hexdump logging *******************************/
/*****************************************************************************/
#define Z_LOG_HEXDUMP2(_level, _inst, _source, _dsource, _data, _len, ...) do { \
        const char *_str = GET_ARG_N(1, __VA_ARGS__); \
        if (!Z_LOG_CONST_LEVEL_CHECK(_level)) { \
                break; \
        } \
        /* For instance logging check instance specific static level */ \
        if (_inst && !IS_ENABLED(CONFIG_LOG_RUNTIME_FILTERING)) { \
                if (_level > ((struct log_source_const_data *)_source)->level) { \
                        break; \
                } \
        } \
        bool is_user_context = k_is_user_context(); \
        uint32_t filters = IS_ENABLED(CONFIG_LOG_RUNTIME_FILTERING) ? \
                                                (_dsource)->filters : 0;\
        \
        if (IS_ENABLED(CONFIG_LOG_MODE_MINIMAL)) { \
                Z_LOG_TO_PRINTK(_level, "%s", _str); \
                z_log_minimal_hexdump_print((_level), \
                                            (const char *)(_data), (_len));\
                break; \
        } \
        if (IS_ENABLED(CONFIG_LOG_RUNTIME_FILTERING) && \
            !is_user_context && (_level) > Z_LOG_RUNTIME_FILTER(filters)) { \
                break; \
        } \
        int mode; \
        void *_src = IS_ENABLED(CONFIG_LOG_RUNTIME_FILTERING) ? \
                (void *)(_dsource) : (void *)(_source); \
        Z_LOG_MSG_CREATE(UTIL_NOT(IS_ENABLED(CONFIG_USERSPACE)), mode, \
                                  Z_LOG_LOCAL_DOMAIN_ID, _src, _level, \
                          _data, _len, \
                        COND_CODE_0(NUM_VA_ARGS_LESS_1(_, ##__VA_ARGS__), \
                                (), \
                          (COND_CODE_0(NUM_VA_ARGS_LESS_1(__VA_ARGS__), \
                                  ("%s", __VA_ARGS__), (__VA_ARGS__)))));\
} while (false)
 
#define Z_LOG_HEXDUMP(_level, _data, _length, ...) \
        Z_LOG_HEXDUMP2(_level, 0, \
                      __log_current_const_data, \
                      __log_current_dynamic_data, \
                      _data, _length, __VA_ARGS__)
 
#define Z_LOG_HEXDUMP_INSTANCE(_level, _inst, _data, _length, _str) \
        Z_LOG_HEXDUMP2(_level, 1, \
                COND_CODE_1(CONFIG_LOG_RUNTIME_FILTERING, (NULL), (Z_LOG_INST(_inst))), \
                (struct log_source_dynamic_data *)COND_CODE_1( \
                                                CONFIG_LOG_RUNTIME_FILTERING, \
                                                (Z_LOG_INST(_inst)), (NULL)), \
                _data, _length, _str)
 
/*****************************************************************************/
/****************** Filtering macros *****************************************/
/*****************************************************************************/
 
#define LOG_LEVEL_BITS 3U
 
#define LOG_FILTER_SLOT_SIZE LOG_LEVEL_BITS
 
#define LOG_FILTERS_NUM_OF_SLOTS (32 / LOG_FILTER_SLOT_SIZE)
 
#define LOG_FILTERS_MAX_BACKENDS \
        (LOG_FILTERS_NUM_OF_SLOTS - (1 + IS_ENABLED(CONFIG_LOG_FRONTEND)))
 
#define LOG_FRONTEND_SLOT_ID (LOG_FILTERS_NUM_OF_SLOTS - 1)
 
#define LOG_FILTER_SLOT_MASK (BIT(LOG_FILTER_SLOT_SIZE) - 1U)
 
#define LOG_FILTER_SLOT_SHIFT(_id) (LOG_FILTER_SLOT_SIZE * (_id))
 
#define LOG_FILTER_SLOT_GET(_filters, _id) \
        ((*(_filters) >> LOG_FILTER_SLOT_SHIFT(_id)) & LOG_FILTER_SLOT_MASK)
 
#define LOG_FILTER_SLOT_SET(_filters, _id, _filter)                  \
        do {                                                         \
                *(_filters) &= ~(LOG_FILTER_SLOT_MASK <<             \
                                 LOG_FILTER_SLOT_SHIFT(_id));        \
                *(_filters) |= ((_filter) & LOG_FILTER_SLOT_MASK) << \
                               LOG_FILTER_SLOT_SHIFT(_id);           \
        } while (false)
 
#define LOG_FILTER_AGGR_SLOT_IDX 0
 
#define LOG_FILTER_AGGR_SLOT_GET(_filters) \
        LOG_FILTER_SLOT_GET(_filters, LOG_FILTER_AGGR_SLOT_IDX)
 
#define LOG_FILTER_FIRST_BACKEND_SLOT_IDX 1
 
/* Return aggregated (highest) level for all enabled backends, e.g. if there
 * are 3 active backends, one backend is set to get INF logs from a module and
 * two other backends are set for ERR, returned level is INF.
 */
#define Z_LOG_RUNTIME_FILTER(_filter) \
        LOG_FILTER_SLOT_GET(&(_filter), LOG_FILTER_AGGR_SLOT_IDX)
 
#define LOG_LEVEL_INTERNAL_RAW_STRING LOG_LEVEL_NONE
 
TYPE_SECTION_START_EXTERN(struct log_source_const_data, log_const);
TYPE_SECTION_END_EXTERN(struct log_source_const_data, log_const);
 
#define Z_LOG_PRINTK(_is_raw, ...) do { \
        if (!IS_ENABLED(CONFIG_LOG)) { \
                break; \
        } \
        if (IS_ENABLED(CONFIG_LOG_MODE_MINIMAL)) { \
                z_log_minimal_printk(__VA_ARGS__); \
                break; \
        } \
        int _mode; \
        if (0) {\
                z_log_printf_arg_checker(__VA_ARGS__); \
        } \
        Z_LOG_MSG_CREATE(!IS_ENABLED(CONFIG_USERSPACE), _mode, \
                          Z_LOG_LOCAL_DOMAIN_ID, (const void *)(uintptr_t)_is_raw, \
                          LOG_LEVEL_INTERNAL_RAW_STRING, NULL, 0, __VA_ARGS__);\
} while (0)
 
static inline uint32_t log_const_source_id(
                                const struct log_source_const_data *data)
{
        return ((const uint8_t *)data - (uint8_t *)TYPE_SECTION_START(log_const))/
                        sizeof(struct log_source_const_data);
}
 
TYPE_SECTION_START_EXTERN(struct log_source_dynamic_data, log_dynamic);
TYPE_SECTION_END_EXTERN(struct log_source_dynamic_data, log_dynamic);
 
#define LOG_ITEM_DYNAMIC_DATA(_name) UTIL_CAT(log_dynamic_, _name)
 
#define LOG_INSTANCE_DYNAMIC_DATA(_module_name, _inst) \
        LOG_ITEM_DYNAMIC_DATA(Z_LOG_INSTANCE_FULL_NAME(_module_name, _inst))
 
static inline uint32_t log_dynamic_source_id(struct log_source_dynamic_data *data)
{
        return ((uint8_t *)data - (uint8_t *)TYPE_SECTION_START(log_dynamic))/
                        sizeof(struct log_source_dynamic_data);
}
 
static inline __printf_like(1, 2)
void z_log_printf_arg_checker(const char *fmt, ...)
{
        ARG_UNUSED(fmt);
}
 
static inline void log_generic(uint8_t level, const char *fmt, va_list ap)
{
        z_log_msg_runtime_vcreate(Z_LOG_LOCAL_DOMAIN_ID, NULL, level,
                                   NULL, 0, 0, fmt, ap);
}
 
#ifdef __cplusplus
}
#endif
 
#endif /* ZEPHYR_INCLUDE_LOGGING_LOG_CORE_H_ */