Squid is acting Strange



  • Hello, Squid has been working fine for the past 10 days. Did a system restart and now the hit rate is down from 60% to 10%-20%. Getting weird errors in the log and im not sure where to start. ive posted the cache.log below. I will provide any information that anyone needs. Thank you for reading.

    2014/03/25 00:00:01| storeDirWriteCleanLogs: Starting…
    2014/03/25 00:00:02|    65536 entries written so far.
    2014/03/25 00:00:03|    131072 entries written so far.
    2014/03/25 00:00:04|    196608 entries written so far.
    2014/03/25 00:00:07|    262144 entries written so far.
    2014/03/25 00:00:10|    327680 entries written so far.
    2014/03/25 00:00:10|    393216 entries written so far.
    2014/03/25 00:00:11|    458752 entries written so far.
    2014/03/25 00:00:12|    524288 entries written so far.
    2014/03/25 00:00:13|    589824 entries written so far.
    2014/03/25 00:00:13|    655360 entries written so far.
    2014/03/25 00:00:14|  Finished.  Wrote 663445 entries.
    2014/03/25 00:00:14|  Took 12.1 seconds (55044.3 entries/sec).
    2014/03/25 00:00:14| logfileRotate: /var/squid/logs/access.log
    2014/03/25 00:00:14| logfileRotate (stdio): /var/squid/logs/access.log
    2014/03/25 00:03:18| clientTryParseRequest: FD 53 (192.168.0.103:61552) Invalid Request
    2014/03/25 00:38:42| parseHttpRequest: Unsupported method '³ÃÜ,='
    2014/03/25 00:38:42| clientTryParseRequest: FD 25 (192.168.0.116:54461) Invalid Request
    2014/03/25 08:50:40| clientTryParseRequest: FD 31 (192.168.0.54:53030) Invalid Request
    2014/03/25 08:51:01| clientTryParseRequest: FD 34 (192.168.0.54:53035) Invalid Request
    2014/03/25 08:51:21| clientTryParseRequest: FD 24 (192.168.0.54:53038) Invalid Request
    2014/03/25 08:51:42| parseHttpRequest: Unsupported method '  '
    2014/03/25 08:51:42| clientTryParseRequest: FD 24 (192.168.0.54:53041) Invalid Request
    2014/03/25 08:52:03| parseHttpRequest: Unsupported method '  '
    2014/03/25 08:52:03| clientTryParseRequest: FD 24 (192.168.0.54:53043) Invalid Request
    2014/03/25 08:52:24| clientTryParseRequest: FD 16 (192.168.0.54:53046) Invalid Request
    2014/03/25 08:52:45| clientTryParseRequest: FD 16 (192.168.0.54:53049) Invalid Request
    2014/03/25 08:53:06| parseHttpRequest: Unsupported method '  '
    2014/03/25 08:53:06| clientTryParseRequest: FD 16 (192.168.0.54:53052) Invalid Request
    2014/03/25 08:53:27| parseHttpRequest: Unsupported method '  '
    2014/03/25 08:53:27| clientTryParseRequest: FD 16 (192.168.0.54:53059) Invalid Request
    2014/03/25 08:53:47| parseHttpRequest: Unsupported method '  '
    2014/03/25 08:53:47| clientTryParseRequest: FD 16 (192.168.0.54:53064) Invalid Request
    2014/03/25 08:54:09| clientTryParseRequest: FD 16 (192.168.0.54:53066) Invalid Request
    2014/03/25 08:54:20| parseHttpRequest: Unsupported method '  '
    2014/03/25 08:54:20| clientTryParseRequest: FD 16 (192.168.0.54:33823) Invalid Request
    2014/03/25 08:55:11| clientTryParseRequest: FD 16 (192.168.0.54:35364) Invalid Request
    2014/03/25 09:04:49| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:04:49| clientTryParseRequest: FD 16 (192.168.0.54:55407) Invalid Request
    2014/03/25 09:12:15| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:12:15| clientTryParseRequest: FD 49 (192.168.0.204:59348) Invalid Request
    2014/03/25 09:12:36| clientTryParseRequest: FD 26 (192.168.0.204:38090) Invalid Request
    2014/03/25 09:12:57| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:12:57| clientTryParseRequest: FD 42 (192.168.0.204:36381) Invalid Request
    2014/03/25 09:13:19| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:13:19| clientTryParseRequest: FD 25 (192.168.0.204:49620) Invalid Request
    2014/03/25 09:13:43| clientTryParseRequest: FD 26 (192.168.0.204:40052) Invalid Request
    2014/03/25 09:14:04| clientTryParseRequest: FD 29 (192.168.0.204:39479) Invalid Request
    2014/03/25 09:14:25| clientTryParseRequest: FD 27 (192.168.0.204:55245) Invalid Request
    2014/03/25 09:14:46| clientTryParseRequest: FD 27 (192.168.0.204:50071) Invalid Request
    2014/03/25 09:15:06| clientTryParseRequest: FD 34 (192.168.0.204:39828) Invalid Request
    2014/03/25 09:15:28| clientTryParseRequest: FD 34 (192.168.0.204:33502) Invalid Request
    2014/03/25 09:15:39| clientTryParseRequest: FD 27 (192.168.0.204:49284) Invalid Request
    2014/03/25 09:16:10| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:16:10| clientTryParseRequest: FD 40 (192.168.0.204:50838) Invalid Request
    2014/03/25 09:19:07| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:19:07| clientTryParseRequest: FD 34 (192.168.0.204:40065) Invalid Request
    2014/03/25 09:19:40| clientTryParseRequest: FD 38 (192.168.0.141:49370) Invalid Request
    2014/03/25 09:20:00| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:20:00| clientTryParseRequest: FD 29 (192.168.0.141:49377) Invalid Request
    2014/03/25 09:20:21| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:20:21| clientTryParseRequest: FD 29 (192.168.0.141:49379) Invalid Request
    2014/03/25 09:20:42| clientTryParseRequest: FD 36 (192.168.0.141:49381) Invalid Request
    2014/03/25 09:21:03| clientTryParseRequest: FD 29 (192.168.0.141:49383) Invalid Request
    2014/03/25 09:21:24| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:21:24| clientTryParseRequest: FD 31 (192.168.0.141:49385) Invalid Request
    2014/03/25 09:21:45| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:21:45| clientTryParseRequest: FD 36 (192.168.0.141:49387) Invalid Request
    2014/03/25 09:22:05| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:22:05| clientTryParseRequest: FD 26 (192.168.0.141:49389) Invalid Request
    2014/03/25 09:22:26| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:22:26| clientTryParseRequest: FD 26 (192.168.0.141:49391) Invalid Request
    2014/03/25 09:22:48| clientTryParseRequest: FD 26 (192.168.0.141:49393) Invalid Request
    2014/03/25 09:22:59| clientTryParseRequest: FD 26 (192.168.0.141:39598) Invalid Request
    2014/03/25 09:23:10| clientTryParseRequest: FD 24 (192.168.0.141:45220) Invalid Request
    2014/03/25 09:23:40| clientTryParseRequest: FD 25 (192.168.0.141:39362) Invalid Request
    2014/03/25 09:24:07| clientTryParseRequest: FD 40 (192.168.0.204:60279) Invalid Request
    2014/03/25 09:24:21| parseHttpRequest: Unsupported method '  '
    2014/03/25 09:24:21| clientTryParseRequest: FD 33 (192.168.0.141:39364) Invalid Request
    2014/03/25 09:26:27| httpAccept: FD 19: accept failure: (53) Software caused connection abort
    2014/03/25 09:26:27| httpAccept: FD 19: accept failure: (53) Software caused connection abort
    2014/03/25 09:37:28| clientTryParseRequest: FD 16 (192.168.0.141:36491) Invalid Request
    2014/03/25 09:39:14| clientTryParseRequest: FD 41 (192.168.0.204:60694) Invalid Request
    2014/03/25 10:02:31| clientTryParseRequest: FD 30 (192.168.0.211:53365) Invalid Request
    2014/03/25 10:02:51| clientTryParseRequest: FD 117 (192.168.0.211:53369) Invalid Request
    2014/03/25 10:03:13| clientTryParseRequest: FD 105 (192.168.0.211:53371) Invalid Request
    2014/03/25 10:03:35| clientTryParseRequest: FD 89 (192.168.0.211:53373) Invalid Request
    2014/03/25 10:03:56| clientTryParseRequest: FD 31 (192.168.0.211:53377) Invalid Request
    2014/03/25 10:04:16| clientTryParseRequest: FD 52 (192.168.0.211:53379) Invalid Request
    2014/03/25 10:04:39| clientTryParseRequest: FD 88 (192.168.0.211:53381) Invalid Request
    2014/03/25 10:05:00| clientTryParseRequest: FD 70 (192.168.0.211:53383) Invalid Request
    2014/03/25 10:05:21| clientTryParseRequest: FD 83 (192.168.0.211:53385) Invalid Request
    2014/03/25 10:05:42| clientTryParseRequest: FD 64 (192.168.0.211:53387) Invalid Request
    2014/03/25 10:05:53| clientTryParseRequest: FD 26 (192.168.0.211:59532) Invalid Request
    2014/03/25 10:06:24| parseHttpRequest: Unsupported method '  '
    2014/03/25 10:06:24| clientTryParseRequest: FD 147 (192.168.0.211:42024) Invalid Request
    2014/03/25 10:11:40| clientTryParseRequest: FD 27 (192.168.0.211:35852) Invalid Request
    2014/03/25 10:17:23| clientTryParseRequest: FD 25 (192.168.0.141:53720) Invalid Request
    2014/03/25 10:19:10| clientTryParseRequest: FD 28 (192.168.0.204:44264) Invalid Request
    2014/03/25 10:34:14| parseHttpRequest: Unsupported method '  '
    2014/03/25 10:34:14| clientTryParseRequest: FD 37 (192.168.0.211:34172) Invalid Request
    2014/03/25 10:35:32| ctx: enter level  0: 'http://vap4sna1.lijit.com/www/delivery/lg.php?bannerid=43044&campaignid=232&cids=232&bids=43044&zoneid=230279&tid=b84a1343121a42d4a2a3a97700968c87651876cc&fpr=3e5014887d8398fadc550e73937837ee&loc=http%3A%2F%2Fhypster.com%2F&cb=03569987'
    2014/03/25 10:35:32| WARNING: unparseable HTTP header field {:: }
    2014/03/25 10:36:06| ctx: exit level  0
    2014/03/25 10:36:06| ctx: enter level  0: 'http://vap3dfw1.lijit.com/www/delivery/lg.php?bannerid=28909&campaignid=1373&cids=1200,1373&bids=21433,28909&tss=NaN,NaN&retarget_matches=null&zoneid=242111&tid=b21f0850ed6d4858950715f55d1254b3d881e639&fpr=3e5014887d8398fadc550e73937837ee&loc=http%3A%2F%2Fhypster.com%2Faccount%2FSignIn&cb=38409645'
    2014/03/25 10:36:06| WARNING: unparseable HTTP header field {:: }
    2014/03/25 10:39:09| ctx: exit level  0
    2014/03/25 10:39:09| clientTryParseRequest: FD 35 (192.168.0.204:60596) Invalid Request
    2014/03/25 10:51:24| ctx: enter level  0: 'http://vap3iad1.lijit.com/www/delivery/lg.php?bannerid=4282&campaignid=573&cids=573&bids=4282&tss=NaN&rtb_tid=441455e9-bee8-4ef7-915b-cac5f10eafc8&rpid=1&retarget_matches=null&zoneid=156455&tid=e9217123230c461998e77301689a154c3b06915c&fpr=3e5014887d8398fadc550e73937837ee&loc=http%3A%2F%2Fhypster.com%2Faccount%2FSignIn&cb=35548147'
    2014/03/25 10:51:24| WARNING: unparseable HTTP header field {:: }
    2014/03/25 10:51:24| ctx: exit level  0
    2014/03/25 10:51:24| ctx: enter level  0: 'http://vap6iad1.lijit.com/www/delivery/lg.php?bannerid=21435&campaignid=1219&cids=1219&bids=21435&tss=NaN&retarget_matches=null&zoneid=242111&tid=c3c09a3c210545c18e74b5a2fb5be0ceedff2a7e&channel_ids=,&fpr=3e5014887d8398fadc550e73937837ee&loc=http%3A%2F%2Fhypster.com%2Faccount%2FSignIn&cb=99745350'
    2014/03/25 10:51:24| WARNING: unparseable HTTP header field {:: }
    2014/03/25 10:51:50| ctx: exit level  0
    2014/03/25 10:51:50| parseHttpRequest: Unsupported method '  '
    2014/03/25 10:51:50| clientTryParseRequest: FD 176 (192.168.0.45:44519) Invalid Request
    2014/03/25 10:52:12| parseHttpRequest: Unsupported method '  '
    2014/03/25 10:52:12| clientTryParseRequest: FD 46 (192.168.0.45:44531) Invalid Request
    2014/03/25 10:52:33| parseHttpRequest: Unsupported method '  '
    2014/03/25 10:52:33| clientTryParseRequest: FD 82 (192.168.0.45:44536) Invalid Request
    2014/03/25 10:53:11| parseHttpRequest: Unsupported method '  '
    2014/03/25 10:53:11| clientTryParseRequest: FD 37 (192.168.0.45:44538) Invalid Request
    2014/03/25 10:53:33| parseHttpRequest: Unsupported method '  '
    2014/03/25 10:53:33| clientTryParseRequest: FD 66 (192.168.0.45:44542) Invalid Request
    2014/03/25 10:53:53| clientTryParseRequest: FD 60 (192.168.0.45:44544) Invalid Request
    2014/03/25 10:54:06| parseHttpRequest: Unsupported method '  '
    2014/03/25 10:54:06| clientTryParseRequest: FD 52 (192.168.0.79:33580) Invalid Request
    2014/03/25 10:54:15| clientTryParseRequest: FD 55 (192.168.0.45:44546) Invalid Request
    2014/03/25 10:54:26| parseHttpRequest: Unsupported method '  '
    2014/03/25 10:54:26| clientTryParseRequest: FD 41 (192.168.0.79:49900) Invalid Request



  • Here is the system log regarding squid

    Mar 21 16:31:07 php: rc.start_packages: Not calling package sync code for dependency squid of squid because some include files are missing.
    Mar 21 16:31:12 squid[22052]: Squid Parent: child process 22615 exited with status 0
    Mar 21 16:31:12 php: rc.start_packages: The command '/usr/local/etc/rc.d/squid.sh stop' returned exit code '1', the output was ''
    Mar 21 16:31:14 squid[56009]: Squid Parent: child process 56487 started
    Mar 21 16:34:08 php: rc.start_packages: Not calling package sync code for dependency squid of squid because some include files are missing.
    Mar 21 16:34:12 squid[56009]: Squid Parent: child process 56487 exited with status 0
    Mar 21 16:34:13 php: rc.start_packages: The command '/usr/local/etc/rc.d/squid.sh stop' returned exit code '1', the output was ''
    Mar 21 16:34:15 squid[13194]: Squid Parent: child process 13332 started
    Mar 23 16:44:33 php: /status_services.php: The command '/usr/local/etc/rc.d/squid.sh stop' returned exit code '1', the output was ''
    Mar 23 16:44:38 php: /status_services.php: The command '/usr/local/etc/rc.d/squid.sh stop' returned exit code '1', the output was 'squid: ERROR: No running copy'
    Mar 23 16:44:40 squid[1861]: Squid Parent: child process 2367 started
    Mar 24 13:04:35 php: /status_services.php: The command '/usr/local/etc/rc.d/squid.sh stop' returned exit code '1', the output was ''
    Mar 24 13:04:40 php: /status_services.php: The command '/usr/local/etc/rc.d/squid.sh stop' returned exit code '1', the output was 'squid: ERROR: No running copy'
    Mar 24 13:04:42 squid[64903]: Squid Parent: child process 65497 started



  • A snippit from the the access.log. i am down to 1% hit. The lowest i have ever seen on my proxy. Not sure what to do.

    1395769405.500    67 192.168.0.112 TCP_MISS/302 911 GET http://ib.adnxs.com/getuidnb? - DIRECT/68.67.128.16 text/html
    1395769405.508    75 192.168.0.112 TCP_MISS/302 1502 GET http://p.rfihub.com/cm? - DIRECT/205.210.187.217 -
    1395769405.517    84 192.168.0.112 TCP_MISS/302 1004 GET http://sync.mathtag.com/sync/img? - DIRECT/74.121.136.139 image/gif
    1395769405.533    100 192.168.0.112 TCP_MISS/302 1057 GET http://ad.turn.com/r/cs? - DIRECT/69.194.244.11 -
    1395769405.541    105 192.168.0.112 TCP_MISS/302 1443 GET http://match.adsrvr.org/track/cmf/generic? - DIRECT/50.18.58.187 text/html
    1395769405.548    46 192.168.0.112 TCP_MISS/200 851 GET http://image2.pubmatic.com/AdServer/Pug? - DIRECT/66.70.125.98 text/html
    1395769405.550    361 192.168.0.112 TCP_MISS/200 641 GET http://loadus.exelator.com/load/? - DIRECT/107.6.91.101 application/x-javascript
    1395769405.570    60 192.168.0.112 TCP_MISS/200 1003 GET http://image2.pubmatic.com/AdServer/Pug? - DIRECT/66.70.125.98 image/gif
    1395769405.570    122 192.168.0.112 TCP_MISS/302 808 GET http://cmap.uac.ace.advertising.com/cfcm.ashx? - DIRECT/207.200.81.20 -
    1395769405.573    139 192.168.0.112 TCP_MISS/200 408 GET http://bcp.crwdcntrl.net/map/c=240/tp=PUBM/tpid=B0AD5A08-ECEC-4527-8342-1C6E5A6B1A8F - DIRECT/54.213.58.69 image/gif
    1395769405.574    55 192.168.0.112 TCP_MISS/302 1106 GET http://image2.pubmatic.com/AdServer/Pug? - DIRECT/66.70.125.98 text/html
    1395769405.580    543 192.168.0.112 TCP_MISS/200 689 GET http://adserver.adtechus.com/addyn/3.0/9414.1/2807791/0/170/ADTECH;loc=100;target=_blank;misc=[TIMESTAMP];rdclick=[CLICKMACRO] - DIRECT/207.200.74.32 application/x-javascript
    1395769405.592    56 192.168.0.112 TCP_MISS/200 837 GET http://image2.pubmatic.com/AdServer/Pug? - DIRECT/66.70.125.98 text/html
    1395769405.594    48 192.168.0.112 TCP_MISS/200 1018 GET http://image2.pubmatic.com/AdServer/Pug? - DIRECT/66.70.125.98 image/gif
    1395769405.622    48 192.168.0.112 TCP_MISS/200 1244 GET http://image2.pubmatic.com/AdServer/Pug? - DIRECT/66.70.125.98 text/html
    1395769405.652    26 192.168.0.112 TCP_MISS/200 1061 GET http://clients.bluecava.com/data/? - DIRECT/216.23.166.110 text/html
    1395769405.660    257 192.168.0.112 TCP_MISS/200 2805 GET http://showads.pubmatic.com/AdServer/AdServerServlet? - DIRECT/66.70.125.126 text/html
    1395769405.669    46 192.168.0.112 TCP_MISS/200 677 GET http://x.bidswitch.net/sync? - DIRECT/54.193.24.118 image/gif
    1395769405.682    23 192.168.0.112 TCP_MISS/304 1042 GET http://sync.graph.bluecava.com/i? - DIRECT/72.21.91.109 -
    1395769405.709    82 192.168.0.112 TCP_MISS/200 3865 GET http://tags.bluekai.com/site/17578? - DIRECT/50.97.236.98 text/html
    1395769405.717    47 192.168.0.112 TCP_MISS/200 397 GET http://pixel.quantserve.com/pixel;r=1412799289;a=p-dyp2Encuxz5Kk;fpan=0;fpa=P0-1377229476-1395768886297;ns=1;ce=1;cm=;je=1;sr=1536x864x24;enc=n;dst=1;et=1395769367783;tzo=420;ref=http%3A%2F%2Fbofeedrt.baronsoffers.com%2Foffer%2Ffeed%2Fq%2FaT0zNTQ4LHM9MzAweDI1MCxuPWlmcmFtZQ%3D%3D;url=http%3A%2F%2Ffeed-rt.baronsoffers.com%2Foffer%2Ffeed%2Fq%2FaT00NzgxLHM9MzAweDI1MCxuPWlmcmFtZQ%3D%3D%3Fsubid%3Ddefault;ogl= - DIRECT/64.94.107.54 image/gif
    1395769405.727    105 192.168.0.112 TCP_MISS/200 3701 GET http://feed-rt.baronsoffers.com/offer/feed/q/aT01NTE1LHM9MzAweDI1MCxuPWlmcmFtZQ==? - DIRECT/162.243.200.43 text/html
    1395769405.756    53 192.168.0.112 TCP_MISS/200 2280 POST http://sync.graph.bluecava.com/req.bci - DIRECT/72.21.91.109 application/json
    1395769405.761    186 192.168.0.112 TCP_MISS/302 945 GET http://pixel.mathtag.com/event/img? - DIRECT/23.41.245.109 image/gif
    1395769405.790    119 192.168.0.112 TCP_MISS/302 708 GET http://bid.g.doubleclick.net/xbbe/creative/ad? - DIRECT/74.125.224.45 image/gif
    1395769405.833    162 192.168.0.112 TCP_MISS/200 10554 GET http://x.vindicosuite.com/imp/? - DIRECT/216.178.47.53 application/javascript
    1395769405.833    94 192.168.0.112 TCP_MISS/200 18014 GET http://ads.pubmatic.com/AdServer/js/showad.js? - DIRECT/23.41.244.174 text/html
    1395769405.887    148 192.168.0.112 TCP_MISS/200 641 GET http://loadus.exelator.com/load/? - DIRECT/107.6.91.101 application/x-javascript
    1395769405.900    45 192.168.0.112 TCP_MISS/200 5949 GET http://tags.bluekai.com/site/13102? - DIRECT/50.97.236.98 image/gif
    1395769405.926    71 192.168.0.112 TCP_MISS/200 488 GET http://cache.specificmedia.com/creative/blank.gif? - DIRECT/72.21.91.74 image/gif
    1395769405.941    86 192.168.0.112 TCP_MISS/302 370 GET http://adadvisor.net/adscores/g.pixel? - DIRECT/216.120.27.21 -
    1395769405.958    102 192.168.0.112 TCP_MISS/200 596 GET http://d.turn.com/r/du/id/L2NzaWQvMS9tcGlkLzIzNjEzMjAy/mpuid/-umwv63TxAVZnB - DIRECT/69.194.244.13



  • So Lightsquid is saying the file i am downloading is cached. But when i redownload the file i get no speed boost, and the file is coming from the internet. But lightsquid is saying the file is 98% Cached. Headscracther for me! ty


Log in to reply