package CHI::Stats; BEGIN { $CHI::Stats::VERSION = '0.50'; } use Log::Any qw($log); use Moose; use strict; use warnings; has 'chi_root_class' => ( is => 'ro' ); has 'data' => ( is => 'ro', default => sub { {} } ); has 'enabled' => ( is => 'ro', default => 0 ); has 'start_time' => ( is => 'ro', default => sub { time } ); __PACKAGE__->meta->make_immutable(); sub enable { $_[0]->{enabled} = 1 } sub disable { $_[0]->{enabled} = 0 } sub flush { my ($self) = @_; my $data = $self->data; foreach my $label ( sort keys %$data ) { my $label_stats = $data->{$label}; foreach my $namespace ( sort keys(%$label_stats) ) { my $namespace_stats = $label_stats->{$namespace}; if (%$namespace_stats) { $self->log_namespace_stats( $label, $namespace, $namespace_stats ); } } } $self->clear(); } sub log_namespace_stats { my ( $self, $label, $namespace, $namespace_stats ) = @_; my $fields_string = join( "; ", map { join( "=", $_, $namespace_stats->{$_} ) } grep { $_ ne 'start_time' } sort keys(%$namespace_stats) ); if ($fields_string) { my $start_time = $namespace_stats->{start_time}; my $end_time = time; $log->infof( '%s stats: namespace=\'%s\'; cache=\'%s\'; start=%s; end=%s; %s', $self->chi_root_class, $namespace, $label, $self->format_time($start_time), $self->format_time($end_time), $fields_string ); } } sub format_time { my ($time) = @_; my ( $sec, $min, $hour, $mday, $mon, $year, $wday, $yday, $isdst ) = localtime(); return sprintf( "%04d%02d%02d:%02d:%02d:%02d", $year + 1900, $mon + 1, $mday, $hour, $min, $sec ); } sub stats_for_driver { my ( $self, $cache ) = @_; my $stats = ( $self->data->{ $cache->label }->{ $cache->namespace } ||= {} ); return $stats; } sub parse_stats_logs { my $self = shift; my ( %results_hash, @results ); foreach my $log (@_) { my $logfh; if ( ref($log) ) { $logfh = $log; } else { open( $logfh, '<', $log ) or die "cannot open $log: $!"; } while ( my $line = <$logfh> ) { chomp($line); if ( my ( $root_class, $namespace, $label, $start, $end, $rest ) = ( $line =~ /(.*) stats: namespace='(.*)'; cache='(.*)'; start=([^;]+); end=([^;]+); (.*)/ ) ) { my $results_set = ( $results_hash{$root_class}->{$label}->{$namespace} ||= {} ); if ( !%$results_set ) { $results_set->{root_class} = $root_class; $results_set->{namespace} = $namespace; $results_set->{cache} = $label; push( @results, $results_set ); } my @pairs = split( '; ', $rest ); foreach my $pair (@pairs) { my ( $key, $value ) = split( /=/, $pair ); $results_set->{$key} += $value; } } } } return \@results; } sub clear { my ($self) = @_; my $data = $self->data; foreach my $key ( keys %{$data} ) { %{ $data->{$key} } = (); } $self->{start_time} = time; } 1; =pod =head1 NAME CHI::Stats - Record and report per-namespace cache statistics =head1 VERSION version 0.50 =head1 SYNOPSIS # Turn on statistics collection CHI->stats->enable(); # Perform cache operations # Flush statistics to logs CHI->stats->flush(); ... # Parse logged statistics my $results = CHI->stats->parse_stats_logs($file1, ...); =head1 DESCRIPTION CHI can record statistics, such as number of hits, misses and sets, on a per-namespace basis and log the results to your L logger. You can then parse the logs to get a combined summary. A single CHI::Stats object is maintained for each CHI root class, and tallies statistics over any number of CHI::Driver objects. Statistics are reported when you call the L method. You can choose to this once at process end, or on a periodic basis. =head1 STATISTICS The following statistics are tracked: =over =item * absent_misses - Number of gets that failed due to item not being in the cache =item * expired_misses - Number of gets that failed due to item expiring =item * get_errors - Number of caught runtime errors during gets =item * hits - Number of gets that succeeded =item * set_key_size - Number of bytes in set keys (divide by number of sets to get average) =item * set_value_size - Number of bytes in set values (divide by number of sets to get average) =item * sets - Number of sets =item * set_errors - Number of caught runtime errors during sets =back =head1 METHODS =over =item enable =item disable =item enabled Enable, disable, and query the current enabled status. When stats are enabled, each new cache object will collect statistics. Enabling and disabling does not affect existing cache objects. e.g. my $cache1 = CHI->new(...); CHI->stats->enable(); # $cache1 will not collect statistics my $cache2 = CHI->new(...); CHI->stats->disable(); # $cache2 will continue to collect statistics =item flush Log all statistics to L (at Info level in the CHI::Stats category), then clear statistics from memory. There is one log message per cache label and namespace, looking like: CHI stats: namespace='Foo'; cache='File'; start=20090102:12:53:05; end=20090102:12:58:05; absent_misses=10; expired_misses=20; hits=50; set_key_size=6; set_value_size=20; sets=30 =item parse_stats_logs (log1, log2, ...) Parses logs output by CHI::Stats and returns a listref of stats totals by root class, cache label, and namespace. e.g. [ {root_class => 'CHI', cache =>'File', namespace => 'Foo', absent_misses => 100, expired_misses => 200, ... }, {root_class => 'CHI', cache =>'File', namespace => 'Bar', ... }, ] Lines with the root class, cache label, and namespace are summed together. Non-stats lines are ignored. Each parameter to this method may be a filename or a reference to an open filehandle. =back =head1 SEE ALSO L =head1 AUTHOR Jonathan Swartz =head1 COPYRIGHT AND LICENSE This software is copyright (c) 2011 by Jonathan Swartz. This is free software; you can redistribute it and/or modify it under the same terms as the Perl 5 programming language system itself. =cut __END__