debug: Fix a few signed/unsigned hickups
[Samba.git] / lib / util / debug.c
blob625d23a0a5549621ef2a7b0f861ab2c8b5c4458d
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 "samba_util.h" /* LIST_SEP */
31 #include "debug.h"
33 /* define what facility to use for syslog */
34 #ifndef SYSLOG_FACILITY
35 #define SYSLOG_FACILITY LOG_DAEMON
36 #endif
38 /* -------------------------------------------------------------------------- **
39 * Defines...
43 * format_bufr[FORMAT_BUFR_SIZE - 1] should always be reserved
44 * for a terminating null byte.
46 #define FORMAT_BUFR_SIZE 1024
48 /* -------------------------------------------------------------------------- **
49 * This module implements Samba's debugging utility.
51 * The syntax of a debugging log file is represented as:
53 * <debugfile> :== { <debugmsg> }
55 * <debugmsg> :== <debughdr> '\n' <debugtext>
57 * <debughdr> :== '[' TIME ',' LEVEL ']' [ [FILENAME ':'] [FUNCTION '()'] ]
59 * <debugtext> :== { <debugline> }
61 * <debugline> :== TEXT '\n'
63 * TEXT is a string of characters excluding the newline character.
64 * LEVEL is the DEBUG level of the message (an integer in the range 0..10).
65 * TIME is a timestamp.
66 * FILENAME is the name of the file from which the debug message was generated.
67 * FUNCTION is the function from which the debug message was generated.
69 * Basically, what that all means is:
71 * - A debugging log file is made up of debug messages.
73 * - Each debug message is made up of a header and text. The header is
74 * separated from the text by a newline.
76 * - The header begins with the timestamp and debug level of the message
77 * enclosed in brackets. The filename and function from which the
78 * message was generated may follow. The filename is terminated by a
79 * colon, and the function name is terminated by parenthesis.
81 * - The message text is made up of zero or more lines, each terminated by
82 * a newline.
85 /* state variables for the debug system */
86 static struct {
87 bool initialized;
88 int fd; /* The log file handle */
89 enum debug_logtype logtype; /* The type of logging we are doing: eg stdout, file, stderr */
90 const char *prog_name;
91 bool reopening_logs;
92 bool schedule_reopen_logs;
94 struct debug_settings settings;
95 char *debugf;
96 debug_callback_fn callback;
97 void *callback_private;
98 } state = {
99 .settings = {
100 .timestamp_logs = true
102 .fd = 2 /* stderr by default */
105 #if defined(WITH_SYSLOG) || defined(HAVE_LIBSYSTEMD_JOURNAL) || defined(HAVE_LIBSYSTEMD)
106 static int debug_level_to_priority(int level)
109 * map debug levels to syslog() priorities
111 static const int priority_map[] = {
112 LOG_ERR, /* 0 */
113 LOG_WARNING, /* 1 */
114 LOG_NOTICE, /* 2 */
115 LOG_NOTICE, /* 3 */
116 LOG_NOTICE, /* 4 */
117 LOG_NOTICE, /* 5 */
118 LOG_INFO, /* 6 */
119 LOG_INFO, /* 7 */
120 LOG_INFO, /* 8 */
121 LOG_INFO, /* 9 */
123 int priority;
125 if( level >= ARRAY_SIZE(priority_map) || level < 0)
126 priority = LOG_DEBUG;
127 else
128 priority = priority_map[level];
130 return priority;
132 #endif
134 /* -------------------------------------------------------------------------- **
135 * Debug backends. When logging to DEBUG_FILE, send the log entries to
136 * all active backends.
139 static void debug_file_log(int msg_level,
140 const char *msg, const char *msg_no_nl)
142 ssize_t ret;
144 check_log_size();
145 do {
146 ret = write(state.fd, msg, strlen(msg));
147 } while (ret == -1 && errno == EINTR);
150 #ifdef WITH_SYSLOG
151 static void debug_syslog_reload(bool enabled, bool previously_enabled,
152 const char *prog_name)
154 if (enabled && !previously_enabled) {
155 #ifdef LOG_DAEMON
156 openlog(prog_name, LOG_PID, SYSLOG_FACILITY);
157 #else
158 /* for old systems that have no facility codes. */
159 openlog(prog_name, LOG_PID );
160 #endif
161 return;
164 if (!enabled && previously_enabled) {
165 closelog();
169 static void debug_syslog_log(int msg_level,
170 const char *msg, const char *msg_no_nl)
172 int priority;
174 priority = debug_level_to_priority(msg_level);
177 * Specify the facility to interoperate with other syslog
178 * callers (vfs_full_audit for example).
180 priority |= SYSLOG_FACILITY;
182 syslog(priority, "%s", msg);
184 #endif /* WITH_SYSLOG */
186 #if defined(HAVE_LIBSYSTEMD_JOURNAL) || defined(HAVE_LIBSYSTEMD)
187 #include <systemd/sd-journal.h>
188 static void debug_systemd_log(int msg_level,
189 const char *msg, const char *msg_no_nl)
191 sd_journal_send("MESSAGE=%s", msg_no_nl,
192 "PRIORITY=%d", debug_level_to_priority(msg_level),
193 "LEVEL=%d", msg_level,
194 NULL);
196 #endif
198 #ifdef HAVE_LTTNG_TRACEF
199 #include <lttng/tracef.h>
200 static void debug_lttng_log(int msg_level,
201 const char *msg, const char *msg_no_nl)
203 tracef(msg_no_nl);
205 #endif /* WITH_LTTNG_TRACEF */
207 #ifdef HAVE_GPFS
208 #include "gpfswrap.h"
209 static void debug_gpfs_reload(bool enabled, bool previously_enabled,
210 const char *prog_name)
212 gpfswrap_init();
214 if (enabled && !previously_enabled) {
215 gpfswrap_init_trace();
216 return;
219 if (!enabled && previously_enabled) {
220 gpfswrap_fini_trace();
221 return;
224 if (enabled) {
226 * Trigger GPFS library to adjust state if necessary.
228 gpfswrap_query_trace();
232 static void debug_gpfs_log(int msg_level,
233 const char *msg, const char *msg_no_nl)
235 gpfswrap_add_trace(msg_level, msg_no_nl);
237 #endif /* HAVE_GPFS */
239 static struct debug_backend {
240 const char *name;
241 int log_level;
242 int new_log_level;
243 void (*reload)(bool enabled, bool prev_enabled, const char *prog_name);
244 void (*log)(int msg_level, const char *msg, const char *msg_no_nl);
245 } debug_backends[] = {
247 .name = "file",
248 .log = debug_file_log,
250 #ifdef WITH_SYSLOG
252 .name = "syslog",
253 .reload = debug_syslog_reload,
254 .log = debug_syslog_log,
256 #endif
258 #if defined(HAVE_LIBSYSTEMD_JOURNAL) || defined(HAVE_LIBSYSTEMD)
260 .name = "systemd",
261 .log = debug_systemd_log,
263 #endif
265 #ifdef HAVE_LTTNG_TRACEF
267 .name = "lttng",
268 .log = debug_lttng_log,
270 #endif
272 #ifdef HAVE_GPFS
274 .name = "gpfs",
275 .reload = debug_gpfs_reload,
276 .log = debug_gpfs_log,
278 #endif
281 static struct debug_backend *debug_find_backend(const char *name)
283 unsigned i;
285 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
286 if (strcmp(name, debug_backends[i].name) == 0) {
287 return &debug_backends[i];
291 return NULL;
295 * parse "backend[:option][@loglevel]
297 static void debug_backend_parse_token(char *tok)
299 char *backend_name_option, *backend_name,*backend_level, *saveptr;
300 struct debug_backend *b;
303 * First parse into backend[:option] and loglevel
305 backend_name_option = strtok_r(tok, "@\0", &saveptr);
306 if (backend_name_option == NULL) {
307 return;
310 backend_level = strtok_r(NULL, "\0", &saveptr);
313 * Now parse backend[:option]
315 backend_name = strtok_r(backend_name_option, ":\0", &saveptr);
316 if (backend_name == NULL) {
317 return;
321 * No backend is using the option yet.
323 #if 0
324 backend_option = strtok_r(NULL, "\0", &saveptr);
325 #endif
328 * Find and update backend
330 b = debug_find_backend(backend_name);
331 if (b == NULL) {
332 return;
335 if (backend_level == NULL) {
336 b->new_log_level = MAX_DEBUG_LEVEL;
337 } else {
338 b->new_log_level = atoi(backend_level);
343 * parse "backend1[:option1][@loglevel1] backend2[option2][@loglevel2] ... "
344 * and enable/disable backends accordingly
346 static void debug_set_backends(const char *param)
348 size_t str_len = strlen(param);
349 char str[str_len+1];
350 char *tok, *saveptr;
351 unsigned i;
354 * initialize new_log_level to detect backends that have been
355 * disabled
357 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
358 debug_backends[i].new_log_level = -1;
361 memcpy(str, param, str_len + 1);
363 tok = strtok_r(str, LIST_SEP, &saveptr);
364 if (tok == NULL) {
365 return;
368 while (tok != NULL) {
369 debug_backend_parse_token(tok);
370 tok = strtok_r(NULL, LIST_SEP, &saveptr);
374 * Let backends react to config changes
376 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
377 struct debug_backend *b = &debug_backends[i];
379 if (b->reload) {
380 bool enabled = b->new_log_level > -1;
381 bool previously_enabled = b->log_level > -1;
383 b->reload(enabled, previously_enabled, state.prog_name);
385 b->log_level = b->new_log_level;
389 static void debug_backends_log(const char *msg, int msg_level)
391 char msg_no_nl[FORMAT_BUFR_SIZE];
392 unsigned i;
393 int len;
396 * Some backends already add an extra newline, so also provide
397 * a buffer without the newline character.
399 len = MIN(strlen(msg), FORMAT_BUFR_SIZE - 1);
400 if (msg[len - 1] == '\n') {
401 len--;
404 memcpy(msg_no_nl, msg, len);
405 msg_no_nl[len] = '\0';
407 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
408 if (msg_level <= debug_backends[i].log_level) {
409 debug_backends[i].log(msg_level, msg, msg_no_nl);
414 /* -------------------------------------------------------------------------- **
415 * External variables.
419 used to check if the user specified a
420 logfile on the command line
422 bool override_logfile;
424 static const char *default_classname_table[] = {
425 [DBGC_ALL] = "all",
426 [DBGC_TDB] = "tdb",
427 [DBGC_PRINTDRIVERS] = "printdrivers",
428 [DBGC_LANMAN] = "lanman",
429 [DBGC_SMB] = "smb",
430 [DBGC_RPC_PARSE] = "rpc_parse",
431 [DBGC_RPC_SRV] = "rpc_srv",
432 [DBGC_RPC_CLI] = "rpc_cli",
433 [DBGC_PASSDB] = "passdb",
434 [DBGC_SAM] = "sam",
435 [DBGC_AUTH] = "auth",
436 [DBGC_WINBIND] = "winbind",
437 [DBGC_VFS] = "vfs",
438 [DBGC_IDMAP] = "idmap",
439 [DBGC_QUOTA] = "quota",
440 [DBGC_ACLS] = "acls",
441 [DBGC_LOCKING] = "locking",
442 [DBGC_MSDFS] = "msdfs",
443 [DBGC_DMAPI] = "dmapi",
444 [DBGC_REGISTRY] = "registry",
445 [DBGC_SCAVENGER] = "scavenger",
446 [DBGC_DNS] = "dns",
447 [DBGC_LDB] = "ldb",
448 [DBGC_TEVENT] = "tevent",
452 * This is to allow reading of DEBUGLEVEL_CLASS before the debug
453 * system has been initialized.
455 static const int debug_class_list_initial[ARRAY_SIZE(default_classname_table)];
457 static int debug_num_classes = 0;
458 int *DEBUGLEVEL_CLASS = discard_const_p(int, debug_class_list_initial);
461 /* -------------------------------------------------------------------------- **
462 * Internal variables.
464 * debug_count - Number of debug messages that have been output.
465 * Used to check log size.
467 * current_msg_level - Internal copy of the message debug level. Written by
468 * dbghdr() and read by Debug1().
470 * format_bufr - Used to format debug messages. The dbgtext() function
471 * prints debug messages to a string, and then passes the
472 * string to format_debug_text(), which uses format_bufr
473 * to build the formatted output.
475 * format_pos - Marks the first free byte of the format_bufr.
478 * log_overflow - When this variable is true, never attempt to check the
479 * size of the log. This is a hack, so that we can write
480 * a message using DEBUG, from open_logs() when we
481 * are unable to open a new log file for some reason.
484 static int debug_count = 0;
485 static int current_msg_level = 0;
486 static char format_bufr[FORMAT_BUFR_SIZE];
487 static size_t format_pos = 0;
488 static bool log_overflow = false;
491 * Define all the debug class selection names here. Names *MUST NOT* contain
492 * white space. There must be one name for each DBGC_<class name>, and they
493 * must be in the table in the order of DBGC_<class name>..
496 static char **classname_table = NULL;
499 /* -------------------------------------------------------------------------- **
500 * Functions...
503 static void debug_init(void);
505 /***************************************************************************
506 Free memory pointed to by global pointers.
507 ****************************************************************************/
509 void gfree_debugsyms(void)
511 TALLOC_FREE(classname_table);
513 if ( DEBUGLEVEL_CLASS != debug_class_list_initial ) {
514 TALLOC_FREE( DEBUGLEVEL_CLASS );
515 DEBUGLEVEL_CLASS = discard_const_p(int, debug_class_list_initial);
518 debug_num_classes = 0;
520 state.initialized = false;
523 /****************************************************************************
524 utility lists registered debug class names's
525 ****************************************************************************/
527 char *debug_list_class_names_and_levels(void)
529 char *buf = NULL;
530 unsigned int i;
531 /* prepare strings */
532 for (i = 0; i < debug_num_classes; i++) {
533 buf = talloc_asprintf_append(buf,
534 "%s:%d%s",
535 classname_table[i],
536 DEBUGLEVEL_CLASS[i],
537 i == (debug_num_classes - 1) ? "\n" : " ");
538 if (buf == NULL) {
539 return NULL;
542 return buf;
545 /****************************************************************************
546 Utility to translate names to debug class index's (internal version).
547 ****************************************************************************/
549 static int debug_lookup_classname_int(const char* classname)
551 int i;
553 if (!classname) return -1;
555 for (i=0; i < debug_num_classes; i++) {
556 if (strcmp(classname, classname_table[i])==0)
557 return i;
559 return -1;
562 /****************************************************************************
563 Add a new debug class to the system.
564 ****************************************************************************/
566 int debug_add_class(const char *classname)
568 int ndx;
569 int *new_class_list;
570 char **new_name_list;
571 int default_level;
573 if (!classname)
574 return -1;
576 /* check the init has yet been called */
577 debug_init();
579 ndx = debug_lookup_classname_int(classname);
580 if (ndx >= 0)
581 return ndx;
582 ndx = debug_num_classes;
584 if (DEBUGLEVEL_CLASS == debug_class_list_initial) {
585 /* Initial loading... */
586 new_class_list = NULL;
587 } else {
588 new_class_list = DEBUGLEVEL_CLASS;
591 default_level = DEBUGLEVEL_CLASS[DBGC_ALL];
593 new_class_list = talloc_realloc(NULL, new_class_list, int, ndx + 1);
594 if (!new_class_list)
595 return -1;
596 DEBUGLEVEL_CLASS = new_class_list;
598 DEBUGLEVEL_CLASS[ndx] = default_level;
600 new_name_list = talloc_realloc(NULL, classname_table, char *, ndx + 1);
601 if (!new_name_list)
602 return -1;
603 classname_table = new_name_list;
605 classname_table[ndx] = talloc_strdup(classname_table, classname);
606 if (! classname_table[ndx])
607 return -1;
609 debug_num_classes = ndx + 1;
611 return ndx;
614 /****************************************************************************
615 Utility to translate names to debug class index's (public version).
616 ****************************************************************************/
618 static int debug_lookup_classname(const char *classname)
620 int ndx;
622 if (!classname || !*classname)
623 return -1;
625 ndx = debug_lookup_classname_int(classname);
627 if (ndx != -1)
628 return ndx;
630 DEBUG(0, ("debug_lookup_classname(%s): Unknown class\n",
631 classname));
632 return debug_add_class(classname);
635 /****************************************************************************
636 Dump the current registered debug levels.
637 ****************************************************************************/
639 static void debug_dump_status(int level)
641 int q;
643 DEBUG(level, ("INFO: Current debug levels:\n"));
644 for (q = 0; q < debug_num_classes; q++) {
645 const char *classname = classname_table[q];
646 DEBUGADD(level, (" %s: %d\n",
647 classname,
648 DEBUGLEVEL_CLASS[q]));
652 static bool debug_parse_param(char *param)
654 char *class_name;
655 char *class_level;
656 char *saveptr;
657 int ndx;
659 class_name = strtok_r(param, ":", &saveptr);
660 if (class_name == NULL) {
661 return false;
664 class_level = strtok_r(NULL, "\0", &saveptr);
665 if (class_level == NULL) {
666 return false;
669 ndx = debug_lookup_classname(class_name);
670 if (ndx == -1) {
671 return false;
674 DEBUGLEVEL_CLASS[ndx] = atoi(class_level);
676 return true;
679 /****************************************************************************
680 Parse the debug levels from smb.conf. Example debug level string:
681 3 tdb:5 printdrivers:7
682 Note: the 1st param has no "name:" preceeding it.
683 ****************************************************************************/
685 bool debug_parse_levels(const char *params_str)
687 size_t str_len = strlen(params_str);
688 char str[str_len+1];
689 char *tok, *saveptr;
690 int i;
692 /* Just in case */
693 debug_init();
695 memcpy(str, params_str, str_len+1);
697 tok = strtok_r(str, LIST_SEP, &saveptr);
698 if (tok == NULL) {
699 return true;
702 /* Allow DBGC_ALL to be specified w/o requiring its class name e.g."10"
703 * v.s. "all:10", this is the traditional way to set DEBUGLEVEL
705 if (isdigit(tok[0])) {
706 DEBUGLEVEL_CLASS[DBGC_ALL] = atoi(tok);
707 tok = strtok_r(NULL, LIST_SEP, &saveptr);
708 } else {
709 DEBUGLEVEL_CLASS[DBGC_ALL] = 0;
712 /* Array is debug_num_classes long */
713 for (i = DBGC_ALL+1; i < debug_num_classes; i++) {
714 DEBUGLEVEL_CLASS[i] = DEBUGLEVEL_CLASS[DBGC_ALL];
717 while (tok != NULL) {
718 bool ok;
720 ok = debug_parse_param(tok);
721 if (!ok) {
722 DEBUG(0,("debug_parse_params: unrecognized debug "
723 "class name or format [%s]\n", tok));
724 return false;
727 tok = strtok_r(NULL, LIST_SEP, &saveptr);
730 debug_dump_status(5);
732 return true;
735 /* setup for logging of talloc warnings */
736 static void talloc_log_fn(const char *msg)
738 DEBUG(0,("%s", msg));
741 void debug_setup_talloc_log(void)
743 talloc_set_log_fn(talloc_log_fn);
747 /****************************************************************************
748 Init debugging (one time stuff)
749 ****************************************************************************/
751 static void debug_init(void)
753 size_t i;
755 if (state.initialized)
756 return;
758 state.initialized = true;
760 debug_setup_talloc_log();
762 for (i = 0; i < ARRAY_SIZE(default_classname_table); i++) {
763 debug_add_class(default_classname_table[i]);
766 for (i = 0; i < ARRAY_SIZE(debug_backends); i++) {
767 debug_backends[i].log_level = -1;
768 debug_backends[i].new_log_level = -1;
772 void debug_set_settings(struct debug_settings *settings,
773 const char *logging_param,
774 int syslog_level, bool syslog_only)
776 char fake_param[256];
777 size_t len = 0;
780 * This forces in some smb.conf derived values into the debug
781 * system. There are no pointers in this structure, so we can
782 * just structure-assign it in
784 state.settings = *settings;
787 * If 'logging' is not set, create backend settings from
788 * deprecated 'syslog' and 'syslog only' parameters
790 if (logging_param != NULL) {
791 len = strlen(logging_param);
793 if (len == 0) {
794 if (syslog_only) {
795 snprintf(fake_param, sizeof(fake_param),
796 "syslog@%d", syslog_level - 1);
797 } else {
798 snprintf(fake_param, sizeof(fake_param),
799 "syslog@%d file@%d", syslog_level -1,
800 MAX_DEBUG_LEVEL);
803 logging_param = fake_param;
806 debug_set_backends(logging_param);
810 control the name of the logfile and whether logging will be to stdout, stderr
811 or a file, and set up syslog
813 new_log indicates the destination for the debug log (an enum in
814 order of precedence - once set to DEBUG_FILE, it is not possible to
815 reset to DEBUG_STDOUT for example. This makes it easy to override
816 for debug to stderr on the command line, as the smb.conf cannot
817 reset it back to file-based logging
819 void setup_logging(const char *prog_name, enum debug_logtype new_logtype)
821 debug_init();
822 if (state.logtype < new_logtype) {
823 state.logtype = new_logtype;
825 if (prog_name) {
826 const char *p = strrchr(prog_name, '/');
828 if (p) {
829 prog_name = p + 1;
832 state.prog_name = prog_name;
834 reopen_logs_internal();
837 /***************************************************************************
838 Set the logfile name.
839 **************************************************************************/
841 void debug_set_logfile(const char *name)
843 if (name == NULL || *name == 0) {
844 /* this copes with calls when smb.conf is not loaded yet */
845 return;
847 TALLOC_FREE(state.debugf);
848 state.debugf = talloc_strdup(NULL, name);
851 static void debug_close_fd(int fd)
853 if (fd > 2) {
854 close(fd);
858 bool debug_get_output_is_stderr(void)
860 return (state.logtype == DEBUG_DEFAULT_STDERR) || (state.logtype == DEBUG_STDERR);
863 bool debug_get_output_is_stdout(void)
865 return (state.logtype == DEBUG_DEFAULT_STDOUT) || (state.logtype == DEBUG_STDOUT);
868 void debug_set_callback(void *private_ptr, debug_callback_fn fn)
870 debug_init();
871 if (fn) {
872 state.logtype = DEBUG_CALLBACK;
873 state.callback_private = private_ptr;
874 state.callback = fn;
875 } else {
876 state.logtype = DEBUG_DEFAULT_STDERR;
877 state.callback_private = NULL;
878 state.callback = NULL;
882 static void debug_callback_log(const char *msg, int msg_level)
884 size_t msg_len = strlen(msg);
885 char msg_copy[msg_len];
887 if ((msg_len > 0) && (msg[msg_len-1] == '\n')) {
888 memcpy(msg_copy, msg, msg_len-1);
889 msg_copy[msg_len-1] = '\0';
890 msg = msg_copy;
893 state.callback(state.callback_private, msg_level, msg);
896 /**************************************************************************
897 reopen the log files
898 note that we now do this unconditionally
899 We attempt to open the new debug fp before closing the old. This means
900 if we run out of fd's we just keep using the old fd rather than aborting.
901 Fix from dgibson@linuxcare.com.
902 **************************************************************************/
905 reopen the log file (usually called because the log file name might have changed)
907 bool reopen_logs_internal(void)
909 mode_t oldumask;
910 int new_fd = 0;
911 int old_fd = 0;
912 bool ret = true;
914 if (state.reopening_logs) {
915 return true;
918 /* Now clear the SIGHUP induced flag */
919 state.schedule_reopen_logs = false;
921 switch (state.logtype) {
922 case DEBUG_CALLBACK:
923 return true;
924 case DEBUG_STDOUT:
925 case DEBUG_DEFAULT_STDOUT:
926 debug_close_fd(state.fd);
927 state.fd = 1;
928 return true;
930 case DEBUG_DEFAULT_STDERR:
931 case DEBUG_STDERR:
932 debug_close_fd(state.fd);
933 state.fd = 2;
934 return true;
936 case DEBUG_FILE:
937 break;
940 oldumask = umask( 022 );
942 if (!state.debugf) {
943 return false;
946 state.reopening_logs = true;
948 new_fd = open( state.debugf, O_WRONLY|O_APPEND|O_CREAT, 0644);
950 if (new_fd == -1) {
951 log_overflow = true;
952 DEBUG(0, ("Unable to open new log file '%s': %s\n", state.debugf, strerror(errno)));
953 log_overflow = false;
954 ret = false;
955 } else {
956 smb_set_close_on_exec(new_fd);
957 old_fd = state.fd;
958 state.fd = new_fd;
959 debug_close_fd(old_fd);
962 /* Fix from klausr@ITAP.Physik.Uni-Stuttgart.De
963 * to fix problem where smbd's that generate less
964 * than 100 messages keep growing the log.
966 force_check_log_size();
967 (void)umask(oldumask);
969 /* Take over stderr to catch output into logs */
970 if (state.fd > 0) {
971 if (dup2(state.fd, 2) == -1) {
972 /* Close stderr too, if dup2 can't point it -
973 at the logfile. There really isn't much
974 that can be done on such a fundamental
975 failure... */
976 close_low_fd(2);
980 state.reopening_logs = false;
982 return ret;
985 /**************************************************************************
986 Force a check of the log size.
987 ***************************************************************************/
989 void force_check_log_size( void )
991 debug_count = 100;
994 _PUBLIC_ void debug_schedule_reopen_logs(void)
996 state.schedule_reopen_logs = true;
1000 /***************************************************************************
1001 Check to see if there is any need to check if the logfile has grown too big.
1002 **************************************************************************/
1004 bool need_to_check_log_size( void )
1006 int maxlog;
1008 if( debug_count < 100)
1009 return( false );
1011 maxlog = state.settings.max_log_size * 1024;
1012 if ( state.fd <=2 || maxlog <= 0 ) {
1013 debug_count = 0;
1014 return(false);
1016 return( true );
1019 /**************************************************************************
1020 Check to see if the log has grown to be too big.
1021 **************************************************************************/
1023 void check_log_size( void )
1025 int maxlog;
1026 struct stat st;
1029 * We need to be root to check/change log-file, skip this and let the main
1030 * loop check do a new check as root.
1033 #if _SAMBA_BUILD_ == 3
1034 if (geteuid() != sec_initial_uid())
1035 #else
1036 if( geteuid() != 0)
1037 #endif
1039 /* We don't check sec_initial_uid() here as it isn't
1040 * available in common code and we don't generally
1041 * want to rotate and the possibly lose logs in
1042 * make test or the build farm */
1043 return;
1046 if(log_overflow || (!state.schedule_reopen_logs && !need_to_check_log_size())) {
1047 return;
1050 maxlog = state.settings.max_log_size * 1024;
1052 if (state.schedule_reopen_logs) {
1053 (void)reopen_logs_internal();
1056 if (maxlog && (fstat(state.fd, &st) == 0
1057 && st.st_size > maxlog )) {
1058 (void)reopen_logs_internal();
1059 if (state.fd > 2 && (fstat(state.fd, &st) == 0
1060 && st.st_size > maxlog)) {
1061 char name[strlen(state.debugf) + 5];
1063 snprintf(name, sizeof(name), "%s.old", state.debugf);
1065 (void)rename(state.debugf, name);
1067 if (!reopen_logs_internal()) {
1068 /* We failed to reopen a log - continue using the old name. */
1069 (void)rename(name, state.debugf);
1075 * Here's where we need to panic if state.fd == 0 or -1 (invalid values)
1078 if (state.fd <= 0) {
1079 /* This code should only be reached in very strange
1080 * circumstances. If we merely fail to open the new log we
1081 * should stick with the old one. ergo this should only be
1082 * reached when opening the logs for the first time: at
1083 * startup or when the log level is increased from zero.
1084 * -dwg 6 June 2000
1086 int fd = open( "/dev/console", O_WRONLY, 0);
1087 if (fd != -1) {
1088 smb_set_close_on_exec(fd);
1089 state.fd = fd;
1090 DEBUG(0,("check_log_size: open of debug file %s failed - using console.\n",
1091 state.debugf ));
1092 } else {
1094 * We cannot continue without a debug file handle.
1096 abort();
1099 debug_count = 0;
1102 /*************************************************************************
1103 Write an debug message on the debugfile.
1104 This is called by dbghdr() and format_debug_text().
1105 ************************************************************************/
1107 static void Debug1(const char *msg)
1109 int old_errno = errno;
1111 debug_count++;
1113 switch(state.logtype) {
1114 case DEBUG_CALLBACK:
1115 debug_callback_log(msg, current_msg_level);
1116 break;
1117 case DEBUG_STDOUT:
1118 case DEBUG_STDERR:
1119 case DEBUG_DEFAULT_STDOUT:
1120 case DEBUG_DEFAULT_STDERR:
1121 if (state.fd > 0) {
1122 ssize_t ret;
1123 do {
1124 ret = write(state.fd, msg, strlen(msg));
1125 } while (ret == -1 && errno == EINTR);
1127 break;
1128 case DEBUG_FILE:
1129 debug_backends_log(msg, current_msg_level);
1130 break;
1133 errno = old_errno;
1136 /**************************************************************************
1137 Print the buffer content via Debug1(), then reset the buffer.
1138 Input: none
1139 Output: none
1140 ****************************************************************************/
1142 static void bufr_print( void )
1144 format_bufr[format_pos] = '\0';
1145 (void)Debug1(format_bufr);
1146 format_pos = 0;
1149 /***************************************************************************
1150 Format the debug message text.
1152 Input: msg - Text to be added to the "current" debug message text.
1154 Output: none.
1156 Notes: The purpose of this is two-fold. First, each call to syslog()
1157 (used by Debug1(), see above) generates a new line of syslog
1158 output. This is fixed by storing the partial lines until the
1159 newline character is encountered. Second, printing the debug
1160 message lines when a newline is encountered allows us to add
1161 spaces, thus indenting the body of the message and making it
1162 more readable.
1163 **************************************************************************/
1165 static void format_debug_text( const char *msg )
1167 size_t i;
1168 bool timestamp = (state.logtype == DEBUG_FILE && (state.settings.timestamp_logs));
1170 debug_init();
1172 for( i = 0; msg[i]; i++ ) {
1173 /* Indent two spaces at each new line. */
1174 if(timestamp && 0 == format_pos) {
1175 format_bufr[0] = format_bufr[1] = ' ';
1176 format_pos = 2;
1179 /* If there's room, copy the character to the format buffer. */
1180 if (format_pos < FORMAT_BUFR_SIZE - 1)
1181 format_bufr[format_pos++] = msg[i];
1183 /* If a newline is encountered, print & restart. */
1184 if( '\n' == msg[i] )
1185 bufr_print();
1187 /* If the buffer is full dump it out, reset it, and put out a line
1188 * continuation indicator.
1190 if (format_pos >= FORMAT_BUFR_SIZE - 1) {
1191 bufr_print();
1192 (void)Debug1( " +>\n" );
1196 /* Just to be safe... */
1197 format_bufr[format_pos] = '\0';
1200 /***************************************************************************
1201 Flush debug output, including the format buffer content.
1203 Input: none
1204 Output: none
1205 ***************************************************************************/
1207 void dbgflush( void )
1209 bufr_print();
1212 /***************************************************************************
1213 Print a Debug Header.
1215 Input: level - Debug level of the message (not the system-wide debug
1216 level. )
1217 cls - Debuglevel class of the calling module.
1218 file - Pointer to a string containing the name of the file
1219 from which this function was called, or an empty string
1220 if the __FILE__ macro is not implemented.
1221 func - Pointer to a string containing the name of the function
1222 from which this function was called, or an empty string
1223 if the __FUNCTION__ macro is not implemented.
1224 line - line number of the call to dbghdr, assuming __LINE__
1225 works.
1227 Output: Always true. This makes it easy to fudge a call to dbghdr()
1228 in a macro, since the function can be called as part of a test.
1229 Eg: ( (level <= DEBUGLEVEL) && (dbghdr(level,"",line)) )
1231 Notes: This function takes care of setting current_msg_level.
1233 ****************************************************************************/
1235 bool dbghdrclass(int level, int cls, const char *location, const char *func)
1237 /* Ensure we don't lose any real errno value. */
1238 int old_errno = errno;
1239 bool verbose = false;
1240 char header_str[300];
1241 size_t hs_len;
1242 struct timeval tv;
1243 struct timeval_buf tvbuf;
1245 if( format_pos ) {
1246 /* This is a fudge. If there is stuff sitting in the format_bufr, then
1247 * the *right* thing to do is to call
1248 * format_debug_text( "\n" );
1249 * to write the remainder, and then proceed with the new header.
1250 * Unfortunately, there are several places in the code at which
1251 * the DEBUG() macro is used to build partial lines. That in mind,
1252 * we'll work under the assumption that an incomplete line indicates
1253 * that a new header is *not* desired.
1255 return( true );
1258 /* Set current_msg_level. */
1259 current_msg_level = level;
1261 /* Don't print a header if we're logging to stdout. */
1262 if ( state.logtype != DEBUG_FILE ) {
1263 return( true );
1266 /* Print the header if timestamps are turned on. If parameters are
1267 * not yet loaded, then default to timestamps on.
1269 if (!(state.settings.timestamp_logs ||
1270 state.settings.debug_prefix_timestamp)) {
1271 return true;
1274 GetTimeOfDay(&tv);
1275 timeval_str_buf(&tv, false, state.settings.debug_hires_timestamp,
1276 &tvbuf);
1278 hs_len = snprintf(header_str, sizeof(header_str), "[%s, %2d",
1279 tvbuf.buf, level);
1280 if (hs_len >= sizeof(header_str)) {
1281 goto full;
1284 if (unlikely(DEBUGLEVEL_CLASS[ cls ] >= 10)) {
1285 verbose = true;
1288 if (verbose || state.settings.debug_pid) {
1289 hs_len += snprintf(
1290 header_str + hs_len, sizeof(header_str) - hs_len,
1291 ", pid=%u", (unsigned int)getpid());
1292 if (hs_len >= sizeof(header_str)) {
1293 goto full;
1297 if (verbose || state.settings.debug_uid) {
1298 hs_len += snprintf(
1299 header_str + hs_len, sizeof(header_str) - hs_len,
1300 ", effective(%u, %u), real(%u, %u)",
1301 (unsigned int)geteuid(), (unsigned int)getegid(),
1302 (unsigned int)getuid(), (unsigned int)getgid());
1303 if (hs_len >= sizeof(header_str)) {
1304 goto full;
1308 if ((verbose || state.settings.debug_class)
1309 && (cls != DBGC_ALL)) {
1310 hs_len += snprintf(
1311 header_str + hs_len, sizeof(header_str) - hs_len,
1312 ", class=%s", classname_table[cls]);
1313 if (hs_len >= sizeof(header_str)) {
1314 goto full;
1319 * No +=, see man man strlcat
1321 hs_len = strlcat(header_str, "] ", sizeof(header_str));
1322 if (hs_len >= sizeof(header_str)) {
1323 goto full;
1326 if (!state.settings.debug_prefix_timestamp) {
1327 hs_len += snprintf(
1328 header_str + hs_len, sizeof(header_str) - hs_len,
1329 "%s(%s)\n", location, func);
1330 if (hs_len >= sizeof(header_str)) {
1331 goto full;
1335 full:
1336 (void)Debug1(header_str);
1338 errno = old_errno;
1339 return( true );
1342 /***************************************************************************
1343 Add text to the body of the "current" debug message via the format buffer.
1345 Input: format_str - Format string, as used in printf(), et. al.
1346 ... - Variable argument list.
1348 ..or.. va_alist - Old style variable parameter list starting point.
1350 Output: Always true. See dbghdr() for more info, though this is not
1351 likely to be used in the same way.
1353 ***************************************************************************/
1355 static inline bool __dbgtext_va(const char *format_str, va_list ap) PRINTF_ATTRIBUTE(1,0);
1356 static inline bool __dbgtext_va(const char *format_str, va_list ap)
1358 char *msgbuf = NULL;
1359 bool ret = true;
1360 int res;
1362 res = vasprintf(&msgbuf, format_str, ap);
1363 if (res != -1) {
1364 format_debug_text(msgbuf);
1365 } else {
1366 ret = false;
1368 SAFE_FREE(msgbuf);
1369 return ret;
1372 bool dbgtext_va(const char *format_str, va_list ap)
1374 return __dbgtext_va(format_str, ap);
1377 bool dbgtext(const char *format_str, ... )
1379 va_list ap;
1380 bool ret;
1382 va_start(ap, format_str);
1383 ret = __dbgtext_va(format_str, ap);
1384 va_end(ap);
1386 return ret;