panic_coredump option. Bug 2892
[exim.git] / src / src / log.c
1 /*************************************************
2 *     Exim - an Internet mail transport agent    *
3 *************************************************/
4
5 /* Copyright (c) The Exim Maintainers 2020 - 2022 */
6 /* Copyright (c) University of Cambridge 1995 - 2018 */
7 /* See the file NOTICE for conditions of use and distribution. */
8
9 /* Functions for writing log files. The code for maintaining datestamped
10 log files was originally contributed by Tony Sheen. */
11
12
13 #include "exim.h"
14
15 #define MAX_SYSLOG_LEN 870
16
17 #define LOG_MODE_FILE   1
18 #define LOG_MODE_SYSLOG 2
19
20 enum { lt_main, lt_reject, lt_panic, lt_debug };
21
22 static uschar *log_names[] = { US"main", US"reject", US"panic", US"debug" };
23
24
25
26 /*************************************************
27 *           Local static variables               *
28 *************************************************/
29
30 static uschar mainlog_name[LOG_NAME_SIZE];
31 static uschar rejectlog_name[LOG_NAME_SIZE];
32
33 static uschar *mainlog_datestamp = NULL;
34 static uschar *rejectlog_datestamp = NULL;
35
36 static int    mainlogfd = -1;
37 static int    rejectlogfd = -1;
38 static ino_t  mainlog_inode = 0;
39 static ino_t  rejectlog_inode = 0;
40
41 static uschar *panic_save_buffer = NULL;
42 static BOOL   panic_recurseflag = FALSE;
43
44 static BOOL   syslog_open = FALSE;
45 static BOOL   path_inspected = FALSE;
46 static int    logging_mode = LOG_MODE_FILE;
47 static uschar *file_path = US"";
48
49 static size_t pid_position[2];
50
51
52 /* These should be kept in-step with the private delivery error
53 number definitions in macros.h */
54
55 static const uschar * exim_errstrings[] = {
56   [0] = US"",
57   [- ERRNO_UNKNOWNERROR] =      US"unknown error",
58   [- ERRNO_USERSLASH] =         US"user slash",
59   [- ERRNO_EXISTRACE] =         US"exist race",
60   [- ERRNO_NOTREGULAR] =        US"not regular",
61   [- ERRNO_NOTDIRECTORY] =      US"not directory",
62   [- ERRNO_BADUGID] =           US"bad ugid",
63   [- ERRNO_BADMODE] =           US"bad mode",
64   [- ERRNO_INODECHANGED] =      US"inode changed",
65   [- ERRNO_LOCKFAILED] =        US"lock failed",
66   [- ERRNO_BADADDRESS2] =       US"bad address2",
67   [- ERRNO_FORBIDPIPE] =        US"forbid pipe",
68   [- ERRNO_FORBIDFILE] =        US"forbid file",
69   [- ERRNO_FORBIDREPLY] =       US"forbid reply",
70   [- ERRNO_MISSINGPIPE] =       US"missing pipe",
71   [- ERRNO_MISSINGFILE] =       US"missing file",
72   [- ERRNO_MISSINGREPLY] =      US"missing reply",
73   [- ERRNO_BADREDIRECT] =       US"bad redirect",
74   [- ERRNO_SMTPCLOSED] =        US"smtp closed",
75   [- ERRNO_SMTPFORMAT] =        US"smtp format",
76   [- ERRNO_SPOOLFORMAT] =       US"spool format",
77   [- ERRNO_NOTABSOLUTE] =       US"not absolute",
78   [- ERRNO_EXIMQUOTA] =         US"Exim-imposed quota",
79   [- ERRNO_HELD] =              US"held",
80   [- ERRNO_FILTER_FAIL] =       US"Delivery filter process failure",
81   [- ERRNO_CHHEADER_FAIL] =     US"Delivery add/remove header failure",
82   [- ERRNO_WRITEINCOMPLETE] =   US"Delivery write incomplete error",
83   [- ERRNO_EXPANDFAIL] =        US"Some expansion failed",
84   [- ERRNO_GIDFAIL] =           US"Failed to get gid",
85   [- ERRNO_UIDFAIL] =           US"Failed to get uid",
86   [- ERRNO_BADTRANSPORT] =      US"Unset or non-existent transport",
87   [- ERRNO_MBXLENGTH] =         US"MBX length mismatch",
88   [- ERRNO_UNKNOWNHOST] =       US"Lookup failed routing or in smtp tpt",
89   [- ERRNO_FORMATUNKNOWN] =     US"Can't match format in appendfile",
90   [- ERRNO_BADCREATE] =         US"Creation outside home in appendfile",
91   [- ERRNO_LISTDEFER] =         US"Can't check a list; lookup defer",
92   [- ERRNO_DNSDEFER] =          US"DNS lookup defer",
93   [- ERRNO_TLSFAILURE] =        US"Failed to start TLS session",
94   [- ERRNO_TLSREQUIRED] =       US"Mandatory TLS session not started",
95   [- ERRNO_CHOWNFAIL] =         US"Failed to chown a file",
96   [- ERRNO_PIPEFAIL] =          US"Failed to create a pipe",
97   [- ERRNO_CALLOUTDEFER] =      US"When verifying",
98   [- ERRNO_AUTHFAIL] =          US"When required by client",
99   [- ERRNO_CONNECTTIMEOUT] =    US"Used internally in smtp transport",
100   [- ERRNO_RCPT4XX] =           US"RCPT gave 4xx error",
101   [- ERRNO_MAIL4XX] =           US"MAIL gave 4xx error",
102   [- ERRNO_DATA4XX] =           US"DATA gave 4xx error",
103   [- ERRNO_PROXYFAIL] =         US"Negotiation failed for proxy configured host",
104   [- ERRNO_AUTHPROB] =          US"Authenticator 'other' failure",
105   [- ERRNO_UTF8_FWD] =          US"target not supporting SMTPUTF8",
106   [- ERRNO_HOST_IS_LOCAL] =     US"host is local",
107   [- ERRNO_TAINT] =             US"tainted filename",
108
109   [- ERRNO_RRETRY] =            US"Not time for routing",
110
111   [- ERRNO_LRETRY] =            US"Not time for local delivery",
112   [- ERRNO_HRETRY] =            US"Not time for any remote host",
113   [- ERRNO_LOCAL_ONLY] =        US"Local-only delivery",
114   [- ERRNO_QUEUE_DOMAIN] =      US"Domain in queue_domains",
115   [- ERRNO_TRETRY] =            US"Transport concurrency limit",
116
117   [- ERRNO_EVENT] =             US"Event requests alternate response",
118 };
119
120
121 /************************************************/
122 const uschar *
123 exim_errstr(int err)
124 {
125 return err < 0 ? exim_errstrings[-err] : CUS strerror(err);
126 }
127
128 /*************************************************
129 *              Write to syslog                   *
130 *************************************************/
131
132 /* The given string is split into sections according to length, or at embedded
133 newlines, and syslogged as a numbered sequence if it is overlong or if there is
134 more than one line. However, if we are running in the test harness, do not do
135 anything. (The test harness doesn't use syslog - for obvious reasons - but we
136 can get here if there is a failure to open the panic log.)
137
138 Arguments:
139   priority       syslog priority
140   s              the string to be written
141
142 Returns:         nothing
143 */
144
145 static void
146 write_syslog(int priority, const uschar *s)
147 {
148 int len;
149 int linecount = 0;
150
151 if (!syslog_pid && LOGGING(pid))
152   s = string_sprintf("%.*s%s", (int)pid_position[0], s, s + pid_position[1]);
153 if (!syslog_timestamp)
154   {
155   len = log_timezone ? 26 : 20;
156   if (LOGGING(millisec)) len += 4;
157   s += len;
158   }
159
160 len = Ustrlen(s);
161
162 #ifndef NO_OPENLOG
163 if (!syslog_open && !f.running_in_test_harness)
164   {
165 # ifdef SYSLOG_LOG_PID
166   openlog(CS syslog_processname, LOG_PID|LOG_CONS, syslog_facility);
167 # else
168   openlog(CS syslog_processname, LOG_CONS, syslog_facility);
169 # endif
170   syslog_open = TRUE;
171   }
172 #endif
173
174 /* First do a scan through the message in order to determine how many lines
175 it is going to end up as. Then rescan to output it. */
176
177 for (int pass = 0; pass < 2; pass++)
178   {
179   const uschar * ss = s;
180   for (int i = 1, tlen = len; tlen > 0; i++)
181     {
182     int plen = tlen;
183     uschar *nlptr = Ustrchr(ss, '\n');
184     if (nlptr != NULL) plen = nlptr - ss;
185 #ifndef SYSLOG_LONG_LINES
186     if (plen > MAX_SYSLOG_LEN) plen = MAX_SYSLOG_LEN;
187 #endif
188     tlen -= plen;
189     if (ss[plen] == '\n') tlen--;    /* chars left */
190
191     if (pass == 0)
192       linecount++;
193     else if (f.running_in_test_harness)
194       if (linecount == 1)
195         fprintf(stderr, "SYSLOG: '%.*s'\n", plen, ss);
196       else
197         fprintf(stderr, "SYSLOG: '[%d%c%d] %.*s'\n", i,
198           ss[plen] == '\n' && tlen != 0 ? '\\' : '/',
199           linecount, plen, ss);
200     else
201       if (linecount == 1)
202         syslog(priority, "%.*s", plen, ss);
203       else
204         syslog(priority, "[%d%c%d] %.*s", i,
205           ss[plen] == '\n' && tlen != 0 ? '\\' : '/',
206           linecount, plen, ss);
207
208     ss += plen;
209     if (*ss == '\n') ss++;
210     }
211   }
212 }
213
214
215
216 /*************************************************
217 *             Die tidily                         *
218 *************************************************/
219
220 /* This is called when Exim is dying as a result of something going wrong in
221 the logging, or after a log call with LOG_PANIC_DIE set. Optionally write a
222 message to debug_file or a stderr file, if they exist. Then, if in the middle
223 of accepting a message, throw it away tidily by calling receive_bomb_out();
224 this will attempt to send an SMTP response if appropriate. Passing NULL as the
225 first argument stops it trying to run the NOTQUIT ACL (which might try further
226 logging and thus cause problems). Otherwise, try to close down an outstanding
227 SMTP call tidily.
228
229 Arguments:
230   s1         Error message to write to debug_file and/or stderr and syslog
231   s2         Error message for any SMTP call that is in progress
232 Returns:     The function does not return
233 */
234
235 static void
236 die(uschar *s1, uschar *s2)
237 {
238 if (s1)
239   {
240   write_syslog(LOG_CRIT, s1);
241   if (debug_file) debug_printf("%s\n", s1);
242   if (log_stderr && log_stderr != debug_file)
243     fprintf(log_stderr, "%s\n", s1);
244   }
245 if (f.receive_call_bombout) receive_bomb_out(NULL, s2);  /* does not return */
246 if (smtp_input) smtp_closedown(s2);
247 exim_exit(EXIT_FAILURE);
248 }
249
250
251
252 /*************************************************
253 *             Create a log file                  *
254 *************************************************/
255
256 /* This function is called to create and open a log file. It may be called in a
257 subprocess when the original process is root.
258
259 Arguments:
260   name         the file name
261
262 The file name has been build in a working buffer, so it is permissible to
263 overwrite it temporarily if it is necessary to create the directory.
264
265 Returns:       a file descriptor, or < 0 on failure (errno set)
266 */
267
268 static int
269 log_open_already_exim(const uschar * const name)
270 {
271 int fd = -1;
272 const int flags = O_WRONLY | O_APPEND | O_CREAT | O_NONBLOCK;
273
274 if (geteuid() != exim_uid)
275   {
276   errno = EACCES;
277   return -1;
278   }
279
280 fd = Uopen(name, flags, LOG_MODE);
281
282 /* If creation failed, attempt to build a log directory in case that is the
283 problem. */
284
285 if (fd < 0 && errno == ENOENT)
286   {
287   BOOL created;
288   uschar *lastslash = Ustrrchr(name, '/');
289   *lastslash = 0;
290   created = directory_make(NULL, name, LOG_DIRECTORY_MODE, FALSE);
291   DEBUG(D_any)
292     if (created)
293       debug_printf("created log directory %s\n", name);
294     else
295       debug_printf("failed to create log directory %s: %s\n", name, strerror(errno));
296   *lastslash = '/';
297   if (created) fd = Uopen(name, flags, LOG_MODE);
298   }
299
300 return fd;
301 }
302
303
304
305 /* Inspired by OpenSSH's mm_send_fd(). Thanks!
306 Send fd over socketpair.
307 Return: true iff good.
308 */
309
310 static BOOL
311 log_send_fd(const int sock, const int fd)
312 {
313 struct msghdr msg;
314 union {
315   struct cmsghdr hdr;
316   char buf[CMSG_SPACE(sizeof(int))];
317 } cmsgbuf;
318 struct cmsghdr *cmsg;
319 char ch = 'A';
320 struct iovec vec = {.iov_base = &ch, .iov_len = 1};
321 ssize_t n;
322
323 memset(&msg, 0, sizeof(msg));
324 memset(&cmsgbuf, 0, sizeof(cmsgbuf));
325 msg.msg_control = &cmsgbuf.buf;
326 msg.msg_controllen = sizeof(cmsgbuf.buf);
327
328 cmsg = CMSG_FIRSTHDR(&msg);
329 cmsg->cmsg_len = CMSG_LEN(sizeof(int));
330 cmsg->cmsg_level = SOL_SOCKET;
331 cmsg->cmsg_type = SCM_RIGHTS;
332 *(int *)CMSG_DATA(cmsg) = fd;
333
334 msg.msg_iov = &vec;
335 msg.msg_iovlen = 1;
336
337 while ((n = sendmsg(sock, &msg, 0)) == -1 && errno == EINTR);
338 return n == 1;
339 }
340
341 /* Inspired by OpenSSH's mm_receive_fd(). Thanks!
342 Return fd passed over socketpair, or -1 on error.
343 */
344
345 static int
346 log_recv_fd(const int sock)
347 {
348 struct msghdr msg;
349 union {
350   struct cmsghdr hdr;
351   char buf[CMSG_SPACE(sizeof(int))];
352 } cmsgbuf;
353 struct cmsghdr *cmsg;
354 char ch = '\0';
355 struct iovec vec = {.iov_base = &ch, .iov_len = 1};
356 ssize_t n;
357 int fd;
358
359 memset(&msg, 0, sizeof(msg));
360 msg.msg_iov = &vec;
361 msg.msg_iovlen = 1;
362
363 memset(&cmsgbuf, 0, sizeof(cmsgbuf));
364 msg.msg_control = &cmsgbuf.buf;
365 msg.msg_controllen = sizeof(cmsgbuf.buf);
366
367 while ((n = recvmsg(sock, &msg, 0)) == -1 && errno == EINTR) ;
368 if (n != 1 || ch != 'A') return -1;
369
370 if (!(cmsg = CMSG_FIRSTHDR(&msg))) return -1;
371 if (cmsg->cmsg_type != SCM_RIGHTS) return -1;
372 if ((fd = *(const int *)CMSG_DATA(cmsg)) < 0) return -1;
373 return fd;
374 }
375
376
377
378 /*************************************************
379 *     Create a log file as the exim user         *
380 *************************************************/
381
382 /* This function is called when we are root to spawn an exim:exim subprocess
383 in which we can create a log file. It must be signal-safe since it is called
384 by the usr1_handler().
385
386 Arguments:
387   name         the file name
388
389 Returns:       a file descriptor, or < 0 on failure (errno set)
390 */
391
392 int
393 log_open_as_exim(const uschar * const name)
394 {
395 int fd = -1;
396 const uid_t euid = geteuid();
397
398 if (euid == exim_uid)
399   fd = log_open_already_exim(name);
400 else if (euid == root_uid)
401   {
402   int sock[2];
403   if (socketpair(AF_UNIX, SOCK_STREAM, 0, sock) == 0)
404     {
405     const pid_t pid = fork();
406     if (pid == 0)
407       {
408       (void)close(sock[0]);
409       if (  setgroups(1, &exim_gid) != 0
410          || setgid(exim_gid) != 0
411          || setuid(exim_uid) != 0
412
413          || getuid() != exim_uid || geteuid() != exim_uid
414          || getgid() != exim_gid || getegid() != exim_gid
415
416          || (fd = log_open_already_exim(name)) < 0
417          || !log_send_fd(sock[1], fd)
418          ) _exit(EXIT_FAILURE);
419       (void)close(sock[1]);
420       _exit(EXIT_SUCCESS);
421       }
422
423     (void)close(sock[1]);
424     if (pid > 0)
425       {
426       fd = log_recv_fd(sock[0]);
427       while (waitpid(pid, NULL, 0) == -1 && errno == EINTR);
428       }
429     (void)close(sock[0]);
430     }
431   }
432
433 if (fd >= 0)
434   {
435   int flags;
436   flags = fcntl(fd, F_GETFD);
437   if (flags != -1) (void)fcntl(fd, F_SETFD, flags | FD_CLOEXEC);
438   flags = fcntl(fd, F_GETFL);
439   if (flags != -1) (void)fcntl(fd, F_SETFL, flags & ~O_NONBLOCK);
440   }
441 else
442   errno = EACCES;
443
444 return fd;
445 }
446
447
448
449
450 /*************************************************
451 *                Open a log file                 *
452 *************************************************/
453
454 /* This function opens one of a number of logs, creating the log directory if
455 it does not exist. This may be called recursively on failure, in order to open
456 the panic log.
457
458 The directory is in the static variable file_path. This is static so that
459 the work of sorting out the path is done just once per Exim process.
460
461 Exim is normally configured to avoid running as root wherever possible, the log
462 files must be owned by the non-privileged exim user. To ensure this, first try
463 an open without O_CREAT - most of the time this will succeed. If it fails, try
464 to create the file; if running as root, this must be done in a subprocess to
465 avoid races.
466
467 Arguments:
468   fd         where to return the resulting file descriptor
469   type       lt_main, lt_reject, lt_panic, or lt_debug
470   tag        optional tag to include in the name (only hooked up for debug)
471
472 Returns:   nothing
473 */
474
475 static void
476 open_log(int * fd, int type, const uschar * tag)
477 {
478 uid_t euid;
479 BOOL ok, ok2;
480 uschar buffer[LOG_NAME_SIZE];
481
482 /* The names of the log files are controlled by file_path. The panic log is
483 written to the same directory as the main and reject logs, but its name does
484 not have a datestamp. The use of datestamps is indicated by %D/%M in file_path.
485 When opening the panic log, if %D or %M is present, we remove the datestamp
486 from the generated name; if it is at the start, remove a following
487 non-alphanumeric character as well; otherwise, remove a preceding
488 non-alphanumeric character. This is definitely kludgy, but it sort of does what
489 people want, I hope. */
490
491 ok = string_format(buffer, sizeof(buffer), CS file_path, log_names[type]);
492
493 switch (type)
494   {
495   case lt_main:
496     /* Save the name of the mainlog for rollover processing. Without a datestamp,
497     it gets statted to see if it has been cycled. With a datestamp, the datestamp
498     will be compared. The static slot for saving it is the same size as buffer,
499     and the text has been checked above to fit, so this use of strcpy() is OK. */
500     Ustrcpy(mainlog_name, buffer);
501     if (string_datestamp_offset > 0)
502       mainlog_datestamp = mainlog_name + string_datestamp_offset;
503     break;
504
505   case lt_reject:
506     /* Ditto for the reject log */
507     Ustrcpy(rejectlog_name, buffer);
508     if (string_datestamp_offset > 0)
509       rejectlog_datestamp = rejectlog_name + string_datestamp_offset;
510     break;
511
512   case lt_debug:
513     /* and deal with the debug log (which keeps the datestamp, but does not
514     update it) */
515     Ustrcpy(debuglog_name, buffer);
516     if (tag)
517       {
518       if (is_tainted(tag))
519         die(US"exim: tainted tag for debug log filename",
520               US"Logging failure; please try later");
521
522       /* this won't change the offset of the datestamp */
523       ok2 = string_format(buffer, sizeof(buffer), "%s%s",
524         debuglog_name, tag);
525       if (ok2)
526         Ustrcpy(debuglog_name, buffer);
527       }
528     break;
529
530   default:
531     /* Remove any datestamp if this is the panic log. This is rare, so there's no
532     need to optimize getting the datestamp length. We remove one non-alphanumeric
533     char afterwards if at the start, otherwise one before. */
534     if (string_datestamp_offset >= 0)
535       {
536       uschar * from = buffer + string_datestamp_offset;
537       uschar * to = from + string_datestamp_length;
538
539       if (from == buffer || from[-1] == '/')
540         {
541         if (!isalnum(*to)) to++;
542         }
543       else
544         if (!isalnum(from[-1])) from--;
545
546       /* This copy is ok, because we know that to is a substring of from. But
547       due to overlap we must use memmove() not Ustrcpy(). */
548       memmove(from, to, Ustrlen(to)+1);
549       }
550     break;
551   }
552
553 /* If the file name is too long, it is an unrecoverable disaster */
554
555 if (!ok)
556   die(US"exim: log file path too long: aborting",
557       US"Logging failure; please try later");
558
559 /* We now have the file name. After a successful open, return. */
560
561 if ((*fd = log_open_as_exim(buffer)) >= 0)
562   return;
563
564 euid = geteuid();
565
566 /* Creation failed. There are some circumstances in which we get here when
567 the effective uid is not root or exim, which is the problem. (For example, a
568 non-setuid binary with log_arguments set, called in certain ways.) Rather than
569 just bombing out, force the log to stderr and carry on if stderr is available.
570 */
571
572 if (euid != root_uid && euid != exim_uid && log_stderr)
573   {
574   *fd = fileno(log_stderr);
575   return;
576   }
577
578 /* Otherwise this is a disaster. This call is deliberately ONLY to the panic
579 log. If possible, save a copy of the original line that was being logged. If we
580 are recursing (can't open the panic log either), the pointer will already be
581 set.  Also, when we had to use a subprocess for the create we didn't retrieve
582 errno from it, so get the error from the open attempt above (which is often
583 meaningful enough, so leave it). */
584
585 if (!panic_save_buffer)
586   if ((panic_save_buffer = US malloc(LOG_BUFFER_SIZE)))
587     memcpy(panic_save_buffer, log_buffer, LOG_BUFFER_SIZE);
588
589 log_write(0, LOG_PANIC_DIE, "Cannot open %s log file \"%s\": %s: "
590   "euid=%d egid=%d", log_names[type], buffer, strerror(errno), euid, getegid());
591 /* Never returns */
592 }
593
594
595 static void
596 unlink_log(int type)
597 {
598 if (type == lt_debug) unlink(CS debuglog_name);
599 }
600
601
602
603 /*************************************************
604 *     Add configuration file info to log line    *
605 *************************************************/
606
607 /* This is put in a function because it's needed twice (once for debugging,
608 once for real).
609
610 Arguments:
611   ptr         pointer to the end of the line we are building
612   flags       log flags
613
614 Returns:      updated pointer
615 */
616
617 static gstring *
618 log_config_info(gstring * g, int flags)
619 {
620 g = string_cat(g, US"Exim configuration error");
621
622 if (flags & (LOG_CONFIG_FOR & ~LOG_CONFIG))
623   return string_cat(g, US" for ");
624
625 if (flags & (LOG_CONFIG_IN & ~LOG_CONFIG))
626   g = string_fmt_append(g, " in line %d of %s", config_lineno, config_filename);
627
628 return string_catn(g, US":\n  ", 4);
629 }
630
631
632 /*************************************************
633 *           A write() operation failed           *
634 *************************************************/
635
636 /* This function is called when write() fails on anything other than the panic
637 log, which can happen if a disk gets full or a file gets too large or whatever.
638 We try to save the relevant message in the panic_save buffer before crashing
639 out.
640
641 The potential invoker should probably not call us for EINTR -1 writes.  But
642 otherwise, short writes are bad as we don't do non-blocking writes to fds
643 subject to flow control.  (If we do, that's new and the logic of this should
644 be reconsidered).
645
646 Arguments:
647   name      the name of the log being written
648   length    the string length being written
649   rc        the return value from write()
650
651 Returns:    does not return
652 */
653
654 static void
655 log_write_failed(uschar *name, int length, int rc)
656 {
657 int save_errno = errno;
658
659 if (!panic_save_buffer)
660   if ((panic_save_buffer = US malloc(LOG_BUFFER_SIZE)))
661     memcpy(panic_save_buffer, log_buffer, LOG_BUFFER_SIZE);
662
663 log_write(0, LOG_PANIC_DIE, "failed to write to %s: length=%d result=%d "
664   "errno=%d (%s)", name, length, rc, save_errno,
665   (save_errno == 0)? "write incomplete" : strerror(save_errno));
666 /* Never returns */
667 }
668
669
670
671 /*************************************************
672 *     Write to an fd, retrying after signals     *
673 *************************************************/
674
675 /* Basic write to fd for logs, handling EINTR.
676
677 Arguments:
678   fd        the fd to write to
679   buf       the string to write
680   length    the string length being written
681
682 Returns:
683   length actually written, persisting an errno from write()
684 */
685 ssize_t
686 write_to_fd_buf(int fd, const uschar *buf, size_t length)
687 {
688 ssize_t wrote;
689 size_t total_written = 0;
690 const uschar *p = buf;
691 size_t left = length;
692
693 while (1)
694   {
695   wrote = write(fd, p, left);
696   if (wrote == (ssize_t)-1)
697     {
698     if (errno == EINTR) continue;
699     return wrote;
700     }
701   total_written += wrote;
702   if (wrote == left)
703     break;
704   else
705     {
706     p += wrote;
707     left -= wrote;
708     }
709   }
710 return total_written;
711 }
712
713
714
715 static void
716 set_file_path(void)
717 {
718 int sep = ':';              /* Fixed separator - outside use */
719 uschar *t;
720 const uschar *tt = US LOG_FILE_PATH;
721 while ((t = string_nextinlist(&tt, &sep, log_buffer, LOG_BUFFER_SIZE)))
722   {
723   if (Ustrcmp(t, "syslog") == 0 || t[0] == 0) continue;
724   file_path = string_copy(t);
725   break;
726   }
727 }
728
729
730 /* Close mainlog, unless we do not see a chance to open the file mainlog later
731 again.  This will happen if we log from a transport process (which has dropped
732 privs); something we traditionally avoid, but the introduction of taint-tracking
733 and resulting detection of errors is makinng harder. */
734
735 void
736 mainlog_close(void)
737 {
738 if (mainlogfd < 0
739    || !(geteuid() == 0 || geteuid() == exim_uid))
740   return;
741 (void)close(mainlogfd);
742 mainlogfd = -1;
743 mainlog_inode = 0;
744 }
745
746 /*************************************************
747 *            Write message to log file           *
748 *************************************************/
749
750 /* Exim can be configured to log to local files, or use syslog, or both. This
751 is controlled by the setting of log_file_path. The following cases are
752 recognized:
753
754   log_file_path = ""               write files in the spool/log directory
755   log_file_path = "xxx"            write files in the xxx directory
756   log_file_path = "syslog"         write to syslog
757   log_file_path = "syslog : xxx"   write to syslog and to files (any order)
758
759 The message always gets '\n' added on the end of it, since more than one
760 process may be writing to the log at once and we don't want intermingling to
761 happen in the middle of lines. To be absolutely sure of this we write the data
762 into a private buffer and then put it out in a single write() call.
763
764 The flags determine which log(s) the message is written to, or for syslogging,
765 which priority to use, and in the case of the panic log, whether the process
766 should die afterwards.
767
768 The variable really_exim is TRUE only when exim is running in privileged state
769 (i.e. not with a changed configuration or with testing options such as -brw).
770 If it is not, don't try to write to the log because permission will probably be
771 denied.
772
773 Avoid actually writing to the logs when exim is called with -bv or -bt to
774 test an address, but take other actions, such as panicking.
775
776 In Exim proper, the buffer for building the message is got at start-up, so that
777 nothing gets done if it can't be got. However, some functions that are also
778 used in utilities occasionally obey log_write calls in error situations, and it
779 is simplest to put a single malloc() here rather than put one in each utility.
780 Malloc is used directly because the store functions may call log_write().
781
782 If a message_id exists, we include it after the timestamp.
783
784 Arguments:
785   selector  write to main log or LOG_INFO only if this value is zero, or if
786               its bit is set in log_selector[0]
787   flags     each bit indicates some independent action:
788               LOG_SENDER      add raw sender to the message
789               LOG_RECIPIENTS  add raw recipients list to message
790               LOG_CONFIG      add "Exim configuration error"
791               LOG_CONFIG_FOR  add " for " instead of ":\n  "
792               LOG_CONFIG_IN   add " in line x[ of file y]"
793               LOG_MAIN        write to main log or syslog LOG_INFO
794               LOG_REJECT      write to reject log or syslog LOG_NOTICE
795               LOG_PANIC       write to panic log or syslog LOG_ALERT
796               LOG_PANIC_DIE   write to panic log or LOG_ALERT and then crash
797   format    a printf() format
798   ...       arguments for format
799
800 Returns:    nothing
801 */
802
803 void
804 log_write(unsigned int selector, int flags, const char *format, ...)
805 {
806 int paniclogfd;
807 ssize_t written_len;
808 gstring gs = { .size = LOG_BUFFER_SIZE-1, .ptr = 0, .s = log_buffer };
809 gstring * g;
810 va_list ap;
811
812 /* If panic_recurseflag is set, we have failed to open the panic log. This is
813 the ultimate disaster. First try to write the message to a debug file and/or
814 stderr and also to syslog. If panic_save_buffer is not NULL, it contains the
815 original log line that caused the problem. Afterwards, expire. */
816
817 if (panic_recurseflag)
818   {
819   uschar *extra = panic_save_buffer ? panic_save_buffer : US"";
820   if (debug_file) debug_printf("%s%s", extra, log_buffer);
821   if (log_stderr && log_stderr != debug_file)
822     fprintf(log_stderr, "%s%s", extra, log_buffer);
823   if (*extra) write_syslog(LOG_CRIT, extra);
824   write_syslog(LOG_CRIT, log_buffer);
825   die(US"exim: could not open panic log - aborting: see message(s) above",
826     US"Unexpected log failure, please try later");
827   }
828
829 /* Ensure we have a buffer (see comment above); this should never be obeyed
830 when running Exim proper, only when running utilities. */
831
832 if (!log_buffer)
833   if (!(log_buffer = US malloc(LOG_BUFFER_SIZE)))
834     {
835     fprintf(stderr, "exim: failed to get store for log buffer\n");
836     exim_exit(EXIT_FAILURE);
837     }
838
839 /* If we haven't already done so, inspect the setting of log_file_path to
840 determine whether to log to files and/or to syslog. Bits in logging_mode
841 control this, and for file logging, the path must end up in file_path. This
842 variable must be in permanent store because it may be required again later in
843 the process. */
844
845 if (!path_inspected)
846   {
847   BOOL multiple = FALSE;
848   int old_pool = store_pool;
849
850   store_pool = POOL_PERM;
851
852   /* If nothing has been set, don't waste effort... the default values for the
853   statics are file_path="" and logging_mode = LOG_MODE_FILE. */
854
855   if (*log_file_path)
856     {
857     int sep = ':';              /* Fixed separator - outside use */
858     uschar *s;
859     const uschar *ss = log_file_path;
860
861     logging_mode = 0;
862     while ((s = string_nextinlist(&ss, &sep, log_buffer, LOG_BUFFER_SIZE)))
863       {
864       if (Ustrcmp(s, "syslog") == 0)
865         logging_mode |= LOG_MODE_SYSLOG;
866       else if (logging_mode & LOG_MODE_FILE)
867         multiple = TRUE;
868       else
869         {
870         logging_mode |= LOG_MODE_FILE;
871
872         /* If a non-empty path is given, use it */
873
874         if (*s)
875           file_path = string_copy(s);
876
877         /* If the path is empty, we want to use the first non-empty, non-
878         syslog item in LOG_FILE_PATH, if there is one, since the value of
879         log_file_path may have been set at runtime. If there is no such item,
880         use the ultimate default in the spool directory. */
881
882         else
883           set_file_path();  /* Empty item in log_file_path */
884         }    /* First non-syslog item in log_file_path */
885       }      /* Scan of log_file_path */
886     }
887
888   /* If no modes have been selected, it is a major disaster */
889
890   if (logging_mode == 0)
891     die(US"Neither syslog nor file logging set in log_file_path",
892         US"Unexpected logging failure");
893
894   /* Set up the ultimate default if necessary. Then revert to the old store
895   pool, and record that we've sorted out the path. */
896
897   if (logging_mode & LOG_MODE_FILE  &&  !file_path[0])
898     file_path = string_sprintf("%s/log/%%slog", spool_directory);
899   store_pool = old_pool;
900   path_inspected = TRUE;
901
902   /* If more than one file path was given, log a complaint. This recursive call
903   should work since we have now set up the routing. */
904
905   if (multiple)
906     log_write(0, LOG_MAIN|LOG_PANIC,
907       "More than one path given in log_file_path: using %s", file_path);
908   }
909
910 /* Optionally trigger debug */
911
912 if (flags & LOG_PANIC && dtrigger_selector & BIT(DTi_panictrigger))
913   debug_trigger_fire();
914
915 /* If debugging, show all log entries, but don't show headers. Do it all
916 in one go so that it doesn't get split when multi-processing. */
917
918 DEBUG(D_any|D_v)
919   {
920   int i;
921
922   g = string_catn(&gs, US"LOG:", 4);
923
924   /* Show the selector that was passed into the call. */
925
926   for (i = 0; i < log_options_count; i++)
927     {
928     unsigned int bitnum = log_options[i].bit;
929     if (bitnum < BITWORDSIZE && selector == BIT(bitnum))
930       g = string_fmt_append(g, " %s", log_options[i].name);
931     }
932
933   g = string_fmt_append(g, "%s%s%s%s\n  ",
934     flags & LOG_MAIN ?    " MAIN"   : "",
935     flags & LOG_PANIC ?   " PANIC"  : "",
936     (flags & LOG_PANIC_DIE) == LOG_PANIC_DIE ? " DIE" : "",
937     flags & LOG_REJECT ?  " REJECT" : "");
938
939   if (flags & LOG_CONFIG) g = log_config_info(g, flags);
940
941   /* We want to be able to log tainted info, but log_buffer is directly
942   malloc'd.  So use deliberately taint-nonchecking routines to build into
943   it, trusting that we will never expand the results. */
944
945   va_start(ap, format);
946   i = g->ptr;
947   if (!string_vformat(g, SVFMT_TAINT_NOCHK, format, ap))
948     {
949     g->ptr = i;
950     g = string_cat(g, US"**** log string overflowed log buffer ****");
951     }
952   va_end(ap);
953
954   g->size = LOG_BUFFER_SIZE;
955   g = string_catn(g, US"\n", 1);
956   debug_printf("%s", string_from_gstring(g));
957
958   gs.size = LOG_BUFFER_SIZE-1;  /* Having used the buffer for debug output, */
959   gs.ptr = 0;                   /* reset it for the real use. */
960   gs.s = log_buffer;
961   }
962 /* If no log file is specified, we are in a mess. */
963
964 if (!(flags & (LOG_MAIN|LOG_PANIC|LOG_REJECT)))
965   log_write(0, LOG_MAIN|LOG_PANIC_DIE, "log_write called with no log "
966     "flags set");
967
968 /* There are some weird circumstances in which logging is disabled. */
969
970 if (f.disable_logging)
971   {
972   DEBUG(D_any) debug_printf("log writing disabled\n");
973   if ((flags & LOG_PANIC_DIE) == LOG_PANIC_DIE) exim_exit(EXIT_FAILURE);
974   return;
975   }
976
977 /* Handle disabled reject log */
978
979 if (!write_rejectlog) flags &= ~LOG_REJECT;
980
981 /* Create the main message in the log buffer. Do not include the message id
982 when called by a utility. */
983
984 g = string_fmt_append(&gs, "%s ", tod_stamp(tod_log));
985
986 if (LOGGING(pid))
987   {
988   if (!syslog_pid) pid_position[0] = g->ptr;            /* remember begin â€¦ */
989   g = string_fmt_append(g, "[%d] ", (int)getpid());
990   if (!syslog_pid) pid_position[1] = g->ptr;            /*  â€¦ and end+1 of the PID */
991   }
992
993 if (f.really_exim && message_id[0] != 0)
994   g = string_fmt_append(g, "%s ", message_id);
995
996 if (flags & LOG_CONFIG)
997   g = log_config_info(g, flags);
998
999 va_start(ap, format);
1000   {
1001   int i = g->ptr;
1002
1003   /* We want to be able to log tainted info, but log_buffer is directly
1004   malloc'd.  So use deliberately taint-nonchecking routines to build into
1005   it, trusting that we will never expand the results. */
1006
1007   if (!string_vformat(g, SVFMT_TAINT_NOCHK, format, ap))
1008     {
1009     g->ptr = i;
1010     g = string_cat(g, US"**** log string overflowed log buffer ****\n");
1011     }
1012   }
1013 va_end(ap);
1014
1015 /* Add the raw, unrewritten, sender to the message if required. This is done
1016 this way because it kind of fits with LOG_RECIPIENTS. */
1017
1018 if (   flags & LOG_SENDER
1019    && g->ptr < LOG_BUFFER_SIZE - 10 - Ustrlen(raw_sender))
1020   g = string_fmt_append_f(g, SVFMT_TAINT_NOCHK, " from <%s>", raw_sender);
1021
1022 /* Add list of recipients to the message if required; the raw list,
1023 before rewriting, was saved in raw_recipients. There may be none, if an ACL
1024 discarded them all. */
1025
1026 if (  flags & LOG_RECIPIENTS
1027    && g->ptr < LOG_BUFFER_SIZE - 6
1028    && raw_recipients_count > 0)
1029   {
1030   int i;
1031   g = string_fmt_append_f(g, SVFMT_TAINT_NOCHK, " for", NULL);
1032   for (i = 0; i < raw_recipients_count; i++)
1033     {
1034     uschar * s = raw_recipients[i];
1035     if (LOG_BUFFER_SIZE - g->ptr < Ustrlen(s) + 3) break;
1036     g = string_fmt_append_f(g, SVFMT_TAINT_NOCHK, " %s", s);
1037     }
1038   }
1039
1040 g = string_catn(g, US"\n", 1);
1041 string_from_gstring(g);
1042
1043 /* Handle loggable errors when running a utility, or when address testing.
1044 Write to log_stderr unless debugging (when it will already have been written),
1045 or unless there is no log_stderr (expn called from daemon, for example). */
1046
1047 if (!f.really_exim || f.log_testing_mode)
1048   {
1049   if (  !debug_selector
1050      && log_stderr
1051      && (selector == 0 || (selector & log_selector[0]) != 0)
1052     )
1053     if (host_checking)
1054       fprintf(log_stderr, "LOG: %s", CS(log_buffer + 20));  /* no timestamp */
1055     else
1056       fprintf(log_stderr, "%s", CS log_buffer);
1057
1058   if ((flags & LOG_PANIC_DIE) == LOG_PANIC_DIE) exim_exit(EXIT_FAILURE);
1059   return;
1060   }
1061
1062 /* Handle the main log. We know that either syslog or file logging (or both) is
1063 set up. A real file gets left open during reception or delivery once it has
1064 been opened, but we don't want to keep on writing to it for too long after it
1065 has been renamed. Therefore, do a stat() and see if the inode has changed, and
1066 if so, re-open. */
1067
1068 if (  flags & LOG_MAIN
1069    && (!selector ||  selector & log_selector[0]))
1070   {
1071   if (  logging_mode & LOG_MODE_SYSLOG
1072      && (syslog_duplication || !(flags & (LOG_REJECT|LOG_PANIC))))
1073     write_syslog(LOG_INFO, log_buffer);
1074
1075   if (logging_mode & LOG_MODE_FILE)
1076     {
1077     struct stat statbuf;
1078
1079     /* Check for a change to the mainlog file name when datestamping is in
1080     operation. This happens at midnight, at which point we want to roll over
1081     the file. Closing it has the desired effect. */
1082
1083     if (mainlog_datestamp)
1084       {
1085       uschar *nowstamp = tod_stamp(string_datestamp_type);
1086       if (Ustrncmp (mainlog_datestamp, nowstamp, Ustrlen(nowstamp)) != 0)
1087         {
1088         (void)close(mainlogfd);       /* Close the file */
1089         mainlogfd = -1;               /* Clear the file descriptor */
1090         mainlog_inode = 0;            /* Unset the inode */
1091         mainlog_datestamp = NULL;     /* Clear the datestamp */
1092         }
1093       }
1094
1095     /* Otherwise, we want to check whether the file has been renamed by a
1096     cycling script. This could be "if else", but for safety's sake, leave it as
1097     "if" so that renaming the log starts a new file even when datestamping is
1098     happening. */
1099
1100     if (mainlogfd >= 0)
1101       if (Ustat(mainlog_name, &statbuf) < 0 || statbuf.st_ino != mainlog_inode)
1102         mainlog_close();
1103
1104     /* If the log is closed, open it. Then write the line. */
1105
1106     if (mainlogfd < 0)
1107       {
1108       open_log(&mainlogfd, lt_main, NULL);     /* No return on error */
1109       if (fstat(mainlogfd, &statbuf) >= 0) mainlog_inode = statbuf.st_ino;
1110       }
1111
1112     /* Failing to write to the log is disastrous */
1113
1114     written_len = write_to_fd_buf(mainlogfd, g->s, g->ptr);
1115     if (written_len != g->ptr)
1116       {
1117       log_write_failed(US"main log", g->ptr, written_len);
1118       /* That function does not return */
1119       }
1120     }
1121   }
1122
1123 /* Handle the log for rejected messages. This can be globally disabled, in
1124 which case the flags are altered above. If there are any header lines (i.e. if
1125 the rejection is happening after the DATA phase), log the recipients and the
1126 headers. */
1127
1128 if (flags & LOG_REJECT)
1129   {
1130   if (header_list && LOGGING(rejected_header))
1131     {
1132     gstring * g2;
1133     int i;
1134
1135     if (recipients_count > 0)
1136       {
1137       /* List the sender */
1138
1139       g2 = string_fmt_append_f(g, SVFMT_TAINT_NOCHK,
1140                         "Envelope-from: <%s>\n", sender_address);
1141       if (g2) g = g2;
1142
1143       /* List up to 5 recipients */
1144
1145       g2 = string_fmt_append_f(g, SVFMT_TAINT_NOCHK,
1146                         "Envelope-to: <%s>\n", recipients_list[0].address);
1147       if (g2) g = g2;
1148
1149       for (i = 1; i < recipients_count && i < 5; i++)
1150         {
1151         g2 = string_fmt_append_f(g, SVFMT_TAINT_NOCHK,
1152                         "    <%s>\n", recipients_list[i].address);
1153         if (g2) g = g2;
1154         }
1155
1156       if (i < recipients_count)
1157         {
1158         g2 = string_fmt_append_f(g, SVFMT_TAINT_NOCHK, "    ...\n", NULL);
1159         if (g2) g = g2;
1160         }
1161       }
1162
1163     /* A header with a NULL text is an unfilled in Received: header */
1164
1165     for (header_line * h = header_list; h; h = h->next) if (h->text)
1166       {
1167       g2 = string_fmt_append_f(g, SVFMT_TAINT_NOCHK,
1168                         "%c %s", h->type, h->text);
1169       if (g2)
1170         g = g2;
1171       else              /* Buffer is full; truncate */
1172         {
1173         g->ptr -= 100;        /* For message and separator */
1174         if (g->s[g->ptr-1] == '\n') g->ptr--;
1175         g = string_cat(g, US"\n*** truncated ***\n");
1176         break;
1177         }
1178       }
1179     }
1180
1181   /* Write to syslog or to a log file */
1182
1183   if (  logging_mode & LOG_MODE_SYSLOG
1184      && (syslog_duplication || !(flags & LOG_PANIC)))
1185     write_syslog(LOG_NOTICE, string_from_gstring(g));
1186
1187   /* Check for a change to the rejectlog file name when datestamping is in
1188   operation. This happens at midnight, at which point we want to roll over
1189   the file. Closing it has the desired effect. */
1190
1191   if (logging_mode & LOG_MODE_FILE)
1192     {
1193     struct stat statbuf;
1194
1195     if (rejectlog_datestamp)
1196       {
1197       uschar *nowstamp = tod_stamp(string_datestamp_type);
1198       if (Ustrncmp (rejectlog_datestamp, nowstamp, Ustrlen(nowstamp)) != 0)
1199         {
1200         (void)close(rejectlogfd);       /* Close the file */
1201         rejectlogfd = -1;               /* Clear the file descriptor */
1202         rejectlog_inode = 0;            /* Unset the inode */
1203         rejectlog_datestamp = NULL;     /* Clear the datestamp */
1204         }
1205       }
1206
1207     /* Otherwise, we want to check whether the file has been renamed by a
1208     cycling script. This could be "if else", but for safety's sake, leave it as
1209     "if" so that renaming the log starts a new file even when datestamping is
1210     happening. */
1211
1212     if (rejectlogfd >= 0)
1213       if (Ustat(rejectlog_name, &statbuf) < 0 ||
1214            statbuf.st_ino != rejectlog_inode)
1215         {
1216         (void)close(rejectlogfd);
1217         rejectlogfd = -1;
1218         rejectlog_inode = 0;
1219         }
1220
1221     /* Open the file if necessary, and write the data */
1222
1223     if (rejectlogfd < 0)
1224       {
1225       open_log(&rejectlogfd, lt_reject, NULL); /* No return on error */
1226       if (fstat(rejectlogfd, &statbuf) >= 0) rejectlog_inode = statbuf.st_ino;
1227       }
1228
1229     written_len = write_to_fd_buf(rejectlogfd, g->s, g->ptr);
1230     if (written_len != g->ptr)
1231       {
1232       log_write_failed(US"reject log", g->ptr, written_len);
1233       /* That function does not return */
1234       }
1235     }
1236   }
1237
1238
1239 /* Handle the panic log, which is not kept open like the others. If it fails to
1240 open, there will be a recursive call to log_write(). We detect this above and
1241 attempt to write to the system log as a last-ditch try at telling somebody. In
1242 all cases except mua_wrapper, try to write to log_stderr. */
1243
1244 if (flags & LOG_PANIC)
1245   {
1246   if (log_stderr && log_stderr != debug_file && !mua_wrapper)
1247     fprintf(log_stderr, "%s", CS string_from_gstring(g));
1248
1249   if (logging_mode & LOG_MODE_SYSLOG)
1250     write_syslog(LOG_ALERT, log_buffer);
1251
1252   /* If this panic logging was caused by a failure to open the main log,
1253   the original log line is in panic_save_buffer. Make an attempt to write it. */
1254
1255   if (logging_mode & LOG_MODE_FILE)
1256     {
1257     panic_recurseflag = TRUE;
1258     open_log(&paniclogfd, lt_panic, NULL);  /* Won't return on failure */
1259     panic_recurseflag = FALSE;
1260
1261     if (panic_save_buffer)
1262       (void) write(paniclogfd, panic_save_buffer, Ustrlen(panic_save_buffer));
1263
1264     written_len = write_to_fd_buf(paniclogfd, g->s, g->ptr);
1265     if (written_len != g->ptr)
1266       {
1267       int save_errno = errno;
1268       write_syslog(LOG_CRIT, log_buffer);
1269       sprintf(CS log_buffer, "write failed on panic log: length=%d result=%d "
1270         "errno=%d (%s)", g->ptr, (int)written_len, save_errno, strerror(save_errno));
1271       write_syslog(LOG_CRIT, string_from_gstring(g));
1272       flags |= LOG_PANIC_DIE;
1273       }
1274
1275     (void)close(paniclogfd);
1276     }
1277
1278   /* Give up if the DIE flag is set */
1279
1280   if ((flags & LOG_PANIC_DIE) != LOG_PANIC)
1281     if (panic_coredump)
1282       kill(getpid(), SIGSEGV);  /* deliberate trap */
1283     else
1284       die(NULL, US"Unexpected failure, please try later");
1285   }
1286 }
1287
1288
1289
1290 /*************************************************
1291 *            Close any open log files            *
1292 *************************************************/
1293
1294 void
1295 log_close_all(void)
1296 {
1297 if (mainlogfd >= 0)
1298   { (void)close(mainlogfd); mainlogfd = -1; }
1299 if (rejectlogfd >= 0)
1300   { (void)close(rejectlogfd); rejectlogfd = -1; }
1301 closelog();
1302 syslog_open = FALSE;
1303 }
1304
1305
1306
1307 /*************************************************
1308 *             Multi-bit set or clear             *
1309 *************************************************/
1310
1311 /* These functions take a list of bit indexes (terminated by -1) and
1312 clear or set the corresponding bits in the selector.
1313
1314 Arguments:
1315   selector       address of the bit string
1316   selsize        number of words in the bit string
1317   bits           list of bits to set
1318 */
1319
1320 void
1321 bits_clear(unsigned int *selector, size_t selsize, int *bits)
1322 {
1323 for(; *bits != -1; ++bits)
1324   BIT_CLEAR(selector, selsize, *bits);
1325 }
1326
1327 void
1328 bits_set(unsigned int *selector, size_t selsize, int *bits)
1329 {
1330 for(; *bits != -1; ++bits)
1331   BIT_SET(selector, selsize, *bits);
1332 }
1333
1334
1335
1336 /*************************************************
1337 *         Decode bit settings for log/debug      *
1338 *************************************************/
1339
1340 /* This function decodes a string containing bit settings in the form of +name
1341 and/or -name sequences, and sets/unsets bits in a bit string accordingly. It
1342 also recognizes a numeric setting of the form =<number>, but this is not
1343 intended for user use. It's an easy way for Exim to pass the debug settings
1344 when it is re-exec'ed.
1345
1346 The option table is a list of names and bit indexes. The index -1
1347 means "set all bits, except for those listed in notall". The notall
1348 list is terminated by -1.
1349
1350 The action taken for bad values varies depending upon why we're here.
1351 For log messages, or if the debugging is triggered from config, then we write
1352 to the log on the way out.  For debug setting triggered from the command-line,
1353 we treat it as an unknown option: error message to stderr and die.
1354
1355 Arguments:
1356   selector       address of the bit string
1357   selsize        number of words in the bit string
1358   notall         list of bits to exclude from "all"
1359   string         the configured string
1360   options        the table of option names
1361   count          size of table
1362   which          "log" or "debug"
1363   flags          DEBUG_FROM_CONFIG
1364
1365 Returns:         nothing on success - bomb out on failure
1366 */
1367
1368 void
1369 decode_bits(unsigned int * selector, size_t selsize, int * notall,
1370   const uschar * string, bit_table * options, int count, uschar * which,
1371   int flags)
1372 {
1373 uschar *errmsg;
1374 if (!string) return;
1375
1376 if (*string == '=')
1377   {
1378   char *end;    /* Not uschar */
1379   memset(selector, 0, sizeof(*selector)*selsize);
1380   *selector = strtoul(CCS string+1, &end, 0);
1381   if (!*end) return;
1382   errmsg = string_sprintf("malformed numeric %s_selector setting: %s", which,
1383     string);
1384   goto ERROR_RETURN;
1385   }
1386
1387 /* Handle symbolic setting */
1388
1389 else for(;;)
1390   {
1391   BOOL adding;
1392   const uschar * s;
1393   int len;
1394   bit_table * start, * end;
1395
1396   Uskip_whitespace(&string);
1397   if (!*string) return;
1398
1399   if (*string != '+' && *string != '-')
1400     {
1401     errmsg = string_sprintf("malformed %s_selector setting: "
1402       "+ or - expected but found \"%s\"", which, string);
1403     goto ERROR_RETURN;
1404     }
1405
1406   adding = *string++ == '+';
1407   s = string;
1408   while (isalnum(*string) || *string == '_') string++;
1409   len = string - s;
1410
1411   start = options;
1412   end = options + count;
1413
1414   while (start < end)
1415     {
1416     bit_table *middle = start + (end - start)/2;
1417     int c = Ustrncmp(s, middle->name, len);
1418     if (c == 0)
1419       if (middle->name[len] != 0) c = -1; else
1420         {
1421         unsigned int bit = middle->bit;
1422
1423         if (bit == -1)
1424           {
1425           if (adding)
1426             {
1427             memset(selector, -1, sizeof(*selector)*selsize);
1428             bits_clear(selector, selsize, notall);
1429             }
1430           else
1431             memset(selector, 0, sizeof(*selector)*selsize);
1432           }
1433         else if (adding)
1434           BIT_SET(selector, selsize, bit);
1435         else
1436           BIT_CLEAR(selector, selsize, bit);
1437
1438         break;  /* Out of loop to match selector name */
1439         }
1440     if (c < 0) end = middle; else start = middle + 1;
1441     }  /* Loop to match selector name */
1442
1443   if (start >= end)
1444     {
1445     errmsg = string_sprintf("unknown %s_selector setting: %c%.*s", which,
1446       adding? '+' : '-', len, s);
1447     goto ERROR_RETURN;
1448     }
1449   }    /* Loop for selector names */
1450
1451 /* Handle disasters */
1452
1453 ERROR_RETURN:
1454 if (Ustrcmp(which, "debug") == 0)
1455   {
1456   if (flags & DEBUG_FROM_CONFIG)
1457     {
1458     log_write(0, LOG_CONFIG|LOG_PANIC, "%s", errmsg);
1459     return;
1460     }
1461   fprintf(stderr, "exim: %s\n", errmsg);
1462   exit(EXIT_FAILURE);
1463   }
1464 else log_write(0, LOG_CONFIG|LOG_PANIC_DIE, "%s", errmsg);
1465 }
1466
1467
1468
1469 /*************************************************
1470 *        Activate a debug logfile (late)         *
1471 *************************************************/
1472
1473 /* Normally, debugging is activated from the command-line; it may be useful
1474 within the configuration to activate debugging later, based on certain
1475 conditions.  If debugging is already in progress, we return early, no action
1476 taken (besides debug-logging that we wanted debug-logging).
1477
1478 Failures in options are not fatal but will result in paniclog entries for the
1479 misconfiguration.
1480
1481 The first use of this is in ACL logic, "control = debug/tag=foo/opts=+expand"
1482 which can be combined with conditions, etc, to activate extra logging only
1483 for certain sources. The second use is inetd wait mode debug preservation.
1484
1485 It might be nice, in ACL-initiated pretrigger mode, to not create the file
1486 immediately but only upon a trigger - but we'd need another cmdline option
1487 to pass the name through child_exxec_exim(). */
1488
1489 void
1490 debug_logging_activate(const uschar * tag_name, const uschar * opts)
1491 {
1492 if (debug_file)
1493   {
1494   debug_printf("DEBUGGING ACTIVATED FROM WITHIN CONFIG.\n"
1495       "DEBUG: Tag=\"%s\" opts=\"%s\"\n", tag_name, opts ? opts : US"");
1496   return;
1497   }
1498
1499 if (tag_name && (Ustrchr(tag_name, '/') != NULL))
1500   {
1501   log_write(0, LOG_MAIN|LOG_PANIC, "debug tag may not contain a '/' in: %s",
1502       tag_name);
1503   return;
1504   }
1505
1506 debug_selector = D_default;
1507 if (opts)
1508   decode_bits(&debug_selector, 1, debug_notall, opts,
1509       debug_options, debug_options_count, US"debug", DEBUG_FROM_CONFIG);
1510
1511 /* When activating from a transport process we may never have logged at all
1512 resulting in certain setup not having been done.  Hack this for now so we
1513 do not segfault; note that nondefault log locations will not work */
1514
1515 if (!*file_path) set_file_path();
1516
1517 open_log(&debug_fd, lt_debug, tag_name);
1518
1519 if (debug_fd != -1)
1520   debug_file = fdopen(debug_fd, "w");
1521 else
1522   log_write(0, LOG_MAIN|LOG_PANIC, "unable to open debug log");
1523 }
1524
1525
1526 void
1527 debug_logging_from_spool(const uschar * filename)
1528 {
1529 if (debug_fd < 0)
1530   {
1531   Ustrncpy(debuglog_name, filename, sizeof(debuglog_name));
1532   if ((debug_fd = log_open_as_exim(filename)) >= 0)
1533     debug_file = fdopen(debug_fd, "w");
1534   DEBUG(D_deliver) debug_printf("debug enabled by spoolfile\n");
1535   }
1536 /*
1537 else DEBUG(D_deliver)
1538   debug_printf("debug already active; ignoring spoolfile '%s'\n", filename);
1539 */
1540 }
1541
1542
1543 void
1544 debug_logging_stop(BOOL kill)
1545 {
1546 debug_pretrigger_discard();
1547 if (!debug_file || !debuglog_name[0]) return;
1548
1549 debug_selector = 0;
1550 fclose(debug_file);
1551 debug_file = NULL;
1552 debug_fd = -1;
1553 if (kill) unlink_log(lt_debug);
1554 }
1555
1556
1557 /* End of log.c */