X-Git-Url: https://mattmccutchen.net/rsync/rsync.git/blobdiff_plain/b2f02464985e9a9e0b8416635ab2d592e31c5096..619d21ffc99dca4afab4d422d610167ae05af0a4:/log.c diff --git a/log.c b/log.c index 3b53a9ae..f7e124f3 100644 --- a/log.c +++ b/log.c @@ -1,18 +1,18 @@ /* -*- c-file-style: "linux"; -*- - + Copyright (C) 1998-2001 by Andrew Tridgell Copyright (C) 2000-2001 by Martin Pool - + 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., 675 Mass Ave, Cambridge, MA 02139, USA. @@ -27,9 +27,19 @@ */ #include "rsync.h" +extern int am_daemon; +extern int am_server; +extern int am_sender; +extern int quiet; +extern int module_id; +extern int msg_fd_out; +extern char *auth_user; +extern char *log_format; + +static int log_initialised; static char *logfname; static FILE *logfile; -static int log_error_fd = -1; +struct stats stats; int log_got_error=0; @@ -37,25 +47,27 @@ struct { int code; char const *name; } const rerr_names[] = { - { RERR_SYNTAX , "syntax or usage error" }, - { RERR_PROTOCOL , "protocol incompatibility" }, - { RERR_FILESELECT , "errors selecting input/output files, dirs" }, - { RERR_UNSUPPORTED, "requested action not supported" }, - { RERR_SOCKETIO , "error in socket IO" }, - { RERR_FILEIO , "error in file IO" }, - { RERR_STREAMIO , "error in rsync protocol data stream" }, - { RERR_MESSAGEIO , "errors with program diagnostics" }, - { RERR_IPC , "error in IPC code" }, - { RERR_SIGNAL , "received SIGUSR1 or SIGINT" }, - { RERR_WAITCHILD , "some error returned by waitpid()" }, - { RERR_MALLOC , "error allocating core memory buffers" }, - { RERR_PARTIAL , "partial transfer" }, - { RERR_TIMEOUT , "timeout in data send/receive" }, + { RERR_SYNTAX , "syntax or usage error" }, + { RERR_PROTOCOL , "protocol incompatibility" }, + { RERR_FILESELECT , "errors selecting input/output files, dirs" }, + { RERR_UNSUPPORTED, "requested action not supported" }, + { RERR_STARTCLIENT, "error starting client-server protocol" }, + { RERR_SOCKETIO , "error in socket IO" }, + { RERR_FILEIO , "error in file IO" }, + { RERR_STREAMIO , "error in rsync protocol data stream" }, + { RERR_MESSAGEIO , "errors with program diagnostics" }, + { RERR_IPC , "error in IPC code" }, + { RERR_SIGNAL , "received SIGUSR1 or SIGINT" }, + { RERR_WAITCHILD , "some error returned by waitpid()" }, + { RERR_MALLOC , "error allocating core memory buffers" }, + { RERR_PARTIAL , "some files could not be transferred" }, + { RERR_VANISHED , "some files vanished before they could be transfered" }, + { RERR_TIMEOUT , "timeout in data send/receive" }, { RERR_CMD_FAILED , "remote shell failed" }, { RERR_CMD_KILLED , "remote shell killed" }, { RERR_CMD_RUN, "remote command could not be run" }, - { RERR_CMD_NOTFOUND, "remote command not found" }, - { 0, NULL } + { RERR_CMD_NOTFOUND, "remote command not found" }, + { 0, NULL } }; @@ -65,67 +77,12 @@ struct { */ static char const *rerr_name(int code) { - int i; - for (i = 0; rerr_names[i].name; i++) { - if (rerr_names[i].code == code) - return rerr_names[i].name; - } - return NULL; -} - -struct err_list { - struct err_list *next; - char *buf; - int len; - int written; /* how many bytes we have written so far */ -}; - -static struct err_list *err_list_head; -static struct err_list *err_list_tail; - -/* add an error message to the pending error list */ -static void err_list_add(int code, char *buf, int len) -{ - struct err_list *el; - el = (struct err_list *)malloc(sizeof(*el)); - if (!el) exit_cleanup(RERR_MALLOC); - el->next = NULL; - el->buf = malloc(len+4); - if (!el->buf) exit_cleanup(RERR_MALLOC); - memcpy(el->buf+4, buf, len); - SIVAL(el->buf, 0, ((code+MPLEX_BASE)<<24) | len); - el->len = len+4; - el->written = 0; - if (err_list_tail) { - err_list_tail->next = el; - } else { - err_list_head = el; - } - err_list_tail = el; -} - - -/* try to push errors off the error list onto the wire */ -void err_list_push(void) -{ - if (log_error_fd == -1) return; - - while (err_list_head) { - struct err_list *el = err_list_head; - int n = write(log_error_fd, el->buf+el->written, el->len - el->written); - /* don't check for an error if the best way of handling the error is - to ignore it */ - if (n == -1) break; - if (n > 0) { - el->written += n; - } - if (el->written == el->len) { - free(el->buf); - err_list_head = el->next; - if (!err_list_head) err_list_tail = NULL; - free(el); - } + int i; + for (i = 0; rerr_names[i].name; i++) { + if (rerr_names[i].code == code) + return rerr_names[i].name; } + return NULL; } @@ -134,7 +91,7 @@ static void logit(int priority, char *buf) if (logfname) { if (!logfile) log_open(); - fprintf(logfile,"%s [%d] %s", + fprintf(logfile,"%s [%d] %s", timestring(time(NULL)), (int)getpid(), buf); fflush(logfile); } else { @@ -144,16 +101,15 @@ static void logit(int priority, char *buf) void log_init(void) { - static int initialised; int options = LOG_PID; time_t t; - if (initialised) return; - initialised = 1; + if (log_initialised) return; + log_initialised = 1; /* this looks pointless, but it is needed in order for the - C library on some systems to fetch the timezone info - before the chroot */ + * C library on some systems to fetch the timezone info + * before the chroot */ t = time(NULL); localtime(&t); @@ -182,7 +138,7 @@ void log_init(void) #endif } -void log_open() +void log_open(void) { if (logfname && !logfile) { extern int orig_umask; @@ -192,7 +148,7 @@ void log_open() } } -void log_close() +void log_close(void) { if (logfile) { fclose(logfile); @@ -200,27 +156,18 @@ void log_close() } } -/* setup the error file descriptor - used when we are a server - that is receiving files */ -void set_error_fd(int fd) -{ - log_error_fd = fd; - set_nonblocking(log_error_fd); -} - /* this is the underlying (unformatted) rsync debugging function. Call - it with FINFO, FERROR or FLOG */ + * it with FINFO, FERROR or FLOG */ void rwrite(enum logcode code, char *buf, int len) { FILE *f=NULL; - extern int am_daemon; - extern int am_server; - extern int quiet; /* recursion can happen with certain fatal conditions */ - if (quiet && code == FINFO) return; + if (quiet && code == FINFO) + return; - if (len < 0) exit_cleanup(RERR_MESSAGEIO); + if (len < 0) + exit_cleanup(RERR_MESSAGEIO); buf[len] = 0; @@ -229,19 +176,27 @@ void rwrite(enum logcode code, char *buf, int len) return; } - /* first try to pass it off to our sibling */ - if (am_server && log_error_fd != -1) { - err_list_add(code, buf, len); - err_list_push(); - return; - } - - /* if that fails, try to pass it to the other end */ - if (am_server && io_multiplex_write(code, buf, len)) { - return; + if (am_server) { + /* Pass it to non-server side, perhaps through our sibling. */ + if (msg_fd_out >= 0) { + send_msg((enum msgcode)code, buf, len); + return; + } + if (!am_daemon + && io_multiplex_write((enum msgcode)code, buf, len)) + return; } - if (am_daemon) { + /* otherwise, if in daemon mode and either we are not a server + * (that is, we are not running --daemon over a remote shell) or + * the log has already been initialised, log the message on this + * side because we don't want the client to see most errors for + * security reasons. We do want early messages when running daemon + * mode over a remote shell to go to the remote side; those will + * fall through to the next case. + * Note that this is only for the time before multiplexing is enabled. + */ + if (am_daemon && (!am_server || log_initialised)) { static int depth; int priority = LOG_INFO; if (code == FERROR) priority = LOG_WARNING; @@ -260,14 +215,14 @@ void rwrite(enum logcode code, char *buf, int len) if (code == FERROR) { log_got_error = 1; f = stderr; - } + } if (code == FINFO) { - if (am_server) + if (am_server) f = stderr; else f = stdout; - } + } if (!f) exit_cleanup(RERR_MESSAGEIO); @@ -281,18 +236,19 @@ void rwrite(enum logcode code, char *buf, int len) * FLOG. */ void rprintf(enum logcode code, const char *format, ...) { - va_list ap; + va_list ap; char buf[1024]; int len; va_start(ap, format); + /* Note: might return -1 */ len = vsnprintf(buf, sizeof(buf), format, ap); va_end(ap); /* Deal with buffer overruns. Instead of panicking, just * truncate the resulting string. Note that some vsnprintf()s * return -1 on truncation, e.g., glibc 2.0.6 and earlier. */ - if (len > sizeof(buf)-1 || len < 0) { + if ((size_t) len > sizeof(buf)-1 || len < 0) { const char ellipsis[] = "[...]"; /* Reset length, and zero-terminate the end of our buffer */ @@ -329,28 +285,33 @@ void rprintf(enum logcode code, const char *format, ...) * message catalog we need to call it once before chroot-ing. */ void rsyserr(enum logcode code, int errcode, const char *format, ...) { - va_list ap; + va_list ap; char buf[1024]; - int len, sys_len; - char *sysmsg; + int len; + size_t sys_len; + char *sysmsg; va_start(ap, format); + /* Note: might return <0 */ len = vsnprintf(buf, sizeof(buf), format, ap); va_end(ap); - if (len > sizeof(buf)-1) exit_cleanup(RERR_MESSAGEIO); + /* TODO: Put in RSYNC_NAME at the start. */ + + if ((size_t) len > sizeof(buf)-1) + exit_cleanup(RERR_MESSAGEIO); - sysmsg = strerror(errcode); - sys_len = strlen(sysmsg); - if (len + 3 + sys_len > sizeof(buf) - 1) - exit_cleanup(RERR_MESSAGEIO); + sysmsg = strerror(errcode); + sys_len = strlen(sysmsg); + if ((size_t) len + 3 + sys_len > sizeof(buf) - 1) + exit_cleanup(RERR_MESSAGEIO); - strcpy(buf + len, ": "); - len += 2; - strcpy(buf + len, sysmsg); - len += sys_len; - strcpy(buf + len, "\n"); - len++; + strcpy(buf + len, ": "); + len += 2; + strcpy(buf + len, sysmsg); + len += sys_len; + strcpy(buf + len, "\n"); + len++; rwrite(code, buf, len); } @@ -360,7 +321,6 @@ void rsyserr(enum logcode code, int errcode, const char *format, ...) void rflush(enum logcode code) { FILE *f = NULL; - extern int am_daemon; if (am_daemon) { return; @@ -368,19 +328,18 @@ void rflush(enum logcode code) if (code == FLOG) { return; - } + } if (code == FERROR) { f = stderr; - } + } if (code == FINFO) { - extern int am_server; - if (am_server) + if (am_server) f = stderr; else f = stdout; - } + } if (!f) exit_cleanup(RERR_MESSAGEIO); fflush(f); @@ -389,94 +348,103 @@ void rflush(enum logcode code) /* a generic logging routine for send/recv, with parameter - substitiution */ + * substitiution */ static void log_formatted(enum logcode code, char *format, char *op, struct file_struct *file, struct stats *initial_stats) { - extern int module_id; - extern char *auth_user; char buf[1024]; char buf2[1024]; char *p, *s, *n; - int l; - extern struct stats stats; - extern int am_sender; - extern int am_daemon; + size_t l; int64 b; + /* We expand % codes one by one in place in buf. We don't + * copy in the terminating nul of the inserted strings, but + * rather keep going until we reach the nul of the format. + * Just to make sure we don't clobber that nul and therefore + * accidentally keep going, we zero the buffer now. */ + memset(buf, 0, sizeof buf); strlcpy(buf, format, sizeof(buf)); - for (s=&buf[0]; - s && (p=strchr(s,'%')); ) { + for (s = &buf[0]; s && (p = strchr(s,'%')); ) { n = NULL; s = p + 1; switch (p[1]) { case 'h': if (am_daemon) n = client_name(0); break; case 'a': if (am_daemon) n = client_addr(0); break; - case 'l': - snprintf(buf2,sizeof(buf2),"%.0f", - (double)file->length); + case 'l': + snprintf(buf2,sizeof(buf2),"%.0f", + (double)file->length); n = buf2; break; - case 'p': - snprintf(buf2,sizeof(buf2),"%d", - (int)getpid()); + case 'p': + snprintf(buf2,sizeof(buf2),"%d", + (int)getpid()); n = buf2; break; case 'o': n = op; break; - case 'f': - snprintf(buf2, sizeof(buf2), "%s/%s", - file->basedir?file->basedir:"", + case 'f': + pathjoin(buf2, sizeof buf2, + file->basedir ? file->basedir : "", f_name(file)); clean_fname(buf2); - n = buf2; + n = buf2; if (*n == '/') n++; break; case 'm': n = lp_name(module_id); break; case 't': n = timestring(time(NULL)); break; case 'P': n = lp_path(module_id); break; case 'u': n = auth_user; break; - case 'b': + case 'b': if (am_sender) { - b = stats.total_written - + b = stats.total_written - initial_stats->total_written; } else { - b = stats.total_read - + b = stats.total_read - initial_stats->total_read; } - snprintf(buf2,sizeof(buf2),"%.0f", (double)b); + snprintf(buf2,sizeof(buf2),"%.0f", (double)b); n = buf2; break; - case 'c': + case 'c': if (!am_sender) { - b = stats.total_written - + b = stats.total_written - initial_stats->total_written; } else { - b = stats.total_read - + b = stats.total_read - initial_stats->total_read; } - snprintf(buf2,sizeof(buf2),"%.0f", (double)b); + snprintf(buf2,sizeof(buf2),"%.0f", (double)b); n = buf2; break; } - if (!n) continue; + /* n is the string to be inserted in place of this % + * code; l is its length not including the trailing + * NUL */ + if (!n) + continue; l = strlen(n); - if ((l-1) + ((int)(s - &buf[0])) > sizeof(buf)) { + if (l + ((int)(s - &buf[0])) >= sizeof(buf)) { rprintf(FERROR,"buffer overflow expanding %%%c - exiting\n", p[0]); exit_cleanup(RERR_MESSAGEIO); } + /* Shuffle the rest of the string along to make space for n */ if (l != 2) { memmove(s+(l-1), s+1, strlen(s+1)+1); } + + /* Copy in n but NOT its nul, because the format sting + * probably continues after this. */ memcpy(p, n, l); + /* Skip over inserted string; continue looking */ s = p+l; } @@ -486,10 +454,6 @@ static void log_formatted(enum logcode code, /* log the outgoing transfer of a file */ void log_send(struct file_struct *file, struct stats *initial_stats) { - extern int module_id; - extern int am_server; - extern char *log_format; - if (lp_transfer_logging(module_id)) { log_formatted(FLOG, lp_log_format(module_id), "send", file, initial_stats); } else if (log_format && !am_server) { @@ -500,10 +464,6 @@ void log_send(struct file_struct *file, struct stats *initial_stats) /* log the incoming transfer of a file */ void log_recv(struct file_struct *file, struct stats *initial_stats) { - extern int module_id; - extern int am_server; - extern char *log_format; - if (lp_transfer_logging(module_id)) { log_formatted(FLOG, lp_log_format(module_id), "recv", file, initial_stats); } else if (log_format && !am_server) { @@ -523,37 +483,24 @@ void log_recv(struct file_struct *file, struct stats *initial_stats) void log_exit(int code, const char *file, int line) { if (code == 0) { - extern struct stats stats; rprintf(FLOG,"wrote %.0f bytes read %.0f bytes total size %.0f\n", (double)stats.total_written, (double)stats.total_read, (double)stats.total_size); } else { - const char *name; - - name = rerr_name(code); - if (!name) - name = "unexplained error"; - - rprintf(FERROR,"rsync error: %s (code %d) at %s(%d)\n", - name, code, file, line); + const char *name; + + name = rerr_name(code); + if (!name) + name = "unexplained error"; + + /* VANISHED is not an error, only a warning */ + if (code == RERR_VANISHED) { + rprintf(FINFO, "rsync warning: %s (code %d) at %s(%d)\n", + name, code, file, line); + } else { + rprintf(FERROR, "rsync error: %s (code %d) at %s(%d)\n", + name, code, file, line); + } } } - - - - -/* log the incoming transfer of a file for interactive use, this - will be called at the end where the client was run - - it i called when a file starts to be transferred -*/ -void log_transfer(struct file_struct *file, const char *fname) -{ - extern int verbose; - - if (!verbose) return; - - rprintf(FINFO,"%s\n", fname); -} -