… | |
… | |
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 { |
… | |
… | |
1016 | my $write = sub { |
1065 | my $write = sub { |
1017 | # we write as many messages as have been queued |
1066 | # we write as many messages as have been queued |
1018 | my $data = join "", @queue; |
1067 | my $data = join "", @queue; |
1019 | @queue = (); |
1068 | @queue = (); |
1020 | |
1069 | |
1021 | AnyEvent::IO::ae_write $fh, $data, sub { |
1070 | AnyEvent::IO::aio_write $fh, $data, sub { |
1022 | $disable = 1; |
1071 | $disable = 1; |
1023 | @_ |
1072 | @_ |
1024 | ? ($_[0] == length $data or AE::log 4 => "unable to write to logfile '$path': short write") |
1073 | ? ($_[0] == length $data or AE::log 4 => "unable to write to logfile '$path': short write") |
1025 | : AE::log 4 => "unable to write to logfile '$path': $!"; |
1074 | : AE::log 4 => "unable to write to logfile '$path': $!"; |
1026 | undef $disable; |
1075 | undef $disable; |
1027 | |
1076 | |
1028 | if ($keepopen) { |
1077 | if ($keepopen) { |
1029 | $kick->($kick); |
1078 | $kick->($kick); |
1030 | } else { |
1079 | } else { |
1031 | AnyEvent::IO::ae_close ($fh, sub { |
1080 | AnyEvent::IO::aio_close ($fh, sub { |
1032 | undef $fh; |
1081 | undef $fh; |
1033 | $kick->($kick); |
1082 | $kick->($kick); |
1034 | }); |
1083 | }); |
1035 | } |
1084 | } |
1036 | }; |
1085 | }; |
1037 | }; |
1086 | }; |
1038 | |
1087 | |
1039 | if ($fh) { |
1088 | if ($fh) { |
1040 | $write->(); |
1089 | $write->(); |
1041 | } else { |
1090 | } else { |
1042 | AnyEvent::IO::ae_open |
1091 | AnyEvent::IO::aio_open |
1043 | $path, |
1092 | $path, |
1044 | AnyEvent::IO::O_CREAT | AnyEvent::IO::O_WRONLY | AnyEvent::IO::O_APPEND, |
1093 | AnyEvent::IO::O_CREAT | AnyEvent::IO::O_WRONLY | AnyEvent::IO::O_APPEND, |
1045 | 0666, |
1094 | 0666, |
1046 | sub { |
1095 | sub { |
1047 | $fh = shift |
1096 | $fh = shift |
… | |
… | |
1450 | =back |
1499 | =back |
1451 | |
1500 | |
1452 | =head1 AUTHOR |
1501 | =head1 AUTHOR |
1453 | |
1502 | |
1454 | Marc Lehmann <schmorp@schmorp.de> |
1503 | Marc Lehmann <schmorp@schmorp.de> |
1455 | http://home.schmorp.de/ |
1504 | http://anyevent.schmorp.de |
1456 | |
1505 | |
1457 | =cut |
1506 | =cut |
1458 | |
1507 | |
1459 | 1 |
1508 | 1 |
1460 | |
1509 | |