<div dir="ltr"><p class=""><span class="">Attaching full log of how it fails initially and then able to resolve dns lookup in 3 minutes</span></p><p class=""><span class="">collected by setting debug_options ALL, 3</span></p><p class=""><span class=""></span>>>>>></p><p class=""><span class="">2016/05/31 16:55:05.157| 42,2| IcmpPinger.cc(211) SendResult: return result to squid. len=76</span></p><p class=""><span class="">2016/05/31 16:55:05.157 kid1| 51,3| fd.cc(93) fd_close: fd_close FD 12 Pinger Socket</span></p><p class=""><span class="">2016/05/31 16:55:05.157| 42,2| IcmpPinger.cc(211) SendResult: return result to squid. len=7990</span></p><p class=""><span class="">2016/05/31 16:55:05.157| 42,2| Icmp.cc(95) Log: pingerLog: 1464728105.157655 127.0.0.1 0 Echo Reply 0ms 1 hops</span></p><p class=""><span class="">2016/05/31 16:55:05.635 kid1| 50,3| ModDaemon.cc(172) logfile_mod_daemon_append: logfile_mod_daemon_append: daemon:/var/log/squid/access.log: appending 2 bytes</span></p><p class=""><span class="">2016/05/31 16:55:05.635 kid1| 50,3| ModDaemon.cc(176) logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 6 of 32768 bytes before append</span></p><p class=""><span class="">2016/05/31 16:55:05.635 kid1| 50,3| ModDaemon.cc(108) logfileHandleWrite: daemon:/var/log/squid/access.log: write returned 8</span></p><p class=""><span class="">2016/05/31 16:55:05.635 kid1| storeLateRelease: released 0 objects</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 38,3| net_db.cc(1286) netdbExchangeStart: netdbExchangeStart: Requesting '<a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 23,3| url.cc(357) urlParse: urlParse: Split URL '<a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>' into proto='http', host='127.0.0.1', port='50005', path='/squid-internal-dynamic/netdb'</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 23,3| HttpRequest.h(82) SetHost: HttpRequest::SetHost() given IP: 127.0.0.1</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 20,3| store.cc(774) storeCreatePureEntry: storeCreateEntry: '<a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 20,3| MemObject.cc(97) MemObject: new MemObject 0x7f0f6646d960</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 20,3| store.cc(499) setReleaseFlag: StoreEntry::setReleaseFlag: '[null_store_key]'</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 20,3| store_key_md5.cc(89) storeKeyPrivate: storeKeyPrivate: GET <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 20,3| store.cc(447) hashInsert: StoreEntry::hashInsert: Inserting Entry e:=XI/0x7f0f66898f80*0 key '8296497AA8EBFF11C70A96598AA18956'</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 20,3| store.cc(483) lock: storeCreateEntry locked key 8296497AA8EBFF11C70A96598AA18956 e:=XIV/0x7f0f66898f80*1</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 90,3| store_client.cc(200) copy: store_client::copy: 8296497AA8EBFF11C70A96598AA18956, from 0, for length 4096, cb 1, cbdata 0x7f0f6646c918</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 20,3| store.cc(483) lock: store_client::copy locked key 8296497AA8EBFF11C70A96598AA18956 e:=XIV/0x7f0f66898f80*2</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 90,3| store_client.cc(297) storeClientCopy2: storeClientCopy2: 8296497AA8EBFF11C70A96598AA18956</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 90,3| store_client.cc(341) doCopy: store_client::doCopy: Waiting for more</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 20,3| store.cc(521) unlock: store_client::copy unlocking key 8296497AA8EBFF11C70A96598AA18956 e:=XIV/0x7f0f66898f80*2</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 17,3| FwdState.cc(332) Start: '<a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 17,2| FwdState.cc(133) FwdState: Forwarding client request , url=<a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 20,3| store.cc(483) lock: FwdState locked key 8296497AA8EBFF11C70A96598AA18956 e:=XIV/0x7f0f66898f80*2</span></p><p class=""><span class="">2016/05/31 16:55:25.176 kid1| 44,3| peer_select.cc(137) peerSelect: e:=XIWV/0x7f0f66898f80*2 <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 20,3| store.cc(483) lock: peerSelect locked key 8296497AA8EBFF11C70A96598AA18956 e:=XIWV/0x7f0f66898f80*3</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,3| peer_select.cc(441) peerSelectFoo: GET 127.0.0.1</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,3| peer_select.cc(468) peerSelectFoo: peerSelectFoo: direct = DIRECT_YES (forwarding loop detected)</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,3| peer_select.cc(477) peerSelectFoo: peerSelectFoo: direct = DIRECT_YES</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,2| peer_select.cc(258) peerSelectDnsPaths: Find IP destination for: <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>' via 127.0.0.1</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,2| peer_select.cc(280) peerSelectDnsPaths: Found sources for '<a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,2| peer_select.cc(281) peerSelectDnsPaths: always_direct = DENIED</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,2| peer_select.cc(282) peerSelectDnsPaths: never_direct = DENIED</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,2| peer_select.cc(286) peerSelectDnsPaths: DIRECT = local=0.0.0.0 remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> flags=1</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,2| peer_select.cc(295) peerSelectDnsPaths: timedout = 0</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 17,3| FwdState.cc(387) startConnectionOrFail: <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 17,3| FwdState.cc(806) connectStart: fwdConnectStart: <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 48,3| pconn.cc(439) pop: lookup for key {<a href="http://127.0.0.1:50005/127.0.0.1"><span class="">127.0.0.1:50005/127.0.0.1</span></a>} failed.</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 17,3| FwdState.cc(1307) GetMarkingsToServer: from 0.0.0.0 netfilter mark 0</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 17,3| AsyncCall.cc(26) AsyncCall: The AsyncCall fwdConnectDoneWrapper constructed, this=0x7f0f6646dfa0 [call52]</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 44,3| peer_select.cc(79) ~ps_state: <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 20,3| store.cc(521) unlock: peerSelect unlocking key 8296497AA8EBFF11C70A96598AA18956 e:=p2XIWV/0x7f0f66898f80*3</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 50,3| comm.cc(347) comm_openex: comm_openex: Attempt open socket for: 0.0.0.0</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 50,3| comm.cc(388) comm_openex: comm_openex: Opened socket local=0.0.0.0 remote=[::] FD 10 flags=1 : family=2, type=1, protocol=6</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 51,3| fd.cc(198) fd_open: fd_open() FD 10 127.0.0.1</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 5,3| ConnOpener.cc(289) createFd: local=0.0.0.0 remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> flags=1 will timeout in 60</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 17,3| AsyncCall.cc(93) ScheduleCall: ConnOpener.cc(137) will call fwdConnectDoneWrapper(local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1, data=0x7f0f6646dee8) [call52]</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 17,3| AsyncCallQueue.cc(55) fireNext: entering fwdConnectDoneWrapper(local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1, data=0x7f0f6646dee8)</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 17,3| AsyncCall.cc(38) make: make call fwdConnectDoneWrapper [call52]</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 17,3| FwdState.cc(680) connectDone: local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1: '<a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 17,3| FwdState.cc(908) dispatch: : Fetching GET <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.177 kid1| 38,3| net_db.cc(325) netdbSendPing: netdbSendPing: pinging 127.0.0.1</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 37,2| IcmpSquid.cc(90) SendEcho: to 127.0.0.1, opcode 3, len 9</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 11,3| http.cc(2278) httpStart: GET <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 20,3| store.cc(483) lock: Client locked key 8296497AA8EBFF11C70A96598AA18956 e:=p2XDIWV/0x7f0f66898f80*3</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 17,3| AsyncCallQueue.cc(57) fireNext: leaving fwdConnectDoneWrapper(local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1, data=0x7f0f6646dee8)</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 timeout 86400</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 22,3| refresh.cc(656) getMaxAge: getMaxAge: '<a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.178| 42,2| IcmpPinger.cc(198) Recv: Pass 127.0.0.1 off to ICMPv4 module.</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 11,2| http.cc(2234) sendRequest: HTTP Server local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 11,2| http.cc(2235) sendRequest: HTTP Server REQUEST:</span></p><p class=""><span class="">---------</span></p><p class=""><span class="">GET /squid-internal-dynamic/netdb HTTP/1.1</span></p><p class=""><span class="">Via: 1.1 SLES12-001-0 (squid/3.5.16)</span></p><p class=""><span class="">X-Forwarded-For: ::</span></p><p class=""><span class="">Host: <a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a></span></p><p class=""><span class="">Authorization: Basic UEFTU1RIUlU=</span></p><p class=""><span class="">Cache-Control: max-age=259200</span></p><p class=""><span class="">Connection: keep-alive</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">----------</span></p><p class=""><span class="">2016/05/31 16:55:25.178| 42,2| Icmp.cc(95) Log: pingerLog: 1464728125.178329 127.0.0.1 32 </span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 5,3| IoCallback.cc(116) finish: called for local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 (0, 0)</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 timeout 900</span></p><p class=""><span class="">2016/05/31 16:55:25.178| 42,2| IcmpPinger.cc(211) SendResult: return result to squid. len=7990</span></p><p class=""><span class="">2016/05/31 16:55:25.178| 42,2| Icmp.cc(95) Log: pingerLog: 1464728125.178565 127.0.0.1 0 Echo Reply 0ms 1 hops</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 38,3| net_db.cc(931) netdbHandlePingReply: netdbHandlePingReply: from 127.0.0.1</span></p><p class=""><span class="">2016/05/31 16:55:25.178 kid1| 38,3| net_db.cc(950) netdbHandlePingReply: netdbHandlePingReply: 127.0.0.0; rtt= 1.00 hops=1.00</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 5,3| Read.cc(144) HandleRead: FD 10, size 16383, retval 99, errno 0</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 5,3| IoCallback.cc(116) finish: called for local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 (0, 0)</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| ctx: enter level 0: '<a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 11,3| http.cc(709) processReplyHeader: processReplyHeader: key '8296497AA8EBFF11C70A96598AA18956'</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 11,2| http.cc(750) processReplyHeader: HTTP Server local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 11,2| http.cc(751) processReplyHeader: HTTP Server REPLY:</span></p><p class=""><span class="">---------</span></p><p class=""><span class="">HTTP/1.1 404 Not Found</span></p><p class=""><span class="">Content-Length: 53</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class=""><html><body><h1>Resource not found</h1></body></html></span></p><p class=""><span class="">----------</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| ctx: exit level 0</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 28,3| Checklist.cc(70) preCheck: 0x7f0f6689a1d8 checking slow rules</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 28,3| Ip.cc(539) match: aclIpMatchIp: '[::]' found</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 28,3| Acl.cc(158) matches: checked: all = 1</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 28,3| Acl.cc(158) matches: checked: adaptation_access#1 = 1</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 28,3| Acl.cc(158) matches: checked: adaptation_access = 1</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 28,3| Checklist.cc(63) markFinished: 0x7f0f6689a1d8 answer ALLOWED for match</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 28,3| Checklist.cc(163) checkCallback: ACLChecklist::checkCallback: 0x7f0f6689a1d8 answer=ALLOWED</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 93,3| AccessCheck.cc(196) callBack: 0x7f0f66898b50*2</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 11,3| http.cc(1065) persistentConnStatus: local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 eof=0</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 5,3| comm.cc(579) commUnsetConnTimeout: Remove timeout for local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 timeout -1</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 17,3| FwdState.cc(447) unregister: <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 48,3| pconn.cc(413) push: new IdleConnList for {<a href="http://127.0.0.1:50005/127.0.0.1"><span class="">127.0.0.1:50005/127.0.0.1</span></a>}</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 timeout 60</span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 48,3| pconn.cc(425) push: pushed local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 for <a href="http://127.0.0.1:50005/127.0.0.1"><span class="">127.0.0.1:50005/127.0.0.1</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.180 kid1| 93,3| Xaction.cc(60) Xaction: Adaptation::Icap::ModXact constructed, this=0x7f0f6689c5b8 [icapxjob9]</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 93,3| Xaction.cc(60) Xaction: Adaptation::Icap::OptXact constructed, this=0x7f0f668bde58 [icapxjob11]</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 93,3| ServiceRep.cc(122) getConnection: got connection: </span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 93,3| Xaction.cc(145) openConnection: Adaptation::Icap::OptXact opens connection to <a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>:1344</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 14,3| Address.cc(389) lookupHostIP: Given Non-IP '<a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>': Name or service not known</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 78,3| dns_internal.cc(1745) idnsALookup: idnsALookup: buf is 44 bytes for <a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>, id = 0x5c1f</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 50,3| comm.cc(957) comm_udp_sendto: comm_udp_sendto: Attempt to send UDP packet to <a href="http://10.42.70.144:53/"><span class="">10.42.70.144:53</span></a> using FD 8 using Port 43651</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 78,3| dns_internal.cc(1683) idnsSendSlaveAAAAQuery: buf is 44 bytes for <a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>, id = 0xd1ee</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 50,3| comm.cc(957) comm_udp_sendto: comm_udp_sendto: Attempt to send UDP packet to <a href="http://10.42.70.144:53/"><span class="">10.42.70.144:53</span></a> using FD 8 using Port 43651</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 93,3| Xaction.cc(71) ~Xaction: Adaptation::Icap::OptXact destructed, this=0x7f0f668bde58 [icapxjob11]</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 93,3| Launcher.cc(95) noteXactAbort: cannot retry or repeat a failed transaction</span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 93,3| ServiceRep.cc(534) noteAdaptationAnswer: failed to fetch options [down,!opt]</span></p><p class=""><span class="">2<font color="#ff0000">016/05/31 16:55:25.181 kid1| essential ICAP service is down after an options fetch failure: icap://<a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>:1344 [down,!opt]</font></span></p><p class=""><span class="">2016/05/31 16:55:25.181 kid1| 93,3| ServiceRep.cc(571) handleNewOptions: got new options and is now [down,!opt]</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 93,3| ../../../src/base/AsyncJobCalls.h(177) dial: Adaptation::Icap::ModXact::noteServiceReady threw exception: ICAP service is unusable</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 93,3| Xaction.cc(71) ~Xaction: Adaptation::Icap::ModXact destructed, this=0x7f0f6689c5b8 [icapxjob9]</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 93,3| Launcher.cc(95) noteXactAbort: cannot retry or repeat a failed transaction</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 17,3| FwdState.cc(416) fail: ERR_ICAP_FAILURE "Internal Server Error"</span></p><p class=""><span class=""><a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb">http://127.0.0.1:50005/squid-internal-dynamic/netdb</a></span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 17,2| FwdState.cc(655) handleUnregisteredServerEnd: self=0x7f0f6646dee8*2 err=0x7f0f668bda78 <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 20,3| store.cc(521) unlock: Client unlocking key 8296497AA8EBFF11C70A96598AA18956 e:=p2XDIWV/0x7f0f66898f80*3</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 17,3| FwdState.cc(266) ~FwdState: FwdState destructor starting</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,2| errorpage.cc(1262) BuildContent: No existing error page language negotiated for ERR_ICAP_FAILURE. Using default error file.</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%l --> '/*</span></p><p class=""><span class=""> * Copyright (C) 1996-2016 The Squid Software Foundation and contributors</span></p><p class=""><span class=""> *</span></p><p class=""><span class=""> * Squid software is distributed under GPLv2+ license and includes</span></p><p class=""><span class=""> * contributions from numerous individuals and organizations.</span></p><p class=""><span class=""> * Please see the COPYING and CONTRIBUTORS files for details.</span></p><p class=""><span class=""> */</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/*</span></p><p class=""><span class=""> Stylesheet for Squid Error pages</span></p><p class=""><span class=""> Adapted from design by Free CSS Templates</span></p><p class=""><span class=""> <a href="http://www.freecsstemplates.org/"><span class="">http://www.freecsstemplates.org</span></a></span></p><p class=""><span class=""> Released for free under a Creative Commons Attribution 2.5 License</span></p><p class=""><span class="">*/</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* Page basics */</span></p><p class=""><span class="">* {</span></p><p class=""><span class="">font-family: verdana, sans-serif;</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">html body {</span></p><p class=""><span class="">margin: 0;</span></p><p class=""><span class="">padding: 0;</span></p><p class=""><span class="">background: #efefef;</span></p><p class=""><span class="">font-size: 12px;</span></p><p class=""><span class="">color: #1e1e1e;</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* Page displayed title area */</span></p><p class=""><span class="">#titles {</span></p><p class=""><span class="">margin-left: 15px;</span></p><p class=""><span class="">padding: 10px;</span></p><p class=""><span class="">padding-left: 100px;</span></p><p class=""><span class="">background: url('/squid-internal-static/icons/SN.png') no-repeat left;</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* initial title */</span></p><p class=""><span class="">#titles h1 {</span></p><p class=""><span class="">color: #000000;</span></p><p class=""><span class="">}</span></p><p class=""><span class="">#titles h2 {</span></p><p class=""><span class="">color: #000000;</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* special event: FTP success page titles */</span></p><p class=""><span class="">#titles ftpsuccess {</span></p><p class=""><span class="">background-color:#00ff00;</span></p><p class=""><span class="">width:100%;</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* Page displayed body content area */</span></p><p class=""><span class="">#content {</span></p><p class=""><span class="">padding: 10px;</span></p><p class=""><span class="">background: #ffffff;</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* General text */</span></p><p class=""><span class="">p {</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* error brief description */</span></p><p class=""><span class="">#error p {</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* some data which may have caused the problem */</span></p><p class=""><span class="">#data {</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* the error message received from the system or other software */</span></p><p class=""><span class="">#sysmsg {</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">pre {</span></p><p class=""><span class=""> font-family:sans-serif;</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* special event: FTP / Gopher directory listing */</span></p><p class=""><span class="">#dirmsg {</span></p><p class=""><span class=""> font-family: courier;</span></p><p class=""><span class=""> color: black;</span></p><p class=""><span class=""> font-size: 10pt;</span></p><p class=""><span class="">}</span></p><p class=""><span class="">#dirlisting {</span></p><p class=""><span class=""> margin-left: 2%;</span></p><p class=""><span class=""> margin-right: 2%;</span></p><p class=""><span class="">}</span></p><p class=""><span class="">#dirlisting tr.entry td.icon,td.filename,td.size,td.date {</span></p><p class=""><span class=""> border-bottom: groove;</span></p><p class=""><span class="">}</span></p><p class=""><span class="">#dirlisting td.size {</span></p><p class=""><span class=""> width: 50px;</span></p><p class=""><span class=""> text-align: right;</span></p><p class=""><span class=""> padding-right: 5px;</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* horizontal lines */</span></p><p class=""><span class="">hr {</span></p><p class=""><span class="">margin: 0;</span></p><p class=""><span class="">}</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">/* page displayed footer area */</span></p><p class=""><span class="">#footer {</span></p><p class=""><span class="">font-size: 9px;</span></p><p class=""><span class="">padding-left: 10px;</span></p><p class=""><span class="">}</span></p><p class=""><span class="">'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%; --> '%;'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%c --> 'ERR_ICAP_FAILURE'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%U --> '<a href="http://PASSTHRU@127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://PASSTHRU@127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%U --> '<a href="http://PASSTHRU@127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://PASSTHRU@127.0.0.1:50005/squid-internal-dynamic/netdb</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%E --> '[No Error]'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%T --> 'Tue, 31 May 2016 20:55:25 GMT'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%h --> 'SLES12-001-0'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%s --> 'squid/3.5.16'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 4,3| errorpage.cc(1101) Convert: errorConvert: %%c --> 'ERR_ICAP_FAILURE'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 20,3| store.cc(483) lock: StoreEntry::storeErrorResponse locked key 8296497AA8EBFF11C70A96598AA18956 e:=p2XDIWV/0x7f0f66898f80*3</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 20,3| store.cc(1862) replaceHttpReply: StoreEntry::replaceHttpReply: <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 20,2| store.cc(949) checkCachable: StoreEntry::checkCachable: NO: not cachable</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 90,3| store_client.cc(732) invokeHandlers: InvokeHandlers: 8296497AA8EBFF11C70A96598AA18956</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 90,3| store_client.cc(738) invokeHandlers: StoreEntry::InvokeHandlers: checking client #0</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 90,3| store_client.cc(297) storeClientCopy2: storeClientCopy2: 8296497AA8EBFF11C70A96598AA18956</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 90,3| store_client.cc(433) scheduleMemRead: store_client::doCopy: Copying normal from memory</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 38,3| net_db.cc(689) netdbExchangeHandleReply: netdbExchangeHandleReply: 3490 read bytes</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 38,3| net_db.cc(697) netdbExchangeHandleReply: netdbExchangeHandleReply: for '<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a>'</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 38,3| net_db.cc(710) netdbExchangeHandleReply: netdbExchangeHandleReply: 3490 bytes buf</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 38,3| net_db.cc(724) netdbExchangeHandleReply: netdbExchangeHandleReply: reply status 500</span></p><p class=""><span class="">2016/05/31 16:55:25.182 kid1| 38,3| net_db.cc(865) netdbExchangeDone: netdbExchangeDone: <a href="http://127.0.0.1:50005/squid-internal-dynamic/netdb"><span class="">http://127.0.0.1:50005/squid-internal-dynamic/netdb</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 90,3| store_client.cc(664) storeUnregister: storeUnregister: called for '8296497AA8EBFF11C70A96598AA18956'</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(483) lock: storeUnregister locked key 8296497AA8EBFF11C70A96598AA18956 e:=p2XDIV/0x7f0f66898f80*4</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 90,3| store_client.cc(758) storePendingNClients: storePendingNClients: returning 0</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 90,3| store_client.cc(768) CheckQuickAbortIsReasonable: entry=0x7f0f66898f80, mem=0x7f0f6646d960</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 90,3| store_client.cc(771) CheckQuickAbortIsReasonable: quick-abort? YES !mem->request->flags.cachable</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(483) lock: StoreEntry::abort locked key 8296497AA8EBFF11C70A96598AA18956 e:=p2XDIV/0x7f0f66898f80*5</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 90,3| store_client.cc(732) invokeHandlers: InvokeHandlers: 8296497AA8EBFF11C70A96598AA18956</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store_swapout.cc(273) swapOutFileClose: storeSwapOutFileClose: 8296497AA8EBFF11C70A96598AA18956 how=1</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store_swapout.cc(274) swapOutFileClose: storeSwapOutFileClose: sio = 0</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(521) unlock: StoreEntry::abort unlocking key 8296497AA8EBFF11C70A96598AA18956 e:=sp2XDINVA/0x7f0f66898f80*5</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(521) unlock: storeUnregister unlocking key 8296497AA8EBFF11C70A96598AA18956 e:=sp2XDINVA/0x7f0f66898f80*4</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(521) unlock: netdbExchangeDone unlocking key 8296497AA8EBFF11C70A96598AA18956 e:=sp2XDINVA/0x7f0f66898f80*3</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(1048) complete: storeComplete: '8296497AA8EBFF11C70A96598AA18956'</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(521) unlock: StoreEntry::storeErrorResponse unlocking key 8296497AA8EBFF11C70A96598AA18956 e:=sp2XDINVA/0x7f0f66898f80*2</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 90,3| store_client.cc(758) storePendingNClients: storePendingNClients: returning 0</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(521) unlock: FwdState unlocking key 8296497AA8EBFF11C70A96598AA18956 e:=sp2XDINVA/0x7f0f66898f80*1</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 90,3| store_client.cc(758) storePendingNClients: storePendingNClients: returning 0</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(1239) release: releasing e:=sp2XDINVA/0x7f0f66898f80*0 8296497AA8EBFF11C70A96598AA18956</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(402) destroyMemObject: destroyMemObject 0x7f0f6646d960</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| MemObject.cc(110) ~MemObject: del MemObject 0x7f0f6646d960</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(420) destroyStoreEntry: destroyStoreEntry: destroying 0x7f0f66898f88</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 20,3| store.cc(402) destroyMemObject: destroyMemObject 0</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 17,3| AsyncCall.cc(56) cancel: will not call fwdConnectDoneWrapper [call52] because FwdState destructed</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 17,3| FwdState.cc(293) ~FwdState: FwdState destructor done</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 78,3| dns_internal.cc(1277) idnsRead: idnsRead: starting with FD 8</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 78,3| dns_internal.cc(1323) idnsRead: idnsRead: FD 8: received 60 bytes from <a href="http://10.42.70.144:53/"><span class="">10.42.70.144:53</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 78,3| dns_internal.cc(1130) idnsGrokReply: idnsGrokReply: QID 0x5c1f, 1 answers</span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 78,3| dns_internal.cc(1323) idnsRead: idnsRead: FD 8: received 104 bytes from <a href="http://10.42.70.144:53/"><span class="">10.42.70.144:53</span></a></span></p><p class=""><span class="">2016/05/31 16:55:25.183 kid1| 78,3| dns_internal.cc(1130) idnsGrokReply: idnsGrokReply: QID 0xd1ee, 0 answers</span></p><p class=""><span class="" style="background-color:rgb(255,0,0)">2016/05/31 16:55:25.183 kid1| 14,3| ipcache.cc(364) ipcacheParse: ipcacheParse: 1 answers for '<a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>'</span></p><p class=""><span class="" style="background-color:rgb(255,0,0)">2016/05/31 16:55:25.183 kid1| 14,3| ipcache.cc(422) ipcacheParse: ipcacheParse: <a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a> #0 10.44.3.1</span></p><p class=""><span class="">2016/05/31 16:56:25.231 kid1| 48,3| pconn.cc(310) Timeout: local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:56:25.231 kid1| 48,3| pconn.cc(70) findIndexOf: found local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 at index 0</span></p><p class=""><span class="">2016/05/31 16:56:25.231 kid1| 48,3| pconn.cc(156) clearHandlers: removing close handler for local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:56:25.231 kid1| 5,3| comm.cc(579) commUnsetConnTimeout: Remove timeout for local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:56:25.231 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://127.0.0.1:51409/"><span class="">127.0.0.1:51409</span></a> remote=<a href="http://127.0.0.1:50005/"><span class="">127.0.0.1:50005</span></a> FD 10 flags=1 timeout -1</span></p><p class=""><span class="">2016/05/31 16:56:25.231 kid1| 48,3| pconn.cc(97) removeAt: deleting <a href="http://127.0.0.1:50005/127.0.0.1"><span class="">127.0.0.1:50005/127.0.0.1</span></a></span></p><p class=""><span class="">2016/05/31 16:56:25.231 kid1| 5,3| comm.cc(868) _comm_close: comm_close: start closing FD 10</span></p><p class=""><span class="">2016/05/31 16:56:25.232 kid1| 5,3| comm.cc(540) commUnsetFdTimeout: Remove timeout for FD 10</span></p><p class=""><span class="">2016/05/31 16:56:25.232 kid1| 51,3| fd.cc(93) fd_close: fd_close FD 10 Idle server: <a href="http://127.0.0.1:50005/127.0.0.1"><span class="">127.0.0.1:50005/127.0.0.1</span></a></span></p><p class=""><span class="">2016/05/31 16:58:25.182 kid1| 93,3| Xaction.cc(60) Xaction: Adaptation::Icap::OptXact constructed, this=0x7f0f668bde58 [icapxjob13]</span></p><p class=""><span class="">2016/05/31 16:58:25.182 kid1| 93,3| ServiceRep.cc(122) getConnection: got connection: </span></p><p class=""><span class="">2016/05/31 16:58:25.182 kid1| 93,3| Xaction.cc(145) openConnection: Adaptation::Icap::OptXact opens connection to <a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>:1344</span></p><p class=""><span class="">2016/05/31 16:58:25.182 kid1| 14,3| Address.cc(389) lookupHostIP: Given Non-IP '<a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>': Name or service not known</span></p><p class=""><span class="">2016/05/31 16:58:25.182 kid1| 93,3| AsyncCall.cc(26) AsyncCall: The AsyncCall Adaptation::Icap::Xaction::noteCommConnected constructed, this=0x7f0f6646fa60 [call502]</span></p><p class=""><span class="">2016/05/31 16:58:25.182 kid1| 50,3| comm.cc(347) comm_openex: comm_openex: Attempt open socket for: 0.0.0.0</span></p><p class=""><span class="">2016/05/31 16:58:25.182 kid1| 50,3| comm.cc(388) comm_openex: comm_openex: Opened socket local=0.0.0.0 remote=[::] FD 10 flags=1 : family=2, type=1, protocol=6</span></p><p class=""><span class="">2016/05/31 16:58:25.182 kid1| 51,3| fd.cc(198) fd_open: fd_open() FD 10 <a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a></span></p><p class=""><span class="">2016/05/31 16:58:25.182 kid1| 5,3| ConnOpener.cc(289) createFd: local=0.0.0.0 remote=<a href="http://10.44.3.1:1344/"><span class="">10.44.3.1:1344</span></a> flags=1 will timeout in 60</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 93,3| AsyncCall.cc(93) ScheduleCall: ConnOpener.cc(137) will call Adaptation::Icap::Xaction::noteCommConnected(local=<a href="http://10.44.3.21:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1, data=0x7f0f668bde58) [call502]</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 93,3| AsyncCallQueue.cc(55) fireNext: entering Adaptation::Icap::Xaction::noteCommConnected(local=<a href="http://10.44.3.21:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.3.1:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1, data=0x7f0f668bde58)</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 93,3| AsyncCall.cc(38) make: make call Adaptation::Icap::Xaction::noteCommConnected [call502]</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 93,3| AsyncJob.cc(123) callStart: Adaptation::Icap::OptXact status in: [FD 10;/ job13]</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://10.44.3.21:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.3.1:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 timeout 60</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 93,3| AsyncCall.cc(26) AsyncCall: The AsyncCall Adaptation::Icap::Xaction::noteCommRead constructed, this=0x7f0f665bb740 [call509]</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://10.44.3.21:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.3.1:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 timeout 900</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 23,3| url.cc(357) urlParse: urlParse: Split URL 'icap://<a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>:1344 ICAP/1.0</span></p><p class=""><span class="">' into proto='icap', host='<a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>', port='1344', path=' ICAP/1.0'</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 23,2| url.cc(393) urlParse: urlParse: URI has whitespace: {icap://<a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>:1344 ICAP/1.0</span></p><p class=""><span class="">}</span></p><p class=""><span class="">2016/05/31 16:58:25.184 kid1| 14,3| Address.cc(389) lookupHostIP: Given Non-IP '<a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>': Name or service not known</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCall.cc(26) AsyncCall: The AsyncCall Adaptation::Icap::Xaction::noteCommWrote constructed, this=0x7f0f668e54a0 [call511]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://10.44.3.21:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.3.1:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 timeout 900</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncJob.cc(152) callEnd: Adaptation::Icap::OptXact status out: [FD 10wr;/ job13]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCallQueue.cc(57) fireNext: leaving Adaptation::Icap::Xaction::noteCommConnected(local=<a href="http://10.44.3.21:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.3.1:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1, data=0x7f0f668bde58)</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| IoCallback.cc(116) finish: called for local=<a href="http://10.44.3.21:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.3.1:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 (0, 0)</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCall.cc(93) ScheduleCall: IoCallback.cc(135) will call Adaptation::Icap::Xaction::noteCommWrote(local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1, data=0x7f0f668bde58) [call511]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCallQueue.cc(55) fireNext: entering Adaptation::Icap::Xaction::noteCommWrote(local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1, data=0x7f0f668bde58)</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCall.cc(38) make: make call Adaptation::Icap::Xaction::noteCommWrote [call511]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncJob.cc(123) callStart: Adaptation::Icap::OptXact status in: [FD 10wr;/ job13]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 timeout 900</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncJob.cc(152) callEnd: Adaptation::Icap::OptXact status out: [FD 10r;/ job13]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCallQueue.cc(57) fireNext: leaving Adaptation::Icap::Xaction::noteCommWrote(local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1, data=0x7f0f668bde58)</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| Read.cc(144) HandleRead: FD 10, size 65535, retval 222, errno 0</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| IoCallback.cc(116) finish: called for local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 (0, 0)</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCall.cc(93) ScheduleCall: IoCallback.cc(135) will call Adaptation::Icap::Xaction::noteCommRead(local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1, data=0x7f0f668bde58, size=222, buf=0x7f0f6689d4a0) [call509]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCallQueue.cc(55) fireNext: entering Adaptation::Icap::Xaction::noteCommRead(local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1, data=0x7f0f668bde58, size=222, buf=0x7f0f6689d4a0)</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCall.cc(38) make: make call Adaptation::Icap::Xaction::noteCommRead [call509]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncJob.cc(123) callStart: Adaptation::Icap::OptXact status in: [FD 10r;/ job13]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| comm.cc(579) commUnsetConnTimeout: Remove timeout for local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 timeout -1</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| Xaction.cc(425) noteCommRead: read 222 bytes</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| ServiceRep.cc(132) putConnection: pushing pconn [FD 10;/ job13]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| comm.cc(579) commUnsetConnTimeout: Remove timeout for local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 timeout -1</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 timeout 60</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| Xaction.cc(71) ~Xaction: Adaptation::Icap::OptXact destructed, this=0x7f0f668bde58 [icapxjob13]</span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| AsyncCallQueue.cc(57) fireNext: leaving Adaptation::Icap::Xaction::noteCommRead(local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1, data=0x7f0f668bde58, size=222, buf=0x7f0f6689d4a0)</span></p><p class=""><span class=""><font color="#ff0000">2016/05/31 16:58:25.185 kid1| essential ICAP service is up: icap://<a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a>:1344 [up]</font></span></p><p class=""><span class="">2016/05/31 16:58:25.185 kid1| 93,3| ServiceRep.cc(571) handleNewOptions: got new options and is now [up]</span></p><p class=""><span class="">2016/05/31 16:59:25.401 kid1| 48,3| pconn.cc(310) Timeout: local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:59:25.401 kid1| 48,3| pconn.cc(70) findIndexOf: found local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 at index 0</span></p><p class=""><span class="">2016/05/31 16:59:25.401 kid1| 48,3| pconn.cc(156) clearHandlers: removing close handler for local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:59:25.402 kid1| 5,3| comm.cc(579) commUnsetConnTimeout: Remove timeout for local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1</span></p><p class=""><span class="">2016/05/31 16:59:25.402 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=<a href="http://10.44.73.219:57084/"><span class="">10.44.3.21:57084</span></a> remote=<a href="http://10.44.73.133:1344/"><span class="">10.44.3.1:1344</span></a> FD 10 flags=1 timeout -1</span></p><p class=""><span class="">2016/05/31 16:59:25.402 kid1| 5,3| comm.cc(868) _comm_close: comm_close: start closing FD 10</span></p><p class=""><span class="">2016/05/31 16:59:25.402 kid1| 5,3| comm.cc(540) commUnsetFdTimeout: Remove timeout for FD 10</span></p><p class=""><span class="">2016/05/31 16:59:25.402 kid1| 51,3| fd.cc(93) fd_close: fd_close FD 10 <a href="http://short.domain.name/"><span class=""><i>short.domain.name</i></span></a></span></p><p class=""><span class="">2016/05/31 17:35:05 kid1| Logfile: opening log stdio:/var/log/squid/netdb.state</span></p><p class=""><span class="">2016/05/31 17:35:05.157 kid1| 51,3| fd.cc(198) fd_open: fd_open() FD 10 /var/log/squid/netdb.state</span></p><p class=""><span class="">2016/05/31 17:35:05.157 kid1| Logfile: closing log stdio:/var/log/squid/netdb.state</span></p><p class=""><span class="">2016/05/31 17:35:05.157 kid1| 51,3| fd.cc(93) fd_close: fd_close FD 10 /var/log/squid/netdb.state</span></p><p class=""><span class="">2016/05/31 17:35:05.157 kid1| NETDB state saved; 1 entries, 0 msec</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class=""><br>
</span></p><p class=""><span class="">Thanks</span></p><p class="">
</p><p class=""><span class="">Aashima</span></p></div>