Mention that less data is sent over the wire when --only-write-batch
[rsync/rsync.git] / log.c
diff --git a/log.c b/log.c
index 87cd1d7..c1ef42b 100644 (file)
--- a/log.c
+++ b/log.c
@@ -59,7 +59,6 @@ struct {
        { RERR_FILESELECT , "errors selecting input/output files, dirs" },
        { RERR_UNSUPPORTED, "requested action not supported" },
        { RERR_STARTCLIENT, "error starting client-server protocol" },
-       { RERR_LOG_FAILURE, "daemon unable to append to log-file" },
        { RERR_SOCKETIO   , "error in socket IO" },
        { RERR_FILEIO     , "error in file IO" },
        { RERR_STREAMIO   , "error in rsync protocol data stream" },
@@ -98,8 +97,13 @@ static char const *rerr_name(int code)
 static void logit(int priority, char *buf)
 {
        if (logfname) {
-               if (!logfile)
+               if (!logfile) {
                        log_open();
+                       if (!logfname) {
+                               logit(priority, buf);
+                               return;
+                       }
+               }
                fprintf(logfile,"%s [%d] %s",
                        timestring(time(NULL)), (int)getpid(), buf);
                fflush(logfile);
@@ -108,9 +112,32 @@ static void logit(int priority, char *buf)
        }
 }
 
-void log_init(void)
+static void syslog_init()
 {
+       static int been_here = 0;
        int options = LOG_PID;
+
+       if (been_here)
+               return;
+       been_here = 1;
+
+#ifdef LOG_NDELAY
+       options |= LOG_NDELAY;
+#endif
+
+#ifdef LOG_DAEMON
+       openlog("rsyncd", options, lp_syslog_facility());
+#else
+       openlog("rsyncd", options);
+#endif
+
+#ifndef LOG_NDELAY
+       logit(LOG_INFO, "rsyncd started\n");
+#endif
+}
+
+void log_init(void)
+{
        time_t t;
 
        if (log_initialised)
@@ -133,19 +160,7 @@ void log_init(void)
                logfname = NULL;
        }
 
-#ifdef LOG_NDELAY
-       options |= LOG_NDELAY;
-#endif
-
-#ifdef LOG_DAEMON
-       openlog("rsyncd", options, lp_syslog_facility());
-#else
-       openlog("rsyncd", options);
-#endif
-
-#ifndef LOG_NDELAY
-       logit(LOG_INFO,"rsyncd started\n");
-#endif
+       syslog_init();
 }
 
 void log_open(void)
@@ -156,9 +171,14 @@ void log_open(void)
                logfile = fopen(logfname, "a");
                umask(old_umask);
                if (!logfile) {
-                       am_daemon = 0; /* avoid trying to log again */
-                       rsyserr(FERROR, errno, "fopen() of log-file failed");
-                       exit_cleanup(RERR_LOG_FAILURE);
+                       char *had_logfname = logfname;
+                       int open_errno = errno;
+                       /* Rsync falls back to using syslog on failure. */
+                       logfname = NULL;
+                       syslog_init();
+                       rsyserr(FERROR, open_errno,
+                               "failed to open log-file %s", had_logfname);
+                       rprintf(FINFO, "Ignoring \"log file\" setting.\n");
                }
        }
 }
@@ -240,7 +260,7 @@ void rwrite(enum logcode code, char *buf, int len)
        if (buf[len-1] == '\r' || buf[len-1] == '\n')
                fflush(f);
 }
-               
+
 
 /* This is the rsync debugging function. Call it with FINFO, FERROR or
  * FLOG. */
@@ -320,7 +340,7 @@ void rsyserr(enum logcode code, int errcode, const char *format, ...)
 void rflush(enum logcode code)
 {
        FILE *f = NULL;
-       
+
        if (am_daemon) {
                return;
        }
@@ -360,11 +380,17 @@ static void log_formatted(enum logcode code, char *format, char *op,
        *fmt = '%';
 
        /* 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. */
+        * copy in the terminating null of the inserted strings, but
+        * rather keep going until we reach the null of the format. */
        total = strlcpy(buf, format, sizeof buf);
-       
-       for (p = buf; (p = strchr(p, '%')) != NULL && p[1]; ) {
+       if (total > MAXPATHLEN) {
+               rprintf(FERROR, "log-format string is WAY too long!\n");
+               exit_cleanup(RERR_MESSAGEIO);
+       }
+       buf[total++] = '\n';
+       buf[total] = '\0';
+
+       for (p = buf; (p = strchr(p, '%')) != NULL; ) {
                s = p++;
                n = fmt + 1;
                if (*p == '-')
@@ -376,7 +402,7 @@ static void log_formatted(enum logcode code, char *format, char *op,
                *n = '\0';
                n = NULL;
 
-               switch (*p++) {
+               switch (*p) {
                case 'h': if (am_daemon) n = client_name(0); break;
                case 'a': if (am_daemon) n = client_addr(0); break;
                case 'l':
@@ -386,9 +412,9 @@ static void log_formatted(enum logcode code, char *format, char *op,
                        n = buf2;
                        break;
                case 'p':
-                       strlcat(fmt, "d", sizeof fmt);
+                       strlcat(fmt, "ld", sizeof fmt);
                        snprintf(buf2, sizeof buf2, fmt,
-                                (int)getpid());
+                                (long)getpid());
                        n = buf2;
                        break;
                case 'o': n = op; break;
@@ -418,22 +444,26 @@ static void log_formatted(enum logcode code, char *format, char *op,
                        break;
                case 'L':
                        if (hlink && *hlink) {
-                               snprintf(buf2, sizeof buf2, " => %s",
-                                        safe_fname(hlink));
-                               n = buf2;
+                               n = safe_fname(hlink);
+                               strcpy(buf2, " => ");
                        } else if (S_ISLNK(file->mode) && file->u.link) {
-                               snprintf(buf2, sizeof buf2, " -> %s",
-                                        safe_fname(file->u.link));
-                               n = buf2;
-                       } else
+                               n = safe_fname(file->u.link);
+                               strcpy(buf2, " -> ");
+                       } else {
                                n = "";
+                               if (!fmt[1])
+                                       break;
+                               strcpy(buf2, "    ");
+                       }
+                       strlcat(fmt, "s", sizeof fmt);
+                       snprintf(buf2 + 4, sizeof buf2 - 4, fmt, n);
+                       n = buf2;
                        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':
-                       strlcat(fmt, ".0f", sizeof fmt);
                        if (am_sender) {
                                b = stats.total_written -
                                        initial_stats->total_written;
@@ -441,11 +471,11 @@ static void log_formatted(enum logcode code, char *format, char *op,
                                b = stats.total_read -
                                        initial_stats->total_read;
                        }
+                       strlcat(fmt, ".0f", sizeof fmt);
                        snprintf(buf2, sizeof buf2, fmt, (double)b);
                        n = buf2;
                        break;
                case 'c':
-                       strlcat(fmt, ".0f", sizeof fmt);
                        if (!am_sender) {
                                b = stats.total_written -
                                        initial_stats->total_written;
@@ -453,6 +483,7 @@ static void log_formatted(enum logcode code, char *format, char *op,
                                b = stats.total_read -
                                        initial_stats->total_read;
                        }
+                       strlcat(fmt, ".0f", sizeof fmt);
                        snprintf(buf2, sizeof buf2, fmt, (double)b);
                        n = buf2;
                        break;
@@ -510,18 +541,18 @@ static void log_formatted(enum logcode code, char *format, char *op,
                len = strlen(n);
 
                /* Subtract the length of the escape from the string's size. */
-               total -= p - s;
+               total -= p - s + 1;
 
-               if (len + total >= sizeof buf) {
+               if (len + total >= (size_t)sizeof buf) {
                        rprintf(FERROR,
                                "buffer overflow expanding %%%c -- exiting\n",
-                               p[-1]);
+                               p[0]);
                        exit_cleanup(RERR_MESSAGEIO);
                }
 
                /* Shuffle the rest of the string along to make space for n */
-               if (len != (size_t)(p - s))
-                       memmove(s + len, p, total - (s - buf) + 1);
+               if (len != (size_t)(p - s + 1))
+                       memmove(s + len, p + 1, total - (s - buf) + 1);
                total += len;
 
                /* Insert the contents of string "n", but NOT its null. */
@@ -532,7 +563,29 @@ static void log_formatted(enum logcode code, char *format, char *op,
                p = s + len;
        }
 
-       rprintf(code, "%s\n", buf);
+       rwrite(code, buf, total);
+}
+
+/* Return 1 if the format escape is in the log-format string (e.g. look for
+ * the 'b' in the "%9b" format escape). */
+int log_format_has(const char *format, char esc)
+{
+       const char *p;
+
+       if (!format)
+               return 0;
+
+       for (p = format; (p = strchr(p, '%')) != NULL; ) {
+               if (*++p == '-')
+                       p++;
+               while (isdigit(*(uchar*)p))
+                       p++;
+               if (!*p)
+                       break;
+               if (*p == esc)
+                       return 1;
+       }
+       return 0;
 }
 
 /* log the transfer of a file */