feat(log): add action and proxy response time to log

Author: joelwurtz

src/ngx_http_redirectionio_module.c

@@ -223,6 +223,8 @@ static ngx_int_t ngx_http_redirectionio_create_ctx_handler(ngx_http_request_t *r
         ctx->response_headers = NULL;
         ctx->body_filter = NULL;
         ctx->action_string = NULL;
+        ctx->action_match_time = 0;
+        ctx->proxy_response_time = 0;
         ctx->action_string_len = 0;
         ctx->action_string_readed = 0;
         ctx->connection_error = 0;
@@ -716,6 +718,7 @@ static void ngx_http_redirectionio_read_match_action_handler(ngx_event_t *rev, c
     ngx_http_redirectionio_ctx_t    *ctx;
     ngx_http_request_t              *r;
     ngx_connection_t                *c;
+    ngx_time_t                      *tp;
 
     c = rev->data;
     r = c->data;
@@ -733,6 +736,9 @@ static void ngx_http_redirectionio_read_match_action_handler(ngx_event_t *rev, c
 
     ctx->action = (struct REDIRECTIONIO_Action *)redirectionio_action_json_deserialize((char *)action_serialized);
 
+    tp = ngx_timeofday();
+    ctx->action_match_time = (tp->sec * 1000 + tp->msec);
+
     ngx_http_core_run_phases(r);
 }
 

src/ngx_http_redirectionio_module.h

@@ -82,6 +82,8 @@ typedef struct {
     struct REDIRECTIONIO_Action                     *action;
     struct REDIRECTIONIO_HeaderMap                  *response_headers;
     struct REDIRECTIONIO_FilterBodyAction           *body_filter;
+    ngx_uint_t                                      action_match_time;
+    ngx_uint_t                                      proxy_response_time;
     ngx_uint_t                                      backend_response_status_code;
 
     ngx_uint_t                                      connection_error;

src/ngx_http_redirectionio_module_filter.c

@@ -194,6 +194,7 @@ ngx_int_t ngx_http_redirectionio_headers_filter(ngx_http_request_t *r) {
 
 static ngx_int_t ngx_http_redirectionio_create_filter_body(ngx_http_request_t *r) {
     ngx_http_redirectionio_ctx_t    *ctx;
+    ngx_time_t                      *tp;
 
     ctx = ngx_http_get_module_ctx(r, ngx_http_redirectionio_module);
 
@@ -213,6 +214,10 @@ static ngx_int_t ngx_http_redirectionio_create_filter_body(ngx_http_request_t *r
         r->headers_out.content_length_n = -1;
     }
 
+    // Save proxy response time
+    tp = ngx_timeofday();
+    ctx->proxy_response_time = (tp->sec * 1000 + tp->msec);
+
     return ngx_http_next_header_filter(r);
 }
 

src/ngx_http_redirectionio_protocol.c

@@ -186,7 +186,7 @@ ngx_http_redirectionio_log_t* ngx_http_redirectionio_protocol_create_log(ngx_htt
     ngx_http_redirectionio_log_t    *log;
 
     client_ip = ngx_http_redirectionio_str_to_char(&r->connection->addr_text, r->pool);
-    log_serialized = redirectionio_api_create_log_in_json(ctx->request, r->headers_out.status, ctx->response_headers, ctx->action, PROXY_VERSION_STR(PROXY_VERSION), time, client_ip);
+    log_serialized = redirectionio_api_create_log_in_json(ctx->request, r->headers_out.status, ctx->response_headers, ctx->action, PROXY_VERSION_STR(PROXY_VERSION), time, ctx->action_match_time, ctx->proxy_response_time, client_ip);
 
     if (log_serialized == NULL) {
         return NULL;