[squid-users] squid 3.2.2 suddenly dies in first request from cache_peer

From: Oguz Yilmaz <oguzyilmazlist_at_gmail.com>
Date: Sun, 7 Oct 2012 13:20:23 +0300

Hello,

I am using dansguardian(8080) - squid(3129) like setup.

With setting "http_port 3129", I am able to connect and get the pages
thru direct proxy connection:

http_proxy="http://127.0.0.1:3129" lynx www.ipnedir.com

However when I want to reach over dansguardian it I get "Alert!:
Unexpected network read error; connection aborted." In squid part,
squid dies and restart itself.
http_proxy="http://127.0.0.1:8080" lynx www.ipnedir.com

FATAL: Bungled (null) line 193: snmp_access deny all
Squid Cache (Version 3.2.2): Terminated abnormally.
CPU Usage: 0.045 seconds = 0.034 user + 0.011 sys
Maximum Resident Size: 53072 KB
Page faults with physical i/o: 0
Memory usage for squid via mallinfo():
        total space in arena: 4484 KB
        Ordinary blocks: 4380 KB 5 blks
        Small blocks: 0 KB 0 blks
        Holding blocks: 14236 KB 8 blks
        Free Small blocks: 0 KB
        Free Ordinary blocks: 103 KB
        Total in use: 18616 KB 415%
        Total free: 103 KB 2%

There is even no line number 193 in squid.conf. There is no line with
snmp_access.

I am almost sure there is no problem with dansguardian. Because I can
change its peer to another proxy server, and it works.

I have compiled squid with gcc 4.4 and libstdc++44-devel.

I need some help about on the issue.

Regards,

squid.conf:

mime_table /etc/squid/mime.conf
cache_effective_user squid
cache_effective_group squid
access_log /var/log/squid/access2.log squid
cache_log /var/log/squid/cache2.log
cache_store_log none
pid_filename /var/run/squid2.pid
debug_options ALL,4

acl localnet src 10.0.0.0/8 # RFC1918 possible internal network
acl localnet src 172.16.0.0/12 # RFC1918 possible internal network
acl localnet src 192.168.0.0/16 # RFC1918 possible internal network
acl localnet src fc00::/7 # RFC 4193 local private network range
acl localnet src fe80::/10 # RFC 4291 link-local (directly
plugged) machines

acl SSL_ports port 443
acl Safe_ports port 80 # http
acl Safe_ports port 21 # ftp
acl Safe_ports port 443 # https
acl Safe_ports port 70 # gopher
acl Safe_ports port 210 # wais
acl Safe_ports port 1025-65535 # unregistered ports
acl Safe_ports port 280 # http-mgmt
acl Safe_ports port 488 # gss-http
acl Safe_ports port 591 # filemaker
acl Safe_ports port 777 # multiling http
acl CONNECT method CONNECT

http_access allow localhost manager
http_access deny manager
http_access deny !Safe_ports
http_access allow localnet
http_access allow localhost
http_access allow all

http_port 3129

refresh_pattern ^ftp: 1440 20% 10080
refresh_pattern ^gopher: 1440 0% 1440
refresh_pattern -i (/cgi-bin/|\?) 0 0% 0
refresh_pattern . 0 20% 4320

debug ALL,4:

2012/10/07 12:53:15.909 kid1| TcpAcceptor.cc(188) doAccept: New
connection on FD 9
2012/10/07 12:53:15.909 kid1| TcpAcceptor.cc(263) acceptNext:
connection on local=0.0.0.0:3129 remote=[::] FD 9 flags=9
2012/10/07 12:53:15.909 kid1| fd_open() FD 10 HTTP Request
2012/10/07 12:53:15.909 kid1| client_side.cc(3220) httpAccept:
local=127.0.0.1:3129 remote=127.0.0.1:38323 FD 10 flags=1: accepted
2012/10/07 12:53:15.909 kid1| ACLList::matches: checking all
2012/10/07 12:53:15.909 kid1| ACL::checklistMatches: checking 'all'
2012/10/07 12:53:15.909 kid1| aclIpMatchIp: '127.0.0.1:38323' found
2012/10/07 12:53:15.909 kid1| ACL::ChecklistMatches: result for 'all' is 1
2012/10/07 12:53:15.909 kid1| ACLList::matches: result is true
2012/10/07 12:53:15.909 kid1| aclmatchAclList: 0xbf9988e4 returning
true (AND list satisfied)
2012/10/07 12:53:15.909 kid1| ACLChecklist::markFinished: 0xbf9988e4
checklist processing finished
2012/10/07 12:53:15.909 kid1| FilledChecklist.cc(100)
~ACLFilledChecklist: ACLFilledChecklist destroyed 0xbf9988e4
2012/10/07 12:53:15.909 kid1| ACLChecklist::~ACLChecklist: destroyed 0xbf9988e4
2012/10/07 12:53:15.909 kid1| Looking up ARP address for 127.0.0.1 on eth0
2012/10/07 12:53:15.909 kid1| Looking up ARP address for 127.0.0.1 on eth13
2012/10/07 12:53:15.909 kid1| Looking up ARP address for 127.0.0.1 on eth14
2012/10/07 12:53:15.909 kid1| Looking up ARP address for 127.0.0.1 on eth1
2012/10/07 12:53:15.909 kid1| Looking up ARP address for 127.0.0.1 on eth2
2012/10/07 12:53:15.909 kid1| Looking up ARP address for 127.0.0.1 on eth2.120
2012/10/07 12:53:15.909 kid1| Eui48.cc(525) lookup: 127.0.0.1 NOT found
2012/10/07 12:53:15.909 kid1| comm.cc(748) commSetConnTimeout:
local=127.0.0.1:3129 remote=127.0.0.1:38323 FD 10 flags=1 timeout 300
2012/10/07 12:53:15.909 kid1| client_side.cc(256) readSomeData:
local=127.0.0.1:3129 remote=127.0.0.1:38323 FD 10 flags=1: reading
request...
2012/10/07 12:53:15.909 kid1| FilledChecklist.cc(100)
~ACLFilledChecklist: ACLFilledChecklist destroyed 0xbf998a6c
2012/10/07 12:53:15.909 kid1| ACLChecklist::~ACLChecklist: destroyed 0xbf998a6c
2012/10/07 12:53:15.909 kid1| comm_read_try: FD 10, size 4095, retval
383, errno 0
2012/10/07 12:53:15.909 kid1| IoCallback.cc(107) finish: called for
local=127.0.0.1:3129 remote=127.0.0.1:38323 FD 10 flags=1 (0, 0)
2012/10/07 12:53:15.909 kid1| parseHttpRequest: req_hdr = {Host:
www.ipnedir.com:P
Accept: text/html, text/plain, audio/mod, image/*, application/msword,
application/pdf, application/postscript, application/x-java-jnlp-file,
text/sgml, */*;q=0.01
Accept-Encoding: identity,gzip
Accept-Language: en
User-Agent: Lynx/2.8.5rel.1 libwww-FM/2.14 SSL-MM/1.4.1 OpenSSL/0.9.8b
X-Forwarded-For: 127.0.0.1

}
2012/10/07 12:53:15.909 kid1| parseHttpRequest: end = {
}
2012/10/07 12:53:15.909 kid1| parseHttpRequest: prefix_sz = 383,
req_line_sz = 38
2012/10/07 12:53:15.909 kid1| clientStreamInsertHead: Inserted node
0x987c570 with data 0x987d6d4 after head
2012/10/07 12:53:15.909 kid1| HTTP Client local=127.0.0.1:3129
remote=127.0.0.1:38323 FD 10 flags=1
2012/10/07 12:53:15.909 kid1| HTTP Client REQUEST:
---------
GET http://www.ipnedir.com/ HTTP/1.0
Host: www.ipnedir.com:P
Accept: text/html, text/plain, audio/mod, image/*, application/msword,
application/pdf, application/postscript, application/x-java-jnlp-file,
text/sgml, */*;q=0.01
Accept-Encoding: identity,gzip
Accept-Language: en
User-Agent: Lynx/2.8.5rel.1 libwww-FM/2.14 SSL-MM/1.4.1 OpenSSL/0.9.8b
X-Forwarded-For: 127.0.0.1

----------
2012/10/07 12:53:15.909 kid1| The AsyncCall clientLifetimeTimeout
constructed, this=0x987d6e0 [call43]
2012/10/07 12:53:15.909 kid1| comm.cc(748) commSetConnTimeout:
local=127.0.0.1:3129 remote=127.0.0.1:38323 FD 10 flags=1 timeout
86400
2012/10/07 12:53:15.909 kid1| urlParse: Split URL
'http://www.ipnedir.com/' into proto='http', host='www.ipnedir.com',
port='80', path='/'
2012/10/07 12:53:15.909 kid1| Address.cc(409) LookupHostIP: Given
Non-IP 'www.ipnedir.com': Name or service not known
2012/10/07 12:53:15.910 kid1| clientSetKeepaliveFlag: http_ver = 1.0
2012/10/07 12:53:15.910 kid1| clientSetKeepaliveFlag: method = GET
2012/10/07 12:53:15.910 kid1| client_side_request.cc(150)
ClientRequestContext: 0x987f3a8 ClientRequestContext constructed
2012/10/07 12:53:15.910 kid1| client_side_request.cc(1504) doCallouts:
Doing calloutContext->hostHeaderVerify()
2012/10/07 12:53:15.910 kid1| Closing HTTP port 0.0.0.0:3129
2012/10/07 12:53:15.910 kid1| comm_close: start closing FD 9
2012/10/07 12:53:15.910 kid1| comm.cc(735) commUnsetFdTimeout: Remove
timeout for FD 9
2012/10/07 12:53:15.910 kid1| The AsyncCall comm_close_complete
constructed, this=0x987e8c0 [call44]
2012/10/07 12:53:15.910 kid1| comm.cc(1154) will call
comm_close_complete(FD 9) [call44]
2012/10/07 12:53:15.910 kid1| leave_suid: PID 12241 called
2012/10/07 12:53:15.910 kid1| storeDirWriteCleanLogs: Starting...
2012/10/07 12:53:15.910 kid1| Finished. Wrote 0 entries.
2012/10/07 12:53:15.910 kid1| Took 0.00 seconds ( 0.00 entries/sec).
FATAL: Bungled (null) line 193: snmp_access deny all
Squid Cache (Version 3.2.2): Terminated abnormally.
CPU Usage: 0.045 seconds = 0.034 user + 0.011 sys
Maximum Resident Size: 53072 KB
Page faults with physical i/o: 0
Memory usage for squid via mallinfo():
        total space in arena: 4484 KB
        Ordinary blocks: 4380 KB 5 blks
        Small blocks: 0 KB 0 blks
        Holding blocks: 14236 KB 8 blks
        Free Small blocks: 0 KB
        Free Ordinary blocks: 103 KB
        Total in use: 18616 KB 415%
        Total free: 103 KB 2%

2012/10/07 13:13:47 kid1| Starting Squid Cache version 3.2.2 for
i686-redhat-linux-gnu...
2012/10/07 13:13:47 kid1| Process ID 16048
2012/10/07 13:13:47 kid1| Process Roles: worker
2012/10/07 13:13:47 kid1| With 32768 file descriptors available
2012/10/07 13:13:47 kid1| Initializing IP Cache...
2012/10/07 13:13:47 kid1| DNS Socket created at 0.0.0.0, FD 7
2012/10/07 13:13:47 kid1| Adding nameserver 192.168.0.1 from squid.conf
2012/10/07 13:13:47 kid1| Logfile: opening log /var/log/squid/access2.log
2012/10/07 13:13:47 kid1| WARNING: log parameters now start with a
module name. Use 'stdio:/var/log/squid/access2.log'
2012/10/07 13:13:47 kid1| Local cache digest enabled; rebuild/rewrite
every 3600/3600 sec
2012/10/07 13:13:47 kid1| Store logging disabled
2012/10/07 13:13:47 kid1| Swap maxSize 0 + 262144 KB, estimated 20164 objects
2012/10/07 13:13:47 kid1| Target number of buckets: 1008
2012/10/07 13:13:47 kid1| Using 8192 Store buckets
2012/10/07 13:13:47 kid1| Max Mem size: 262144 KB
2012/10/07 13:13:47 kid1| Max Swap size: 0 KB
2012/10/07 13:13:47 kid1| Using Least Load store dir selection
2012/10/07 13:13:47 kid1| Current Directory is /
2012/10/07 13:13:47 kid1| Loaded Icons.
2012/10/07 13:13:47 kid1| HTCP Disabled.
2012/10/07 13:13:47 kid1| Squid plugin modules loaded: 0
2012/10/07 13:13:47 kid1| Accepting HTTP Socket connections at
local=0.0.0.0:3129 remote=[::] FD 9 flags=9
2012/10/07 13:13:48 kid1| storeLateRelease: released 0 objects

--
Oguz YILMAZ
Received on Sun Oct 07 2012 - 10:20:58 MDT

This archive was generated by hypermail 2.2.0 : Mon Oct 08 2012 - 12:00:02 MDT