Re: [squid-users] major squid problems

From: Marc Elsen <marc.elsen@dont-contact.us>
Date: Thu, 30 Jan 2003 17:30:56 +0100

ty kirk wrote:
>
> Hello all.
> We've been having problems with our squid proxy recently and I've been
> wondering how we can debug it. We're running version
> squid-2.4.STABLE6-6.7.3 on red hat 7.2 and it goes down every 3 days or so.
>
> When it goes down, the service/process still is up, but no pages can be
> served. Any ideas of why this is happening and/or how to debug it? Here is
> what I see in the logs when it goes down. Looks like there is a dns issue,
> but dns is up and running fine when this happens.

 There seems to be some degradation in 'DNS functionality' indeed.
 Check your DNS configuration ?
 Are you using internal or external DNS ?

 Check cachmgr -> DNS stats to possibly get extra stat's/problem
 info concerning DNS.

 M.

>
> Any help would be much, much appreciated.
>
> Thanks.
>
> 2003/01/16 10:29:30| Starting Squid Cache version 2.4.STABLE6 for
> i386-redhat-linux-gnu...
> 2003/01/16 10:29:30| Process ID 7924
> 2003/01/16 10:29:30| With 1024 file descriptors available
> 2003/01/16 10:29:30| DNS Socket created on FD 4
> 2003/01/16 10:29:30| Adding nameserver 205.171.3.65 from /etc/resolv.conf
> 2003/01/16 10:29:30| Unlinkd pipe opened on FD 9
> 2003/01/16 10:29:30| Swap maxSize 102400 KB, estimated 7876 objects
> 2003/01/16 10:29:30| Target number of buckets: 393
> 2003/01/16 10:29:30| Using 8192 Store buckets
> 2003/01/16 10:29:30| Max Mem size: 8192 KB
> 2003/01/16 10:29:30| Max Swap size: 102400 KB
> 2003/01/16 10:29:30| Rebuilding storage in /var/spool/squid (CLEAN)
> 2003/01/16 10:29:30| Using Least Load store dir selection
> 2003/01/16 10:29:30| Set Current Directory to /var/spool/squid
> 2003/01/16 10:29:30| Loaded Icons.
> 2003/01/16 10:29:30| Accepting HTTP connections at 0.0.0.0, port 80, FD 11.
> 2003/01/16 10:29:30| Accepting ICP messages at 0.0.0.0, port 3130, FD 12.
> 2003/01/16 10:29:30| WCCP Disabled.
> 2003/01/16 10:29:30| Ready to serve requests.
> 2003/01/16 10:29:30| Store rebuilding is 40.9% complete
> 2003/01/16 10:29:30| Done reading /var/spool/squid swaplog (10026 entries)
> 2003/01/16 10:29:30| Finished rebuilding storage from disk.
> 2003/01/16 10:29:30| 10026 Entries scanned
> 2003/01/16 10:29:30| 0 Invalid entries.
> 2003/01/16 10:29:30| 0 With invalid flags.
> 2003/01/16 10:29:30| 10026 Objects loaded.
> 2003/01/16 10:29:30| 0 Objects expired.
> 2003/01/16 10:29:30| 0 Objects cancelled.
> 2003/01/16 10:29:30| 0 Duplicate URLs purged.
> 2003/01/16 10:29:30| 0 Swapfile clashes avoided.
> 2003/01/16 10:29:30| Took 0.5 seconds (19269.1 objects/sec).
> 2003/01/16 10:29:30| Beginning Validation Procedure
> 2003/01/16 10:29:30| Completed Validation Procedure
> 2003/01/16 10:29:30| Validated 10026 Entries
> 2003/01/16 10:29:30| store_swap_size = 89173k
> 2003/01/16 10:29:31| storeLateRelease: released 0 objects
> 2003/01/17 11:12:43| idnsCheckQueue: ID fd: giving up after 60 tries and
> 303.4 seconds
> 2003/01/22 16:42:09| sslReadServer: FD 37: read failure: (104) Connection
> reset by peer
> 2003/01/23 14:24:36| idnsCheckQueue: ID 1527: giving up after 60 tries and
> 303.4 seconds
> 2003/01/23 14:24:36| idnsCheckQueue: ID 1528: giving up after 60 tries and
> 303.4 seconds
> 2003/01/23 14:27:56| idnsCheckQueue: ID 1529: giving up after 60 tries and
> 303.4 seconds
> 2003/01/23 14:27:56| idnsCheckQueue: ID 152a: giving up after 60 tries and
> 303.4 seconds
> 2003/01/23 14:27:57| idnsCheckQueue: ID 152b: giving up after 60 tries and
> 304.2 seconds
> 2003/01/23 14:27:57| idnsCheckQueue: ID 152c: giving up after 60 tries and
> 304.2 seconds
> 2003/01/23 14:27:57| idnsCheckQueue: ID 152d: giving up after 60 tries and
> 304.1 seconds
> 2003/01/23 14:27:57| idnsCheckQueue: ID 152e: giving up after 60 tries and
> 304.1 seconds
> 2003/01/23 14:28:23| idnsCheckQueue: ID 152f: giving up after 60 tries and
> 304.1 seconds
> 2003/01/23 14:28:23| idnsCheckQueue: ID 1530: giving up after 60 tries and
> 304.1 seconds
> 2003/01/23 14:28:24| idnsCheckQueue: ID 1531: giving up after 60 tries and
> 304.2 seconds
> 2003/01/23 14:28:24| idnsCheckQueue: ID 1532: giving up after 60 tries and
> 304.2 seconds
> 2003/01/23 14:28:24| idnsCheckQueue: ID 1533: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:28:24| idnsCheckQueue: ID 1534: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:28:24| idnsCheckQueue: ID 1535: giving up after 60 tries and
> 303.8 seconds
> 2003/01/23 14:28:24| idnsCheckQueue: ID 1536: giving up after 60 tries and
> 303.8 seconds
> 2003/01/23 14:28:54| idnsCheckQueue: ID 1537: giving up after 60 tries and
> 303.4 seconds
> 2003/01/23 14:28:54| idnsCheckQueue: ID 1538: giving up after 60 tries and
> 303.4 seconds
> 2003/01/23 14:29:40| idnsCheckQueue: ID 1539: giving up after 60 tries and
> 304.4 seconds
> 2003/01/23 14:29:40| idnsCheckQueue: ID 153a: giving up after 60 tries and
> 304.4 seconds
> 2003/01/23 14:30:28| idnsCheckQueue: ID 153b: giving up after 60 tries and
> 303.7 seconds
> 2003/01/23 14:30:28| idnsCheckQueue: ID 153c: giving up after 60 tries and
> 303.7 seconds
> 2003/01/23 14:30:29| idnsCheckQueue: ID 153d: giving up after 60 tries and
> 304.2 seconds
> 2003/01/23 14:30:29| idnsCheckQueue: ID 153e: giving up after 60 tries and
> 304.2 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 153f: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1540: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1541: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1542: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1543: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1544: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1545: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1546: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1547: giving up after 60 tries and
> 303.7 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1548: giving up after 60 tries and
> 303.7 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 1549: giving up after 60 tries and
> 303.7 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 154a: giving up after 60 tries and
> 303.7 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 154b: giving up after 60 tries and
> 303.6 seconds
> 2003/01/23 14:30:56| idnsCheckQueue: ID 154c: giving up after 60 tries and
> 303.6 seconds
> 2003/01/23 14:30:57| idnsCheckQueue: ID 154d: giving up after 60 tries and
> 304.4 seconds
> 2003/01/23 14:30:57| idnsCheckQueue: ID 154e: giving up after 60 tries and
> 304.4 seconds
> 2003/01/23 14:30:57| idnsCheckQueue: ID 154f: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:30:57| idnsCheckQueue: ID 1550: giving up after 60 tries and
> 303.9 seconds
> 2003/01/23 14:31:02| idnsCheckQueue: ID 1551: giving up after 60 tries and
> 303.8 seconds
> 2003/01/23 14:31:02| idnsCheckQueue: ID 1552: giving up after 60 tries and
> 303.8 seconds
> 2003/01/23 14:34:44| idnsCheckQueue: ID 1553: giving up after 60 tries and
> 303.4 seconds
> 2003/01/23 14:34:44| idnsCheckQueue: ID 1554: giving up after 60 tries and
> 303.4 seconds
> 2003/01/23 14:35:13| idnsCheckQueue: ID 1555: giving up after 60 tries and
> 303.8 seconds
> 2003/01/23 14:35:38| idnsCheckQueue: ID 1556: giving up after 60 tries and
> 304.3 seconds
> 2003/01/23 14:35:38| idnsCheckQueue: ID 1557: giving up after 60 tries and
> 304.3 seconds
> 2003/01/23 14:35:47| Preparing for shutdown after 21638 requests
> 2003/01/23 14:35:47| Waiting 30 seconds for active connections to finish
> 2003/01/23 14:35:47| FD 11 Closing HTTP connection
> 2003/01/23 14:35:52| idnsSendQuery: Can't send query, no DNS socket!
> 2003/01/23 14:36:18| Shutting down...
> 2003/01/23 14:36:18| FD 12 Closing ICP connection
> 2003/01/23 14:36:18| WARNING: Closing client 192.168.104.140 connection due
> to lifetime timeout
> 2003/01/23 14:36:18| http://www.yahoo.com/
> 2003/01/23 14:36:18| WARNING: Closing client 192.168.104.153 connection due
> to lifetime timeout
> 2003/01/23 14:36:18| http://www.yahoo.com/
> 2003/01/23 14:36:18| WARNING: Closing client 192.168.104.140 connection due
> to lifetime timeout
> 2003/01/23 14:36:18| Closing unlinkd pipe on FD 9
> 2003/01/23 14:36:18| storeDirWriteCleanLogs: Starting...
> 2003/01/23 14:36:18| Finished. Wrote 7865 entries.
> 2003/01/23 14:36:18| Took 0.0 seconds (642617.9 entries/sec).
> CPU Usage: 543.234 seconds = 137.256 user + 405.979 sys
> Maximum Resident Size: 0 KB
> Page faults with physical i/o: 369
> Memory usage for squid via mallinfo():
> total space in arena: 17633 KB
> Ordinary blocks: 15138 KB 1611 blks
> Small blocks: 0 KB 0 blks
> Holding blocks: 176 KB 1 blks
> Free Small blocks: 0 KB
> Free Ordinary blocks: 2495 KB
> Total in use: 15314 KB 87%
> Total free: 2495 KB 14%
> 2003/01/23 14:36:18| Squid Cache (Version 2.4.STABLE6): Exiting normally. -
> this is me shutting down and then restarting
>
> _________________________________________________________________
> Help STOP SPAM with the new MSN 8 and get 2 months FREE*
> http://join.msn.com/?page=features/junkmail

-- 
 'Time is a consequence of Matter thus
 General Relativity is a direct consequence of QM
 (M.E. Mar 2002)
Received on Thu Jan 30 2003 - 09:36:37 MST

This archive was generated by hypermail pre-2.1.9 : Tue Dec 09 2003 - 17:12:59 MST