[secnet] / log.c

#include "secnet.h"
#include <stdio.h>
#include <string.h>
#include <stdlib.h>
#include <time.h>
#include <errno.h>
#include <syslog.h>
#include <assert.h>
#include <unistd.h>
#include "process.h"
#include "util.h"

bool_t secnet_is_daemon=False;
uint32_t message_level=M_WARNING|M_ERR|M_SECURITY|M_FATAL;
struct log_if *system_log=NULL;

static void vMessageFallback(uint32_t class, const char *message, va_list args)
    FORMAT(printf,2,0);
static void vMessageFallback(uint32_t class, const char *message, va_list args)
{
    FILE *dest=stdout;
    /* Messages go to stdout/stderr */
    if (class & message_level) {
	if (class&M_FATAL || class&M_ERR || class&M_WARNING) {
	    dest=stderr;
	}
	vfprintf(dest,message,args);
    }
}

static void vMessage(uint32_t class, const char *message, va_list args)
{

    if (system_log) {
	/* Messages go to the system log interface */
	vslilog_part(system_log, class, message, args);
    } else {
	vMessageFallback(class,message,args);
    }
}  

void Message(uint32_t class, const char *message, ...)
{
    va_list ap;

    va_start(ap,message);
    vMessage(class,message,ap);
    va_end(ap);
}

static void MessageFallback(uint32_t class, const char *message, ...)
    FORMAT(printf,2,3);
static void MessageFallback(uint32_t class, const char *message, ...)
{
    va_list ap;

    va_start(ap,message);
    vMessageFallback(class,message,ap);
    va_end(ap);
}

static NORETURN(vfatal(int status, bool_t perror, const char *message,
		       va_list args));

static void vfatal(int status, bool_t perror, const char *message,
		   va_list args)
{
    int err;

    err=errno;

    enter_phase(PHASE_SHUTDOWN);
    Message(M_FATAL, "secnet fatal error: ");
    vMessage(M_FATAL, message, args);
    if (perror)
	Message(M_FATAL, ": %s\n",strerror(err));
    else
	Message(M_FATAL, "\n");
    exit(status);
}

void fatal(const char *message, ...)
{
    va_list args;
    va_start(args,message);
    vfatal(current_phase,False,message,args);
    va_end(args);
}

void fatal_status(int status, const char *message, ...)
{
    va_list args;
    va_start(args,message);
    vfatal(status,False,message,args);
    va_end(args);
}

void fatal_perror(const char *message, ...)
{
    va_list args;
    va_start(args,message);
    vfatal(current_phase,True,message,args);
    va_end(args);
}

void fatal_perror_status(int status, const char *message, ...)
{
    va_list args;
    va_start(args,message);
    vfatal(status,True,message,args);
    va_end(args);
}

void vcfgfatal_maybefile(FILE *maybe_f /* or 0 */, struct cloc loc,
			 cstring_t facility, const char *message, va_list args)
{
    enter_phase(PHASE_SHUTDOWN);

    if (maybe_f && ferror(maybe_f)) {
	assert(loc.file);
	Message(M_FATAL, "error reading config file (%s, %s): %s",
		facility, loc.file, strerror(errno));
    } else if (maybe_f && feof(maybe_f)) {
	assert(loc.file);
	Message(M_FATAL, "unexpected end of config file (%s, %s)",
		facility, loc.file);
    } else if (loc.file && loc.line) {
	Message(M_FATAL, "config error (%s, %s:%d): ",facility,loc.file,
		loc.line);
    } else if (!loc.file && loc.line) {
	Message(M_FATAL, "config error (%s, line %d): ",facility,loc.line);
    } else {
	Message(M_FATAL, "config error (%s): ",facility);
    }
    
    vMessage(M_FATAL,message,args);
    exit(current_phase);
}

void cfgfatal_maybefile(FILE *maybe_f, struct cloc loc, cstring_t facility,
			const char *message, ...)
{
    va_list args;

    va_start(args,message);
    vcfgfatal_maybefile(maybe_f,loc,facility,message,args);
    va_end(args);
}    

void cfgfatal(struct cloc loc, cstring_t facility, const char *message, ...)
{
    va_list args;

    va_start(args,message);
    vcfgfatal_maybefile(0,loc,facility,message,args);
    va_end(args);
}

void cfgfile_postreadcheck(struct cloc loc, FILE *f)
{
    assert(loc.file);
    if (ferror(f)) {
	Message(M_FATAL, "error reading config file (%s): %s\n",
		loc.file, strerror(errno));
	exit(current_phase);
    } else if (feof(f)) {
	Message(M_FATAL, "unexpected end of config file (%s)\n", loc.file);
	exit(current_phase);
    }
}

/* Take a list of log closures and merge them */
struct loglist {
    struct log_if *l;
    struct loglist *next;
};

static void log_vmulti(void *sst, int class, const char *message, va_list args)
{
    struct loglist *st=sst, *i;

    if (secnet_is_daemon) {
	for (i=st; i; i=i->next) {
	    vslilog(i->l,class,message,args);
	}
    } else {
	vMessage(class,message,args);
	Message(class,"\n");
    }
}

void lg_vperror(struct log_if *lg, const char *desc, struct cloc *loc,
		int class, int errnoval, const char *fmt, va_list al)
{
    int status=current_phase;
    int esave=errno;

    if (!lg)
	lg=system_log;

    if (class & M_FATAL)
	enter_phase(PHASE_SHUTDOWN);

    slilog_part(lg,class,"%s",desc);
    if (loc)
	slilog_part(lg,class," (%s:%d)",loc->file,loc->line);
    slilog_part(lg,class,": ");
    vslilog_part(lg,class,fmt,al);
    if (errnoval)
	slilog_part(lg,class,": %s",strerror(errnoval));
    slilog_part(lg,class,"\n");

    if (class & M_FATAL)
	exit(status);

    errno=esave;
}

void lg_perror(struct log_if *lg, const char *desc, struct cloc *loc,
	       int class, int errnoval, const char *fmt, ...)
{
    va_list al;
    va_start(al,fmt);
    lg_vperror(lg,desc,loc,class,errnoval,fmt,al);
    va_end(al);
}

void lg_exitstatus(struct log_if *lg, const char *desc, struct cloc *loc,
		   int class, int status, const char *progname)
{
    if (!status)
	lg_perror(lg,desc,loc,class,0,"%s exited",progname);
    else if (WIFEXITED(status))
	lg_perror(lg,desc,loc,class,0,"%s exited with error exit status %d",
		  progname,WEXITSTATUS(status));
    else if (WIFSIGNALED(status))
	lg_perror(lg,desc,loc,class,0,"%s died due to fatal signal %s (%d)%s",
		  progname,strsignal(WTERMSIG(status)),WTERMSIG(status),
		  WCOREDUMP(status)?" (core dumped)":"");
    else
	lg_perror(lg,desc,loc,class,0,"%s died with unknown wait status %d",
		  progname,status);
}

struct log_if *init_log(list_t *ll)
{
    int i=0;
    item_t *item;
    closure_t *cl;
    struct loglist *l=NULL, *n;
    struct log_if *r;

    if (list_length(ll)==1) {
	item=list_elem(ll,0);
	cl=item->data.closure;
	if (cl->type!=CL_LOG) {
	    cfgfatal(item->loc,"init_log","closure is not a logger");
	}
	return cl->interface;
    }
    while ((item=list_elem(ll,i++))) {
	if (item->type!=t_closure) {
	    cfgfatal(item->loc,"init_log","item is not a closure");
	}
	cl=item->data.closure;
	if (cl->type!=CL_LOG) {
	    cfgfatal(item->loc,"init_log","closure is not a logger");
	}
	n=safe_malloc(sizeof(*n),"init_log");
	n->l=cl->interface;
	n->next=l;
	l=n;
    }
    if (!l) {
	fatal("init_log: no log");
    }
    r=safe_malloc(sizeof(*r), "init_log");
    r->st=l;
    r->vlogfn=log_vmulti;
    r->buff[0]=0;
    return r;
}

struct logfile {
    closure_t cl;
    struct log_if ops;
    struct cloc loc;
    string_t logfile;
    uint32_t level;
    FILE *f;
};

static cstring_t months[]={
    "Jan","Feb","Mar","Apr","May","Jun","Jul","Aug","Sep","Oct","Nov","Dec"};

static void logfile_vlog(void *sst, int class, const char *message,
			 va_list args)
{
    struct logfile *st=sst;
    time_t t;
    struct tm *tm;

    if (secnet_is_daemon && st->f) {
	if (class&st->level) {
	    t=time(NULL);
	    tm=localtime(&t);
	    fprintf(st->f,"%s %2d %02d:%02d:%02d ",
		    months[tm->tm_mon],tm->tm_mday,tm->tm_hour,tm->tm_min,
		    tm->tm_sec);
	    vfprintf(st->f,message,args);
	    fprintf(st->f,"\n");
	    fflush(st->f);
	}
    } else {
	vMessageFallback(class,message,args);
	MessageFallback(class,"\n");
    }
}

static void logfile_log(void *state, int class, const char *message, ...)
    FORMAT(printf,3,4);
static void logfile_log(void *state, int class, const char *message, ...)
{
    va_list ap;

    va_start(ap,message);
    logfile_vlog(state,class,message,ap);
    va_end(ap);
}

static void logfile_hup_notify(void *sst, int signum)
{
    struct logfile *st=sst;
    FILE *f;
    f=fopen(st->logfile,"a");
    if (!f) {
	logfile_log(st,M_FATAL,"received SIGHUP, but could not reopen "
		    "logfile: %s",strerror(errno));
    } else {
	fclose(st->f);
	st->f=f;
	logfile_log(st,M_INFO,"received SIGHUP");
    }
}

static void logfile_phase_hook(void *sst, uint32_t new_phase)
{
    struct logfile *st=sst;
    FILE *f;

    if (background) {
	f=fopen(st->logfile,"a");
	if (!f) fatal_perror("logfile (%s:%d): cannot open \"%s\"",
			     st->loc.file,st->loc.line,st->logfile);
	st->f=f;
	request_signal_notification(SIGHUP, logfile_hup_notify,st);
    }
}

static struct flagstr message_class_table[]={
    { "debug-config", M_DEBUG_CONFIG },
    { "debug-phase", M_DEBUG_PHASE },
    { "debug", M_DEBUG },
    { "all-debug", M_DEBUG|M_DEBUG_PHASE|M_DEBUG_CONFIG },
    { "info", M_INFO },
    { "notice", M_NOTICE },
    { "warning", M_WARNING },
    { "error", M_ERR },
    { "security", M_SECURITY },
    { "fatal", M_FATAL },
    { "default", M_WARNING|M_ERR|M_SECURITY|M_FATAL },
    { "verbose", M_INFO|M_NOTICE|M_WARNING|M_ERR|M_SECURITY|M_FATAL },
    { "quiet", M_FATAL },
    { NULL, 0 }
};

static list_t *logfile_apply(closure_t *self, struct cloc loc, dict_t *context,
			     list_t *args)
{
    struct logfile *st;
    item_t *item;
    dict_t *dict;

    /* We should defer opening the logfile until the getresources
       phase.  We should defer writing into the logfile until after we
       become a daemon. */
    
    st=safe_malloc(sizeof(*st),"logfile_apply");
    st->cl.description="logfile";
    st->cl.type=CL_LOG;
    st->cl.apply=NULL;
    st->cl.interface=&st->ops;
    st->ops.st=st;
    st->ops.vlogfn=logfile_vlog;
    st->ops.buff[0]=0;
    st->loc=loc;
    st->f=NULL;

    item=list_elem(args,0);
    if (!item || item->type!=t_dict) {
	cfgfatal(loc,"logfile","argument must be a dictionary\n");
    }
    dict=item->data.dict;

    st->logfile=dict_read_string(dict,"filename",True,"logfile",loc);
    st->level=string_list_to_word(dict_lookup(dict,"class"),
				       message_class_table,"logfile");

    add_hook(PHASE_GETRESOURCES,logfile_phase_hook,st);

    return new_closure(&st->cl);
}

struct syslog {
    closure_t cl;
    struct log_if ops;
    string_t ident;
    int facility;
    bool_t open;
};

static int msgclass_to_syslogpriority(uint32_t m)
{
    switch (m) {
    case M_DEBUG_CONFIG: return LOG_DEBUG;
    case M_DEBUG_PHASE: return LOG_DEBUG;
    case M_DEBUG: return LOG_DEBUG;
    case M_INFO: return LOG_INFO;
    case M_NOTICE: return LOG_NOTICE;
    case M_WARNING: return LOG_WARNING;
    case M_ERR: return LOG_ERR;
    case M_SECURITY: return LOG_CRIT;
    case M_FATAL: return LOG_EMERG;
    default: return LOG_NOTICE;
    }
}
    
static void syslog_vlog(void *sst, int class, const char *message,
			 va_list args)
    FORMAT(printf,3,0);
static void syslog_vlog(void *sst, int class, const char *message,
			 va_list args)
{
    struct syslog *st=sst;

    if (st->open)
	vsyslog(msgclass_to_syslogpriority(class),message,args);
    else {
	vMessageFallback(class,message,args);
	MessageFallback(class,"\n");
    }
}

static struct flagstr syslog_facility_table[]={
#ifdef LOG_AUTH
    { "auth", LOG_AUTH },
#endif
#ifdef LOG_AUTHPRIV
    { "authpriv", LOG_AUTHPRIV },
#endif
    { "cron", LOG_CRON },
    { "daemon", LOG_DAEMON },
    { "kern", LOG_KERN },
    { "local0", LOG_LOCAL0 },
    { "local1", LOG_LOCAL1 },
    { "local2", LOG_LOCAL2 },
    { "local3", LOG_LOCAL3 },
    { "local4", LOG_LOCAL4 },
    { "local5", LOG_LOCAL5 },
    { "local6", LOG_LOCAL6 },
    { "local7", LOG_LOCAL7 },
    { "lpr", LOG_LPR },
    { "mail", LOG_MAIL },
    { "news", LOG_NEWS },
    { "syslog", LOG_SYSLOG },
    { "user", LOG_USER },
    { "uucp", LOG_UUCP },
    { NULL, 0 }
};

static void syslog_phase_hook(void *sst, uint32_t newphase)
{
    struct syslog *st=sst;

    if (background) {
	openlog(st->ident,0,st->facility);
	st->open=True;
    }
}

static list_t *syslog_apply(closure_t *self, struct cloc loc, dict_t *context,
			    list_t *args)
{
    struct syslog *st;
    dict_t *d;
    item_t *item;
    string_t facstr;

    st=safe_malloc(sizeof(*st),"syslog_apply");
    st->cl.description="syslog";
    st->cl.type=CL_LOG;
    st->cl.apply=NULL;
    st->cl.interface=&st->ops;
    st->ops.st=st;
    st->ops.vlogfn=syslog_vlog;
    st->ops.buff[0]=0;

    item=list_elem(args,0);
    if (!item || item->type!=t_dict)
	cfgfatal(loc,"syslog","parameter must be a dictionary\n");
    d=item->data.dict;

    st->ident=dict_read_string(d, "ident", False, "syslog", loc);
    facstr=dict_read_string(d, "facility", True, "syslog", loc);
    st->facility=string_to_word(facstr,loc,
				syslog_facility_table,"syslog");
    st->open=False;
    add_hook(PHASE_GETRESOURCES,syslog_phase_hook,st);

    return new_closure(&st->cl);
}    

/* Read from a fd and output to a log.  This is a quick hack to
   support logging stderr, and needs code adding to tidy up before it
   can be used for anything else. */
#define FDLOG_BUFSIZE 1024
struct fdlog {
    struct log_if *log;
    int fd;
    cstring_t prefix;
    string_t buffer;
    int i;
    bool_t finished;
};

static int log_from_fd_beforepoll(void *sst, struct pollfd *fds, int *nfds_io,
				  int *timeout_io)
{
    struct fdlog *st=sst;
    if (!st->finished) {
	BEFOREPOLL_WANT_FDS(1);
	fds[0].fd=st->fd;
	fds[0].events=POLLIN;
    } else {
	BEFOREPOLL_WANT_FDS(0);
    }
    return 0;
}

static void log_from_fd_afterpoll(void *sst, struct pollfd *fds, int nfds)
{
    struct fdlog *st=sst;
    int r,remain,i;

    if (nfds==0) return;
    if (fds[0].revents&POLLERR) {
	st->finished=True;
    }
    if (fds[0].revents&POLLIN) {
	remain=FDLOG_BUFSIZE-st->i-1;
	if (remain<=0) {
	    st->buffer[FDLOG_BUFSIZE-1]=0;
	    slilog(st->log,M_WARNING,"%s: overlong line: %s",
			 st->prefix,st->buffer);
	    st->i=0;
	    remain=FDLOG_BUFSIZE-1;
	}
	r=read(st->fd,st->buffer+st->i,remain);
	if (r>0) {
	    st->i+=r;
	    for (i=0; i<st->i; i++) {
		if (st->buffer[i]=='\n') {
		    st->buffer[i]=0;
		    slilog(st->log,M_INFO,"%s: %s",
				 st->prefix,st->buffer);
		    i++;
		    memmove(st->buffer,st->buffer+i,st->i-i);
		    st->i-=i;
		    i=-1;
		}
	    }
	} else if (errno==EINTR || iswouldblock(errno)) {
	} else {
	    Message(M_WARNING,"log_from_fd: %s\n",strerror(errno));
	    st->finished=True;
	}
    }
}
		
void log_from_fd(int fd, cstring_t prefix, struct log_if *log)
{
    struct fdlog *st;

    st=safe_malloc(sizeof(*st),"log_from_fd");
    st->log=log;
    st->fd=fd;
    st->prefix=prefix;
    st->buffer=safe_malloc(FDLOG_BUFSIZE,"log_from_fd");
    st->i=0;
    st->finished=False;

    setnonblock(st->fd);

    register_for_poll(st,log_from_fd_beforepoll,log_from_fd_afterpoll,
		      prefix);
}

void log_module(dict_t *dict)
{
    add_closure(dict,"logfile",logfile_apply);
    add_closure(dict,"syslog",syslog_apply);
}
Commit	Line	Data
7138d0c5 SE	1	#include "secnet.h"
	2	#include <stdio.h>
	3	#include <string.h>
	4	#include <stdlib.h>
	5	#include <time.h>
	6	#include <errno.h>
	7	#include <syslog.h>
4f5e39ec	8	#include <assert.h>
fe5e9cc4	9	#include <unistd.h>
7138d0c5	10	#include "process.h"
08ee90a2	11	#include "util.h"
7138d0c5 SE	12
7138d0c5 SE	13	bool_t secnet_is_daemon=False;
469fd1d9	14	uint32_t message_level=M_WARNING\|M_ERR\|M_SECURITY\|M_FATAL;
7138d0c5 SE	15	struct log_if *system_log=NULL;
7138d0c5 SE	16
ba72e2bf	17	static void vMessageFallback(uint32_t class, const char *message, va_list args)
779837e1 IJ	18	FORMAT(printf,2,0);
779837e1 IJ	19	static void vMessageFallback(uint32_t class, const char *message, va_list args)
7138d0c5 SE	20	{
7138d0c5 SE	21	FILE *dest=stdout;
ba72e2bf IJ	22	/* Messages go to stdout/stderr */
	23	if (class & message_level) {
	24	if (class&M_FATAL \|\| class&M_ERR \|\| class&M_WARNING) {
	25	dest=stderr;
	26	}
	27	vfprintf(dest,message,args);
	28	}
	29	}
	30
	31	static void vMessage(uint32_t class, const char *message, va_list args)
	32	{
7138d0c5	33
7b1a9fb7	34	if (system_log) {
7138d0c5	35	/* Messages go to the system log interface */
ff1dcd86	36	vslilog_part(system_log, class, message, args);
7138d0c5	37	} else {
ba72e2bf	38	vMessageFallback(class,message,args);
7138d0c5 SE	39	}
	40	}
	41
fe5e9cc4	42	void Message(uint32_t class, const char *message, ...)
7138d0c5 SE	43	{
	44	va_list ap;
	45
	46	va_start(ap,message);
	47	vMessage(class,message,ap);
	48	va_end(ap);
	49	}
	50
ba72e2bf	51	static void MessageFallback(uint32_t class, const char *message, ...)
779837e1 IJ	52	FORMAT(printf,2,3);
779837e1 IJ	53	static void MessageFallback(uint32_t class, const char *message, ...)
ba72e2bf IJ	54	{
	55	va_list ap;
	56
	57	va_start(ap,message);
	58	vMessageFallback(class,message,ap);
	59	va_end(ap);
	60	}
	61
fe5e9cc4	62	static NORETURN(vfatal(int status, bool_t perror, const char *message,
4f5e39ec SE	63	va_list args));
4f5e39ec SE	64
fe5e9cc4 SE	65	static void vfatal(int status, bool_t perror, const char *message,
fe5e9cc4 SE	66	va_list args)
7138d0c5 SE	67	{
	68	int err;
	69
	70	err=errno;
	71
	72	enter_phase(PHASE_SHUTDOWN);
4f5e39ec SE	73	Message(M_FATAL, "secnet fatal error: ");
	74	vMessage(M_FATAL, message, args);
	75	if (perror)
7138d0c5	76	Message(M_FATAL, ": %s\n",strerror(err));
4f5e39ec SE	77	else
4f5e39ec SE	78	Message(M_FATAL, "\n");
7138d0c5 SE	79	exit(status);
	80	}
	81
fe5e9cc4	82	void fatal(const char *message, ...)
7138d0c5 SE	83	{
	84	va_list args;
	85	va_start(args,message);
	86	vfatal(current_phase,False,message,args);
	87	va_end(args);
	88	}
	89
fe5e9cc4	90	void fatal_status(int status, const char *message, ...)
7138d0c5 SE	91	{
	92	va_list args;
	93	va_start(args,message);
	94	vfatal(status,False,message,args);
	95	va_end(args);
	96	}
	97
fe5e9cc4	98	void fatal_perror(const char *message, ...)
7138d0c5 SE	99	{
	100	va_list args;
	101	va_start(args,message);
	102	vfatal(current_phase,True,message,args);
	103	va_end(args);
	104	}
	105
fe5e9cc4	106	void fatal_perror_status(int status, const char *message, ...)
7138d0c5 SE	107	{
	108	va_list args;
	109	va_start(args,message);
	110	vfatal(status,True,message,args);
	111	va_end(args);
	112	}
	113
4f5e39ec	114	void vcfgfatal_maybefile(FILE maybe_f / or 0 */, struct cloc loc,
fe5e9cc4	115	cstring_t facility, const char *message, va_list args)
7138d0c5	116	{
7138d0c5 SE	117	enter_phase(PHASE_SHUTDOWN);
7138d0c5 SE	118
4f5e39ec SE	119	if (maybe_f && ferror(maybe_f)) {
	120	assert(loc.file);
	121	Message(M_FATAL, "error reading config file (%s, %s): %s",
	122	facility, loc.file, strerror(errno));
	123	} else if (maybe_f && feof(maybe_f)) {
	124	assert(loc.file);
	125	Message(M_FATAL, "unexpected end of config file (%s, %s)",
	126	facility, loc.file);
	127	} else if (loc.file && loc.line) {
7138d0c5 SE	128	Message(M_FATAL, "config error (%s, %s:%d): ",facility,loc.file,
	129	loc.line);
	130	} else if (!loc.file && loc.line) {
	131	Message(M_FATAL, "config error (%s, line %d): ",facility,loc.line);
	132	} else {
	133	Message(M_FATAL, "config error (%s): ",facility);
	134	}
	135
	136	vMessage(M_FATAL,message,args);
7138d0c5 SE	137	exit(current_phase);
	138	}
	139
fe5e9cc4 SE	140	void cfgfatal_maybefile(FILE *maybe_f, struct cloc loc, cstring_t facility,
fe5e9cc4 SE	141	const char *message, ...)
4f5e39ec SE	142	{
	143	va_list args;
	144
	145	va_start(args,message);
	146	vcfgfatal_maybefile(maybe_f,loc,facility,message,args);
	147	va_end(args);
	148	}
	149
fe5e9cc4	150	void cfgfatal(struct cloc loc, cstring_t facility, const char *message, ...)
4f5e39ec SE	151	{
	152	va_list args;
	153
	154	va_start(args,message);
	155	vcfgfatal_maybefile(0,loc,facility,message,args);
	156	va_end(args);
	157	}
	158
	159	void cfgfile_postreadcheck(struct cloc loc, FILE *f)
	160	{
	161	assert(loc.file);
	162	if (ferror(f)) {
39a6b1e2	163	Message(M_FATAL, "error reading config file (%s): %s\n",
4f5e39ec SE	164	loc.file, strerror(errno));
	165	exit(current_phase);
	166	} else if (feof(f)) {
39a6b1e2	167	Message(M_FATAL, "unexpected end of config file (%s)\n", loc.file);
4f5e39ec SE	168	exit(current_phase);
	169	}
	170	}
	171
7138d0c5 SE	172	/* Take a list of log closures and merge them */
	173	struct loglist {
	174	struct log_if *l;
	175	struct loglist *next;
	176	};
	177
fe5e9cc4	178	static void log_vmulti(void sst, int class, const char message, va_list args)
7138d0c5 SE	179	{
	180	struct loglist st=sst, i;
	181
	182	if (secnet_is_daemon) {
	183	for (i=st; i; i=i->next) {
779837e1	184	vslilog(i->l,class,message,args);
7138d0c5 SE	185	}
	186	} else {
	187	vMessage(class,message,args);
	188	Message(class,"\n");
	189	}
	190	}
	191
f1393100 IJ	192	void lg_vperror(struct log_if lg, const char desc, struct cloc *loc,
	193	int class, int errnoval, const char *fmt, va_list al)
	194	{
	195	int status=current_phase;
	196	int esave=errno;
	197
	198	if (!lg)
	199	lg=system_log;
	200
	201	if (class & M_FATAL)
	202	enter_phase(PHASE_SHUTDOWN);
	203
	204	slilog_part(lg,class,"%s",desc);
	205	if (loc)
	206	slilog_part(lg,class," (%s:%d)",loc->file,loc->line);
	207	slilog_part(lg,class,": ");
	208	vslilog_part(lg,class,fmt,al);
	209	if (errnoval)
	210	slilog_part(lg,class,": %s",strerror(errnoval));
	211	slilog_part(lg,class,"\n");
	212
	213	if (class & M_FATAL)
	214	exit(status);
	215
	216	errno=esave;
	217	}
	218
	219	void lg_perror(struct log_if lg, const char desc, struct cloc *loc,
	220	int class, int errnoval, const char *fmt, ...)
	221	{
	222	va_list al;
	223	va_start(al,fmt);
	224	lg_vperror(lg,desc,loc,class,errnoval,fmt,al);
	225	va_end(al);
	226	}
	227
45736f73 IJ	228	void lg_exitstatus(struct log_if lg, const char desc, struct cloc *loc,
	229	int class, int status, const char *progname)
	230	{
	231	if (!status)
	232	lg_perror(lg,desc,loc,class,0,"%s exited",progname);
	233	else if (WIFEXITED(status))
	234	lg_perror(lg,desc,loc,class,0,"%s exited with error exit status %d",
	235	progname,WEXITSTATUS(status));
	236	else if (WIFSIGNALED(status))
	237	lg_perror(lg,desc,loc,class,0,"%s died due to fatal signal %s (%d)%s",
	238	progname,strsignal(WTERMSIG(status)),WTERMSIG(status),
	239	WCOREDUMP(status)?" (core dumped)":"");
	240	else
	241	lg_perror(lg,desc,loc,class,0,"%s died with unknown wait status %d",
	242	progname,status);
	243	}
	244
7138d0c5 SE	245	struct log_if init_log(list_t ll)
	246	{
	247	int i=0;
	248	item_t *item;
	249	closure_t *cl;
	250	struct loglist l=NULL, n;
	251	struct log_if *r;
	252
	253	if (list_length(ll)==1) {
	254	item=list_elem(ll,0);
	255	cl=item->data.closure;
	256	if (cl->type!=CL_LOG) {
	257	cfgfatal(item->loc,"init_log","closure is not a logger");
	258	}
	259	return cl->interface;
	260	}
	261	while ((item=list_elem(ll,i++))) {
	262	if (item->type!=t_closure) {
	263	cfgfatal(item->loc,"init_log","item is not a closure");
	264	}
	265	cl=item->data.closure;
	266	if (cl->type!=CL_LOG) {
	267	cfgfatal(item->loc,"init_log","closure is not a logger");
	268	}
	269	n=safe_malloc(sizeof(*n),"init_log");
	270	n->l=cl->interface;
	271	n->next=l;
	272	l=n;
	273	}
	274	if (!l) {
	275	fatal("init_log: no log");
	276	}
	277	r=safe_malloc(sizeof(*r), "init_log");
	278	r->st=l;
779837e1	279	r->vlogfn=log_vmulti;
ff1dcd86	280	r->buff[0]=0;
7138d0c5 SE	281	return r;
	282	}
	283
	284	struct logfile {
	285	closure_t cl;
	286	struct log_if ops;
	287	struct cloc loc;
	288	string_t logfile;
	289	uint32_t level;
	290	FILE *f;
	291	};
	292
fe5e9cc4	293	static cstring_t months[]={
7138d0c5 SE	294	"Jan","Feb","Mar","Apr","May","Jun","Jul","Aug","Sep","Oct","Nov","Dec"};
7138d0c5 SE	295
fe5e9cc4 SE	296	static void logfile_vlog(void sst, int class, const char message,
fe5e9cc4 SE	297	va_list args)
7138d0c5 SE	298	{
	299	struct logfile *st=sst;
	300	time_t t;
	301	struct tm *tm;
	302
fe5e9cc4	303	if (secnet_is_daemon && st->f) {
7138d0c5 SE	304	if (class&st->level) {
	305	t=time(NULL);
	306	tm=localtime(&t);
	307	fprintf(st->f,"%s %2d %02d:%02d:%02d ",
	308	months[tm->tm_mon],tm->tm_mday,tm->tm_hour,tm->tm_min,
	309	tm->tm_sec);
	310	vfprintf(st->f,message,args);
	311	fprintf(st->f,"\n");
	312	fflush(st->f);
	313	}
	314	} else {
ba72e2bf IJ	315	vMessageFallback(class,message,args);
ba72e2bf IJ	316	MessageFallback(class,"\n");
7138d0c5 SE	317	}
	318	}
	319
fe5e9cc4	320	static void logfile_log(void state, int class, const char message, ...)
779837e1 IJ	321	FORMAT(printf,3,4);
779837e1 IJ	322	static void logfile_log(void state, int class, const char message, ...)
7138d0c5 SE	323	{
	324	va_list ap;
	325
	326	va_start(ap,message);
fe5e9cc4	327	logfile_vlog(state,class,message,ap);
7138d0c5 SE	328	va_end(ap);
	329	}
	330
	331	static void logfile_hup_notify(void *sst, int signum)
	332	{
	333	struct logfile *st=sst;
	334	FILE *f;
	335	f=fopen(st->logfile,"a");
	336	if (!f) {
	337	logfile_log(st,M_FATAL,"received SIGHUP, but could not reopen "
	338	"logfile: %s",strerror(errno));
	339	} else {
	340	fclose(st->f);
	341	st->f=f;
	342	logfile_log(st,M_INFO,"received SIGHUP");
	343	}
	344	}
	345
	346	static void logfile_phase_hook(void *sst, uint32_t new_phase)
	347	{
	348	struct logfile *st=sst;
	349	FILE *f;
	350
	351	if (background) {
	352	f=fopen(st->logfile,"a");
	353	if (!f) fatal_perror("logfile (%s:%d): cannot open \"%s\"",
	354	st->loc.file,st->loc.line,st->logfile);
	355	st->f=f;
	356	request_signal_notification(SIGHUP, logfile_hup_notify,st);
	357	}
	358	}
	359
	360	static struct flagstr message_class_table[]={
	361	{ "debug-config", M_DEBUG_CONFIG },
	362	{ "debug-phase", M_DEBUG_PHASE },
	363	{ "debug", M_DEBUG },
	364	{ "all-debug", M_DEBUG\|M_DEBUG_PHASE\|M_DEBUG_CONFIG },
	365	{ "info", M_INFO },
	366	{ "notice", M_NOTICE },
	367	{ "warning", M_WARNING },
469fd1d9	368	{ "error", M_ERR },
7138d0c5 SE	369	{ "security", M_SECURITY },
7138d0c5 SE	370	{ "fatal", M_FATAL },
469fd1d9 SE	371	{ "default", M_WARNING\|M_ERR\|M_SECURITY\|M_FATAL },
469fd1d9 SE	372	{ "verbose", M_INFO\|M_NOTICE\|M_WARNING\|M_ERR\|M_SECURITY\|M_FATAL },
7138d0c5 SE	373	{ "quiet", M_FATAL },
	374	{ NULL, 0 }
	375	};
	376
	377	static list_t logfile_apply(closure_t self, struct cloc loc, dict_t *context,
	378	list_t *args)
	379	{
	380	struct logfile *st;
	381	item_t *item;
	382	dict_t *dict;
	383
	384	/* We should defer opening the logfile until the getresources
	385	phase. We should defer writing into the logfile until after we
	386	become a daemon. */
	387
	388	st=safe_malloc(sizeof(*st),"logfile_apply");
	389	st->cl.description="logfile";
	390	st->cl.type=CL_LOG;
	391	st->cl.apply=NULL;
	392	st->cl.interface=&st->ops;
	393	st->ops.st=st;
779837e1	394	st->ops.vlogfn=logfile_vlog;
ff1dcd86	395	st->ops.buff[0]=0;
7138d0c5	396	st->loc=loc;
fe5e9cc4	397	st->f=NULL;
7138d0c5 SE	398
	399	item=list_elem(args,0);
	400	if (!item \|\| item->type!=t_dict) {
	401	cfgfatal(loc,"logfile","argument must be a dictionary\n");
	402	}
	403	dict=item->data.dict;
	404
	405	st->logfile=dict_read_string(dict,"filename",True,"logfile",loc);
	406	st->level=string_list_to_word(dict_lookup(dict,"class"),
	407	message_class_table,"logfile");
	408
	409	add_hook(PHASE_GETRESOURCES,logfile_phase_hook,st);
	410
	411	return new_closure(&st->cl);
	412	}
	413
	414	struct syslog {
	415	closure_t cl;
	416	struct log_if ops;
	417	string_t ident;
	418	int facility;
	419	bool_t open;
	420	};
	421
	422	static int msgclass_to_syslogpriority(uint32_t m)
	423	{
	424	switch (m) {
	425	case M_DEBUG_CONFIG: return LOG_DEBUG;
	426	case M_DEBUG_PHASE: return LOG_DEBUG;
	427	case M_DEBUG: return LOG_DEBUG;
	428	case M_INFO: return LOG_INFO;
	429	case M_NOTICE: return LOG_NOTICE;
	430	case M_WARNING: return LOG_WARNING;
469fd1d9	431	case M_ERR: return LOG_ERR;
7138d0c5 SE	432	case M_SECURITY: return LOG_CRIT;
	433	case M_FATAL: return LOG_EMERG;
	434	default: return LOG_NOTICE;
	435	}
	436	}
	437
fe5e9cc4	438	static void syslog_vlog(void sst, int class, const char message,
7138d0c5	439	va_list args)
779837e1 IJ	440	FORMAT(printf,3,0);
	441	static void syslog_vlog(void sst, int class, const char message,
	442	va_list args)
7138d0c5 SE	443	{
	444	struct syslog *st=sst;
	445
	446	if (st->open)
	447	vsyslog(msgclass_to_syslogpriority(class),message,args);
	448	else {
ba72e2bf IJ	449	vMessageFallback(class,message,args);
ba72e2bf IJ	450	MessageFallback(class,"\n");
7138d0c5 SE	451	}
	452	}
	453
7138d0c5	454	static struct flagstr syslog_facility_table[]={
8dea8d37 SE	455	#ifdef LOG_AUTH
	456	{ "auth", LOG_AUTH },
	457	#endif
	458	#ifdef LOG_AUTHPRIV
7138d0c5	459	{ "authpriv", LOG_AUTHPRIV },
8dea8d37	460	#endif
7138d0c5 SE	461	{ "cron", LOG_CRON },
	462	{ "daemon", LOG_DAEMON },
	463	{ "kern", LOG_KERN },
	464	{ "local0", LOG_LOCAL0 },
	465	{ "local1", LOG_LOCAL1 },
	466	{ "local2", LOG_LOCAL2 },
	467	{ "local3", LOG_LOCAL3 },
	468	{ "local4", LOG_LOCAL4 },
	469	{ "local5", LOG_LOCAL5 },
	470	{ "local6", LOG_LOCAL6 },
	471	{ "local7", LOG_LOCAL7 },
	472	{ "lpr", LOG_LPR },
	473	{ "mail", LOG_MAIL },
	474	{ "news", LOG_NEWS },
	475	{ "syslog", LOG_SYSLOG },
	476	{ "user", LOG_USER },
	477	{ "uucp", LOG_UUCP },
	478	{ NULL, 0 }
	479	};
	480
	481	static void syslog_phase_hook(void *sst, uint32_t newphase)
	482	{
	483	struct syslog *st=sst;
	484
	485	if (background) {
	486	openlog(st->ident,0,st->facility);
	487	st->open=True;
	488	}
	489	}
	490
	491	static list_t syslog_apply(closure_t self, struct cloc loc, dict_t *context,
	492	list_t *args)
	493	{
	494	struct syslog *st;
	495	dict_t *d;
	496	item_t *item;
	497	string_t facstr;
	498
	499	st=safe_malloc(sizeof(*st),"syslog_apply");
	500	st->cl.description="syslog";
	501	st->cl.type=CL_LOG;
	502	st->cl.apply=NULL;
	503	st->cl.interface=&st->ops;
	504	st->ops.st=st;
779837e1	505	st->ops.vlogfn=syslog_vlog;
ff1dcd86	506	st->ops.buff[0]=0;
7138d0c5 SE	507
	508	item=list_elem(args,0);
	509	if (!item \|\| item->type!=t_dict)
	510	cfgfatal(loc,"syslog","parameter must be a dictionary\n");
	511	d=item->data.dict;
	512
	513	st->ident=dict_read_string(d, "ident", False, "syslog", loc);
	514	facstr=dict_read_string(d, "facility", True, "syslog", loc);
	515	st->facility=string_to_word(facstr,loc,
	516	syslog_facility_table,"syslog");
	517	st->open=False;
	518	add_hook(PHASE_GETRESOURCES,syslog_phase_hook,st);
	519
	520	return new_closure(&st->cl);
	521	}
	522
fe5e9cc4 SE	523	/* Read from a fd and output to a log. This is a quick hack to
	524	support logging stderr, and needs code adding to tidy up before it
	525	can be used for anything else. */
	526	#define FDLOG_BUFSIZE 1024
	527	struct fdlog {
	528	struct log_if *log;
	529	int fd;
	530	cstring_t prefix;
	531	string_t buffer;
	532	int i;
	533	bool_t finished;
	534	};
	535
	536	static int log_from_fd_beforepoll(void sst, struct pollfd fds, int *nfds_io,
90a39563	537	int *timeout_io)
fe5e9cc4 SE	538	{
	539	struct fdlog *st=sst;
	540	if (!st->finished) {
ee697dd9	541	BEFOREPOLL_WANT_FDS(1);
fe5e9cc4 SE	542	fds[0].fd=st->fd;
fe5e9cc4 SE	543	fds[0].events=POLLIN;
ee697dd9 IJ	544	} else {
ee697dd9 IJ	545	BEFOREPOLL_WANT_FDS(0);
fe5e9cc4 SE	546	}
	547	return 0;
	548	}
	549
90a39563	550	static void log_from_fd_afterpoll(void sst, struct pollfd fds, int nfds)
fe5e9cc4 SE	551	{
	552	struct fdlog *st=sst;
	553	int r,remain,i;
	554
	555	if (nfds==0) return;
	556	if (fds[0].revents&POLLERR) {
	557	st->finished=True;
	558	}
	559	if (fds[0].revents&POLLIN) {
	560	remain=FDLOG_BUFSIZE-st->i-1;
	561	if (remain<=0) {
	562	st->buffer[FDLOG_BUFSIZE-1]=0;
779837e1	563	slilog(st->log,M_WARNING,"%s: overlong line: %s",
fe5e9cc4 SE	564	st->prefix,st->buffer);
	565	st->i=0;
	566	remain=FDLOG_BUFSIZE-1;
	567	}
	568	r=read(st->fd,st->buffer+st->i,remain);
	569	if (r>0) {
	570	st->i+=r;
	571	for (i=0; i<st->i; i++) {
	572	if (st->buffer[i]=='\n') {
	573	st->buffer[i]=0;
779837e1	574	slilog(st->log,M_INFO,"%s: %s",
fe5e9cc4 SE	575	st->prefix,st->buffer);
	576	i++;
	577	memmove(st->buffer,st->buffer+i,st->i-i);
	578	st->i-=i;
	579	i=-1;
	580	}
	581	}
ba703386	582	} else if (errno==EINTR \|\| iswouldblock(errno)) {
fe5e9cc4 SE	583	} else {
	584	Message(M_WARNING,"log_from_fd: %s\n",strerror(errno));
	585	st->finished=True;
	586	}
	587	}
	588	}
	589
	590	void log_from_fd(int fd, cstring_t prefix, struct log_if *log)
	591	{
	592	struct fdlog *st;
	593
	594	st=safe_malloc(sizeof(*st),"log_from_fd");
	595	st->log=log;
	596	st->fd=fd;
	597	st->prefix=prefix;
	598	st->buffer=safe_malloc(FDLOG_BUFSIZE,"log_from_fd");
	599	st->i=0;
	600	st->finished=False;
	601
ba703386 IJ	602	setnonblock(st->fd);
ba703386 IJ	603
32fc582f	604	register_for_poll(st,log_from_fd_beforepoll,log_from_fd_afterpoll,
fe5e9cc4 SE	605	prefix);
	606	}
	607
7138d0c5 SE	608	void log_module(dict_t *dict)
	609	{
	610	add_closure(dict,"logfile",logfile_apply);
	611	add_closure(dict,"syslog",syslog_apply);
	612	}