Bug 626494 - Enhancement to script-fu-server logging to output milliseconds taken

This commit is contained in:
Cameron Gregory 2016-06-25 20:52:27 +02:00 committed by Michael Schumacher
parent 5627ad589f
commit 6e608f6aae
1 changed files with 13 additions and 10 deletions

View File

@ -547,15 +547,16 @@ server_start (const gchar *listen_ip,
static gboolean static gboolean
execute_command (SFCommand *cmd) execute_command (SFCommand *cmd)
{ {
guchar buffer[RESPONSE_HEADER]; guchar buffer[RESPONSE_HEADER];
GString *response; GString *response;
time_t clock1; time_t clocknow;
time_t clock2; gboolean error;
gboolean error; gint i;
gint i; gdouble total_time;
GTimer *timer;
server_log ("Processing request #%d\n", cmd->request_no); server_log ("Processing request #%d\n", cmd->request_no);
time (&clock1); timer = g_timer_new ();
response = g_string_new (NULL); response = g_string_new (NULL);
ts_register_output_func (ts_gstring_output_func, response); ts_register_output_func (ts_gstring_output_func, response);
@ -574,10 +575,12 @@ execute_command (SFCommand *cmd)
if (response->len == 0) if (response->len == 0)
g_string_assign (response, ts_get_success_msg ()); g_string_assign (response, ts_get_success_msg ());
time (&clock2); total_time = g_timer_elapsed (timer, NULL);
server_log ("Request #%d processed in %f seconds, finishing on %s", time (&clocknow);
cmd->request_no, difftime (clock2, clock1), ctime (&clock2)); server_log ("Request #%d processed in %.3f seconds, finishing on %s",
cmd->request_no, total_time, ctime (&clocknow));
} }
g_timer_destroy (timer);
buffer[MAGIC_BYTE] = MAGIC; buffer[MAGIC_BYTE] = MAGIC;
buffer[ERROR_BYTE] = error ? TRUE : FALSE; buffer[ERROR_BYTE] = error ? TRUE : FALSE;