Weird double squid process

From: Evelyne Pichler <Evelyne.Pichler@dont-contact.us>
Date: Tue, 14 Dec 1999 12:45:15 +0100

I am running squid 2.2stable 4 on Linux redhat
redhat-release-6.0-1
kernel 2.2.12-20

I logrotate every night at like 4 am, stop the squid, rotate, start the squid.

Somehow the squid tried to start the squid process twice. I include the
cache.log files. Maybe any of you have an explanation for that...

Thanks
Evelyn

Now this is how the cache log ends before logrotate:

1999/12/07 04:02:01| Preparing for shutdown after 427746 requests
1999/12/07 04:02:01| Waiting 30 seconds for active connections to finish
1999/12/07 04:02:01| FD 32 Closing HTTP connection
1999/12/07 04:02:11| Shutting down...
1999/12/07 04:02:11| FD 33 Closing ICP connection
1999/12/07 04:02:11| FD 34 Closing SNMP socket
1999/12/07 04:02:11| Closing unlinkd pipe on FD 10
1999/12/07 04:02:11| storeDirWriteCleanLogs: Starting...
1999/12/07 04:02:11| Finished. Wrote 13186 entries.
1999/12/07 04:02:11| Took 0 seconds (13186.0 entries/sec).
CPU Usage: 4193.360 seconds
Maximum Resident Size: 0 KB
Page faults with physical i/o: 27989
Memory usage for squid via mallinfo():
        total space in arena: 50562 KB
        Ordinary blocks: 44774 KB 6484 blks
        Small blocks: 0 KB 0 blks
        Holding blocks: 176 KB 1 blks
        Free Small blocks: 0 KB
        Free Ordinary blocks: 5788 KB
        Total in use: 44950 KB 89%
        Total free: 5788 KB 11%
1999/12/07 04:02:11| Squid Cache (Version 2.2.STABLE4): Exiting normally.
1999/12/07 04:02:11| Starting Squid Cache version 2.2.STABLE4 for
i686-pc-linux-gnu...
1999/12/07 04:02:11| Process ID 14735
1999/12/07 04:02:11| With 1024 file descriptors available
1999/12/07 04:02:11| helperOpenServers: Starting 5 'dnsserver' processes
1999/12/07 04:02:11| Unlinkd pipe opened on FD 10
1999/12/07 04:02:11| Swap maxSize 102400 KB, estimated 17066 objects
1999/12/07 04:02:11| Target number of buckets: 341
1999/12/07 04:02:11| Using 8192 Store buckets, replacement runs every 10 seconds
1999/12/07 04:02:11| Max Mem size: 32768 KB
1999/12/07 04:02:11| Max Swap size: 102400 KB
1999/12/07 04:02:11| Store logging disabled
1999/12/07 04:02:11| Rebuilding storage in Cache Dir #0 (CLEAN)
1999/12/07 04:02:11| Set Current Directory to /var/spool/squid
1999/12/07 04:02:11| Loaded Icons.
1999/12/07 04:02:11| Accepting HTTP connections on port 3128, FD 32.
1999/12/07 04:02:11| Accepting ICP messages on port 3130, FD 33.
1999/12/07 04:02:11| Accepting SNMP messages on port 3401, FD 34.
1999/12/07 04:02:11| Ready to serve requests.
1999/12/07 04:02:12| Configuring Parent dshgw1.dsh.at/3128/3130
1999/12/07 04:02:12| Done reading Cache Dir #0 swaplog (13186 entries)
1999/12/07 04:02:12| Finished rebuilding storage disk.
1999/12/07 04:02:12| 13186 Entries read from previous logfile.
1999/12/07 04:02:12| 0 Entries scanned from swap files.
1999/12/07 04:02:12| 0 Invalid entries.
1999/12/07 04:02:12| 0 With invalid flags.
1999/12/07 04:02:12| 13186 Objects loaded.
1999/12/07 04:02:12| 0 Objects expired.
1999/12/07 04:02:12| 0 Objects cancelled.
1999/12/07 04:02:12| 0 Duplicate URLs purged.
1999/12/07 04:02:12| 0 Swapfile clashes avoided.
1999/12/07 04:02:12| Took 1 seconds (13186.0 objects/sec).
1999/12/07 04:02:12| Beginning Validation Procedure
1999/12/07 04:02:13| Completed Validation Procedure
1999/12/07 04:02:13| Validated 13186 Entries
1999/12/07 04:02:13| store_swap_size = 93790k
1999/12/07 04:02:13| storeLateRelease: released 1 objects
1999/12/07 04:08:26| Preparing for shutdown after 25 requests
1999/12/07 04:08:26| Waiting 30 seconds for active connections to finish
1999/12/07 04:08:26| FD 32 Closing HTTP connection
1999/12/07 04:08:32| Shutting down...
1999/12/07 04:08:32| FD 33 Closing ICP connection
1999/12/07 04:08:32| FD 34 Closing SNMP socket
1999/12/07 04:08:32| Closing unlinkd pipe on FD 10
1999/12/07 04:08:32| storeDirWriteCleanLogs: Starting...
1999/12/07 04:08:32| Finished. Wrote 13186 entries.
1999/12/07 04:08:32| Took 0 seconds (13186.0 entries/sec).
CPU Usage: 0.690 seconds
Maximum Resident Size: 0 KB
Page faults with physical i/o: 516
Memory usage for squid via mallinfo():
        total space in arena: 3586 KB
        Ordinary blocks: 3543 KB 292 blks
        Small blocks: 0 KB 0 blks
        Holding blocks: 176 KB 1 blks
        Free Small blocks: 0 KB
        Free Ordinary blocks: 43 KB
        Total in use: 3719 KB 104%
        Total free: 43 KB 1%
1999/12/07 04:08:32| Squid Cache (Version 2.2.STABLE4): Exiting normally.

And this is how the next one starts after logrotate:

1999/12/07 04:08:32| Starting Squid Cache version 2.2.STABLE4 for
i686-pc-linux-gnu...
1999/12/07 04:08:32| Process ID 15163
1999/12/07 04:08:32| With 1024 file descriptors available
1999/12/07 04:08:32| helperOpenServers: Starting 5 'dnsserver' processes
1999/12/07 04:08:33| Starting Squid Cache version 2.2.STABLE4 for
i686-pc-linux-gnu...
1999/12/07 04:08:33| Process ID 15188
1999/12/07 04:08:33| With 1024 file descriptors available
1999/12/07 04:08:33| helperOpenServers: Starting 5 'dnsserver' processes
1999/12/07 04:08:32| Unlinkd pipe opened on FD 10
1999/12/07 04:08:32| Swap maxSize 102400 KB, estimated 17066 objects
1999/12/07 04:08:32| Target number of buckets: 341
1999/12/07 04:08:32| Using 8192 Store buckets, replacement runs every 10 seconds
1999/12/07 04:08:32| Max Mem size: 32768 KB
1999/12/07 04:08:32| Max Swap size: 102400 KB
1999/12/07 04:08:32| Store logging disabled
1999/12/07 04:08:32| Rebuilding storage in Cache Dir #0 (CLEAN)
1999/12/07 04:08:32| Set Current Directory to /var/spool/squid
1999/12/07 04:08:32| Loaded Icons.
1999/12/07 04:08:32| Accepting HTTP connections on port 3128, FD 32.
1999/12/07 04:08:32| Accepting ICP messages on port 3130, FD 33.
1999/12/07 04:08:32| Accepting SNMP messages on port 3401, FD 34.
1999/12/07 04:08:32| Ready to serve requests.
1999/12/07 04:08:33| Configuring Parent dshgw1.dsh.at/3128/3130
1999/12/07 04:08:33| Done reading Cache Dir #0 swaplog (13186 entries)
1999/12/07 04:08:33| Finished rebuilding storage disk.
1999/12/07 04:08:33| 13186 Entries read from previous logfile.
1999/12/07 04:08:33| 0 Entries scanned from swap files.
1999/12/07 04:08:33| 0 Invalid entries.
1999/12/07 04:08:33| 0 With invalid flags.
1999/12/07 04:08:33| 13186 Objects loaded.
1999/12/07 04:08:33| 0 Objects expired.
1999/12/07 04:08:33| 0 Objects cancelled.
1999/12/07 04:08:33| 0 Duplicate URLs purged.
1999/12/07 04:08:33| 0 Swapfile clashes avoided.
1999/12/07 04:08:33| Took 1 seconds (13186.0 objects/sec).
1999/12/07 04:08:33| Beginning Validation Procedure
1999/12/07 04:08:33| Completed Validation Procedure
1999/12/07 04:08:33| Validated 13186 Entries
1999/12/07 04:08:33| store_swap_size = 93790k
1999/12/07 04:08:33| Unlinkd pipe opened on FD 10
1999/12/07 04:08:33| Swap maxSize 102400 KB, estimated 17066 objects
1999/12/07 04:08:33| Target number of buckets: 341
1999/12/07 04:08:33| Using 8192 Store buckets, replacement runs every 10 seconds
1999/12/07 04:08:33| Max Mem size: 32768 KB
1999/12/07 04:08:33| Max Swap size: 102400 KB
1999/12/07 04:08:33| Store logging disabled
1999/12/07 04:08:33| Rebuilding storage in Cache Dir #0 (DIRTY)
1999/12/07 04:08:33| Set Current Directory to /var/spool/squid
1999/12/07 04:08:33| Loaded Icons.
1999/12/07 04:08:33| commBind: Cannot bind socket FD 32 to *:3128: (98) Address
already in use
FATAL: Cannot open HTTP Port
Received on Tue Dec 14 1999 - 04:58:30 MST

This archive was generated by hypermail pre-2.1.9 : Tue Dec 09 2003 - 16:49:53 MST