1 7e9b7812 2021-02-07 op /*
  2 7e9b7812 2021-02-07 op  * Copyright (c) 2021 Omar Polo <op@omarpolo.com>
  3 7e9b7812 2021-02-07 op  *
  4 7e9b7812 2021-02-07 op  * Permission to use, copy, modify, and distribute this software for any
  5 7e9b7812 2021-02-07 op  * purpose with or without fee is hereby granted, provided that the above
  6 7e9b7812 2021-02-07 op  * copyright notice and this permission notice appear in all copies.
  7 7e9b7812 2021-02-07 op  *
  8 7e9b7812 2021-02-07 op  * THE SOFTWARE IS PROVIDED "AS IS" AND THE AUTHOR DISCLAIMS ALL WARRANTIES
  9 7e9b7812 2021-02-07 op  * WITH REGARD TO THIS SOFTWARE INCLUDING ALL IMPLIED WARRANTIES OF
 10 7e9b7812 2021-02-07 op  * MERCHANTABILITY AND FITNESS. IN NO EVENT SHALL THE AUTHOR BE LIABLE FOR
 11 7e9b7812 2021-02-07 op  * ANY SPECIAL, DIRECT, INDIRECT, OR CONSEQUENTIAL DAMAGES OR ANY DAMAGES
 12 7e9b7812 2021-02-07 op  * WHATSOEVER RESULTING FROM LOSS OF USE, DATA OR PROFITS, WHETHER IN AN
 13 7e9b7812 2021-02-07 op  * ACTION OF CONTRACT, NEGLIGENCE OR OTHER TORTIOUS ACTION, ARISING OUT OF
 14 7e9b7812 2021-02-07 op  * OR IN CONNECTION WITH THE USE OR PERFORMANCE OF THIS SOFTWARE.
 15 7e9b7812 2021-02-07 op  */
 16 7e9b7812 2021-02-07 op 
 17 52418c8d 2021-02-12 op #include "gmid.h"
 18 52418c8d 2021-02-12 op 
 19 d278a0c3 2021-02-23 op #include <sys/types.h>
 20 d278a0c3 2021-02-23 op #include <sys/uio.h>
 21 d278a0c3 2021-02-23 op 
 22 7e9b7812 2021-02-07 op #include <errno.h>
 23 d278a0c3 2021-02-23 op #include <event.h>
 24 d278a0c3 2021-02-23 op #include <imsg.h>
 25 7e9b7812 2021-02-07 op #include <netdb.h>
 26 2ef7f631 2021-04-28 op #include <poll.h>
 27 7e9b7812 2021-02-07 op #include <stdarg.h>
 28 7e9b7812 2021-02-07 op #include <stdio.h>
 29 7e9b7812 2021-02-07 op #include <string.h>
 30 7e9b7812 2021-02-07 op #include <syslog.h>
 31 b3342582 2021-04-14 op #include <time.h>
 32 7e9b7812 2021-02-07 op 
 33 bc99d868 2021-03-19 op static struct event imsgev;
 34 d278a0c3 2021-02-23 op 
 35 e952c505 2021-06-15 op static FILE *log;
 36 e952c505 2021-06-15 op 
 37 bc99d868 2021-03-19 op static void	handle_imsg_quit(struct imsgbuf*, struct imsg*, size_t);
 38 bc99d868 2021-03-19 op static void	handle_imsg_log(struct imsgbuf*, struct imsg*, size_t);
 39 e952c505 2021-06-15 op static void	handle_imsg_log_type(struct imsgbuf*, struct imsg*, size_t);
 40 bc99d868 2021-03-19 op static void	handle_dispatch_imsg(int, short, void*);
 41 d278a0c3 2021-02-23 op 
 42 bc99d868 2021-03-19 op static imsg_handlerfn *handlers[] = {
 43 bc99d868 2021-03-19 op 	[IMSG_QUIT] = handle_imsg_quit,
 44 bc99d868 2021-03-19 op 	[IMSG_LOG] = handle_imsg_log,
 45 41395640 2021-07-19 op 	[IMSG_LOG_REQUEST] = handle_imsg_log,
 46 e952c505 2021-06-15 op 	[IMSG_LOG_TYPE] = handle_imsg_log_type,
 47 bc99d868 2021-03-19 op };
 48 b3342582 2021-04-14 op 
 49 b3342582 2021-04-14 op static inline void
 50 e952c505 2021-06-15 op print_date(FILE *f)
 51 b3342582 2021-04-14 op {
 52 b3342582 2021-04-14 op 	struct tm	tminfo;
 53 b3342582 2021-04-14 op 	time_t		t;
 54 b3342582 2021-04-14 op 	char		buf[20];
 55 b3342582 2021-04-14 op 
 56 b3342582 2021-04-14 op 	time(&t);
 57 b3342582 2021-04-14 op 	strftime(buf, sizeof(buf), "%F %T",
 58 b3342582 2021-04-14 op 	    localtime_r(&t, &tminfo));
 59 e952c505 2021-06-15 op 	fprintf(f, "[%s] ", buf);
 60 7e9b7812 2021-02-07 op }
 61 7e9b7812 2021-02-07 op 
 62 7e9b7812 2021-02-07 op static inline int
 63 7e9b7812 2021-02-07 op should_log(int priority)
 64 7e9b7812 2021-02-07 op {
 65 7e9b7812 2021-02-07 op 	switch (priority) {
 66 7e9b7812 2021-02-07 op 	case LOG_ERR:
 67 7e9b7812 2021-02-07 op 		return 1;
 68 7e9b7812 2021-02-07 op 	case LOG_WARNING:
 69 7e9b7812 2021-02-07 op 		return 1;
 70 7e9b7812 2021-02-07 op 	case LOG_NOTICE:
 71 7e9b7812 2021-02-07 op 		return conf.verbose >= 1;
 72 7e9b7812 2021-02-07 op 	case LOG_INFO:
 73 7e9b7812 2021-02-07 op 		return conf.verbose >= 2;
 74 7e9b7812 2021-02-07 op 	case LOG_DEBUG:
 75 7e9b7812 2021-02-07 op 		return conf.verbose >= 3;
 76 7e9b7812 2021-02-07 op 	default:
 77 7e9b7812 2021-02-07 op 		return 0;
 78 7e9b7812 2021-02-07 op 	}
 79 7e9b7812 2021-02-07 op }
 80 7e9b7812 2021-02-07 op 
 81 d278a0c3 2021-02-23 op static inline void
 82 2b5e2a5f 2021-07-19 op send_log(int type, int priority, const char *msg, size_t len)
 83 d278a0c3 2021-02-23 op {
 84 2b5e2a5f 2021-07-19 op 	imsg_compose(&logibuf, type, priority, 0, -1, msg, len);
 85 bc99d868 2021-03-19 op 	imsg_flush(&logibuf);
 86 d278a0c3 2021-02-23 op }
 87 d278a0c3 2021-02-23 op 
 88 d89a9060 2021-04-28 op void
 89 d89a9060 2021-04-28 op fatal(const char *fmt, ...)
 90 d89a9060 2021-04-28 op {
 91 2ef7f631 2021-04-28 op 	struct pollfd pfd;
 92 d89a9060 2021-04-28 op 	va_list	 ap;
 93 d89a9060 2021-04-28 op 	int	 r;
 94 d89a9060 2021-04-28 op 	char	*fmted;
 95 d89a9060 2021-04-28 op 
 96 d89a9060 2021-04-28 op 	va_start(ap, fmt);
 97 d89a9060 2021-04-28 op 	if ((r = vasprintf(&fmted, fmt, ap)) != -1) {
 98 ef352ec3 2021-08-23 op 		send_log(IMSG_LOG, LOG_CRIT, fmted, r+1);
 99 d89a9060 2021-04-28 op 		free(fmted);
100 2ef7f631 2021-04-28 op 
101 2ef7f631 2021-04-28 op 		/* wait for the logger process to shut down */
102 2ef7f631 2021-04-28 op 		pfd.fd = logibuf.fd;
103 2ef7f631 2021-04-28 op 		pfd.events = POLLIN;
104 2ef7f631 2021-04-28 op 		poll(&pfd, 1, 1000);
105 d89a9060 2021-04-28 op 	}
106 d89a9060 2021-04-28 op 	va_end(ap);
107 d89a9060 2021-04-28 op 	exit(1);
108 d89a9060 2021-04-28 op }
109 d89a9060 2021-04-28 op 
110 d278a0c3 2021-02-23 op static inline void
111 d278a0c3 2021-02-23 op vlog(int priority, struct client *c,
112 7e9b7812 2021-02-07 op     const char *fmt, va_list ap)
113 7e9b7812 2021-02-07 op {
114 7e9b7812 2021-02-07 op 	char hbuf[NI_MAXHOST], sbuf[NI_MAXSERV];
115 7e9b7812 2021-02-07 op 	char *fmted, *s;
116 7e9b7812 2021-02-07 op 	size_t len;
117 7e9b7812 2021-02-07 op 	int ec;
118 7e9b7812 2021-02-07 op 
119 7e9b7812 2021-02-07 op 	if (!should_log(priority))
120 7e9b7812 2021-02-07 op 		return;
121 7e9b7812 2021-02-07 op 
122 d278a0c3 2021-02-23 op 	if (c != NULL) {
123 7e9b7812 2021-02-07 op 		len = sizeof(c->addr);
124 7e9b7812 2021-02-07 op 		ec = getnameinfo((struct sockaddr*)&c->addr, len,
125 7e9b7812 2021-02-07 op 		    hbuf, sizeof(hbuf),
126 7e9b7812 2021-02-07 op 		    sbuf, sizeof(sbuf),
127 7e9b7812 2021-02-07 op 		    NI_NUMERICHOST | NI_NUMERICSERV);
128 7e9b7812 2021-02-07 op 		if (ec != 0)
129 7e9b7812 2021-02-07 op 			fatal("getnameinfo: %s", gai_strerror(ec));
130 7e9b7812 2021-02-07 op 	}
131 7e9b7812 2021-02-07 op 
132 7e9b7812 2021-02-07 op 	if (vasprintf(&fmted, fmt, ap) == -1)
133 7e9b7812 2021-02-07 op 		fatal("vasprintf: %s", strerror(errno));
134 7e9b7812 2021-02-07 op 
135 7700ab07 2021-07-06 op 	if (c == NULL)
136 d278a0c3 2021-02-23 op 		ec = asprintf(&s, "internal: %s", fmted);
137 d278a0c3 2021-02-23 op 	else
138 d278a0c3 2021-02-23 op 		ec = asprintf(&s, "%s:%s %s", hbuf, sbuf, fmted);
139 7e9b7812 2021-02-07 op 
140 d278a0c3 2021-02-23 op 	if (ec < 0)
141 d278a0c3 2021-02-23 op 		fatal("asprintf: %s", strerror(errno));
142 d278a0c3 2021-02-23 op 
143 ef352ec3 2021-08-23 op 	send_log(IMSG_LOG, priority, s, ec+1);
144 d278a0c3 2021-02-23 op 
145 7e9b7812 2021-02-07 op 	free(fmted);
146 d278a0c3 2021-02-23 op 	free(s);
147 7e9b7812 2021-02-07 op }
148 7e9b7812 2021-02-07 op 
149 7e9b7812 2021-02-07 op void
150 7e9b7812 2021-02-07 op log_err(struct client *c, const char *fmt, ...)
151 7e9b7812 2021-02-07 op {
152 7e9b7812 2021-02-07 op 	va_list ap;
153 7e9b7812 2021-02-07 op 
154 7e9b7812 2021-02-07 op 	va_start(ap, fmt);
155 d278a0c3 2021-02-23 op 	vlog(LOG_ERR, c, fmt, ap);
156 7e9b7812 2021-02-07 op 	va_end(ap);
157 7e9b7812 2021-02-07 op }
158 7e9b7812 2021-02-07 op 
159 7e9b7812 2021-02-07 op void
160 7e9b7812 2021-02-07 op log_warn(struct client *c, const char *fmt, ...)
161 7e9b7812 2021-02-07 op {
162 7e9b7812 2021-02-07 op 	va_list ap;
163 7e9b7812 2021-02-07 op 
164 7e9b7812 2021-02-07 op 	va_start(ap, fmt);
165 d278a0c3 2021-02-23 op 	vlog(LOG_WARNING, c, fmt, ap);
166 7e9b7812 2021-02-07 op 	va_end(ap);
167 7e9b7812 2021-02-07 op }
168 7e9b7812 2021-02-07 op 
169 7e9b7812 2021-02-07 op void
170 7e9b7812 2021-02-07 op log_notice(struct client *c, const char *fmt, ...)
171 7e9b7812 2021-02-07 op {
172 7e9b7812 2021-02-07 op 	va_list ap;
173 7e9b7812 2021-02-07 op 
174 7e9b7812 2021-02-07 op 	va_start(ap, fmt);
175 d278a0c3 2021-02-23 op 	vlog(LOG_NOTICE, c, fmt, ap);
176 7e9b7812 2021-02-07 op 	va_end(ap);
177 7e9b7812 2021-02-07 op }
178 7e9b7812 2021-02-07 op 
179 7e9b7812 2021-02-07 op void
180 7e9b7812 2021-02-07 op log_info(struct client *c, const char *fmt, ...)
181 7e9b7812 2021-02-07 op {
182 7e9b7812 2021-02-07 op 	va_list ap;
183 7e9b7812 2021-02-07 op 
184 7e9b7812 2021-02-07 op 	va_start(ap, fmt);
185 d278a0c3 2021-02-23 op 	vlog(LOG_INFO, c, fmt, ap);
186 7e9b7812 2021-02-07 op 	va_end(ap);
187 7e9b7812 2021-02-07 op }
188 7e9b7812 2021-02-07 op 
189 7e9b7812 2021-02-07 op void
190 7e9b7812 2021-02-07 op log_debug(struct client *c, const char *fmt, ...)
191 7e9b7812 2021-02-07 op {
192 7e9b7812 2021-02-07 op 	va_list ap;
193 7e9b7812 2021-02-07 op 
194 7e9b7812 2021-02-07 op 	va_start(ap, fmt);
195 d278a0c3 2021-02-23 op 	vlog(LOG_DEBUG, c, fmt, ap);
196 7e9b7812 2021-02-07 op 	va_end(ap);
197 7e9b7812 2021-02-07 op }
198 7e9b7812 2021-02-07 op 
199 7e9b7812 2021-02-07 op /* strchr, but with a bound */
200 7e9b7812 2021-02-07 op static char *
201 7e9b7812 2021-02-07 op gmid_strnchr(char *s, int c, size_t len)
202 7e9b7812 2021-02-07 op {
203 7e9b7812 2021-02-07 op 	size_t i;
204 7e9b7812 2021-02-07 op 
205 7e9b7812 2021-02-07 op 	for (i = 0; i < len; ++i)
206 7e9b7812 2021-02-07 op 		if (s[i] == c)
207 7e9b7812 2021-02-07 op 			return &s[i];
208 7e9b7812 2021-02-07 op 	return NULL;
209 7e9b7812 2021-02-07 op }
210 7e9b7812 2021-02-07 op 
211 7e9b7812 2021-02-07 op void
212 7e9b7812 2021-02-07 op log_request(struct client *c, char *meta, size_t l)
213 7e9b7812 2021-02-07 op {
214 7e9b7812 2021-02-07 op 	char hbuf[NI_MAXHOST], sbuf[NI_MAXSERV], b[GEMINI_URL_LEN];
215 d278a0c3 2021-02-23 op 	char *t, *fmted;
216 7e9b7812 2021-02-07 op 	size_t len;
217 7e9b7812 2021-02-07 op 	int ec;
218 7e9b7812 2021-02-07 op 
219 7e9b7812 2021-02-07 op 	len = sizeof(c->addr);
220 7e9b7812 2021-02-07 op 	ec = getnameinfo((struct sockaddr*)&c->addr, len,
221 7e9b7812 2021-02-07 op 	    hbuf, sizeof(hbuf),
222 7e9b7812 2021-02-07 op 	    sbuf, sizeof(sbuf),
223 7e9b7812 2021-02-07 op 	    NI_NUMERICHOST | NI_NUMERICSERV);
224 7e9b7812 2021-02-07 op 	if (ec != 0)
225 7e9b7812 2021-02-07 op 		fatal("getnameinfo: %s", gai_strerror(ec));
226 7e9b7812 2021-02-07 op 
227 7e9b7812 2021-02-07 op 	if (c->iri.schema != NULL) {
228 7e9b7812 2021-02-07 op 		/* serialize the IRI */
229 7e9b7812 2021-02-07 op 		strlcpy(b, c->iri.schema, sizeof(b));
230 7e9b7812 2021-02-07 op 		strlcat(b, "://", sizeof(b));
231 7e9b7812 2021-02-07 op 
232 7e9b7812 2021-02-07 op 		/* log the decoded host name, but if it was invalid
233 7e9b7812 2021-02-07 op 		 * use the raw one. */
234 7e9b7812 2021-02-07 op 		if (*c->domain != '\0')
235 7e9b7812 2021-02-07 op 			strlcat(b, c->domain, sizeof(b));
236 7e9b7812 2021-02-07 op 		else
237 7e9b7812 2021-02-07 op 			strlcat(b, c->iri.host, sizeof(b));
238 7e9b7812 2021-02-07 op 
239 7e9b7812 2021-02-07 op 		strlcat(b, "/", sizeof(b));
240 7e9b7812 2021-02-07 op 		strlcat(b, c->iri.path, sizeof(b)); /* TODO: sanitize UTF8 */
241 7e9b7812 2021-02-07 op 		if (*c->iri.query != '\0') {	    /* TODO: sanitize UTF8 */
242 7e9b7812 2021-02-07 op 			strlcat(b, "?", sizeof(b));
243 7e9b7812 2021-02-07 op 			strlcat(b, c->iri.query, sizeof(b));
244 7e9b7812 2021-02-07 op 		}
245 7e9b7812 2021-02-07 op 	} else {
246 7e9b7812 2021-02-07 op 		strlcpy(b, c->req, sizeof(b));
247 7e9b7812 2021-02-07 op 	}
248 7e9b7812 2021-02-07 op 
249 7e9b7812 2021-02-07 op 	if ((t = gmid_strnchr(meta, '\r', l)) == NULL)
250 7e9b7812 2021-02-07 op 		t = meta + len;
251 7e9b7812 2021-02-07 op 
252 d278a0c3 2021-02-23 op 	ec = asprintf(&fmted, "%s:%s GET %s %.*s", hbuf, sbuf, b,
253 d278a0c3 2021-02-23 op 	    (int)(t-meta), meta);
254 d278a0c3 2021-02-23 op 	if (ec < 0)
255 d278a0c3 2021-02-23 op 		err(1, "asprintf");
256 ef352ec3 2021-08-23 op 	send_log(IMSG_LOG_REQUEST, LOG_NOTICE, fmted, ec+1);
257 d278a0c3 2021-02-23 op 	free(fmted);
258 d278a0c3 2021-02-23 op }
259 d278a0c3 2021-02-23 op 
260 d278a0c3 2021-02-23 op 
261 d278a0c3 2021-02-23 op 
262 d278a0c3 2021-02-23 op static void
263 41395640 2021-07-19 op do_log(int type, int priority, const char *msg)
264 e952c505 2021-06-15 op {
265 e952c505 2021-06-15 op 	int quit = 0;
266 e952c505 2021-06-15 op 
267 e952c505 2021-06-15 op 	if (priority == LOG_CRIT) {
268 e952c505 2021-06-15 op 		quit = 1;
269 e952c505 2021-06-15 op 		priority = LOG_ERR;
270 e952c505 2021-06-15 op 	}
271 e952c505 2021-06-15 op 
272 e952c505 2021-06-15 op 	if (log != NULL) {
273 41395640 2021-07-19 op 		if (type != IMSG_LOG_REQUEST)
274 41395640 2021-07-19 op 			print_date(log);
275 e952c505 2021-06-15 op 		fprintf(log, "%s\n", msg);
276 e952c505 2021-06-15 op 	} else
277 e952c505 2021-06-15 op 		syslog(LOG_DAEMON | priority, "%s", msg);
278 e952c505 2021-06-15 op 
279 e952c505 2021-06-15 op 	if (quit)
280 e952c505 2021-06-15 op 		exit(1);
281 e952c505 2021-06-15 op }
282 e952c505 2021-06-15 op 
283 e952c505 2021-06-15 op static void
284 bc99d868 2021-03-19 op handle_imsg_quit(struct imsgbuf *ibuf, struct imsg *imsg, size_t datalen)
285 d278a0c3 2021-02-23 op {
286 bc99d868 2021-03-19 op 	event_loopbreak();
287 bc99d868 2021-03-19 op }
288 d278a0c3 2021-02-23 op 
289 bc99d868 2021-03-19 op static void
290 bc99d868 2021-03-19 op handle_imsg_log(struct imsgbuf *ibuf, struct imsg *imsg, size_t datalen)
291 bc99d868 2021-03-19 op {
292 984c46a8 2021-06-16 op 	int	 priority;
293 2ef7f631 2021-04-28 op 	char	*msg;
294 d278a0c3 2021-02-23 op 
295 bc99d868 2021-03-19 op 	msg = imsg->data;
296 bc99d868 2021-03-19 op 	msg[datalen-1] = '\0';
297 2ef7f631 2021-04-28 op 	priority = imsg->hdr.peerid;
298 41395640 2021-07-19 op 	do_log(imsg->hdr.type, priority, msg);
299 e952c505 2021-06-15 op }
300 2ef7f631 2021-04-28 op 
301 e952c505 2021-06-15 op static void
302 e952c505 2021-06-15 op handle_imsg_log_type(struct imsgbuf *ibuf, struct imsg *imsg, size_t datalen)
303 e952c505 2021-06-15 op {
304 8a50fc03 2021-07-07 op 	if (log != NULL && log != stderr) {
305 e952c505 2021-06-15 op 		fflush(log);
306 e952c505 2021-06-15 op 		fclose(log);
307 2ef7f631 2021-04-28 op 	}
308 8a50fc03 2021-07-07 op 	log = NULL;
309 2ef7f631 2021-04-28 op 
310 e952c505 2021-06-15 op 	if (imsg->fd != -1) {
311 e952c505 2021-06-15 op 		if ((log = fdopen(imsg->fd, "a")) == NULL) {
312 e952c505 2021-06-15 op 			syslog(LOG_DAEMON | LOG_ERR, "fdopen: %s",
313 e952c505 2021-06-15 op 			    strerror(errno));
314 e952c505 2021-06-15 op 			exit(1);
315 e952c505 2021-06-15 op 		}
316 e952c505 2021-06-15 op 	}
317 bc99d868 2021-03-19 op }
318 d278a0c3 2021-02-23 op 
319 bc99d868 2021-03-19 op static void
320 bc99d868 2021-03-19 op handle_dispatch_imsg(int fd, short ev, void *d)
321 bc99d868 2021-03-19 op {
322 bc99d868 2021-03-19 op 	struct imsgbuf *ibuf = d;
323 bc99d868 2021-03-19 op 	dispatch_imsg(ibuf, handlers, sizeof(handlers));
324 7e9b7812 2021-02-07 op }
325 d278a0c3 2021-02-23 op 
326 376a5407 2021-02-23 op int
327 d278a0c3 2021-02-23 op logger_main(int fd, struct imsgbuf *ibuf)
328 d278a0c3 2021-02-23 op {
329 8a50fc03 2021-07-07 op 	log = stderr;
330 db623d75 2021-09-17 op 
331 db623d75 2021-09-17 op 	openlog(getprogname(), LOG_NDELAY, LOG_DAEMON);
332 8a50fc03 2021-07-07 op 
333 d278a0c3 2021-02-23 op 	event_init();
334 d278a0c3 2021-02-23 op 
335 bc99d868 2021-03-19 op 	event_set(&imsgev, fd, EV_READ | EV_PERSIST, &handle_dispatch_imsg, ibuf);
336 bc99d868 2021-03-19 op 	event_add(&imsgev, NULL);
337 d278a0c3 2021-02-23 op 
338 62e001b0 2021-03-20 op 	sandbox_logger_process();
339 d278a0c3 2021-02-23 op 
340 d278a0c3 2021-02-23 op 	event_dispatch();
341 d278a0c3 2021-02-23 op 
342 db623d75 2021-09-17 op 	closelog();
343 db623d75 2021-09-17 op 
344 d278a0c3 2021-02-23 op 	return 0;
345 d278a0c3 2021-02-23 op }