rtos: Rewrite rtos_qsymbol() and fix auto-detect false positive
[fw/openocd] / src / helper / log.c
index 8567c037a3f9536ebfe0bf2dcf28ccf5bc9b7897..ff84dbf00514f5b87913273f1a603e8c40e55abc 100644 (file)
-/***************************************************************************\r
- *   Copyright (C) 2005 by Dominic Rath                                    *\r
- *   Dominic.Rath@gmx.de                                                   *\r
- *                                                                         *\r
- *   This program is free software; you can redistribute it and/or modify  *\r
- *   it under the terms of the GNU General Public License as published by  *\r
- *   the Free Software Foundation; either version 2 of the License, or     *\r
- *   (at your option) any later version.                                   *\r
- *                                                                         *\r
- *   This program is distributed in the hope that it will be useful,       *\r
- *   but WITHOUT ANY WARRANTY; without even the implied warranty of        *\r
- *   MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the         *\r
- *   GNU General Public License for more details.                          *\r
- *                                                                         *\r
- *   You should have received a copy of the GNU General Public License     *\r
- *   along with this program; if not, write to the                         *\r
- *   Free Software Foundation, Inc.,                                       *\r
- *   59 Temple Place - Suite 330, Boston, MA  02111-1307, USA.             *\r
- ***************************************************************************/\r
-#ifdef HAVE_CONFIG_H\r
-#include "config.h"\r
-#endif\r
-\r
-#include "log.h"\r
-#include "configuration.h"\r
-\r
-#include <stdio.h>\r
-#include <stdlib.h>\r
-#include <string.h>\r
-#include <stdarg.h>\r
-#include <time.h>\r
-\r
-int debug_level = -1;\r
-\r
-static FILE* log_output;\r
-\r
-static void *privData;\r
-static logCallback callback;\r
-static time_t start;\r
-\r
-void log_setCallback(logCallback c, void *p)\r
-{\r
-       callback = c;\r
-       privData = p;\r
-}\r
-\r
-static char *log_strings[5] = \r
-{\r
-       "User:   ",\r
-       "Error:  ",\r
-       "Warning:",\r
-       "Info:   ",\r
-       "Debug:  "\r
-};\r
-\r
-void log_printf(enum log_levels level, const char *file, int line, const char *function, const char *format, ...)\r
-{\r
-       static int count = 0;\r
-       count++;\r
-       va_list args;\r
-       char buffer[512];\r
-\r
-       if (level > debug_level)\r
-               return;\r
-\r
-       va_start(args, format);\r
-       vsnprintf(buffer, 512, format, args);\r
-\r
-       char *f = strrchr(file, '/');\r
-       if (f != NULL)\r
-               file = f + 1;\r
-\r
-       if (debug_level >= LOG_DEBUG)\r
-       {\r
-               /* print with count and time information */\r
-               time_t t=time(NULL)-start;\r
-               fprintf(log_output, "%s %d %ld %s:%d %s(): %s\n", log_strings[level+1], count, t, file, line, function, buffer);\r
-       }\r
-       else\r
-       {\r
-               /* do not print count and time */\r
-               fprintf(log_output, "%s %s:%d %s(): %s\n", log_strings[level+1], file, line, function, buffer);\r
-       }\r
-\r
-       fflush(log_output);\r
-       \r
-       va_end(args);\r
-\r
-       /* Never forward LOG_DEBUG, too verbose and they can be found in the log if need be */\r
-       if (callback && (level <= LOG_INFO))\r
-       {\r
-               va_start(args, format);\r
-               callback(privData, file, line, function, format, args);\r
-               va_end(args);\r
-       }\r
-}\r
-\r
-/* change the current debug level on the fly\r
- * 0: only ERRORS\r
- * 1: + WARNINGS\r
- * 2: + INFORMATIONAL MSGS\r
- * 3: + DEBUG MSGS\r
- */\r
-int handle_debug_level_command(struct command_context_s *cmd_ctx, char *cmd, char **args, int argc)\r
-{\r
-       if (argc == 0)\r
-               command_print(cmd_ctx, "debug_level: %i", debug_level);\r
-\r
-       if (argc > 0)\r
-               debug_level = strtoul(args[0], NULL, 0);\r
-\r
-       if (debug_level < 0)\r
-               debug_level = 0;\r
-\r
-       if (debug_level > 3)\r
-               debug_level = 3;\r
-\r
-       return ERROR_OK;\r
-}\r
-\r
-int handle_log_output_command(struct command_context_s *cmd_ctx, char *cmd, char **args, int argc)\r
-{\r
-       if (argc == 1)\r
-       {\r
-               FILE* file = fopen(args[0], "w");\r
-               \r
-               if (file)\r
-               {\r
-                       log_output = file;\r
-               }\r
-       }\r
-\r
-       return ERROR_OK;\r
-}\r
-\r
-int log_register_commands(struct command_context_s *cmd_ctx)\r
-{\r
-       start = time(NULL);\r
-       register_command(cmd_ctx, NULL, "log_output", handle_log_output_command,\r
-               COMMAND_ANY, "redirect logging to <file> (default: stderr)");\r
-       register_command(cmd_ctx, NULL, "debug_level", handle_debug_level_command,\r
-               COMMAND_ANY, "adjust debug level <0-3>");\r
-\r
-       return ERROR_OK;\r
-}\r
-\r
-int log_init(struct command_context_s *cmd_ctx)\r
-{\r
-       /* set defaults for daemon configuration, if not set by cmdline or cfgfile */\r
-       if (debug_level == -1)\r
-               debug_level = LOG_INFO;\r
-       \r
-       if (log_output == NULL)\r
-       {\r
-               log_output = stderr;\r
-       }\r
-       \r
-       return ERROR_OK;\r
-}\r
-       \r
-int set_log_output(struct command_context_s *cmd_ctx, FILE *output)\r
-{\r
-       log_output = output;\r
-       return ERROR_OK;\r
-}\r
-\r
-/* return allocated string w/printf() result */\r
-char *allocPrintf(const char *fmt, va_list ap)\r
-{\r
-       char *string = NULL;\r
-       \r
-       /* start by 0 to exercise all the code paths. Need minimum 2 bytes to\r
-        * fit 1 char and 0 terminator. */\r
-       int size = 0;\r
-       int first = 1;\r
-       for (;;)\r
-       {\r
-               if ((string == NULL) || (!first))\r
-               {\r
-                       size = size * 2 + 2;\r
-                       char *t = string;\r
-                       string = realloc(string, size);\r
-                       if (string == NULL)\r
-                       {\r
-                               if (t != NULL)\r
-                                       free(t);\r
-                               return NULL;\r
-                       }\r
-               }\r
-       \r
-           int ret;\r
-           ret = vsnprintf(string, size, fmt, ap);\r
-           /* NB! The result of the vsnprintf() might be an *EMPTY* string! */\r
-           if ((ret >= 0) && ((ret + 1) < size))\r
-           {\r
-               return string;\r
-           }\r
-           /* there was just enough or not enough space, allocate more. */\r
-           first = 0;\r
-       }\r
-}\r
+/***************************************************************************
+ *   Copyright (C) 2005 by Dominic Rath                                    *
+ *   Dominic.Rath@gmx.de                                                   *
+ *                                                                         *
+ *   Copyright (C) 2007-2010 Ã˜yvind Harboe                                 *
+ *   oyvind.harboe@zylin.com                                               *
+ *                                                                         *
+ *   Copyright (C) 2008 by Spencer Oliver                                  *
+ *   spen@spen-soft.co.uk                                                  *
+ *                                                                         *
+ *   This program is free software; you can redistribute it and/or modify  *
+ *   it under the terms of the GNU General Public License as published by  *
+ *   the Free Software Foundation; either version 2 of the License, or     *
+ *   (at your option) any later version.                                   *
+ *                                                                         *
+ *   This program 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 General Public License for more details.                          *
+ *                                                                         *
+ *   You should have received a copy of the GNU General Public License     *
+ *   along with this program; if not, write to the                         *
+ *   Free Software Foundation, Inc.,                                       *
+ *   59 Temple Place - Suite 330, Boston, MA  02111-1307, USA.             *
+ ***************************************************************************/
+
+#ifdef HAVE_CONFIG_H
+#include "config.h"
+#endif
+
+#include "time_support.h"
+/* @todo the inclusion of server.h here is a layering violation */
+#include <server/server.h>
+
+#include <stdarg.h>
+
+#ifdef _DEBUG_FREE_SPACE_
+#ifdef HAVE_MALLOC_H
+#include <malloc.h>
+#else
+#error "malloc.h is required to use --enable-malloc-logging"
+#endif
+#endif
+
+int debug_level = -1;
+
+static FILE *log_output;
+static struct log_callback *log_callbacks;
+
+static long long last_time;
+static long long current_time;
+
+static long long start;
+
+static char *log_strings[5] = {
+       "User : ",
+       "Error: ",
+       "Warn : ",      /* want a space after each colon, all same width, colons aligned */
+       "Info : ",
+       "Debug: "
+};
+
+static int count;
+
+static struct store_log_forward *log_head;
+static int log_forward_count;
+
+struct store_log_forward {
+       struct store_log_forward *next;
+       const char *file;
+       int line;
+       const char *function;
+       const char *string;
+};
+
+/* either forward the log to the listeners or store it for possible forwarding later */
+static void log_forward(const char *file, unsigned line, const char *function, const char *string)
+{
+       if (log_forward_count == 0) {
+               struct log_callback *cb, *next;
+               cb = log_callbacks;
+               /* DANGER!!!! the log callback can remove itself!!!! */
+               while (cb) {
+                       next = cb->next;
+                       cb->fn(cb->priv, file, line, function, string);
+                       cb = next;
+               }
+       } else {
+               struct store_log_forward *log = malloc(sizeof(struct store_log_forward));
+               log->file = strdup(file);
+               log->line = line;
+               log->function = strdup(function);
+               log->string = strdup(string);
+               log->next = NULL;
+               if (log_head == NULL)
+                       log_head = log;
+               else {
+                       /* append to tail */
+                       struct store_log_forward *t;
+                       t = log_head;
+                       while (t->next != NULL)
+                               t = t->next;
+                       t->next = log;
+               }
+       }
+}
+
+/* The log_puts() serves to somewhat different goals:
+ *
+ * - logging
+ * - feeding low-level info to the user in GDB or Telnet
+ *
+ * The latter dictates that strings without newline are not logged, lest there
+ * will be *MANY log lines when sending one char at the time(e.g.
+ * target_request.c).
+ *
+ */
+static void log_puts(enum log_levels level,
+       const char *file,
+       int line,
+       const char *function,
+       const char *string)
+{
+       char *f;
+       if (level == LOG_LVL_OUTPUT) {
+               /* do not prepend any headers, just print out what we were given and return */
+               fputs(string, log_output);
+               fflush(log_output);
+               return;
+       }
+
+       f = strrchr(file, '/');
+       if (f != NULL)
+               file = f + 1;
+
+       if (strlen(string) > 0) {
+               if (debug_level >= LOG_LVL_DEBUG) {
+                       /* print with count and time information */
+                       int t = (int)(timeval_ms()-start);
+#ifdef _DEBUG_FREE_SPACE_
+                       struct mallinfo info;
+                       info = mallinfo();
+#endif
+                       fprintf(log_output, "%s%d %d %s:%d %s()"
+#ifdef _DEBUG_FREE_SPACE_
+                               " %d"
+#endif
+                               ": %s", log_strings[level + 1], count, t, file, line, function,
+#ifdef _DEBUG_FREE_SPACE_
+                               info.fordblks,
+#endif
+                               string);
+               } else {
+                       /* if we are using gdb through pipes then we do not want any output
+                        * to the pipe otherwise we get repeated strings */
+                       fprintf(log_output, "%s%s",
+                               (level > LOG_LVL_USER) ? log_strings[level + 1] : "", string);
+               }
+       } else {
+               /* Empty strings are sent to log callbacks to keep e.g. gdbserver alive, here we do
+                *nothing. */
+       }
+
+       fflush(log_output);
+
+       /* Never forward LOG_LVL_DEBUG, too verbose and they can be found in the log if need be */
+       if (level <= LOG_LVL_INFO)
+               log_forward(file, line, function, string);
+}
+
+void log_printf(enum log_levels level,
+       const char *file,
+       unsigned line,
+       const char *function,
+       const char *format,
+       ...)
+{
+       char *string;
+       va_list ap;
+
+       count++;
+       if (level > debug_level)
+               return;
+
+       va_start(ap, format);
+
+       string = alloc_vprintf(format, ap);
+       if (string != NULL) {
+               log_puts(level, file, line, function, string);
+               free(string);
+       }
+
+       va_end(ap);
+}
+
+void log_printf_lf(enum log_levels level,
+       const char *file,
+       unsigned line,
+       const char *function,
+       const char *format,
+       ...)
+{
+       char *string;
+       va_list ap;
+
+       count++;
+       if (level > debug_level)
+               return;
+
+       va_start(ap, format);
+
+       string = alloc_vprintf(format, ap);
+       if (string != NULL) {
+               strcat(string, "\n");   /* alloc_vprintf guaranteed the buffer to be at least one
+                                        *char longer */
+               log_puts(level, file, line, function, string);
+               free(string);
+       }
+
+       va_end(ap);
+}
+
+COMMAND_HANDLER(handle_debug_level_command)
+{
+       if (CMD_ARGC == 1) {
+               int new_level;
+               COMMAND_PARSE_NUMBER(int, CMD_ARGV[0], new_level);
+               if ((new_level > LOG_LVL_DEBUG) || (new_level < LOG_LVL_SILENT)) {
+                       LOG_ERROR("level must be between %d and %d", LOG_LVL_SILENT, LOG_LVL_DEBUG);
+                       return ERROR_COMMAND_SYNTAX_ERROR;
+               }
+               debug_level = new_level;
+       } else if (CMD_ARGC > 1)
+               return ERROR_COMMAND_SYNTAX_ERROR;
+
+       command_print(CMD_CTX, "debug_level: %i", debug_level);
+
+       return ERROR_OK;
+}
+
+COMMAND_HANDLER(handle_log_output_command)
+{
+       if (CMD_ARGC == 1) {
+               FILE *file = fopen(CMD_ARGV[0], "w");
+
+               if (file)
+                       log_output = file;
+       }
+
+       return ERROR_OK;
+}
+
+static struct command_registration log_command_handlers[] = {
+       {
+               .name = "log_output",
+               .handler = handle_log_output_command,
+               .mode = COMMAND_ANY,
+               .help = "redirect logging to a file (default: stderr)",
+               .usage = "file_name",
+       },
+       {
+               .name = "debug_level",
+               .handler = handle_debug_level_command,
+               .mode = COMMAND_ANY,
+               .help = "Sets the verbosity level of debugging output. "
+                       "0 shows errors only; 1 adds warnings; "
+                       "2 (default) adds other info; 3 adds debugging.",
+               .usage = "number",
+       },
+       COMMAND_REGISTRATION_DONE
+};
+
+int log_register_commands(struct command_context *cmd_ctx)
+{
+       return register_commands(cmd_ctx, NULL, log_command_handlers);
+}
+
+void log_init(void)
+{
+       /* set defaults for daemon configuration,
+        * if not set by cmdline or cfgfile */
+       if (debug_level == -1)
+               debug_level = LOG_LVL_INFO;
+
+       char *debug_env = getenv("OPENOCD_DEBUG_LEVEL");
+       if (NULL != debug_env) {
+               int value;
+               int retval = parse_int(debug_env, &value);
+               if (ERROR_OK == retval &&
+                               debug_level >= LOG_LVL_SILENT &&
+                               debug_level <= LOG_LVL_DEBUG)
+                               debug_level = value;
+       }
+
+       if (log_output == NULL)
+               log_output = stderr;
+
+       start = last_time = timeval_ms();
+}
+
+int set_log_output(struct command_context *cmd_ctx, FILE *output)
+{
+       log_output = output;
+       return ERROR_OK;
+}
+
+/* add/remove log callback handler */
+int log_add_callback(log_callback_fn fn, void *priv)
+{
+       struct log_callback *cb;
+
+       /* prevent the same callback to be registered more than once, just for sure */
+       for (cb = log_callbacks; cb; cb = cb->next) {
+               if (cb->fn == fn && cb->priv == priv)
+                       return ERROR_COMMAND_SYNTAX_ERROR;
+       }
+
+       /* alloc memory, it is safe just to return in case of an error, no need for the caller to
+        *check this */
+       cb = malloc(sizeof(struct log_callback));
+       if (cb == NULL)
+               return ERROR_BUF_TOO_SMALL;
+
+       /* add item to the beginning of the linked list */
+       cb->fn = fn;
+       cb->priv = priv;
+       cb->next = log_callbacks;
+       log_callbacks = cb;
+
+       return ERROR_OK;
+}
+
+int log_remove_callback(log_callback_fn fn, void *priv)
+{
+       struct log_callback *cb, **p;
+
+       for (p = &log_callbacks; (cb = *p); p = &(*p)->next) {
+               if (cb->fn == fn && cb->priv == priv) {
+                       *p = cb->next;
+                       free(cb);
+                       return ERROR_OK;
+               }
+       }
+
+       /* no such item */
+       return ERROR_COMMAND_SYNTAX_ERROR;
+}
+
+/* return allocated string w/printf() result */
+char *alloc_vprintf(const char *fmt, va_list ap)
+{
+       va_list ap_copy;
+       int len;
+       char *string;
+
+       /* determine the length of the buffer needed */
+       va_copy(ap_copy, ap);
+       len = vsnprintf(NULL, 0, fmt, ap_copy);
+       va_end(ap_copy);
+
+       /* allocate and make room for terminating zero. */
+       /* FIXME: The old version always allocated at least one byte extra and
+        * other code depend on that. They should be probably be fixed, but for
+        * now reserve the extra byte. */
+       string = malloc(len + 2);
+       if (string == NULL)
+               return NULL;
+
+       /* do the real work */
+       vsnprintf(string, len + 1, fmt, ap);
+
+       return string;
+}
+
+char *alloc_printf(const char *format, ...)
+{
+       char *string;
+       va_list ap;
+       va_start(ap, format);
+       string = alloc_vprintf(format, ap);
+       va_end(ap);
+       return string;
+}
+
+/* Code must return to the server loop before 1000ms has returned or invoke
+ * this function.
+ *
+ * The GDB connection will time out if it spends >2000ms and you'll get nasty
+ * error messages from GDB:
+ *
+ * Ignoring packet error, continuing...
+ * Reply contains invalid hex digit 116
+ *
+ * While it is possible use "set remotetimeout" to more than the default 2000ms
+ * in GDB, OpenOCD guarantees that it sends keep-alive packages on the
+ * GDB protocol and it is a bug in OpenOCD not to either return to the server
+ * loop or invoke keep_alive() every 1000ms.
+ *
+ * This function will send a keep alive packet if >500ms has passed since last time
+ * it was invoked.
+ *
+ * Note that this function can be invoked often, so it needs to be relatively
+ * fast when invoked more often than every 500ms.
+ *
+ */
+void keep_alive()
+{
+       current_time = timeval_ms();
+       if (current_time-last_time > 1000) {
+               extern int gdb_actual_connections;
+
+               if (gdb_actual_connections)
+                       LOG_WARNING("keep_alive() was not invoked in the "
+                               "1000ms timelimit. GDB alive packet not "
+                               "sent! (%lld). Workaround: increase "
+                               "\"set remotetimeout\" in GDB",
+                               current_time-last_time);
+               else
+                       LOG_DEBUG("keep_alive() was not invoked in the "
+                               "1000ms timelimit (%lld). This may cause "
+                               "trouble with GDB connections.",
+                               current_time-last_time);
+       }
+       if (current_time-last_time > 500) {
+               /* this will keep the GDB connection alive */
+               LOG_USER_N("%s", "");
+
+               /* DANGER!!!! do not add code to invoke e.g. target event processing,
+                * jim timer processing, etc. it can cause infinite recursion +
+                * jim event callbacks need to happen at a well defined time,
+                * not anywhere keep_alive() is invoked.
+                *
+                * These functions should be invoked at a well defined spot in server.c
+                */
+
+               last_time = current_time;
+       }
+}
+
+/* reset keep alive timer without sending message */
+void kept_alive()
+{
+       current_time = timeval_ms();
+       last_time = current_time;
+}
+
+/* if we sleep for extended periods of time, we must invoke keep_alive() intermittantly */
+void alive_sleep(uint64_t ms)
+{
+       uint64_t napTime = 10;
+       for (uint64_t i = 0; i < ms; i += napTime) {
+               uint64_t sleep_a_bit = ms - i;
+               if (sleep_a_bit > napTime)
+                       sleep_a_bit = napTime;
+
+               usleep(sleep_a_bit * 1000);
+               keep_alive();
+       }
+}
+
+void busy_sleep(uint64_t ms)
+{
+       uint64_t then = timeval_ms();
+       while (timeval_ms() - then < ms) {
+               /*
+                * busy wait
+                */
+       }
+}