You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@trafficserver.apache.org by Vasanth Mathivanan <va...@evolutiondigital.com> on 2019/03/11 05:48:37 UTC
traffic.out continuously throw Htto transaction logs
Hi all ,
We have an same setup in other servers .But I had an a problem of throwing continuously Http transaction logs ,it only happens on two servers , we compared the configuration both of the servers everything is same . So for your reference have attached the logs below . We are not enabled “http transaction debug “ in records.config .Then tried both restart and config reload but no luck . And root memory was full by that logs have more trouble , please do the needful solution for me .
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) server info = 127.0.0.1:8083
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hfsco] cache action: CACHE_DO_NO_ACTION
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_no_cache_operation_on_forward_server_response] (hncoofsr)
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_seq) [handle_no_cache_operation_on_forward_server_response]
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] server sent back 200
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] next action will be OS_READ_CACHE_NOOP
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] age_value: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] date_value: 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_time: 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now: 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now (fixed): 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] apparent_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_received_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_delay: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_initial_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] resident_time: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] current_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_content_length_header] RESPONSE cont len in hdr is 1680
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [Squid code generation] Hit/Miss: 1, Log: 3, Hier: 2
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) Adding Server: ATS/6.2.3
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] State Transition: SM_ACTION_ORIGIN_SERVER_OPEN -> SM_ACTION_SERVER_READ {009}
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_smage) [186] Incrementing Age. {010}
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_redirect) is_redirect_required 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) Setup Server Transfer
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding producer 'http server'
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding consumer 'user agent'
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] perform_cache_write_action CACHE_DO_NO_ACTION
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) tunnel_run started, p_arg is provided
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) desired TCP congestion window is 0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] [tunnel_run] producer already done
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] producer_handler [http server HTTP_TUNNEL_EVENT_PRECOMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::producer_handler] enable_redirection: [0 0 0] event: 2302
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_server, HTTP_TUNNEL_EVENT_PRECOMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] session closing, netvc 0x2b6ccc00e7a0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] connection closed, ip: 127.0.0.1:8083, count: 0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] consumer_handler [user agent VC_EVENT_WRITE_COMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_ua, VC_EVENT_WRITE_COMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session closed
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [HttpSM::main_handler, HTTP_TUNNEL_EVENT_DONE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler, HTTP_TUNNEL_EVENT_DONE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session destroy
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 1
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_seq) Skipping cop heartbeat logging & stats due to config
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] deallocating sm
Thanks
__Vasanth
Sent from Mail<https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10
Re: traffic.out continuously throw Http transaction logs
Posted by Bryan Call <bc...@apache.org>.
You can run this on the command line it it should take effect in a few seconds:
sudo /opt/trafficserver/bin/traffic_ctl config set proxy.config.diags.debug.enabled 0
-Bryan
> On Mar 12, 2019, at 9:54 AM, Vasanth Mathivanan <va...@evolutiondigital.com> wrote:
>
> How can I disable that debug config , because I check did not saw that type of the line in records.config . Please suggest me .
>
> Thanks
> __Vasanth M
>
> Sent from Mail <https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10
>
> From: Vasanth Mathivanan <ma...@evolutiondigital.com>
> Sent: Monday, March 11, 2019 10:49 PM
> To: users@trafficserver.apache.org <ma...@trafficserver.apache.org>
> Subject: RE: traffic.out continuously throw Htto transaction logs
>
> Yes bryan , diags debug tags are enabled in some servers .
>
> sudo /opt/trafficserver/bin/traffic_ctl config match diags.debug
>
> proxy.config.diags.debug.enabled: 1
> proxy.config.diags.debug.tags: http.*|dns.*
>
> Thanks
> __Vasanth M
>
> Sent from Mail <https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10
>
> From: Bryan Call <ma...@apache.org>
> Sent: Monday, March 11, 2019 9:30 PM
> To: users <ma...@trafficserver.apache.org>
> Subject: Re: traffic.out continuously throw Htto transaction logs
>
> Looks like you have debug enabled on those servers. You can verify these setting by running this command:
>
> 08:57:22 homer:~$ sudo /usr/local/bin/traffic_ctl config match diags.debug
> proxy.config.diags.debug.enabled: 0
> proxy.config.diags.debug.tags: http|dns
>
> -Bryan
>
>
>
>> On Mar 10, 2019, at 10:48 PM, Vasanth Mathivanan <vasanth.m@evolutiondigital.com <ma...@evolutiondigital.com>> wrote:
>>
>> Hi all ,
>>
>> We have an same setup in other servers .But I had an a problem of throwing continuously Http transaction logs ,it only happens on two servers , we compared the configuration both of the servers everything is same . So for your reference have attached the logs below . We are not enabled “http transaction debug “ in records.config .Then tried both restart and config reload but no luck . And root memory was full by that logs have more trouble , please do the needful solution for me .
>>
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) server info = 127.0.0.1:8083
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hfsco] cache action: CACHE_DO_NO_ACTION
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_no_cache_operation_on_forward_server_response] (hncoofsr)
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_seq) [handle_no_cache_operation_on_forward_server_response]
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] server sent back 200
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] next action will be OS_READ_CACHE_NOOP
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] age_value: 0
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] date_value: 1552283141
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_time: 1552283141
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now: 1552283141
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now (fixed): 1552283141
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] apparent_age: 0
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_received_age: 0
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_delay: 0
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_initial_age: 0
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] resident_time: 0
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] current_age: 0
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_content_length_header] RESPONSE cont len in hdr is 1680
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [Squid code generation] Hit/Miss: 1, Log: 3, Hier: 2
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) Adding Server: ATS/6.2.3
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] State Transition: SM_ACTION_ORIGIN_SERVER_OPEN -> SM_ACTION_SERVER_READ {009}
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_smage) [186] Incrementing Age. {010}
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_redirect) is_redirect_required 0
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) Setup Server Transfer
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding producer 'http server'
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding consumer 'user agent'
>> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] perform_cache_write_action CACHE_DO_NO_ACTION
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) tunnel_run started, p_arg is provided
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 0
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) desired TCP congestion window is 0
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] [tunnel_run] producer already done
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] producer_handler [http server HTTP_TUNNEL_EVENT_PRECOMPLETE]
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::producer_handler] enable_redirection: [0 0 0] event: 2302
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_server, HTTP_TUNNEL_EVENT_PRECOMPLETE]
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] session closing, netvc 0x2b6ccc00e7a0
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] connection closed, ip: 127.0.0.1:8083, count: 0
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] consumer_handler [user agent VC_EVENT_WRITE_COMPLETE]
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_ua, VC_EVENT_WRITE_COMPLETE]
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session closed
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [HttpSM::main_handler, HTTP_TUNNEL_EVENT_DONE]
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler, HTTP_TUNNEL_EVENT_DONE]
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session destroy
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 1
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_seq) Skipping cop heartbeat logging & stats due to config
>> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] deallocating sm
>>
>>
>>
>>
>> Thanks
>> __Vasanth
>>
>>
>>
>>
>> Sent from Mail <https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10
RE: traffic.out continuously throw Http transaction logs
Posted by Vasanth Mathivanan <va...@evolutiondigital.com>.
How can I disable that debug config , because I check did not saw that type of the line in records.config . Please suggest me .
Thanks
__Vasanth M
Sent from Mail<https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10
From: Vasanth Mathivanan<ma...@evolutiondigital.com>
Sent: Monday, March 11, 2019 10:49 PM
To: users@trafficserver.apache.org<ma...@trafficserver.apache.org>
Subject: RE: traffic.out continuously throw Htto transaction logs
Yes bryan , diags debug tags are enabled in some servers .
sudo /opt/trafficserver/bin/traffic_ctl config match diags.debug
proxy.config.diags.debug.enabled: 1
proxy.config.diags.debug.tags: http.*|dns.*
Thanks
__Vasanth M
Sent from Mail<https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10
From: Bryan Call<ma...@apache.org>
Sent: Monday, March 11, 2019 9:30 PM
To: users<ma...@trafficserver.apache.org>
Subject: Re: traffic.out continuously throw Htto transaction logs
Looks like you have debug enabled on those servers. You can verify these setting by running this command:
08:57:22 homer:~$ sudo /usr/local/bin/traffic_ctl config match diags.debug
proxy.config.diags.debug.enabled: 0
proxy.config.diags.debug.tags: http|dns
-Bryan
On Mar 10, 2019, at 10:48 PM, Vasanth Mathivanan <va...@evolutiondigital.com>> wrote:
Hi all ,
We have an same setup in other servers .But I had an a problem of throwing continuously Http transaction logs ,it only happens on two servers , we compared the configuration both of the servers everything is same . So for your reference have attached the logs below . We are not enabled “http transaction debug “ in records.config .Then tried both restart and config reload but no luck . And root memory was full by that logs have more trouble , please do the needful solution for me .
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) server info = 127.0.0.1:8083
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hfsco] cache action: CACHE_DO_NO_ACTION
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_no_cache_operation_on_forward_server_response] (hncoofsr)
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_seq) [handle_no_cache_operation_on_forward_server_response]
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] server sent back 200
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] next action will be OS_READ_CACHE_NOOP
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] age_value: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] date_value: 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_time: 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now: 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now (fixed): 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] apparent_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_received_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_delay: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_initial_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] resident_time: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] current_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_content_length_header] RESPONSE cont len in hdr is 1680
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [Squid code generation] Hit/Miss: 1, Log: 3, Hier: 2
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) Adding Server: ATS/6.2.3
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] State Transition: SM_ACTION_ORIGIN_SERVER_OPEN -> SM_ACTION_SERVER_READ {009}
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_smage) [186] Incrementing Age. {010}
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_redirect) is_redirect_required 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) Setup Server Transfer
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding producer 'http server'
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding consumer 'user agent'
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] perform_cache_write_action CACHE_DO_NO_ACTION
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) tunnel_run started, p_arg is provided
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) desired TCP congestion window is 0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] [tunnel_run] producer already done
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] producer_handler [http server HTTP_TUNNEL_EVENT_PRECOMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::producer_handler] enable_redirection: [0 0 0] event: 2302
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_server, HTTP_TUNNEL_EVENT_PRECOMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] session closing, netvc 0x2b6ccc00e7a0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] connection closed, ip: 127.0.0.1:8083, count: 0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] consumer_handler [user agent VC_EVENT_WRITE_COMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_ua, VC_EVENT_WRITE_COMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session closed
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [HttpSM::main_handler, HTTP_TUNNEL_EVENT_DONE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler, HTTP_TUNNEL_EVENT_DONE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session destroy
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 1
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_seq) Skipping cop heartbeat logging & stats due to config
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] deallocating sm
Thanks
__Vasanth
Sent from Mail<https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10
RE: traffic.out continuously throw Htto transaction logs
Posted by Vasanth Mathivanan <va...@evolutiondigital.com>.
Yes bryan , diags debug tags are enabled in some servers .
sudo /opt/trafficserver/bin/traffic_ctl config match diags.debug
proxy.config.diags.debug.enabled: 1
proxy.config.diags.debug.tags: http.*|dns.*
Thanks
__Vasanth M
Sent from Mail<https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10
From: Bryan Call<ma...@apache.org>
Sent: Monday, March 11, 2019 9:30 PM
To: users<ma...@trafficserver.apache.org>
Subject: Re: traffic.out continuously throw Htto transaction logs
Looks like you have debug enabled on those servers. You can verify these setting by running this command:
08:57:22 homer:~$ sudo /usr/local/bin/traffic_ctl config match diags.debug
proxy.config.diags.debug.enabled: 0
proxy.config.diags.debug.tags: http|dns
-Bryan
On Mar 10, 2019, at 10:48 PM, Vasanth Mathivanan <va...@evolutiondigital.com>> wrote:
Hi all ,
We have an same setup in other servers .But I had an a problem of throwing continuously Http transaction logs ,it only happens on two servers , we compared the configuration both of the servers everything is same . So for your reference have attached the logs below . We are not enabled “http transaction debug “ in records.config .Then tried both restart and config reload but no luck . And root memory was full by that logs have more trouble , please do the needful solution for me .
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) server info = 127.0.0.1:8083
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hfsco] cache action: CACHE_DO_NO_ACTION
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_no_cache_operation_on_forward_server_response] (hncoofsr)
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_seq) [handle_no_cache_operation_on_forward_server_response]
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] server sent back 200
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] next action will be OS_READ_CACHE_NOOP
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] age_value: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] date_value: 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_time: 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now: 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now (fixed): 1552283141
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] apparent_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_received_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_delay: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_initial_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] resident_time: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] current_age: 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_content_length_header] RESPONSE cont len in hdr is 1680
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [Squid code generation] Hit/Miss: 1, Log: 3, Hier: 2
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) Adding Server: ATS/6.2.3
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] State Transition: SM_ACTION_ORIGIN_SERVER_OPEN -> SM_ACTION_SERVER_READ {009}
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_smage) [186] Incrementing Age. {010}
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_redirect) is_redirect_required 0
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) Setup Server Transfer
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding producer 'http server'
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding consumer 'user agent'
[Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] perform_cache_write_action CACHE_DO_NO_ACTION
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) tunnel_run started, p_arg is provided
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) desired TCP congestion window is 0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] [tunnel_run] producer already done
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] producer_handler [http server HTTP_TUNNEL_EVENT_PRECOMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::producer_handler] enable_redirection: [0 0 0] event: 2302
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_server, HTTP_TUNNEL_EVENT_PRECOMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] session closing, netvc 0x2b6ccc00e7a0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] connection closed, ip: 127.0.0.1:8083, count: 0
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] consumer_handler [user agent VC_EVENT_WRITE_COMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_ua, VC_EVENT_WRITE_COMPLETE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session closed
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [HttpSM::main_handler, HTTP_TUNNEL_EVENT_DONE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler, HTTP_TUNNEL_EVENT_DONE]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session destroy
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 1
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_seq) Skipping cop heartbeat logging & stats due to config
[Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] deallocating sm
Thanks
__Vasanth
Sent from Mail<https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10
Re: traffic.out continuously throw Htto transaction logs
Posted by Bryan Call <bc...@apache.org>.
Looks like you have debug enabled on those servers. You can verify these setting by running this command:
08:57:22 homer:~$ sudo /usr/local/bin/traffic_ctl config match diags.debug
proxy.config.diags.debug.enabled: 0
proxy.config.diags.debug.tags: http|dns
-Bryan
> On Mar 10, 2019, at 10:48 PM, Vasanth Mathivanan <va...@evolutiondigital.com> wrote:
>
> Hi all ,
>
> We have an same setup in other servers .But I had an a problem of throwing continuously Http transaction logs ,it only happens on two servers , we compared the configuration both of the servers everything is same . So for your reference have attached the logs below . We are not enabled “http transaction debug “ in records.config .Then tried both restart and config reload but no luck . And root memory was full by that logs have more trouble , please do the needful solution for me .
>
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) server info = 127.0.0.1:8083
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hfsco] cache action: CACHE_DO_NO_ACTION
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_no_cache_operation_on_forward_server_response] (hncoofsr)
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_seq) [handle_no_cache_operation_on_forward_server_response]
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] server sent back 200
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [hncoofsr] next action will be OS_READ_CACHE_NOOP
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] age_value: 0
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] date_value: 1552283141
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_time: 1552283141
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now: 1552283141
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] now (fixed): 1552283141
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] apparent_age: 0
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_received_age: 0
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] response_delay: 0
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] corrected_initial_age: 0
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] resident_time: 0
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_age) [calculate_document_age] current_age: 0
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [handle_content_length_header] RESPONSE cont len in hdr is 1680
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) [Squid code generation] Hit/Miss: 1, Log: 3, Hier: 2
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_trans) Adding Server: ATS/6.2.3
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] State Transition: SM_ACTION_ORIGIN_SERVER_OPEN -> SM_ACTION_SERVER_READ {009}
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_smage) [186] Incrementing Age. {010}
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_redirect) is_redirect_required 0
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) Setup Server Transfer
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding producer 'http server'
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] adding consumer 'user agent'
> [Mar 11 00:45:41.990] Server {0x2b6c29263700} DEBUG: (http) [186] perform_cache_write_action CACHE_DO_NO_ACTION
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) tunnel_run started, p_arg is provided
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 0
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) desired TCP congestion window is 0
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] [tunnel_run] producer already done
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] producer_handler [http server HTTP_TUNNEL_EVENT_PRECOMPLETE]
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::producer_handler] enable_redirection: [0 0 0] event: 2302
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_server, HTTP_TUNNEL_EVENT_PRECOMPLETE]
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] session closing, netvc 0x2b6ccc00e7a0
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_ss) [186] connection closed, ip: 127.0.0.1:8083, count: 0
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_tunnel) [186] consumer_handler [user agent VC_EVENT_WRITE_COMPLETE]
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler_ua, VC_EVENT_WRITE_COMPLETE]
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session closed
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [HttpSM::main_handler, HTTP_TUNNEL_EVENT_DONE]
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] [&HttpSM::tunnel_handler, HTTP_TUNNEL_EVENT_DONE]
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers]
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) [186] session destroy
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_cs) tcp_init_cwnd_set 1
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http_seq) Skipping cop heartbeat logging & stats due to config
> [Mar 11 00:45:41.991] Server {0x2b6c29263700} DEBUG: (http) [186] deallocating sm
>
>
>
>
> Thanks
> __Vasanth
>
>
>
>
> Sent from Mail <https://go.microsoft.com/fwlink/?LinkId=550986> for Windows 10