Created
October 4, 2015 15:10
-
-
Save GUI/0c53a6c4fdc2782b14aa to your computer and use it in GitHub Desktop.
TrafficServer Keep-Alive Handling
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
[Oct 3 22:43:13.298] Server {0x2b5061dbd480} DEBUG: (http_cs) [129] [&HttpClientSession::state_keep_alive, VC_EVENT_READ_READY] | |
[Oct 3 22:43:13.298] Server {0x2b5061dbd480} DEBUG: (http_cs) [129] Starting transaction 7 using sm [454] | |
[Oct 3 22:43:13.298] Server {0x2b5061dbd480} DEBUG: (http) [454] [HttpSM::main_handler, VC_EVENT_READ_READY] | |
[Oct 3 22:43:13.298] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_read_client_request_header, VC_EVENT_READ_READY] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [454] done parsing client request header | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) START HttpTransact::ModifyRequest | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) [ink_cluster_time] local: 1443912193, highest_delta: 0, cluster: 1443912193 | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) END HttpTransact::ModifyRequest | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) Checking if transaction wants to upgrade | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) Next action SM_ACTION_API_READ_REQUEST_HDR; HttpTransact::StartRemapRequest | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_UNDEFINED -> SM_ACTION_API_READ_REQUEST_HDR | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [454] calling plugin on hook TS_HTTP_READ_REQUEST_HDR_HOOK at hook 0x25bc250 | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) START HttpTransact::StartRemapRequest | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) Before Remapping: | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) HTTP_HEADER 0x2b506b181898: [T: 3, L: 48, OBJFLAGS: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [TYPE: REQ, V: 10001, URL: 0x2b506b181b18, METHOD: "GET", METHOD_LEN: 3, FIELDS: 0x2b506b1818c8] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) URL 0x2b506b181b18: [T: 2, L: 112, OBJFLAGS: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [URLTYPE: 1, SWKSIDX: 99, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SCHEME: "http", SCHEME_LEN: 4, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) USER: "", USER_LEN: 0, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) PASSWORD: "", PASSWORD_LEN: 0, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) HOST: "", HOST_LEN: 0, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) PORT: "", PORT_LEN: 0, PORT_NUM: 0 | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) PATH: "hello", PATH_LEN: 5, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) PARAMS: "", PARAMS_LEN: 0, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) QUERY: "", QUERY_LEN: 0, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) FRAGMENT: "", FRAGMENT_LEN: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) MIME_HEADER 0x2b506b1818c8: [T: 4, L: 592, OBJFLAGS: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[PBITS: 0x0000000001000000, SLACC: 0xFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF, HEADBLK: 0x2b506b181908, TAILBLK: 0x2b506b181908] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [CBITS: 0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) FIELD_BLOCK 0x2b506b181908: [T: 5, L: 528, OBJFLAGS: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [FREETOP: 9, NEXTBLK: (nil)] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 0 (0x2b506b181918), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "Host", N_LEN: 4, N_IDX: 30, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "localhost:9080", V_LEN: 14, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 22, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 1 (0x2b506b181938), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Forwarded-For", N_LEN: 15, N_IDX: 127, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "127.0.0.1", V_LEN: 9, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 28, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 2 (0x2b506b181958), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Forwarded-Proto", N_LEN: 17, N_IDX: -1, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "http", V_LEN: 4, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 25, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 3 (0x2b506b181978), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Forwarded-Port", N_LEN: 16, N_IDX: -1, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "9080", V_LEN: 4, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 24, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 4 (0x2b506b181998), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Request-ID", N_LEN: 25, N_IDX: -1, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "ag5vfpa4k4uaap6bor30", V_LEN: 20, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 49, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 5 (0x2b506b1819b8), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Backend-Scheme", N_LEN: 29, N_IDX: -1, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "http", V_LEN: 4, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 37, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 6 (0x2b506b1819d8), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Backend-Host", N_LEN: 27, N_IDX: -1, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "localhost", V_LEN: 9, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 40, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 7 (0x2b506b1819f8), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Backend-Id", N_LEN: 25, N_IDX: -1, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "db-config", V_LEN: 9, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 38, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 8 (0x2b506b181a18), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-User-Id", N_LEN: 13, N_IDX: -1, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "60223455-2f78-4c81-9841-aaa891682fb2", V_LEN: 36, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 53, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) END HttpTransact::StartRemapRequest | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) Next action SM_ACTION_API_PRE_REMAP; HttpTransact::PerformRemap | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_API_READ_REQUEST_HDR -> SM_ACTION_API_PRE_REMAP | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) Inside PerformRemap | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) Next action SM_ACTION_REMAP_REQUEST; HttpTransact::EndRemapRequest | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_API_PRE_REMAP -> SM_ACTION_REMAP_REQUEST | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpSM::do_remap_request] Remapping request | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) START HttpTransact::EndRemapRequest | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) EndRemapRequest host is 127.0.0.1 | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http_trans) After Remapping: | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) HTTP_HEADER 0x2b506b181898: [T: 3, L: 48, OBJFLAGS: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [TYPE: REQ, V: 10001, URL: 0x2b506b181b18, METHOD: "GET", METHOD_LEN: 3, FIELDS: 0x2b506b1818c8] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) URL 0x2b506b181b18: [T: 2, L: 112, OBJFLAGS: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [URLTYPE: 1, SWKSIDX: 99, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SCHEME: "http", SCHEME_LEN: 4, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) USER: "", USER_LEN: 0, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) PASSWORD: "", PASSWORD_LEN: 0, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) HOST: "127.0.0.1", HOST_LEN: 9, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) PORT: "13011", PORT_LEN: 5, PORT_NUM: 13011 | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) PATH: "hello", PATH_LEN: 5, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) PARAMS: "", PARAMS_LEN: 0, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) QUERY: "", QUERY_LEN: 0, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) FRAGMENT: "", FRAGMENT_LEN: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) MIME_HEADER 0x2b506b1818c8: [T: 4, L: 592, OBJFLAGS: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[PBITS: 0x0000000001000000, SLACC: 0xFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF, HEADBLK: 0x2b506b181908, TAILBLK: 0x2b506b181908] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [CBITS: 0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) FIELD_BLOCK 0x2b506b181908: [T: 5, L: 528, OBJFLAGS: 0] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [FREETOP: 9, NEXTBLK: (nil)] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 0 (0x2b506b181918), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "Host", N_LEN: 4, N_IDX: 30, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) V: "127.0.0.1:13011", V_LEN: 15, | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 0, RAWLEN: 23, F: 1] | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 1 (0x2b506b181938), LIVE | |
[Oct 3 22:43:13.299] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Forwarded-For", N_LEN: 15, N_IDX: 127, | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) V: "127.0.0.1", V_LEN: 9, | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 28, F: 1] | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 2 (0x2b506b181958), LIVE | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Forwarded-Proto", N_LEN: 17, N_IDX: -1, | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) V: "http", V_LEN: 4, | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 25, F: 1] | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 3 (0x2b506b181978), LIVE | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Forwarded-Port", N_LEN: 16, N_IDX: -1, | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) V: "9080", V_LEN: 4, | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 24, F: 1] | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 4 (0x2b506b181998), LIVE | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Request-ID", N_LEN: 25, N_IDX: -1, | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) V: "ag5vfpa4k4uaap6bor30", V_LEN: 20, | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 49, F: 1] | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 5 (0x2b506b1819b8), LIVE | |
[Oct 3 22:43:13.300] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Backend-Scheme", N_LEN: 29, N_IDX: -1, | |
[Oct 3 22:43:13.300] Server {0x2b506283a700} DEBUG: (http_ss) [129] [session_pool] session 0x28f05b0 received io notice [VC_EVENT_EOS] | |
[Oct 3 22:43:13.300] Server {0x2b506283a700} DEBUG: (http_ss) [129] session closing, netvc 0x2b507c015dc0 | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) V: "http", V_LEN: 4, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 37, F: 1] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 6 (0x2b506b1819d8), LIVE | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Backend-Host", N_LEN: 27, N_IDX: -1, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) V: "localhost", V_LEN: 9, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 40, F: 1] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 7 (0x2b506b1819f8), LIVE | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Backend-Id", N_LEN: 25, N_IDX: -1, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) V: "db-config", V_LEN: 9, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 38, F: 1] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 8 (0x2b506b181a18), LIVE | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-User-Id", N_LEN: 13, N_IDX: -1, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) V: "60223455-2f78-4c81-9841-aaa891682fb2", V_LEN: 36, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 53, F: 1] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http_trans) END HttpTransact::EndRemapRequest | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http_trans) Next action SM_ACTION_API_POST_REMAP; HttpTransact::HandleRequest | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_REMAP_REQUEST -> SM_ACTION_API_POST_REMAP | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [454] calling plugin on hook TS_HTTP_POST_REMAP_HOOK at hook 0x25bc270 | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http_trans) START HttpTransact::HandleRequest | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http_trans) [init_stat_vars_from_req] set req cont length to 0 | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http_trans) [is_request_valid]no request header errors | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpTransact::HandleRequest] request valid. | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) HTTP_HEADER 0x2b506b181898: [T: 3, L: 48, OBJFLAGS: 0] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [TYPE: REQ, V: 10001, URL: 0x2b506b181b18, METHOD: "GET", METHOD_LEN: 3, FIELDS: 0x2b506b1818c8] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) URL 0x2b506b181b18: [T: 2, L: 112, OBJFLAGS: 0] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [URLTYPE: 1, SWKSIDX: 99, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) SCHEME: "http", SCHEME_LEN: 4, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) USER: "", USER_LEN: 0, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) PASSWORD: "", PASSWORD_LEN: 0, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) HOST: "127.0.0.1", HOST_LEN: 9, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) PORT: "13011", PORT_LEN: 5, PORT_NUM: 13011 | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) PATH: "hello", PATH_LEN: 5, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) PARAMS: "", PARAMS_LEN: 0, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) QUERY: "", QUERY_LEN: 0, | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) FRAGMENT: "", FRAGMENT_LEN: 0] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) MIME_HEADER 0x2b506b1818c8: [T: 4, L: 592, OBJFLAGS: 0] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) | |
[PBITS: 0x0000000001000000, SLACC: 0xFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF, HEADBLK: 0x2b506b181908, TAILBLK: 0x2b506b181908] | |
[Oct 3 22:43:13.301] Server {0x2b506283a700} DEBUG: (http_cs) [130] [&HttpClientSession::state_keep_alive, VC_EVENT_EOS] | |
[Oct 3 22:43:13.301] Server {0x2b5061dbd480} DEBUG: (http) [CBITS: 0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) FIELD_BLOCK 0x2b506b181908: [T: 5, L: 528, OBJFLAGS: 0] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [FREETOP: 9, NEXTBLK: (nil)] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 0 (0x2b506b181918), LIVE | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [N: "Host", N_LEN: 4, N_IDX: 30, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) V: "127.0.0.1:13011", V_LEN: 15, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 0, RAWLEN: 23, F: 1] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 1 (0x2b506b181938), LIVE | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Forwarded-For", N_LEN: 15, N_IDX: 127, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) V: "127.0.0.1", V_LEN: 9, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 28, F: 1] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 2 (0x2b506b181958), LIVE | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Forwarded-Proto", N_LEN: 17, N_IDX: -1, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) V: "http", V_LEN: 4, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 25, F: 1] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 3 (0x2b506b181978), LIVE | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Forwarded-Port", N_LEN: 16, N_IDX: -1, | |
[Oct 3 22:43:13.302] Server {0x2b506283a700} DEBUG: (http_cs) [130] session closed | |
[Oct 3 22:43:13.302] Server {0x2b506283a700} DEBUG: (http_cs) [130] session destroy | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) V: "9080", V_LEN: 4, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 24, F: 1] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 4 (0x2b506b181998), LIVE | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Request-ID", N_LEN: 25, N_IDX: -1, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) V: "ag5vfpa4k4uaap6bor30", V_LEN: 20, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 49, F: 1] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 5 (0x2b506b1819b8), LIVE | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Backend-Scheme", N_LEN: 29, N_IDX: -1, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) V: "http", V_LEN: 4, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 37, F: 1] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 6 (0x2b506b1819d8), LIVE | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Backend-Host", N_LEN: 27, N_IDX: -1, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) V: "localhost", V_LEN: 9, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 40, F: 1] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 7 (0x2b506b1819f8), LIVE | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-Umbrella-Backend-Id", N_LEN: 25, N_IDX: -1, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) V: "db-config", V_LEN: 9, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 38, F: 1] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) SLOT # 8 (0x2b506b181a18), LIVE | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [N: "X-Api-User-Id", N_LEN: 13, N_IDX: -1, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) V: "60223455-2f78-4c81-9841-aaa891682fb2", V_LEN: 36, | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 53, F: 1] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) | |
+++++++++ Incoming Request +++++++++ | |
-- State Machine Id: 454 | |
GET http://127.0.0.1:13011/hello HTTP/1.1 | |
Host: 127.0.0.1:13011 | |
X-Forwarded-For: 127.0.0.1 | |
X-Forwarded-Proto: http | |
X-Forwarded-Port: 9080 | |
X-Api-Umbrella-Request-ID: ag5vfpa4k4uaap6bor30 | |
X-Api-Umbrella-Backend-Scheme: http | |
X-Api-Umbrella-Backend-Host: localhost | |
X-Api-Umbrella-Backend-Id: db-config | |
X-Api-User-Id: 60223455-2f78-4c81-9841-aaa891682fb2 | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) [DecideCacheLookup] Will do cache lookup. | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_seq) [DecideCacheLookup] Will do cache lookup | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) Next action SM_ACTION_CACHE_LOOKUP; __null | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_API_POST_REMAP -> SM_ACTION_CACHE_LOOKUP | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpSM::do_cache_lookup_and_read] [454] Issuing cache lookup for URL http://127.0.0.1:13011/hello | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_cache) [454] [&HttpCacheSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [454] [HttpSM::main_handler, CACHE_EVENT_OPEN_READ_FAILED] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [454] cache_open_read - CACHE_EVENT_OPEN_READ_FAILED | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [state_cache_open_read] open read failed. | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) [HttpTransact::HandleCacheOpenRead] | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) CacheOpenRead -- miss | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) Next action SM_ACTION_DNS_LOOKUP; OSDNSLookup | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_CACHE_LOOKUP -> SM_ACTION_DNS_LOOKUP | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (dns) [HttpTransact::HandleRequest] Skipping DNS lookup for 127.0.0.1 because it's loopback | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) [HttpTransact::OSDNSLookup] This was attempt 1 | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpTransact::OSDNSLookup] DNS Lookup successful | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) [OSDNSLookup] DNS lookup for O.S. successful IP: 127.0.0.1 | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) Next action SM_ACTION_API_OS_DNS; HandleCacheOpenReadMiss | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_DNS_LOOKUP -> SM_ACTION_API_OS_DNS | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) [HandleCacheOpenReadMiss] --- MISS | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpTransact::HandleCacheOpenReadMiss] Miss in cache | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) [build_request] removing host name from url | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) [build_request] request like cacheable and conditional headers removed | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) [ink_cluster_time] local: 1443912193, highest_delta: 0, cluster: 1443912193 | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http_trans) [build_request] request_sent_time: 1443912193 | |
+++++++++ Proxy's Request +++++++++ | |
-- State Machine Id: 454 | |
GET /hello HTTP/1.1 | |
Host: 127.0.0.1:13011 | |
X-Forwarded-For: 127.0.0.1 | |
X-Forwarded-Proto: http | |
X-Forwarded-Port: 9080 | |
X-Api-Umbrella-Request-ID: ag5vfpa4k4uaap6bor30 | |
X-Api-Umbrella-Backend-Scheme: http | |
X-Api-Umbrella-Backend-Host: localhost | |
X-Api-Umbrella-Backend-Id: db-config | |
X-Api-User-Id: 60223455-2f78-4c81-9841-aaa891682fb2 | |
Via: http/1.1 api.vagrant[0A00020F] (ApacheTrafficServer/5.3.1) | |
[Oct 3 22:43:13.302] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_API_OS_DNS -> SM_ACTION_CACHE_ISSUE_WRITE | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_cache_write) [454] writing to cache with URL http://127.0.0.1:13011/hello | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_cache) [454] [&HttpCacheSM::state_cache_open_write, CACHE_EVENT_OPEN_WRITE] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [HttpSM::main_handler, CACHE_EVENT_OPEN_WRITE] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM : state_cache_open_write, CACHE_EVENT_OPEN_WRITE] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_trans) Next action next; __null | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_CACHE_ISSUE_WRITE -> SM_ACTION_ORIGIN_SERVER_OPEN | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_track) entered inside do_http_server_open ][IPv4] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] open connection to 127.0.0.1: 127.0.0.1:13011 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpSM::do_http_server_open] Sending request to server | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_ss) [128] [acquire session] return session from shared pool | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] calling plugin on hook TS_HTTP_SEND_REQUEST_HDR_HOOK at hook 0x25bc230 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
+++++++++ Proxy's Request after hooks +++++++++ | |
-- State Machine Id: 454 | |
GET /hello HTTP/1.1 | |
Host: 127.0.0.1:13011 | |
X-Forwarded-For: 127.0.0.1 | |
X-Forwarded-Proto: http | |
X-Forwarded-Port: 9080 | |
X-Api-Umbrella-Request-ID: ag5vfpa4k4uaap6bor30 | |
X-Api-Umbrella-Backend-Scheme: http | |
X-Api-Umbrella-Backend-Host: localhost | |
X-Api-Umbrella-Backend-Id: db-config | |
X-Api-User-Id: 60223455-2f78-4c81-9841-aaa891682fb2 | |
Via: http/1.1 api.vagrant[0A00020F] (ApacheTrafficServer/5.3.1) | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [HttpSM::main_handler, VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_send_server_request_header, VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [HttpSM::main_handler, VC_EVENT_EOS] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_read_server_response_header, VC_EVENT_EOS] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::handle_server_setup_error, VC_EVENT_EOS] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_ss) [128] session closing, netvc 0x2b507c016340 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_trans) [HttpTransact::HandleResponse] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpTransact::HandleResponse] Response received | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_trans) [ink_cluster_time] local: 1443912193, highest_delta: 0, cluster: 1443912193 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_trans) [HandleResponse] response_received_time: 1443912193 | |
+++++++++ Incoming O.S. Response +++++++++ | |
-- State Machine Id: 454 | |
HTTP/0.9 0 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpTransact::HandleResponse] Response not valid | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_trans) [handle_response_from_server] (hrfs) | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_trans) [1] failed to connect [4] to 127.0.0.1 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_trans) [retry_server_connection_not_open] attempts now: 2, max: 3 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_trans) [handle_response_from_server] Error. Retrying... | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_ORIGIN_SERVER_OPEN -> SM_ACTION_ORIGIN_SERVER_OPEN | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_track) entered inside do_http_server_open ][IPv4] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] open connection to 127.0.0.1: 127.0.0.1:13011 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpSM::do_http_server_open] Sending request to server | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) calling netProcessor.connect_re | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [HttpSM::main_handler, NET_EVENT_OPEN] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_track) entered inside state_http_server_open | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_http_server_open, NET_EVENT_OPEN] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http_ss) [130] session born, netvc 0x2b507c014d40 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] calling plugin on hook TS_HTTP_SEND_REQUEST_HDR_HOOK at hook 0x25bc230 | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
+++++++++ Proxy's Request after hooks +++++++++ | |
-- State Machine Id: 454 | |
GET /hello HTTP/1.1 | |
Host: 127.0.0.1:13011 | |
X-Forwarded-For: 127.0.0.1 | |
X-Forwarded-Proto: http | |
X-Forwarded-Port: 9080 | |
X-Api-Umbrella-Request-ID: ag5vfpa4k4uaap6bor30 | |
X-Api-Umbrella-Backend-Scheme: http | |
X-Api-Umbrella-Backend-Host: localhost | |
X-Api-Umbrella-Backend-Id: db-config | |
X-Api-User-Id: 60223455-2f78-4c81-9841-aaa891682fb2 | |
Via: http/1.1 api.vagrant[0A00020F] (ApacheTrafficServer/5.3.1) | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [HttpSM::main_handler, VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 22:43:13.303] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_send_server_request_header, VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [HttpSM::main_handler, VC_EVENT_READ_READY] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_read_server_response_header, VC_EVENT_READ_READY] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_seq) Done parsing server response header | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] calling plugin on hook TS_HTTP_READ_RESPONSE_HDR_HOOK at hook 0x25bc210 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [HttpTransact::HandleResponse] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpTransact::HandleResponse] Response received | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [ink_cluster_time] local: 1443912193, highest_delta: 0, cluster: 1443912193 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [HandleResponse] response_received_time: 1443912193 | |
+++++++++ Incoming O.S. Response +++++++++ | |
-- State Machine Id: 454 | |
HTTP/1.1 200 OK | |
Server: openresty | |
Date: Sat, 03 Oct 2015 22:43:13 GMT | |
Transfer-Encoding: chunked | |
Connection: keep-alive | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [is_response_valid] No errors in response | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpTransact::HandleResponse] Response valid | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_hdrs) [initialize_state_variables_from_response]Server is keep-alive. | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_hdrs) [init_state_vars_from_resp] transfer encoding: chunked! | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [handle_response_from_server] (hrfs) | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [hrfs] connection alive | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [handle_forward_server_connection_open] (hfsco) | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpTransact::handle_server_connection_open] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) server info = 127.0.0.1:13011 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [hfsco] cache action: CACHE_DO_WRITE | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [handle_cache_operation_on_forward_server_response] (hcoofsr) | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_seq) [handle_cache_operation_on_forward_server_response] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [is_response_cacheable] client permits storing | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [is_response_cacheable] expires header or max-age is required | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [hcoofsr] response is not cacheable | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [hcoofsr] response code: 200 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] age_value: 0 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] date_value: 1443912193 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] response_time: 1443912193 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] now: 1443912193 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] now (fixed): 1443912193 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] apparent_age: 0 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] corrected_received_age: 0 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] response_delay: 0 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] corrected_initial_age: 0 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] resident_time: 0 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_age) [calculate_document_age] current_age: 0 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_trans) [Squid code generation] Hit/Miss: 1, Log: 3, Hier: 2 | |
+++++++++ Base Header for Building Response +++++++++ | |
-- State Machine Id: 454 | |
HTTP/1.1 200 OK | |
Server: openresty | |
Date: Sat, 03 Oct 2015 22:43:13 GMT | |
Connection: keep-alive | |
+++++++++ Proxy's Response 2 +++++++++ | |
-- State Machine Id: 454 | |
HTTP/1.1 200 OK | |
Server: openresty | |
Date: Sat, 03 Oct 2015 22:43:13 GMT | |
Age: 0 | |
Transfer-Encoding: chunked | |
Connection: keep-alive | |
Via: http/1.1 api.vagrant (ApacheTrafficServer/5.3.1 [cMsSf ]) | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] State Transition: SM_ACTION_ORIGIN_SERVER_OPEN -> SM_ACTION_SERVER_READ | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_redirect) is_redirect_required 0 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] calling plugin on hook TS_HTTP_SEND_RESPONSE_HDR_HOOK at hook 0x25bc1f0 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_redirect) [HttpSM::do_redirect] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) Setup Server Transfer | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_tunnel) [454] adding producer 'http server' | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_tunnel) [454] adding consumer 'user agent' | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] perform_cache_write_action CACHE_DO_NO_ACTION | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_tunnel) tunnel_run started, p_arg is provided | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_cs) tcp_init_cwnd_set 1 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_tunnel) [producer_run] do_dechunking p->chunked_handler.chunked_reader->read_avail() = 220 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_tunnel) [producer_run] do_dechunking p->chunked_handler.skip_bytes = 199 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_tunnel) [454] producer_handler [http server VC_EVENT_READ_READY] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_tunnel) [454] producer_handler_chunked [http server VC_EVENT_READ_READY] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_chunk) read chunk size of 11 bytes | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_chunk) completed read of chunk of 11 bytes | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_chunk) read chunk size of 0 bytes | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_chunk) completed read of trailers | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_redirect) [HttpTunnel::producer_handler] enable_redirection: [1 0 0] event: 102 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::tunnel_handler_server, VC_EVENT_READ_COMPLETE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_ss) Releasing session, private_session=0, sharing_match=1 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_ss) [130] [release session] session placed into shared pool | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_ss) [130] [session_pool] session 0x28f0690 received io notice [VC_EVENT_EOS] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_ss) [130] session closing, netvc 0x2b507c014d40 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_tunnel) [454] consumer_handler [user agent VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::tunnel_handler_ua, VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_cs) [129] session released by sm [454] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_cs) tcp_init_cwnd_set 1 | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_cs) [129] initiating io for next header | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [HttpSM::main_handler, HTTP_TUNNEL_EVENT_DONE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http) [454] [&HttpSM::tunnel_handler, HTTP_TUNNEL_EVENT_DONE] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers] | |
[Oct 3 22:43:13.809] Server {0x2b5061dbd480} DEBUG: (http_seq) [HttpSM::update_stats] Logging transaction | |
[Oct 3 22:43:13.810] Server {0x2b5061dbd480} DEBUG: (http) [454] deallocating sm | |
[Oct 3 22:43:13.810] Server {0x2b5061dbd480} DEBUG: (http_cs) [129] [&HttpClientSession::state_keep_alive, VC_EVENT_EOS] | |
[Oct 3 22:43:13.810] Server {0x2b5061dbd480} DEBUG: (http_cs) [129] session closed | |
[Oct 3 22:43:13.810] Server {0x2b5061dbd480} DEBUG: (http_cs) [129] session destroy |
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpClientSession.cc:409 (state_keep_alive)> (http_cs) [68] [&HttpClientSession::state_keep_alive, VC_EVENT_READ_READY] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpClientSession.cc:139 (new_transaction)> (http_cs) [68] Starting transaction 4 using sm [176] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2525 (main_handler)> (http) [176] [HttpSM::main_handler, VC_EVENT_READ_READY] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:574 (state_read_client_request_header)> (http) [176] [&HttpSM::state_read_client_request_header, VC_EVENT_READ_READY] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:719 (state_read_client_request_header)> (http) [176] done parsing client request header | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1087 (ModifyRequest)> (http_trans) START HttpTransact::ModifyRequest | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:8337 (ink_cluster_time)> (http_trans) [ink_cluster_time] local: 1443914784, highest_delta: 0, cluster: 1443914784 | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1178 (ModifyRequest)> (http_trans) END HttpTransact::ModifyRequest | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1180 (ModifyRequest)> (http_trans) Checking if transaction wants to upgrade | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1187 (ModifyRequest)> (http_trans) Next action SM_ACTION_API_READ_REQUEST_HDR; HttpTransact::StartRemapRequest | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_UNDEFINED -> SM_ACTION_API_READ_REQUEST_HDR | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1369 (state_api_callout)> (http) [176] calling plugin on hook TS_HTTP_READ_REQUEST_HDR_HOOK at hook 0x2a29060 | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1264 (state_api_callback)> (http) [176] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1304 (state_api_callout)> (http) [176] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:693 (StartRemapRequest)> (http_trans) START HttpTransact::StartRemapRequest | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:734 (StartRemapRequest)> (http_trans) Before Remapping: | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) HTTP_HEADER 0x2ad8818c3898: [T: 3, L: 48, OBJFLAGS: 0] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HTTP.cc:577 (http_hdr_describe)> (http) [TYPE: REQ, V: 10001, URL: 0x2ad8818c3b18, METHOD: "GET", METHOD_LEN: 3, FIELDS: 0x2ad8818c38c8] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) URL 0x2ad8818c3b18: [T: 2, L: 112, OBJFLAGS: 0] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1529 (url_describe)> (http) [URLTYPE: 1, SWKSIDX: 99, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1531 (url_describe)> (http) SCHEME: "http", SCHEME_LEN: 4, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1533 (url_describe)> (http) USER: "", USER_LEN: 0, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1535 (url_describe)> (http) PASSWORD: "", PASSWORD_LEN: 0, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1537 (url_describe)> (http) HOST: "", HOST_LEN: 0, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1539 (url_describe)> (http) PORT: "", PORT_LEN: 0, PORT_NUM: 0 | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1541 (url_describe)> (http) PATH: "hello", PATH_LEN: 5, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1543 (url_describe)> (http) PARAMS: "", PARAMS_LEN: 0, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1545 (url_describe)> (http) QUERY: "", QUERY_LEN: 0, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <URL.cc:1547 (url_describe)> (http) FRAGMENT: "", FRAGMENT_LEN: 0] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) MIME_HEADER 0x2ad8818c38c8: [T: 4, L: 592, OBJFLAGS: 0] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2602 (mime_hdr_describe)> (http) | |
[PBITS: 0x0000000001000000, SLACC: 0xFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF, HEADBLK: 0x2ad8818c3908, TAILBLK: 0x2ad8818c3908] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2607 (mime_hdr_describe)> (http) [CBITS: 0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) FIELD_BLOCK 0x2ad8818c3908: [T: 5, L: 528, OBJFLAGS: 0] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2622 (mime_field_block_describe)> (http) [FREETOP: 9, NEXTBLK: (nil)] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 0 (0x2ad8818c3918), LIVE | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "Host", N_LEN: 4, N_IDX: 30, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "localhost:9080", V_LEN: 14, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 22, F: 1] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 1 (0x2ad8818c3938), LIVE | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Forwarded-For", N_LEN: 15, N_IDX: 127, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "127.0.0.1", V_LEN: 9, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 28, F: 1] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 2 (0x2ad8818c3958), LIVE | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Forwarded-Proto", N_LEN: 17, N_IDX: -1, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "http", V_LEN: 4, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 25, F: 1] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 3 (0x2ad8818c3978), LIVE | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Forwarded-Port", N_LEN: 16, N_IDX: -1, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "9080", V_LEN: 4, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 24, F: 1] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 4 (0x2ad8818c3998), LIVE | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Request-ID", N_LEN: 25, N_IDX: -1, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "ag603hu0d77nhli9s66g", V_LEN: 20, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 49, F: 1] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 5 (0x2ad8818c39b8), LIVE | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Backend-Scheme", N_LEN: 29, N_IDX: -1, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "http", V_LEN: 4, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 37, F: 1] | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 6 (0x2ad8818c39d8), LIVE | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Backend-Host", N_LEN: 27, N_IDX: -1, | |
[Oct 3 23:26:24.513] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "localhost", V_LEN: 9, | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 40, F: 1] | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 7 (0x2ad8818c39f8), LIVE | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Backend-Id", N_LEN: 25, N_IDX: -1, | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "db-config", V_LEN: 9, | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 38, F: 1] | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 8 (0x2ad8818c3a18), LIVE | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-User-Id", N_LEN: 13, N_IDX: -1, | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "5300e8ae-912e-4056-ae5f-b5e41a32aa86", V_LEN: 36, | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 53, F: 1] | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:746 (StartRemapRequest)> (http_trans) END HttpTransact::StartRemapRequest | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:747 (StartRemapRequest)> (http_trans) Next action SM_ACTION_API_PRE_REMAP; HttpTransact::PerformRemap | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_API_READ_REQUEST_HDR -> SM_ACTION_API_PRE_REMAP | |
[Oct 3 23:26:24.514] Server {0x2ad8649a5700} DEBUG: <HttpSessionManager.cc:191 (eventHandler)> (http_ss) [66] [session_pool] session 0x2ad87c047e90 received io notice [VC_EVENT_EOS] | |
[Oct 3 23:26:24.514] Server {0x2ad8649a5700} DEBUG: <HttpServerSession.cc:121 (do_io_close)> (http_ss) [66] session closing, netvc 0x2ad884016780 | |
[Oct 3 23:26:24.514] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:753 (PerformRemap)> (http_trans) Inside PerformRemap | |
[Oct 3 23:26:24.515] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:754 (PerformRemap)> (http_trans) Next action SM_ACTION_REMAP_REQUEST; HttpTransact::EndRemapRequest | |
[Oct 3 23:26:24.515] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_API_PRE_REMAP -> SM_ACTION_REMAP_REQUEST | |
[Oct 3 23:26:24.515] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:3886 (do_remap_request)> (http_seq) [HttpSM::do_remap_request] Remapping request | |
[Oct 3 23:26:24.515] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:760 (EndRemapRequest)> (http_trans) START HttpTransact::EndRemapRequest | |
[Oct 3 23:26:24.515] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:766 (EndRemapRequest)> (http_trans) EndRemapRequest host is 127.0.0.1 | |
[Oct 3 23:26:24.515] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:878 (EndRemapRequest)> (http_trans) After Remapping: | |
[Oct 3 23:26:24.515] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) HTTP_HEADER 0x2ad8818c3898: [T: 3, L: 48, OBJFLAGS: 0] | |
[Oct 3 23:26:24.515] Server {0x2ad864ca8700} DEBUG: <HTTP.cc:577 (http_hdr_describe)> (http) [TYPE: REQ, V: 10001, URL: 0x2ad8818c3b18, METHOD: "GET", METHOD_LEN: 3, FIELDS: 0x2ad8818c38c8] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) URL 0x2ad8818c3b18: [T: 2, L: 112, OBJFLAGS: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1529 (url_describe)> (http) [URLTYPE: 1, SWKSIDX: 99, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1531 (url_describe)> (http) SCHEME: "http", SCHEME_LEN: 4, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1533 (url_describe)> (http) USER: "", USER_LEN: 0, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1535 (url_describe)> (http) PASSWORD: "", PASSWORD_LEN: 0, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1537 (url_describe)> (http) HOST: "127.0.0.1", HOST_LEN: 9, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1539 (url_describe)> (http) PORT: "13011", PORT_LEN: 5, PORT_NUM: 13011 | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1541 (url_describe)> (http) PATH: "hello", PATH_LEN: 5, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1543 (url_describe)> (http) PARAMS: "", PARAMS_LEN: 0, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1545 (url_describe)> (http) QUERY: "", QUERY_LEN: 0, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1547 (url_describe)> (http) FRAGMENT: "", FRAGMENT_LEN: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) MIME_HEADER 0x2ad8818c38c8: [T: 4, L: 592, OBJFLAGS: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2602 (mime_hdr_describe)> (http) | |
[PBITS: 0x0000000001000000, SLACC: 0xFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF, HEADBLK: 0x2ad8818c3908, TAILBLK: 0x2ad8818c3908] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2607 (mime_hdr_describe)> (http) [CBITS: 0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) FIELD_BLOCK 0x2ad8818c3908: [T: 5, L: 528, OBJFLAGS: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2622 (mime_field_block_describe)> (http) [FREETOP: 9, NEXTBLK: (nil)] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 0 (0x2ad8818c3918), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "Host", N_LEN: 4, N_IDX: 30, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "127.0.0.1:13011", V_LEN: 15, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 0, RAWLEN: 23, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 1 (0x2ad8818c3938), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Forwarded-For", N_LEN: 15, N_IDX: 127, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "127.0.0.1", V_LEN: 9, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 28, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 2 (0x2ad8818c3958), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Forwarded-Proto", N_LEN: 17, N_IDX: -1, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "http", V_LEN: 4, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 25, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 3 (0x2ad8818c3978), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Forwarded-Port", N_LEN: 16, N_IDX: -1, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "9080", V_LEN: 4, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 24, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 4 (0x2ad8818c3998), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Request-ID", N_LEN: 25, N_IDX: -1, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "ag603hu0d77nhli9s66g", V_LEN: 20, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 49, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 5 (0x2ad8818c39b8), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Backend-Scheme", N_LEN: 29, N_IDX: -1, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "http", V_LEN: 4, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 37, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 6 (0x2ad8818c39d8), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Backend-Host", N_LEN: 27, N_IDX: -1, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "localhost", V_LEN: 9, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 40, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 7 (0x2ad8818c39f8), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Backend-Id", N_LEN: 25, N_IDX: -1, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "db-config", V_LEN: 9, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 38, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 8 (0x2ad8818c3a18), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-User-Id", N_LEN: 13, N_IDX: -1, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "5300e8ae-912e-4056-ae5f-b5e41a32aa86", V_LEN: 36, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 53, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:896 (EndRemapRequest)> (http_trans) END HttpTransact::EndRemapRequest | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:902 (EndRemapRequest)> (http_trans) Next action SM_ACTION_API_POST_REMAP; HttpTransact::HandleRequest | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_REMAP_REQUEST -> SM_ACTION_API_POST_REMAP | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1369 (state_api_callout)> (http) [176] calling plugin on hook TS_HTTP_POST_REMAP_HOOK at hook 0x2a29080 | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1264 (state_api_callback)> (http) [176] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1304 (state_api_callout)> (http) [176] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1224 (HandleRequest)> (http_trans) START HttpTransact::HandleRequest | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:5275 (check_request_validity)> (http_trans) [init_stat_vars_from_req] set req cont length to 0 | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:6311 (is_request_valid)> (http_trans) [is_request_valid]no request header errors | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1245 (HandleRequest)> (http_seq) [HttpTransact::HandleRequest] request valid. | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) HTTP_HEADER 0x2ad8818c3898: [T: 3, L: 48, OBJFLAGS: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HTTP.cc:577 (http_hdr_describe)> (http) [TYPE: REQ, V: 10001, URL: 0x2ad8818c3b18, METHOD: "GET", METHOD_LEN: 3, FIELDS: 0x2ad8818c38c8] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) URL 0x2ad8818c3b18: [T: 2, L: 112, OBJFLAGS: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1529 (url_describe)> (http) [URLTYPE: 1, SWKSIDX: 99, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1531 (url_describe)> (http) SCHEME: "http", SCHEME_LEN: 4, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1533 (url_describe)> (http) USER: "", USER_LEN: 0, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1535 (url_describe)> (http) PASSWORD: "", PASSWORD_LEN: 0, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1537 (url_describe)> (http) HOST: "127.0.0.1", HOST_LEN: 9, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1539 (url_describe)> (http) PORT: "13011", PORT_LEN: 5, PORT_NUM: 13011 | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1541 (url_describe)> (http) PATH: "hello", PATH_LEN: 5, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1543 (url_describe)> (http) PARAMS: "", PARAMS_LEN: 0, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1545 (url_describe)> (http) QUERY: "", QUERY_LEN: 0, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <URL.cc:1547 (url_describe)> (http) FRAGMENT: "", FRAGMENT_LEN: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) MIME_HEADER 0x2ad8818c38c8: [T: 4, L: 592, OBJFLAGS: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2602 (mime_hdr_describe)> (http) | |
[PBITS: 0x0000000001000000, SLACC: 0xFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF, HEADBLK: 0x2ad8818c3908, TAILBLK: 0x2ad8818c3908] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2607 (mime_hdr_describe)> (http) [CBITS: 0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <HdrHeap.cc:57 (obj_describe)> (http) FIELD_BLOCK 0x2ad8818c3908: [T: 5, L: 528, OBJFLAGS: 0] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2622 (mime_field_block_describe)> (http) [FREETOP: 9, NEXTBLK: (nil)] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 0 (0x2ad8818c3918), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "Host", N_LEN: 4, N_IDX: 30, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "127.0.0.1:13011", V_LEN: 15, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 0, RAWLEN: 23, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 1 (0x2ad8818c3938), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Forwarded-For", N_LEN: 15, N_IDX: 127, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "127.0.0.1", V_LEN: 9, | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 28, F: 1] | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 2 (0x2ad8818c3958), LIVE | |
[Oct 3 23:26:24.516] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Forwarded-Proto", N_LEN: 17, N_IDX: -1, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "http", V_LEN: 4, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 25, F: 1] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 3 (0x2ad8818c3978), LIVE | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Forwarded-Port", N_LEN: 16, N_IDX: -1, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "9080", V_LEN: 4, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 24, F: 1] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 4 (0x2ad8818c3998), LIVE | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Request-ID", N_LEN: 25, N_IDX: -1, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "ag603hu0d77nhli9s66g", V_LEN: 20, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 49, F: 1] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 5 (0x2ad8818c39b8), LIVE | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Backend-Scheme", N_LEN: 29, N_IDX: -1, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "http", V_LEN: 4, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 37, F: 1] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 6 (0x2ad8818c39d8), LIVE | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Backend-Host", N_LEN: 27, N_IDX: -1, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "localhost", V_LEN: 9, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 40, F: 1] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 7 (0x2ad8818c39f8), LIVE | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-Umbrella-Backend-Id", N_LEN: 25, N_IDX: -1, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "db-config", V_LEN: 9, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 38, F: 1] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2626 (mime_field_block_describe)> (http) SLOT # 8 (0x2ad8818c3a18), LIVE | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2635 (mime_field_block_describe)> (http) [N: "X-Api-User-Id", N_LEN: 13, N_IDX: -1, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2636 (mime_field_block_describe)> (http) V: "5300e8ae-912e-4056-ae5f-b5e41a32aa86", V_LEN: 36, | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2638 (mime_field_block_describe)> (http) NEXTDUP: (nil), RAW: 1, RAWLEN: 53, F: 1] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <MIME.cc:2641 (mime_field_block_describe)> (http) | |
+++++++++ Incoming Request +++++++++ | |
-- State Machine Id: 176 | |
GET http://127.0.0.1:13011/hello HTTP/1.1 | |
Host: 127.0.0.1:13011 | |
X-Forwarded-For: 127.0.0.1 | |
X-Forwarded-Proto: http | |
X-Forwarded-Port: 9080 | |
X-Api-Umbrella-Request-ID: ag603hu0d77nhli9s66g | |
X-Api-Umbrella-Backend-Scheme: http | |
X-Api-Umbrella-Backend-Host: localhost | |
X-Api-Umbrella-Backend-Id: db-config | |
X-Api-User-Id: 5300e8ae-912e-4056-ae5f-b5e41a32aa86 | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1924 (DecideCacheLookup)> (http_trans) [DecideCacheLookup] Will do cache lookup. | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1925 (DecideCacheLookup)> (http_seq) [DecideCacheLookup] Will do cache lookup | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1968 (DecideCacheLookup)> (http_trans) Next action SM_ACTION_CACHE_LOOKUP; __null | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_API_POST_REMAP -> SM_ACTION_CACHE_LOOKUP | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:4387 (do_cache_lookup_and_read)> (http_seq) [HttpSM::do_cache_lookup_and_read] [176] Issuing cache lookup for URL http://127.0.0.1:13011/hello | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpCacheSM.cc:102 (state_cache_open_read)> (http_cache) [176] [&HttpCacheSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2525 (main_handler)> (http) [176] [HttpSM::main_handler, CACHE_EVENT_OPEN_READ_FAILED] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2457 (state_cache_open_read)> (http) [176] [&HttpSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2492 (state_cache_open_read)> (http) [176] cache_open_read - CACHE_EVENT_OPEN_READ_FAILED | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2493 (state_cache_open_read)> (http) [state_cache_open_read] open read failed. | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:2182 (HandleCacheOpenRead)> (http_trans) [HttpTransact::HandleCacheOpenRead] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:2212 (HandleCacheOpenRead)> (http_trans) CacheOpenRead -- miss | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:2219 (HandleCacheOpenRead)> (http_trans) Next action SM_ACTION_DNS_LOOKUP; OSDNSLookup | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_CACHE_LOOKUP -> SM_ACTION_DNS_LOOKUP | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6949 (set_next_state)> (dns) [HttpTransact::HandleRequest] Skipping DNS lookup for 127.0.0.1 because it's loopback | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1655 (OSDNSLookup)> (http_trans) [HttpTransact::OSDNSLookup] This was attempt 1 | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1718 (OSDNSLookup)> (http_seq) [HttpTransact::OSDNSLookup] DNS Lookup successful | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1752 (OSDNSLookup)> (http_trans) [OSDNSLookup] DNS lookup for O.S. successful IP: 127.0.0.1 | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:1822 (OSDNSLookup)> (http_trans) Next action SM_ACTION_API_OS_DNS; HandleCacheOpenReadMiss | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_DNS_LOOKUP -> SM_ACTION_API_OS_DNS | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:3062 (HandleCacheOpenReadMiss)> (http_trans) [HandleCacheOpenReadMiss] --- MISS | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:3064 (HandleCacheOpenReadMiss)> (http_seq) [HttpTransact::HandleCacheOpenReadMiss] Miss in cache | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:7782 (build_request)> (http_trans) [build_request] removing host name from url | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:7805 (build_request)> (http_trans) [build_request] request like cacheable and conditional headers removed | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:8337 (ink_cluster_time)> (http_trans) [ink_cluster_time] local: 1443914784, highest_delta: 0, cluster: 1443914784 | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:7829 (build_request)> (http_trans) [build_request] request_sent_time: 1443914784 | |
+++++++++ Proxy's Request +++++++++ | |
-- State Machine Id: 176 | |
GET /hello HTTP/1.1 | |
Host: 127.0.0.1:13011 | |
X-Forwarded-For: 127.0.0.1 | |
X-Forwarded-Proto: http | |
X-Forwarded-Port: 9080 | |
X-Api-Umbrella-Request-ID: ag603hu0d77nhli9s66g | |
X-Api-Umbrella-Backend-Scheme: http | |
X-Api-Umbrella-Backend-Host: localhost | |
X-Api-Umbrella-Backend-Id: db-config | |
X-Api-User-Id: 5300e8ae-912e-4056-ae5f-b5e41a32aa86 | |
Via: http/1.1 api.vagrant[0A00020F] (ApacheTrafficServer/6.0.0) | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_API_OS_DNS -> SM_ACTION_CACHE_ISSUE_WRITE | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:4514 (do_cache_prepare_action)> (http_cache_write) [176] writing to cache with URL http://127.0.0.1:13011/hello | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpCacheSM.cc:161 (state_cache_open_write)> (http_cache) [176] [&HttpCacheSM::state_cache_open_write, CACHE_EVENT_OPEN_WRITE] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2525 (main_handler)> (http) [176] [HttpSM::main_handler, CACHE_EVENT_OPEN_WRITE] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2356 (state_cache_open_write)> (http) [176] [&HttpSM : state_cache_open_write, CACHE_EVENT_OPEN_WRITE] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:3028 (handle_cache_write_lock)> (http_trans) Next action next; __null | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_CACHE_ISSUE_WRITE -> SM_ACTION_ORIGIN_SERVER_OPEN | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:4539 (do_http_server_open)> (http_track) entered inside do_http_server_open ][IPv4] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:4559 (do_http_server_open)> (http) [176] open connection to 127.0.0.1: 127.0.0.1:13011 | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:4571 (do_http_server_open)> (http_seq) [HttpSM::do_http_server_open] Sending request to server | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSessionManager.cc:276 (acquire_session)> (http_ss) [acquire session] thread pool search successful | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSessionManager.cc:282 (acquire_session)> (http_ss) [68] [acquire session] return session from shared pool | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1369 (state_api_callout)> (http) [176] calling plugin on hook TS_HTTP_SEND_REQUEST_HDR_HOOK at hook 0x2a29040 | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1264 (state_api_callback)> (http) [176] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1304 (state_api_callout)> (http) [176] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
+++++++++ Proxy's Request after hooks +++++++++ | |
-- State Machine Id: 176 | |
GET /hello HTTP/1.1 | |
Host: 127.0.0.1:13011 | |
X-Forwarded-For: 127.0.0.1 | |
X-Forwarded-Proto: http | |
X-Forwarded-Port: 9080 | |
X-Api-Umbrella-Request-ID: ag603hu0d77nhli9s66g | |
X-Api-Umbrella-Backend-Scheme: http | |
X-Api-Umbrella-Backend-Host: localhost | |
X-Api-Umbrella-Backend-Id: db-config | |
X-Api-User-Id: 5300e8ae-912e-4056-ae5f-b5e41a32aa86 | |
Via: http/1.1 api.vagrant[0A00020F] (ApacheTrafficServer/6.0.0) | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2525 (main_handler)> (http) [176] [HttpSM::main_handler, VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1874 (state_send_server_request_header)> (http) [176] [&HttpSM::state_send_server_request_header, VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2525 (main_handler)> (http) [176] [HttpSM::main_handler, VC_EVENT_EOS] | |
[Oct 3 23:26:24.517] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1705 (state_read_server_response_header)> (http) [176] [&HttpSM::state_read_server_response_header, VC_EVENT_EOS] | |
[Oct 3 23:26:24.525] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:5185 (handle_server_setup_error)> (http) [176] [&HttpSM::handle_server_setup_error, VC_EVENT_EOS] | |
[Oct 3 23:26:24.525] Server {0x2ad864ca8700} DEBUG: <HttpServerSession.cc:121 (do_io_close)> (http_ss) [68] session closing, netvc 0x2ad884017020 | |
[Oct 3 23:26:24.525] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:3293 (HandleResponse)> (http_trans) [HttpTransact::HandleResponse] | |
[Oct 3 23:26:24.525] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:3294 (HandleResponse)> (http_seq) [HttpTransact::HandleResponse] Response received | |
[Oct 3 23:26:24.525] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:8337 (ink_cluster_time)> (http_trans) [ink_cluster_time] local: 1443914784, highest_delta: 0, cluster: 1443914784 | |
[Oct 3 23:26:24.525] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:3301 (HandleResponse)> (http_trans) [HandleResponse] response_received_time: 1443914784 | |
+++++++++ Incoming O.S. Response +++++++++ | |
-- State Machine Id: 176 | |
HTTP/1.0 0 | |
[Oct 3 23:26:24.525] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:3317 (HandleResponse)> (http_seq) [HttpTransact::HandleResponse] Response not valid | |
[Oct 3 23:26:24.525] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:3628 (handle_response_from_server)> (http_trans) [handle_response_from_server] (hrfs) | |
[Oct 3 23:26:24.525] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:3659 (handle_response_from_server)> (http_trans) [handle_response_from_server] Transaction received a bad response or a partial response, not retrying... | |
[Oct 3 23:26:24.526] Server {0x2ad864ca8700} DEBUG: <HttpTransactHeaders.cc:629 (generate_and_set_squid_codes)> (http_trans) [Squid code generation] Hit/Miss: 1, Log: u, Hier: 2 | |
+++++++++ Proxy's Response 2 +++++++++ | |
-- State Machine Id: 176 | |
HTTP/1.1 502 Server Hangup | |
Date: Sat, 03 Oct 2015 23:26:24 GMT | |
Connection: keep-alive | |
Via: http/1.1 api.vagrant (ApacheTrafficServer/6.0.0 [cMs f ]) | |
[Oct 3 23:26:24.527] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6885 (call_transact_and_set_next_state)> (http) [176] State Transition: SM_ACTION_ORIGIN_SERVER_OPEN -> SM_ACTION_SEND_ERROR_CACHE_NOOP | |
[Oct 3 23:26:24.527] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1369 (state_api_callout)> (http) [176] calling plugin on hook TS_HTTP_SEND_RESPONSE_HDR_HOOK at hook 0x2a29000 | |
[Oct 3 23:26:24.527] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1264 (state_api_callback)> (http) [176] [&HttpSM::state_api_callback, HTTP_API_CONTINUE] | |
[Oct 3 23:26:24.527] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:1304 (state_api_callout)> (http) [176] [&HttpSM::state_api_callout, HTTP_API_CONTINUE] | |
[Oct 3 23:26:24.527] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:7368 (do_redirect)> (http_redirect) [HttpSM::do_redirect] | |
[Oct 3 23:26:24.527] Server {0x2ad864ca8700} DEBUG: <HttpTunnel.cc:1669 (deallocate_redirect_postdata_buffers)> (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpTunnel.cc:1669 (deallocate_redirect_postdata_buffers)> (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpTunnel.cc:629 (add_producer)> (http_tunnel) [176] adding producer 'internal msg' | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpTunnel.cc:684 (add_consumer)> (http_tunnel) [176] adding consumer 'user agent' | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpTunnel.cc:730 (tunnel_run)> (http_tunnel) tunnel_run started, p_arg is provided | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpClientSession.cc:236 (do_io_write)> (http_cs) tcp_init_cwnd_set 1 | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpTunnel.cc:1306 (consumer_handler)> (http_tunnel) [176] consumer_handler [user agent VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:3079 (tunnel_handler_ua)> (http) [176] [&HttpSM::tunnel_handler_ua, VC_EVENT_WRITE_COMPLETE] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpClientSession.cc:498 (release)> (http_cs) [68] session released by sm [176] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpClientSession.cc:236 (do_io_write)> (http_cs) tcp_init_cwnd_set 1 | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpClientSession.cc:527 (release)> (http_cs) [68] initiating io for next header | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2525 (main_handler)> (http) [176] [HttpSM::main_handler, HTTP_TUNNEL_EVENT_DONE] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:2778 (tunnel_handler)> (http) [176] [&HttpSM::tunnel_handler, HTTP_TUNNEL_EVENT_DONE] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpTunnel.cc:1669 (deallocate_redirect_postdata_buffers)> (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpTunnel.cc:1669 (deallocate_redirect_postdata_buffers)> (http_redirect) [HttpTunnel::deallocate_postdata_copy_buffers] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpTransact.cc:8968 (add_new_stat_block)> (http_trans) Adding new large stat block | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6604 (kill_this)> (http_seq) [HttpSM::update_stats] Logging transaction | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpSM.cc:6631 (kill_this)> (http) [176] deallocating sm | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpClientSession.cc:409 (state_keep_alive)> (http_cs) [68] [&HttpClientSession::state_keep_alive, VC_EVENT_EOS] | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpClientSession.cc:325 (do_io_close)> (http_cs) [68] session closed | |
[Oct 3 23:26:24.528] Server {0x2ad864ca8700} DEBUG: <HttpClientSession.cc:73 (destroy)> (http_cs) [68] session destroy |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment