' or "" - for "Immediate invocation" * $count = trace action count ( it may get reset ) * $action = action name - extracted from cf=action name * where cf stands for "current filter" * If there is no current filter the value is * $data[6] = function: 'apply_filters' or 'do_action' * $data[7] = filter/action: the 'immediate' filter or action being applied 2012-03-27 11:00:37 0.49518200 608 cf=shutdown The version 1.17 code now includes "immediate" actions which are created by bw_lazy_trace_action_immediate(), which is called by manually added bw_trace_action_immediate() calls in wp-includes/plugins.php Sample output: 2012-10-04 14:10:20 0.03808400 914 cf=option_blog_charset 2012-10-04 14:10:20 0.04394300 I> 916 cf! apply_filters option_blog_charset 2012-10-04 14:10:20 0.04776700 The filter has ended normally **?** - but our interceptor wasn't there I> end of our action filter >E E> end of another action filter etc... The code to analyze the performance is not yet complete. But it's good enough to enable preliminary performance investigations. Perhaps we could keep a count of the number of SQL queries that have been performed to date! */ function bw_handle_action( $data ) { global $actions; $ts = $data[0] . $data[1]; $msec = $data[2]; $storend = $data[3]; $count = $data[4]; $action = substr( $data[5], 3 ) ; if ( $storend == "" ) { bw_action_end_time( $action, $ts, $msec ); } else { $action = $data[7]; if ( !$action ) { bw_backtrace(); bw_trace2(); //gobang(); } else { bw_array_inc( $actions, $action ); bw_action_start_time( $action, $ts, $msec ); bw_action_end_time( $action, $ts, $msec ); } } } function bw_format_action_row( $action, $count, $elapsed, $average ) { $elapsed = sprintf( "%.6f", $elapsed ); $average = sprintf( "%.6f", $average ); $tr = array( $action, $count, $elapsed, $average ); bw_tablerow( $tr ); //echo $action; } function bw_format_action_csv( $action, $count, $elapsed, $average ) { $elapsed = sprintf( "%.6f", $elapsed ); $average = sprintf( "%.6f", $average ); $tr = array( $action, $count, $elapsed, $average ); $line = implode( $tr, "," ); echo $line; echo PHP_EOL; //echo $action; } /** * Summarize the actions in the action file * * Deprecated */ function bw_summarise_actions( $file="bwaction.loh", $html=true ) { $target = "bwaction.cpy" ; //$res = copy( $file, $target ); //echo "copy $res\n"; $cwd = getcwd(); bw_trace2( $cwd, "cwd" ); $handle = fopen( $target, "r" ); bw_trace2( $handle, $target ); // echo $handle; while ( ($data = fgetcsv( $handle, 1000, " ")) !== FALSE ) { bw_trace2( $data ); gob(); bw_handle_action( $data ); } fclose($handle); global $actions, $actions_elapsed; if ( count( $actions ) ) { foreach ( $actions as $action => $count ) { $elapsed = bw_array_get( $actions_elapsed, $action, 0 ); $average = bw_average( $elapsed, $count ); if ( $html ) { bw_format_action_row( $action, $count, $elapsed, $average ); } else { bw_format_action_csv( $action, $count, $elapsed, $average ); } } } } /** * Display a summary of actions from the most recent action file * */ function bw_action_summary() { oik_require2( "includes/oik-bwtrace.inc", "oik-bwtrace" ); $file = bw_action_file(); if ( $file ) { stag( "table", "form-table" ); stag( "thead" ); stag( "tr" ); th( "Action" ); th( "Invocations" ); th( "Elapsed" ); th( "Average" ); etag( "tr" ); etag( "thead" ); stag( "tbody" ); //bw_summarise_actions( $file ); etag( "tbody" ); etag( "table" ); bw_action_reset_form(); } } /** * Create the Action reset button for use somewhere in any page */ function bw_action_reset_form() { oik_require( "bobbforms.inc" ); e( '

' ); e( "" ); etag( "form" ); }