On 02/01/2013 01:09 AM, Amos Jeffries wrote: > On 1/02/2013 6:03 a.m., Alex Rousskov wrote: >> On 01/31/2013 03:06 AM, Amos Jeffries wrote: >>> On 31/01/2013 10:24 p.m., Simone Levy wrote: >>>> Hello there, >>>> >>>> we are receiving warnings after upgrading squid from version 3.1 to >>>> 3.2 on FreeBSD. Squid appears to be fully operational though. >>>> >>>> The warnings seem to be relative to starting the helpers and opening >>>> the log files, but the helpers are started and the log files written to. >>> When dealing with logs from asynchronous event code things are not >>> always as they seem. >>> If those are working its most likely not them. >>> >>> You might have to start Squid under a debugger to find out what >>> specifically setuid is being called for. >> Amos, >> >> FWIW, I have seen this warning on FreeBSD as well. Squid calls >> set_uid(0) unconditionally. My setuid man page does not mention UID of >> zero, and I have not investigated why that call was added, but I have a >> feeling that FreeBSD does not like it: >> >>> no_suid(void) >>> { >> ... >>> debugs(21, 3, "no_suid: PID " << getpid() << " giving up root priveleges for ever"); >>> >>> if (setuid(0) < 0) >>> debugs(50, DBG_IMPORTANT, "WARNING: no_suid: setuid(0): " << xstrerror()); > > > Hmm. Yes the warning is new since we started adding debugs() about failed system calls to display reviously hidden system errors. > > Looking at all the documentation about setuid() and seteuid() I'm wondering if this was supposed to be seteuid(0) - to clear any effective-user restrictions before dropping > privileges down to the low-privileges UID. > > I'm also wondering if setuid(uid) was done earlier and the low-privilege user is what is being dened the setuid(0) - but I can't see any sign of the "Dropping privileges" > message that should appear first. Can one of you start your Squid with level-3 debug and see where in this startup list the dropping message appears? > > There is also http://bugs.squid-cache.org/show_bug.cgi?id=3751 involved with this somehow. > > > Amos Hi Amos, here's a cache.log excerpt after setting debug_options ALL,3. 2013/02/04 09:44:35.142| tools.cc(757) enter_suid: enter_suid: PID 2156 taking root privileges 2013/02/04 09:44:35.142| cache_manager.cc(103) registerProfile: registering legacy config 2013/02/04 09:44:35.142| cache_manager.cc(88) registerProfile: registered profile: config 2013/02/04 09:44:35.142| mem.cc(485) Report: Memory pools are 'on'; limit: 5.000 MB 2013/02/04 09:44:35.142| main.cc(1414) SquidMain: Doing post-config initialization 2013/02/04 09:44:35.142| tools.cc(690) leave_suid: leave_suid: PID 2156 called 2013/02/04 09:44:35.142| tools.cc(712) leave_suid: leave_suid: PID 2156 giving up root, becoming 'squid' 2013/02/04 09:44:35.142| tools.cc(757) enter_suid: enter_suid: PID 2156 taking root privileges 2013/02/04 09:44:35.143| fd.cc(221) fd_open: fd_open() FD 0 stdin 2013/02/04 09:44:35.143| fd.cc(221) fd_open: fd_open() FD 1 stdout 2013/02/04 09:44:35.143| fd.cc(221) fd_open: fd_open() FD 2 stderr 2013/02/04 09:44:35.143| tools.cc(690) leave_suid: leave_suid: PID 2156 called 2013/02/04 09:44:35.143| tools.cc(712) leave_suid: leave_suid: PID 2156 giving up root, becoming 'squid' 2013/02/04 09:44:35.143| fd.cc(221) fd_open: fd_open() FD 3 /var/log/squid/cache.log 2013/02/04 09:44:35.143| Starting Squid Cache version 3.2.6 for amd64-portbld-freebsd9.0... 2013/02/04 09:44:35.143| Process ID 2156 2013/02/04 09:44:35.143| Process Roles: master worker 2013/02/04 09:44:35.143| With 11095 file descriptors available 2013/02/04 09:44:35.143| Initializing IP Cache... 2013/02/04 09:44:35.143| cache_manager.cc(103) registerProfile: registering legacy ipcache 2013/02/04 09:44:35.143| cache_manager.cc(88) registerProfile: registered profile: ipcache 2013/02/04 09:44:35.143| cache_manager.cc(103) registerProfile: registering legacy fqdncache 2013/02/04 09:44:35.143| cache_manager.cc(88) registerProfile: registered profile: fqdncache 2013/02/04 09:44:35.143| fqdncache.cc(615) fqdncache_init: Initializing FQDN Cache... 2013/02/04 09:44:35.144| ipcache.cc(198) ipcacheRelease: ipcacheRelease: Releasing entry for 'localhost.trebigen.3b' 2013/02/04 09:44:35.144| ipcache.cc(198) ipcacheRelease: ipcacheRelease: Releasing entry for 'localhost.trebigen.3b' 2013/02/04 09:44:35.144| ipcache.cc(198) ipcacheRelease: ipcacheRelease: Releasing entry for 'localhost.trebigen.3b' 2013/02/04 09:44:35.144| ipcache.cc(198) ipcacheRelease: ipcacheRelease: Releasing entry for 'freedom2.trebigen.3b' 2013/02/04 09:44:35.144| dns_internal.cc(1494) dnsInit: idnsInit: attempt open DNS socket to: [::] 2013/02/04 09:44:35.144| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::] 2013/02/04 09:44:35.144| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::] remote=[::] FD 5 flags=1 : family=28, type=2, protocol=17 2013/02/04 09:44:35.144| fd.cc(221) fd_open: fd_open() FD 5 DNS Socket IPv6 2013/02/04 09:44:35.144| dns_internal.cc(1503) dnsInit: idnsInit: attempt open DNS socket to: 0.0.0.0 2013/02/04 09:44:35.144| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: 0.0.0.0 2013/02/04 09:44:35.144| comm.cc(570) comm_openex: comm_openex: Opened socket local=0.0.0.0 remote=[::] FD 6 flags=1 : family=2, type=2, protocol=17 2013/02/04 09:44:35.144| fd.cc(221) fd_open: fd_open() FD 6 DNS Socket IPv4 2013/02/04 09:44:35.144| DNS Socket created at [::], FD 5 2013/02/04 09:44:35.144| DNS Socket created at 0.0.0.0, FD 6 2013/02/04 09:44:35.144| Adding domain trebigen.3b from /etc/resolv.conf 2013/02/04 09:44:35.144| dns_internal.cc(330) idnsAddPathComponent: idnsAddPathComponent: Added domain #0: trebigen.3b 2013/02/04 09:44:35.144| Adding nameserver 192.168.2.11 from /etc/resolv.conf 2013/02/04 09:44:35.144| dns_internal.cc(301) idnsAddNameserver: idnsAddNameserver: Added nameserver #0 (192.168.2.11:53) 2013/02/04 09:44:35.144| Adding nameserver 192.168.42.87 from /etc/resolv.conf 2013/02/04 09:44:35.144| dns_internal.cc(301) idnsAddNameserver: idnsAddNameserver: Added nameserver #1 (192.168.42.87:53) 2013/02/04 09:44:35.144| cache_manager.cc(103) registerProfile: registering legacy idns 2013/02/04 09:44:35.144| cache_manager.cc(88) registerProfile: registered profile: idns 2013/02/04 09:44:35.144| cache_manager.cc(103) registerProfile: registering legacy redirector 2013/02/04 09:44:35.144| cache_manager.cc(88) registerProfile: registered profile: redirector 2013/02/04 09:44:35.144| helperOpenServers: Starting 5/20 'ntlm_auth' processes 2013/02/04 09:44:35.144| fd.cc(221) fd_open: fd_open() FD 7 IPC UNIX STREAM Parent 2013/02/04 09:44:35.144| fd.cc(221) fd_open: fd_open() FD 8 IPC UNIX STREAM Parent 2013/02/04 09:44:35.144| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 7 2013/02/04 09:44:35.144| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 7 2013/02/04 09:44:35.144| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 8 2013/02/04 09:44:35.144| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 8 2013/02/04 09:44:35.144| comm.cc(1077) _comm_close: comm_close: start closing FD 8 2013/02/04 09:44:35.144| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 8 2013/02/04 09:44:35.145| tools.cc(690) leave_suid: leave_suid: PID 2157 called 2013/02/04 09:44:35.145| tools.cc(783) no_suid: no_suid: PID 2157 giving up root priveleges forever 2013/02/04 09:44:35.145| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.145| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 7 2013/02/04 09:44:35.145| fd.cc(221) fd_open: fd_open() FD 9 IPC UNIX STREAM Parent 2013/02/04 09:44:35.145| fd.cc(221) fd_open: fd_open() FD 10 IPC UNIX STREAM Parent 2013/02/04 09:44:35.145| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 9 2013/02/04 09:44:35.145| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 9 2013/02/04 09:44:35.145| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 10 2013/02/04 09:44:35.145| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 10 2013/02/04 09:44:35.146| comm.cc(1077) _comm_close: comm_close: start closing FD 10 2013/02/04 09:44:35.146| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 10 2013/02/04 09:44:35.147| tools.cc(690) leave_suid: leave_suid: PID 2158 called 2013/02/04 09:44:35.149| tools.cc(783) no_suid: no_suid: PID 2158 giving up root priveleges forever 2013/02/04 09:44:35.149| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.151| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 9 2013/02/04 09:44:35.151| fd.cc(221) fd_open: fd_open() FD 11 IPC UNIX STREAM Parent 2013/02/04 09:44:35.151| fd.cc(221) fd_open: fd_open() FD 12 IPC UNIX STREAM Parent 2013/02/04 09:44:35.151| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 11 2013/02/04 09:44:35.151| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 11 2013/02/04 09:44:35.151| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 12 2013/02/04 09:44:35.151| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 12 2013/02/04 09:44:35.152| comm.cc(1077) _comm_close: comm_close: start closing FD 12 2013/02/04 09:44:35.152| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 12 2013/02/04 09:44:35.152| tools.cc(690) leave_suid: leave_suid: PID 2159 called 2013/02/04 09:44:35.157| tools.cc(783) no_suid: no_suid: PID 2159 giving up root priveleges forever 2013/02/04 09:44:35.157| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.159| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 11 2013/02/04 09:44:35.159| fd.cc(221) fd_open: fd_open() FD 13 IPC UNIX STREAM Parent 2013/02/04 09:44:35.159| fd.cc(221) fd_open: fd_open() FD 14 IPC UNIX STREAM Parent 2013/02/04 09:44:35.159| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 13 2013/02/04 09:44:35.159| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 13 2013/02/04 09:44:35.159| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 14 2013/02/04 09:44:35.159| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 14 2013/02/04 09:44:35.160| comm.cc(1077) _comm_close: comm_close: start closing FD 14 2013/02/04 09:44:35.160| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 14 2013/02/04 09:44:35.163| tools.cc(690) leave_suid: leave_suid: PID 2160 called 2013/02/04 09:44:35.163| tools.cc(783) no_suid: no_suid: PID 2160 giving up root priveleges forever 2013/02/04 09:44:35.163| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.165| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 13 2013/02/04 09:44:35.165| fd.cc(221) fd_open: fd_open() FD 15 IPC UNIX STREAM Parent 2013/02/04 09:44:35.165| fd.cc(221) fd_open: fd_open() FD 16 IPC UNIX STREAM Parent 2013/02/04 09:44:35.165| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 15 2013/02/04 09:44:35.165| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 15 2013/02/04 09:44:35.165| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 16 2013/02/04 09:44:35.165| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 16 2013/02/04 09:44:35.165| comm.cc(1077) _comm_close: comm_close: start closing FD 16 2013/02/04 09:44:35.165| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 16 2013/02/04 09:44:35.166| tools.cc(690) leave_suid: leave_suid: PID 2161 called 2013/02/04 09:44:35.169| tools.cc(783) no_suid: no_suid: PID 2161 giving up root priveleges forever 2013/02/04 09:44:35.169| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.170| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 15 2013/02/04 09:44:35.170| helperOpenServers: Starting 2/10 'ntlm_auth' processes 2013/02/04 09:44:35.172| fd.cc(221) fd_open: fd_open() FD 17 IPC UNIX STREAM Parent 2013/02/04 09:44:35.172| fd.cc(221) fd_open: fd_open() FD 18 IPC UNIX STREAM Parent 2013/02/04 09:44:35.172| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 17 2013/02/04 09:44:35.172| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 17 2013/02/04 09:44:35.172| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 18 2013/02/04 09:44:35.172| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 18 2013/02/04 09:44:35.173| comm.cc(1077) _comm_close: comm_close: start closing FD 18 2013/02/04 09:44:35.173| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 18 2013/02/04 09:44:35.173| tools.cc(690) leave_suid: leave_suid: PID 2162 called 2013/02/04 09:44:35.175| tools.cc(783) no_suid: no_suid: PID 2162 giving up root priveleges forever 2013/02/04 09:44:35.175| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.178| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 17 2013/02/04 09:44:35.178| fd.cc(221) fd_open: fd_open() FD 19 IPC UNIX STREAM Parent 2013/02/04 09:44:35.178| fd.cc(221) fd_open: fd_open() FD 20 IPC UNIX STREAM Parent 2013/02/04 09:44:35.178| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 19 2013/02/04 09:44:35.178| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 19 2013/02/04 09:44:35.178| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 20 2013/02/04 09:44:35.178| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 20 2013/02/04 09:44:35.179| comm.cc(1077) _comm_close: comm_close: start closing FD 20 2013/02/04 09:44:35.179| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 20 2013/02/04 09:44:35.181| tools.cc(690) leave_suid: leave_suid: PID 2163 called 2013/02/04 09:44:35.181| tools.cc(783) no_suid: no_suid: PID 2163 giving up root priveleges forever 2013/02/04 09:44:35.181| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.182| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 19 2013/02/04 09:44:35.182| helperOpenServers: Starting 0/5 'ntlm_auth' processes 2013/02/04 09:44:35.182| helperOpenServers: No 'ntlm_auth' processes needed. 2013/02/04 09:44:35.186| cache_manager.cc(103) registerProfile: registering legacy negotiateauthenticator 2013/02/04 09:44:35.186| cache_manager.cc(88) registerProfile: registered profile: negotiateauthenticator 2013/02/04 09:44:35.186| cache_manager.cc(103) registerProfile: registering legacy ntlmauthenticator 2013/02/04 09:44:35.186| cache_manager.cc(88) registerProfile: registered profile: ntlmauthenticator 2013/02/04 09:44:35.186| cache_manager.cc(103) registerProfile: registering legacy basicauthenticator 2013/02/04 09:44:35.186| cache_manager.cc(88) registerProfile: registered profile: basicauthenticator 2013/02/04 09:44:35.186| helperOpenServers: Starting 5/5 'ext_wbinfo_group_acl' processes 2013/02/04 09:44:35.187| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.187| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 21 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.187| fd.cc(221) fd_open: fd_open() FD 21 ext_wbinfo_group_acl 2013/02/04 09:44:35.187| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.187| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 22 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.187| fd.cc(221) fd_open: fd_open() FD 22 ext_wbinfo_group_acl 2013/02/04 09:44:35.187| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 22 2013/02/04 09:44:35.187| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 22 2013/02/04 09:44:35.187| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 21 2013/02/04 09:44:35.187| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 21 2013/02/04 09:44:35.187| ipc.cc(212) ipcCreate: ipcCreate: FD 22 sockaddr [::1]:31345 2013/02/04 09:44:35.187| ipc.cc(228) ipcCreate: ipcCreate: FD 21 sockaddr [::1]:32774 2013/02/04 09:44:35.187| ipc.cc(238) ipcCreate: ipcCreate: FD 21 listening... 2013/02/04 09:44:35.187| comm.cc(1077) _comm_close: comm_close: start closing FD 21 2013/02/04 09:44:35.187| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 21 2013/02/04 09:44:35.188| tools.cc(690) leave_suid: leave_suid: PID 2164 called 2013/02/04 09:44:35.188| tools.cc(783) no_suid: no_suid: PID 2164 giving up root priveleges forever 2013/02/04 09:44:35.188| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.188| ipc.cc(319) ipcCreate: ipcCreate: calling accept on FD 21 2013/02/04 09:44:35.188| ipc.cc(326) ipcCreate: ipcCreate: CHILD accepted new FD 4 2013/02/04 09:44:35.188| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 22 2013/02/04 09:44:35.196| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.196| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 23 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.196| fd.cc(221) fd_open: fd_open() FD 23 ext_wbinfo_group_acl 2013/02/04 09:44:35.196| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.196| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 24 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.196| fd.cc(221) fd_open: fd_open() FD 24 ext_wbinfo_group_acl 2013/02/04 09:44:35.196| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 24 2013/02/04 09:44:35.196| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 24 2013/02/04 09:44:35.196| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 23 2013/02/04 09:44:35.196| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 23 2013/02/04 09:44:35.196| ipc.cc(212) ipcCreate: ipcCreate: FD 24 sockaddr [::1]:25672 2013/02/04 09:44:35.196| ipc.cc(228) ipcCreate: ipcCreate: FD 23 sockaddr [::1]:41178 2013/02/04 09:44:35.196| ipc.cc(238) ipcCreate: ipcCreate: FD 23 listening... 2013/02/04 09:44:35.196| comm.cc(1077) _comm_close: comm_close: start closing FD 23 2013/02/04 09:44:35.197| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 23 2013/02/04 09:44:35.197| tools.cc(690) leave_suid: leave_suid: PID 2165 called 2013/02/04 09:44:35.197| tools.cc(783) no_suid: no_suid: PID 2165 giving up root priveleges forever 2013/02/04 09:44:35.197| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.197| ipc.cc(319) ipcCreate: ipcCreate: calling accept on FD 23 2013/02/04 09:44:35.197| ipc.cc(326) ipcCreate: ipcCreate: CHILD accepted new FD 4 2013/02/04 09:44:35.197| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 24 2013/02/04 09:44:35.197| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.197| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 25 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.197| fd.cc(221) fd_open: fd_open() FD 25 ext_wbinfo_group_acl 2013/02/04 09:44:35.197| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.197| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 26 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.197| fd.cc(221) fd_open: fd_open() FD 26 ext_wbinfo_group_acl 2013/02/04 09:44:35.197| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 26 2013/02/04 09:44:35.197| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 26 2013/02/04 09:44:35.197| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 25 2013/02/04 09:44:35.197| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 25 2013/02/04 09:44:35.197| ipc.cc(212) ipcCreate: ipcCreate: FD 26 sockaddr [::1]:50921 2013/02/04 09:44:35.197| ipc.cc(228) ipcCreate: ipcCreate: FD 25 sockaddr [::1]:30573 2013/02/04 09:44:35.197| ipc.cc(238) ipcCreate: ipcCreate: FD 25 listening... 2013/02/04 09:44:35.197| comm.cc(1077) _comm_close: comm_close: start closing FD 25 2013/02/04 09:44:35.197| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 25 2013/02/04 09:44:35.198| tools.cc(690) leave_suid: leave_suid: PID 2166 called 2013/02/04 09:44:35.203| tools.cc(783) no_suid: no_suid: PID 2166 giving up root priveleges forever 2013/02/04 09:44:35.203| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.204| ipc.cc(319) ipcCreate: ipcCreate: calling accept on FD 25 2013/02/04 09:44:35.204| ipc.cc(326) ipcCreate: ipcCreate: CHILD accepted new FD 4 2013/02/04 09:44:35.205| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 26 2013/02/04 09:44:35.205| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.205| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 27 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.205| fd.cc(221) fd_open: fd_open() FD 27 ext_wbinfo_group_acl 2013/02/04 09:44:35.205| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.205| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 28 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.205| fd.cc(221) fd_open: fd_open() FD 28 ext_wbinfo_group_acl 2013/02/04 09:44:35.205| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 28 2013/02/04 09:44:35.205| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 28 2013/02/04 09:44:35.205| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 27 2013/02/04 09:44:35.212| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 27 2013/02/04 09:44:35.212| ipc.cc(212) ipcCreate: ipcCreate: FD 28 sockaddr [::1]:36801 2013/02/04 09:44:35.212| ipc.cc(228) ipcCreate: ipcCreate: FD 27 sockaddr [::1]:14915 2013/02/04 09:44:35.212| ipc.cc(238) ipcCreate: ipcCreate: FD 27 listening... 2013/02/04 09:44:35.213| comm.cc(1077) _comm_close: comm_close: start closing FD 27 2013/02/04 09:44:35.213| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 27 2013/02/04 09:44:35.213| tools.cc(690) leave_suid: leave_suid: PID 2167 called 2013/02/04 09:44:35.213| tools.cc(783) no_suid: no_suid: PID 2167 giving up root priveleges forever 2013/02/04 09:44:35.213| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.214| ipc.cc(319) ipcCreate: ipcCreate: calling accept on FD 27 2013/02/04 09:44:35.214| ipc.cc(326) ipcCreate: ipcCreate: CHILD accepted new FD 4 2013/02/04 09:44:35.214| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 28 2013/02/04 09:44:35.214| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.214| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 29 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.214| fd.cc(221) fd_open: fd_open() FD 29 ext_wbinfo_group_acl 2013/02/04 09:44:35.214| comm.cc(529) comm_openex: comm_openex: Attempt open socket for: [::1] 2013/02/04 09:44:35.214| comm.cc(570) comm_openex: comm_openex: Opened socket local=[::1] remote=[::] FD 30 flags=1 : family=28, type=1, protocol=0 2013/02/04 09:44:35.214| fd.cc(221) fd_open: fd_open() FD 30 ext_wbinfo_group_acl 2013/02/04 09:44:35.214| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 30 2013/02/04 09:44:35.214| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 30 2013/02/04 09:44:35.214| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 29 2013/02/04 09:44:35.214| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 29 2013/02/04 09:44:35.214| ipc.cc(212) ipcCreate: ipcCreate: FD 30 sockaddr [::1]:39937 2013/02/04 09:44:35.214| ipc.cc(228) ipcCreate: ipcCreate: FD 29 sockaddr [::1]:57478 2013/02/04 09:44:35.214| ipc.cc(238) ipcCreate: ipcCreate: FD 29 listening... 2013/02/04 09:44:35.214| comm.cc(1077) _comm_close: comm_close: start closing FD 29 2013/02/04 09:44:35.214| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 29 2013/02/04 09:44:35.216| tools.cc(690) leave_suid: leave_suid: PID 2168 called 2013/02/04 09:44:35.220| tools.cc(783) no_suid: no_suid: PID 2168 giving up root priveleges forever 2013/02/04 09:44:35.220| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.222| ipc.cc(319) ipcCreate: ipcCreate: calling accept on FD 29 2013/02/04 09:44:35.222| ipc.cc(326) ipcCreate: ipcCreate: CHILD accepted new FD 4 2013/02/04 09:44:35.229| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 30 2013/02/04 09:44:35.229| cache_manager.cc(103) registerProfile: registering legacy external_acl 2013/02/04 09:44:35.229| cache_manager.cc(88) registerProfile: registered profile: external_acl 2013/02/04 09:44:35.229| cache_manager.cc(103) registerProfile: registering legacy http_headers 2013/02/04 09:44:35.229| cache_manager.cc(88) registerProfile: registered profile: http_headers 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_ACCESS_DENIED 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_ACCESS_DENIED 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_CACHE_ACCESS_DENIED 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_CACHE_ACCESS_DENIED 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_CACHE_MGR_ACCESS_DENIED 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_CACHE_MGR_ACCESS_DENI 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_FORWARDING_DENIED 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_FORWARDING_DENIED 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_NO_RELAY 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_NO_RELAY 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_CANNOT_FORWARD 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_CANNOT_FORWARD 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_READ_TIMEOUT 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_READ_TIMEOUT 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_LIFETIME_EXP 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_LIFETIME_EXP 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_READ_ERROR 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_READ_ERROR 2013/02/04 09:44:35.229| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_WRITE_ERROR 2013/02/04 09:44:35.229| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_WRITE_ERROR 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_CONNECT_FAIL 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_CONNECT_FAIL 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_SECURE_CONNECT_FAIL 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_SECURE_CONNECT_FAIL 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_SOCKET_FAILURE 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_SOCKET_FAILURE 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_DNS_FAIL 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_DNS_FAIL 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_URN_RESOLVE 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_URN_RESOLVE 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_ONLY_IF_CACHED_MISS 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_ONLY_IF_CACHED_MISS 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_TOO_BIG 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_TOO_BIG 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_INVALID_RESP 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_INVALID_RESP 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_UNSUP_HTTPVERSION 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_UNSUP_HTTPVERSION 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_INVALID_REQ 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_INVALID_REQ 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_UNSUP_REQ 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_UNSUP_REQ 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_INVALID_URL 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_INVALID_URL 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_ZERO_SIZE_OBJECT 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_ZERO_SIZE_OBJECT 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_PRECONDITION_FAILED 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_PRECONDITION_FAILED 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_CONFLICT_HOST 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_CONFLICT_HOST 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_DISABLED 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_DISABLED 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_UNAVAILABLE 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_UNAVAILABLE 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_FAILURE 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_FAILURE 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_PUT_ERROR 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_PUT_ERROR 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_NOT_FOUND 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_NOT_FOUND 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_FORBIDDEN 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_FORBIDDEN 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_PUT_CREATED 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_PUT_CREATED 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_PUT_MODIFIED 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_FTP_PUT_MODIFIED 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_ESI 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_ESI 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_ICAP_FAILURE 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_ICAP_FAILURE 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_GATEWAY_FAILURE 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_GATEWAY_FAILURE 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_DIR_LISTING 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_DIR_LISTING 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errors/templates/ERR_SHUTTING_DOWN 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errors/templates/ERR_SHUTTING_DOWN 2013/02/04 09:44:35.230| disk.cc(85) file_open: file_open: error opening file /usr/local/etc/squid/errors/templates/MGR_INDEX: (2) No such file or directory 2013/02/04 09:44:35.230| errorpage.cc(302) loadDefault: WARNING: failed to find or read error text file MGR_INDEX 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 /usr/local/etc/squid/errorpage.css 2013/02/04 09:44:35.230| fd.cc(116) fd_close: fd_close FD 31 /usr/local/etc/squid/errorpage.css 2013/02/04 09:44:35.230| Logfile: opening log daemon:/var/log/squid/access.log 2013/02/04 09:44:35.230| Logfile Daemon: opening log /var/log/squid/access.log 2013/02/04 09:44:35.230| fd.cc(221) fd_open: fd_open() FD 31 IPC UNIX STREAM Parent 2013/02/04 09:44:35.231| fd.cc(221) fd_open: fd_open() FD 32 IPC UNIX STREAM Parent 2013/02/04 09:44:35.231| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 31 2013/02/04 09:44:35.231| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 31 2013/02/04 09:44:35.231| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 32 2013/02/04 09:44:35.231| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 32 2013/02/04 09:44:35.231| tools.cc(690) leave_suid: leave_suid: PID 2169 called 2013/02/04 09:44:35.231| tools.cc(783) no_suid: no_suid: PID 2169 giving up root priveleges forever 2013/02/04 09:44:35.231| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.231| comm.cc(1077) _comm_close: comm_close: start closing FD 32 2013/02/04 09:44:35.231| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 32 2013/02/04 09:44:35.232| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 31 2013/02/04 09:44:35.232| ModDaemon.cc(189) logfile_mod_daemon_append: logfile_mod_daemon_append: daemon:/var/log/squid/access.log: appending 6 bytes 2013/02/04 09:44:35.232| ModDaemon.cc(193) logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 0 of 32768 bytes before append 2013/02/04 09:44:35.232| fd.cc(221) fd_open: fd_open() FD 33 IPC FIFO Parent Read 2013/02/04 09:44:35.232| fd.cc(221) fd_open: fd_open() FD 34 IPC FIFO Child Write 2013/02/04 09:44:35.232| fd.cc(221) fd_open: fd_open() FD 35 IPC FIFO Child Read 2013/02/04 09:44:35.232| fd.cc(221) fd_open: fd_open() FD 36 IPC FIFO Parent Write 2013/02/04 09:44:35.232| ipc.cc(196) ipcCreate: ipcCreate: prfd FD 33 2013/02/04 09:44:35.232| ipc.cc(197) ipcCreate: ipcCreate: pwfd FD 36 2013/02/04 09:44:35.232| ipc.cc(198) ipcCreate: ipcCreate: crfd FD 35 2013/02/04 09:44:35.232| ipc.cc(199) ipcCreate: ipcCreate: cwfd FD 34 2013/02/04 09:44:35.232| comm.cc(1077) _comm_close: comm_close: start closing FD 35 2013/02/04 09:44:35.232| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 35 2013/02/04 09:44:35.232| comm.cc(1077) _comm_close: comm_close: start closing FD 34 2013/02/04 09:44:35.232| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 34 2013/02/04 09:44:35.235| tools.cc(690) leave_suid: leave_suid: PID 2170 called 2013/02/04 09:44:35.235| tools.cc(783) no_suid: no_suid: PID 2170 giving up root priveleges forever 2013/02/04 09:44:35.235| WARNING: no_suid: setuid(0): (1) Operation not permitted 2013/02/04 09:44:35.236| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 33 2013/02/04 09:44:35.493| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 33 2013/02/04 09:44:35.493| comm.cc(735) commUnsetFdTimeout: Remove timeout for FD 36 2013/02/04 09:44:35.493| Unlinkd pipe opened on FD 36 2013/02/04 09:44:35.496| cache_manager.cc(88) registerProfile: registered profile: info Simone