winbind: Don't explicitly send "server_id" for ONLINESTATUS
[Samba.git] / lib / util / debug.c
blob64285506d1b2b479249460a1b9c516ec7f348cbd
1 /*
2 Unix SMB/CIFS implementation.
3 Samba utility functions
4 Copyright (C) Andrew Tridgell 1992-1998
5 Copyright (C) Elrond 2002
6 Copyright (C) Simo Sorce 2002
8 This program is free software; you can redistribute it and/or modify
9 it under the terms of the GNU General Public License as published by
10 the Free Software Foundation; either version 3 of the License, or
11 (at your option) any later version.
13 This program is distributed in the hope that it will be useful,
14 but WITHOUT ANY WARRANTY; without even the implied warranty of
15 MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the
16 GNU General Public License for more details.
18 You should have received a copy of the GNU General Public License
19 along with this program. If not, see <http://www.gnu.org/licenses/>.
22 #include "replace.h"
23 #include <talloc.h>
24 #include "system/filesys.h"
25 #include "system/syslog.h"
26 #include "system/locale.h"
27 #include "time_basic.h"
28 #include "close_low_fd.h"
29 #include "memory.h"
30 #include "util_strlist.h" /* LIST_SEP */
31 #include "blocking.h"
32 #include "debug.h"
34 /* define what facility to use for syslog */
35 #ifndef SYSLOG_FACILITY
36 #define SYSLOG_FACILITY LOG_DAEMON
37 #endif
39 /* -------------------------------------------------------------------------- **
40 * Defines...
44 * format_bufr[FORMAT_BUFR_SIZE - 1] should always be reserved
45 * for a terminating null byte.
47 #define FORMAT_BUFR_SIZE 1024
49 /* -------------------------------------------------------------------------- **
50 * This module implements Samba's debugging utility.
52 * The syntax of a debugging log file is represented as:
54 * <debugfile> :== { <debugmsg> }
56 * <debugmsg> :== <debughdr> '\n' <debugtext>
58 * <debughdr> :== '[' TIME ',' LEVEL ']' [ [FILENAME ':'] [FUNCTION '()'] ]
60 * <debugtext> :== { <debugline> }
62 * <debugline> :== TEXT '\n'
64 * TEXT is a string of characters excluding the newline character.
65 * LEVEL is the DEBUG level of the message (an integer in the range 0..10).
66 * TIME is a timestamp.
67 * FILENAME is the name of the file from which the debug message was generated.
68 * FUNCTION is the function from which the debug message was generated.
70 * Basically, what that all means is:
72 * - A debugging log file is made up of debug messages.
74 * - Each debug message is made up of a header and text. The header is
75 * separated from the text by a newline.
77 * - The header begins with the timestamp and debug level of the message
78 * enclosed in brackets. The filename and function from which the
79 * message was generated may follow. The filename is terminated by a
80 * colon, and the function name is terminated by parenthesis.
82 * - The message text is made up of zero or more lines, each terminated by
83 * a newline.
86 /* state variables for the debug system */
87 static struct {
88 bool initialized;
89 int fd; /* The log file handle */
90 enum debug_logtype logtype; /* The type of logging we are doing: eg stdout, file, stderr */
91 const char *prog_name;
92 bool reopening_logs;
93 bool schedule_reopen_logs;
95 struct debug_settings settings;
96 char *debugf;
97 debug_callback_fn callback;
98 void *callback_private;
99 } state = {
100 .settings = {
101 .timestamp_logs = true
103 .fd = 2 /* stderr by default */
106 #if defined(WITH_SYSLOG) || defined(HAVE_LIBSYSTEMD_JOURNAL) || defined(HAVE_LIBSYSTEMD)
107 static int debug_level_to_priority(int level)
110 * map debug levels to syslog() priorities
112 static const int priority_map[] = {
113 LOG_ERR, /* 0 */
114 LOG_WARNING, /* 1 */
115 LOG_NOTICE, /* 2 */
116 LOG_NOTICE, /* 3 */
117 LOG_NOTICE, /* 4 */
118 LOG_NOTICE, /* 5 */
119 LOG_INFO, /* 6 */
120 LOG_INFO, /* 7 */
121 LOG_INFO, /* 8 */
122 LOG_INFO, /* 9 */
124 int priority;
126 if( level >= ARRAY_SIZE(priority_map) || level < 0)
127 priority = LOG_DEBUG;
128 else
129 priority = priority_map[level];
131 return priority;
133 #endif
135 /* -------------------------------------------------------------------------- **
136 * Debug backends. When logging to DEBUG_FILE, send the log entries to
137 * all active backends.
140 static void debug_file_log(int msg_level,
141 const char *msg, const char *msg_no_nl)
143 ssize_t ret;
145 check_log_size();
146 do {
147 ret = write(state.fd, msg, strlen(msg));
148 } while (ret == -1 && errno == EINTR);
151 #ifdef WITH_SYSLOG
152 static void debug_syslog_reload(bool enabled, bool previously_enabled,
153 const char *prog_name, char *option)
155 if (enabled && !previously_enabled) {
156 #ifdef LOG_DAEMON
157 openlog(prog_name, LOG_PID, SYSLOG_FACILITY);
158 #else
159 /* for old systems that have no facility codes. */
160 openlog(prog_name, LOG_PID );
161 #endif
162 return;
165 if (!enabled && previously_enabled) {
166 closelog();
170 static void debug_syslog_log(int msg_level,
171 const char *msg, const char *msg_no_nl)
173 int priority;
175 priority = debug_level_to_priority(msg_level);
178 * Specify the facility to interoperate with other syslog
179 * callers (vfs_full_audit for example).
181 priority |= SYSLOG_FACILITY;
183 syslog(priority, "%s", msg);
185 #endif /* WITH_SYSLOG */
187 #if defined(HAVE_LIBSYSTEMD_JOURNAL) || defined(HAVE_LIBSYSTEMD)
188 #include <systemd/sd-journal.h>
189 static void debug_systemd_log(int msg_level,
190 const char *msg, const char *msg_no_nl)
192 sd_journal_send("MESSAGE=%s", msg_no_nl,
193 "PRIORITY=%d", debug_level_to_priority(msg_level),
194 "LEVEL=%d", msg_level,
195 NULL);
197 #endif
199 #ifdef HAVE_LTTNG_TRACEF
200 #include <lttng/tracef.h>
201 static void debug_lttng_log(int msg_level,
202 const char *msg, const char *msg_no_nl)
204 tracef(msg_no_nl);
206 #endif /* WITH_LTTNG_TRACEF */
208 #ifdef HAVE_GPFS
209 #include "gpfswrap.h"
210 static void debug_gpfs_reload(bool enabled, bool previously_enabled,
211 const char *prog_name, char *option)
213 gpfswrap_init();
215 if (enabled && !previously_enabled) {
216 gpfswrap_init_trace();
217 return;
220 if (!enabled && previously_enabled) {
221 gpfswrap_fini_trace();
222 return;
225 if (enabled) {
227 * Trigger GPFS library to adjust state if necessary.
229 gpfswrap_query_trace();
233 static void debug_gpfs_log(int msg_level,
234 const char *msg, const char *msg_no_nl)
236 gpfswrap_add_trace(msg_level, msg_no_nl);
238 #endif /* HAVE_GPFS */
240 #define DEBUG_RINGBUF_SIZE (1024 * 1024)
241 #define DEBUG_RINGBUF_SIZE_OPT "size="
243 static char *debug_ringbuf;
244 static size_t debug_ringbuf_size;
245 static size_t debug_ringbuf_ofs;
247 /* We ensure in debug_ringbuf_log() that this is always \0 terminated */
248 char *debug_get_ringbuf(void)
250 return debug_ringbuf;
253 /* Return the size of the ringbuf (including a \0 terminator) */
254 size_t debug_get_ringbuf_size(void)
256 return debug_ringbuf_size;
259 static void debug_ringbuf_reload(bool enabled, bool previously_enabled,
260 const char *prog_name, char *option)
262 bool cmp;
263 size_t optlen = strlen(DEBUG_RINGBUF_SIZE_OPT);
265 debug_ringbuf_size = DEBUG_RINGBUF_SIZE;
266 debug_ringbuf_ofs = 0;
268 SAFE_FREE(debug_ringbuf);
270 if (!enabled) {
271 return;
274 if (option != NULL) {
275 cmp = strncmp(option, DEBUG_RINGBUF_SIZE_OPT, optlen);
276 if (cmp == 0) {
277 debug_ringbuf_size = (size_t)strtoull(
278 option + optlen, NULL, 10);
282 debug_ringbuf = calloc(debug_ringbuf_size, sizeof(char));
283 if (debug_ringbuf == NULL) {
284 return;
288 static void debug_ringbuf_log(int msg_level,
289 const char *msg,
290 const char *msg_no_nl)
292 size_t msglen = strlen(msg);
293 size_t allowed_size;
295 if (debug_ringbuf == NULL) {
296 return;
299 /* Ensure the buffer is always \0 terminated */
300 allowed_size = debug_ringbuf_size - 1;
302 if (msglen > allowed_size) {
303 return;
306 if ((debug_ringbuf_ofs + msglen) < debug_ringbuf_ofs) {
307 return;
310 if ((debug_ringbuf_ofs + msglen) > allowed_size) {
311 debug_ringbuf_ofs = 0;
314 memcpy(debug_ringbuf + debug_ringbuf_ofs, msg, msglen);
315 debug_ringbuf_ofs += msglen;
318 static struct debug_backend {
319 const char *name;
320 int log_level;
321 int new_log_level;
322 void (*reload)(bool enabled, bool prev_enabled,
323 const char *prog_name, char *option);
324 void (*log)(int msg_level, const char *msg, const char *msg_no_nl);
325 char *option;
326 } debug_backends[] = {
328 .name = "file",
329 .log = debug_file_log,
331 #ifdef WITH_SYSLOG
333 .name = "syslog",
334 .reload = debug_syslog_reload,
335 .log = debug_syslog_log,
337 #endif
339 #if defined(HAVE_LIBSYSTEMD_JOURNAL) || defined(HAVE_LIBSYSTEMD)
341 .name = "systemd",
342 .log = debug_systemd_log,
344 #endif
346 #ifdef HAVE_LTTNG_TRACEF
348 .name = "lttng",
349 .log = debug_lttng_log,
351 #endif
353 #ifdef HAVE_GPFS
355 .name = "gpfs",
356 .reload = debug_gpfs_reload,
357 .log = debug_gpfs_log,
359 #endif
361 .name = "ringbuf",
362 .log = debug_ringbuf_log,
363 .reload = debug_ringbuf_reload,
367 static struct debug_backend *debug_find_backend(const char *name)
369 unsigned i;
371 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
372 if (strcmp(name, debug_backends[i].name) == 0) {
373 return &debug_backends[i];
377 return NULL;
381 * parse "backend[:option][@loglevel]
383 static void debug_backend_parse_token(char *tok)
385 char *backend_name_option, *backend_name,*backend_level, *saveptr;
386 char *backend_option;
387 struct debug_backend *b;
390 * First parse into backend[:option] and loglevel
392 backend_name_option = strtok_r(tok, "@\0", &saveptr);
393 if (backend_name_option == NULL) {
394 return;
397 backend_level = strtok_r(NULL, "\0", &saveptr);
400 * Now parse backend[:option]
402 backend_name = strtok_r(backend_name_option, ":\0", &saveptr);
403 if (backend_name == NULL) {
404 return;
407 backend_option = strtok_r(NULL, "\0", &saveptr);
410 * Find and update backend
412 b = debug_find_backend(backend_name);
413 if (b == NULL) {
414 return;
417 if (backend_level == NULL) {
418 b->new_log_level = MAX_DEBUG_LEVEL;
419 } else {
420 b->new_log_level = atoi(backend_level);
423 if (backend_option != NULL) {
424 b->option = strdup(backend_option);
425 if (b->option == NULL) {
426 return;
432 * parse "backend1[:option1][@loglevel1] backend2[option2][@loglevel2] ... "
433 * and enable/disable backends accordingly
435 static void debug_set_backends(const char *param)
437 size_t str_len = strlen(param);
438 char str[str_len+1];
439 char *tok, *saveptr;
440 unsigned i;
443 * initialize new_log_level to detect backends that have been
444 * disabled
446 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
447 SAFE_FREE(debug_backends[i].option);
448 debug_backends[i].new_log_level = -1;
451 memcpy(str, param, str_len + 1);
453 tok = strtok_r(str, LIST_SEP, &saveptr);
454 if (tok == NULL) {
455 return;
458 while (tok != NULL) {
459 debug_backend_parse_token(tok);
460 tok = strtok_r(NULL, LIST_SEP, &saveptr);
464 * Let backends react to config changes
466 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
467 struct debug_backend *b = &debug_backends[i];
469 if (b->reload) {
470 bool enabled = b->new_log_level > -1;
471 bool previously_enabled = b->log_level > -1;
473 b->reload(enabled, previously_enabled, state.prog_name,
474 b->option);
476 b->log_level = b->new_log_level;
480 static void debug_backends_log(const char *msg, int msg_level)
482 char msg_no_nl[FORMAT_BUFR_SIZE];
483 unsigned i;
484 int len;
487 * Some backends already add an extra newline, so also provide
488 * a buffer without the newline character.
490 len = MIN(strlen(msg), FORMAT_BUFR_SIZE - 1);
491 if ((len > 0) && (msg[len - 1] == '\n')) {
492 len--;
495 memcpy(msg_no_nl, msg, len);
496 msg_no_nl[len] = '\0';
498 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
499 if (msg_level <= debug_backends[i].log_level) {
500 debug_backends[i].log(msg_level, msg, msg_no_nl);
505 /* -------------------------------------------------------------------------- **
506 * External variables.
510 used to check if the user specified a
511 logfile on the command line
513 bool override_logfile;
515 static const char *default_classname_table[] = {
516 [DBGC_ALL] = "all",
517 [DBGC_TDB] = "tdb",
518 [DBGC_PRINTDRIVERS] = "printdrivers",
519 [DBGC_LANMAN] = "lanman",
520 [DBGC_SMB] = "smb",
521 [DBGC_RPC_PARSE] = "rpc_parse",
522 [DBGC_RPC_SRV] = "rpc_srv",
523 [DBGC_RPC_CLI] = "rpc_cli",
524 [DBGC_PASSDB] = "passdb",
525 [DBGC_SAM] = "sam",
526 [DBGC_AUTH] = "auth",
527 [DBGC_WINBIND] = "winbind",
528 [DBGC_VFS] = "vfs",
529 [DBGC_IDMAP] = "idmap",
530 [DBGC_QUOTA] = "quota",
531 [DBGC_ACLS] = "acls",
532 [DBGC_LOCKING] = "locking",
533 [DBGC_MSDFS] = "msdfs",
534 [DBGC_DMAPI] = "dmapi",
535 [DBGC_REGISTRY] = "registry",
536 [DBGC_SCAVENGER] = "scavenger",
537 [DBGC_DNS] = "dns",
538 [DBGC_LDB] = "ldb",
539 [DBGC_TEVENT] = "tevent",
540 [DBGC_AUTH_AUDIT] = "auth_audit",
541 [DBGC_AUTH_AUDIT_JSON] = "auth_json_audit",
542 [DBGC_KERBEROS] = "kerberos",
543 [DBGC_DRS_REPL] = "drs_repl",
547 * This is to allow reading of DEBUGLEVEL_CLASS before the debug
548 * system has been initialized.
550 static const int debug_class_list_initial[ARRAY_SIZE(default_classname_table)];
552 static int debug_num_classes = 0;
553 int *DEBUGLEVEL_CLASS = discard_const_p(int, debug_class_list_initial);
556 /* -------------------------------------------------------------------------- **
557 * Internal variables.
559 * debug_count - Number of debug messages that have been output.
560 * Used to check log size.
562 * current_msg_level - Internal copy of the message debug level. Written by
563 * dbghdr() and read by Debug1().
565 * format_bufr - Used to format debug messages. The dbgtext() function
566 * prints debug messages to a string, and then passes the
567 * string to format_debug_text(), which uses format_bufr
568 * to build the formatted output.
570 * format_pos - Marks the first free byte of the format_bufr.
573 * log_overflow - When this variable is true, never attempt to check the
574 * size of the log. This is a hack, so that we can write
575 * a message using DEBUG, from open_logs() when we
576 * are unable to open a new log file for some reason.
579 static int debug_count = 0;
580 static int current_msg_level = 0;
581 static char format_bufr[FORMAT_BUFR_SIZE];
582 static size_t format_pos = 0;
583 static bool log_overflow = false;
586 * Define all the debug class selection names here. Names *MUST NOT* contain
587 * white space. There must be one name for each DBGC_<class name>, and they
588 * must be in the table in the order of DBGC_<class name>..
591 static char **classname_table = NULL;
594 /* -------------------------------------------------------------------------- **
595 * Functions...
598 static void debug_init(void);
600 /***************************************************************************
601 Free memory pointed to by global pointers.
602 ****************************************************************************/
604 void gfree_debugsyms(void)
606 unsigned i;
608 TALLOC_FREE(classname_table);
610 if ( DEBUGLEVEL_CLASS != debug_class_list_initial ) {
611 TALLOC_FREE( DEBUGLEVEL_CLASS );
612 DEBUGLEVEL_CLASS = discard_const_p(int, debug_class_list_initial);
615 debug_num_classes = 0;
617 state.initialized = false;
619 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
620 SAFE_FREE(debug_backends[i].option);
624 /****************************************************************************
625 utility lists registered debug class names's
626 ****************************************************************************/
628 char *debug_list_class_names_and_levels(void)
630 char *buf = NULL;
631 int i;
632 /* prepare strings */
633 for (i = 0; i < debug_num_classes; i++) {
634 buf = talloc_asprintf_append(buf,
635 "%s:%d%s",
636 classname_table[i],
637 DEBUGLEVEL_CLASS[i],
638 i == (debug_num_classes - 1) ? "\n" : " ");
639 if (buf == NULL) {
640 return NULL;
643 return buf;
646 /****************************************************************************
647 Utility to translate names to debug class index's (internal version).
648 ****************************************************************************/
650 static int debug_lookup_classname_int(const char* classname)
652 int i;
654 if (!classname) return -1;
656 for (i=0; i < debug_num_classes; i++) {
657 if (strcmp(classname, classname_table[i])==0)
658 return i;
660 return -1;
663 /****************************************************************************
664 Add a new debug class to the system.
665 ****************************************************************************/
667 int debug_add_class(const char *classname)
669 int ndx;
670 int *new_class_list;
671 char **new_name_list;
672 int default_level;
674 if (!classname)
675 return -1;
677 /* check the init has yet been called */
678 debug_init();
680 ndx = debug_lookup_classname_int(classname);
681 if (ndx >= 0)
682 return ndx;
683 ndx = debug_num_classes;
685 if (DEBUGLEVEL_CLASS == debug_class_list_initial) {
686 /* Initial loading... */
687 new_class_list = NULL;
688 } else {
689 new_class_list = DEBUGLEVEL_CLASS;
692 default_level = DEBUGLEVEL_CLASS[DBGC_ALL];
694 new_class_list = talloc_realloc(NULL, new_class_list, int, ndx + 1);
695 if (!new_class_list)
696 return -1;
697 DEBUGLEVEL_CLASS = new_class_list;
699 DEBUGLEVEL_CLASS[ndx] = default_level;
701 new_name_list = talloc_realloc(NULL, classname_table, char *, ndx + 1);
702 if (!new_name_list)
703 return -1;
704 classname_table = new_name_list;
706 classname_table[ndx] = talloc_strdup(classname_table, classname);
707 if (! classname_table[ndx])
708 return -1;
710 debug_num_classes = ndx + 1;
712 return ndx;
715 /****************************************************************************
716 Utility to translate names to debug class index's (public version).
717 ****************************************************************************/
719 static int debug_lookup_classname(const char *classname)
721 int ndx;
723 if (!classname || !*classname)
724 return -1;
726 ndx = debug_lookup_classname_int(classname);
728 if (ndx != -1)
729 return ndx;
731 DEBUG(0, ("debug_lookup_classname(%s): Unknown class\n",
732 classname));
733 return debug_add_class(classname);
736 /****************************************************************************
737 Dump the current registered debug levels.
738 ****************************************************************************/
740 static void debug_dump_status(int level)
742 int q;
744 DEBUG(level, ("INFO: Current debug levels:\n"));
745 for (q = 0; q < debug_num_classes; q++) {
746 const char *classname = classname_table[q];
747 DEBUGADD(level, (" %s: %d\n",
748 classname,
749 DEBUGLEVEL_CLASS[q]));
753 static bool debug_parse_param(char *param)
755 char *class_name;
756 char *class_level;
757 char *saveptr = NULL;
758 int ndx;
760 class_name = strtok_r(param, ":", &saveptr);
761 if (class_name == NULL) {
762 return false;
765 class_level = strtok_r(NULL, "\0", &saveptr);
766 if (class_level == NULL) {
767 return false;
770 ndx = debug_lookup_classname(class_name);
771 if (ndx == -1) {
772 return false;
775 DEBUGLEVEL_CLASS[ndx] = atoi(class_level);
777 return true;
780 /****************************************************************************
781 Parse the debug levels from smb.conf. Example debug level string:
782 3 tdb:5 printdrivers:7
783 Note: the 1st param has no "name:" preceeding it.
784 ****************************************************************************/
786 bool debug_parse_levels(const char *params_str)
788 size_t str_len = strlen(params_str);
789 char str[str_len+1];
790 char *tok, *saveptr;
791 int i;
793 /* Just in case */
794 debug_init();
796 memcpy(str, params_str, str_len+1);
798 tok = strtok_r(str, LIST_SEP, &saveptr);
799 if (tok == NULL) {
800 return true;
803 /* Allow DBGC_ALL to be specified w/o requiring its class name e.g."10"
804 * v.s. "all:10", this is the traditional way to set DEBUGLEVEL
806 if (isdigit(tok[0])) {
807 DEBUGLEVEL_CLASS[DBGC_ALL] = atoi(tok);
808 tok = strtok_r(NULL, LIST_SEP, &saveptr);
809 } else {
810 DEBUGLEVEL_CLASS[DBGC_ALL] = 0;
813 /* Array is debug_num_classes long */
814 for (i = DBGC_ALL+1; i < debug_num_classes; i++) {
815 DEBUGLEVEL_CLASS[i] = DEBUGLEVEL_CLASS[DBGC_ALL];
818 while (tok != NULL) {
819 bool ok;
821 ok = debug_parse_param(tok);
822 if (!ok) {
823 DEBUG(0,("debug_parse_params: unrecognized debug "
824 "class name or format [%s]\n", tok));
825 return false;
828 tok = strtok_r(NULL, LIST_SEP, &saveptr);
831 debug_dump_status(5);
833 return true;
836 /* setup for logging of talloc warnings */
837 static void talloc_log_fn(const char *msg)
839 DEBUG(0,("%s", msg));
842 void debug_setup_talloc_log(void)
844 talloc_set_log_fn(talloc_log_fn);
848 /****************************************************************************
849 Init debugging (one time stuff)
850 ****************************************************************************/
852 static void debug_init(void)
854 size_t i;
856 if (state.initialized)
857 return;
859 state.initialized = true;
861 debug_setup_talloc_log();
863 for (i = 0; i < ARRAY_SIZE(default_classname_table); i++) {
864 debug_add_class(default_classname_table[i]);
867 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
868 debug_backends[i].log_level = -1;
869 debug_backends[i].new_log_level = -1;
873 void debug_set_settings(struct debug_settings *settings,
874 const char *logging_param,
875 int syslog_level, bool syslog_only)
877 char fake_param[256];
878 size_t len = 0;
881 * This forces in some smb.conf derived values into the debug
882 * system. There are no pointers in this structure, so we can
883 * just structure-assign it in
885 state.settings = *settings;
888 * If 'logging' is not set, create backend settings from
889 * deprecated 'syslog' and 'syslog only' parameters
891 if (logging_param != NULL) {
892 len = strlen(logging_param);
894 if (len == 0) {
895 if (syslog_only) {
896 snprintf(fake_param, sizeof(fake_param),
897 "syslog@%d", syslog_level - 1);
898 } else {
899 snprintf(fake_param, sizeof(fake_param),
900 "syslog@%d file@%d", syslog_level -1,
901 MAX_DEBUG_LEVEL);
904 logging_param = fake_param;
907 debug_set_backends(logging_param);
911 control the name of the logfile and whether logging will be to stdout, stderr
912 or a file, and set up syslog
914 new_log indicates the destination for the debug log (an enum in
915 order of precedence - once set to DEBUG_FILE, it is not possible to
916 reset to DEBUG_STDOUT for example. This makes it easy to override
917 for debug to stderr on the command line, as the smb.conf cannot
918 reset it back to file-based logging
920 void setup_logging(const char *prog_name, enum debug_logtype new_logtype)
922 debug_init();
923 if (state.logtype < new_logtype) {
924 state.logtype = new_logtype;
926 if (prog_name) {
927 const char *p = strrchr(prog_name, '/');
929 if (p) {
930 prog_name = p + 1;
933 state.prog_name = prog_name;
935 reopen_logs_internal();
938 /***************************************************************************
939 Set the logfile name.
940 **************************************************************************/
942 void debug_set_logfile(const char *name)
944 if (name == NULL || *name == 0) {
945 /* this copes with calls when smb.conf is not loaded yet */
946 return;
948 TALLOC_FREE(state.debugf);
949 state.debugf = talloc_strdup(NULL, name);
952 static void debug_close_fd(int fd)
954 if (fd > 2) {
955 close(fd);
959 bool debug_get_output_is_stderr(void)
961 return (state.logtype == DEBUG_DEFAULT_STDERR) || (state.logtype == DEBUG_STDERR);
964 bool debug_get_output_is_stdout(void)
966 return (state.logtype == DEBUG_DEFAULT_STDOUT) || (state.logtype == DEBUG_STDOUT);
969 void debug_set_callback(void *private_ptr, debug_callback_fn fn)
971 debug_init();
972 if (fn) {
973 state.logtype = DEBUG_CALLBACK;
974 state.callback_private = private_ptr;
975 state.callback = fn;
976 } else {
977 state.logtype = DEBUG_DEFAULT_STDERR;
978 state.callback_private = NULL;
979 state.callback = NULL;
983 static void debug_callback_log(const char *msg, int msg_level)
985 size_t msg_len = strlen(msg);
986 char msg_copy[msg_len];
988 if ((msg_len > 0) && (msg[msg_len-1] == '\n')) {
989 memcpy(msg_copy, msg, msg_len-1);
990 msg_copy[msg_len-1] = '\0';
991 msg = msg_copy;
994 state.callback(state.callback_private, msg_level, msg);
997 /**************************************************************************
998 reopen the log files
999 note that we now do this unconditionally
1000 We attempt to open the new debug fp before closing the old. This means
1001 if we run out of fd's we just keep using the old fd rather than aborting.
1002 Fix from dgibson@linuxcare.com.
1003 **************************************************************************/
1006 reopen the log file (usually called because the log file name might have changed)
1008 bool reopen_logs_internal(void)
1010 mode_t oldumask;
1011 int new_fd = 0;
1012 int old_fd = 0;
1013 bool ret = true;
1015 if (state.reopening_logs) {
1016 return true;
1019 /* Now clear the SIGHUP induced flag */
1020 state.schedule_reopen_logs = false;
1022 switch (state.logtype) {
1023 case DEBUG_CALLBACK:
1024 return true;
1025 case DEBUG_STDOUT:
1026 case DEBUG_DEFAULT_STDOUT:
1027 debug_close_fd(state.fd);
1028 state.fd = 1;
1029 return true;
1031 case DEBUG_DEFAULT_STDERR:
1032 case DEBUG_STDERR:
1033 debug_close_fd(state.fd);
1034 state.fd = 2;
1035 return true;
1037 case DEBUG_FILE:
1038 break;
1041 oldumask = umask( 022 );
1043 if (!state.debugf) {
1044 return false;
1047 state.reopening_logs = true;
1049 new_fd = open( state.debugf, O_WRONLY|O_APPEND|O_CREAT, 0644);
1051 if (new_fd == -1) {
1052 log_overflow = true;
1053 DEBUG(0, ("Unable to open new log file '%s': %s\n", state.debugf, strerror(errno)));
1054 log_overflow = false;
1055 ret = false;
1056 } else {
1057 smb_set_close_on_exec(new_fd);
1058 old_fd = state.fd;
1059 state.fd = new_fd;
1060 debug_close_fd(old_fd);
1063 /* Fix from klausr@ITAP.Physik.Uni-Stuttgart.De
1064 * to fix problem where smbd's that generate less
1065 * than 100 messages keep growing the log.
1067 force_check_log_size();
1068 (void)umask(oldumask);
1070 /* Take over stderr to catch output into logs */
1071 if (state.fd > 0) {
1072 if (dup2(state.fd, 2) == -1) {
1073 /* Close stderr too, if dup2 can't point it -
1074 at the logfile. There really isn't much
1075 that can be done on such a fundamental
1076 failure... */
1077 close_low_fd(2);
1081 state.reopening_logs = false;
1083 return ret;
1086 /**************************************************************************
1087 Force a check of the log size.
1088 ***************************************************************************/
1090 void force_check_log_size( void )
1092 debug_count = 100;
1095 _PUBLIC_ void debug_schedule_reopen_logs(void)
1097 state.schedule_reopen_logs = true;
1101 /***************************************************************************
1102 Check to see if there is any need to check if the logfile has grown too big.
1103 **************************************************************************/
1105 bool need_to_check_log_size( void )
1107 int maxlog;
1109 if( debug_count < 100)
1110 return( false );
1112 maxlog = state.settings.max_log_size * 1024;
1113 if ( state.fd <=2 || maxlog <= 0 ) {
1114 debug_count = 0;
1115 return(false);
1117 return( true );
1120 /**************************************************************************
1121 Check to see if the log has grown to be too big.
1122 **************************************************************************/
1124 void check_log_size( void )
1126 int maxlog;
1127 struct stat st;
1130 * We need to be root to check/change log-file, skip this and let the main
1131 * loop check do a new check as root.
1134 #if _SAMBA_BUILD_ == 3
1135 if (geteuid() != sec_initial_uid())
1136 #else
1137 if( geteuid() != 0)
1138 #endif
1140 /* We don't check sec_initial_uid() here as it isn't
1141 * available in common code and we don't generally
1142 * want to rotate and the possibly lose logs in
1143 * make test or the build farm */
1144 return;
1147 if(log_overflow || (!state.schedule_reopen_logs && !need_to_check_log_size())) {
1148 return;
1151 maxlog = state.settings.max_log_size * 1024;
1153 if (state.schedule_reopen_logs) {
1154 (void)reopen_logs_internal();
1157 if (maxlog && (fstat(state.fd, &st) == 0
1158 && st.st_size > maxlog )) {
1159 (void)reopen_logs_internal();
1160 if (state.fd > 2 && (fstat(state.fd, &st) == 0
1161 && st.st_size > maxlog)) {
1162 char name[strlen(state.debugf) + 5];
1164 snprintf(name, sizeof(name), "%s.old", state.debugf);
1166 (void)rename(state.debugf, name);
1168 if (!reopen_logs_internal()) {
1169 /* We failed to reopen a log - continue using the old name. */
1170 (void)rename(name, state.debugf);
1176 * Here's where we need to panic if state.fd == 0 or -1 (invalid values)
1179 if (state.fd <= 0) {
1180 /* This code should only be reached in very strange
1181 * circumstances. If we merely fail to open the new log we
1182 * should stick with the old one. ergo this should only be
1183 * reached when opening the logs for the first time: at
1184 * startup or when the log level is increased from zero.
1185 * -dwg 6 June 2000
1187 int fd = open( "/dev/console", O_WRONLY, 0);
1188 if (fd != -1) {
1189 smb_set_close_on_exec(fd);
1190 state.fd = fd;
1191 DEBUG(0,("check_log_size: open of debug file %s failed - using console.\n",
1192 state.debugf ));
1193 } else {
1195 * We cannot continue without a debug file handle.
1197 abort();
1200 debug_count = 0;
1203 /*************************************************************************
1204 Write an debug message on the debugfile.
1205 This is called by dbghdr() and format_debug_text().
1206 ************************************************************************/
1208 static void Debug1(const char *msg)
1210 int old_errno = errno;
1212 debug_count++;
1214 switch(state.logtype) {
1215 case DEBUG_CALLBACK:
1216 debug_callback_log(msg, current_msg_level);
1217 break;
1218 case DEBUG_STDOUT:
1219 case DEBUG_STDERR:
1220 case DEBUG_DEFAULT_STDOUT:
1221 case DEBUG_DEFAULT_STDERR:
1222 if (state.fd > 0) {
1223 ssize_t ret;
1224 do {
1225 ret = write(state.fd, msg, strlen(msg));
1226 } while (ret == -1 && errno == EINTR);
1228 break;
1229 case DEBUG_FILE:
1230 debug_backends_log(msg, current_msg_level);
1231 break;
1234 errno = old_errno;
1237 /**************************************************************************
1238 Print the buffer content via Debug1(), then reset the buffer.
1239 Input: none
1240 Output: none
1241 ****************************************************************************/
1243 static void bufr_print( void )
1245 format_bufr[format_pos] = '\0';
1246 (void)Debug1(format_bufr);
1247 format_pos = 0;
1250 /***************************************************************************
1251 Format the debug message text.
1253 Input: msg - Text to be added to the "current" debug message text.
1255 Output: none.
1257 Notes: The purpose of this is two-fold. First, each call to syslog()
1258 (used by Debug1(), see above) generates a new line of syslog
1259 output. This is fixed by storing the partial lines until the
1260 newline character is encountered. Second, printing the debug
1261 message lines when a newline is encountered allows us to add
1262 spaces, thus indenting the body of the message and making it
1263 more readable.
1264 **************************************************************************/
1266 static void format_debug_text( const char *msg )
1268 size_t i;
1269 bool timestamp = (state.logtype == DEBUG_FILE && (state.settings.timestamp_logs));
1271 debug_init();
1273 for( i = 0; msg[i]; i++ ) {
1274 /* Indent two spaces at each new line. */
1275 if(timestamp && 0 == format_pos) {
1276 format_bufr[0] = format_bufr[1] = ' ';
1277 format_pos = 2;
1280 /* If there's room, copy the character to the format buffer. */
1281 if (format_pos < FORMAT_BUFR_SIZE - 1)
1282 format_bufr[format_pos++] = msg[i];
1284 /* If a newline is encountered, print & restart. */
1285 if( '\n' == msg[i] )
1286 bufr_print();
1288 /* If the buffer is full dump it out, reset it, and put out a line
1289 * continuation indicator.
1291 if (format_pos >= FORMAT_BUFR_SIZE - 1) {
1292 bufr_print();
1293 (void)Debug1( " +>\n" );
1297 /* Just to be safe... */
1298 format_bufr[format_pos] = '\0';
1301 /***************************************************************************
1302 Flush debug output, including the format buffer content.
1304 Input: none
1305 Output: none
1306 ***************************************************************************/
1308 void dbgflush( void )
1310 bufr_print();
1313 /***************************************************************************
1314 Print a Debug Header.
1316 Input: level - Debug level of the message (not the system-wide debug
1317 level. )
1318 cls - Debuglevel class of the calling module.
1319 location - Pointer to a string containing the name of the file
1320 from which this function was called, or an empty string
1321 if the __FILE__ macro is not implemented.
1322 func - Pointer to a string containing the name of the function
1323 from which this function was called, or an empty string
1324 if the __FUNCTION__ macro is not implemented.
1326 Output: Always true. This makes it easy to fudge a call to dbghdr()
1327 in a macro, since the function can be called as part of a test.
1328 Eg: ( (level <= DEBUGLEVEL) && (dbghdr(level,"",line)) )
1330 Notes: This function takes care of setting current_msg_level.
1332 ****************************************************************************/
1334 bool dbghdrclass(int level, int cls, const char *location, const char *func)
1336 /* Ensure we don't lose any real errno value. */
1337 int old_errno = errno;
1338 bool verbose = false;
1339 char header_str[300];
1340 size_t hs_len;
1341 struct timeval tv;
1342 struct timeval_buf tvbuf;
1344 if( format_pos ) {
1345 /* This is a fudge. If there is stuff sitting in the format_bufr, then
1346 * the *right* thing to do is to call
1347 * format_debug_text( "\n" );
1348 * to write the remainder, and then proceed with the new header.
1349 * Unfortunately, there are several places in the code at which
1350 * the DEBUG() macro is used to build partial lines. That in mind,
1351 * we'll work under the assumption that an incomplete line indicates
1352 * that a new header is *not* desired.
1354 return( true );
1357 /* Set current_msg_level. */
1358 current_msg_level = level;
1360 /* Don't print a header if we're logging to stdout. */
1361 if ( state.logtype != DEBUG_FILE ) {
1362 return( true );
1365 /* Print the header if timestamps are turned on. If parameters are
1366 * not yet loaded, then default to timestamps on.
1368 if (!(state.settings.timestamp_logs ||
1369 state.settings.debug_prefix_timestamp)) {
1370 return true;
1373 GetTimeOfDay(&tv);
1374 timeval_str_buf(&tv, false, state.settings.debug_hires_timestamp,
1375 &tvbuf);
1377 hs_len = snprintf(header_str, sizeof(header_str), "[%s, %2d",
1378 tvbuf.buf, level);
1379 if (hs_len >= sizeof(header_str)) {
1380 goto full;
1383 if (unlikely(DEBUGLEVEL_CLASS[ cls ] >= 10)) {
1384 verbose = true;
1387 if (verbose || state.settings.debug_pid) {
1388 hs_len += snprintf(
1389 header_str + hs_len, sizeof(header_str) - hs_len,
1390 ", pid=%u", (unsigned int)getpid());
1391 if (hs_len >= sizeof(header_str)) {
1392 goto full;
1396 if (verbose || state.settings.debug_uid) {
1397 hs_len += snprintf(
1398 header_str + hs_len, sizeof(header_str) - hs_len,
1399 ", effective(%u, %u), real(%u, %u)",
1400 (unsigned int)geteuid(), (unsigned int)getegid(),
1401 (unsigned int)getuid(), (unsigned int)getgid());
1402 if (hs_len >= sizeof(header_str)) {
1403 goto full;
1407 if ((verbose || state.settings.debug_class)
1408 && (cls != DBGC_ALL)) {
1409 hs_len += snprintf(
1410 header_str + hs_len, sizeof(header_str) - hs_len,
1411 ", class=%s", classname_table[cls]);
1412 if (hs_len >= sizeof(header_str)) {
1413 goto full;
1418 * No +=, see man man strlcat
1420 hs_len = strlcat(header_str, "] ", sizeof(header_str));
1421 if (hs_len >= sizeof(header_str)) {
1422 goto full;
1425 if (!state.settings.debug_prefix_timestamp) {
1426 hs_len += snprintf(
1427 header_str + hs_len, sizeof(header_str) - hs_len,
1428 "%s(%s)\n", location, func);
1429 if (hs_len >= sizeof(header_str)) {
1430 goto full;
1434 full:
1435 (void)Debug1(header_str);
1437 errno = old_errno;
1438 return( true );
1441 /***************************************************************************
1442 Add text to the body of the "current" debug message via the format buffer.
1444 Input: format_str - Format string, as used in printf(), et. al.
1445 ... - Variable argument list.
1447 ..or.. va_alist - Old style variable parameter list starting point.
1449 Output: Always true. See dbghdr() for more info, though this is not
1450 likely to be used in the same way.
1452 ***************************************************************************/
1454 static inline bool __dbgtext_va(const char *format_str, va_list ap) PRINTF_ATTRIBUTE(1,0);
1455 static inline bool __dbgtext_va(const char *format_str, va_list ap)
1457 char *msgbuf = NULL;
1458 bool ret = true;
1459 int res;
1461 res = vasprintf(&msgbuf, format_str, ap);
1462 if (res != -1) {
1463 format_debug_text(msgbuf);
1464 } else {
1465 ret = false;
1467 SAFE_FREE(msgbuf);
1468 return ret;
1471 bool dbgtext_va(const char *format_str, va_list ap)
1473 return __dbgtext_va(format_str, ap);
1476 bool dbgtext(const char *format_str, ... )
1478 va_list ap;
1479 bool ret;
1481 va_start(ap, format_str);
1482 ret = __dbgtext_va(format_str, ap);
1483 va_end(ap);
1485 return ret;