Hi, After I have installed squid3-stable11 from source on fedora 9 with all updates done to fedora some clients complained that they browsing interruptions and slowness going through the logs I noticed that squid every now and then crashes with the following error and restarts by itself 2009/01/19 19:17:45| clientParseRequestMethod: Unsupported method: This is not a bug. see squid.conf extension_methods 2009/01/19 19:17:45| clientParseRequestMethod: Unsupported method in request '__ _______j___A__t_#__vQ_____s_V_s_{WZ_fd}_____3__9G(_____(__DQ_N___W_Ba__T_u__ _`__ __OH_k_#>_k____Wz+' 2009/01/19 19:17:45| clientProcessRequest: Invalid Request 2009/01/19 19:17:59| squidaio_queue_request: WARNING - Queue congestion 2009/01/19 19:18:44| ctx: enter level 0: 'http://msgr.updates.yahoo.com/vitalit y_proxy/V1/getEvents?max=10&since=1231714888&includeStatus=true&alias=thinkk _aga inn&fmt=2.0&intl=us&os=win&ver=9.0.0.2112&buddies=(jean_safy,jessa.jess,road runn er_coyote2000,squeezebox20022001)' 2009/01/19 19:18:44| http.cc(561) HttpStateData::cacheableReply: unexpected http status code 519 2009/01/19 19:20:08| ctx: exit level 0 2009/01/19 19:20:08| storeDirWriteCleanLogs: Starting... 2009/01/19 19:20:08| WARNING: Closing open FD 17 2009/01/19 19:20:08| 65536 entries written so far. 2009/01/19 19:20:08| 131072 entries written so far. 2009/01/19 19:20:08| 196608 entries written so far. 2009/01/19 19:20:08| 262144 entries written so far. 2009/01/19 19:20:08| 327680 entries written so far. 2009/01/19 19:20:08| 393216 entries written so far. 2009/01/19 19:20:08| 458752 entries written so far. 2009/01/19 19:20:08| 524288 entries written so far. 2009/01/19 19:20:08| 589824 entries written so far. 2009/01/19 19:20:08| 655360 entries written so far. 2009/01/19 19:20:08| 720896 entries written so far. 2009/01/19 19:20:09| 786432 entries written so far. 2009/01/19 19:20:09| 851968 entries written so far. 2009/01/19 19:20:09| 917504 entries written so far. 2009/01/19 19:20:09| Finished. Wrote 978773 entries. 2009/01/19 19:20:09| Took 0.35 seconds (2810079.01 entries/sec). FATAL: comm_write: fd 1055: pending callback! Squid Cache (Version 3.0.STABLE10): Terminated abnormally. CPU Usage: 95.485 seconds = 54.536 user + 40.950 sys Maximum Resident Size: 0 KB Page faults with physical i/o: 0 Memory usage for squid via mallinfo(): total space in arena: 60196 KB Ordinary blocks: 60039 KB 93 blks Small blocks: 0 KB 1 blks Holding blocks: 309248 KB 1672 blks Free Small blocks: 0 KB Free Ordinary blocks: 156 KB Total in use: 369287 KB 613% Total free: 156 KB 0% 2009/01/19 19:20:12| Starting Squid Cache version 3.0.STABLE10 for x86_64-redhat -linux-gnu... 2009/01/19 19:20:12| Process ID 3883 2009/01/19 19:20:12| With 16384 file descriptors available 2009/01/19 19:20:12| DNS Socket created at 0.0.0.0, port 33227, FD 7 2009/01/19 19:20:12| Adding domain smartelecom.org from /etc/resolv.conf 2009/01/19 19:20:12| Adding nameserver 127.0.0.1 from /etc/resolv.conf 2009/01/19 19:20:12| Adding nameserver 81.85.241.28 from /etc/resolv.conf 2009/01/19 19:20:12| Adding nameserver 81.85.241.29 from /etc/resolv.conf 2009/01/19 19:20:12| helperStatefulOpenServers: Starting 5 'ntlm_auth' processes [2009/01/19 19:20:12, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! [2009/01/19 19:20:12, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! [2009/01/19 19:20:12, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! [2009/01/19 19:20:12, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! 2009/01/19 19:20:12| User-Agent logging is disabled. 2009/01/19 19:20:12| Referer logging is disabled. [2009/01/19 19:20:12, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! 2009/01/19 19:20:12| Unlinkd pipe opened on FD 16 2009/01/19 19:20:12| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec 2009/01/19 19:20:12| Store logging disabled 2009/01/19 19:20:12| Swap maxSize 209879040 KB, estimated 16144541 objects 2009/01/19 19:20:12| Target number of buckets: 807227 2009/01/19 19:20:12| Using 1048576 Store buckets 2009/01/19 19:20:12| Max Mem size: 4194304 KB 2009/01/19 19:20:12| Max Swap size: 209879040 KB 2009/01/19 19:20:12| Version 1 of swap file without LFS support detected... 2009/01/19 19:20:12| Rebuilding storage in /cache1 (CLEAN) 2009/01/19 19:20:12| Using Least Load store dir selection 2009/01/19 19:20:12| Set Current Directory to /var/spool/squid 2009/01/19 19:20:13| Loaded Icons. 2009/01/19 19:20:13| Accepting transparently proxied HTTP connections at 0.0.0.0 , port 8080, FD 17. 2009/01/19 19:20:13| Accepting ICP messages at 0.0.0.0, port 3130, FD 18. 2009/01/19 19:20:13| HTCP Disabled. 2009/01/19 19:20:13| Ready to serve requests. 2009/01/19 19:20:13| Store rebuilding is 0.42% complete 2009/01/19 19:20:22| Done reading /cache1 swaplog (978773 entries) 2009/01/19 19:20:22| Finished rebuilding storage from disk. 2009/01/19 19:20:22| 978773 Entries scanned 2009/01/19 19:20:22| 0 Invalid entries. 2009/01/19 19:20:22| 0 With invalid flags. 2009/01/19 19:20:22| 978547 Objects loaded. 2009/01/19 19:20:22| 0 Objects expired. 2009/01/19 19:20:22| 0 Objects cancelled. 2009/01/19 19:20:22| 57 Duplicate URLs purged. 2009/01/19 19:20:22| 169 Swapfile clashes avoided. 2009/01/19 19:20:22| Took 9.40 seconds (104145.43 objects/sec). 2009/01/19 19:20:22| Beginning Validation Procedure 2009/01/19 19:20:22| 524288 Entries Validated so far. 2009/01/19 19:20:22| 786432 Entries Validated so far. 2009/01/19 19:20:22| 1310720 Entries Validated so far. 2009/01/19 19:20:22| 1835008 Entries Validated so far. 2009/01/19 19:20:22| Completed Validation Procedure 2009/01/19 19:20:22| Validated 1957542 Entries 2009/01/19 19:20:22| store_swap_size = 21246804 2009/01/19 19:20:24| storeLateRelease: released 8 objects 2009/01/19 19:22:03| clientParseRequestMethod: Unsupported method: This is not a bug. see squid.conf extension_methods 2009/01/19 19:22:03| clientParseRequestMethod: Unsupported method in request '$n !!nE7!!]5W!-^)2!#1g.'k+iA!ZmB)!!Ox4!!y!5~~~~~~:NxU-:Q*v2M.jTN"; bh="b!!!!1!!-yu! !!!':N=1j!!.+B!!!!' 2009/01/19 19:22:03| clientProcessRequest: Invalid Request 2009/01/19 19:22:12| squidaio_queue_request: WARNING - Queue congestion 2009/01/19 19:24:07| clientParseRequestMethod: Unsupported method: This is not a bug. see squid.conf extension_methods 2009/01/19 19:24:07| clientParseRequestMethod: Unsupported method in request 'D_ &_w_>o___&_ _>j_x_&' 2009/01/19 19:24:07| clientProcessRequest: Invalid Request 2009/01/19 19:27:25| squidaio_queue_request: WARNING - Queue congestion 2009/01/19 19:29:45| ctx: enter level 0: 'http://msgr.updates.yahoo.com/vitalit y_proxy/V1/getEvents?max=10&since=1231714888&includeStatus=true&alias=thinkk _aga inn&fmt=2.0&intl=us&os=win&ver=9.0.0.2112&buddies=(jean_safy,jessa.jess,road runn er_coyote2000,squeezebox20022001,tupenga_99)' 2009/01/19 19:29:45| http.cc(561) HttpStateData::cacheableReply: unexpected http status code 519 2009/01/19 19:29:50| ctx: exit level 0 2009/01/19 19:29:50| ctx: enter level 0: 'http://msgr.updates.yahoo.com/vitalit y_proxy/V1/getEvents?max=10&since=1231714888&includeStatus=true&alias=rmc456 2002 &fmt=2.0&intl=us&os=win&ver=9.0.0.2112&buddies=(ace_0178,aubreyshane28,fresh ly_g irl14,girlfaithful_oneheart_onelove,jessy_alex4,lalyn.latayan,laura_sorescu2 000, merriam_2007,shadime89,squeezebox20022001)' 2009/01/19 19:29:50| http.cc(561) HttpStateData::cacheableReply: unexpected http status code 519 2009/01/19 19:30:37| ctx: exit level 0 2009/01/19 19:30:37| ctx: enter level 0: 'http://msgr.updates.yahoo.com/vitalit y_proxy/V1/getEvents?max=10&since=1231714888&includeStatus=true&alias=rmc456 2002 &fmt=2.0&intl=us&os=win&ver=9.0.0.2112&buddies=(ace_0178,aubreyshane28,fresh ly_g irl14,girlfaithful_oneheart_onelove,jessy_alex4,lalyn.latayan,laura_sorescu2 000, merriam_2007,shadime89,squeezebox20022001)' 2009/01/19 19:30:37| http.cc(561) HttpStateData::cacheableReply: unexpected http status code 519 http.cc(561) HttpStateData::cacheableReply: unexpected http status code 554 2009/01/19 20:02:59| ctx: exit level 0 2009/01/19 20:02:59| ctx: enter level 0: 'http://msgr.updates.yahoo.com/vitalit y_proxy/V1/getEvents?max=10&alias=michellhaber&fmt=2.0&intl=uk&os=win&ver=9. 0.0. 2034&buddies=(abir_saliba,ammarchalak,colettesaliba,ezzatattar,fa_sekran,mar iaha ber_51)' 2009/01/19 20:02:59| http.cc(561) HttpStateData::cacheableReply: unexpected http status code 554 2009/01/19 20:04:44| ctx: exit level 0 2009/01/19 20:04:44| clientParseRequestMethod: Unsupported method: This is not a bug. see squid.conf extension_methods 2009/01/19 20:04:44| clientParseRequestMethod: Unsupported method in request 'Yr F___0___F_" ""__y_yG'Q__i____a(z____~-_3_____E___M___|B______8G' 2009/01/19 20:04:44| clientProcessRequest: Invalid Request 2009/01/19 20:06:19| clientParseRequestMethod: Unsupported method: This is not a bug. see squid.conf extension_methods 2009/01/19 20:06:19| clientParseRequestMethod: Unsupported method in request '__ __>__N__zx_'_____kv3__jj_^+E_u[___,*__/_Y______`__$?______%____(_____D_ye__k J__p ____r___;__t_<_7__' 2009/01/19 20:06:19| clientProcessRequest: Invalid Request 2009/01/19 20:07:32| storeDirWriteCleanLogs: Starting... 2009/01/19 20:07:32| WARNING: Closing open FD 17 2009/01/19 20:07:32| 65536 entries written so far. 2009/01/19 20:07:32| 131072 entries written so far. 2009/01/19 20:07:32| 196608 entries written so far. 2009/01/19 20:07:33| 262144 entries written so far. 2009/01/19 20:07:33| 327680 entries written so far. 2009/01/19 20:07:33| 393216 entries written so far. 2009/01/19 20:07:33| 458752 entries written so far. 2009/01/19 20:07:33| 524288 entries written so far. 2009/01/19 20:07:33| 589824 entries written so far. 2009/01/19 20:07:33| 655360 entries written so far. 2009/01/19 20:07:33| 720896 entries written so far. 2009/01/19 20:07:33| 786432 entries written so far. 2009/01/19 20:07:33| 851968 entries written so far. 2009/01/19 20:07:33| 917504 entries written so far. 2009/01/19 20:07:33| 983040 entries written so far. 2009/01/19 20:07:33| Finished. Wrote 1019349 entries. 2009/01/19 20:07:33| Took 0.35 seconds (2913807.69 entries/sec). FATAL: comm_write: fd 672: pending callback! Squid Cache (Version 3.0.STABLE10): Terminated abnormally. CPU Usage: 42.703 seconds = 22.635 user + 20.068 sys Maximum Resident Size: 0 KB Page faults with physical i/o: 0 Memory usage for squid via mallinfo(): total space in arena: 29824 KB Ordinary blocks: 29709 KB 361 blks Small blocks: 0 KB 1 blks Holding blocks: 229220 KB 1065 blks Free Small blocks: 0 KB Free Ordinary blocks: 114 KB Total in use: 258929 KB 868% Total free: 115 KB 0% 2009/01/19 20:07:36| Starting Squid Cache version 3.0.STABLE10 for x86_64-redhat -linux-gnu... 2009/01/19 20:07:36| Process ID 3980 2009/01/19 20:07:36| With 16384 file descriptors available 2009/01/19 20:07:36| DNS Socket created at 0.0.0.0, port 45513, FD 7 2009/01/19 20:07:36| Adding domain smartelecom.org from /etc/resolv.conf 2009/01/19 20:07:36| Adding nameserver 127.0.0.1 from /etc/resolv.conf 2009/01/19 20:07:36| Adding nameserver 81.85.241.28 from /etc/resolv.conf 2009/01/19 20:07:36| Adding nameserver 81.85.241.29 from /etc/resolv.conf 2009/01/19 20:07:36| helperStatefulOpenServers: Starting 5 'ntlm_auth' processes [2009/01/19 20:07:36, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! [2009/01/19 20:07:36, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! [2009/01/19 20:07:36, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! [2009/01/19 20:07:36, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! 2009/01/19 20:07:36| User-Agent logging is disabled. 2009/01/19 20:07:36| Referer logging is disabled. [2009/01/19 20:07:36, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! 2009/01/19 20:07:37| Unlinkd pipe opened on FD 16 2009/01/19 20:07:37| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec 2009/01/19 20:07:37| Store logging disabled 2009/01/19 20:07:37| Swap maxSize 209879040 KB, estimated 16144541 objects 2009/01/19 20:07:37| Target number of buckets: 807227 2009/01/19 20:07:37| Using 1048576 Store buckets 2009/01/19 20:07:37| Max Mem size: 4194304 KB 2009/01/19 20:07:37| Max Swap size: 209879040 KB 2009/01/19 20:07:37| Version 1 of swap file without LFS support detected... 2009/01/19 20:07:37| Rebuilding storage in /cache1 (CLEAN) 2009/01/19 20:07:37| Using Least Load store dir selection 2009/01/19 20:07:37| Set Current Directory to /var/spool/squid 2009/01/19 20:07:37| Loaded Icons. 2009/01/19 20:07:37| Accepting transparently proxied HTTP connections at 0.0.0.0 , port 8080, FD 17. 2009/01/19 20:07:37| Accepting ICP messages at 0.0.0.0, port 3130, FD 18. 2009/01/19 20:07:37| HTCP Disabled. 2009/01/19 20:07:37| Ready to serve requests. 2009/01/19 20:07:37| Store rebuilding is 0.40% complete 2009/01/19 20:07:48| Done reading /cache1 swaplog (1019349 entries) 2009/01/19 20:07:48| Finished rebuilding storage from disk. 2009/01/19 20:07:48| 1019349 Entries scanned 2009/01/19 20:07:48| 0 Invalid entries. 2009/01/19 20:07:48| 0 With invalid flags. 2009/01/19 20:07:48| 1018979 Objects loaded. 2009/01/19 20:07:48| 0 Objects expired. 2009/01/19 20:07:48| 0 Objects cancelled. 2009/01/19 20:07:48| 139 Duplicate URLs purged. 2009/01/19 20:07:48| 231 Swapfile clashes avoided. 2009/01/19 20:07:48| Took 11.55 seconds (88261.15 objects/sec). 2009/01/19 20:07:48| Beginning Validation Procedure 2009/01/19 20:07:48| 786432 Entries Validated so far. 2009/01/19 20:07:48| 1048576 Entries Validated so far. 2009/01/19 20:07:48| 1572864 Entries Validated so far. 2009/01/19 20:07:48| 1835008 Entries Validated so far. 2009/01/19 20:07:48| Completed Validation Procedure 2009/01/19 20:07:48| Validated 2038446 Entries 2009/01/19 20:07:48| store_swap_size = 21948304 2009/01/19 20:07:50| storeLateRelease: released 19 objects 2009/01/19 20:09:05| storeDirWriteCleanLogs: Starting... 2009/01/19 20:09:05| WARNING: Closing open FD 17 2009/01/19 20:09:05| 65536 entries written so far. 2009/01/19 20:09:05| 131072 entries written so far. 2009/01/19 20:09:05| 196608 entries written so far. 2009/01/19 20:09:05| 262144 entries written so far. 2009/01/19 20:09:05| 327680 entries written so far. 2009/01/19 20:09:05| 393216 entries written so far. 2009/01/19 20:09:05| 458752 entries written so far. 2009/01/19 20:09:05| 524288 entries written so far. 2009/01/19 20:09:05| 589824 entries written so far. 2009/01/19 20:09:05| 655360 entries written so far. 2009/01/19 20:09:05| 720896 entries written so far. 2009/01/19 20:09:05| 786432 entries written so far. 2009/01/19 20:09:05| 851968 entries written so far. 2009/01/19 20:09:05| 917504 entries written so far. 2009/01/19 20:09:05| 983040 entries written so far. 2009/01/19 20:09:05| Finished. Wrote 1020275 entries. 2009/01/19 20:09:05| Took 0.35 seconds (2914014.06 entries/sec). FATAL: comm_write: fd 2296: pending callback! Squid Cache (Version 3.0.STABLE10): Terminated abnormally. CPU Usage: 16.439 seconds = 6.647 user + 9.792 sys Maximum Resident Size: 0 KB Page faults with physical i/o: 0 Memory usage for squid via mallinfo(): total space in arena: 14160 KB Ordinary blocks: 13969 KB 526 blks Small blocks: 0 KB 1 blks Holding blocks: 183708 KB 731 blks Free Small blocks: 0 KB Free Ordinary blocks: 190 KB Total in use: 197677 KB 1396% Total free: 190 KB 1% 2009/01/19 20:09:09| Starting Squid Cache version 3.0.STABLE10 for x86_64-redhat -linux-gnu... 2009/01/19 20:09:09| Process ID 4005 2009/01/19 20:09:09| With 16384 file descriptors available 2009/01/19 20:09:09| DNS Socket created at 0.0.0.0, port 31876, FD 7 2009/01/19 20:09:09| Adding domain smartelecom.org from /etc/resolv.conf 2009/01/19 20:09:09| Adding nameserver 127.0.0.1 from /etc/resolv.conf 2009/01/19 20:09:09| Adding nameserver 81.85.241.28 from /etc/resolv.conf 2009/01/19 20:09:09| Adding nameserver 81.85.241.29 from /etc/resolv.conf 2009/01/19 20:09:09| helperStatefulOpenServers: Starting 5 'ntlm_auth' processes [2009/01/19 20:09:09, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! [2009/01/19 20:09:09, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! [2009/01/19 20:09:09, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! [2009/01/19 20:09:09, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! 2009/01/19 20:09:09| User-Agent logging is disabled. 2009/01/19 20:09:09| Referer logging is disabled. [2009/01/19 20:09:09, 0] utils/ntlm_auth.c:get_winbind_domain(173) could not obtain winbind domain name! 2009/01/19 20:09:09| Unlinkd pipe opened on FD 16 2009/01/19 20:09:09| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec 2009/01/19 20:09:09| Store logging disabled 2009/01/19 20:09:09| Swap maxSize 209879040 KB, estimated 16144541 objects 2009/01/19 20:09:09| Target number of buckets: 807227 2009/01/19 20:09:09| Using 1048576 Store buckets 2009/01/19 20:09:09| Max Mem size: 4194304 KB 2009/01/19 20:09:09| Max Swap size: 209879040 KB 2009/01/19 20:09:09| Version 1 of swap file without LFS support detected... 2009/01/19 20:09:09| Rebuilding storage in /cache1 (CLEAN) 2009/01/19 20:09:09| Using Least Load store dir selection 2009/01/19 20:09:09| Set Current Directory to /var/spool/squid 2009/01/19 20:09:09| Loaded Icons. 2009/01/19 20:09:09| Accepting transparently proxied HTTP connections at 0.0.0.0 , port 8080, FD 17. 2009/01/19 20:09:09| Accepting ICP messages at 0.0.0.0, port 3130, FD 18. 2009/01/19 20:09:09| HTCP Disabled. 2009/01/19 20:09:09| Ready to serve requests. 2009/01/19 20:09:09| Store rebuilding is 0.40% complete 2009/01/19 20:09:19| Done reading /cache1 swaplog (1020275 entries) 2009/01/19 20:09:19| Finished rebuilding storage from disk. 2009/01/19 20:09:19| 1020275 Entries scanned 2009/01/19 20:09:19| 0 Invalid entries. 2009/01/19 20:09:19| 0 With invalid flags. 2009/01/19 20:09:19| 1019684 Objects loaded. 2009/01/19 20:09:19| 0 Objects expired. 2009/01/19 20:09:19| 0 Objects cancelled. 2009/01/19 20:09:19| 205 Duplicate URLs purged. 2009/01/19 20:09:19| 386 Swapfile clashes avoided. 2009/01/19 20:09:19| Took 10.04 seconds (101525.65 objects/sec). 2009/01/19 20:09:19| Beginning Validation Procedure 2009/01/19 20:09:19| 1048576 Entries Validated so far. 2009/01/19 20:09:19| 1310720 Entries Validated so far. 2009/01/19 20:09:19| 1572864 Entries Validated so far. 2009/01/19 20:09:19| Completed Validation Procedure 2009/01/19 20:09:19| Validated 2040006 Entries 2009/01/19 20:09:19| store_swap_size = 21957116 2009/01/19 20:09:21| storeLateRelease: released 29 objects 2009/01/19 20:09:21| clientParseRequestMethod: Unsupported method: This is not a bug. see squid.conf extension_methods 2009/01/19 20:09:21| clientParseRequestMethod: Unsupported method in request '__ ___'4Ud_-c__X_oJ___E________ziG__8mu___t___s______UH_f__Z_&__m_______4__4*__ ___6 ___H__[=[_Q____s__' 2009/01/19 20:09:21| clientProcessRequest: Invalid Request 2009/01/19 20:11:13| squidaio_queue_request: WARNING - Queue congestion Something is happening where squid says FATAL: comm_write: fd 2296: pending callback! And it stops and restart. Does anyone have any idea what's wrong with the system. Do you think it is a configuration problem. The system is a core2 Duo 2.66G CPU and 8GB of RAM and 1 HDD exclusively for caching 250GB Sata 10000 RPM. Can the problem be resolved with Squid3 or shall I go back to squid 2.6 or 2.7? My uname -r output gives the following 2.6.27.9-73.fc9.x86_64 as function kernel Thank you Ragheb Rustom