Nach Upgrade auf Core Update 127 ist Verbindung über Proxy sehr langsam

Post Reply
omerk
Posts: 39
Joined: June 1st, 2010, 12:43 pm

Nach Upgrade auf Core Update 127 ist Verbindung über Proxy sehr langsam

Post by omerk » February 12th, 2019, 10:24 am

Hallo,

ich habe gestern das Update auf Core Update 127 vorgenommen. Seitdem ist mein Seitenaufbau im Browser sehr langsam geworden. Auch der Zugriff aufs IPFire Webinterface wird sehr langsam. Deaktiviere ich den Squid, so ist das Problem vorbei.
Wie gehe ich am besten vor, um herauszufinden, woran es liegen kann? Also welche Log-Files sollte ich mir anschauen bzw. welche Testbefehle soll ich auf der SSH Konsole ausführen, um das Problem besser einzugrenzen?

Infos:
  • Ich habe den Squid auf Port 800 und normalerweise auch transparent auf "green"
  • ClamAV und URLFilter sind aktiviert. Eine Deaktivierung bringt jedoch keinen Unterschied
  • Der Cachemanager ist aktiviert
  • Ich habe eine eigene proxy.pac, die in den Browsern eingetragen ist. Diese prüft, ob die eigene IP im lokalen Adressbereich ist und gibt dann die Proxy-Adresse zurück. In dieser Datei habe ich nun

    Code: Select all

    return "DIRECT";"
    eingetragen, damit (und natürlich Entfernen der "transparent" Option) gehen die Clients ohny Proxy ins Netz und es läuft schnell

omerk
Posts: 39
Joined: June 1st, 2010, 12:43 pm

Re: Nach Upgrade auf Core Update 127 ist Verbindung über Proxy sehr langsam

Post by omerk » February 12th, 2019, 11:16 am

Nachdem der Editor joe auf der Konsole nicht mehr verfügbar war, obwohl er in pakfire als installiert angezeigt wurde, habe ich diesen über pakfire entfernt und neu installiert. Dann hatte ich meinen joe wieder.

Testweise habe ich deswegen squidclamav und clamav genauso über pakfire entfernt und neu installiert. Nun läuft es wieder flott, auch wenn ich den Squid wieder transparent und mit ClamAV und URLFilter laufen lassen.

Ich hoffe das hilft jemanden, der ähnliche Probleme hat.

Hellfire
Posts: 695
Joined: November 8th, 2015, 8:54 am

Re: Nach Upgrade auf Core Update 127 ist Verbindung über Proxy sehr langsam

Post by Hellfire » February 12th, 2019, 11:32 am

omerk wrote:
February 12th, 2019, 11:16 am
Testweise habe ich deswegen squidclamav und clamav genauso über pakfire entfernt und neu installiert. Nun läuft es wieder flott, auch wenn ich den Squid wieder transparent und mit ClamAV und URLFilter laufen lassen.
Also für mich nochmal zum mitschreiben ;) Du hast lediglich ClamAV und SquidClamAV per Pakfire entfernt und nun läufts wieder wie gewohnt (schnell)? Die Option in der proxy.pac hast du dann auch wieder entfernt?

Thx,
Michael
Image

User avatar
FischerM
Community Developer
Community Developer
Posts: 1024
Joined: November 2nd, 2011, 12:28 pm

Re: Nach Upgrade auf Core Update 127 ist Verbindung über Proxy sehr langsam

Post by FischerM » February 12th, 2019, 11:38 am

Hi,

Hm. Ein derartiges Verhalten konnte ich bei meinen 'squid 4.x'-Tests bisher nicht beobachten. Wobei ich überwiegend mit einem Zwangsproxy getestet habe.

Du könntest mal in die '/var/log/squid/cache.log' und die '/var/log/squid/squidclamav.log' reinschauen, ob dort irgendwelche Auffälligkeiten protokolliert wurden.

HTH,
Matthias

omerk
Posts: 39
Joined: June 1st, 2010, 12:43 pm

Re: Nach Upgrade auf Core Update 127 ist Verbindung über Proxy sehr langsam

Post by omerk » February 12th, 2019, 11:39 am

Also das "return "DIRECT" steht noch in der proxy.pac drin. Aber auch ich habe Squid aktuell als Zwangsproxy konfiguriert.

(genauer gesagt benutze ich einen etwas anderen Namen, da "proxy.pac" von IPFire erstellt und gepflegt wird, denke ich. In den Browsern ist aber die URL zu meiner eigenen Datei hinterlegt)

Ich sehe aber im squid access.log, dass mein PC über den Proxy zugreift und auch URLFilter Treffer und Eicar-ClamAV Treffer zeigen mir, dass ich über den Squid unterwegs bin. Zumindest unter http...

Der IPFire läuft schon seit Jahren mit dieser Konfiguration. Ich vermute mal, dass durch die Neuinstallation einige Prozesse neu gestartet oder sogar einige Dateien wieder am richtigen Ort hergstellt wurden. Einen Reboot des IPFire hatte ich bis dahin nicht gemacht, der wurde ja erst gestern nach dem Update neu gestartet ;)

User avatar
FischerM
Community Developer
Community Developer
Posts: 1024
Joined: November 2nd, 2011, 12:28 pm

Re: Nach Upgrade auf Core Update 127 ist Verbindung über Proxy sehr langsam

Post by FischerM » February 12th, 2019, 11:42 am

@Hellfire:
omerk wrote:...und neu installiert.
Eventuell lags auch an einer beschädigten Clamav-Signatur. Kam schon vor. Aber in letzter Zeit eher selten.

Könnte - je nach Auslastung - auch an den Redirektoren liegen. Da wurde was geändert.

Derartige Probleme werden von 'squid' protokolliert und wären in der 'cache.log' auffindbar.

Gruß,
Matthias

omerk
Posts: 39
Joined: June 1st, 2010, 12:43 pm

Re: Nach Upgrade auf Core Update 127 ist Verbindung über Proxy sehr langsam

Post by omerk » February 12th, 2019, 12:50 pm

Danke für den Hinweis auf die 'cache.log'. Hier finde ich ein paar Hinweise:

1) Eine Forwarding Loop (die wohl immer noch besteht, aber ich habe auch schon solche Einträge VOR dem Update auf 127), <domain> ist ein Platzhalter meiner internen Domain:

Code: Select all

2019/02/12 12:07:58 kid1| WARNING: Forwarding loop detected for:
HEAD / HTTP/1.1
Accept: */*
Via: 1.1 ipfire.<domain>.local (squid/4.5)
Cache-Control: max-age=259200
Connection: keep-alive
Host: ipfire.<domain>.local


2019/02/12 12:07:58 kid1| WARNING: Forwarding loop detected for:
GET / HTTP/1.1
Accept-Encoding: gzip,identity
Accept: */*
User-Agent: WWW-Mechanize/1.0.0 (http://rubyforge.org/projects/mechanize/)
Accept-Language: en-us,en;q=0.5
Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
Via: 1.1 ipfire.<domain>.local (squid/4.5)
Cache-Control: max-age=259200
Connection: keep-alive
Host: ipfire.<domain>.local
2) Update Neustart war gestern 11.02.2019 um 11:54:20. Danach habe ich dies im Protokoll (sorry ist etwas lang):

Code: Select all

2019/02/11 11:54:23| Current Directory is /
2019/02/11 11:54:23 kid1| Preparing for shutdown after 178 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Killing master process, pid 6601
2019/02/11 11:54:23 kid1| Closing HTTP(S) port 192.168.123.254:800
2019/02/11 11:54:23 kid1| Closing HTTP(S) port 192.168.123.254:80
2019/02/11 11:54:23 kid1| Closing HTTP(S) port 127.0.0.1:800
2019/02/11 11:54:23 kid1| Preparing for shutdown after 178 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 178 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 178 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 178 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| WARNING: redirector #Hlpr2 exited
2019/02/11 11:54:23 kid1| Too few redirector processes are running (need 1/2)
2019/02/11 11:54:23 kid1| Starting new helpers
2019/02/11 11:54:23 kid1| helperOpenServers: Starting 1/2 'redirect_wrapper' processes
2019/02/11 11:54:23 kid1| Preparing for shutdown after 178 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 178 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 178 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:23 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:23 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| WARNING: redirector #Hlpr1 exited
2019/02/11 11:54:24 kid1| Too few redirector processes are running (need 1/2)
2019/02/11 11:54:24 kid1| ERROR: The redirector helpers are crashing too rapidly, need help!
2019/02/11 11:54:24 kid1| Starting new helpers
2019/02/11 11:54:24 kid1| helperOpenServers: Starting 1/2 'redirect_wrapper' processes
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:24 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:24 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:25 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:25 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:25 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:25 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:25 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:25 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:25 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:25 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:25 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:25 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:26 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:26 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:27 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:27 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:28 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:28 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 179 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Shutdown: NTLM authentication.
2019/02/11 11:54:29 kid1| Shutdown: Negotiate authentication.
2019/02/11 11:54:29 kid1| Shutdown: Digest authentication.
2019/02/11 11:54:29 kid1| Shutdown: Basic authentication.
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Preparing for shutdown after 255 requests
2019/02/11 11:54:29 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 11:54:29 kid1| Shutting down...
2019/02/11 11:54:29 kid1| storeDirWriteCleanLogs: Starting...
2019/02/11 11:54:29 kid1|   Finished.  Wrote 665 entries.
2019/02/11 11:54:29 kid1|   Took 0.00 seconds (393491.12 entries/sec).
CPU Usage: 1.303 seconds = 0.603 user + 0.700 sys
Maximum Resident Size: 84528 KB
Page faults with physical i/o: 0
2019/02/11 11:54:29 kid1| Logfile: closing log stdio:/var/log/squid/access.log
2019/02/11 11:54:29 kid1| Open FD UNSTARTED    11 redirect_wrapper #1
2019/02/11 11:54:29 kid1| Open FD UNSTARTED    12 redirect_wrapper #1
2019/02/11 11:54:29 kid1| Squid Cache (Version 4.5): Exiting normally.
2019/02/11 11:54:29| Removing PID file (/var/run/squid.pid)
2019/02/11 11:56:40| Created PID file (/var/run/squid.pid)
2019/02/11 11:56:40 kid1| Current Directory is /
2019/02/11 11:56:40 kid1| Creating missing swap directories
2019/02/11 11:56:40 kid1| /var/log/cache exists
2019/02/11 11:56:40 kid1| /var/log/cache/00 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/00
2019/02/11 11:56:40 kid1| /var/log/cache/01 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/01
2019/02/11 11:56:40 kid1| /var/log/cache/02 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/02
2019/02/11 11:56:40 kid1| /var/log/cache/03 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/03
2019/02/11 11:56:40 kid1| /var/log/cache/04 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/04
2019/02/11 11:56:40 kid1| /var/log/cache/05 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/05
2019/02/11 11:56:40 kid1| /var/log/cache/06 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/06
2019/02/11 11:56:40 kid1| /var/log/cache/07 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/07
2019/02/11 11:56:40 kid1| /var/log/cache/08 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/08
2019/02/11 11:56:40 kid1| /var/log/cache/09 exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/09
2019/02/11 11:56:40 kid1| /var/log/cache/0A exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/0A
2019/02/11 11:56:40 kid1| /var/log/cache/0B exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/0B
2019/02/11 11:56:40 kid1| /var/log/cache/0C exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/0C
2019/02/11 11:56:40 kid1| /var/log/cache/0D exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/0D
2019/02/11 11:56:40 kid1| /var/log/cache/0E exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/0E
2019/02/11 11:56:40 kid1| /var/log/cache/0F exists
2019/02/11 11:56:40 kid1| Making directories in /var/log/cache/0F
2019/02/11 11:56:40| Removing PID file (/var/run/squid.pid)
2019/02/11 11:56:41| Created PID file (/var/run/squid.pid)
2019/02/11 11:56:41 kid1| Current Directory is /
2019/02/11 11:56:41 kid1| Starting Squid Cache version 4.5 for i586-pc-linux-gnu...
2019/02/11 11:56:41 kid1| Service Name: squid
2019/02/11 11:56:41 kid1| Process ID 13821
2019/02/11 11:56:41 kid1| Process Roles: worker
2019/02/11 11:56:41 kid1| With 16384 file descriptors available
2019/02/11 11:56:41 kid1| Initializing IP Cache...
2019/02/11 11:56:41 kid1| DNS Socket created at [::], FD 5
2019/02/11 11:56:41 kid1| DNS Socket created at 0.0.0.0, FD 8
2019/02/11 11:56:41 kid1| Adding domain <domain>.local from /etc/resolv.conf
2019/02/11 11:56:41 kid1| Adding nameserver 127.0.0.1 from /etc/resolv.conf
2019/02/11 11:56:41 kid1| helperOpenServers: Starting 2/2 'redirect_wrapper' processes
2019/02/11 11:56:41 kid1| Logfile: opening log stdio:/var/log/squid/access.log
2019/02/11 11:56:41 kid1| Store logging disabled
2019/02/11 11:56:41 kid1| Swap maxSize 51200 + 2048 KB, estimated 4096 objects
2019/02/11 11:56:41 kid1| Target number of buckets: 204
2019/02/11 11:56:41 kid1| Using 8192 Store buckets
2019/02/11 11:56:41 kid1| Max Mem  size: 2048 KB
2019/02/11 11:56:41 kid1| Max Swap size: 51200 KB
2019/02/11 11:56:41 kid1| Rebuilding storage in /var/log/cache (clean log)
2019/02/11 11:56:41 kid1| Using Least Load store dir selection
2019/02/11 11:56:41 kid1| Current Directory is /
2019/02/11 11:56:41 kid1| Finished loading MIME types and icons.
2019/02/11 11:56:41 kid1| HTCP Disabled.
2019/02/11 11:56:41 kid1| Squid plugin modules loaded: 0
2019/02/11 11:56:41 kid1| Adaptation support is off.
2019/02/11 11:56:41 kid1| Accepting HTTP Socket connections at local=192.168.123.254:800 remote=[::] FD 16 flags=9
2019/02/11 11:56:41 kid1| Accepting NAT intercepted HTTP Socket connections at local=192.168.123.254:80 remote=[::] FD 17 flags=41
2019/02/11 11:56:41 kid1| Accepting HTTP Socket connections at local=127.0.0.1:800 remote=[::] FD 18 flags=9
2019/02/11 11:56:41 kid1| Done reading /var/log/cache swaplog (665 entries)
2019/02/11 11:56:41 kid1| Finished rebuilding storage from disk.
2019/02/11 11:56:41 kid1|       665 Entries scanned
2019/02/11 11:56:41 kid1|         0 Invalid entries.
2019/02/11 11:56:41 kid1|         0 With invalid flags.
2019/02/11 11:56:41 kid1|       665 Objects loaded.
2019/02/11 11:56:41 kid1|         0 Objects expired.
2019/02/11 11:56:41 kid1|         0 Objects cancelled.
2019/02/11 11:56:41 kid1|         0 Duplicate URLs purged.
2019/02/11 11:56:41 kid1|         0 Swapfile clashes avoided.
2019/02/11 11:56:41 kid1|   Took 0.16 seconds (4274.91 objects/sec).
2019/02/11 11:56:41 kid1| Beginning Validation Procedure
2019/02/11 11:56:41 kid1|   Completed Validation Procedure
2019/02/11 11:56:41 kid1|   Validated 665 Entries
2019/02/11 11:56:41 kid1|   store_swap_size = 46064.00 KB
2019/02/11 11:56:42 kid1| storeLateRelease: released 0 objects
2019/02/11 12:04:40| Current Directory is /srv/web/ipfire/cgi-bin
2019/02/11 12:04:40 kid1| Preparing for shutdown after 322 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:40 kid1| Killing master process, pid 13819
2019/02/11 12:04:40 kid1| Closing HTTP(S) port 192.168.123.254:800
2019/02/11 12:04:40 kid1| Closing HTTP(S) port 192.168.123.254:80
2019/02/11 12:04:40 kid1| Closing HTTP(S) port 127.0.0.1:800
2019/02/11 12:04:40 kid1| Preparing for shutdown after 322 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:40 kid1| WARNING: redirector #Hlpr2 exited
2019/02/11 12:04:40 kid1| Too few redirector processes are running (need 1/2)
2019/02/11 12:04:40 kid1| Starting new helpers
2019/02/11 12:04:40 kid1| helperOpenServers: Starting 1/2 'redirect_wrapper' processes
2019/02/11 12:04:40 kid1| Preparing for shutdown after 322 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:40 kid1| WARNING: redirector #Hlpr1 exited
2019/02/11 12:04:40 kid1| Too few redirector processes are running (need 1/2)
2019/02/11 12:04:40 kid1| ERROR: The redirector helpers are crashing too rapidly, need help!
2019/02/11 12:04:40 kid1| Starting new helpers
2019/02/11 12:04:40 kid1| helperOpenServers: Starting 1/2 'redirect_wrapper' processes
2019/02/11 12:04:40 kid1| Preparing for shutdown after 322 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:40 kid1| Preparing for shutdown after 322 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:40 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:40 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:40 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:40 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:40 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:40 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:41 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:41 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:42 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:42 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 324 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 325 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 326 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 326 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 326 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:43 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:43 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:44 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:44 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:44 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:44 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:44 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:44 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:44 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:44 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:45 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:45 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:45 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:45 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:45 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:45 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:45 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:45 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:46 kid1| Preparing for shutdown after 327 requests
2019/02/11 12:04:46 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:46 kid1| Shutdown: NTLM authentication.
2019/02/11 12:04:46 kid1| Shutdown: Negotiate authentication.
2019/02/11 12:04:46 kid1| Shutdown: Digest authentication.
2019/02/11 12:04:46 kid1| Shutdown: Basic authentication.
2019/02/11 12:04:46 kid1| Preparing for shutdown after 346 requests
2019/02/11 12:04:46 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:46 kid1| Preparing for shutdown after 346 requests
2019/02/11 12:04:46 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:46 kid1| Preparing for shutdown after 346 requests
2019/02/11 12:04:46 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:46 kid1| Preparing for shutdown after 346 requests
2019/02/11 12:04:46 kid1| Waiting 5 seconds for active connections to finish
2019/02/11 12:04:46 kid1| Shutting down...
2019/02/11 12:04:46 kid1| storeDirWriteCleanLogs: Starting...
2019/02/11 12:04:46 kid1|   Finished.  Wrote 685 entries.
2019/02/11 12:04:46 kid1|   Took 0.00 seconds (590517.24 entries/sec).
CPU Usage: 1.155 seconds = 0.645 user + 0.510 sys
Maximum Resident Size: 81680 KB
Page faults with physical i/o: 3
2019/02/11 12:04:46 kid1| Logfile: closing log stdio:/var/log/squid/access.log
2019/02/11 12:04:46 kid1| Open FD UNSTARTED    11 redirect_wrapper #1
2019/02/11 12:04:46 kid1| Open FD UNSTARTED    14 redirect_wrapper #1
2019/02/11 12:04:46 kid1| Squid Cache (Version 4.5): Exiting normally.
2019/02/11 12:04:46| Removing PID file (/var/run/squid.pid)
2019/02/11 12:04:51| Created PID file (/var/run/squid.pid)
2019/02/11 12:04:51 kid1| Current Directory is /srv/web/ipfire/cgi-bin
2019/02/11 12:04:51 kid1| Creating missing swap directories
2019/02/11 12:04:51 kid1| /var/log/cache exists
2019/02/11 12:04:51 kid1| /var/log/cache/00 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/00
2019/02/11 12:04:51 kid1| /var/log/cache/01 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/01
2019/02/11 12:04:51 kid1| /var/log/cache/02 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/02
2019/02/11 12:04:51 kid1| /var/log/cache/03 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/03
2019/02/11 12:04:51 kid1| /var/log/cache/04 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/04
2019/02/11 12:04:51 kid1| /var/log/cache/05 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/05
2019/02/11 12:04:51 kid1| /var/log/cache/06 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/06
2019/02/11 12:04:51 kid1| /var/log/cache/07 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/07
2019/02/11 12:04:51 kid1| /var/log/cache/08 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/08
2019/02/11 12:04:51 kid1| /var/log/cache/09 exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/09
2019/02/11 12:04:51 kid1| /var/log/cache/0A exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/0A
2019/02/11 12:04:51 kid1| /var/log/cache/0B exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/0B
2019/02/11 12:04:51 kid1| /var/log/cache/0C exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/0C
2019/02/11 12:04:51 kid1| /var/log/cache/0D exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/0D
2019/02/11 12:04:51 kid1| /var/log/cache/0E exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/0E
2019/02/11 12:04:51 kid1| /var/log/cache/0F exists
2019/02/11 12:04:51 kid1| Making directories in /var/log/cache/0F
2019/02/11 12:04:51| Removing PID file (/var/run/squid.pid)
2019/02/11 12:04:52| Created PID file (/var/run/squid.pid)
2019/02/11 12:04:52 kid1| Current Directory is /srv/web/ipfire/cgi-bin
2019/02/11 12:04:52 kid1| Starting Squid Cache version 4.5 for i586-pc-linux-gnu...
2019/02/11 12:04:52 kid1| Service Name: squid
2019/02/11 12:04:52 kid1| Process ID 16300
2019/02/11 12:04:52 kid1| Process Roles: worker
2019/02/11 12:04:52 kid1| With 16384 file descriptors available
2019/02/11 12:04:52 kid1| Initializing IP Cache...
2019/02/11 12:04:52 kid1| DNS Socket created at [::], FD 5
2019/02/11 12:04:52 kid1| DNS Socket created at 0.0.0.0, FD 8
2019/02/11 12:04:52 kid1| Adding domain <domain>.local from /etc/resolv.conf
2019/02/11 12:04:52 kid1| Adding nameserver 127.0.0.1 from /etc/resolv.conf
2019/02/11 12:04:52 kid1| helperOpenServers: Starting 2/2 'redirect_wrapper' processes
2019/02/11 12:04:52 kid1| Logfile: opening log stdio:/var/log/squid/access.log
2019/02/11 12:04:52 kid1| Store logging disabled
2019/02/11 12:04:52 kid1| Swap maxSize 51200 + 4096 KB, estimated 4253 objects
2019/02/11 12:04:52 kid1| Target number of buckets: 212
2019/02/11 12:04:52 kid1| Using 8192 Store buckets
2019/02/11 12:04:52 kid1| Max Mem  size: 4096 KB
2019/02/11 12:04:52 kid1| Max Swap size: 51200 KB
2019/02/11 12:04:52 kid1| Rebuilding storage in /var/log/cache (clean log)
2019/02/11 12:04:52 kid1| Using Least Load store dir selection
2019/02/11 12:04:52 kid1| Current Directory is /srv/web/ipfire/cgi-bin
2019/02/11 12:04:52 kid1| Finished loading MIME types and icons.
2019/02/11 12:04:52 kid1| HTCP Disabled.
2019/02/11 12:04:52 kid1| Squid plugin modules loaded: 0
2019/02/11 12:04:52 kid1| Adaptation support is off.
2019/02/11 12:04:52 kid1| Accepting HTTP Socket connections at local=192.168.123.254:800 remote=[::] FD 16 flags=9
2019/02/11 12:04:52 kid1| Accepting NAT intercepted HTTP Socket connections at local=192.168.123.254:80 remote=[::] FD 17 flags=41
2019/02/11 12:04:52 kid1| Accepting HTTP Socket connections at local=127.0.0.1:800 remote=[::] FD 18 flags=9
2019/02/11 12:04:52 kid1| Done reading /var/log/cache swaplog (685 entries)
2019/02/11 12:04:52 kid1| Finished rebuilding storage from disk.
2019/02/11 12:04:52 kid1|       685 Entries scanned
2019/02/11 12:04:52 kid1|         0 Invalid entries.
2019/02/11 12:04:52 kid1|         0 With invalid flags.
2019/02/11 12:04:52 kid1|       685 Objects loaded.
2019/02/11 12:04:52 kid1|         0 Objects expired.
2019/02/11 12:04:52 kid1|         0 Objects cancelled.
2019/02/11 12:04:52 kid1|         0 Duplicate URLs purged.
2019/02/11 12:04:52 kid1|         0 Swapfile clashes avoided.
2019/02/11 12:04:52 kid1|   Took 0.14 seconds (4884.90 objects/sec).
2019/02/11 12:04:52 kid1| Beginning Validation Procedure
2019/02/11 12:04:52 kid1|   Completed Validation Procedure
2019/02/11 12:04:52 kid1|   Validated 685 Entries
2019/02/11 12:04:52 kid1|   store_swap_size = 45360.00 KB
2019/02/11 12:04:53 kid1| storeLateRelease: released 0 objects
Neben den vielen Versuchen eines Shutdowns fällt mir dies hier auf (ich vermute der Reboot war von mir um 11:54:23 ausgelöst und um 11:56:42 zu Ende):

Code: Select all

2019/02/11 12:04:40 kid1| ERROR: The redirector helpers are crashing too rapidly, need help!
Warum der Prozess aber um 12:04:40 wieder beendet wurde, ist mir nicht ganz klar.

unter '/var/log/pakfire' finde ich Installationsprotokolle. Um 11:53:32 wurde wohl ClamAV aktualisiert.

Post Reply