[squid-users] Squid crash on OpenBSD 5.2

From: Loïc Blot <loic.blot_at_unix-experience.fr>
Date: Mon, 07 Jan 2013 14:02:44 +0100

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

-- 
Best regards, 
Loïc BLOT, Engineering
UNIX Systems, Security and Networks
http://www.unix-experience.fr
Received on Mon Jan 07 2013 - 13:02:45 MST

This archive was generated by hypermail 2.2.0 : Mon Jan 07 2013 - 12:00:03 MST