2015/06/30 10:09:38.432 kid1| Acl.cc(138) matches: checking always_direct 2015/06/30 10:09:38.432 kid1| Acl.cc(138) matches: checking always_direct#1 2015/06/30 10:09:38.432 kid1| Acl.cc(138) matches: checking fakespeed 2015/06/30 10:09:38.432 kid1| RegexData.cc(51) match: aclRegexData::match: checking 'https://r1---sn-4g57knls.googlevideo.com/videoplayback?mime=video/mp4&key=yt5&ms=au&mt=1435651756&mv=m&upn=vXBl$ 2015/06/30 10:09:38.432 kid1| RegexData.cc(62) match: aclRegexData::match: looking for '(\.*(speedtest|espeed).*\/((latency|random.*|upload)\.(jpg|txt|php)))' 2015/06/30 10:09:38.432 kid1| Acl.cc(158) matches: checked: fakespeed = 0 2015/06/30 10:09:38.432 kid1| Acl.cc(158) matches: checked: always_direct#1 = 0 2015/06/30 10:09:38.432 kid1| Acl.cc(138) matches: checking always_direct#2 2015/06/30 10:09:38.432 kid1| Acl.cc(138) matches: checking bau1 2015/06/30 10:09:38.432 kid1| DomainData.cc(108) match: aclMatchDomainList: checking 'r1---sn-4g57knls.googlevideo.com' 2015/06/30 10:09:38.432 kid1| DomainData.cc(113) match: aclMatchDomainList: 'r1---sn-4g57knls.googlevideo.com' NOT found 2015/06/30 10:09:38.433 kid1| Acl.cc(158) matches: checked: bau1 = 0 2015/06/30 10:09:38.433 kid1| Acl.cc(158) matches: checked: always_direct#2 = 0 2015/06/30 10:09:38.433 kid1| Acl.cc(138) matches: checking always_direct#3 2015/06/30 10:09:38.433 kid1| Acl.cc(138) matches: checking betty1 2015/06/30 10:09:38.433 kid1| DomainData.cc(108) match: aclMatchDomainList: checking 'r1---sn-4g57knls.googlevideo.com' 2015/06/30 10:09:38.433 kid1| DomainData.cc(113) match: aclMatchDomainList: 'r1---sn-4g57knls.googlevideo.com' NOT found 2015/06/30 10:09:38.433 kid1| Acl.cc(158) matches: checked: betty1 = 0 2015/06/30 10:09:38.433 kid1| Acl.cc(158) matches: checked: always_direct#3 = 0 2015/06/30 10:09:38.433 kid1| Acl.cc(138) matches: checking always_direct#4 2015/06/30 10:09:38.433 kid1| Acl.cc(138) matches: checking all 2015/06/30 10:09:38.433 kid1| Ip.cc(95) aclIpAddrNetworkCompare: aclIpAddrNetworkCompare: compare: 10.11.20.1:15088/[::] ([::]:15088) vs [::]-[::]/[::] 2015/06/30 10:09:38.433 kid1| Ip.cc(539) match: aclIpMatchIp: '10.11.20.1:15088' found 2015/06/30 10:09:38.433 kid1| Acl.cc(158) matches: checked: all = 1 2015/06/30 10:09:38.433 kid1| Acl.cc(158) matches: checked: always_direct#4 = 1 2015/06/30 10:09:38.433 kid1| Acl.cc(158) matches: checked: always_direct = 1 2015/06/30 10:09:38.433 kid1| Checklist.cc(61) markFinished: 0x2077cf098 answer ALLOWED for match 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x2038018=0 2015/06/30 10:09:38.433 kid1| Checklist.cc(161) checkCallback: ACLChecklist::checkCallback: 0x2077cf098 answer=ALLOWED 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x38f7288 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x38f7288=0 2015/06/30 10:09:38.433 kid1| peer_select.cc(194) peerCheckAlwaysDirectDone: peerCheckAlwaysDirectDone: ALLOWED 2015/06/30 10:09:38.433 kid1| peer_select.cc(200) peerCheckAlwaysDirectDone: direct = DIRECT_YES (always_direct allow) 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x7abd088 2015/06/30 10:09:38.433 kid1| peer_select.cc(441) peerSelectFoo: GET r1---sn-4g57knls.googlevideo.com 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| client_side.cc(4974) validatePinnedConnection: local=10.150.15.11:47595 remote=74.125.99.6:443 FD 132 flags=1 2015/06/30 10:09:38.433 kid1| peer_select.cc(940) peerAddFwdServer: peerAddFwdServer: adding DIRECT PINNED 2015/06/30 10:09:38.433 kid1| peer_select.cc(940) peerAddFwdServer: peerAddFwdServer: adding DIRECT HIER_DIRECT 2015/06/30 10:09:38.433 kid1| peer_select.cc(940) peerAddFwdServer: peerAddFwdServer: adding DIRECT PINNED 2015/06/30 10:09:38.433 kid1| peer_select.cc(940) peerAddFwdServer: peerAddFwdServer: adding DIRECT HIER_DIRECT 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x7abd088 2015/06/30 10:09:38.433 kid1| peer_select.cc(258) peerSelectDnsPaths: Find IP destination for: http://cdn.youtube/id=o-ACQ6eJqVKCPZIUmKoUQvsHsrXismY31LLzsOB4swKbq-&itag=135mime=video/mp4' via r1--$ 2015/06/30 10:09:38.433 kid1| ipcache.cc(501) ipcache_nbgethostbyname: ipcache_nbgethostbyname: Name 'r1---sn-4g57knls.googlevideo.com'. 2015/06/30 10:09:38.433 kid1| Address.cc(389) lookupHostIP: Given Non-IP 'r1---sn-4g57knls.googlevideo.com': Name or service not known 2015/06/30 10:09:38.433 kid1| ipcache.cc(533) ipcache_nbgethostbyname: ipcache_nbgethostbyname: HIT for 'r1---sn-4g57knls.googlevideo.com' 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x38f7288=1 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x38f7288 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x38f7288=0 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x7abd088 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x7abd088 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x7abd088 2015/06/30 10:09:38.433 kid1| peer_select.cc(280) peerSelectDnsPaths: Found sources for 'http://cdn.youtube/id=o-ACQ6eJqVKCPZIUmKoUQvsHsrXismY31LLzsOB4swKbq-&itag=135mime=video/mp4' 2015/06/30 10:09:38.433 kid1| peer_select.cc(281) peerSelectDnsPaths: always_direct = ALLOWED 2015/06/30 10:09:38.433 kid1| peer_select.cc(282) peerSelectDnsPaths: never_direct = DUNNO 2015/06/30 10:09:38.433 kid1| peer_select.cc(290) peerSelectDnsPaths: PINNED = local=0.0.0.0 remote=74.125.99.6:443 flags=1 2015/06/30 10:09:38.433 kid1| peer_select.cc(288) peerSelectDnsPaths: ORIGINAL_DST = local=0.0.0.0 remote=74.125.99.6:443 flags=1 2015/06/30 10:09:38.433 kid1| peer_select.cc(295) peerSelectDnsPaths: timedout = 0 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x7abd088 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x7abd088=1 2015/06/30 10:09:38.433 kid1| FwdState.cc(383) startConnectionOrFail: http://cdn.youtube/id=o-ACQ6eJqVKCPZIUmKoUQvsHsrXismY31LLzsOB4swKbq-&itag=135mime=video/mp4 2015/06/30 10:09:38.433 kid1| HttpRequest.cc(486) clearError: old error details: 0/0 2015/06/30 10:09:38.433 kid1| FwdState.cc(781) connectStart: fwdConnectStart: http://cdn.youtube/id=o-ACQ6eJqVKCPZIUmKoUQvsHsrXismY31LLzsOB4swKbq-&itag=135mime=video/mp4 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x207709228 2015/06/30 10:09:38.433 kid1| FwdState.cc(798) connectStart: pinned peer connection: 0x207709228 2015/06/30 10:09:38.433 kid1| client_side.cc(4999) borrowPinnedConnection: local=10.150.15.11:47595 remote=74.125.99.6:443 FD 132 flags=1 2015/06/30 10:09:38.433 kid1| client_side.cc(4974) validatePinnedConnection: local=10.150.15.11:47595 remote=74.125.99.6:443 FD 132 flags=1 2015/06/30 10:09:38.433 kid1| comm.cc(983) comm_add_close_handler: comm_add_close_handler: FD 132, handler=1, data=0x7abd088 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x7abd088=2 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x7abd088=3 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(26) AsyncCall: The AsyncCall SomeCloseHandler constructed, this=0x209c42180 [call226891275] 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x7abd088=4 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x7abd088=3 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x7abd088=2 2015/06/30 10:09:38.433 kid1| comm.cc(993) comm_add_close_handler: comm_add_close_handler: FD 132, AsyncCall=0x209c42180*1 2015/06/30 10:09:38.433 kid1| FwdState.cc(902) dispatch: local=74.125.99.6:443 remote=10.11.20.1:15088 FD 17 flags=33: Fetching GET http://cdn.youtube/id=o-ACQ6eJqVKCPZIUmKoUQvsHsrXismY31LLzsOB4sw$ 2015/06/30 10:09:38.433 kid1| http.cc(2261) httpStart: GET http://cdn.youtube/id=o-ACQ6eJqVKCPZIUmKoUQvsHsrXismY31LLzsOB4swKbq-&itag=135mime=video/mp4 2015/06/30 10:09:38.433 kid1| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x1bdb83218 2015/06/30 10:09:38.433 kid1| AsyncJob.cc(34) AsyncJob: AsyncJob constructed, this=0x1bdb83308 type=HttpStateData [job8249863] 2015/06/30 10:09:38.433 kid1| store.cc(485) lock: Client locked key C1D7592C129BCF72DDE33D05D8CDEC4F e:=p2DIWV/0x1cb0af600*4 2015/06/30 10:09:38.433 kid1| http.cc(89) HttpStateData: HttpStateData 0x1bdb83218 created 2015/06/30 10:09:38.433 kid1| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x1cf024708 2015/06/30 10:09:38.433 kid1| http.cc(89) HttpStateData: HttpStateData 0x1bdb83218 created 2015/06/30 10:09:38.433 kid1| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x1cf024708 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=1 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=2 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=3 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=4 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=3 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(26) AsyncCall: The AsyncCall HttpStateData::httpStateConnClosed constructed, this=0x208b0d090 [call226891276] 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=4 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=5 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=4 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=3 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=2 2015/06/30 10:09:38.433 kid1| comm.cc(993) comm_add_close_handler: comm_add_close_handler: FD 132, AsyncCall=0x208b0d090*1 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=3 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=4 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(26) AsyncCall: The AsyncCall AsyncJob::start constructed, this=0x1977797e0 [call226891277] 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=5 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(93) ScheduleCall: AsyncJob.cc(26) will call AsyncJob::start() [call226891277] 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=4 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=3 2015/06/30 10:09:38.433 kid1| peer_select.cc(79) ~ps_state: http://cdn.youtube/id=o-ACQ6eJqVKCPZIUmKoUQvsHsrXismY31LLzsOB4swKbq-&itag=135mime=video/mp4 2015/06/30 10:09:38.433 kid1| store.cc(523) unlock: peerSelect unlocking key C1D7592C129BCF72DDE33D05D8CDEC4F e:=p2DIWV/0x1cb0af600*4 2015/06/30 10:09:38.433 kid1| cbdata.cc(321) cbdataInternalFree: 0x38f7288 2015/06/30 10:09:38.433 kid1| cbdata.cc(338) cbdataInternalFree: Freeing 0x38f7288 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x207709228=12 2015/06/30 10:09:38.433 kid1| FilledChecklist.cc(66) ~ACLFilledChecklist: ACLFilledChecklist destroyed 0x2077cf098 2015/06/30 10:09:38.433 kid1| Checklist.cc(195) ~ACLChecklist: ACLChecklist::~ACLChecklist: destroyed 0x2077cf098 2015/06/30 10:09:38.433 kid1| cbdata.cc(321) cbdataInternalFree: 0x2077cf098 2015/06/30 10:09:38.433 kid1| cbdata.cc(338) cbdataInternalFree: Freeing 0x2077cf098 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x207709228=11 2015/06/30 10:09:38.433 kid1| FilledChecklist.cc(66) ~ACLFilledChecklist: ACLFilledChecklist destroyed 0x1c3d6fcb8 2015/06/30 10:09:38.433 kid1| Checklist.cc(195) ~ACLChecklist: ACLChecklist::~ACLChecklist: destroyed 0x1c3d6fcb8 2015/06/30 10:09:38.433 kid1| cbdata.cc(321) cbdataInternalFree: 0x1c3d6fcb8 2015/06/30 10:09:38.433 kid1| cbdata.cc(338) cbdataInternalFree: Freeing 0x1c3d6fcb8 2015/06/30 10:09:38.433 kid1| SBuf.cc(124) ~SBuf: SBuf47491706 destructed 2015/06/30 10:09:38.433 kid1| MemBlob.cc(83) ~MemBlob: destructed, this=0x207bb6ad0 id=blob18581031 capacity=1024 size=881 2015/06/30 10:09:38.433 kid1| cbdata.cc(321) cbdataInternalFree: 0x1bc392258 2015/06/30 10:09:38.433 kid1| cbdata.cc(338) cbdataInternalFree: Freeing 0x1bc392258 2015/06/30 10:09:38.433 kid1| helper.cc(1167) GetFirstAvailable: GetFirstAvailable: Running servers 1 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x260f758=3 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x260f758=4 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x260f758=3 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x260f758=4 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(26) AsyncCall: The AsyncCall helperHandleRead constructed, this=0x206469a30 [call226891278] 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x260f758=5 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x260f758=4 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x260f758=3 2015/06/30 10:09:38.433 kid1| Read.cc(58) comm_read_base: comm_read, queueing read for local=[::] remote=[::] FD 113 flags=1; asynCall 0x206469a30*1 2015/06/30 10:09:38.433 kid1| ModEpoll.cc(116) SetSelect: FD 113, type=1, handler=1, client_data=0x7fbc33d7c510, timeout=0 2015/06/30 10:09:38.433 kid1| AsyncCallQueue.cc(57) fireNext: leaving helperHandleRead(local=[::] remote=[::] FD 113 flags=1, data=0x260f758, size=108, buf=0x260f9e0) 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x260f758=2 2015/06/30 10:09:38.433 kid1| AsyncCallQueue.cc(55) fireNext: entering TunnelBlindCopyWriteHandler(local=216.58.210.10:443 remote=10.11.20.1:15053 FD 24 flags=33, data=0xa3698a8, size=7596, buf=0x$ 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(38) make: make call TunnelBlindCopyWriteHandler [call226891274] 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0xa3698a8 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0xa3698a8 2015/06/30 10:09:38.433 kid1| tunnel.cc(636) writeClientDone: local=216.58.210.10:443 remote=10.11.20.1:15053 FD 24 flags=33, 7596 bytes written, flag=0 2015/06/30 10:09:38.433 kid1| tunnel.cc(624) dataSent: len=7596 - amount=7596 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0xa3698a8=7 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0xa3698a8 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0xa3698a8=8 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0xa3698a8=9 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(26) AsyncCall: The AsyncCall TunnelBlindCopyReadHandler constructed, this=0x294e6a0 [call226891279] 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0xa3698a8=10 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0xa3698a8=9 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0xa3698a8=8 2015/06/30 10:09:38.433 kid1| Read.cc(58) comm_read_base: comm_read, queueing read for local=10.150.15.11:43712 remote=216.58.210.10:443 FD 27 flags=1; asynCall 0x294e6a0*1 2015/06/30 10:09:38.433 kid1| ModEpoll.cc(116) SetSelect: FD 27, type=1, handler=1, client_data=0x7fbc33d79cc0, timeout=0 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0xa3698a8=7 2015/06/30 10:09:38.433 kid1| AsyncCallQueue.cc(57) fireNext: leaving TunnelBlindCopyWriteHandler(local=216.58.210.10:443 remote=10.11.20.1:15053 FD 24 flags=33, data=0xa3698a8, size=7596, buf=0x2$ 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0xa3698a8=6 2015/06/30 10:09:38.433 kid1| AsyncCallQueue.cc(55) fireNext: entering AsyncJob::start() 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(38) make: make call AsyncJob::start [call226891277] 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| AsyncJob.cc(123) callStart: HttpStateData status in: [ job8249863] 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| http.cc(2141) sendRequest: local=10.150.15.11:47595 remote=74.125.99.6:443 FD 132 flags=1, request 0x1f63a2650, this 0x1bdb83218. 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=4 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=5 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=6 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=7 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=6 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(26) AsyncCall: The AsyncCall HttpStateData::httpTimeout constructed, this=0x1d5cbc4f0 [call226891280] 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(26) AsyncCall: The AsyncCall HttpStateData::httpTimeout constructed, this=0x1d5cbc4f0 [call226891280] 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=7 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=8 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=7 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=6 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=5 2015/06/30 10:09:38.433 kid1| comm.cc(553) commSetConnTimeout: local=10.150.15.11:47595 remote=74.125.99.6:443 FD 132 flags=1 timeout 86400 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x11e9df98=7 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x11e9df98=6 2015/06/30 10:09:38.433 kid1| http.cc(1498) maybeReadVirginBody: may read up to 16383 bytes from local=10.150.15.11:47595 remote=74.125.99.6:443 FD 132 flags=1 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=6 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=7 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=8 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=9 2015/06/30 10:09:38.433 kid1| cbdata.cc(426) cbdataInternalUnlock: 0x1bdb83218=8 2015/06/30 10:09:38.433 kid1| AsyncCall.cc(26) AsyncCall: The AsyncCall HttpStateData::readReply constructed, this=0x201f4a450 [call226891281] 2015/06/30 10:09:38.433 kid1| cbdata.cc(492) cbdataReferenceValid: 0x1bdb83218 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=9 2015/06/30 10:09:38.433 kid1| cbdata.cc(394) cbdataInternalLock: 0x1bdb83218=10 2015/06/30 10:09:38.433 kid1| Read.cc(58) comm_read_base: comm_read, queueing read for local=10.150.15.11:47595 remote=74.125.99.6:443 FD 132 flags=1; asynCall 0x201f4a450*1 2015/06/30 10:09:38.433 kid1| assertion failed: Read.cc:69: "fd_table[conn->fd].halfClosedReader != NULL" 2015/06/30 10:09:41.916 kid1| tools.cc(610) enter_suid: enter_suid: PID 11394 taking root privileges 2015/06/30 10:09:41.916 kid1| cache_manager.cc(80) registerProfile: registering legacy config 2015/06/30 10:09:41.916 kid1| cache_manager.cc(114) findAction: CacheManager::findAction: looking for action config 2015/06/30 10:09:41.916 kid1| cache_manager.cc(122) findAction: Action not found. 2015/06/30 10:09:41.916 kid1| cache_manager.cc(65) registerProfile: registered profile: config 2015/06/30 10:09:41.916 kid1| mem.cc(473) Report: Memory pools are 'on'; limit: 100.000 MB 2015/06/30 10:09:41.916 kid1| main.cc(1427) SquidMain: Doing post-config initialization 2015/06/30 10:09:41.916 kid1| tools.cc(543) leave_suid: leave_suid: PID 11394 called 2015/06/30 10:09:41.916 kid1| tools.cc(565) leave_suid: leave_suid: PID 11394 giving up root, becoming 'proxy' 2015/06/30 10:09:41.916 kid1| main.cc(1429) SquidMain: running RegisteredRunner::finalizeConfig 2015/06/30 10:09:41.916 kid1| main.cc(1430) SquidMain: running RegisteredRunner::claimMemoryNeeds 2015/06/30 10:09:41.916 kid1| main.cc(1431) SquidMain: running RegisteredRunner::useConfig 2015/06/30 10:09:41.916 kid1| cache_manager.cc(80) registerProfile: registering legacy client_list 2015/06/30 10:09:41.916 kid1| cache_manager.cc(114) findAction: CacheManager::findAction: looking for action client_list 2015/06/30 10:09:41.916 kid1| cache_manager.cc(122) findAction: Action not found. 2015/06/30 10:09:41.916 kid1| cache_manager.cc(65) registerProfile: registered profile: client_list 2015/06/30 10:09:41.916 kid1| tools.cc(610) enter_suid: enter_suid: PID 11394 taking root privileges 2015/06/30 10:09:41.918 kid1| cache_manager.cc(80) registerProfile: registering legacy comm_epoll_incoming 2015/06/30 10:09:41.918 kid1| cache_manager.cc(114) findAction: CacheManager::findAction: looking for action comm_epoll_incoming 2015/06/30 10:09:41.918 kid1| cache_manager.cc(122) findAction: Action not found. 2015/06/30 10:09:41.918 kid1| cache_manager.cc(65) registerProfile: registered profile: comm_epoll_incoming 2015/06/30 10:09:41.918 kid1| Set Current Directory to /var/spool/squid 2015/06/30 10:09:41.918 kid1| cache_manager.cc(65) registerProfile: registered profile: comm_epoll_incoming 2015/06/30 10:09:41.918 kid1| Set Current Directory to /var/spool/squid 2015/06/30 10:09:41.918 kid1| tools.cc(543) leave_suid: leave_suid: PID 11394 called 2015/06/30 10:09:41.918 kid1| tools.cc(565) leave_suid: leave_suid: PID 11394 giving up root, becoming 'proxy' 2015/06/30 10:09:41.918 kid1| fd.cc(198) fd_open: fd_open() FD 4 /var/log/squid/cache.log 2015/06/30 10:09:41.918 kid1| Starting Squid Cache version 3.5.5-20150624-r13848 for x86_64-unknown-linux-gnu... 2015/06/30 10:09:41.918 kid1| Service Name: squid 2015/06/30 10:09:41.918 kid1| Process ID 11394 2015/06/30 10:09:41.918 kid1| Process Roles: worker 2015/06/30 10:09:41.918 kid1| With 65535 file descriptors available 2015/06/30 10:09:41.918 kid1| Initializing IP Cache... 2015/06/30 10:09:41.918 kid1| cache_manager.cc(80) registerProfile: registering legacy ipcache 2015/06/30 10:09:41.918 kid1| cache_manager.cc(114) findAction: CacheManager::findAction: looking for action ipcache 2015/06/30 10:09:41.918 kid1| cache_manager.cc(122) findAction: Action not found. 2015/06/30 10:09:41.918 kid1| cache_manager.cc(65) registerProfile: registered profile: ipcache 2015/06/30 10:09:41.918 kid1| cache_manager.cc(80) registerProfile: registering legacy fqdncache 2015/06/30 10:09:41.918 kid1| cache_manager.cc(114) findAction: CacheManager::findAction: looking for action fqdncache 2015/06/30 10:09:41.919 kid1| cache_manager.cc(122) findAction: Action not found. 2015/06/30 10:09:41.919 kid1| cache_manager.cc(65) registerProfile: registered profile: fqdncache 2015/06/30 10:09:41.919 kid1| fqdncache.cc(720) fqdncache_init: Initializing FQDN Cache... 2015/06/30 10:09:41.919 kid1| tools.cc(1054) parseEtcHosts: etc_hosts: line is '127.0.0.1 localhost ' 2015/06/30 10:09:41.919 kid1| tools.cc(1063) parseEtcHosts: etc_hosts: address is '127.0.0.1' 2015/06/30 10:09:41.919 kid1| tools.cc(1077) parseEtcHosts: etc_hosts: got hostname 'localhost' 2015/06/30 10:09:41.919 kid1| tools.cc(1054) parseEtcHosts: etc_hosts: line is '10.150.15.10 issa ' 2015/06/30 10:09:41.919 kid1| tools.cc(1063) parseEtcHosts: etc_hosts: address is '10.150.15.10' 2015/06/30 10:09:41.919 kid1| tools.cc(1077) parseEtcHosts: etc_hosts: got hostname 'issa' 2015/06/30 10:09:41.919 kid1| tools.cc(1054) parseEtcHosts: etc_hosts: line is ' ' 2015/06/30 10:09:41.919 kid1| tools.cc(1063) parseEtcHosts: etc_hosts: address is '' 2015/06/30 10:09:41.919 kid1| tools.cc(1054) parseEtcHosts: etc_hosts: line is '::1 localhost ip6-localhost ip6-loopback ' 2015/06/30 10:09:41.919 kid1| tools.cc(1063) parseEtcHosts: etc_hosts: address is '::1' 2015/06/30 10:09:41.919 kid1| tools.cc(1071) parseEtcHosts: etc_hosts: multiple spaces, skipping 2015/06/30 10:09:41.919 kid1| tools.cc(1071) parseEtcHosts: etc_hosts: multiple spaces, skipping 2015/06/30 10:09:41.919 kid1| tools.cc(1071) parseEtcHosts: etc_hosts: multiple spaces, skipping 2015/06/30 10:09:41.919 kid1| tools.cc(1071) parseEtcHosts: etc_hosts: multiple spaces, skipping 2015/06/30 10:09:41.919 kid1| tools.cc(1077) parseEtcHosts: etc_hosts: got hostname 'localhost' 2015/06/30 10:09:41.919 kid1| ipcache.cc(174) ipcacheRelease: ipcacheRelease: Releasing entry for 'localhost' 2015/06/30 10:09:41.919 kid1| tools.cc(1077) parseEtcHosts: etc_hosts: got hostname 'ip6-localhost' 2015/06/30 10:09:41.919 kid1| tools.cc(1077) parseEtcHosts: etc_hosts: got hostname 'ip6-loopback' 2015/06/30 10:09:41.919 kid1| tools.cc(1054) parseEtcHosts: etc_hosts: line is 'ff02::1 ip6-allnodes ' 2015/06/30 10:09:41.919 kid1| tools.cc(1063) parseEtcHosts: etc_hosts: address is 'ff02::1' 2015/06/30 10:09:41.919 kid1| tools.cc(1077) parseEtcHosts: etc_hosts: got hostname 'ip6-allnodes' 2015/06/30 10:09:41.919 kid1| tools.cc(1054) parseEtcHosts: etc_hosts: line is 'ff02::2 ip6-allrouters -- View this message in context: http://squid-web-proxy-cache.1019090.n4.nabble.com/assertion-failed-comm-cc-178-fd-table-conn-fd-halfClosedReader-NULL-tp4670979p4671958.html Sent from the Squid - Users mailing list archive at Nabble.com. _______________________________________________ squid-users mailing list squid-users@xxxxxxxxxxxxxxxxxxxxx http://lists.squid-cache.org/listinfo/squid-users
