Whamcloud - gitweb
LU-14051 utils: flush alr batch file in thread
[fs/lustre-release.git] / lustre / utils / ofd_access_log_reader.c
1 /*
2  * GPL HEADER START
3  *
4  * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
5  *
6  * This program is free software; you can redistribute it and/or modify
7  * it under the terms of the GNU General Public License version 2 only,
8  * as published by the Free Software Foundation.
9  *
10  * This program is distributed in the hope that it will be useful, but
11  * WITHOUT ANY WARRANTY; without even the implied warranty of
12  * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the GNU
13  * General Public License version 2 for more details (a copy is included
14  * in the LICENSE file that accompanied this code).
15  *
16  * You should have received a copy of the GNU General Public License
17  * version 2 along with this program; If not, see
18  * http://www.gnu.org/licenses/gpl-2.0.html
19  *
20  * GPL HEADER END
21  *
22  * Copyright 2020, DataDirect Networks Storage.
23  *
24  * This file is part of Lustre, http://www.lustre.org/
25  *
26  * Author: John L. Hammond <jhammond@whamcloud.com>
27  *
28  * lustre/utils/ofd_access_log_reader.c
29  *
30  * Sample utility to discover and read Lustre (ofd) access logs.
31  *
32  * This demonstrates the discovery and reading of Lustre access logs
33  * (see linux/lustre/lustre_access_log.h and
34  * lustre/ofd/ofd_access_log.c.). By default it opens the control
35  * device, discovers and opens all access log devices, and consumes
36  * all access log entries. If invoked with the --list option then it
37  * prints information about all available devices to stdout and exits.
38  *
39  * Structured trace points (when --trace is used) are added to permit
40  * testing of the access log functionality (see test_165* in
41  * lustre/tests/sanity.sh).
42  */
43 #include <stddef.h>
44 #include <stdio.h>
45 #include <stdlib.h>
46 #include <assert.h>
47 #include <dirent.h>
48 #include <errno.h>
49 #include <fcntl.h>
50 #include <getopt.h>
51 #include <inttypes.h>
52 #include <limits.h>
53 #include <malloc.h>
54 #include <pthread.h>
55 #include <signal.h>
56 #include <string.h>
57 #include <unistd.h>
58 #include <sys/epoll.h>
59 #include <sys/ioctl.h>
60 #include <sys/signalfd.h>
61 #include <sys/stat.h>
62 #include <sys/sysmacros.h>
63 #include <sys/timerfd.h>
64 #include <sys/types.h>
65 #include <linux/types.h>
66 #include <linux/lustre/lustre_user.h>
67 #include <linux/lustre/lustre_access_log.h>
68 #include "ofd_access_batch.h"
69 #include "lstddef.h"
70
71 /* TODO fsname filter */
72
73 static FILE *debug_file;
74 static FILE *trace_file;
75
76 #define DEBUG(fmt, args...)                                             \
77         do {                                                            \
78                 if (debug_file != NULL)                                 \
79                         fprintf(debug_file, "DEBUG %s:%d: "fmt, __func__, __LINE__, ##args); \
80         } while (0)
81
82 #define TRACE(fmt, args...)                                             \
83         do {                                                            \
84                 if (trace_file != NULL)                                 \
85                         fprintf(trace_file, "TRACE "fmt, ##args);       \
86         } while (0)
87
88 #define DEBUG_D(x) DEBUG("%s = %"PRIdMAX"\n", #x, (intmax_t)x)
89 #define DEBUG_P(x) DEBUG("%s = %p\n", #x, x)
90 #define DEBUG_S(x) DEBUG("%s = '%s'\n", #x, x)
91 #define DEBUG_U(x) DEBUG("%s = %"PRIuMAX"\n", #x, (uintmax_t)x)
92
93 #define ERROR(fmt, args...) \
94         fprintf(stderr, "%s: "fmt, program_invocation_short_name, ##args)
95
96 #define FATAL(fmt, args...)                     \
97         do {                                    \
98                 ERROR("FATAL: "fmt, ##args);    \
99                 exit(EXIT_FAILURE);             \
100         } while (0)
101
102 enum {
103         ALR_EXIT_SUCCESS = INT_MIN + EXIT_SUCCESS,
104         ALR_EXIT_FAILURE = INT_MIN + EXIT_FAILURE,
105         ALR_ERROR = -1,
106         ALR_EOF = 0,
107         ALR_OK = 1,
108 };
109
110 struct alr_dev {
111         char *alr_name;
112         int (*alr_io)(int /* epoll_fd */, struct alr_dev * /* this */, unsigned int /* mask */);
113         void (*alr_destroy)(struct alr_dev *);
114         int alr_fd;
115 };
116
117 struct alr_log {
118         struct alr_dev alr_dev;
119         char *alr_buf;
120         size_t alr_buf_size;
121         size_t alr_entry_size;
122         dev_t alr_rdev;
123 };
124
125 static struct alr_log *alr_log[1 << 20]; /* 20 == MINORBITS */
126 static int oal_version; /* FIXME ... major version, minor version */
127 static unsigned int oal_log_major;
128 static unsigned int oal_log_minor_max;
129 static struct alr_batch *alr_batch;
130 static FILE *alr_batch_file;
131 static pthread_mutex_t alr_batch_file_mutex = PTHREAD_MUTEX_INITIALIZER;
132 static const char *alr_batch_file_path;
133 static int alr_print_fraction = 100;
134
135 #define D_ALR_DEV "%s %d"
136 #define P_ALR_DEV(ad) \
137         (ad)->alr_name, (ad)->alr_fd
138
139 #define D_ALR_LOG D_ALR_DEV" %u:%u"
140 #define P_ALR_LOG(al) \
141         P_ALR_DEV(&(al)->alr_dev), major((al)->alr_rdev), minor((al)->alr_rdev)
142
143 static void alr_dev_free(int epoll_fd, struct alr_dev *ad)
144 {
145         TRACE("alr_dev_free %s\n", ad->alr_name);
146
147         if (!(ad->alr_fd < 0))
148                 epoll_ctl(epoll_fd, EPOLL_CTL_DEL, ad->alr_fd, NULL);
149
150         if (ad->alr_destroy != NULL)
151                 (*ad->alr_destroy)(ad);
152
153         if (!(ad->alr_fd < 0))
154                 close(ad->alr_fd);
155
156         free(ad->alr_name);
157         free(ad);
158 }
159
160 static struct alr_log **alr_log_lookup(dev_t rdev)
161 {
162         assert(major(rdev) == oal_log_major);
163
164         if (!(minor(rdev) < ARRAY_SIZE(alr_log)))
165                 return NULL;
166
167         return &alr_log[minor(rdev)];
168 }
169
170 static const char *alr_flags_to_str(unsigned int flags)
171 {
172         switch (flags & (OFD_ACCESS_READ | OFD_ACCESS_WRITE)) {
173         default:
174                 return "0";
175         case OFD_ACCESS_READ:
176                 return "r";
177         case OFD_ACCESS_WRITE:
178                 return "w";
179         case OFD_ACCESS_READ | OFD_ACCESS_WRITE:
180                 return "rw";
181         }
182 }
183
184 /* /dev/lustre-access-log/scratch-OST0000 device poll callback: read entries
185  * from log and print. */
186 static int alr_log_io(int epoll_fd, struct alr_dev *ad, unsigned int mask)
187 {
188         struct alr_log *al = container_of(ad, struct alr_log, alr_dev);
189         ssize_t i, count;
190
191         TRACE("alr_log_io %s\n", ad->alr_name);
192         DEBUG_U(mask);
193
194         assert(al->alr_entry_size != 0);
195         assert(al->alr_buf_size != 0);
196         assert(al->alr_buf != NULL);
197
198         count = read(ad->alr_fd, al->alr_buf, al->alr_buf_size);
199         if (count < 0) {
200                 ERROR("cannot read events from '%s': %s\n", ad->alr_name, strerror(errno));
201                 return ALR_ERROR;
202         }
203
204         if (count == 0) {
205                 TRACE("alr_log_eof %s\n", ad->alr_name);
206                 return ALR_EOF;
207         }
208
209         if (count % al->alr_entry_size != 0) {
210                 ERROR("invalid read from "D_ALR_LOG": entry_size = %zu, count = %zd\n",
211                         P_ALR_LOG(al), al->alr_entry_size, count);
212                 return ALR_ERROR;
213         }
214
215         DEBUG("read "D_ALR_LOG", count = %zd\n", P_ALR_LOG(al), count);
216
217         for (i = 0; i < count; i += al->alr_entry_size) {
218                 struct ofd_access_entry_v1 *oae =
219                         (struct ofd_access_entry_v1 *)&al->alr_buf[i];
220
221                 TRACE("alr_log_entry %s "DFID" %lu %lu %lu %u %u %s\n",
222                         ad->alr_name,
223                         PFID(&oae->oae_parent_fid),
224                         (unsigned long)oae->oae_begin,
225                         (unsigned long)oae->oae_end,
226                         (unsigned long)oae->oae_time,
227                         (unsigned int)oae->oae_size,
228                         (unsigned int)oae->oae_segment_count,
229                         alr_flags_to_str(oae->oae_flags));
230
231                 alr_batch_add(alr_batch, ad->alr_name, &oae->oae_parent_fid,
232                         oae->oae_time, oae->oae_begin, oae->oae_end,
233                         oae->oae_size, oae->oae_segment_count, oae->oae_flags);
234         }
235
236         return ALR_OK;
237 }
238
239 static void alr_log_destroy(struct alr_dev *ad)
240 {
241         struct alr_log *al = container_of(ad, struct alr_log, alr_dev);
242         struct alr_log **pal;
243
244         TRACE("alr_log_free %s\n", ad->alr_name);
245         assert(major(al->alr_rdev) == oal_log_major);
246
247         pal = alr_log_lookup(al->alr_rdev);
248         if (pal != NULL && *pal == al)
249                 *pal = NULL;
250
251         free(al->alr_buf);
252         al->alr_buf = NULL;
253         al->alr_buf_size = 0;
254 }
255
256 /* Add an access log (identified by path) to the epoll set. */
257 static int alr_log_add(int epoll_fd, const char *path)
258 {
259         struct alr_log **pal, *al = NULL;
260         struct stat st;
261         int fd = -1;
262         int rc;
263
264         DEBUG_S(path);
265
266         fd = open(path, O_RDONLY|O_NONBLOCK|O_CLOEXEC);
267         if (fd < 0) {
268                 ERROR("cannot open device '%s': %s\n", path, strerror(errno));
269                 rc = (errno == ENOENT ? 0 : -1); /* Possible race. */
270                 goto out;
271         }
272
273         /* Revalidate rdev in case of race. */
274         rc = fstat(fd, &st);
275         if (rc < 0) {
276                 ERROR("cannot stat '%s': %s\n", path, strerror(errno));
277                 goto out;
278         }
279
280         if (major(st.st_rdev) != oal_log_major)
281                 goto out;
282
283         pal = alr_log_lookup(st.st_rdev);
284         if (pal == NULL) {
285                 ERROR("no device slot available for '%s' with minor %u\n",
286                         path, minor(st.st_rdev));
287                 goto out;
288         }
289
290         if (*pal != NULL)
291                 goto out; /* We already have this device. */
292
293         struct lustre_access_log_info_v1 lali;
294
295         memset(&lali, 0, sizeof(lali));
296
297         rc = ioctl(fd, LUSTRE_ACCESS_LOG_IOCTL_INFO, &lali);
298         if (rc < 0) {
299                 ERROR("cannot get info for device '%s': %s\n",
300                         path, strerror(errno));
301                 goto out;
302         }
303
304         if (lali.lali_type != LUSTRE_ACCESS_LOG_TYPE_OFD) {
305                 rc = 0;
306                 goto out;
307         }
308
309         al = calloc(1, sizeof(*al));
310         if (al == NULL)
311                 FATAL("cannot allocate struct alr_dev of size %zu: %s\n",
312                         sizeof(*al), strerror(errno));
313
314         al->alr_dev.alr_io = &alr_log_io;
315         al->alr_dev.alr_destroy = &alr_log_destroy;
316         al->alr_dev.alr_fd = fd;
317         fd = -1;
318
319         al->alr_rdev = st.st_rdev;
320
321         al->alr_dev.alr_name = strdup(lali.lali_name);
322         if (al->alr_dev.alr_name == NULL)
323                 FATAL("cannot copy name of size %zu: %s\n",
324                         strlen(lali.lali_name), strerror(errno));
325
326         al->alr_buf_size = lali.lali_log_size;
327         al->alr_entry_size = lali.lali_entry_size;
328
329         if (al->alr_entry_size == 0) {
330                 ERROR("device '%s' has zero entry size\n", path);
331                 rc = -1;
332                 goto out;
333         }
334
335         if (al->alr_buf_size == 0)
336                 al->alr_buf_size = 1048576;
337
338         al->alr_buf_size = roundup(al->alr_buf_size, al->alr_entry_size);
339
340         al->alr_buf = malloc(al->alr_buf_size);
341         if (al->alr_buf == NULL)
342                 FATAL("cannot allocate log buffer for '%s' of size %zu: %s\n",
343                         path, al->alr_buf_size, strerror(errno));
344
345         struct epoll_event ev = {
346                 .events = EPOLLIN | EPOLLHUP,
347                 .data.ptr = &al->alr_dev,
348         };
349
350         rc = epoll_ctl(epoll_fd, EPOLL_CTL_ADD, al->alr_dev.alr_fd, &ev);
351         if (rc < 0) {
352                 ERROR("cannot add device '%s' to epoll set: %s\n",
353                         path, strerror(errno));
354                 goto out;
355         }
356
357         TRACE("alr_log_add %s\n", al->alr_dev.alr_name);
358
359         if (oal_log_minor_max < minor(al->alr_rdev))
360                 oal_log_minor_max = minor(al->alr_rdev);
361
362         assert(*pal == NULL);
363         *pal = al;
364         al = NULL;
365         rc = 0;
366 out:
367         if (al != NULL)
368                 alr_dev_free(epoll_fd, &al->alr_dev);
369
370         if (!(fd < 0))
371                 close(fd);
372
373         return rc;
374 }
375
376 /* Scan /dev/lustre-access-log/ for new access log devices and add to
377  * epoll set. */
378 static int alr_scan(int epoll_fd)
379 {
380         const char dir_path[] = "/dev/"LUSTRE_ACCESS_LOG_DIR_NAME;
381         DIR *dir;
382         int dir_fd;
383         struct dirent *d;
384         int rc;
385
386         dir = opendir(dir_path);
387         if (dir == NULL) {
388                 ERROR("cannot open '%s' for scanning: %s\n", dir_path, strerror(errno));
389                 return ALR_EXIT_FAILURE;
390         }
391
392         dir_fd = dirfd(dir);
393
394         /* Scan /dev for devices with major equal to oal_log_major and add
395          * any new devices. */
396         while ((d = readdir(dir)) != NULL) {
397                 char path[6 + PATH_MAX];
398                 struct alr_log **pal;
399                 struct stat st;
400
401                 if (d->d_type != DT_CHR)
402                         continue;
403
404                 rc = fstatat(dir_fd, d->d_name, &st, 0);
405                 if (rc < 0) {
406                         ERROR("cannot stat '%s/%s' while scanning: %s\n",
407                                 dir_path, d->d_name, strerror(errno));
408                         continue;
409                 }
410
411                 if (!S_ISCHR(st.st_mode))
412                         continue;
413
414                 if (major(st.st_rdev) != oal_log_major)
415                         continue;
416
417                 pal = alr_log_lookup(st.st_rdev);
418                 if (pal == NULL) {
419                         ERROR("no device slot available for '%s/%s' with minor %u\n",
420                                 dir_path, d->d_name, minor(st.st_rdev));
421                         continue;
422                 }
423
424                 if (*pal != NULL)
425                         continue; /* We already have this device. */
426
427                 snprintf(path, sizeof(path), "%s/%s", dir_path, d->d_name);
428
429                 alr_log_add(epoll_fd, path);
430         }
431
432         closedir(dir);
433
434         return ALR_OK;
435 }
436
437 /* /dev/lustre-access-log/control device poll callback: call prescan
438  * ioctl and scan /dev/lustre-access-log/ for new access log
439  * devices. */
440 static int alr_ctl_io(int epoll_fd, struct alr_dev *cd, unsigned int mask)
441 {
442         int rc;
443
444         TRACE("%s\n", __func__);
445         DEBUG_U(mask);
446
447         if (mask & EPOLLERR)
448                 return ALR_EXIT_FAILURE;
449
450         if (mask & EPOLLHUP)
451                 return ALR_EXIT_SUCCESS;
452
453         rc = ioctl(cd->alr_fd, LUSTRE_ACCESS_LOG_IOCTL_PRESCAN);
454         if (rc < 0) {
455                 ERROR("cannot start scanning: %s\n", strerror(errno));
456                 return ALR_EXIT_FAILURE;
457         }
458
459         return alr_scan(epoll_fd);
460 }
461
462 /* signalfd epoll callback. Handle SIGINT and SIGTERM by breaking from
463  * the epoll loop and exiting normally.*/
464 static int alr_signal_io(int epoll_fd, struct alr_dev *sd, unsigned int mask)
465 {
466         struct signalfd_siginfo ssi;
467         ssize_t rc;
468
469         TRACE("%s\n", __func__);
470         DEBUG_U(mask);
471
472         rc = read(sd->alr_fd, &ssi, sizeof(ssi));
473         if (rc <= 0)
474                 return ALR_OK;
475
476         DEBUG_U(ssi.ssi_signo);
477         switch (ssi.ssi_signo) {
478         case SIGINT:
479         case SIGTERM:
480                 return ALR_EXIT_SUCCESS;
481         default:
482                 return ALR_OK;
483         }
484 }
485
486 /* batching timerfd epoll callback. Print batched access entries to
487  * alr_batch_file. */
488 static int alr_batch_timer_io(int epoll_fd, struct alr_dev *td, unsigned int mask)
489 {
490         time_t now = time(NULL);
491         uint64_t expire_count;
492         ssize_t rc;
493
494         TRACE("%s\n", __func__);
495         DEBUG_D(now);
496         DEBUG_U(mask);
497
498         rc = read(td->alr_fd, &expire_count, sizeof(expire_count));
499         if (rc <= 0)
500                 return ALR_OK;
501
502         DEBUG_U(expire_count);
503
504         rc = alr_batch_print(alr_batch, alr_batch_file, &alr_batch_file_mutex,
505                              alr_print_fraction);
506         if (rc < 0) {
507                 ERROR("cannot write to '%s': %s\n",
508                         alr_batch_file_path, strerror(errno));
509                 goto out;
510         }
511 out:
512         /* Failed writes will leave alr_batch_file (pipe) in a
513          * weird state so make that fatal. */
514         return (rc < 0) ? ALR_EXIT_FAILURE : ALR_OK;
515 }
516
517 /* Call LUSTRE_ACCESS_LOG_IOCTL_INFO to get access log info and print
518  * YAML formatted info to stdout. */
519 static int alr_log_info(struct alr_log *al)
520 {
521         struct lustre_access_log_info_v1 lali;
522         int rc;
523
524         rc = ioctl(al->alr_dev.alr_fd, LUSTRE_ACCESS_LOG_IOCTL_INFO, &lali);
525         if (rc < 0) {
526                 ERROR("cannot get info for device '%s': %s\n",
527                         al->alr_dev.alr_name, strerror(errno));
528                 return -1;
529         }
530
531         printf("- name: %s\n"
532                "  version: %#x\n"
533                "  type: %#x\n"
534                "  log_size: %u\n"
535                "  entry_size: %u\n"
536                "  _head: %u\n"
537                "  _tail: %u\n"
538                "  _entry_space: %u\n"
539                "  _entry_count: %u\n"
540                "  _drop_count: %u\n"
541                "  _is_closed: %u\n",
542                lali.lali_name,
543                lali.lali_version,
544                lali.lali_type,
545                lali.lali_log_size,
546                lali.lali_entry_size,
547                lali._lali_head,
548                lali._lali_tail,
549                lali._lali_entry_space,
550                lali._lali_entry_count,
551                lali._lali_drop_count,
552                lali._lali_is_closed);
553
554         return 0;
555 }
556
557 static struct alr_dev *alr_dev_create(int epoll_fd, int fd, const char *name,
558                         int (*io)(int, struct alr_dev *, unsigned int),
559                         void (*destroy)(struct alr_dev *))
560 {
561         struct alr_dev *alr;
562         int rc;
563
564         alr = calloc(1, sizeof(*alr));
565         if (alr == NULL)
566                 return NULL;
567
568         alr->alr_name = strdup(name);
569         if (alr->alr_name == NULL) {
570                 free(alr);
571                 return NULL;
572         }
573         alr->alr_io = io;
574         alr->alr_destroy = destroy;
575         alr->alr_fd = fd;
576
577         struct epoll_event event = {
578                 .events = EPOLLIN | EPOLLHUP,
579                 .data.ptr = alr,
580         };
581
582         rc = epoll_ctl(epoll_fd, EPOLL_CTL_ADD, alr->alr_fd, &event);
583         if (rc < 0) {
584                 free(alr);
585                 return NULL;
586         }
587
588         return alr;
589 }
590
591 void usage(void)
592 {
593         printf("Usage: %s: [OPTION]...\n"
594 "Discover, read, batch, and write Lustre access logs\n"
595 "\n"
596 "Mandatory arguments to long options are mandatory for short options too.\n"
597 "  -f, --batch-file=FILE          print batch to file (default stdout)\n"
598 "  -i, --batch-interval=INTERVAL  print batch every INTERVAL seconds\n"
599 "  -o, --batch-offset=OFFSET      print batch at OFFSET seconds\n"
600 "  -d, --debug[=FILE]             print debug messages to FILE (stderr)\n"
601 "  -h, --help                     display this help and exit\n"
602 "  -l, --list                     print YAML list of available access logs\n"
603 "  -t, --trace[=FILE]             print trace messages to FILE (stderr)\n",
604                 program_invocation_short_name);
605 }
606
607 int main(int argc, char *argv[])
608 {
609         const char ctl_path[] = "/dev/"LUSTRE_ACCESS_LOG_DIR_NAME"/control";
610         struct alr_dev *alr_signal = NULL;
611         struct alr_dev *alr_batch_timer = NULL;
612         struct alr_dev *alr_ctl = NULL;
613         time_t batch_interval = 0;
614         time_t batch_offset = 0;
615         unsigned int m;
616         int list_info = 0;
617         int epoll_fd = -1;
618         int exit_status;
619         int rc;
620         int c;
621
622         static struct option options[] = {
623                 { .name = "batch-file", .has_arg = required_argument, .val = 'f', },
624                 { .name = "batch-interval", .has_arg = required_argument, .val = 'i', },
625                 { .name = "batch-offset", .has_arg = required_argument, .val = 'o', },
626                 { .name = "debug", .has_arg = optional_argument, .val = 'd', },
627                 { .name = "help", .has_arg = no_argument, .val = 'h', },
628                 { .name = "fraction", .has_arg = required_argument, .val = 'F', },
629                 { .name = "list", .has_arg = no_argument, .val = 'l', },
630                 { .name = "trace", .has_arg = optional_argument, .val = 't', },
631                 { .name = NULL, },
632         };
633
634         while ((c = getopt_long(argc, argv, "d::f:F:hi:lt::", options, NULL)) != -1) {
635                 switch (c) {
636                 case 'f':
637                         alr_batch_file_path = optarg;
638                         break;
639                 case 'i':
640                         errno = 0;
641                         batch_interval = strtoll(optarg, NULL, 0);
642                         if (batch_interval < 0 || batch_interval >= 1048576 ||
643                             errno != 0)
644                                 FATAL("invalid batch interval '%s'\n", optarg);
645                         break;
646                 case 'o':
647                         errno = 0;
648                         batch_offset = strtoll(optarg, NULL, 0);
649                         if (batch_offset < 0 || batch_offset >= 1048576 ||
650                             errno != 0)
651                                 FATAL("invalid batch offset '%s'\n", optarg);
652                         break;
653                 case 'd':
654                         if (optarg == NULL) {
655                                 debug_file = stderr;
656                         } else if (strcmp(optarg, "-") == 0) {
657                                 debug_file = stdout;
658                         } else {
659                                 debug_file = fopen(optarg, "a");
660                                 if (debug_file == NULL)
661                                         FATAL("cannot open debug file '%s': %s\n",
662                                                 optarg, strerror(errno));
663                         }
664
665                         break;
666                 case 'h':
667                         usage();
668                         exit(EXIT_SUCCESS);
669                 case 'F':
670                         alr_print_fraction = strtoll(optarg, NULL, 0);
671                         if (alr_print_fraction < 1 || alr_print_fraction > 100)
672                                 FATAL("invalid batch offset '%s'\n", optarg);
673                         break;
674                 case 'l':
675                         list_info = 1;
676                         break;
677                 case 't':
678                         if (optarg == NULL) {
679                                 trace_file = stderr;
680                         } else if (strcmp(optarg, "-") == 0) {
681                                 trace_file = stdout;
682                         } else {
683                                 trace_file = fopen(optarg, "a");
684                                 if (debug_file == NULL)
685                                         FATAL("cannot open debug file '%s': %s\n",
686                                                 optarg, strerror(errno));
687                         }
688
689                         break;
690                 case '?':
691                         fprintf(stderr, "Try '%s --help' for more information.\n",
692                                 program_invocation_short_name);
693                         exit(EXIT_FAILURE);
694                 }
695         }
696
697         if (batch_interval > 0) {
698                 alr_batch = alr_batch_create(-1);
699                 if (alr_batch == NULL)
700                         FATAL("cannot create batch struct: %s\n",
701                                 strerror(errno));
702         }
703
704         if (alr_batch_file_path != NULL) {
705                 alr_batch_file = fopen(alr_batch_file_path, "w");
706                 if (alr_batch_file == NULL)
707                         FATAL("cannot open batch file '%s': %s\n",
708                                 alr_batch_file_path, strerror(errno));
709         } else {
710                 alr_batch_file_path = "stdout";
711                 alr_batch_file = stdout;
712         }
713
714         epoll_fd = epoll_create1(EPOLL_CLOEXEC);
715         if (epoll_fd < 0)
716                 FATAL("cannot create epoll set: %s\n", strerror(errno));
717
718         /* Setup signal FD and add to epoll set. */
719         sigset_t signal_mask;
720         sigemptyset(&signal_mask);
721         sigaddset(&signal_mask, SIGINT);
722         sigaddset(&signal_mask, SIGTERM);
723         rc = sigprocmask(SIG_BLOCK, &signal_mask, NULL);
724         if (rc < 0)
725                 FATAL("cannot set process signal mask: %s\n", strerror(errno));
726
727         int signal_fd = signalfd(-1, &signal_mask, SFD_NONBLOCK|SFD_CLOEXEC);
728         if (signal_fd < 0)
729                 FATAL("cannot create signalfd: %s\n", strerror(errno));
730
731         alr_signal = alr_dev_create(epoll_fd, signal_fd, "signal", &alr_signal_io, NULL);
732         if (alr_signal == NULL)
733                 FATAL("cannot register signalfd: %s\n", strerror(errno));
734
735         signal_fd = -1;
736
737         /* Setup batch timer FD and add to epoll set. */
738         struct timespec now;
739         rc = clock_gettime(CLOCK_REALTIME, &now);
740         if (rc < 0)
741                 FATAL("cannot read realtime clock: %s\n", strerror(errno));
742
743         int timer_fd = timerfd_create(CLOCK_REALTIME, TFD_NONBLOCK|TFD_CLOEXEC);
744         if (timer_fd < 0)
745                 FATAL("cannot create batch timerfd: %s\n", strerror(errno));
746
747         struct itimerspec it = {
748                 .it_value.tv_sec = (batch_interval > 0) ?
749                                    roundup(now.tv_sec, batch_interval) +
750                                    (batch_offset % batch_interval) :
751                                    0,
752                 .it_interval.tv_sec = batch_interval,
753         };
754
755         DEBUG_D(it.it_value.tv_sec);
756
757         rc = timerfd_settime(timer_fd, TFD_TIMER_ABSTIME, &it, NULL);
758         if (rc < 0)
759                 FATAL("cannot arm timerfd: %s\n", strerror(errno));
760
761         alr_batch_timer = alr_dev_create(epoll_fd, timer_fd, "batch_timer",
762                                         &alr_batch_timer_io, NULL);
763         if (alr_batch_timer == NULL)
764                 FATAL("cannot register batch timerfd: %s\n", strerror(errno));
765
766         timer_fd = -1;
767
768         /* Open control device. */
769         int ctl_fd = open(ctl_path, O_RDONLY|O_NONBLOCK|O_CLOEXEC);
770         if (ctl_fd < 0)
771                 FATAL("cannot open '%s': %s\n", ctl_path, strerror(errno));
772
773         /* Get and print interface version. */
774         oal_version = ioctl(ctl_fd, LUSTRE_ACCESS_LOG_IOCTL_VERSION);
775         if (oal_version < 0)
776                 FATAL("cannot get ofd access log interface version: %s\n", strerror(errno));
777
778         DEBUG_D(oal_version);
779
780         /* Get and print device major used for access log devices. */
781         oal_log_major = ioctl(ctl_fd, LUSTRE_ACCESS_LOG_IOCTL_MAJOR);
782         if (oal_log_major < 0)
783                 FATAL("cannot get ofd access log major: %s\n", strerror(errno));
784
785         DEBUG_D(oal_log_major);
786
787         /* Add control device to epoll set. */
788         alr_ctl = alr_dev_create(epoll_fd, ctl_fd, "control", &alr_ctl_io, NULL);
789         if (alr_ctl == NULL)
790                 FATAL("cannot register control device: %s\n", strerror(errno));
791
792         ctl_fd = -1;
793
794         do {
795                 struct epoll_event ev[32];
796                 int timeout = (list_info ? 0 : -1);
797                 int i, ev_count;
798
799                 ev_count = epoll_wait(epoll_fd, ev, ARRAY_SIZE(ev), timeout);
800                 if (ev_count < 0) {
801                         if (errno == EINTR) /* Signal or timeout. */
802                                 continue;
803
804                         ERROR("cannot wait on epoll set: %s\n", strerror(errno));
805                         exit_status = EXIT_FAILURE;
806                         goto out;
807                 }
808
809                 DEBUG_D(ev_count);
810
811                 for (i = 0; i < ev_count; i++) {
812                         struct alr_dev *ad = ev[i].data.ptr;
813                         unsigned int mask = ev[i].events;
814
815                         rc = (*ad->alr_io)(epoll_fd, ad, mask);
816                         switch (rc) {
817                         case ALR_EXIT_FAILURE:
818                                 exit_status = EXIT_FAILURE;
819                                 goto out;
820                         case ALR_EXIT_SUCCESS:
821                                 exit_status = EXIT_SUCCESS;
822                                 goto out;
823                         case ALR_ERROR:
824                         case ALR_EOF:
825                                 alr_dev_free(epoll_fd, ad);
826                                 break;
827                         case ALR_OK:
828                         default:
829                                 break;
830                         }
831                 }
832         } while (!list_info);
833
834         exit_status = EXIT_SUCCESS;
835 out:
836         assert(oal_log_minor_max < ARRAY_SIZE(alr_log));
837
838         for (m = 0; m <= oal_log_minor_max; m++) {
839                 if (alr_log[m] == NULL)
840                         continue;
841
842                 if (list_info) {
843                         rc = alr_log_info(alr_log[m]);
844                         if (rc < 0)
845                                 exit_status = EXIT_FAILURE;
846                 }
847
848                 alr_dev_free(epoll_fd, &alr_log[m]->alr_dev);
849         }
850
851         alr_dev_free(epoll_fd, alr_ctl);
852         alr_dev_free(epoll_fd, alr_signal);
853         alr_dev_free(epoll_fd, alr_batch_timer);
854         close(epoll_fd);
855
856         alr_batch_destroy(alr_batch);
857
858         DEBUG_D(exit_status);
859
860         return exit_status;
861 }