Re: [squid-users] Squid crash on OpenBSD 5.2

Hello,
at first time, only 10 squidGuard helpers are used. Next i increase the
amount because i thought squid doesn't have so many helpers, to this
limit 150/192.
But it was a big crash. I have posted the crash datas and my fix on
bugzilla (now no crash since the fix). Assert is a bad thing :(.

http://bugs.squid-cache.org/show_bug.cgi?id=3732

-- 
Best regards, 
Loïc BLOT, Engineering
UNIX Systems, Security and Networks
http://www.unix-experience.fr
Le lundi 07 janvier 2013 à 16:13 +0200, Eliezer Croitoru a écrit :
> Hey Loïc,
> 
> I am not sure but a squid.conf will help a lot.
> I want to make sure first:
> are you using 150/192 squidguard helpers?
> What have you tried to do?
> Is this a regular forward proxy? is intercept proxy?
> all these can be answered with squid.conf
> 
> Best regards,
> Eliezer
> 
> 
> On 1/7/2013 3:02 PM, Loïc Blot wrote:
> > Hello,
> > i get crashes since migrating from Squid 3.1.22 to Squid 3.2.5 last
> > week. In 3.1.22 there are less crashes :s.
> >
> > I want to use 3.2.X to improve the functionnalities. Squid is configured
> > with SquidGuard.
> >
> > Do you have an idea ?
> >
> > Here are the logs:
> >
> > Page faults with physical i/o: 0
> > 2013/01/07 13:52:11 kid1| Starting Squid Cache version 3.2.5 for
> > amd64-unknown-openbsd5.2...
> > 2013/01/07 13:52:11 kid1| Process ID 5577
> > 2013/01/07 13:52:11 kid1| Process Roles: worker
> > 2013/01/07 13:52:11 kid1| With 4096 file descriptors available
> > 2013/01/07 13:52:11 kid1| Initializing IP Cache...
> > 2013/01/07 13:52:11 kid1| DNS Socket created at 0.0.0.0, FD 6
> > 2013/01/07 13:52:11 kid1| Adding nameserver 127.0.0.1
> > from /etc/resolv.conf
> > 2013/01/07 13:52:11 kid1| helperOpenServers: Starting 150/192
> > 'squidGuard' processes
> > 2013/01/07 13:52:12 kid1| Logfile: opening log /var/log/squid/access.log
> > 2013/01/07 13:52:12 kid1| WARNING: log parameters now start with a
> > module name. Use 'stdio:/var/log/squid/access.log'
> > 2013/01/07 13:52:12 kid1| Unlinkd pipe opened on FD 311
> > 2013/01/07 13:52:12 kid1| Store logging disabled
> > 2013/01/07 13:52:12 kid1| Swap maxSize 2867200 + 2867200 KB, estimated
> > 441107 objects
> > 2013/01/07 13:52:12 kid1| Target number of buckets: 22055
> > 2013/01/07 13:52:12 kid1| Using 32768 Store buckets
> > 2013/01/07 13:52:12 kid1| Max Mem  size: 2867200 KB
> > 2013/01/07 13:52:12 kid1| Max Swap size: 2867200 KB
> > 2013/01/07 13:52:12 kid1| Rebuilding storage in /var/squid/cache (clean
> > log)
> > 2013/01/07 13:52:12 kid1| Using Least Load store dir selection
> > 2013/01/07 13:52:12 kid1| Set Current Directory to /var/squid/cache
> > 2013/01/07 13:52:12 kid1| Loaded Icons.
> > 2013/01/07 13:52:12 kid1| commBind: Cannot bind socket FD 315 to
> > 0.0.0.0:3128: (48) Address already in use
> > 2013/01/07 13:52:12 kid1| HTCP Disabled.
> > 2013/01/07 13:52:12 kid1| Squid plugin modules loaded: 0
> > 2013/01/07 13:52:12 kid1| Accepting HTTP Socket connections at
> > local=0.0.0.0:3128 remote=[::] FD 314 flags=9
> > 2013/01/07 13:52:12 kid1| Store rebuilding is 5.98% complete
> > 2013/01/07 13:52:12 kid1| Failed to select source for
> > 'http://shijie.institutoptique.fr/'
> > 2013/01/07 13:52:12 kid1|   always_direct = 0
> > 2013/01/07 13:52:12 kid1|    never_direct = 0
> > 2013/01/07 13:52:12 kid1|        timedout = 0
> > 2013/01/07 13:52:12 kid1| Done reading /var/squid/cache swaplog (66864
> > entries)
> > 2013/01/07 13:52:12 kid1| Finished rebuilding storage from disk.
> > 2013/01/07 13:52:12 kid1|     66863 Entries scanned
> > 2013/01/07 13:52:12 kid1|         1 Invalid entries.
> > 2013/01/07 13:52:12 kid1|         0 With invalid flags.
> > 2013/01/07 13:52:12 kid1|     66863 Objects loaded.
> > 2013/01/07 13:52:12 kid1|         0 Objects expired.
> > 2013/01/07 13:52:12 kid1|         0 Objects cancelled.
> > 2013/01/07 13:52:12 kid1|         0 Duplicate URLs purged.
> > 2013/01/07 13:52:12 kid1|         0 Swapfile clashes avoided.
> > 2013/01/07 13:52:12 kid1|   Took 0.33 seconds (200209.60 objects/sec).
> > 2013/01/07 13:52:12 kid1| Beginning Validation Procedure
> > 2013/01/07 13:52:12 kid1|   Completed Validation Procedure
> > 2013/01/07 13:52:12 kid1|   Validated 66863 Entries
> > 2013/01/07 13:52:12 kid1|   store_swap_size = 2582071.00 KB
> > 2013/01/07 13:52:13 kid1| storeLateRelease: released 0 objects
> > 2013/01/07 13:52:14 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:14 kid1|   always_direct = 0
> > 2013/01/07 13:52:14 kid1|    never_direct = 0
> > 2013/01/07 13:52:14 kid1|        timedout = 0
> > 2013/01/07 13:52:15 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:15 kid1|   always_direct = 0
> > 2013/01/07 13:52:15 kid1|    never_direct = 0
> > 2013/01/07 13:52:15 kid1|        timedout = 0
> > 2013/01/07 13:52:16 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:16 kid1|   always_direct = 0
> > 2013/01/07 13:52:16 kid1|    never_direct = 0
> > 2013/01/07 13:52:16 kid1|        timedout = 0
> > 2013/01/07 13:52:17 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:17 kid1|   always_direct = 0
> > 2013/01/07 13:52:17 kid1|    never_direct = 0
> > 2013/01/07 13:52:17 kid1|        timedout = 0
> > 2013/01/07 13:52:17 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:17 kid1|   always_direct = 0
> > 2013/01/07 13:52:17 kid1|    never_direct = 0
> > 2013/01/07 13:52:17 kid1|        timedout = 0
> > 2013/01/07 13:52:17 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:17 kid1|   always_direct = 0
> > 2013/01/07 13:52:17 kid1|    never_direct = 0
> > 2013/01/07 13:52:17 kid1|        timedout = 0
> > 2013/01/07 13:52:18 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:18 kid1|   always_direct = 0
> > 2013/01/07 13:52:18 kid1|    never_direct = 0
> > 2013/01/07 13:52:18 kid1|        timedout = 0
> > 2013/01/07 13:52:21 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:21 kid1|   always_direct = 0
> > 2013/01/07 13:52:21 kid1|    never_direct = 0
> > 2013/01/07 13:52:21 kid1|        timedout = 0
> > 2013/01/07 13:52:21 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:21 kid1|   always_direct = 0
> > 2013/01/07 13:52:21 kid1|    never_direct = 0
> > 2013/01/07 13:52:21 kid1|        timedout = 0
> > 2013/01/07 13:52:22 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:22 kid1|   always_direct = 0
> > 2013/01/07 13:52:22 kid1|    never_direct = 0
> > 2013/01/07 13:52:22 kid1|        timedout = 0
> > 2013/01/07 13:52:24 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:24 kid1|   always_direct = 0
> > 2013/01/07 13:52:24 kid1|    never_direct = 0
> > 2013/01/07 13:52:24 kid1|        timedout = 0
> > 2013/01/07 13:52:24 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:24 kid1|   always_direct = 0
> > 2013/01/07 13:52:24 kid1|    never_direct = 0
> > 2013/01/07 13:52:24 kid1|        timedout = 0
> > 2013/01/07 13:52:24 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:24 kid1|   always_direct = 0
> > 2013/01/07 13:52:24 kid1|    never_direct = 0
> > 2013/01/07 13:52:24 kid1|        timedout = 0
> > 2013/01/07 13:52:25 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:25 kid1|   always_direct = 0
> > 2013/01/07 13:52:25 kid1|    never_direct = 0
> > 2013/01/07 13:52:25 kid1|        timedout = 0
> > 2013/01/07 13:52:26 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:26 kid1|   always_direct = 0
> > 2013/01/07 13:52:26 kid1|    never_direct = 0
> > 2013/01/07 13:52:26 kid1|        timedout = 0
> > 2013/01/07 13:52:27 kid1| ipcacheParse: No Address records in response
> > to 'ipv6.msftncsi.com'
> > 2013/01/07 13:52:27 kid1| ipcacheParse: No Address records in response
> > to 'ipv6.msftncsi.com'
> > 2013/01/07 13:52:27 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:27 kid1|   always_direct = 0
> > 2013/01/07 13:52:27 kid1|    never_direct = 0
> > 2013/01/07 13:52:27 kid1|        timedout = 0
> > 2013/01/07 13:52:27 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:27 kid1|   always_direct = 0
> > 2013/01/07 13:52:27 kid1|    never_direct = 0
> > 2013/01/07 13:52:27 kid1|        timedout = 0
> > 2013/01/07 13:52:27 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:27 kid1|   always_direct = 0
> > 2013/01/07 13:52:27 kid1|    never_direct = 0
> > 2013/01/07 13:52:27 kid1|        timedout = 0
> > 2013/01/07 13:52:29 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:29 kid1|   always_direct = 0
> > 2013/01/07 13:52:29 kid1|    never_direct = 0
> > 2013/01/07 13:52:29 kid1|        timedout = 0
> > FATAL: Received Bus Error...dying.
> > 2013/01/07 13:52:41 kid1| Closing HTTP port 0.0.0.0:3128
> > 2013/01/07 13:52:41 kid1| Closing HTTP port 0.0.0.0:3128
> > 2013/01/07 13:52:41 kid1| storeDirWriteCleanLogs: Starting...
> > 2013/01/07 13:52:42 kid1|     65536 entries written so far.
> > 2013/01/07 13:52:42 kid1|   Finished.  Wrote 66957 entries.
> > 2013/01/07 13:52:42 kid1|   Took 0.02 seconds (4130088.82 entries/sec).
> > CPU Usage: 4.250 seconds = 1.260 user + 2.990 sys
> > Maximum Resident Size: 53180 KB
> > Page faults with physical i/o: 0
> > 2013/01/07 13:52:45 kid1| Starting Squid Cache version 3.2.5 for
> > amd64-unknown-openbsd5.2...
> > 2013/01/07 13:52:45 kid1| Process ID 25866
> > 2013/01/07 13:52:45 kid1| Process Roles: worker
> > 2013/01/07 13:52:45 kid1| With 4096 file descriptors available
> > 2013/01/07 13:52:45 kid1| Initializing IP Cache...
> > 2013/01/07 13:52:45 kid1| DNS Socket created at 0.0.0.0, FD 6
> > 2013/01/07 13:52:45 kid1| Adding nameserver 127.0.0.1
> > from /etc/resolv.conf
> > 2013/01/07 13:52:45 kid1| helperOpenServers: Starting 150/192
> > 'squidGuard' processes
> > 2013/01/07 13:52:46 kid1| Logfile: opening log /var/log/squid/access.log
> > 2013/01/07 13:52:46 kid1| WARNING: log parameters now start with a
> > module name. Use 'stdio:/var/log/squid/access.log'
> > 2013/01/07 13:52:46 kid1| Unlinkd pipe opened on FD 311
> > 2013/01/07 13:52:46 kid1| Store logging disabled
> > 2013/01/07 13:52:46 kid1| Swap maxSize 2867200 + 2867200 KB, estimated
> > 441107 objects
> > 2013/01/07 13:52:46 kid1| Target number of buckets: 22055
> > 2013/01/07 13:52:46 kid1| Using 32768 Store buckets
> > 2013/01/07 13:52:46 kid1| Max Mem  size: 2867200 KB
> > 2013/01/07 13:52:46 kid1| Max Swap size: 2867200 KB
> > 2013/01/07 13:52:46 kid1| Rebuilding storage in /var/squid/cache (clean
> > log)
> > 2013/01/07 13:52:46 kid1| Using Least Load store dir selection
> > 2013/01/07 13:52:46 kid1| Set Current Directory to /var/squid/cache
> > 2013/01/07 13:52:46 kid1| Loaded Icons.
> > 2013/01/07 13:52:46 kid1| commBind: Cannot bind socket FD 315 to
> > 0.0.0.0:3128: (48) Address already in use
> > 2013/01/07 13:52:46 kid1| HTCP Disabled.
> > 2013/01/07 13:52:46 kid1| Squid plugin modules loaded: 0
> > 2013/01/07 13:52:46 kid1| Accepting HTTP Socket connections at
> > local=0.0.0.0:3128 remote=[::] FD 314 flags=9
> > 2013/01/07 13:52:46 kid1| Store rebuilding is 5.97% complete
> > 2013/01/07 13:52:46 kid1| Done reading /var/squid/cache swaplog (66957
> > entries)
> > 2013/01/07 13:52:46 kid1| Finished rebuilding storage from disk.
> > 2013/01/07 13:52:46 kid1|     66957 Entries scanned
> > 2013/01/07 13:52:46 kid1|         0 Invalid entries.
> > 2013/01/07 13:52:46 kid1|         0 With invalid flags.
> > 2013/01/07 13:52:46 kid1|     66957 Objects loaded.
> > 2013/01/07 13:52:46 kid1|         0 Objects expired.
> > 2013/01/07 13:52:46 kid1|         0 Objects cancelled.
> > 2013/01/07 13:52:46 kid1|         0 Duplicate URLs purged.
> > 2013/01/07 13:52:46 kid1|         0 Swapfile clashes avoided.
> > 2013/01/07 13:52:46 kid1|   Took 0.34 seconds (196973.49 objects/sec).
> > 2013/01/07 13:52:46 kid1| Beginning Validation Procedure
> > 2013/01/07 13:52:46 kid1|   Completed Validation Procedure
> > 2013/01/07 13:52:46 kid1|   Validated 66957 Entries
> > 2013/01/07 13:52:46 kid1|   store_swap_size = 2579500.00 KB
> > 2013/01/07 13:52:47 kid1| storeLateRelease: released 0 objects
> > 2013/01/07 13:52:48 kid1| ipcacheParse: No Address records in response
> > to 'ipv6.msftncsi.com'
> > 2013/01/07 13:52:48 kid1| ipcacheParse: No Address records in response
> > to 'ipv6.msftncsi.com'
> > 2013/01/07 13:52:48 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:48 kid1|   always_direct = 0
> > 2013/01/07 13:52:48 kid1|    never_direct = 0
> > 2013/01/07 13:52:48 kid1|        timedout = 0
> > 2013/01/07 13:52:49 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:49 kid1|   always_direct = 0
> > 2013/01/07 13:52:49 kid1|    never_direct = 0
> > 2013/01/07 13:52:49 kid1|        timedout = 0
> > 2013/01/07 13:52:50 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:52:50 kid1|   always_direct = 0
> > 2013/01/07 13:52:50 kid1|    never_direct = 0
> > 2013/01/07 13:52:50 kid1|        timedout = 0
> > 2013/01/07 13:53:11 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:53:11 kid1|   always_direct = 0
> > 2013/01/07 13:53:11 kid1|    never_direct = 0
> > 2013/01/07 13:53:11 kid1|        timedout = 0
> > 2013/01/07 13:53:26 kid1| Failed to select source for '[null_entry]'
> > 2013/01/07 13:53:26 kid1|   always_direct = 0
> > 2013/01/07 13:53:26 kid1|    never_direct = 0
> > 2013/01/07 13:53:26 kid1|        timedout = 0
> > FATAL: Received Segment Violation...dying.
> > 2013/01/07 13:53:35 kid1| Closing HTTP port 0.0.0.0:3128
> > 2013/01/07 13:53:35 kid1| Closing HTTP port 0.0.0.0:3128
> > 2013/01/07 13:53:35 kid1| storeDirWriteCleanLogs: Starting...
> > 2013/01/07 13:53:35 kid1|     65536 entries written so far.
> > 2013/01/07 13:53:35 kid1|   Finished.  Wrote 67222 entries.
> > 2013/01/07 13:53:35 kid1|   Took 0.02 seconds (4038084.94 entries/sec).
> > CPU Usage: 6.710 seconds = 1.600 user + 5.110 sys
> > Maximum Resident Size: 69424 KB
> > Page faults with physical i/o: 0
> >
>