HTTP request latency statistics

Edit online

For small object writes, the latency that is recorded in the access log associated with writing the object content to the stores is not counted against the storage_wait field, but instead the commit latency.
Table 1. HTTP request latency statistics
Name Mandatory Description
pre-transfer Yes The latency between the time the request was first received until when channel operations began.
post_transfer Yes The latency between the end of all channel operation until request processing is complete.
client_wait No The cumulative amount of time spent reading from or writing to the client channel.
storage_wait No The cumulative duration of time spent reading from or writing to the storage channel.
digest No For PUT requests that require calculation of a message digest or MD5 (for example, S3), the cumulative duration of time spent waiting for the digest calculation.
commit No The total time that was spent waiting for close/commit processing to complete.
turn_around_time No Turn-around time in ms measuring the duration from the last byte of input from the client to the first byte of the response to the client.
total_transfer No The total time that was spent copying data from source to destination channel, along with any additional time spent closing the channel.
The following code is an example of an S3 PUT operation: 

{
  "type": "http",
  "principals": {},
  "vault_name": "SmokeS3",
  "request_type": "REST.PUT.OBJECT",
  "object_name": "439722e249c74f39b07da2d7c99d35c80000",
  "delete_marker": false,
  "version_transient": true,
  "version_name": "702aa906-9b44-4c0d-a487-9344d93f14dd",
  "interface_type": "s3",
  "object_length": "2048",
  "request_uri": "/s3/SmokeS3/439722e249c74f39b07da2d7c99d35c80000",
  "request_method": "PUT",
  "time_finish": "29/Jan/2016:03:06:17 +0000",
  "time_start": "29/Jan/2016:03:06:17 +0000",
  "timestamp_finish": "1454036777986",
  "timestamp_start": "1454036777890",
  "remote_address": "127.0.0.1",
  "server_name": "localhost",
  "protocol": "HTTP/1.1",
  "status": 200,
  "request_length": "2048",  
  "response_length": "0",
  "user_agent": "aws-sdk-java/1.7.5 Linux/3.14.0-0.clevos.1-amd64 OpenJDK_64-Bit_Server_VM/25.45-
    b02/1.8.0_45-internal",
  "request_latency": "96",
  "request_id": "b3515f64-8e11-4462-a126-523be1980878",
  "stat": {
    "post_transfer": 0.061,
    "pre_transfer": 1.358,
    "total_transfer": 94.04,
    "turn_around_time": 94.156,
    "commit": 93.685,
    "digest": 0.007,
    "storage_wait": 0.231,
    "client_wait": 0.1
  },
  "object_length": "1572864"
}
The following code is an example of an S3 GET operation: 

{
  "type": "http",
  "principals": {},
  "vault_name": "SmokeS3",
  "request_type": "REST.GET.OBJECT",
  "interface_type": "s3",
  "request_uri": "/s3/SmokeS3/2ed0296fef7e405ba571de44965694620000",
  "request_method": "GET",
  "time_finish": "29/Jan/2016:03:06:17 +0000",
  "time_start": "29/Jan/2016:03:06:17 +0000",
  "timestamp_finish": "1454036777989",
  "timestamp_start": "1454036777901",
  "remote_address": "127.0.0.1",
  "server_name": "localhost",
  "protocol": "HTTP/1.1",
  "status": 200,
  "response_length": "1572864",
  "user_agent": "aws-sdk-java/1.7.5 Linux/3.14.0-0.clevos.1-amd64 OpenJDK_64-Bit_Server_VM/25.45-
    b02/1.8.0_45-internal",
  "request_latency": "88",
  "request_id": "a16df7d2-b7c6-49d2-ac7e-47b3606db760",
  "stat": {
    "post_transfer": 0.052,
    "pre_transfer": 32.931,
    "total_transfer": 54.11,
    "turn_around_time": 84.977,
    "client_wait": 1.281,
    "storage_wait": 52.796
  },
"object_length": "1572864"
}
The following code is an example of an S3 DELETE operation: 

{
  "type": "http",
  "principals": {},
  "vault_name": "SmokeS3",
  "request_type": "REST.DELETE.OBJECT",
  "object_name": "463ea6aa89ac41668adb69108d2f9b570000",
  "delete_marker": false,
  "version_transient": true,
  "version_name": "b8ad7307-81b0-4487-8f06-8aed2459b7ba",
  "interface_type": "s3",
  "request_uri": "/s3/SmokeS3/463ea6aa89ac41668adb69108d2f9b570000",
  "request_method": "DELETE",
  "time_finish": "29/Jan/2016:04:00:55 +0000",
  "time_start": "29/Jan/2016:04:00:55 +0000",
  "timestamp_finish": "1454040055733",
  "timestamp_start": "1454040055570",
  "remote_address": "127.0.0.1",
  "server_name": "localhost",
  "protocol": "HTTP/1.1",  
  "status": 204,
  "response_length": "0",
  "user_agent": "aws-sdk-java/1.7.5 Linux/3.14.0-0.clevos.1-amd64 OpenJDK_64-Bit_Server_VM/25.45-
    b02/1.8.0_45-internal",
  "request_latency": "163",
  "request_id": "9474592e-b2f9-477f-8a0b-843b4ae5f4bf",
  "stat": {
    "post_transfer": 0.04,
    "pre_transfer": 0.072,
    "total_transfer": 162.841,
    "turn_around_time": 162.882,
    "commit": 162.841,
    "digest": 0,
    "storage_wait": 0,
    "client_wait": 0
  },
"object_length": "1572864"
}