Hi,
I am trying to access my internal point-to-point VPN URLS but not able access
also check with debug mode but I unable to trace the issue. Please guide me.
My all URL on 80 port with authentication. Please check the attachment of debug
file.
Regards,
Prasad Jadhav
________________________________
This email (message and any attachment) is confidential and may be privileged.
If you are not certain that you are the intended recipient, please notify the
sender immediately by replying to this message, and delete all copies of this
message and attachments. Any other use of this email by you is prohibited.
________________________________
traffic_server: using root directory '/usr/local/traffic-cache'
[Apr 6 10:16:43.623] Server {0x7fd243c96700} DEBUG: (http_seq)
[HttpSessionAccept:mainEvent 0x7fd21c016630] accepted connection from
172.16.100.98:38537 transport type = 1
[Apr 6 10:16:43.623] Server {0x7fd243c96700} DEBUG: (http_cs) [0] session
born, netvc 0x7fd21c016630
[Apr 6 10:16:43.623] Server {0x7fd243c96700} DEBUG: (http_cs) [0] Starting
transaction 1 using sm [0]
[Apr 6 10:16:43.623] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_add_to_list, VC_EVENT_NONE]
[Apr 6 10:16:43.623] Server {0x7fd243c96700} DEBUG: (http) [0]
[HttpSM::main_handler, VC_EVENT_READ_READY]
[Apr 6 10:16:43.623] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_read_client_request_header, VC_EVENT_READ_READY]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [0] done parsing
client request header
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http_trans) START
HttpTransact::ModifyRequest
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http_trans)
[ink_cluster_time] local: 1428295603, highest_delta: 0, cluster: 1428295603
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http_trans)
[ModifyRequest] normalized Accept-Encoding to gzip
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http_trans) END
HttpTransact::ModifyRequest
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http_trans) Checking if
transaction wants to upgrade
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http_trans) Next action
SM_ACTION_API_READ_REQUEST_HDR; HttpTransact::StartRemapRequest
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [0] State
Transition: SM_ACTION_UNDEFINED -> SM_ACTION_API_READ_REQUEST_HDR
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [0] calling plugin
on hook TS_HTTP_READ_REQUEST_HDR_HOOK at hook 0x2ba4470
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_api_callback, HTTP_API_CONTINUE]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_api_callout, HTTP_API_CONTINUE]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [0] calling plugin
on hook TS_HTTP_READ_REQUEST_HDR_HOOK at hook 0x2ba4450
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_api_callback, HTTP_API_CONTINUE]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_api_callout, HTTP_API_CONTINUE]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http_trans) START
HttpTransact::StartRemapRequest
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http_trans) Before
Remapping:
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) HTTP_HEADER
0x7fd241045898: [T: 3, L: 48, OBJFLAGS: 0]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [TYPE: REQ, V:
10001, URL: 0x7fd241045b18, METHOD: "GET", METHOD_LEN: 3, FIELDS:
0x7fd2410458c8]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) URL 0x7fd241045b18:
[T: 2, L: 112, OBJFLAGS: 0]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [URLTYPE: 1,
SWKSIDX: 97,
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) SCHEME: "http",
SCHEME_LEN: 4,
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) USER: "",
USER_LEN: 0,
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) PASSWORD: "",
PASSWORD_LEN: 0,
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) HOST:
"172.17.10.75", HOST_LEN: 12,
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) PORT: "",
PORT_LEN: 0, PORT_NUM: 0
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) PATH: "",
PATH_LEN: 0,
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) PARAMS: "",
PARAMS_LEN: 0,
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) QUERY: "",
QUERY_LEN: 0,
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) FRAGMENT: "",
FRAGMENT_LEN: 0]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) MIME_HEADER
0x7fd2410458c8: [T: 4, L: 592, OBJFLAGS: 0]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http)
[PBITS: 0x000800000100080D, SLACC: 0xFFFF34F2FFFFFFF5FFFFFFFFFFF1FFFF,
HEADBLK: 0x7fd241045908, TAILBLK: 0x7fd241045908]
[Apr 6 10:16:43.624] Server {0x7fd243c96700} DEBUG: (http) [CBITS:
0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0]
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) FIELD_BLOCK
0x7fd241045908: [T: 5, L: 528, OBJFLAGS: 0]
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) [FREETOP: 6,
NEXTBLK: (nil)]
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) SLOT # 0
(0x7fd241045918), LIVE
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) [N: "Host", N_LEN:
4, N_IDX: 30,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) V: "172.17.10.75",
V_LEN: 12,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 20, F: 1]
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) SLOT # 1
(0x7fd241045938), LIVE
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) [N: "User-Agent",
N_LEN: 10, N_IDX: 64,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) V: "Mozilla/5.0
(Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101 Firefox/39.0", V_LEN: 72,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 86, F: 1]
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) SLOT # 2
(0x7fd241045958), LIVE
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) [N: "Accept",
N_LEN: 6, N_IDX: 4,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) V:
"text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8", V_LEN: 63,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 73, F: 1]
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) SLOT # 3
(0x7fd241045978), LIVE
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) [N:
"Accept-Language", N_LEN: 15, N_IDX: 2,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) V:
"en-US,en;q=0.5", V_LEN: 14,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 33, F: 1]
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) SLOT # 4
(0x7fd241045998), LIVE
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) [N:
"Accept-Encoding", N_LEN: 15, N_IDX: 1,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) V: "gzip", V_LEN: 4,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 0, RAWLEN: 23, F: 1]
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) SLOT # 5
(0x7fd2410459b8), LIVE
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) [N: "Connection",
N_LEN: 10, N_IDX: 12,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) V: "keep-alive",
V_LEN: 10,
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 24, F: 1]
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http_trans) END
HttpTransact::StartRemapRequest
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http_trans) Next action
SM_ACTION_API_PRE_REMAP; HttpTransact::PerformRemap
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) [0] State
Transition: SM_ACTION_API_READ_REQUEST_HDR -> SM_ACTION_API_PRE_REMAP
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http_trans) Inside
PerformRemap
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http_trans) Next action
SM_ACTION_REMAP_REQUEST; HttpTransact::EndRemapRequest
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http) [0] State
Transition: SM_ACTION_API_PRE_REMAP -> SM_ACTION_REMAP_REQUEST
[Apr 6 10:16:43.625] Server {0x7fd243c96700} DEBUG: (http_seq)
[HttpSM::do_remap_request] Remapping request
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http_trans) START
HttpTransact::EndRemapRequest
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http_trans)
EndRemapRequest host is 172.17.10.75
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http_trans) After
Remapping:
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) HTTP_HEADER
0x7fd241045898: [T: 3, L: 48, OBJFLAGS: 0]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) [TYPE: REQ, V:
10001, URL: 0x7fd241045b18, METHOD: "GET", METHOD_LEN: 3, FIELDS:
0x7fd2410458c8]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) URL 0x7fd241045b18:
[T: 2, L: 112, OBJFLAGS: 0]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) [URLTYPE: 1,
SWKSIDX: 97,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) SCHEME: "http",
SCHEME_LEN: 4,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) USER: "",
USER_LEN: 0,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) PASSWORD: "",
PASSWORD_LEN: 0,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) HOST:
"172.17.10.75", HOST_LEN: 12,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) PORT: "",
PORT_LEN: 0, PORT_NUM: 0
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) PATH: "",
PATH_LEN: 0,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) PARAMS: "",
PARAMS_LEN: 0,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) QUERY: "",
QUERY_LEN: 0,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) FRAGMENT: "",
FRAGMENT_LEN: 0]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) MIME_HEADER
0x7fd2410458c8: [T: 4, L: 592, OBJFLAGS: 0]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http)
[PBITS: 0x000800000100080D, SLACC: 0xFFFF34F2FFFFFFF5FFFFFFFFFFF1FFFF,
HEADBLK: 0x7fd241045908, TAILBLK: 0x7fd241045908]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) [CBITS:
0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) FIELD_BLOCK
0x7fd241045908: [T: 5, L: 528, OBJFLAGS: 0]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) [FREETOP: 6,
NEXTBLK: (nil)]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) SLOT # 0
(0x7fd241045918), LIVE
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) [N: "Host", N_LEN:
4, N_IDX: 30,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) V: "172.17.10.75",
V_LEN: 12,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 20, F: 1]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) SLOT # 1
(0x7fd241045938), LIVE
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) [N: "User-Agent",
N_LEN: 10, N_IDX: 64,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) V: "Mozilla/5.0
(Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101 Firefox/39.0", V_LEN: 72,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 86, F: 1]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) SLOT # 2
(0x7fd241045958), LIVE
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) [N: "Accept",
N_LEN: 6, N_IDX: 4,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) V:
"text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8", V_LEN: 63,
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 73, F: 1]
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) SLOT # 3
(0x7fd241045978), LIVE
[Apr 6 10:16:43.626] Server {0x7fd243c96700} DEBUG: (http) [N:
"Accept-Language", N_LEN: 15, N_IDX: 2,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) V:
"en-US,en;q=0.5", V_LEN: 14,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 33, F: 1]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) SLOT # 4
(0x7fd241045998), LIVE
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) [N:
"Accept-Encoding", N_LEN: 15, N_IDX: 1,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) V: "gzip", V_LEN: 4,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 0, RAWLEN: 23, F: 1]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) SLOT # 5
(0x7fd2410459b8), LIVE
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) [N: "Connection",
N_LEN: 10, N_IDX: 12,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) V: "keep-alive",
V_LEN: 10,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 24, F: 1]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http_trans) END
HttpTransact::EndRemapRequest
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http_trans) Next action
SM_ACTION_API_POST_REMAP; HttpTransact::HandleRequest
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) [0] State
Transition: SM_ACTION_REMAP_REQUEST -> SM_ACTION_API_POST_REMAP
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http_trans) START
HttpTransact::HandleRequest
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http_trans)
[init_stat_vars_from_req] set req cont length to 0
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http_trans)
[is_request_valid]no request header errors
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http_seq)
[HttpTransact::HandleRequest] request valid.
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) HTTP_HEADER
0x7fd241045898: [T: 3, L: 48, OBJFLAGS: 0]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) [TYPE: REQ, V:
10001, URL: 0x7fd241045b18, METHOD: "GET", METHOD_LEN: 3, FIELDS:
0x7fd2410458c8]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) URL 0x7fd241045b18:
[T: 2, L: 112, OBJFLAGS: 0]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) [URLTYPE: 1,
SWKSIDX: 97,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) SCHEME: "http",
SCHEME_LEN: 4,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) USER: "",
USER_LEN: 0,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) PASSWORD: "",
PASSWORD_LEN: 0,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) HOST:
"172.17.10.75", HOST_LEN: 12,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) PORT: "",
PORT_LEN: 0, PORT_NUM: 0
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) PATH: "",
PATH_LEN: 0,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) PARAMS: "",
PARAMS_LEN: 0,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) QUERY: "",
QUERY_LEN: 0,
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) FRAGMENT: "",
FRAGMENT_LEN: 0]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) MIME_HEADER
0x7fd2410458c8: [T: 4, L: 592, OBJFLAGS: 0]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http)
[PBITS: 0x000800000100080D, SLACC: 0xFFFF34F2FFFFFFF5FFFFFFFFFFF1FFFF,
HEADBLK: 0x7fd241045908, TAILBLK: 0x7fd241045908]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) [CBITS:
0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) FIELD_BLOCK
0x7fd241045908: [T: 5, L: 528, OBJFLAGS: 0]
[Apr 6 10:16:43.627] Server {0x7fd243c96700} DEBUG: (http) [FREETOP: 6,
NEXTBLK: (nil)]
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) SLOT # 0
(0x7fd241045918), LIVE
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) [N: "Host", N_LEN:
4, N_IDX: 30,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) V: "172.17.10.75",
V_LEN: 12,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 20, F: 1]
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) SLOT # 1
(0x7fd241045938), LIVE
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) [N: "User-Agent",
N_LEN: 10, N_IDX: 64,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) V: "Mozilla/5.0
(Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101 Firefox/39.0", V_LEN: 72,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 86, F: 1]
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) SLOT # 2
(0x7fd241045958), LIVE
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) [N: "Accept",
N_LEN: 6, N_IDX: 4,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) V:
"text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8", V_LEN: 63,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 73, F: 1]
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) SLOT # 3
(0x7fd241045978), LIVE
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) [N:
"Accept-Language", N_LEN: 15, N_IDX: 2,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) V:
"en-US,en;q=0.5", V_LEN: 14,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 33, F: 1]
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) SLOT # 4
(0x7fd241045998), LIVE
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) [N:
"Accept-Encoding", N_LEN: 15, N_IDX: 1,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) V: "gzip", V_LEN: 4,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 0, RAWLEN: 23, F: 1]
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http)
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) SLOT # 5
(0x7fd2410459b8), LIVE
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) [N: "Connection",
N_LEN: 10, N_IDX: 12,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) V: "keep-alive",
V_LEN: 10,
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 24, F: 1]
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http)
+++++++++ Incoming Request +++++++++
-- State Machine Id: 0
GET http://172.17.10.75/ HTTP/1.1
Host: 172.17.10.75
User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101
Firefox/39.0
Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
Accept-Language: en-US,en;q=0.5
Accept-Encoding: gzip
Connection: keep-alive
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http_trans)
[DecideCacheLookup] Will do cache lookup.
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http_seq)
[DecideCacheLookup] Will do cache lookup
[Apr 6 10:16:43.628] Server {0x7fd243c96700} DEBUG: (http_trans) Next action
SM_ACTION_CACHE_LOOKUP; __null
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http) [0] State
Transition: SM_ACTION_API_POST_REMAP -> SM_ACTION_CACHE_LOOKUP
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_seq)
[HttpSM::do_cache_lookup_and_read] [0] Issuing cache lookup for URL
http://172.17.10.75/
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_cache) [0]
[&HttpCacheSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED]
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http) [0]
[HttpSM::main_handler, CACHE_EVENT_OPEN_READ_FAILED]
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED]
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http) [0] cache_open_read
- CACHE_EVENT_OPEN_READ_FAILED
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http)
[state_cache_open_read] open read failed.
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans)
[HttpTransact::HandleCacheOpenRead]
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans) CacheOpenRead
-- miss
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans) Next action
SM_ACTION_DNS_LOOKUP; OSDNSLookup
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http) [0] State
Transition: SM_ACTION_CACHE_LOOKUP -> SM_ACTION_DNS_LOOKUP
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_seq)
[HttpSM::do_hostdb_lookup] Doing DNS Lookup
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans)
[ink_cluster_time] local: 1428295603, highest_delta: 0, cluster: 1428295603
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans)
[HttpTransact::OSDNSLookup] This was attempt 1
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_seq)
[HttpTransact::OSDNSLookup] DNS Lookup successful
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans) [OSDNSLookup]
DNS lookup for O.S. successful IP: 172.17.10.75
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans) Next action
SM_ACTION_API_OS_DNS; HandleCacheOpenReadMiss
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http) [0] State
Transition: SM_ACTION_DNS_LOOKUP -> SM_ACTION_API_OS_DNS
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans)
[HandleCacheOpenReadMiss] --- MISS
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_seq)
[HttpTransact::HandleCacheOpenReadMiss] Miss in cache
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans) client_ip_set
= 0
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans) inserted
request header 'Client-ip: 172.16.100.98'
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans)
[add_client_ip_to_outgoing_request] Appended connecting client's
(172.16.100.98) to the X-Forwards header
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans)
[build_request] removing host name from url
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans)
[build_request] request like cacheable and conditional headers removed
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans)
[ink_cluster_time] local: 1428295603, highest_delta: 0, cluster: 1428295603
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_trans)
[build_request] request_sent_time: 1428295603
+++++++++ Proxy's Request +++++++++
-- State Machine Id: 0
GET / HTTP/1.1
Host: 172.17.10.75
User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101
Firefox/39.0
Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
Accept-Language: en-US,en;q=0.5
Accept-Encoding: gzip
Client-ip: 172.16.100.98
X-Forwarded-For: 172.16.100.98
Via: http/1.1 <proxy_name>[0A0A140A] (ApacheTrafficServer/5.3.0)
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http) [0] State
Transition: SM_ACTION_API_OS_DNS -> SM_ACTION_CACHE_ISSUE_WRITE
[Apr 6 10:16:43.629] Server {0x7fd243c96700} DEBUG: (http_cache_write) [0]
writing to cache with URL http://172.17.10.75/
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http_cache) [0]
[&HttpCacheSM::state_cache_open_write, CACHE_EVENT_OPEN_WRITE]
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http) [0]
[HttpSM::main_handler, CACHE_EVENT_OPEN_WRITE]
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM:state_cache_open_write, CACHE_EVENT_OPEN_WRITE]
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http_trans) Next action
next; __null
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http) [0] State
Transition: SM_ACTION_CACHE_ISSUE_WRITE -> SM_ACTION_ORIGIN_SERVER_OPEN
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http_track) entered
inside do_http_server_open ][IPv4]
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http) [0] open connection
to 172.17.10.75: 172.17.10.75:80
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http_seq)
[HttpSM::do_http_server_open] Sending request to server
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http) calling
netProcessor.connect_re
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http) [0]
[HttpSM::main_handler, NET_EVENT_OPEN]
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http_track) entered
inside state_http_server_open
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_http_server_open, NET_EVENT_OPEN]
[Apr 6 10:16:43.630] Server {0x7fd243c96700} DEBUG: (http_ss) [0] session
born, netvc 0x7fd21c016370
+++++++++ Proxy's Request after hooks +++++++++
-- State Machine Id: 0
GET / HTTP/1.1
Host: 172.17.10.75
User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101
Firefox/39.0
Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
Accept-Language: en-US,en;q=0.5
Accept-Encoding: gzip
Client-ip: 172.16.100.98
X-Forwarded-For: 172.16.100.98
Via: http/1.1 <proxy_name>[0A0A140A] (ApacheTrafficServer/5.3.0)
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http) [0]
[HttpSM::main_handler, VC_EVENT_INACTIVITY_TIMEOUT]
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_watch_for_client_abort, VC_EVENT_INACTIVITY_TIMEOUT]
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http_redirect)
[HttpTunnel::deallocate_postdata_copy_buffers]
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http_cs) [0] session
closed
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http_cs) [0] session
destroy
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http_ss) [0] session
closing, netvc 0x7fd21c016370
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http_redirect)
[HttpTunnel::deallocate_postdata_copy_buffers]
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http) [0]
[&HttpSM::state_remove_from_list, VC_EVENT_NONE]
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http_seq)
[HttpSM::update_stats] Logging transaction
[Apr 6 10:16:54.247] Server {0x7fd243c96700} DEBUG: (http) [0] deallocating sm
[Apr 6 10:17:06.055] Server {0x7fd243b95700} DEBUG: (http_seq)
[HttpSessionAccept:mainEvent 0x7fd21c016630] accepted connection from
172.16.100.98:38541 transport type = 1
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http_cs) [1] session
born, netvc 0x7fd21c016630
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http_cs) [1] Starting
transaction 1 using sm [1]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::state_add_to_list, VC_EVENT_NONE]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1]
[HttpSM::main_handler, VC_EVENT_READ_READY]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::state_read_client_request_header, VC_EVENT_READ_READY]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1] done parsing
client request header
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http_trans) START
HttpTransact::ModifyRequest
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http_trans)
[ink_cluster_time] local: 1428295626, highest_delta: 0, cluster: 1428295626
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http_trans) END
HttpTransact::ModifyRequest
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http_trans) Checking if
transaction wants to upgrade
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http_trans) Next action
SM_ACTION_API_READ_REQUEST_HDR; HttpTransact::StartRemapRequest
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1] State
Transition: SM_ACTION_UNDEFINED -> SM_ACTION_API_READ_REQUEST_HDR
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1] calling plugin
on hook TS_HTTP_READ_REQUEST_HDR_HOOK at hook 0x2ba4470
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::state_api_callback, HTTP_API_CONTINUE]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::state_api_callout, HTTP_API_CONTINUE]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1] calling plugin
on hook TS_HTTP_READ_REQUEST_HDR_HOOK at hook 0x2ba4450
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::state_api_callback, HTTP_API_CONTINUE]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::state_api_callout, HTTP_API_CONTINUE]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http_trans) START
HttpTransact::StartRemapRequest
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http_trans) Before
Remapping:
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) HTTP_HEADER
0x7fd241044898: [T: 3, L: 48, OBJFLAGS: 0]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [TYPE: REQ, V:
10001, URL: 0x7fd241044b18, METHOD: "CONNECT", METHOD_LEN: 7, FIELDS:
0x7fd2410448c8]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) URL 0x7fd241044b18:
[T: 2, L: 112, OBJFLAGS: 0]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) [URLTYPE: 0,
SWKSIDX: -1,
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) SCHEME: "",
SCHEME_LEN: 0,
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) USER: "",
USER_LEN: 0,
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) PASSWORD: "",
PASSWORD_LEN: 0,
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) HOST:
"aus4.mozilla.org", HOST_LEN: 16,
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) PORT: "443",
PORT_LEN: 3, PORT_NUM: 443
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) PATH: "",
PATH_LEN: 0,
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) PARAMS: "",
PARAMS_LEN: 0,
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) QUERY: "",
QUERY_LEN: 0,
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) FRAGMENT: "",
FRAGMENT_LEN: 0]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http) MIME_HEADER
0x7fd2410448c8: [T: 4, L: 592, OBJFLAGS: 0]
[Apr 6 10:17:06.056] Server {0x7fd243b95700} DEBUG: (http)
[PBITS: 0x0008010001000800, SLACC: 0xFFFFFFFFFFFFFFF21FFFFFFFFFF0FFFF,
HEADBLK: 0x7fd241044908, TAILBLK: 0x7fd241044908]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [CBITS:
0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) FIELD_BLOCK
0x7fd241044908: [T: 5, L: 528, OBJFLAGS: 0]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [FREETOP: 4,
NEXTBLK: (nil)]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) SLOT # 0
(0x7fd241044918), LIVE
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [N: "User-Agent",
N_LEN: 10, N_IDX: 64,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) V: "Mozilla/5.0
(Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101 Firefox/39.0", V_LEN: 72,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 86, F: 1]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) SLOT # 1
(0x7fd241044938), LIVE
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [N:
"Proxy-Connection", N_LEN: 16, N_IDX: 50,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) V: "keep-alive",
V_LEN: 10,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 30, F: 1]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) SLOT # 2
(0x7fd241044958), LIVE
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [N: "Connection",
N_LEN: 10, N_IDX: 12,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) V: "keep-alive",
V_LEN: 10,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 24, F: 1]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) SLOT # 3
(0x7fd241044978), LIVE
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [N: "Host", N_LEN:
4, N_IDX: 30,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) V:
"aus4.mozilla.org:443", V_LEN: 20,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 28, F: 1]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http_trans) END
HttpTransact::StartRemapRequest
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http_trans) Next action
SM_ACTION_API_PRE_REMAP; HttpTransact::PerformRemap
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [1] State
Transition: SM_ACTION_API_READ_REQUEST_HDR -> SM_ACTION_API_PRE_REMAP
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http_trans) Inside
PerformRemap
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http_trans) Next action
SM_ACTION_REMAP_REQUEST; HttpTransact::EndRemapRequest
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [1] State
Transition: SM_ACTION_API_PRE_REMAP -> SM_ACTION_REMAP_REQUEST
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http_seq)
[HttpSM::do_remap_request] Remapping request
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http_trans) START
HttpTransact::EndRemapRequest
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http_trans)
EndRemapRequest host is aus4.mozilla.org
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http_trans) After
Remapping:
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) HTTP_HEADER
0x7fd241044898: [T: 3, L: 48, OBJFLAGS: 0]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [TYPE: REQ, V:
10001, URL: 0x7fd241044b18, METHOD: "CONNECT", METHOD_LEN: 7, FIELDS:
0x7fd2410448c8]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) URL 0x7fd241044b18:
[T: 2, L: 112, OBJFLAGS: 0]
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) [URLTYPE: 0,
SWKSIDX: -1,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) SCHEME: "",
SCHEME_LEN: 0,
[Apr 6 10:17:06.057] Server {0x7fd243b95700} DEBUG: (http) USER: "",
USER_LEN: 0,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) PASSWORD: "",
PASSWORD_LEN: 0,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) HOST:
"aus4.mozilla.org", HOST_LEN: 16,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) PORT: "443",
PORT_LEN: 3, PORT_NUM: 443
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) PATH: "",
PATH_LEN: 0,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) PARAMS: "",
PARAMS_LEN: 0,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) QUERY: "",
QUERY_LEN: 0,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) FRAGMENT: "",
FRAGMENT_LEN: 0]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) MIME_HEADER
0x7fd2410448c8: [T: 4, L: 592, OBJFLAGS: 0]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http)
[PBITS: 0x0008010001000800, SLACC: 0xFFFFFFFFFFFFFFF21FFFFFFFFFF0FFFF,
HEADBLK: 0x7fd241044908, TAILBLK: 0x7fd241044908]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) [CBITS:
0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) FIELD_BLOCK
0x7fd241044908: [T: 5, L: 528, OBJFLAGS: 0]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) [FREETOP: 4,
NEXTBLK: (nil)]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) SLOT # 0
(0x7fd241044918), LIVE
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) [N: "User-Agent",
N_LEN: 10, N_IDX: 64,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) V: "Mozilla/5.0
(Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101 Firefox/39.0", V_LEN: 72,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 86, F: 1]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) SLOT # 1
(0x7fd241044938), LIVE
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) [N:
"Proxy-Connection", N_LEN: 16, N_IDX: 50,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) V: "keep-alive",
V_LEN: 10,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 30, F: 1]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) SLOT # 2
(0x7fd241044958), LIVE
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) [N: "Connection",
N_LEN: 10, N_IDX: 12,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) V: "keep-alive",
V_LEN: 10,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 24, F: 1]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) SLOT # 3
(0x7fd241044978), LIVE
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) [N: "Host", N_LEN:
4, N_IDX: 30,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) V:
"aus4.mozilla.org:443", V_LEN: 20,
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 28, F: 1]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http_trans) END
HttpTransact::EndRemapRequest
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http_trans) Next action
SM_ACTION_API_POST_REMAP; HttpTransact::HandleRequest
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) [1] State
Transition: SM_ACTION_REMAP_REQUEST -> SM_ACTION_API_POST_REMAP
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http_trans) START
HttpTransact::HandleRequest
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http_trans)
[init_stat_vars_from_req] set req cont length to 0
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http_trans)
[is_request_valid]no request header errors
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http_seq)
[HttpTransact::HandleRequest] request valid.
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) HTTP_HEADER
0x7fd241044898: [T: 3, L: 48, OBJFLAGS: 0]
[Apr 6 10:17:06.058] Server {0x7fd243b95700} DEBUG: (http) [TYPE: REQ, V:
10001, URL: 0x7fd241044b18, METHOD: "CONNECT", METHOD_LEN: 7, FIELDS:
0x7fd2410448c8]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) URL 0x7fd241044b18:
[T: 2, L: 112, OBJFLAGS: 0]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) [URLTYPE: 0,
SWKSIDX: -1,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) SCHEME: "",
SCHEME_LEN: 0,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) USER: "",
USER_LEN: 0,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) PASSWORD: "",
PASSWORD_LEN: 0,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) HOST:
"aus4.mozilla.org", HOST_LEN: 16,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) PORT: "443",
PORT_LEN: 3, PORT_NUM: 443
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) PATH: "",
PATH_LEN: 0,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) PARAMS: "",
PARAMS_LEN: 0,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) QUERY: "",
QUERY_LEN: 0,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) FRAGMENT: "",
FRAGMENT_LEN: 0]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) MIME_HEADER
0x7fd2410448c8: [T: 4, L: 592, OBJFLAGS: 0]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http)
[PBITS: 0x0008010001000800, SLACC: 0xFFFFFFFFFFFFFFF21FFFFFFFFFF0FFFF,
HEADBLK: 0x7fd241044908, TAILBLK: 0x7fd241044908]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) [CBITS:
0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) FIELD_BLOCK
0x7fd241044908: [T: 5, L: 528, OBJFLAGS: 0]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) [FREETOP: 4,
NEXTBLK: (nil)]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) SLOT # 0
(0x7fd241044918), LIVE
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) [N: "User-Agent",
N_LEN: 10, N_IDX: 64,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) V: "Mozilla/5.0
(Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101 Firefox/39.0", V_LEN: 72,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 86, F: 1]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) SLOT # 1
(0x7fd241044938), LIVE
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) [N:
"Proxy-Connection", N_LEN: 16, N_IDX: 50,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) V: "keep-alive",
V_LEN: 10,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 30, F: 1]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) SLOT # 2
(0x7fd241044958), LIVE
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) [N: "Connection",
N_LEN: 10, N_IDX: 12,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) V: "keep-alive",
V_LEN: 10,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 24, F: 1]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http)
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) SLOT # 3
(0x7fd241044978), LIVE
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) [N: "Host", N_LEN:
4, N_IDX: 30,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) V:
"aus4.mozilla.org:443", V_LEN: 20,
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) NEXTDUP: (nil),
RAW: 1, RAWLEN: 28, F: 1]
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http)
+++++++++ Incoming Request +++++++++
-- State Machine Id: 1
CONNECT aus4.mozilla.org:443 HTTP/1.1
User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101
Firefox/39.0
Proxy-Connection: keep-alive
Connection: keep-alive
Host: aus4.mozilla.org:443
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http_trans)
[DecideCacheLookup] Will NOT do cache lookup.
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http_seq)
[DecideCacheLookup] Will NOT do cache lookup
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http_trans) Next action
SM_ACTION_API_CACHE_LOOKUP_COMPLETE; CallOSDNSLookup
[Apr 6 10:17:06.059] Server {0x7fd243b95700} DEBUG: (http) [1] State
Transition: SM_ACTION_API_POST_REMAP -> SM_ACTION_API_CACHE_LOOKUP_COMPLETE
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans) Next action
SM_ACTION_DNS_LOOKUP; OSDNSLookup
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http) [1] State
Transition: SM_ACTION_API_CACHE_LOOKUP_COMPLETE -> SM_ACTION_DNS_LOOKUP
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_seq)
[HttpSM::do_hostdb_lookup] Doing DNS Lookup
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans)
[ink_cluster_time] local: 1428295626, highest_delta: 0, cluster: 1428295626
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans)
[HttpTransact::OSDNSLookup] This was attempt 1
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_seq)
[HttpTransact::OSDNSLookup] DNS Lookup successful
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans) [OSDNSLookup]
DNS lookup for O.S. successful IP: 63.245.217.219
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans) Next action
SM_ACTION_API_OS_DNS; LookupSkipOpenServer
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http) [1] State
Transition: SM_ACTION_DNS_LOOKUP -> SM_ACTION_API_OS_DNS
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans) request not
cacheable, so bypass parent
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans) client_ip_set
= 0
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans) inserted
request header 'Client-ip: 172.16.100.98'
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans)
[add_client_ip_to_outgoing_request] Appended connecting client's
(172.16.100.98) to the X-Forwards header
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans)
[build_request] removing host name from url
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans)
[ink_cluster_time] local: 1428295626, highest_delta: 0, cluster: 1428295626
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans)
[build_request] request_sent_time: 1428295626
+++++++++ Proxy's Request +++++++++
-- State Machine Id: 1
CONNECT HTTP/1.1
User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:39.0) Gecko/20100101
Firefox/39.0
Host: aus4.mozilla.org:443
Client-ip: 172.16.100.98
X-Forwarded-For: 172.16.100.98
Via: http/1.1 <proxy_name>[0A0A140A] (ApacheTrafficServer/5.3.0)
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_trans) Next action
next; HttpTransact::HandleResponse
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http) [1] State
Transition: SM_ACTION_API_OS_DNS -> SM_ACTION_ORIGIN_SERVER_RAW_OPEN
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_track) entered
inside do_http_server_open ][IPv4]
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http) [1] open connection
to aus4.mozilla.org: 63.245.217.219:443
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http_seq)
[HttpSM::do_http_server_open] Sending request to server
[Apr 6 10:17:06.060] Server {0x7fd243b95700} DEBUG: (http) calling
netProcessor.connect_s
[Apr 6 10:17:06.322] Server {0x7fd243b95700} DEBUG: (http) [1]
[HttpSM::main_handler, NET_EVENT_OPEN]
[Apr 6 10:17:06.322] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::state_raw_http_server_open, NET_EVENT_OPEN]
[Apr 6 10:17:06.322] Server {0x7fd243b95700} DEBUG: (http_trans)
[HttpTransact::OriginServerRawOpen]
[Apr 6 10:17:06.322] Server {0x7fd243b95700} DEBUG: (http_trans) [Squid code
generation] Hit/Miss: 49, Log: 51, Hier: 50
[Apr 6 10:17:06.322] Server {0x7fd243b95700} DEBUG: (http_trans) Adding
Server: ATS/5.3.0
+++++++++ Proxy's Response 2 +++++++++
-- State Machine Id: 1
HTTP/1.1 200 OK
Date: Mon, 06 Apr 2015 04:47:06 GMT
Proxy-Connection: close
Server: ATS/5.3.0
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http_trans)
[OriginServerRawOpen] connection alive. next action is ssl_tunnel
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http) [1] State
Transition: SM_ACTION_ORIGIN_SERVER_RAW_OPEN -> SM_ACTION_SSL_TUNNEL
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1] adding
producer 'http server - tunnel'
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1] adding
consumer 'user agent - tunnel'
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1] adding
producer 'user agent - tunnel'
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1] adding
consumer 'http server - tunnel'
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http_tunnel) tunnel_run
started, p_arg is NULL
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http_cs)
tcp_init_cwnd_set 0
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http_cs) desired TCP
congestion window is 0
[Apr 6 10:17:06.323] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
consumer_handler [user agent - tunnel VC_EVENT_WRITE_READY]
[Apr 6 10:17:06.326] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
producer_handler [user agent - tunnel VC_EVENT_READ_READY]
[Apr 6 10:17:06.326] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::producer_handler] enable_redirection: [1 0 1] event: 100
[Apr 6 10:17:06.326] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
consumer_handler [http server - tunnel VC_EVENT_WRITE_READY]
[Apr 6 10:17:06.602] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
producer_handler [http server - tunnel VC_EVENT_READ_READY]
[Apr 6 10:17:06.602] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::producer_handler] enable_redirection: [1 0 1] event: 100
[Apr 6 10:17:06.602] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
consumer_handler [user agent - tunnel VC_EVENT_WRITE_READY]
[Apr 6 10:17:06.613] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
producer_handler [user agent - tunnel VC_EVENT_READ_READY]
[Apr 6 10:17:06.613] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::producer_handler] enable_redirection: [1 0 1] event: 100
[Apr 6 10:17:06.613] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
consumer_handler [http server - tunnel VC_EVENT_WRITE_READY]
[Apr 6 10:17:06.614] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
producer_handler [user agent - tunnel VC_EVENT_READ_READY]
[Apr 6 10:17:06.614] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::producer_handler] enable_redirection: [1 0 1] event: 100
[Apr 6 10:17:06.614] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
consumer_handler [http server - tunnel VC_EVENT_WRITE_READY]
[Apr 6 10:17:07.197] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
producer_handler [http server - tunnel VC_EVENT_READ_READY]
[Apr 6 10:17:07.197] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::producer_handler] enable_redirection: [1 0 1] event: 100
[Apr 6 10:17:07.197] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
consumer_handler [user agent - tunnel VC_EVENT_WRITE_READY]
[Apr 6 10:17:12.199] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
producer_handler [user agent - tunnel VC_EVENT_READ_READY]
[Apr 6 10:17:12.199] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::producer_handler] enable_redirection: [1 0 1] event: 100
[Apr 6 10:17:12.199] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
producer_handler [user agent - tunnel VC_EVENT_EOS]
[Apr 6 10:17:12.199] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::producer_handler] enable_redirection: [1 0 1] event: 104
[Apr 6 10:17:12.199] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::tunnel_handler_ssl_producer, VC_EVENT_EOS]
[Apr 6 10:17:12.199] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
consumer_handler [http server - tunnel VC_EVENT_WRITE_COMPLETE]
[Apr 6 10:17:12.200] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::tunnel_handler_ssl_consumer, VC_EVENT_WRITE_COMPLETE]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
producer_handler [http server - tunnel VC_EVENT_EOS]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::producer_handler] enable_redirection: [1 0 0] event: 104
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::tunnel_handler_ssl_producer, VC_EVENT_EOS]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http_tunnel) [1]
consumer_handler [user agent - tunnel VC_EVENT_WRITE_COMPLETE]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::tunnel_handler_ssl_consumer, VC_EVENT_WRITE_COMPLETE]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http_cs) [1] session
closed
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http_cs) [1] session
destroy
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http) [1]
[HttpSM::main_handler, HTTP_TUNNEL_EVENT_DONE]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::tunnel_handler, HTTP_TUNNEL_EVENT_DONE]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::deallocate_postdata_copy_buffers]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http_redirect)
[HttpTunnel::deallocate_postdata_copy_buffers]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http) [1]
[&HttpSM::state_remove_from_list, VC_EVENT_NONE]
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http_seq)
[HttpSM::update_stats] Logging transaction
[Apr 6 10:17:12.453] Server {0x7fd243b95700} DEBUG: (http) [1] deallocating sm
