Problems with squid - restart solves for short time

denisevhoorn · 7 July 2021 08:06

Hi there,

I am really confused about the squid-proxy server, because, I have to restart it daily to make sure, all connection will function. If I don’t do, so some websites are not reachable, linux-updates do not function. If I restart the squid, all runs fine until the same problem occurs.

I already stopped squid manually, deleted the cache completely and restarted squid, but this did not help.

As I have seen, this problem is known since core 155 I have 157 now and I remember, I had it already before, but this wasn’t worse at this time. But the requirements in our network have changed, and so I have to solve the problem. Has this problem already solved?

My search took a lot of time, but wasn’t successful. Maybe because I used the wrong keywords for searching.

I did not find anything special in the logfiles, which gives a hint on the problem.
The disks are not full.

Greetz

Denise

IPFire 2.25 (i586) - Core Update 157

arne_f · 7 July 2021 09:01

Have you enabled squid-clamav? If yes try to disable it.

Also clean the disk cache or increasing number filedescriptors often help with squid problems.

denisevhoorn · 7 July 2021 10:42

Hi Arne,

thanks for your reply.

I disabled it clamav now. I have to wait now, if it happens again. Can take up to two days.
My filedescriptors are at 4096, the cache at 20MB. Shall I increase it? As far as I remember I increased it, when I installed IPFire.

arne_f · 7 July 2021 14:30

20MB HDD cache size is very small. You should disable it or use at least 200MB.

denisevhoorn · 8 July 2021 08:04

First I increased it to 200MB now. Today, it was stable. I hope it stays like this. Could ClamAV the troublemaker? We will see…

denisevhoorn · 12 July 2021 09:50

All right. After the weekend, the problem still exist.

Cache increased to 200 MB
clamav switched off
Squid 4.15

I am really confused. What else can it be? Looking at the logfiles, I could not find anything, which gives a reason for this.

Is it possible to uninstall and reinstall squid without damaging the whole installation?

EDIT by mod: added offsite image. please post images within the Community.

pmueller · 12 July 2021 17:46

Hi,

if I may slip into this conversation…

First, the 32-bit Intel version of IPFire (i586/i686, to be precise) is deprecated and will no longer be supported after December 31, 2021. Please refer to this announcement for technical details. Unless the hardware you are running IPFire on is very old, it will most probably support 64-bit. You will then just have to create a backup, install IPFire’s 64-bit version, and restore the backup again.

Second: Could you provide us the contents of Squids’ cache log file regardless? Feel free to scrub any sensitive information (public IP addresses, internal FQDNs, etc.) from it.

Not really, and Squid should never get that corrupted during an installation.

Does your problem correlate to other events or is it occurring after a fixed amount of time (say every 24 hours or so)?

Thanks, and best regards,
Peter Müller

denisevhoorn · 13 July 2021 08:25

Hi,

thanks for slipping in. Well, nevertheless the hardware is old (and there is less chance to get new one in the future, because of 'other plans to restructure the internal internet-network) I am running a 64 bit-OS (Info)

Restarting Squid on console I got an

2021/07/13 10:08:06| WARNING: BCP 177 violation. Detected non-functional IPv6 loopback.

but forum says, this is ignoreable.

Publishing Logfiles is a problem and for me - in this case - simply not allowed. I already searched for something like “err” and found several entries like this:

access.log:

1626132301.251      0 192.168.4.82 NONE/000 0 NONE error:transaction-end-before-headers - HIER_NONE/- -

I also tried keywords “terminated” and “kill”

But in cache.log I found this:

[root@minen-von-moria squid]# cat cache.log
2021/07/11 01:27:20| Created PID file (/var/run/squid.pid)
2021/07/11 01:27:20 kid1| Current Directory is /
2021/07/11 01:27:20 kid1| Creating missing swap directories
2021/07/11 01:27:20 kid1| /var/log/cache exists
2021/07/11 01:27:20 kid1| /var/log/cache/00 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/00
2021/07/11 01:27:20 kid1| /var/log/cache/01 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/01
2021/07/11 01:27:20 kid1| /var/log/cache/02 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/02
2021/07/11 01:27:20 kid1| /var/log/cache/03 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/03
2021/07/11 01:27:20 kid1| /var/log/cache/04 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/04
2021/07/11 01:27:20 kid1| /var/log/cache/05 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/05
2021/07/11 01:27:20 kid1| /var/log/cache/06 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/06
2021/07/11 01:27:20 kid1| /var/log/cache/07 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/07
2021/07/11 01:27:20 kid1| /var/log/cache/08 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/08
2021/07/11 01:27:20 kid1| /var/log/cache/09 exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/09
2021/07/11 01:27:20 kid1| /var/log/cache/0A exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/0A
2021/07/11 01:27:20 kid1| /var/log/cache/0B exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/0B
2021/07/11 01:27:20 kid1| /var/log/cache/0C exists
2021/07/11 01:27:20 kid1| Making directories in /var/log/cache/0C
2021/07/11 01:27:21 kid1| /var/log/cache/0D exists
2021/07/11 01:27:21 kid1| Making directories in /var/log/cache/0D
2021/07/11 01:27:21 kid1| /var/log/cache/0E exists
2021/07/11 01:27:21 kid1| Making directories in /var/log/cache/0E
2021/07/11 01:27:21 kid1| /var/log/cache/0F exists
2021/07/11 01:27:21 kid1| Making directories in /var/log/cache/0F
2021/07/11 01:27:21| Removing PID file (/var/run/squid.pid)
2021/07/12 01:27:33| Created PID file (/var/run/squid.pid)
2021/07/12 01:27:33| FATAL: Squid is already running: Found fresh instance PID file (/var/run/squid.pid) with PID 16545
    exception location: Instance.cc(121) ThrowIfAlreadyRunningWith

2021/07/12 01:27:33 kid1| Current Directory is /
2021/07/12 01:27:33 kid1| Creating missing swap directories
2021/07/12 01:27:33 kid1| /var/log/cache exists
2021/07/12 01:27:33 kid1| /var/log/cache/00 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/00
2021/07/12 01:27:33 kid1| /var/log/cache/01 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/01
2021/07/12 01:27:33 kid1| /var/log/cache/02 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/02
2021/07/12 01:27:33 kid1| /var/log/cache/03 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/03
2021/07/12 01:27:33 kid1| /var/log/cache/04 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/04
2021/07/12 01:27:33 kid1| /var/log/cache/05 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/05
2021/07/12 01:27:33 kid1| /var/log/cache/06 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/06
2021/07/12 01:27:33 kid1| /var/log/cache/07 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/07
2021/07/12 01:27:33 kid1| /var/log/cache/08 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/08
2021/07/12 01:27:33 kid1| /var/log/cache/09 exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/09
2021/07/12 01:27:33 kid1| /var/log/cache/0A exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/0A
2021/07/12 01:27:33 kid1| /var/log/cache/0B exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/0B
2021/07/12 01:27:33 kid1| /var/log/cache/0C exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/0C
2021/07/12 01:27:33 kid1| /var/log/cache/0D exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/0D
2021/07/12 01:27:33 kid1| /var/log/cache/0E exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/0E
2021/07/12 01:27:33 kid1| /var/log/cache/0F exists
2021/07/12 01:27:33 kid1| Making directories in /var/log/cache/0F
2021/07/12 01:27:33| Removing PID file (/var/run/squid.pid)
2021/07/12 11:27:13| Created PID file (/var/run/squid.pid)
2021/07/12 11:27:13 kid1| Current Directory is /srv/web/ipfire/cgi-bin
2021/07/12 11:27:13 kid1| Creating missing swap directories
2021/07/12 11:27:13 kid1| /var/log/cache exists
2021/07/12 11:27:13 kid1| /var/log/cache/00 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/00
2021/07/12 11:27:13 kid1| /var/log/cache/01 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/01
2021/07/12 11:27:13 kid1| /var/log/cache/02 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/02
2021/07/12 11:27:13 kid1| /var/log/cache/03 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/03
2021/07/12 11:27:13 kid1| /var/log/cache/04 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/04
2021/07/12 11:27:13 kid1| /var/log/cache/05 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/05
2021/07/12 11:27:13 kid1| /var/log/cache/06 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/06
2021/07/12 11:27:13 kid1| /var/log/cache/07 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/07
2021/07/12 11:27:13 kid1| /var/log/cache/08 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/08
2021/07/12 11:27:13 kid1| /var/log/cache/09 exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/09
2021/07/12 11:27:13 kid1| /var/log/cache/0A exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/0A
2021/07/12 11:27:13 kid1| /var/log/cache/0B exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/0B
2021/07/12 11:27:13 kid1| /var/log/cache/0C exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/0C
2021/07/12 11:27:13 kid1| /var/log/cache/0D exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/0D
2021/07/12 11:27:13 kid1| /var/log/cache/0E exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/0E
2021/07/12 11:27:13 kid1| /var/log/cache/0F exists
2021/07/12 11:27:13 kid1| Making directories in /var/log/cache/0F
2021/07/12 11:27:13| Removing PID file (/var/run/squid.pid)
2021/07/12 11:27:14| Created PID file (/var/run/squid.pid)
2021/07/12 11:27:14 kid1| Current Directory is /srv/web/ipfire/cgi-bin
2021/07/12 11:27:14 kid1| Starting Squid Cache version 4.15 for i586-pc-linux-gnu...
2021/07/12 11:27:14 kid1| Service Name: squid
2021/07/12 11:27:14 kid1| Process ID 28233
2021/07/12 11:27:14 kid1| Process Roles: worker
2021/07/12 11:27:14 kid1| With 4096 file descriptors available
2021/07/12 11:27:14 kid1| Initializing IP Cache...
2021/07/12 11:27:14 kid1| DNS Socket created at 0.0.0.0, FD 5
2021/07/12 11:27:14 kid1| Adding domain tux-net.localdomain from /etc/resolv.conf
2021/07/12 11:27:14 kid1| Adding nameserver 127.0.0.1 from /etc/resolv.conf
2021/07/12 11:27:14 kid1| Logfile: opening log stdio:/var/log/squid/access.log
2021/07/12 11:27:14 kid1| Logfile: opening log stdio:/var/log/squid/user_agent.log
2021/07/12 11:27:14 kid1| Store logging disabled
2021/07/12 11:27:14 kid1| Swap maxSize 512000 + 204800 KB, estimated 55138 objects
2021/07/12 11:27:14 kid1| Target number of buckets: 2756
2021/07/12 11:27:14 kid1| Using 8192 Store buckets
2021/07/12 11:27:14 kid1| Max Mem  size: 204800 KB
2021/07/12 11:27:14 kid1| Max Swap size: 512000 KB
2021/07/12 11:27:14 kid1| Rebuilding storage in /var/log/cache (clean log)
2021/07/12 11:27:14 kid1| Using Least Load store dir selection
2021/07/12 11:27:14 kid1| Current Directory is /srv/web/ipfire/cgi-bin
2021/07/12 11:27:14 kid1| Finished loading MIME types and icons.
2021/07/12 11:27:14 kid1| HTCP Disabled.
2021/07/12 11:27:14 kid1| Squid plugin modules loaded: 0
2021/07/12 11:27:14 kid1| Adaptation support is off.
2021/07/12 11:27:14 kid1| Accepting HTTP Socket connections at local=192.168.0.10:800 remote=[::] FD 12 flags=9
2021/07/12 11:27:14 kid1| Accepting NAT intercepted HTTP Socket connections at local=192.168.0.10:801 remote=[::] FD 13 flags=41
2021/07/12 11:27:14 kid1| Done reading /var/log/cache swaplog (252 entries)
2021/07/12 11:27:14 kid1| Finished rebuilding storage from disk.
2021/07/12 11:27:14 kid1|       252 Entries scanned
2021/07/12 11:27:14 kid1|         0 Invalid entries.
2021/07/12 11:27:14 kid1|         0 With invalid flags.
2021/07/12 11:27:14 kid1|       252 Objects loaded.
2021/07/12 11:27:14 kid1|         0 Objects expired.
2021/07/12 11:27:14 kid1|         0 Objects cancelled.
2021/07/12 11:27:14 kid1|         0 Duplicate URLs purged.
2021/07/12 11:27:14 kid1|         0 Swapfile clashes avoided.
2021/07/12 11:27:14 kid1|   Took 0.18 seconds (1425.19 objects/sec).
2021/07/12 11:27:14 kid1| Beginning Validation Procedure
2021/07/12 11:27:14 kid1|   Completed Validation Procedure
2021/07/12 11:27:14 kid1|   Validated 252 Entries
2021/07/12 11:27:14 kid1|   store_swap_size = 459992.00 KB
2021/07/12 11:27:15 kid1| storeLateRelease: released 0 objects
2021/07/13 01:27:25| Current Directory is /
2021/07/13 01:27:25 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:25 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:25 kid1| Killing master process, pid 28230
2021/07/13 01:27:25 kid1| Closing HTTP(S) port 192.168.0.10:800
2021/07/13 01:27:25 kid1| Closing HTTP(S) port 192.168.0.10:801
2021/07/13 01:27:25 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:25 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:25 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:25 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:25 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:25 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:25 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:25 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:26 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:26 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:26 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:26 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:27 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:27 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:27 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:27 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:28 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:28 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:28 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:28 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:29 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:29 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:29 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:29 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:29 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:29 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:29 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:29 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:30 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:30 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:30 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:30 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:31 kid1| Preparing for shutdown after 14559 requests
2021/07/13 01:27:31 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:31 kid1| Shutdown: NTLM authentication.
2021/07/13 01:27:31 kid1| Shutdown: Negotiate authentication.
2021/07/13 01:27:31 kid1| Shutdown: Digest authentication.
2021/07/13 01:27:31 kid1| Shutdown: Basic authentication.
2021/07/13 01:27:31 kid1| Preparing for shutdown after 14561 requests
2021/07/13 01:27:31 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:31 kid1| Preparing for shutdown after 14561 requests
2021/07/13 01:27:31 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:31 kid1| Preparing for shutdown after 14561 requests
2021/07/13 01:27:31 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:31 kid1| Preparing for shutdown after 14561 requests
2021/07/13 01:27:31 kid1| Waiting 5 seconds for active connections to finish
2021/07/13 01:27:31 kid1| Shutting down...
2021/07/13 01:27:31 kid1| storeDirWriteCleanLogs: Starting...
2021/07/13 01:27:31 kid1|   Finished.  Wrote 1146 entries.
2021/07/13 01:27:31 kid1|   Took 0.01 seconds (207083.48 entries/sec).
CPU Usage: 47.110 seconds = 28.427 user + 18.683 sys
Maximum Resident Size: 142352 KB
Page faults with physical i/o: 34
2021/07/13 01:27:31 kid1| Logfile: closing log stdio:/var/log/squid/access.log
2021/07/13 01:27:31 kid1| Logfile: closing log stdio:/var/log/squid/user_agent.log
2021/07/13 01:27:31 kid1| Squid Cache (Version 4.15): Exiting normally.
2021/07/13 01:27:31| Removing PID file (/var/run/squid.pid)
2021/07/13 01:27:36| Created PID file (/var/run/squid.pid)
2021/07/13 01:27:36| FATAL: Squid is already running: Found fresh instance PID file (/var/run/squid.pid) with PID 1823
    exception location: Instance.cc(121) ThrowIfAlreadyRunningWith

2021/07/13 01:27:36 kid1| Current Directory is /
2021/07/13 01:27:36 kid1| Creating missing swap directories
2021/07/13 01:27:36 kid1| /var/log/cache exists
2021/07/13 01:27:36 kid1| /var/log/cache/00 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/00
2021/07/13 01:27:36 kid1| /var/log/cache/01 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/01
2021/07/13 01:27:36 kid1| /var/log/cache/02 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/02
2021/07/13 01:27:36 kid1| /var/log/cache/03 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/03
2021/07/13 01:27:36 kid1| /var/log/cache/04 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/04
2021/07/13 01:27:36 kid1| /var/log/cache/05 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/05
2021/07/13 01:27:36 kid1| /var/log/cache/06 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/06
2021/07/13 01:27:36 kid1| /var/log/cache/07 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/07
2021/07/13 01:27:36 kid1| /var/log/cache/08 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/08
2021/07/13 01:27:36 kid1| /var/log/cache/09 exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/09
2021/07/13 01:27:36 kid1| /var/log/cache/0A exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/0A
2021/07/13 01:27:36 kid1| /var/log/cache/0B exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/0B
2021/07/13 01:27:36 kid1| /var/log/cache/0C exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/0C
2021/07/13 01:27:36 kid1| /var/log/cache/0D exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/0D
2021/07/13 01:27:36 kid1| /var/log/cache/0E exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/0E
2021/07/13 01:27:36 kid1| /var/log/cache/0F exists
2021/07/13 01:27:36 kid1| Making directories in /var/log/cache/0F
2021/07/13 01:27:36| Removing PID file (/var/run/squid.pid)
2021/07/13 10:08:05| Created PID file (/var/run/squid.pid)
2021/07/13 10:08:05 kid1| Current Directory is /var/log/squid
2021/07/13 10:08:05 kid1| Creating missing swap directories
2021/07/13 10:08:05 kid1| /var/log/cache exists
2021/07/13 10:08:05 kid1| /var/log/cache/00 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/00
2021/07/13 10:08:05 kid1| /var/log/cache/01 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/01
2021/07/13 10:08:05 kid1| /var/log/cache/02 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/02
2021/07/13 10:08:05 kid1| /var/log/cache/03 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/03
2021/07/13 10:08:05 kid1| /var/log/cache/04 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/04
2021/07/13 10:08:05 kid1| /var/log/cache/05 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/05
2021/07/13 10:08:05 kid1| /var/log/cache/06 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/06
2021/07/13 10:08:05 kid1| /var/log/cache/07 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/07
2021/07/13 10:08:05 kid1| /var/log/cache/08 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/08
2021/07/13 10:08:05 kid1| /var/log/cache/09 exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/09
2021/07/13 10:08:05 kid1| /var/log/cache/0A exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/0A
2021/07/13 10:08:05 kid1| /var/log/cache/0B exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/0B
2021/07/13 10:08:05 kid1| /var/log/cache/0C exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/0C
2021/07/13 10:08:05 kid1| /var/log/cache/0D exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/0D
2021/07/13 10:08:05 kid1| /var/log/cache/0E exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/0E
2021/07/13 10:08:05 kid1| /var/log/cache/0F exists
2021/07/13 10:08:05 kid1| Making directories in /var/log/cache/0F
2021/07/13 10:08:05| Removing PID file (/var/run/squid.pid)
2021/07/13 10:08:06| Created PID file (/var/run/squid.pid)
2021/07/13 10:08:06 kid1| Current Directory is /var/log/squid
2021/07/13 10:08:06 kid1| Starting Squid Cache version 4.15 for i586-pc-linux-gnu...
2021/07/13 10:08:06 kid1| Service Name: squid
2021/07/13 10:08:06 kid1| Process ID 28414
2021/07/13 10:08:06 kid1| Process Roles: worker
2021/07/13 10:08:06 kid1| With 4096 file descriptors available
2021/07/13 10:08:06 kid1| Initializing IP Cache...
2021/07/13 10:08:06 kid1| DNS Socket created at 0.0.0.0, FD 5
2021/07/13 10:08:06 kid1| Adding domain tux-net.localdomain from /etc/resolv.conf
2021/07/13 10:08:06 kid1| Adding nameserver 127.0.0.1 from /etc/resolv.conf
2021/07/13 10:08:06 kid1| Logfile: opening log stdio:/var/log/squid/access.log
2021/07/13 10:08:06 kid1| Logfile: opening log stdio:/var/log/squid/user_agent.log
2021/07/13 10:08:06 kid1| Store logging disabled
2021/07/13 10:08:06 kid1| Swap maxSize 512000 + 204800 KB, estimated 55138 objects
2021/07/13 10:08:06 kid1| Target number of buckets: 2756
2021/07/13 10:08:06 kid1| Using 8192 Store buckets
2021/07/13 10:08:06 kid1| Max Mem  size: 204800 KB
2021/07/13 10:08:06 kid1| Max Swap size: 512000 KB
2021/07/13 10:08:06 kid1| Rebuilding storage in /var/log/cache (clean log)
2021/07/13 10:08:06 kid1| Using Least Load store dir selection
2021/07/13 10:08:06 kid1| Current Directory is /var/log/squid
2021/07/13 10:08:06 kid1| Finished loading MIME types and icons.
2021/07/13 10:08:06 kid1| HTCP Disabled.
2021/07/13 10:08:06 kid1| Squid plugin modules loaded: 0
2021/07/13 10:08:06 kid1| Adaptation support is off.
2021/07/13 10:08:06 kid1| Accepting HTTP Socket connections at local=192.168.0.10:800 remote=[::] FD 12 flags=9
2021/07/13 10:08:06 kid1| Accepting NAT intercepted HTTP Socket connections at local=192.168.0.10:801 remote=[::] FD 13 flags=41
2021/07/13 10:08:06 kid1| Done reading /var/log/cache swaplog (1146 entries)
2021/07/13 10:08:06 kid1| Finished rebuilding storage from disk.
2021/07/13 10:08:06 kid1|      1146 Entries scanned
2021/07/13 10:08:06 kid1|         0 Invalid entries.
2021/07/13 10:08:06 kid1|         0 With invalid flags.
2021/07/13 10:08:06 kid1|      1146 Objects loaded.
2021/07/13 10:08:06 kid1|         0 Objects expired.
2021/07/13 10:08:06 kid1|         0 Objects cancelled.
2021/07/13 10:08:06 kid1|         0 Duplicate URLs purged.
2021/07/13 10:08:06 kid1|         0 Swapfile clashes avoided.
2021/07/13 10:08:06 kid1|   Took 0.20 seconds (5814.57 objects/sec).
2021/07/13 10:08:06 kid1| Beginning Validation Procedure
2021/07/13 10:08:06 kid1|   Completed Validation Procedure
2021/07/13 10:08:06 kid1|   Validated 1146 Entries
2021/07/13 10:08:06 kid1|   store_swap_size = 460540.00 KB
2021/07/13 10:08:07 kid1| storeLateRelease: released 0 objects

Any other keywords I can search for?

EDIT: I forgot: this happens in about 24 hours. Have to restart squid every day.

pmueller · 13 July 2021 15:21

Hi,

according to the Fireinfo profile, your hardware should be still quite sufficient for this task. Looking at the log snippet you provided, there is one oddity - more on that below.

… by using IPFire, I hope.

denisevhoorn:

I already searched for something like “err” and found several entries like this:

access.log:
1626132301.251      0 192.168.4.82 NONE/000 0 NONE error:transaction-end-before-headers - HIER_NONE/- -

Such log lines usually indicate broken or poorly written client software. Nothing to worry about, although it would be better if everyone behaved standard-compliant on the internet…

The “i586” snippet should definitely not appear on a 64-bit system.

This sounds more worrying than it is.

Unfortunately, I still did not really got an idea about what went wrong here. It is odd to see Squid terminating this way, but the logs do not really tell why it thinks it needs to terminate.

What else is happening every 24 hours at the same time? Would you mind posting some of your machines status diagrams, especially the ones for CPU consumption, load average and RAM? Are there any corresponding messages in /var/log/messages at or near these timestamps?

Thanks, and best regards,
Peter Müller

mfischer · 13 July 2021 16:19

Hi,

Hm…

OP says:

And for what I know if this would be really a 64bit system, squid would never start.

What makes me wonder is:

What do you get if you restart squid from console with:

/etc/init.d/squid restart

And perhaps you should raise the number of file descriptors (e.g.: 16384). Just guessing, because the log doesn’t say much about the culprit.

Rebooting at ~1:27 is normal, but it seems that squid can`t clean the PID file!?

HTH,
Matthias

denisevhoorn · 19 July 2021 11:45

Here is, what I get from console restarting squid:

 /etc/init.d/squid restart
Creating Squid swap directories...                                     [  OK  ]
Starting Squid Proxy Server...
2021/07/19 13:38:02| WARNING: BCP 177 violation. Detected non-functional IPv6 lo                                                                                                                               opback.                                                                [  OK  ]

What I can’t understand: When Squid is not working well, the clients can surf. But not elsewhere. And Updates for the linux-clients do not function. I found out, I can check the functionality of my squid-server if I start an online game. The viewer of this game points to FireStorm-Viewer Startpage when starting. If I don’t see this page, so the squid is not working well.

That really confuses me.