[squid-users] X-Squid-Error
joe
chip_pop at hotmail.com
Fri Aug 12 18:22:44 UTC 2016
hmmm if i try to vue the same page cnn.com no more error
wen i clean my cache and try again it happen it look like only on clean
cache can triger it
debug_options ALL,3
2016/08/12 21:05:45.904 kid1| 36,3| ufs/UFSSwapDir.cc(1334) DirClean:
Cleaning directory /mnt/cache/02/7C
2016/08/12 21:06:00.905 kid1| 36,3| ufs/UFSSwapDir.cc(1334) DirClean:
Cleaning directory /mnt/cache/03/7C
2016/08/12 21:06:15.906 kid1| 36,3| ufs/UFSSwapDir.cc(1334) DirClean:
Cleaning directory /mnt/cache/04/7C
2016/08/12 21:06:18.660 kid1| 5,2| TcpAcceptor.cc(226) doAccept: New
connection on FD 20
2016/08/12 21:06:18.660 kid1| 5,2| TcpAcceptor.cc(315) acceptNext:
connection on local=0.0.0.0:3128 remote=[::] FD 20 flags=41
2016/08/12 21:06:18.660 kid1| 51,3| fd.cc(199) fd_open: fd_open() FD 11 HTTP
Request
2016/08/12 21:06:18.660 kid1| 28,3| Eui48.cc(516) lookup: id=0x181b404
10.4.4.60 NOT found
2016/08/12 21:06:18.660 kid1| 78,3| dns_internal.cc(1823) idnsPTRLookup:
idnsPTRLookup: buf is 40 bytes for 10.4.4.60, id = 0x28ae
2016/08/12 21:06:18.660 kid1| 50,3| comm.cc(960) comm_udp_sendto:
comm_udp_sendto: Attempt to send UDP packet to 4.2.2.3:53 using FD 6 using
Port 10982
2016/08/12 21:06:18.660 kid1| 5,3| comm.cc(559) commSetConnTimeout:
local=54.192.217.48:80 remote=10.4.4.60:3318 FD 11 flags=33 timeout 300
2016/08/12 21:06:18.778 kid1| 78,3| dns_internal.cc(1330) idnsRead:
idnsRead: starting with FD 6
2016/08/12 21:06:18.778 kid1| 78,3| dns_internal.cc(1376) idnsRead:
idnsRead: FD 6: received 99 bytes from 4.2.2.3:53
2016/08/12 21:06:18.778 kid1| 78,3| dns_internal.cc(1183) idnsGrokReply:
idnsGrokReply: QID 0x28ae, -3 answers
2016/08/12 21:06:18.778 kid1| 78,3| dns_internal.cc(1248) idnsGrokReply:
idnsGrokReply: error Name Error: The domain name does not exist. (3)
2016/08/12 21:06:18.778 kid1| 35,3| fqdncache.cc(327) fqdncacheParse:
fqdncacheParse: Lookup of '10.4.4.60' failed (Name Error: The domain name
does not exist.)
2016/08/12 21:06:20.969 kid1| 5,2| TcpAcceptor.cc(226) doAccept: New
connection on FD 20
2016/08/12 21:06:20.969 kid1| 5,2| TcpAcceptor.cc(315) acceptNext:
connection on local=0.0.0.0:3128 remote=[::] FD 20 flags=41
2016/08/12 21:06:20.969 kid1| 51,3| fd.cc(199) fd_open: fd_open() FD 13 HTTP
Request
2016/08/12 21:06:20.969 kid1| 28,3| Eui48.cc(516) lookup: id=0x1409634
10.4.4.61 NOT found
2016/08/12 21:06:20.969 kid1| 78,3| dns_internal.cc(1823) idnsPTRLookup:
idnsPTRLookup: buf is 40 bytes for 10.4.4.61, id = 0x75b7
2016/08/12 21:06:20.969 kid1| 50,3| comm.cc(960) comm_udp_sendto:
comm_udp_sendto: Attempt to send UDP packet to 4.2.2.3:53 using FD 6 using
Port 10982
2016/08/12 21:06:20.969 kid1| 5,3| comm.cc(559) commSetConnTimeout:
local=173.236.187.17:80 remote=10.4.4.61:12441 FD 13 flags=33 timeout 300
2016/08/12 21:06:20.969 kid1| 5,3| IoCallback.cc(116) finish: called for
local=173.236.187.17:80 remote=10.4.4.61:12441 FD 13 flags=33 (0, 0)
2016/08/12 21:06:20.969 kid1| 5,3| Read.cc(92) ReadNow:
local=173.236.187.17:80 remote=10.4.4.61:12441 FD 13 flags=33, size 4096,
retval 363, errno 0
2016/08/12 21:06:20.969 kid1| 5,3| comm.cc(559) commSetConnTimeout:
local=173.236.187.17:80 remote=10.4.4.61:12441 FD 13 flags=33 timeout 120
2016/08/12 21:06:20.970 kid1| 5,3| IoCallback.cc(116) finish: called for
local=173.236.187.17:80 remote=10.4.4.61:12441 FD 13 flags=33 (0, 0)
2016/08/12 21:06:20.970 kid1| 5,3| Read.cc(92) ReadNow:
local=173.236.187.17:80 remote=10.4.4.61:12441 FD 13 flags=33, size 3733,
retval 0, errno 0
2016/08/12 21:06:20.970 kid1| 33,3| client_side.cc(1429)
connFinishedWithConn: local=173.236.187.17:80 remote=10.4.4.61:12441 FD 13
flags=33 aborted (half_closed_clients disabled)
2016/08/12 21:06:20.970 kid1| 5,3| comm.cc(871) _comm_close: comm_close:
start closing FD 13
2016/08/12 21:06:20.970 kid1| 5,3| comm.cc(546) commUnsetFdTimeout: Remove
timeout for FD 13
2016/08/12 21:06:20.970 kid1| 33,2| client_side.cc(586) swanSong:
local=173.236.187.17:80 remote=10.4.4.61:12441 flags=33
2016/08/12 21:06:20.970 kid1| 33,3| client_side_request.cc(270)
~ClientHttpRequest: httpRequestFree: error:transaction-end-before-headers
2016/08/12 21:06:20.970 kid1| 28,3| Checklist.cc(70) preCheck:
0x7ffc80a911a0 checking fast ACLs
2016/08/12 21:06:20.970 kid1| 28,3| Acl.cc(290) matches: checked:
(access_log daemon:/var/log/squid/access.log line) = 1
2016/08/12 21:06:20.970 kid1| 28,3| Acl.cc(290) matches: checked: access_log
daemon:/var/log/squid/access.log = 1
2016/08/12 21:06:20.970 kid1| 28,3| Checklist.cc(63) markFinished:
0x7ffc80a911a0 answer ALLOWED for match
2016/08/12 21:06:20.970 kid1| 50,3| ModDaemon.cc(174)
logfile_mod_daemon_append: logfile_mod_daemon_append:
daemon:/var/log/squid/access.log: appending 1 bytes
2016/08/12 21:06:20.970 kid1| 50,3| ModDaemon.cc(178)
logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 0
of 32768 bytes before append
2016/08/12 21:06:20.970 kid1| 50,3| ModDaemon.cc(174)
logfile_mod_daemon_append: logfile_mod_daemon_append:
daemon:/var/log/squid/access.log: appending 100 bytes
2016/08/12 21:06:20.970 kid1| 50,3| ModDaemon.cc(178)
logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 1
of 32768 bytes before append
2016/08/12 21:06:20.970 kid1| 50,3| ModDaemon.cc(174)
logfile_mod_daemon_append: logfile_mod_daemon_append:
daemon:/var/log/squid/access.log: appending 7 bytes
2016/08/12 21:06:20.970 kid1| 50,3| ModDaemon.cc(178)
logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 101
of 32768 bytes before append
2016/08/12 21:06:20.970 kid1| 33,3| client_side.cc(4004) unpinConnection:
2016/08/12 21:06:20.970 kid1| 33,3| client_side.cc(616) ~ConnStateData:
local=173.236.187.17:80 remote=10.4.4.61:12441 flags=33
2016/08/12 21:06:20.970 kid1| 51,3| fd.cc(94) fd_close: fd_close FD 13
Reading next request
2016/08/12 21:06:21.057 kid1| 78,3| dns_internal.cc(1330) idnsRead:
idnsRead: starting with FD 6
2016/08/12 21:06:21.057 kid1| 78,3| dns_internal.cc(1376) idnsRead:
idnsRead: FD 6: received 99 bytes from 4.2.2.3:53
2016/08/12 21:06:21.057 kid1| 78,3| dns_internal.cc(1183) idnsGrokReply:
idnsGrokReply: QID 0x75b7, -3 answers
2016/08/12 21:06:21.057 kid1| 78,3| dns_internal.cc(1248) idnsGrokReply:
idnsGrokReply: error Name Error: The domain name does not exist. (3)
2016/08/12 21:06:21.057 kid1| 35,3| fqdncache.cc(327) fqdncacheParse:
fqdncacheParse: Lookup of '10.4.4.61' failed (Name Error: The domain name
does not exist.)
2016/08/12 21:06:21.081 kid1| 5,2| TcpAcceptor.cc(226) doAccept: New
connection on FD 20
2016/08/12 21:06:21.081 kid1| 5,2| TcpAcceptor.cc(315) acceptNext:
connection on local=0.0.0.0:3128 remote=[::] FD 20 flags=41
2016/08/12 21:06:21.081 kid1| 51,3| fd.cc(199) fd_open: fd_open() FD 13 HTTP
Request
2016/08/12 21:06:21.081 kid1| 28,3| Eui48.cc(516) lookup: id=0x1409634
10.4.4.61 NOT found
2016/08/12 21:06:21.082 kid1| 5,3| comm.cc(559) commSetConnTimeout:
local=173.236.187.17:80 remote=10.4.4.61:12442 FD 13 flags=33 timeout 300
2016/08/12 21:06:21.082 kid1| 5,3| IoCallback.cc(116) finish: called for
local=173.236.187.17:80 remote=10.4.4.61:12442 FD 13 flags=33 (0, 0)
2016/08/12 21:06:21.082 kid1| 5,3| Read.cc(92) ReadNow:
local=173.236.187.17:80 remote=10.4.4.61:12442 FD 13 flags=33, size 4096,
retval 373, errno 0
2016/08/12 21:06:21.082 kid1| 5,3| comm.cc(559) commSetConnTimeout:
local=173.236.187.17:80 remote=10.4.4.61:12442 FD 13 flags=33 timeout 120
2016/08/12 21:06:21.082 kid1| 87,3| clientStream.cc(140)
clientStreamInsertHead: clientStreamInsertHead: Inserted node 0x140afc8 with
data 0x140ce78 after head
2016/08/12 21:06:21.082 kid1| 5,3| comm.cc(559) commSetConnTimeout:
local=173.236.187.17:80 remote=10.4.4.61:12442 FD 13 flags=33 timeout 86400
2016/08/12 21:06:21.082 kid1| 33,3| Pipeline.cc(24) add: Pipeline 0x14096e0
add request 1 0x140be10*3
2016/08/12 21:06:21.082 kid1| 33,2| Http1Server.cc(101) buildHttpRequest:
Invalid Request
2016/08/12 21:06:21.082 kid1| 20,3| store.cc(777) storeCreatePureEntry:
storeCreateEntry: 'error:invalid-request'
2016/08/12 21:06:21.082 kid1| 20,3| MemObject.cc(110) MemObject: new
MemObject 0x1824f10
2016/08/12 21:06:21.082 kid1| 20,3| store.cc(472) setReleaseFlag:
StoreEntry::setReleaseFlag: '[null_store_key]'
2016/08/12 21:06:21.082 kid1| 20,3| store.cc(421) hashInsert:
StoreEntry::hashInsert: Inserting Entry e:=XI/0x140e330*0 key
'34000000000000009A53000001000000'
2016/08/12 21:06:21.082 kid1| 20,3| store.cc(457) lock: storeCreateEntry
locked key 34000000000000009A53000001000000 e:=XIV/0x140e330*1
2016/08/12 21:06:21.082 kid1| 4,2| errorpage.cc(1260) BuildContent: No
existing error page language negotiated for ERR_PROTOCOL_UNKNOWN. Using
default error file.
2016/08/12 21:06:21.082 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%l --> '/*
* Copyright (C) 1996-2015 The Squid Software Foundation and contributors
*
* Squid software is distributed under GPLv2+ license and includes
* contributions from numerous individuals and organizations.
* Please see the COPYING and CONTRIBUTORS files for details.
*/
/*
Stylesheet for Squid Error pages
Adapted from design by Free CSS Templates
http://www.freecsstemplates.org
Released for free under a Creative Commons Attribution 2.5 License
*/
/* Page basics */
* {
font-family: verdana, sans-serif;
}
html body {
margin: 0;
padding: 0;
background: #efefef;
font-size: 12px;
color: #1e1e1e;
}
/* Page displayed title area */
#titles {
margin-left: 15px;
padding: 10px;
padding-left: 100px;
background: url('http://www.squid-cache.org/Artwork/SN.png') no-repeat
left;
}
/* initial title */
#titles h1 {
color: #000000;
}
#titles h2 {
color: #000000;
}
/* special event: FTP success page titles */
#titles ftpsuccess {
background-color:#00ff00;
width:100%;
}
/* Page displayed body content area */
#content {
padding: 10px;
background: #ffffff;
}
/* General text */
p {
}
/* error brief description */
#error p {
}
/* some data which may have caused the problem */
#data {
}
/* the error message received from the system or other software */
#sysmsg {
}
pre {
font-family:sans-serif;
}
/* special event: FTP / Gopher directory listing */
#dirmsg {
font-family: courier;
color: black;
font-size: 10pt;
}
#dirlisting {
margin-left: 2%;
margin-right: 2%;
}
#dirlisting tr.entry td.icon,td.filename,td.size,td.date {
border-bottom: groove;
}
#dirlisting td.size {
width: 50px;
text-align: right;
padding-right: 5px;
}
/* horizontal lines */
hr {
margin: 0;
}
/* page displayed footer area */
#footer {
font-size: 9px;
padding-left: 10px;
}
'
2016/08/12 21:06:21.082 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%; --> '%;'
2016/08/12 21:06:21.082 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%c --> 'ERR_PROTOCOL_UNKNOWN'
2016/08/12 21:06:21.082 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%U --> 'error:invalid-request'
2016/08/12 21:06:21.082 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%U --> 'error:invalid-request'
2016/08/12 21:06:21.082 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%w --> 'webmaster'
2016/08/12 21:06:21.082 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%W -->
'?subject=CacheErrorInfo%20-%20ERR_PROTOCOL_UNKNOWN&body=CacheHost%3A%20proxy.netgatesss.com%0D%0AErrPage%3A%20ERR_PROTOCOL_UNKNOWN%0D%0AErr%3A%20%5Bnone%5D%0D%0ATimeStamp%3A%20Fri,%2012%20Aug%202016%2018%3A06%3A21%20GMT%0D%0A%0D%0AClientIP%3A%2010.4.4.61%0D%0A%0D%0AHTTP%20Request%3A%0D%0A%0D%0A%0D%0A'
2016/08/12 21:06:21.083 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%w --> 'webmaster'
2016/08/12 21:06:21.083 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%T --> 'Fri, 12 Aug 2016 18:06:21 GMT'
2016/08/12 21:06:21.083 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%h --> 'proxy.netgatesss.com'
2016/08/12 21:06:21.083 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%s --> 'squid'
2016/08/12 21:06:21.083 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%c --> 'ERR_PROTOCOL_UNKNOWN'
2016/08/12 21:06:21.083 kid1| 20,3| store.cc(457) lock:
StoreEntry::storeErrorResponse locked key 34000000000000009A53000001000000
e:=XIV/0x140e330*2
2016/08/12 21:06:21.083 kid1| 20,3| store.cc(1848) replaceHttpReply:
StoreEntry::replaceHttpReply: error:invalid-request
2016/08/12 21:06:21.083 kid1| 20,2| store.cc(990) checkCachable:
StoreEntry::checkCachable: NO: not cachable
2016/08/12 21:06:21.083 kid1| 20,3| store_swapout.cc(382) mayStartSwapOut:
not cachable
2016/08/12 21:06:21.083 kid1| 20,2| store.cc(990) checkCachable:
StoreEntry::checkCachable: NO: not cachable
2016/08/12 21:06:21.083 kid1| 90,3| store_client.cc(729) invokeHandlers:
InvokeHandlers: 34000000000000009A53000001000000
2016/08/12 21:06:21.083 kid1| 90,3| store_client.cc(735) invokeHandlers:
StoreEntry::InvokeHandlers: checking client #0
2016/08/12 21:06:21.083 kid1| 20,3| store.cc(1089) complete: storeComplete:
'34000000000000009A53000001000000'
2016/08/12 21:06:21.083 kid1| 20,3| store.cc(1342) validLength:
storeEntryValidLength: Checking '34000000000000009A53000001000000'
2016/08/12 21:06:21.083 kid1| 20,3| store_swapout.cc(357) mayStartSwapOut:
already rejected
2016/08/12 21:06:21.083 kid1| 20,2| store.cc(990) checkCachable:
StoreEntry::checkCachable: NO: not cachable
2016/08/12 21:06:21.083 kid1| 90,3| store_client.cc(729) invokeHandlers:
InvokeHandlers: 34000000000000009A53000001000000
2016/08/12 21:06:21.083 kid1| 90,3| store_client.cc(735) invokeHandlers:
StoreEntry::InvokeHandlers: checking client #0
2016/08/12 21:06:21.083 kid1| 20,3| store.cc(494) unlock:
StoreEntry::storeErrorResponse unlocking key
34000000000000009A53000001000000 e:=sXINV/0x140e330*2
2016/08/12 21:06:21.083 kid1| 87,3| clientStream.cc(180) clientStreamRead:
clientStreamRead: Calling 1 with cbdata 0x140e298 from node 0x140afc8
2016/08/12 21:06:21.083 kid1| 90,3| store_client.cc(198) copy:
store_client::copy: 34000000000000009A53000001000000, from 0, for length
4096, cb 1, cbdata 0x140d1e8
2016/08/12 21:06:21.083 kid1| 20,3| store.cc(457) lock: store_client::copy
locked key 34000000000000009A53000001000000 e:=sXINV/0x140e330*2
2016/08/12 21:06:21.083 kid1| 90,3| store_client.cc(295) storeClientCopy2:
storeClientCopy2: 34000000000000009A53000001000000
2016/08/12 21:06:21.083 kid1| 90,3| store_client.cc(429) scheduleMemRead:
store_client::doCopy: Copying normal from memory
2016/08/12 21:06:21.083 kid1| 33,2| QosConfig.cc(163) doTosLocalMiss: QOS:
Preserving TOS on miss, TOS=0
2016/08/12 21:06:21.083 kid1| 50,3| ../../src/ip/Qos.cci(22) setSockTos: for
FD 13 to 0
2016/08/12 21:06:21.083 kid1| 88,2| client_side_reply.cc(2054)
processReplyAccessResult: The reply for NONE error:invalid-request is
ALLOWED, because it matched (access_log daemon:/var/log/squid/access.log
line)
2016/08/12 21:06:21.083 kid1| 20,3| store.cc(457) lock:
ClientHttpRequest::loggingEntry locked key 34000000000000009A53000001000000
e:=sXINV/0x140e330*3
2016/08/12 21:06:21.083 kid1| 88,3| client_side_reply.cc(2092)
processReplyAccessResult: clientReplyContext::sendMoreData: Appending 3367
bytes after 199 bytes of headers
2016/08/12 21:06:21.083 kid1| 87,3| clientStream.cc(158)
clientStreamCallback: clientStreamCallback: Calling 1 with cbdata 0x140ce78
from node 0x1409a68
2016/08/12 21:06:21.083 kid1| 33,3| Pipeline.cc(35) front: Pipeline
0x14096e0 front 0x140be10*3
2016/08/12 21:06:21.083 kid1| 33,3| Pipeline.cc(35) front: Pipeline
0x14096e0 front 0x140be10*3
2016/08/12 21:06:21.083 kid1| 11,2| Stream.cc(266) sendStartOfMessage: HTTP
Client local=173.236.187.17:80 remote=10.4.4.61:12442 FD 13 flags=33
2016/08/12 21:06:21.083 kid1| 11,2| Stream.cc(267) sendStartOfMessage: HTTP
Client REPLY:
---------
HTTP/1.1 400 Bad Request
Server: squid
Mime-Version: 1.0
Date: Fri, 12 Aug 2016 18:06:21 GMT
Content-Type: text/html;charset=utf-8
Content-Length: 3367
X-Squid-Error: ERR_PROTOCOL_UNKNOWN 0
X-Cache: MISS from proxy.netgatesss.com
Connection: close
----------
2016/08/12 21:06:21.083 kid1| 20,3| store.cc(494) unlock: store_client::copy
unlocking key 34000000000000009A53000001000000 e:=sXINV/0x140e330*3
2016/08/12 21:06:21.084 kid1| 5,3| IoCallback.cc(116) finish: called for
local=173.236.187.17:80 remote=10.4.4.61:12442 FD 13 flags=33 (0, 0)
2016/08/12 21:06:21.084 kid1| 33,3| Pipeline.cc(35) front: Pipeline
0x14096e0 front 0x140be10*2
2016/08/12 21:06:21.084 kid1| 88,3| client_side_reply.cc(1150)
storeOKTransferDone: storeOKTransferDone out.offset=3367 objectLen()=3566
headers_sz=199
2016/08/12 21:06:21.084 kid1| 5,3| comm.cc(871) _comm_close: comm_close:
start closing FD 13
2016/08/12 21:06:21.084 kid1| 5,3| comm.cc(546) commUnsetFdTimeout: Remove
timeout for FD 13
2016/08/12 21:06:21.084 kid1| 87,3| clientStream.cc(198) clientStreamDetach:
clientStreamDetach: Detaching node 0x140afc8
2016/08/12 21:06:21.084 kid1| 87,3| clientStream.cc(97) ~clientStreamNode:
Freeing clientStreamNode 0x140afc8
2016/08/12 21:06:21.084 kid1| 87,3| clientStream.cc(219) clientStreamDetach:
clientStreamDetach: Calling 1 with cbdata 0x140e298
2016/08/12 21:06:21.084 kid1| 87,3| clientStream.cc(198) clientStreamDetach:
clientStreamDetach: Detaching node 0x1409a68
2016/08/12 21:06:21.084 kid1| 87,3| clientStream.cc(97) ~clientStreamNode:
Freeing clientStreamNode 0x1409a68
2016/08/12 21:06:21.084 kid1| 90,3| store_client.cc(661) storeUnregister:
storeUnregister: called for '34000000000000009A53000001000000'
2016/08/12 21:06:21.084 kid1| 20,3| store_swapout.cc(357) mayStartSwapOut:
already rejected
2016/08/12 21:06:21.084 kid1| 20,2| store.cc(990) checkCachable:
StoreEntry::checkCachable: NO: not cachable
2016/08/12 21:06:21.084 kid1| 20,3| store.cc(457) lock: storeUnregister
locked key 34000000000000009A53000001000000 e:=sXINV/0x140e330*3
2016/08/12 21:06:21.084 kid1| 90,3| store_client.cc(755)
storePendingNClients: storePendingNClients: returning 0
2016/08/12 21:06:21.084 kid1| 20,3| store.cc(494) unlock: storeUnregister
unlocking key 34000000000000009A53000001000000 e:=sXINV/0x140e330*3
2016/08/12 21:06:21.084 kid1| 20,3| store.cc(494) unlock:
clientReplyContext::removeStoreReference unlocking key
34000000000000009A53000001000000 e:=sXINV/0x140e330*2
2016/08/12 21:06:21.084 kid1| 33,3| Pipeline.cc(69) popMe: Pipeline
0x14096e0 drop 0x140be10*3
2016/08/12 21:06:21.084 kid1| 33,2| client_side.cc(893) kick:
local=173.236.187.17:80 remote=10.4.4.61:12442 flags=33 Connection was
closed
2016/08/12 21:06:21.084 kid1| 33,3| client_side_request.cc(270)
~ClientHttpRequest: httpRequestFree: error:invalid-request
2016/08/12 21:06:21.084 kid1| 28,3| Checklist.cc(70) preCheck:
0x7ffc80a91290 checking fast ACLs
2016/08/12 21:06:21.084 kid1| 28,3| Acl.cc(290) matches: checked:
(access_log daemon:/var/log/squid/access.log line) = 1
2016/08/12 21:06:21.084 kid1| 28,3| Acl.cc(290) matches: checked: access_log
daemon:/var/log/squid/access.log = 1
2016/08/12 21:06:21.084 kid1| 28,3| Checklist.cc(63) markFinished:
0x7ffc80a91290 answer ALLOWED for match
2016/08/12 21:06:21.084 kid1| 50,3| ModDaemon.cc(174)
logfile_mod_daemon_append: logfile_mod_daemon_append:
daemon:/var/log/squid/access.log: appending 1 bytes
2016/08/12 21:06:21.084 kid1| 50,3| ModDaemon.cc(178)
logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 108
of 32768 bytes before append
2016/08/12 21:06:21.084 kid1| 50,3| ModDaemon.cc(174)
logfile_mod_daemon_append: logfile_mod_daemon_append:
daemon:/var/log/squid/access.log: appending 96 bytes
2016/08/12 21:06:21.084 kid1| 50,3| ModDaemon.cc(178)
logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 109
of 32768 bytes before append
2016/08/12 21:06:21.084 kid1| 50,3| ModDaemon.cc(174)
logfile_mod_daemon_append: logfile_mod_daemon_append:
daemon:/var/log/squid/access.log: appending 220 bytes
2016/08/12 21:06:21.084 kid1| 50,3| ModDaemon.cc(178)
logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 205
of 32768 bytes before append
2016/08/12 21:06:21.084 kid1| 20,3| store.cc(494) unlock:
ClientHttpRequest::loggingEntry unlocking key
34000000000000009A53000001000000 e:=sXINV/0x140e330*1
2016/08/12 21:06:21.084 kid1| 90,3| store_client.cc(755)
storePendingNClients: storePendingNClients: returning 0
2016/08/12 21:06:21.084 kid1| 20,3| store.cc(1252) release: releasing
e:=sXINV/0x140e330*0 34000000000000009A53000001000000
2016/08/12 21:06:21.084 kid1| 20,3| store.cc(378) destroyMemObject:
destroyMemObject 0x1824f10
2016/08/12 21:06:21.084 kid1| 20,3| MemObject.cc(119) ~MemObject: del
MemObject 0x1824f10
2016/08/12 21:06:21.084 kid1| 20,3| store.cc(396) destroyStoreEntry:
destroyStoreEntry: destroying 0x140e338
2016/08/12 21:06:21.084 kid1| 20,3| store.cc(378) destroyMemObject:
destroyMemObject 0
2016/08/12 21:06:21.085 kid1| 33,2| client_side.cc(586) swanSong:
local=173.236.187.17:80 remote=10.4.4.61:12442 flags=33
2016/08/12 21:06:21.085 kid1| 33,3| client_side_request.cc(270)
~ClientHttpRequest: httpRequestFree: error:transaction-end-before-headers
2016/08/12 21:06:21.085 kid1| 28,3| Checklist.cc(70) preCheck:
0x7ffc80a911a0 checking fast ACLs
2016/08/12 21:06:21.085 kid1| 28,3| Acl.cc(290) matches: checked:
(access_log daemon:/var/log/squid/access.log line) = 1
2016/08/12 21:06:21.085 kid1| 28,3| Acl.cc(290) matches: checked: access_log
daemon:/var/log/squid/access.log = 1
2016/08/12 21:06:21.085 kid1| 28,3| Checklist.cc(63) markFinished:
0x7ffc80a911a0 answer ALLOWED for match
2016/08/12 21:06:21.085 kid1| 50,3| ModDaemon.cc(174)
logfile_mod_daemon_append: logfile_mod_daemon_append:
daemon:/var/log/squid/access.log: appending 1 bytes
2016/08/12 21:06:21.085 kid1| 50,3| ModDaemon.cc(178)
logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 425
of 32768 bytes before append
2016/08/12 21:06:21.085 kid1| 50,3| ModDaemon.cc(174)
logfile_mod_daemon_append: logfile_mod_daemon_append:
daemon:/var/log/squid/access.log: appending 100 bytes
2016/08/12 21:06:21.085 kid1| 50,3| ModDaemon.cc(178)
logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 426
of 32768 bytes before append
2016/08/12 21:06:21.085 kid1| 50,3| ModDaemon.cc(174)
logfile_mod_daemon_append: logfile_mod_daemon_append:
daemon:/var/log/squid/access.log: appending 7 bytes
2016/08/12 21:06:21.085 kid1| 50,3| ModDaemon.cc(178)
logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 526
of 32768 bytes before append
2016/08/12 21:06:21.085 kid1| 33,3| client_side.cc(4004) unpinConnection:
2016/08/12 21:06:21.085 kid1| 33,3| client_side.cc(616) ~ConnStateData:
local=173.236.187.17:80 remote=10.4.4.61:12442 flags=33
2016/08/12 21:06:21.085 kid1| 51,3| fd.cc(94) fd_close: fd_close FD 13
Reading next request
2016/08/12 21:06:21.168 kid1| 5,2| TcpAcceptor.cc(226) doAccept: New
connection on FD 20
2016/08/12 21:06:21.168 kid1| 5,2| TcpAcceptor.cc(315) acceptNext:
connection on local=0.0.0.0:3128 remote=[::] FD 20 flags=41
2016/08/12 21:06:21.168 kid1| 51,3| fd.cc(199) fd_open: fd_open() FD 13 HTTP
Request
2016/08/12 21:06:21.168 kid1| 28,3| Eui48.cc(516) lookup: id=0x1409634
10.4.4.61 NOT found
2016/08/12 21:06:21.168 kid1| 5,3| comm.cc(559) commSetConnTimeout:
local=173.236.187.17:80 remote=10.4.4.61:12443 FD 13 flags=33 timeout 300
2016/08/12 21:06:21.169 kid1| 5,3| IoCallback.cc(116) finish: called for
local=173.236.187.17:80 remote=10.4.4.61:12443 FD 13 flags=33 (0, 0)
2016/08/12 21:06:21.169 kid1| 5,3| Read.cc(92) ReadNow:
local=173.236.187.17:80 remote=10.4.4.61:12443 FD 13 flags=33, size 4096,
retval 216, errno 0
2016/08/12 21:06:21.169 kid1| 5,3| comm.cc(559) commSetConnTimeout:
local=173.236.187.17:80 remote=10.4.4.61:12443 FD 13 flags=33 timeout 120
2016/08/12 21:06:21.169 kid1| 87,3| clientStream.cc(140)
clientStreamInsertHead: clientStreamInsertHead: Inserted node 0x140afc8 with
data 0x140ce78 after head
2016/08/12 21:06:21.169 kid1| 5,3| comm.cc(559) commSetConnTimeout:
local=173.236.187.17:80 remote=10.4.4.61:12443 FD 13 flags=33 timeout 86400
2016/08/12 21:06:21.169 kid1| 33,3| Pipeline.cc(24) add: Pipeline 0x14096e0
add request 1 0x140be10*3
2016/08/12 21:06:21.169 kid1| 33,2| Http1Server.cc(101) buildHttpRequest:
Invalid Request
2016/08/12 21:06:21.169 kid1| 20,3| store.cc(777) storeCreatePureEntry:
storeCreateEntry: 'error:invalid-request'
2016/08/12 21:06:21.169 kid1| 20,3| MemObject.cc(110) MemObject: new
MemObject 0x1824f10
2016/08/12 21:06:21.169 kid1| 20,3| store.cc(472) setReleaseFlag:
StoreEntry::setReleaseFlag: '[null_store_key]'
2016/08/12 21:06:21.169 kid1| 20,3| store.cc(421) hashInsert:
StoreEntry::hashInsert: Inserting Entry e:=XI/0x140e330*0 key
'35000000000000009A53000001000000'
2016/08/12 21:06:21.169 kid1| 20,3| store.cc(457) lock: storeCreateEntry
locked key 35000000000000009A53000001000000 e:=XIV/0x140e330*1
2016/08/12 21:06:21.169 kid1| 4,2| errorpage.cc(1260) BuildContent: No
existing error page language negotiated for ERR_PROTOCOL_UNKNOWN. Using
default error file.
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%l --> '/*
* Copyright (C) 1996-2015 The Squid Software Foundation and contributors
*
* Squid software is distributed under GPLv2+ license and includes
* contributions from numerous individuals and organizations.
* Please see the COPYING and CONTRIBUTORS files for details.
*/
/*
Stylesheet for Squid Error pages
Adapted from design by Free CSS Templates
http://www.freecsstemplates.org
Released for free under a Creative Commons Attribution 2.5 License
*/
/* Page basics */
* {
font-family: verdana, sans-serif;
}
html body {
margin: 0;
padding: 0;
background: #efefef;
font-size: 12px;
color: #1e1e1e;
}
/* Page displayed title area */
#titles {
margin-left: 15px;
padding: 10px;
padding-left: 100px;
background: url('http://www.squid-cache.org/Artwork/SN.png') no-repeat
left;
}
/* initial title */
#titles h1 {
color: #000000;
}
#titles h2 {
color: #000000;
}
/* special event: FTP success page titles */
#titles ftpsuccess {
background-color:#00ff00;
width:100%;
}
/* Page displayed body content area */
#content {
padding: 10px;
background: #ffffff;
}
/* General text */
p {
}
/* error brief description */
#error p {
}
/* some data which may have caused the problem */
#data {
}
/* the error message received from the system or other software */
#sysmsg {
}
pre {
font-family:sans-serif;
}
/* special event: FTP / Gopher directory listing */
#dirmsg {
font-family: courier;
color: black;
font-size: 10pt;
}
#dirlisting {
margin-left: 2%;
margin-right: 2%;
}
#dirlisting tr.entry td.icon,td.filename,td.size,td.date {
border-bottom: groove;
}
#dirlisting td.size {
width: 50px;
text-align: right;
padding-right: 5px;
}
/* horizontal lines */
hr {
margin: 0;
}
/* page displayed footer area */
#footer {
font-size: 9px;
padding-left: 10px;
}
'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%; --> '%;'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%c --> 'ERR_PROTOCOL_UNKNOWN'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%U --> 'error:invalid-request'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%U --> 'error:invalid-request'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%w --> 'webmaster'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%W -->
'?subject=CacheErrorInfo%20-%20ERR_PROTOCOL_UNKNOWN&body=CacheHost%3A%20proxy.netgatesss.com%0D%0AErrPage%3A%20ERR_PROTOCOL_UNKNOWN%0D%0AErr%3A%20%5Bnone%5D%0D%0ATimeStamp%3A%20Fri,%2012%20Aug%202016%2018%3A06%3A21%20GMT%0D%0A%0D%0AClientIP%3A%2010.4.4.61%0D%0A%0D%0AHTTP%20Request%3A%0D%0A%0D%0A%0D%0A'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%w --> 'webmaster'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%T --> 'Fri, 12 Aug 2016 18:06:21 GMT'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%h --> 'proxy.netgatesss.com'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%s --> 'squid'
2016/08/12 21:06:21.169 kid1| 4,3| errorpage.cc(1099) Convert: errorConvert:
%%c --> 'ERR_PROTOCOL_UNKNOWN'
2016/08/12 21:06:21.169 kid1| 20,3| store.cc(457) lock:
StoreEntry::storeErrorResponse locked key 35000000000000009A53000001000000
e:=XIV/0x140e330*2
2016/08/12 21:06:21.169 kid1| 20,3| store.cc(1848) replaceHttpReply:
StoreEntry::replaceHttpReply: error:invalid-request
2016/08/12 21:06:21.170 kid1| 20,2| store.cc(990) checkCachable:
StoreEntry::checkCachable: NO: not cachable
2016/08/12 21:06:21.170 kid1| 20,3| store_swapout.cc(382) mayStartSwapOut:
not cachable
2016/08/12 21:06:21.170 kid1| 20,2| store.cc(990) checkCachable:
StoreEntry::checkCachable: NO: not cachable
2016/08/12 21:06:21.170 kid1| 90,3| store_client.cc(729) invokeHandlers:
InvokeHandlers: 35000000000000009A53000001000000
2016/08/12 21:06:21.170 kid1| 90,3| store_client.cc(735) invokeHandlers:
StoreEntry::InvokeHandlers: checking client #0
2016/08/12 21:06:21.170 kid1| 20,3| store.cc(1089) complete: storeComplete:
'35000000000000009A53000001000000'
2016/08/12 21:06:21.170 kid1| 20,3| store.cc(1342) validLength:
storeEntryValidLength: Checking '35000000000000009A53000001000000'
2016/08/12 21:06:21.170 kid1| 20,3| store_swapout.cc(357) mayStartSwapOut:
already rejected
2016/08/12 21:06:21.170 kid1| 20,2| store.cc(990) checkCachable:
StoreEntry::checkCachable: NO: not cachable
2016/08/12 21:06:21.170 kid1| 90,3| store_client.cc(729) invokeHandlers:
InvokeHandlers: 35000000000000009A53000001000000
2016/08/12 21:06:21.170 kid1| 90,3| store_client.cc(735) invokeHandlers:
StoreEntry::InvokeHandlers: checking client #0
2016/08/12 21:06:21.170 kid1| 20,3| store.cc(494) unlock:
StoreEntry::storeErrorResponse unlocking key
35000000000000009A53000001000000 e:=sXINV/0x140e330*2
2016/08/12 21:06:21.170 kid1| 87,3| clientStream.cc(180) clientStreamRead:
clientStreamRead: Calling 1 with cbdata 0x140e298 from node 0x140afc8
2016/08/12 21:06:21.170 kid1| 90,3| store_client.cc(198) copy:
store_client::copy: 35000000000000009A53000001000000, from 0, for length
4096, cb 1, cbdata 0x140d1e8
2016/08/12 21:06:21.170 kid1| 20,3| store.cc(457) lock: store_client::copy
locked key 35000000000000009A53000001000000 e:=sXINV/0x140e330*2
2016/08/12 21:06:21.170 kid1| 90,3| store_client.cc(295) storeClientCopy2:
storeClientCopy2: 35000000000000009A53000001000000
2016/08/12 21:06:21.170 kid1| 90,3| store_client.cc(429) scheduleMemRead:
store_client::doCopy: Copying normal from memory
2016/08/12 21:06:21.170 kid1| 33,2| QosConfig.cc(163) doTosLocalMiss: QOS:
Preserving TOS on miss, TOS=0
2016/08/12 21:06:21.170 kid1| 50,3| ../../src/ip/Qos.cci(22) setSockTos: for
FD 13 to 0
2016/08/12 21:06:21.170 kid1| 88,2| client_side_reply.cc(2054)
processReplyAccessResult: The reply for NONE error:invalid-request is
ALLOWED, because it matched (access_log daemon:/var/log/squid/access.log
line)
2016/08/12 21:06:21.170 kid1| 20,3| store.cc(457) lock:
ClientHttpRequest::loggingEntry locked key 35000000000000009A53000001000000
e:=sXINV/0x140e330*3
2016/08/12 21:06:21.170 kid1| 88,3| client_side_reply.cc(2092)
processReplyAccessResult: clientReplyContext::sendMoreData: Appending 3367
bytes after 199 bytes of headers
2016/08/12 21:06:21.170 kid1| 87,3| clientStream.cc(158)
clientStreamCallback: clientStreamCallback: Calling 1 with cbdata 0x140ce78
from node 0x1409a68
2016/08/12 21:06:21.170 kid1| 33,3| Pipeline.cc(35) front: Pipeline
0x14096e0 front 0x140be10*3
2016/08/12 21:06:21.170 kid1| 33,3| Pipeline.cc(35) front: Pipeline
0x14096e0 front 0x140be10*3
2016/08/12 21:06:21.170 kid1| 11,2| Stream.cc(266) sendStartOfMessage: HTTP
Client local=173.236.187.17:80 remote=10.4.4.61:12443 FD 13 flags=33
2016/08/12 21:06:21.170 kid1| 11,2| Stream.cc(267) sendStartOfMessage: HTTP
Client REPLY:
---------
HTTP/1.1 400 Bad Request
Server: squid
Mime-Version: 1.0
Date: Fri, 12 Aug 2016 18:06:21 GMT
Content-Type: text/html;charset=utf-8
Content-Length: 3367
X-Squid-Error: ERR_PROTOCOL_UNKNOWN 0
X-Cache: MISS from proxy.netgatesss.com
Connection: close
----------
--
View this message in context: http://squid-web-proxy-cache.1019090.n4.nabble.com/X-Squid-Error-tp4678848p4678889.html
Sent from the Squid - Users mailing list archive at Nabble.com.
More information about the squid-users
mailing list