… | |
… | |
153 | our ($COLLECT, $FILTER, $LOG); |
153 | our ($COLLECT, $FILTER, $LOG); |
154 | |
154 | |
155 | our ($now_int, $now_str1, $now_str2); |
155 | our ($now_int, $now_str1, $now_str2); |
156 | |
156 | |
157 | # Format Time, not public - yet? |
157 | # Format Time, not public - yet? |
158 | sub ft($) { |
158 | sub format_time($) { |
159 | my $i = int $_[0]; |
159 | my $i = int $_[0]; |
160 | my $f = sprintf "%06d", 1e6 * ($_[0] - $i); |
160 | my $f = sprintf "%06d", 1e6 * ($_[0] - $i); |
161 | |
161 | |
162 | ($now_int, $now_str1, $now_str2) = ($i, split /\x01/, POSIX::strftime "%Y-%m-%d %H:%M:%S.\x01 %z", localtime $i) |
162 | ($now_int, $now_str1, $now_str2) = ($i, split /\x01/, POSIX::strftime "%Y-%m-%d %H:%M:%S.\x01 %z", localtime $i) |
163 | if $now_int != $i; |
163 | if $now_int != $i; |
… | |
… | |
262 | }; |
262 | }; |
263 | |
263 | |
264 | our @LEVEL2STR = qw(0 fatal alert crit error warn note info debug trace); |
264 | our @LEVEL2STR = qw(0 fatal alert crit error warn note info debug trace); |
265 | |
265 | |
266 | # time, ctx, level, msg |
266 | # time, ctx, level, msg |
267 | sub _format($$$$) { |
267 | sub default_format($$$$) { |
268 | my $ts = ft $_[0]; |
268 | my $ts = format_time $_[0]; |
269 | my $ct = " "; |
269 | my $ct = " "; |
270 | |
270 | |
271 | my @res; |
271 | my @res; |
272 | |
272 | |
273 | for (split /\n/, sprintf "%-5s %s: %s", $LEVEL2STR[$_[2]], $_[1][0], $_[3]) { |
273 | for (split /\n/, sprintf "%-5s %s: %s", $LEVEL2STR[$_[2]], $_[1][0], $_[3]) { |
… | |
… | |
322 | }; |
322 | }; |
323 | |
323 | |
324 | # format msg |
324 | # format msg |
325 | my $str = $ctx->[4] |
325 | my $str = $ctx->[4] |
326 | ? $ctx->[4]($now, $_[0], $level, $format) |
326 | ? $ctx->[4]($now, $_[0], $level, $format) |
327 | : ($fmt[$level] ||= _format $now, $_[0], $level, $format); |
327 | : ($fmt[$level] ||= default_format $now, $_[0], $level, $format); |
328 | |
328 | |
329 | $success = 1; |
329 | $success = 1; |
330 | |
330 | |
331 | $ctx->[3]($str) |
331 | $ctx->[3]($str) |
332 | or push @ctx, values %{ $ctx->[2] }; # not consumed - propagate |
332 | or push @ctx, values %{ $ctx->[2] }; # not consumed - propagate |
… | |
… | |
454 | Since C<AnyEvent::Log> has to work even before the L<AnyEvent> has been |
454 | Since C<AnyEvent::Log> has to work even before the L<AnyEvent> has been |
455 | initialised, this switch will also decide whether to use C<CORE::time> or |
455 | initialised, this switch will also decide whether to use C<CORE::time> or |
456 | C<Time::HiRes::time> when logging a message before L<AnyEvent> becomes |
456 | C<Time::HiRes::time> when logging a message before L<AnyEvent> becomes |
457 | available. |
457 | available. |
458 | |
458 | |
|
|
459 | =item AnyEvent::Log::format_time $timestamp |
|
|
460 | |
|
|
461 | Formats a timestamp as returned by C<< AnyEvent->now >> or C<< |
|
|
462 | AnyEvent->time >> or many other functions in the same way as |
|
|
463 | C<AnyEvent::Log> does. |
|
|
464 | |
|
|
465 | In your main program (as opposed to in your module) you can override |
|
|
466 | the default timestamp display format by loading this module and then |
|
|
467 | redefining this function. |
|
|
468 | |
|
|
469 | Most commonly, this function can be used in formatting callbacks. |
|
|
470 | |
|
|
471 | =item AnyEvent::Log::default_format $time, $ctx, $level, $msg |
|
|
472 | |
|
|
473 | Format a log message using the given timestamp, logging context, log level |
|
|
474 | and log message. |
|
|
475 | |
|
|
476 | This is the formatting function used to format messages when no custom |
|
|
477 | function is provided. |
|
|
478 | |
|
|
479 | In your main program (as opposed to in your module) you can override the |
|
|
480 | default message format by loading this module and then redefining this |
|
|
481 | function. |
|
|
482 | |
|
|
483 | =item AnyEvent::Log::fatal_exit |
|
|
484 | |
|
|
485 | This is the function that is called after logging a C<fatal> log |
|
|
486 | message. It must not return. |
|
|
487 | |
|
|
488 | The default implementation simpl calls C<exit 1>. |
|
|
489 | |
|
|
490 | In your main program (as opposed to in your module) you can override |
|
|
491 | the fatal exit function by loading this module and then redefining this |
|
|
492 | function. Make sure you don't return. |
|
|
493 | |
459 | =back |
494 | =back |
460 | |
495 | |
461 | =head1 LOGGING CONTEXTS |
496 | =head1 LOGGING CONTEXTS |
462 | |
497 | |
463 | This module associates every log message with a so-called I<logging |
498 | This module associates every log message with a so-called I<logging |
… | |
… | |
907 | reference that just stores the values. |
942 | reference that just stores the values. |
908 | |
943 | |
909 | If, for some reason, you want to use C<caller> to find out more about the |
944 | If, for some reason, you want to use C<caller> to find out more about the |
910 | logger then you should walk up the call stack until you are no longer |
945 | logger then you should walk up the call stack until you are no longer |
911 | inside the C<AnyEvent::Log> package. |
946 | inside the C<AnyEvent::Log> package. |
|
|
947 | |
|
|
948 | To implement your own logging callback, you might find the |
|
|
949 | C<AnyEvent::Log::format_time> and C<AnyEvent::Log::default_format> |
|
|
950 | functions useful. |
|
|
951 | |
|
|
952 | Example: format the message just as AnyEvent::Log would, by letting |
|
|
953 | AnyEvent::Log do the work. This is a good basis to design a formatting |
|
|
954 | callback that only changes minor aspects of the formatting. |
|
|
955 | |
|
|
956 | $ctx->fmt_cb (sub { |
|
|
957 | my ($time, $ctx, $lvl, $msg) = @_; |
|
|
958 | |
|
|
959 | AnyEvent::Log::default_format $time, $ctx, $lvl, $msg |
|
|
960 | }); |
912 | |
961 | |
913 | Example: format just the raw message, with numeric log level in angle |
962 | Example: format just the raw message, with numeric log level in angle |
914 | brackets. |
963 | brackets. |
915 | |
964 | |
916 | $ctx->fmt_cb (sub { |
965 | $ctx->fmt_cb (sub { |