2 This file is part of GNUnet.
3 Copyright (C) 2011 GNUnet e.V.
5 GNUnet is free software: you can redistribute it and/or modify it
6 under the terms of the GNU General Public License as published
7 by the Free Software Foundation, either version 3 of the License,
8 or (at your option) any later version.
10 GNUnet 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 Affero General Public License for more details.
17 * @file util/test_common_logging_runtime_loglevels.c
18 * @brief testcase for the logging module (runtime log level adjustment)
22 #include "gnunet_util_lib.h"
24 #define VERBOSE GNUNET_NO
27 * How much time the child is allowed to waste on skipped log calls, at most.
28 * Raspberry Pi takes 113 microseconds tops, this is 3x that value.
30 #define MAX_SKIP_DELAY GNUNET_TIME_relative_multiply (GNUNET_TIME_UNIT_MICROSECONDS, 400).rel_value_us
33 * How much time non-skipped log call should take, at least.
34 * Keep in sync with the value in the dummy!
36 #define OUTPUT_DELAY GNUNET_TIME_relative_multiply (GNUNET_TIME_UNIT_MICROSECONDS, 1000).rel_value_us
42 static struct GNUNET_OS_Process *proc;
44 /* Pipe to read from started processes stdout (on read end) */
45 static struct GNUNET_DISK_PipeHandle *pipe_stdout;
47 static struct GNUNET_SCHEDULER_Task * die_task;
49 static struct GNUNET_SCHEDULER_Task * read_task;
58 GNUNET_log (GNUNET_ERROR_TYPE_DEBUG, "Ending phase %d, ok is %d\n", phase,
62 if (0 != GNUNET_OS_process_kill (proc, GNUNET_TERM_SIG))
64 GNUNET_log_strerror (GNUNET_ERROR_TYPE_WARNING, "kill");
66 GNUNET_OS_process_wait (proc);
67 GNUNET_OS_process_destroy (proc);
70 if (NULL != read_task)
72 GNUNET_SCHEDULER_cancel (read_task);
75 GNUNET_DISK_pipe_close (pipe_stdout);
87 GNUNET_log (GNUNET_ERROR_TYPE_ERROR, "failing\n");
92 read_output_line (int phase_from1, int phase_to1, int phase_from2,
93 int phase_to2, char c, const char *expect_level,
94 long delay_morethan, long delay_lessthan, int phase,
96 int *len, long *delay, char level[8])
103 int delay_is_a_dummy;
104 int delay_outside_of_range;
109 if (!(phase >= phase_from1 && phase <= phase_to1) &&
110 !(phase >= phase_from2 && phase <= phase_to2))
113 GNUNET_log (GNUNET_ERROR_TYPE_DEBUG,
114 "Trying to match '%c%s \\d\\r\\n' on %s\n", c, expect_level, p);
116 for (i = 0; i < *len && !stop; i++)
120 case 0: /* read first char */
123 GNUNET_log (GNUNET_ERROR_TYPE_ERROR, "Expected '%c', but got '%c'\n", c,
130 case 1: /* read at most 7 char-long error level string, finished by ' ' */
146 case 2: /* read the delay, finished by '\n' */
149 if (r[i] == '\r' && r[i + 1] == '\n')
153 *delay = strtol (t, NULL, 10);
160 *delay = strtol (t, NULL, 10);
167 level_matches = (strcmp (expect_level, level) == 0);
168 delay_is_sane = (*delay >= 0) && (*delay <= 1000000);
169 delay_is_a_dummy = (c == 'L');
170 /* Delay must be either less than 'lessthan' (log call is skipped)
171 * or more than 'morethan' (log call is not skipped)
173 delay_outside_of_range = ((*delay < delay_lessthan) || (*delay >= delay_morethan));
174 if (delay_is_a_dummy)
175 delay_outside_of_range = 1;
178 GNUNET_log (GNUNET_ERROR_TYPE_ERROR,
179 "Wrong log format?\n");
181 GNUNET_log (GNUNET_ERROR_TYPE_ERROR,
182 "Wrong log level\n");
184 GNUNET_log (GNUNET_ERROR_TYPE_ERROR,
185 "Delay %ld is insane\n",
187 if (! delay_outside_of_range)
188 GNUNET_log (GNUNET_ERROR_TYPE_ERROR,
189 "Delay %ld is not outside of range (%ld ; %ld)\n",
193 if (!stop || !level_matches || !delay_is_sane || !delay_outside_of_range)
200 * Up to 8 non-skipped GNUNET_log() calls
201 * + extra line with delay for each one
203 #define LOG_MAX_NUM_LINES (8 * 2)
205 * Actual message is 17 chars at most
207 #define LOG_MAX_LINE_LENGTH (17)
209 #define LOG_BUFFER_SIZE LOG_MAX_NUM_LINES * LOG_MAX_LINE_LENGTH
211 static char buf[LOG_BUFFER_SIZE];
213 static char *buf_ptr;
219 read_call (void *cls)
221 const struct GNUNET_DISK_FileHandle *stdout_read_handle = cls;
228 rd = GNUNET_DISK_file_read (stdout_read_handle, buf_ptr,
229 sizeof (buf) - bytes);
235 FPRINTF (stderr, "got %d bytes, reading more\n", rd);
237 read_task = GNUNET_SCHEDULER_add_read_file (GNUNET_TIME_UNIT_FOREVER_REL,
240 (void*) stdout_read_handle);
245 FPRINTF (stderr, "bytes is %d:%s\n", bytes, buf);
248 /* +------CHILD OUTPUT--
259 * | 7D * * * * * * * *
265 if (bytes == LOG_BUFFER_SIZE ||
267 read_output_line (0, 3, 4, 9, 'L', "ERROR", -1,
269 &bytes, &delay, level)) ||
271 read_output_line (0, 3, 4, 9, '1', "ERROR", OUTPUT_DELAY,
272 MAX_SKIP_DELAY, phase, p,
273 &bytes, &delays[0], level)) ||
275 read_output_line (1, 3, 5, 9, 'L', "WARNING", -1,
277 &bytes, &delay, level)) ||
279 read_output_line (0, 3, 4, 9, '1', "WARNING", OUTPUT_DELAY,
280 MAX_SKIP_DELAY, phase, p,
281 &bytes, &delays[1], level)) ||
283 read_output_line (2, 3, 6, 7, 'L', "INFO", -1,
285 &bytes, &delay, level)) ||
287 read_output_line (0, 3, 4, 9, '1', "INFO", OUTPUT_DELAY,
288 MAX_SKIP_DELAY, phase, p,
289 &bytes, &delays[2], level)) ||
291 read_output_line (3, 3, 7, 7, 'L', "DEBUG", -1,
293 &bytes, &delay, level)) ||
295 read_output_line (0, 3, 4, 9, '1', "DEBUG", OUTPUT_DELAY,
296 MAX_SKIP_DELAY, phase, p,
297 &bytes, &delays[3], level)) ||
299 read_output_line (0, 3, 4, 9, 'L', "ERROR", -1,
301 &bytes, &delay, level)) ||
303 read_output_line (0, 3, 4, 9, '2', "ERROR", OUTPUT_DELAY,
304 MAX_SKIP_DELAY, phase, p,
305 &bytes, &delays[4], level)) ||
307 read_output_line (0, 3, 5, 9, 'L', "WARNING", -1,
309 &bytes, &delay, level)) ||
311 read_output_line (0, 3, 4, 9, '2', "WARNING", OUTPUT_DELAY,
312 MAX_SKIP_DELAY, phase, p,
313 &bytes, &delays[5], level)) ||
315 read_output_line (-1, -1, 6, 7, 'L', "INFO", -1,
317 &bytes, &delay, level)) ||
319 read_output_line (0, 3, 4, 9, '2', "INFO", OUTPUT_DELAY,
320 MAX_SKIP_DELAY, phase, p,
321 &bytes, &delays[6], level)) ||
323 read_output_line (-1, -1, 7, 7, 'L', "DEBUG", -1,
325 &bytes, &delay, level)) ||
327 read_output_line (0, 3, 4, 9, '2', "DEBUG", OUTPUT_DELAY,
328 MAX_SKIP_DELAY, phase, p,
329 &bytes, &delays[7], level)))
331 if (bytes == LOG_BUFFER_SIZE)
332 FPRINTF (stderr, "%s", "Ran out of buffer space!\n");
335 GNUNET_SCHEDULER_cancel (die_task);
336 GNUNET_SCHEDULER_add_now (&end_task, NULL);
340 GNUNET_SCHEDULER_cancel (die_task);
341 GNUNET_SCHEDULER_add_now (&end_task, NULL);
348 const struct GNUNET_DISK_FileHandle *stdout_read_handle;
350 pipe_stdout = GNUNET_DISK_pipe (GNUNET_YES, GNUNET_YES, GNUNET_NO, GNUNET_YES);
352 if (pipe_stdout == NULL)
359 putenv ("GNUNET_LOG=");
360 putenv ("GNUNET_FORCE_LOG=");
361 putenv ("GNUNET_FORCE_LOGFILE=");
365 putenv ("GNUNET_LOG=;;;;ERROR");
368 putenv ("GNUNET_LOG=;;;;WARNING");
371 putenv ("GNUNET_LOG=;;;;INFO");
374 putenv ("GNUNET_LOG=;;;;DEBUG");
377 putenv ("GNUNET_FORCE_LOG=;;;;ERROR");
380 putenv ("GNUNET_FORCE_LOG=;;;;WARNING");
383 putenv ("GNUNET_FORCE_LOG=;;;;INFO");
386 putenv ("GNUNET_FORCE_LOG=;;;;DEBUG");
389 putenv ("GNUNET_LOG=blah;;;;ERROR");
392 putenv ("GNUNET_FORCE_LOG=blah;;;;ERROR");
396 proc = GNUNET_OS_start_process (GNUNET_NO, GNUNET_OS_INHERIT_STD_OUT_AND_ERR,
397 NULL, pipe_stdout, NULL,
399 "test_common_logging_dummy",
401 "./test_common_logging_dummy",
403 "test_common_logging_dummy", NULL);
404 GNUNET_assert (NULL != proc);
405 putenv ("GNUNET_FORCE_LOG=");
406 putenv ("GNUNET_LOG=");
408 /* Close the write end of the read pipe */
409 GNUNET_DISK_pipe_close_end (pipe_stdout, GNUNET_DISK_PIPE_END_WRITE);
412 GNUNET_DISK_pipe_handle (pipe_stdout, GNUNET_DISK_PIPE_END_READ);
415 GNUNET_SCHEDULER_add_delayed (GNUNET_TIME_relative_multiply
416 (GNUNET_TIME_UNIT_SECONDS, 10),
422 memset (&buf, 0, sizeof (buf));
424 read_task = GNUNET_SCHEDULER_add_read_file (GNUNET_TIME_UNIT_FOREVER_REL,
427 (void*) stdout_read_handle);
440 main (int argc, char *argv[])
442 GNUNET_log_setup ("test-common-logging-runtime-loglevels",
446 GNUNET_SCHEDULER_run (&task, &ok);
450 /* end of test_common_logging_runtime_loglevels.c */