X-Git-Url: http://git.shadowcat.co.uk/gitweb/gitweb.cgi?p=catagits%2FCatalyst-Runtime.git;a=blobdiff_plain;f=lib%2FCatalyst.pm;h=9935d18ff49e9d677c50ee8eab74c2326ed4b9e3;hp=b04806c38e0d6d38c16fe297ca0cae1edebba17e;hb=2666dd3ba45edb0fa31508f67d94fe80072f94f6;hpb=60dd6e1dd01a67d12445740b913e1ac2c711fe76 diff --git a/lib/Catalyst.pm b/lib/Catalyst.pm index b04806c..9935d18 100644 --- a/lib/Catalyst.pm +++ b/lib/Catalyst.pm @@ -17,6 +17,8 @@ use Path::Class; use Time::HiRes qw/gettimeofday tv_interval/; use URI; use Scalar::Util qw/weaken/; +use Tree::Simple qw/use_weak_refs/; +use Tree::Simple::Visitor::FindByUID; use attributes; __PACKAGE__->mk_accessors( @@ -890,14 +892,8 @@ sub execute { $class = $c->components->{$class} || $class; $c->state(0); - my $callsub = - ( caller(0) )[0]->isa('Catalyst::Action') - ? ( caller(2) )[3] - : ( caller(1) )[3]; - - my $action = ''; if ( $c->debug ) { - $action = "$code"; + my $action = "$code"; $action = "/$action" unless $action =~ /\-\>/; $c->counter->{"$code"}++; @@ -908,9 +904,51 @@ sub execute { $c->state(0); return $c->state; } + + # determine if the call was the result of a forward + my $callsub_index = ( caller(0) )[0]->isa('Catalyst::Action') ? 2 : 1; + if ( ( caller($callsub_index) )[3] =~ /^NEXT/ ) { + # work around NEXT if execute was extended by a plugin + $callsub_index += 3; + } + my $callsub = ( caller($callsub_index) )[3]; $action = "-> $action" if $callsub =~ /forward$/; + + my $node = Tree::Simple->new( { + action => $action, + elapsed => undef, # to be filled in later + } ); + $node->setUID( "$code" . $c->counter->{"$code"} ); + + unless ( ( $code->name =~ /^_.*/ ) + && ( !$c->config->{show_internal_actions} ) ) + { + # is this a root-level call or a forwarded call? + if ( $callsub =~ /forward$/ ) { + + # forward, locate the caller + if ( my $parent = $c->stack->[-1] ) { + my $visitor = Tree::Simple::Visitor::FindByUID->new; + $visitor->searchForUID( + "$parent" . $c->counter->{"$parent"} ); + $c->{stats}->accept( $visitor ); + if ( my $result = $visitor->getResult ) { + $result->addChild( $node ); + } + } + else { + # forward with no caller may come from a plugin + $c->{stats}->addChild( $node ); + } + } + else { + # root-level call + $c->{stats}->addChild( $node ); + } + } } + push( @{ $c->stack }, $code ); my $elapsed = 0; my $start = 0; @@ -922,14 +960,27 @@ sub execute { unless ( ( $code->name =~ /^_.*/ ) && ( !$c->config->{show_internal_actions} ) ) { - push @{ $c->{stats} }, [ $action, sprintf( '%fs', $elapsed ) ]; + # FindByUID uses an internal die, so we save the existing error + my $error = $@; + + # locate the node in the tree and update the elapsed time + my $visitor = Tree::Simple::Visitor::FindByUID->new; + $visitor->searchForUID( "$code" . $c->counter->{"$code"} ); + $c->{stats}->accept( $visitor ); + if ( my $result = $visitor->getResult ) { + my $value = $result->getNodeValue; + $value->{elapsed} = sprintf( '%fs', $elapsed ); + $result->setNodeValue( $value ); + } + + # restore error + $@ = $error || undef; } } my $last = ${ $c->stack }[-1]; pop( @{ $c->stack } ); if ( my $error = $@ ) { - if ( $error eq $DETACH ) { die $DETACH if $c->depth > 1 } else { unless ( ref $error ) { @@ -1099,11 +1150,11 @@ sub handle_request { # Always expect worst case! my $status = -1; eval { - my @stats = (); + my $stats = ( $class->debug ) ? Tree::Simple->new : q{}; my $handler = sub { my $c = $class->prepare(@arguments); - $c->{stats} = \@stats; + $c->{stats} = $stats; $c->dispatch; return $c->finalize; }; @@ -1116,8 +1167,16 @@ sub handle_request { my $av = sprintf '%.3f', ( $elapsed == 0 ? '??' : ( 1 / $elapsed ) ); my $t = Text::SimpleTable->new( [ 64, 'Action' ], [ 9, 'Time' ] ); - - for my $stat (@stats) { $t->row( $stat->[0], $stat->[1] ) } + + $stats->traverse( sub { + my $action = shift; + my $stat = $action->getNodeValue; + $t->row( + ( q{ } x $action->getDepth ) . $stat->{action}, + $stat->{elapsed} || '??' + ); + } ); + $class->log->info( "Request took ${elapsed}s ($av/s)\n" . $t->draw ); }