[Gelöst] Squid crasht nach Start - I don't handle this error well!

Gemeinsam ins Internet mit Firewall und Proxy.
Antworten
Arny80Hexa
Beiträge: 3
Registriert: 22.01.2018 13:31:10

[Gelöst] Squid crasht nach Start - I don't handle this error well!

Beitrag von Arny80Hexa » 22.01.2018 15:01:06

Ich versuche gerade auf einem frisch installierten Debian 9 einen Squid-Server zum laufen zu bekommen. Nach dem Restart dauert es einen Moment und dann ist der Dienst zwar "loaded" aber "inactive":
root@bm-intranet1:/var/log/squid# systemctl status squid.service

squid.service - LSB: Squid HTTP Proxy version 3.x
Loaded: loaded (/etc/init.d/squid; generated; vendor preset: enabled)
Active: inactive (dead) since Mon 2018-01-22 14:00:48 CET; 14min ago
Docs: man:systemd-sysv-generator(8)
Process: 11035 ExecStop=/etc/init.d/squid stop (code=exited, status=0/SUCCESS)
Process: 10959 ExecStart=/etc/init.d/squid start (code=exited, status=0/SUCCESS)
Main PID: 10999
In den Logs folgendes zu finden:
root@bm-intranet1:/var/log/squid# systemctl restart squid.service
root@bm-intranet1:/var/log/squid# journalctl -xe

Jan 22 14:00:10 bm-intranet1 systemd[1]: Starting LSB: Squid HTTP Proxy version 3.x...
-- Subject: Unit squid.service has begun start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
--
-- Unit squid.service has begun starting up.
Jan 22 14:00:10 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: will start 1 kids
Jan 22 14:00:10 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 10999 started
Jan 22 14:00:10 bm-intranet1 squid[10959]: Starting Squid HTTP Proxy: squid.
Jan 22 14:00:10 bm-intranet1 systemd[1]: squid.service: PID file /var/run/squid.pid not readable (yet?) after start: No such file or directory
Jan 22 14:00:10 bm-intranet1 systemd[1]: ^[[0;1;39msquid.service: Supervising process 10999 which is not our child. We'll most likely not notice when it exits.
Jan 22 14:00:10 bm-intranet1 systemd[1]: Started LSB: Squid HTTP Proxy version 3.x.
-- Subject: Unit squid.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
--
-- Unit squid.service has finished starting up.
--
-- The start-up result is done.
Jan 22 14:00:11 bm-intranet1 (squid-1)[10999]: I don't handle this error well!
Jan 22 14:00:11 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 10999 exited with status 1
Jan 22 14:00:14 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 11006 started
Jan 22 14:00:15 bm-intranet1 (squid-1)[11006]: I don't handle this error well!
Jan 22 14:00:15 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 11006 exited with status 1
Jan 22 14:00:18 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 11014 started
Jan 22 14:00:19 bm-intranet1 (squid-1)[11014]: I don't handle this error well!
Jan 22 14:00:19 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 11014 exited with status 1
Jan 22 14:00:22 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 11021 started
Jan 22 14:00:23 bm-intranet1 (squid-1)[11021]: I don't handle this error well!
Jan 22 14:00:23 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 11021 exited with status 1
Jan 22 14:00:26 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 11028 started
Jan 22 14:00:27 bm-intranet1 (squid-1)[11028]: I don't handle this error well!
Jan 22 14:00:27 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 11028 exited with status 1
Jan 22 14:00:27 bm-intranet1 squid[10997]: ^[[0;1;39mSquid Parent: (squid-1) process 11028 will not be restarted due to repeated, frequent failures
Jan 22 14:00:27 bm-intranet1 squid[10997]: Exiting due to repeated, frequent failures
Jan 22 14:00:48 bm-intranet1 squid[11035]: Stopping Squid HTTP Proxy: squid.
Jan 22 14:00:48 bm-intranet1 systemd[1]: ^[[0;1;39msquid.service: Killing process 10999 (n/a) with signal SIGKILL.
Jan 22 14:00:48 bm-intranet1 systemd[1]: ^[[0;1;39msquid.service: Killing process 10999 (n/a) with signal SIGKILL.
Und das squid cache.log
2018/01/22 14:18:17 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:17 kid1| Starting Squid Cache version 3.5.23 for x86_64-pc-linux-gnu...
2018/01/22 14:18:17 kid1| Service Name: squid
2018/01/22 14:18:17 kid1| Process ID 11104
2018/01/22 14:18:17 kid1| Process Roles: worker
2018/01/22 14:18:17 kid1| With 65535 file descriptors available
2018/01/22 14:18:17 kid1| Initializing IP Cache...
2018/01/22 14:18:17 kid1| DNS Socket created at [::], FD 6
2018/01/22 14:18:17 kid1| DNS Socket created at 0.0.0.0, FD 8
2018/01/22 14:18:17 kid1| Adding nameserver 10.254.1.253 from squid.conf
2018/01/22 14:18:17 kid1| Logfile: opening log daemon:/var/log/squid/access.log
2018/01/22 14:18:17 kid1| Logfile Daemon: opening log /var/log/squid/access.log
2018/01/22 14:18:17 kid1| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec
2018/01/22 14:18:17 kid1| Logfile: opening log daemon:/var/log/squid/store.log
2018/01/22 14:18:17 kid1| Logfile Daemon: opening log /var/log/squid/store.log
2018/01/22 14:18:17 kid1| Swap maxSize 16777216 + 262144 KB, estimated 1310720 objects
2018/01/22 14:18:17 kid1| Target number of buckets: 65536
2018/01/22 14:18:17 kid1| Using 65536 Store buckets
2018/01/22 14:18:17 kid1| Max Mem size: 262144 KB
2018/01/22 14:18:17 kid1| Max Swap size: 16777216 KB
2018/01/22 14:18:17 kid1| Rebuilding storage in /var/spool/squid (clean log)
2018/01/22 14:18:17 kid1| Using Least Load store dir selection
2018/01/22 14:18:17 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:17 kid1| Finished loading MIME types and icons.
2018/01/22 14:18:17 kid1| HTCP Disabled.
2018/01/22 14:18:17 kid1| Pinger socket opened on FD 17
2018/01/22 14:18:17 kid1| Squid plugin modules loaded: 0
2018/01/22 14:18:17 kid1| Adaptation support is off.
2018/01/22 14:18:17 kid1| Accepting HTTP Socket connections at local=[::]:8080 remote=[::] FD 15 flags=9
2018/01/22 14:18:17 kid1| Done reading /var/spool/squid swaplog (0 entries)
2018/01/22 14:18:17 kid1| Store rebuilding is 0.00% complete
2018/01/22 14:18:17 kid1| Finished rebuilding storage from disk.
2018/01/22 14:18:17 kid1| 0 Entries scanned
2018/01/22 14:18:17 kid1| 0 Invalid entries.
2018/01/22 14:18:17 kid1| 0 With invalid flags.
2018/01/22 14:18:17 kid1| 0 Objects loaded.
2018/01/22 14:18:17 kid1| 0 Objects expired.
2018/01/22 14:18:17 kid1| 0 Objects cancelled.
2018/01/22 14:18:17 kid1| 0 Duplicate URLs purged.
2018/01/22 14:18:17 kid1| 0 Swapfile clashes avoided.
2018/01/22 14:18:17 kid1| Took 0.01 seconds ( 0.00 objects/sec).
2018/01/22 14:18:17 kid1| Beginning Validation Procedure
2018/01/22 14:18:17 kid1| Completed Validation Procedure
2018/01/22 14:18:17 kid1| Validated 0 Entries
2018/01/22 14:18:17 kid1| store_swap_size = 0.00 KB
2018/01/22 14:18:17| pinger: Initialising ICMP pinger ...
2018/01/22 14:18:17| pinger: ICMP socket opened.
2018/01/22 14:18:17| pinger: ICMPv6 socket opened
2018/01/22 14:18:18 kid1| logfileHandleWrite: daemon:/var/log/squid/access.log: error writing ((32) Broken pipe)
2018/01/22 14:18:18 kid1| Closing HTTP port [::]:8080
2018/01/22 14:18:18 kid1| storeDirWriteCleanLogs: Starting...
2018/01/22 14:18:18 kid1| Finished. Wrote 0 entries.
2018/01/22 14:18:18 kid1| Took 0.00 seconds ( 0.00 entries/sec).
FATAL: I don't handle this error well!
Squid Cache (Version 3.5.23): Terminated abnormally.
CPU Usage: 0.024 seconds = 0.016 user + 0.008 sys
Maximum Resident Size: 108016 KB
Page faults with physical i/o: 0
2018/01/22 14:18:18 kid1| Closing Pinger socket on FD 17
2018/01/22 14:18:21 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:21 kid1| Starting Squid Cache version 3.5.23 for x86_64-pc-linux-gnu...
2018/01/22 14:18:21 kid1| Service Name: squid
2018/01/22 14:18:21 kid1| Process ID 11111
2018/01/22 14:18:21 kid1| Process Roles: worker
2018/01/22 14:18:21 kid1| With 65535 file descriptors available
2018/01/22 14:18:21 kid1| Initializing IP Cache...
2018/01/22 14:18:21 kid1| DNS Socket created at [::], FD 6
2018/01/22 14:18:21 kid1| DNS Socket created at 0.0.0.0, FD 8
2018/01/22 14:18:21 kid1| Adding nameserver 10.254.1.253 from squid.conf
2018/01/22 14:18:21 kid1| Logfile: opening log daemon:/var/log/squid/access.log
2018/01/22 14:18:21 kid1| Logfile Daemon: opening log /var/log/squid/access.log
2018/01/22 14:18:21 kid1| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec
2018/01/22 14:18:21 kid1| Logfile: opening log daemon:/var/log/squid/store.log
2018/01/22 14:18:21 kid1| Logfile Daemon: opening log /var/log/squid/store.log
2018/01/22 14:18:21 kid1| Swap maxSize 16777216 + 262144 KB, estimated 1310720 objects
2018/01/22 14:18:21 kid1| Target number of buckets: 65536
2018/01/22 14:18:21 kid1| Using 65536 Store buckets
2018/01/22 14:18:21 kid1| Max Mem size: 262144 KB
2018/01/22 14:18:21 kid1| Max Swap size: 16777216 KB
2018/01/22 14:18:21 kid1| Rebuilding storage in /var/spool/squid (clean log)
2018/01/22 14:18:21 kid1| Using Least Load store dir selection
2018/01/22 14:18:21 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:21 kid1| Finished loading MIME types and icons.
2018/01/22 14:18:21 kid1| HTCP Disabled.
2018/01/22 14:18:21 kid1| Pinger socket opened on FD 17
2018/01/22 14:18:21 kid1| Squid plugin modules loaded: 0
2018/01/22 14:18:21 kid1| Adaptation support is off.
2018/01/22 14:18:21 kid1| Accepting HTTP Socket connections at local=[::]:8080 remote=[::] FD 15 flags=9
2018/01/22 14:18:21 kid1| Done reading /var/spool/squid swaplog (0 entries)
2018/01/22 14:18:21 kid1| Store rebuilding is 0.00% complete
2018/01/22 14:18:21 kid1| Finished rebuilding storage from disk.
2018/01/22 14:18:21 kid1| 0 Entries scanned
2018/01/22 14:18:21 kid1| 0 Invalid entries.
2018/01/22 14:18:21 kid1| 0 With invalid flags.
2018/01/22 14:18:21 kid1| 0 Objects loaded.
2018/01/22 14:18:21 kid1| 0 Objects expired.
2018/01/22 14:18:21 kid1| 0 Objects cancelled.
2018/01/22 14:18:21 kid1| 0 Duplicate URLs purged.
2018/01/22 14:18:21 kid1| 0 Swapfile clashes avoided.
2018/01/22 14:18:21 kid1| Took 0.01 seconds ( 0.00 objects/sec).
2018/01/22 14:18:21 kid1| Beginning Validation Procedure
2018/01/22 14:18:21 kid1| Completed Validation Procedure
2018/01/22 14:18:21 kid1| Validated 0 Entries
2018/01/22 14:18:21 kid1| store_swap_size = 0.00 KB
2018/01/22 14:18:21| pinger: Initialising ICMP pinger ...
2018/01/22 14:18:21| pinger: ICMP socket opened.
2018/01/22 14:18:21| pinger: ICMPv6 socket opened
2018/01/22 14:18:22 kid1| logfileHandleWrite: daemon:/var/log/squid/access.log: error writing ((32) Broken pipe)
2018/01/22 14:18:22 kid1| Closing HTTP port [::]:8080
2018/01/22 14:18:22 kid1| storeDirWriteCleanLogs: Starting...
2018/01/22 14:18:22 kid1| Finished. Wrote 0 entries.
2018/01/22 14:18:22 kid1| Took 0.00 seconds ( 0.00 entries/sec).
FATAL: I don't handle this error well!
Squid Cache (Version 3.5.23): Terminated abnormally.
CPU Usage: 0.032 seconds = 0.020 user + 0.012 sys
Maximum Resident Size: 108784 KB
Page faults with physical i/o: 0
2018/01/22 14:18:22 kid1| Closing Pinger socket on FD 17
2018/01/22 14:18:25 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:25 kid1| Starting Squid Cache version 3.5.23 for x86_64-pc-linux-gnu...
2018/01/22 14:18:25 kid1| Service Name: squid
2018/01/22 14:18:25 kid1| Process ID 11118
2018/01/22 14:18:25 kid1| Process Roles: worker
2018/01/22 14:18:25 kid1| With 65535 file descriptors available
2018/01/22 14:18:25 kid1| Initializing IP Cache...
2018/01/22 14:18:25 kid1| DNS Socket created at [::], FD 6
2018/01/22 14:18:25 kid1| DNS Socket created at 0.0.0.0, FD 8
2018/01/22 14:18:25 kid1| Adding nameserver 10.254.1.253 from squid.conf
2018/01/22 14:18:25 kid1| Logfile: opening log daemon:/var/log/squid/access.log
2018/01/22 14:18:25 kid1| Logfile Daemon: opening log /var/log/squid/access.log
2018/01/22 14:18:25 kid1| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec
2018/01/22 14:18:25 kid1| Logfile: opening log daemon:/var/log/squid/store.log
2018/01/22 14:18:25 kid1| Logfile Daemon: opening log /var/log/squid/store.log
2018/01/22 14:18:25 kid1| Swap maxSize 16777216 + 262144 KB, estimated 1310720 objects
2018/01/22 14:18:25 kid1| Target number of buckets: 65536
2018/01/22 14:18:25 kid1| Using 65536 Store buckets
2018/01/22 14:18:25 kid1| Max Mem size: 262144 KB
2018/01/22 14:18:25 kid1| Max Swap size: 16777216 KB
2018/01/22 14:18:25 kid1| Rebuilding storage in /var/spool/squid (clean log)
2018/01/22 14:18:25 kid1| Using Least Load store dir selection
2018/01/22 14:18:25 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:25 kid1| Finished loading MIME types and icons.
2018/01/22 14:18:25 kid1| HTCP Disabled.
2018/01/22 14:18:25 kid1| Pinger socket opened on FD 17
2018/01/22 14:18:25 kid1| Squid plugin modules loaded: 0
2018/01/22 14:18:25 kid1| Adaptation support is off.
2018/01/22 14:18:25 kid1| Accepting HTTP Socket connections at local=[::]:8080 remote=[::] FD 15 flags=9
2018/01/22 14:18:25 kid1| Done reading /var/spool/squid swaplog (0 entries)
2018/01/22 14:18:25 kid1| Store rebuilding is 0.00% complete
2018/01/22 14:18:25 kid1| Finished rebuilding storage from disk.
2018/01/22 14:18:25 kid1| 0 Entries scanned
2018/01/22 14:18:25 kid1| 0 Invalid entries.
2018/01/22 14:18:25 kid1| 0 With invalid flags.
2018/01/22 14:18:25 kid1| 0 Objects loaded.
2018/01/22 14:18:25 kid1| 0 Objects expired.
2018/01/22 14:18:25 kid1| 0 Objects cancelled.
2018/01/22 14:18:25 kid1| 0 Duplicate URLs purged.
2018/01/22 14:18:25 kid1| 0 Swapfile clashes avoided.
2018/01/22 14:18:25 kid1| Took 0.01 seconds ( 0.00 objects/sec).
2018/01/22 14:18:25 kid1| Beginning Validation Procedure
2018/01/22 14:18:25 kid1| Completed Validation Procedure
2018/01/22 14:18:25 kid1| Validated 0 Entries
2018/01/22 14:18:25 kid1| store_swap_size = 0.00 KB
2018/01/22 14:18:25| pinger: Initialising ICMP pinger ...
2018/01/22 14:18:25| pinger: ICMP socket opened.
2018/01/22 14:18:25| pinger: ICMPv6 socket opened
2018/01/22 14:18:26 kid1| logfileHandleWrite: daemon:/var/log/squid/access.log: error writing ((32) Broken pipe)
2018/01/22 14:18:26 kid1| Closing HTTP port [::]:8080
2018/01/22 14:18:26 kid1| storeDirWriteCleanLogs: Starting...
2018/01/22 14:18:26 kid1| Finished. Wrote 0 entries.
2018/01/22 14:18:26 kid1| Took 0.00 seconds ( 0.00 entries/sec).
FATAL: I don't handle this error well!
Squid Cache (Version 3.5.23): Terminated abnormally.
CPU Usage: 0.028 seconds = 0.016 user + 0.012 sys
Maximum Resident Size: 108400 KB
Page faults with physical i/o: 0
2018/01/22 14:18:26 kid1| Closing Pinger socket on FD 17
2018/01/22 14:18:29 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:29 kid1| Starting Squid Cache version 3.5.23 for x86_64-pc-linux-gnu...
2018/01/22 14:18:29 kid1| Service Name: squid
2018/01/22 14:18:29 kid1| Process ID 11125
2018/01/22 14:18:29 kid1| Process Roles: worker
2018/01/22 14:18:29 kid1| With 65535 file descriptors available
2018/01/22 14:18:29 kid1| Initializing IP Cache...
2018/01/22 14:18:29 kid1| DNS Socket created at [::], FD 6
2018/01/22 14:18:29 kid1| DNS Socket created at 0.0.0.0, FD 8
2018/01/22 14:18:29 kid1| Adding nameserver 10.254.1.253 from squid.conf
2018/01/22 14:18:29 kid1| Logfile: opening log daemon:/var/log/squid/access.log
2018/01/22 14:18:29 kid1| Logfile Daemon: opening log /var/log/squid/access.log
2018/01/22 14:18:29 kid1| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec
2018/01/22 14:18:29 kid1| Logfile: opening log daemon:/var/log/squid/store.log
2018/01/22 14:18:29 kid1| Logfile Daemon: opening log /var/log/squid/store.log
2018/01/22 14:18:29 kid1| Swap maxSize 16777216 + 262144 KB, estimated 1310720 objects
2018/01/22 14:18:29 kid1| Target number of buckets: 65536
2018/01/22 14:18:29 kid1| Using 65536 Store buckets
2018/01/22 14:18:29 kid1| Max Mem size: 262144 KB
2018/01/22 14:18:29 kid1| Max Swap size: 16777216 KB
2018/01/22 14:18:29 kid1| Rebuilding storage in /var/spool/squid (clean log)
2018/01/22 14:18:29 kid1| Using Least Load store dir selection
2018/01/22 14:18:29 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:29 kid1| Finished loading MIME types and icons.
2018/01/22 14:18:29 kid1| HTCP Disabled.
2018/01/22 14:18:29 kid1| Pinger socket opened on FD 17
2018/01/22 14:18:29 kid1| Squid plugin modules loaded: 0
2018/01/22 14:18:29 kid1| Adaptation support is off.
2018/01/22 14:18:29 kid1| Accepting HTTP Socket connections at local=[::]:8080 remote=[::] FD 15 flags=9
2018/01/22 14:18:29 kid1| Done reading /var/spool/squid swaplog (0 entries)
2018/01/22 14:18:29 kid1| Store rebuilding is 0.00% complete
2018/01/22 14:18:29 kid1| Finished rebuilding storage from disk.
2018/01/22 14:18:29 kid1| 0 Entries scanned
2018/01/22 14:18:29 kid1| 0 Invalid entries.
2018/01/22 14:18:29 kid1| 0 With invalid flags.
2018/01/22 14:18:29 kid1| 0 Objects loaded.
2018/01/22 14:18:29 kid1| 0 Objects expired.
2018/01/22 14:18:29 kid1| 0 Objects cancelled.
2018/01/22 14:18:29 kid1| 0 Duplicate URLs purged.
2018/01/22 14:18:29 kid1| 0 Swapfile clashes avoided.
2018/01/22 14:18:29 kid1| Took 0.01 seconds ( 0.00 objects/sec).
2018/01/22 14:18:29 kid1| Beginning Validation Procedure
2018/01/22 14:18:29 kid1| Completed Validation Procedure
2018/01/22 14:18:29 kid1| Validated 0 Entries
2018/01/22 14:18:29 kid1| store_swap_size = 0.00 KB
2018/01/22 14:18:29| pinger: Initialising ICMP pinger ...
2018/01/22 14:18:29| pinger: ICMP socket opened.
2018/01/22 14:18:29| pinger: ICMPv6 socket opened
2018/01/22 14:18:29| Pinger exiting.
2018/01/22 14:18:30 kid1| logfileHandleWrite: daemon:/var/log/squid/access.log: error writing ((32) Broken pipe)
2018/01/22 14:18:30 kid1| Closing HTTP port [::]:8080
2018/01/22 14:18:30 kid1| storeDirWriteCleanLogs: Starting...
2018/01/22 14:18:30 kid1| Finished. Wrote 0 entries.
2018/01/22 14:18:30 kid1| Took 0.00 seconds ( 0.00 entries/sec).
FATAL: I don't handle this error well!
Squid Cache (Version 3.5.23): Terminated abnormally.
CPU Usage: 0.024 seconds = 0.016 user + 0.008 sys
Maximum Resident Size: 107888 KB
Page faults with physical i/o: 0
2018/01/22 14:18:30 kid1| Closing Pinger socket on FD 17
2018/01/22 14:18:33 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:33 kid1| Starting Squid Cache version 3.5.23 for x86_64-pc-linux-gnu...
2018/01/22 14:18:33 kid1| Service Name: squid
2018/01/22 14:18:33 kid1| Process ID 11133
2018/01/22 14:18:33 kid1| Process Roles: worker
2018/01/22 14:18:33 kid1| With 65535 file descriptors available
2018/01/22 14:18:33 kid1| Initializing IP Cache...
2018/01/22 14:18:33 kid1| DNS Socket created at [::], FD 6
2018/01/22 14:18:33 kid1| DNS Socket created at 0.0.0.0, FD 8
2018/01/22 14:18:33 kid1| Adding nameserver 10.254.1.253 from squid.conf
2018/01/22 14:18:33 kid1| Logfile: opening log daemon:/var/log/squid/access.log
2018/01/22 14:18:33 kid1| Logfile Daemon: opening log /var/log/squid/access.log
2018/01/22 14:18:33 kid1| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec
2018/01/22 14:18:33 kid1| Logfile: opening log daemon:/var/log/squid/store.log
2018/01/22 14:18:33 kid1| Logfile Daemon: opening log /var/log/squid/store.log
2018/01/22 14:18:33 kid1| Swap maxSize 16777216 + 262144 KB, estimated 1310720 objects
2018/01/22 14:18:33 kid1| Target number of buckets: 65536
2018/01/22 14:18:33 kid1| Using 65536 Store buckets
2018/01/22 14:18:33 kid1| Max Mem size: 262144 KB
2018/01/22 14:18:33 kid1| Max Swap size: 16777216 KB
2018/01/22 14:18:33 kid1| Rebuilding storage in /var/spool/squid (clean log)
2018/01/22 14:18:33 kid1| Using Least Load store dir selection
2018/01/22 14:18:33 kid1| Set Current Directory to /var/spool/squid
2018/01/22 14:18:33 kid1| Finished loading MIME types and icons.
2018/01/22 14:18:33 kid1| HTCP Disabled.
2018/01/22 14:18:33 kid1| Pinger socket opened on FD 17
2018/01/22 14:18:33 kid1| Squid plugin modules loaded: 0
2018/01/22 14:18:33 kid1| Adaptation support is off.
2018/01/22 14:18:33 kid1| Accepting HTTP Socket connections at local=[::]:8080 remote=[::] FD 15 flags=9
2018/01/22 14:18:33 kid1| Done reading /var/spool/squid swaplog (0 entries)
2018/01/22 14:18:33 kid1| Store rebuilding is 0.00% complete
2018/01/22 14:18:33 kid1| Finished rebuilding storage from disk.
2018/01/22 14:18:33 kid1| 0 Entries scanned
2018/01/22 14:18:33 kid1| 0 Invalid entries.
2018/01/22 14:18:33 kid1| 0 With invalid flags.
2018/01/22 14:18:33 kid1| 0 Objects loaded.
2018/01/22 14:18:33 kid1| 0 Objects expired.
2018/01/22 14:18:33 kid1| 0 Objects cancelled.
2018/01/22 14:18:33 kid1| 0 Duplicate URLs purged.
2018/01/22 14:18:33 kid1| 0 Swapfile clashes avoided.
2018/01/22 14:18:33 kid1| Took 0.01 seconds ( 0.00 objects/sec).
2018/01/22 14:18:33 kid1| Beginning Validation Procedure
2018/01/22 14:18:33 kid1| Completed Validation Procedure
2018/01/22 14:18:33 kid1| Validated 0 Entries
2018/01/22 14:18:33 kid1| store_swap_size = 0.00 KB
2018/01/22 14:18:33| pinger: Initialising ICMP pinger ...
2018/01/22 14:18:33| pinger: ICMP socket opened.
2018/01/22 14:18:33| pinger: ICMPv6 socket opened
2018/01/22 14:18:33| Pinger exiting.
2018/01/22 14:18:34 kid1| logfileHandleWrite: daemon:/var/log/squid/access.log: error writing ((32) Broken pipe)
2018/01/22 14:18:34 kid1| Closing HTTP port [::]:8080
2018/01/22 14:18:34 kid1| storeDirWriteCleanLogs: Starting...
2018/01/22 14:18:34 kid1| Finished. Wrote 0 entries.
2018/01/22 14:18:34 kid1| Took 0.00 seconds ( 0.00 entries/sec).
FATAL: I don't handle this error well!
Squid Cache (Version 3.5.23): Terminated abnormally.
CPU Usage: 0.024 seconds = 0.020 user + 0.004 sys
Maximum Resident Size: 108208 KB
Page faults with physical i/o: 0
2018/01/22 14:18:34 kid1| Closing Pinger socket on FD 17
2018/01/22 14:18:43| Pinger exiting.
2018/01/22 14:18:43| Pinger exiting.
2018/01/22 14:18:53| Pinger exiting.
Hier noch der Auszug aus meiner Squid.conf (ich hab die ACL's und http_access mal weg gelassen um das ganze ein wenig zu kürzen):
### Squid basic Configuration
# Service
http_port 8080
dns_nameservers 10.254.1.253
dns_retransmit_interval 2 seconds

#Cache
cache_effective_user proxy
cache_mem 256 MB
cache_dir aufs /var/spool/squid 16384 32 256
cache_swap_low 90
cache_swap_high 95
# minimum_object_size 2 KB # Default: no limit
# maximum_object_size 4 MB
# maximum_object_size_in_memory 512 KB

# Log
logfile_daemon /var/log/squid/daemon.log
#access_log daemon:/var/log/squid/access.log squid #default
cache_log /var/log/squid/cache.log
cache_store_log daemon:/var/log/squid/store.log
# logfile_rotate 0 # Zero, because squid use external Linux Logrotation Methods

# chrdir / chroot
coredump_dir /var/spool/squid

# Athentication
ftp_user yyy@xxx.de

auth_param basic children 5 startup=5 idle=1
auth_param basic realm Squid proxy-caching web server
auth_param basic credentialsttl 2 hours





### Refresh Pattern
refresh_pattern ^ftp: 1440 20% 10080
refresh_pattern ^gopher: 1440 0% 1440
refresh_pattern -i (/cgi-bin/|\?) 0 0% 0
refresh_pattern . 0 20% 4320
Da hier die ein oder andere Fehlermeldung auf fehlerhafte Zugriffsrechte schließen lässt, hier eine entsprechende Auflistung:
##### Anmerkung: ich hatte hier schon mal die Rechte gelockert um zu schauen ob es daran liegt, hat aber nichts geholfen

root@bm-intranet1:/var/log/squid# ll /var/log/squid/
total 432K
drwxr-xr-x 2 proxy proxy 4.0K Jan 22 13:29 .
drwxr-xr-x 6 root root 4.0K Jan 22 06:25 ..
-rwxrwxr-x 1 proxy proxy 0 Jan 22 11:49 access.log
-rwxrwxr-x 1 proxy proxy 0 Jan 19 09:32 access.log.1
-rwxrwxr-x 1 proxy proxy 291K Jan 22 14:18 cache.log
-rwxrwxr-x 1 proxy proxy 124K Jan 19 12:40 cache.log.1
-rwxrwxr-x 1 proxy proxy 0 Jan 22 10:36 daemon.log
-rwxrwxr-x 1 proxy proxy 0 Jan 19 12:09 daemon.log.1
-rwxrwxr-x 1 proxy proxy 0 Jan 19 10:12 netdb.state
-rwxrwxr-x 1 proxy proxy 0 Jan 19 12:07 store.log.1
root@bm-intranet1:/var/log/squid# ll /var/spool/squid/
total 140K
drwxr-xr-x 34 proxy proxy 4.0K Jan 22 14:18 .
drwxr-xr-x 5 root root 4.0K Jan 19 09:32 ..
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 00
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 01
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 02
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 03
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 04
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 05
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 06
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 07
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 08
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 09
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 0A
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 0B
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 0C
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 0D
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 0E
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 0F
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 10
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 11
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 12
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 13
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 14
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 15
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 16
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 17
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 18
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 19
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 1A
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 1B
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 1C
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 1D
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 1E
drwxr-x--- 258 proxy proxy 4.0K Jan 19 12:07 1F
-rw-r----- 1 proxy proxy 72 Jan 22 14:18 swap.state
-rw-r----- 1 proxy proxy 0 Jan 22 14:18 swap.state.last-clean
Ich suche nun schon einen Arbeitstag das Netz ab um zu einer Lösung zu kommen. Total unbergreiflich ist, dass beim Start in der cache.log weiter oben steht dass er die access.log öffnet und weiter unten kommt dann logfileHandleWrite: daemon:/var/log/squid/access.log: error writing ((32) Broken pipe).

Ich vermute auch, dass der Dienst wegen dieser Meldung abstürzt, aber sicher bin ich mir an der Stelle nicht. Die Meldung "FATAL: I don't handle this error well!" ist jedenfalls für Linux-Verhältnisse ungewöhnlich wenig aussagekräftig. Auch die Meldung "Parent: (squid-1) process 11125 exited with status 1" brachte in der Googlesuche leider keine verwertbaren Hinweise. So stehe ich mittlerweile ganz schön auf dem Schlauch und schreibe in einer letzten verzweifelten Tat in dieses Forum. Hat jemand eine Idee dazu? Vielen Dank schon mal!
Zuletzt geändert von Arny80Hexa am 23.01.2018 14:58:58, insgesamt 1-mal geändert.

MSfree
Beiträge: 3407
Registriert: 25.09.2007 19:59:30

Re: Squid crasht nach Start - I don't handle this error well!

Beitrag von MSfree » 22.01.2018 15:37:34

Du kannst die squid Konfiguration auf der Kommandozeile prüfen lassen:

Was gibt

Code: Alles auswählen

squid -k parse
aus?

Arny80Hexa
Beiträge: 3
Registriert: 22.01.2018 13:31:10

Re: Squid crasht nach Start - I don't handle this error well!

Beitrag von Arny80Hexa » 22.01.2018 15:54:22

Sieht für mich alles ok aus...
root@bm-intranet1:/var/log/squid# squid -k parse
2018/01/22 15:51:49| Startup: Initializing Authentication Schemes ...
2018/01/22 15:51:49| Startup: Initialized Authentication Scheme 'basic'
2018/01/22 15:51:49| Startup: Initialized Authentication Scheme 'digest'
2018/01/22 15:51:49| Startup: Initialized Authentication Scheme 'negotiate'
2018/01/22 15:51:49| Startup: Initialized Authentication Scheme 'ntlm'
2018/01/22 15:51:49| Startup: Initialized Authentication.
2018/01/22 15:51:49| Processing Configuration File: /etc/squid/squid.conf (depth 0)
2018/01/22 15:51:49| Processing: http_port 8080
2018/01/22 15:51:49| Processing: dns_nameservers 10.254.1.253
2018/01/22 15:51:49| Processing: dns_retransmit_interval 2 seconds
2018/01/22 15:51:49| Processing: cache_effective_user proxy
2018/01/22 15:51:49| Processing: cache_mem 256 MB
2018/01/22 15:51:49| Processing: cache_dir aufs /var/spool/squid 16384 32 256
2018/01/22 15:51:49| Processing: cache_swap_low 90
2018/01/22 15:51:49| Processing: cache_swap_high 95
2018/01/22 15:51:49| Processing: logfile_daemon /var/log/squid/daemon.log
2018/01/22 15:51:49| Processing: cache_log /var/log/squid/cache.log
2018/01/22 15:51:49| Processing: cache_store_log daemon:/var/log/squid/store.log
2018/01/22 15:51:49| Processing: coredump_dir /var/spool/squid
2018/01/22 15:51:49| Processing: ftp_user Squid-dvz@kultus-mv.de
2018/01/22 15:51:49| Processing: auth_param basic children 5 startup=5 idle=1
2018/01/22 15:51:49| Processing: auth_param basic realm Squid proxy-caching web server
2018/01/22 15:51:49| Processing: auth_param basic credentialsttl 2 hours
2018/01/22 15:51:49| Processing: refresh_pattern ^ftp: 1440 20% 10080
2018/01/22 15:51:49| Processing: refresh_pattern ^gopher: 1440 0% 1440
2018/01/22 15:51:49| Processing: refresh_pattern -i (/cgi-bin/|\?) 0 0% 0
2018/01/22 15:51:49| Processing: refresh_pattern . 0 20% 4320
2018/01/22 15:51:49| Processing: acl SSL_ports port 443 563
2018/01/22 15:51:49| Processing: acl Safe_ports port 80 # http
2018/01/22 15:51:49| Processing: acl Safe_ports port 21 # ftp
2018/01/22 15:51:49| Processing: acl Safe_ports port 443 563 # https, snews
2018/01/22 15:51:49| Processing: acl Safe_ports port 70 # gopher
2018/01/22 15:51:49| Processing: acl Safe_ports port 210 # wais
2018/01/22 15:51:49| Processing: acl Safe_ports port 1025-65535 # unregistered ports
2018/01/22 15:51:49| Processing: acl Safe_ports port 280 # http-mgmt
2018/01/22 15:51:49| Processing: acl Safe_ports port 488 # gss-http
2018/01/22 15:51:49| Processing: acl Safe_ports port 591 # filemaker
2018/01/22 15:51:49| Processing: acl Safe_ports port 777 # multiling http
2018/01/22 15:51:49| Processing: acl CONNECT method CONNECT
2018/01/22 15:51:49| Processing: acl sasn src 192.168.120.0/24
[...]
2018/01/22 15:51:49| Processing: http_access deny !Safe_ports # Deny requests to certain unsafe ports
2018/01/22 15:51:49| Processing: http_access deny all
2018/01/22 15:51:49| Processing: http_access deny to_localhost
2018/01/22 15:51:49| Processing: http_reply_access allow all
2018/01/22 15:51:49| Processing: icp_access allow all
2018/01/22 15:51:49| Processing: http_access allow sasn
[...]

Arny80Hexa
Beiträge: 3
Registriert: 22.01.2018 13:31:10

Re: [Gelöst] Squid crasht nach Start - I don't handle this error well!

Beitrag von Arny80Hexa » 23.01.2018 15:01:45

Am Ende war es mal wieder die eigene Dummheit. :facepalm:
# TAG: logfile_daemon
# Specify the path to the logfile-writing daemon. This daemon is
# used to write the access and store logs, if configured.
#
# Squid sends a number of commands to the log daemon:
# L<data>\n - logfile data
# R\n - rotate file
# T\n - truncate file
# O\n - reopen file
# F\n - flush file
# r<n>\n - set rotate count to <n>
# b<n>\n - 1 = buffer output, 0 = don't buffer output
#
# No responses is expected.
#Default:
# logfile_daemon /usr/lib/squid/log_file_daemon
logfile_daemon hat also nichts damit zu tun, wo ein Logfile hin geschriben werden soll, als vielmehr damit, wo der daemon liegt, der das schreiben der Logfiles übernimmt. Wer lesen kann ist klar im Vorteil. :hail:

Antworten