LinuxQuestions.org

LinuxQuestions.org (/questions/)
-   Linux - Server (https://www.linuxquestions.org/questions/linux-server-73/)
-   -   Apache randomly crashes (https://www.linuxquestions.org/questions/linux-server-73/apache-randomly-crashes-864120/)

newuser455 02-21-2011 02:55 PM

Apache randomly crashes
 
This has been happening for a while now and I can't seem to isolate the issue. Apache will run fine for anywhere from a few days to a few weeks but then all of a sudden it will "crash". I put that in quotes because the process is not completely crashing. Basically when this happens, Apache just refuses to accept any connections and cannot be restarted using the init script (it simply fails to stop then fails to start and gives an error about the address being in use). Every time this happens, I just do a "killall httpd", then start the process with the init script again and everything works perfectly. Of course having this happen is quite a nuisance.

Does anyone know what could be going on here? I have checked the error log and it only contains notices, no errors.

I'd appreciate any help. Thanks

anomie 02-21-2011 03:01 PM

More info needed to troubleshoot. You can make Apache log better chatter with the directive:
LogLevel info

After you've added that to your config:
Code:

# apachectl -k graceful
Next time Apache runs into problems, check your error logs again.

newuser455 02-21-2011 03:05 PM

Ok I did that. But in the mean time, is it possible that it is reaching the max connections or would that have shown up in my error log? I had the log level set to warn.

anomie 02-21-2011 03:19 PM

Sure - but a number of things are possible. Let's see what Apache himself tells us.

newuser455 03-08-2011 01:59 PM

I can't seem to get any info from the error logs. My error logs for the past 8+ days are non existent and before that, I do not see any useful information. Here is the message I get when I try to restart apache after a "crash".

[root@srv1 httpd]# service httpd restart
Stopping httpd: [FAILED]
Starting httpd: (98)Address already in use: make_sock: could not bind to address [::]:80
(98)Address already in use: make_sock: could not bind to address 0.0.0.0:80
no listening sockets available, shutting down
Unable to open logs
[FAILED]
[root@srv1 httpd]#

jcalzare 03-08-2011 02:24 PM

It looks like something else is listening on port 80, so apache can't start. What's running on 80?

You might want to get a look at netstat when apache is 'crashed'. Do something like:

netstat -an | grep :80

You can add a wc -l to the end to see how many connections there are. If it's around your max clients configured in apache, that might be the issue.

siranjeevi 03-08-2011 03:48 PM

Hi,



Quote:

[root@srv1 httpd]# service httpd restart
Stopping httpd: [FAILED]
Starting httpd: (98)Address already in use: make_sock: could not bind to address [::]:80
(98)Address already in use: make_sock: could not bind to address 0.0.0.0:80
no listening sockets available, shutting down
Unable to open logs
[FAILED]
[root@srv1 httpd]#
May be your apache didn't stop properly and it makes you to kill all the httpd processes to restart it. I had the same issue with my client's server, and the reason for that was the server reached maximum clients and got hanged. You can check them with your error logs, while analyzing I found that developer made a mistake while developing a ffmpeg script and that ffmpeg process forms a loop and multiplies the number of httpd requests to the server which completely eats up the number of connections.

newuser455 03-08-2011 04:48 PM

Quote:

Originally Posted by jcalzare (Post 4283168)
It looks like something else is listening on port 80, so apache can't start. What's running on 80?

Doesn't look like anything else is running on that port. If I was reaching the max connections, it would seem that would show up in the error log.

I suppose it's possible that a module is causing this. How would I go about finding the module that was causing this?

newuser455 08-26-2011 12:18 PM

I'm still having this issue. I have tried everything I can think of, including changing the apache LogLevel to debug. Even in debug, there is nothing in the error log or system messages log around the time apache "crashes".

Does anyone have any ideas?

tzakrajs 09-01-2011 06:24 AM

Does it still say that it cannot bind to port 80?

Could you give us the out put of the following command:
netstat -pant|grep ":80 "

newuser455 12-04-2011 05:41 PM

Here is my apache error log with debug turned on. Keep in mind that I have no idea if these messages are from around the time of the crash because I do not know when it crashed.

Code:

[Sun Nov 27 04:02:14 2011] [notice] Digest: generating secret for digest authentication ...
[Sun Nov 27 04:02:14 2011] [notice] Digest: done
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain1.com
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain2.com
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain3.com
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain4.com
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain5.com
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain6.com
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain7.com
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain8.com
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain9.com
[Sun Nov 27 04:02:14 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8305470 rmm=0x83054a0 for VHOST: domain10.com
[Sun Nov 27 04:02:14 2011] [info] APR LDAP: Built with OpenLDAP LDAP SDK
[Sun Nov 27 04:02:14 2011] [info] LDAP: SSL support available
[Sun Nov 27 04:02:14 2011] [info] mod_fcgid: Process manager 2357 started
[Sun Nov 27 04:02:14 2011] [notice] mod_python: Creating 4 session mutexes based on 256 max processes and 0 max threads.
[Sun Nov 27 04:02:14 2011] [info] Init: Seeding PRNG with 256 bytes of entropy
[Sun Nov 27 04:02:15 2011] [info] Init: Generating temporary RSA private keys (512/1024 bits)
[Sun Nov 27 04:02:15 2011] [info] Init: Generating temporary DH parameters (512/1024 bits)
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(374): shmcb_init allocated 512000 bytes of shared memory
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(554): entered shmcb_init_memory()
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(576): for 512000 bytes, recommending 4266 indexes
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(619): shmcb_init_memory choices follow
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(621): division_mask = 0x1F
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(623): division_offset = 64
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(625): division_size = 15998
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(627): queue_size = 1604
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(629): index_num = 133
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(631): index_offset = 8
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(633): index_size = 12
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(635): cache_data_offset = 8
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(637): cache_data_size = 14386
[Sun Nov 27 04:02:15 2011] [debug] ssl_scache_shmcb.c(650): leaving shmcb_init_memory()
[Sun Nov 27 04:02:15 2011] [info] Shared memory session cache initialised
[Sun Nov 27 04:02:15 2011] [info] Init: Initializing (virtual) servers for SSL
[Sun Nov 27 04:02:15 2011] [info] Server: Apache/2.2.3, Interface: mod_ssl/2.2.3, Library: OpenSSL/0.9.8e-fips-rhel5
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2367 for worker proxy:reverse
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2367 for (*)
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2368 for worker proxy:reverse
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2368 for (*)
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2369 for worker proxy:reverse
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2369 for (*)
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2370 for worker proxy:reverse
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2370 for (*)
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2373 for worker proxy:reverse
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:15 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2373 for (*)
[Sun Nov 27 04:02:15 2011] [notice] Apache/2.2.3 (CentOS) configured -- resuming normal operations
[Sun Nov 27 04:02:15 2011] [info] Server built: Sep  7 2011 21:44:18
[Sun Nov 27 04:02:15 2011] [debug] prefork.c(996): AcceptMutex: sysvsem (default: sysvsem)
[Sun Nov 27 04:02:15 2011] [notice] Graceful restart requested, doing restart
[Sun Nov 27 04:02:16 2011] [info] mod_fcgid: Process manager 2357 stopped
[Sun Nov 27 04:02:17 2011] [notice] Digest: generating secret for digest authentication ...
[Sun Nov 27 04:02:17 2011] [notice] Digest: done
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain1.com
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain2.com
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain3.com
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain4.com
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain5.com
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain6.com
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain7.com
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain8.com
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain9.com
[Sun Nov 27 04:02:17 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8313a20 rmm=0x8313a50 for VHOST: domain10.com
[Sun Nov 27 04:02:17 2011] [info] APR LDAP: Built with OpenLDAP LDAP SDK
[Sun Nov 27 04:02:17 2011] [info] LDAP: SSL support available
[Sun Nov 27 04:02:17 2011] [info] mod_fcgid: Process manager 2414 started
[Sun Nov 27 04:02:17 2011] [notice] mod_python: Creating 4 session mutexes based on 256 max processes and 0 max threads.
[Sun Nov 27 04:02:17 2011] [info] Init: Seeding PRNG with 256 bytes of entropy
[Sun Nov 27 04:02:17 2011] [info] Init: Generating temporary RSA private keys (512/1024 bits)
[Sun Nov 27 04:02:17 2011] [info] Init: Generating temporary DH parameters (512/1024 bits)
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(374): shmcb_init allocated 512000 bytes of shared memory
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(554): entered shmcb_init_memory()
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(576): for 512000 bytes, recommending 4266 indexes
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(619): shmcb_init_memory choices follow
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(621): division_mask = 0x1F
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(623): division_offset = 64
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(625): division_size = 15998
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(627): queue_size = 1604
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(629): index_num = 133
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(631): index_offset = 8
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(633): index_size = 12
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(635): cache_data_offset = 8
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(637): cache_data_size = 14386
[Sun Nov 27 04:02:17 2011] [debug] ssl_scache_shmcb.c(650): leaving shmcb_init_memory()
[Sun Nov 27 04:02:17 2011] [info] Shared memory session cache initialised
[Sun Nov 27 04:02:17 2011] [info] Init: Initializing (virtual) servers for SSL
[Sun Nov 27 04:02:17 2011] [info] Server: Apache/2.2.3, Interface: mod_ssl/2.2.3, Library: OpenSSL/0.9.8e-fips-rhel5
[Sun Nov 27 04:02:17 2011] [notice] Apache/2.2.3 (CentOS) configured -- resuming normal operations
[Sun Nov 27 04:02:17 2011] [info] Server built: Sep  7 2011 21:44:18
[Sun Nov 27 04:02:17 2011] [debug] prefork.c(996): AcceptMutex: sysvsem (default: sysvsem)
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2430 for worker proxy:reverse
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2430 for (*)
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2431 for worker proxy:reverse
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2431 for (*)
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2432 for worker proxy:reverse
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2432 for (*)
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2433 for worker proxy:reverse
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2433 for (*)
[Sun Nov 27 04:02:17 2011] [notice] Graceful restart requested, doing restart
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2434 for worker proxy:reverse
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:17 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2434 for (*)
[Sun Nov 27 04:02:19 2011] [info] mod_fcgid: Process manager 2414 stopped
[Sun Nov 27 04:02:19 2011] [notice] Digest: generating secret for digest authentication ...
[Sun Nov 27 04:02:19 2011] [notice] Digest: done
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain1.com
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain2.com
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain3.com
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain4.com
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain5.com
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain6.com
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain7.com
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain8.com
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain9.com
[Sun Nov 27 04:02:19 2011] [debug] util_ldap.c(2052): LDAP merging Shared Cache conf: shm=0x8328d00 rmm=0x8328d30 for VHOST: domain10.com
[Sun Nov 27 04:02:19 2011] [info] APR LDAP: Built with OpenLDAP LDAP SDK
[Sun Nov 27 04:02:19 2011] [info] LDAP: SSL support available
[Sun Nov 27 04:02:19 2011] [info] mod_fcgid: Process manager 2460 started
[Sun Nov 27 04:02:19 2011] [notice] mod_python: Creating 4 session mutexes based on 256 max processes and 0 max threads.
[Sun Nov 27 04:02:19 2011] [info] Init: Seeding PRNG with 256 bytes of entropy
[Sun Nov 27 04:02:19 2011] [info] Init: Generating temporary RSA private keys (512/1024 bits)
[Sun Nov 27 04:02:19 2011] [info] Init: Generating temporary DH parameters (512/1024 bits)
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(374): shmcb_init allocated 512000 bytes of shared memory
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(554): entered shmcb_init_memory()
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(576): for 512000 bytes, recommending 4266 indexes
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(619): shmcb_init_memory choices follow
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(621): division_mask = 0x1F
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(623): division_offset = 64
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(625): division_size = 15998
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(627): queue_size = 1604
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(629): index_num = 133
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(631): index_offset = 8
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(633): index_size = 12
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(635): cache_data_offset = 8
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(637): cache_data_size = 14386
[Sun Nov 27 04:02:19 2011] [debug] ssl_scache_shmcb.c(650): leaving shmcb_init_memory()
[Sun Nov 27 04:02:19 2011] [info] Shared memory session cache initialised
[Sun Nov 27 04:02:19 2011] [info] Init: Initializing (virtual) servers for SSL
[Sun Nov 27 04:02:19 2011] [info] Server: Apache/2.2.3, Interface: mod_ssl/2.2.3, Library: OpenSSL/0.9.8e-fips-rhel5
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2462 for worker proxy:reverse
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2462 for (*)
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2463 for worker proxy:reverse
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2463 for (*)
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2464 for worker proxy:reverse
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2464 for (*)
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2465 for worker proxy:reverse
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2465 for (*)
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1817): proxy: grabbed scoreboard slot 0 in child 2466 for worker proxy:reverse
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1836): proxy: worker proxy:reverse already initialized
[Sun Nov 27 04:02:19 2011] [debug] proxy_util.c(1930): proxy: initialized single connection worker 0 in child 2466 for (*)
[Sun Nov 27 04:02:19 2011] [notice] Apache/2.2.3 (CentOS) configured -- resuming normal operations
[Sun Nov 27 04:02:19 2011] [info] Server built: Sep  7 2011 21:44:18
[Sun Nov 27 04:02:19 2011] [debug] prefork.c(996): AcceptMutex: sysvsem (default: sysvsem)

Here is the output of that netstat command while apache is crashed

Code:

[root@srv1 ~]# netstat -pant|grep ":80 "
tcp        0      0 myip:80            ip1:23710        SYN_RECV    -
tcp        0      0 myip:80            ip2:30374          SYN_RECV    -
tcp        0      0 myip:80            ip1:23657        SYN_RECV    -
tcp        0      0 myip:80            ip3:33671        SYN_RECV    -
tcp        0      0 myip:80            ip1:23671        SYN_RECV    -
tcp        0      0 :::80                      :::*                        LISTEN      2460/httpd
[root@srv1 ~]#


timetraveler 12-04-2011 05:51 PM

Crashed is not the same as "no longer accepting connections"
Try running strace -p 2460

you might also try lsof |grep "^httpd"

newuser455 12-04-2011 05:59 PM

Quote:

Originally Posted by timetraveler (Post 4542003)
Crashed is not the same as "no longer accepting connections"
Try running strace -p 2460

you might also try lsof |grep "^httpd"

But also consider that it is not stopping properly with the init script after a "crash"

Here is some output from strace (not sure what this does, but it seemed to be never ending so these are the last few lines of output if that helps at all). From what I could tell, it was almost the same thing repeated over and over again.

Code:

poll([{fd=33, events=POLLIN}], 1, 3000) = 1 ([{fd=33, revents=POLLHUP}])
read(33, "", 12828)                    = 0
gettimeofday({1323042821, 955730}, NULL) = 0
gettimeofday({1323042821, 955798}, NULL) = 0
gettimeofday({1323042821, 955867}, NULL) = 0
gettimeofday({1323042821, 955935}, NULL) = 0
poll([{fd=33, events=POLLIN}], 1, 3000) = 1 ([{fd=33, revents=POLLHUP}])
read(33, "", 12828)                    = 0
gettimeofday({1323042821, 956172}, NULL) = 0
gettimeofday({1323042821, 956241}, NULL) = 0
gettimeofday({1323042821, 956310}, NULL) = 0
gettimeofday({1323042821, 956382}, NULL) = 0
poll([{fd=33, events=POLLIN}], 1, 3000) = 1 ([{fd=33, revents=POLLHUP}])
read(33, "", 12828)                    = 0
gettimeofday({1323042821, 956614}, NULL) = 0
gettimeofday({1323042821, 956682}, NULL) = 0
gettimeofday({1323042821, 956752}, NULL) = 0
gettimeofday({1323042821, 956819}, NULL) = 0
poll([{fd=33, events=POLLIN}], 1, 3000) = 1 ([{fd=33, revents=POLLHUP}])
read(33, "", 12828)                    = 0
gettimeofday(9}, NULL) = 0
Process 2460 detached


As for lsof:

Code:

httpd      2460                  apache  cwd      DIR      253,0    4096          2 /
httpd      2460                  apache  rtd      DIR      253,0    4096          2 /
httpd      2460                  apache  txt      REG      253,0  315284    1318267 /usr/sbin/httpd
httpd      2460                  apache  mem      REG      253,0  129208    1343646 /lib/libpcre.so.0.0.1
httpd      2460                  apache  mem      REG      253,0  133184    1343631 /lib/libexpat.so.0.5.0
httpd      2460                  apache  mem      REG      253,0    20668    1343562 /lib/libdl-2.5.so
httpd      2460                  apache  mem      REG      253,0  1693812    1343561 /lib/libc-2.5.so
httpd      2460                  apache  mem      REG      253,0  245376    1343611 /lib/libsepol.so.1
httpd      2460                  apache  mem      REG      253,0    7812    1343613 /lib/libcom_err.so.2.1
httpd      2460                  apache  mem      REG      253,0    9628    1475859 /usr/lib/httpd/modules/mod_auth_basic.so
httpd      2460                  apache  mem      REG      253,0    5500    1475869 /usr/lib/httpd/modules/mod_authn_anon.so
httpd      2460                  apache  mem      REG      253,0    5500    1475873 /usr/lib/httpd/modules/mod_authn_dbm.so
httpd      2460                  apache  mem      REG      253,0    5500    1476085 /usr/lib/httpd/modules/mod_authz_user.so
httpd      2460                  apache  mem      REG      253,0    5472    1475883 /usr/lib/httpd/modules/mod_authz_default.so
httpd      2460                  apache  mem      REG      253,0    5536    1480297 /usr/lib/httpd/modules/mod_logio.so
httpd      2460                  apache  mem      REG      253,0    5496    1479398 /usr/lib/httpd/modules/mod_env.so
httpd      2460                  apache  mem      REG      253,0  1011760    1343524 /lib/libdb-4.3.so
httpd      2460                  apache  mem      REG      253,0  190712    1319127 /usr/lib/libgssapi_krb5.so.2.2
httpd      2460                  apache  mem      REG      253,0  137908    1343625 /lib/libpthread-2.5.so
httpd      2460                  apache  mem      REG      253,0  293428    1343619 /lib/libssl.so.0.9.8e
httpd      2460                  apache  mem      REG      253,0    46808    1480003 /usr/lib/httpd/modules/mod_ldap.so
httpd      2460                  apache  mem      REG      253,0    30240    1475881 /usr/lib/httpd/modules/mod_authnz_ldap.so
httpd      2460                  apache  mem      REG      253,0    42588    1479570 /usr/lib/httpd/modules/mod_include.so
httpd      2460                  apache  mem      REG      253,0    22348    1480798 /usr/lib/httpd/modules/mod_mime_magic.so
httpd      2460                  apache  mem      REG      253,0    9660    1480842 /usr/lib/httpd/modules/mod_setenvif.so
httpd      2460                  apache  mem      REG      253,0    9628    1475820 /usr/lib/httpd/modules/mod_actions.so
httpd      2460                  apache  mem      REG      253,0    93508    1343612 /lib/libselinux.so.1
httpd      2460                  apache  mem      REG      253,0  1297124    1343617 /lib/libcrypto.so.0.9.8e
httpd      2460                  apache  mem      REG      253,0  613716    1318362 /usr/lib/libkrb5.so.3.3
httpd      2460                  apache  mem      REG      253,0    17916    1478708 /usr/lib/httpd/modules/mod_deflate.so
httpd      2460                  apache  mem      REG      253,0    13852    1479782 /usr/lib/httpd/modules/mod_headers.so
httpd      2460                  apache  mem      REG      253,0    17912    1480324 /usr/lib/httpd/modules/mod_mime.so
httpd      2460                  apache  mem      REG      253,0    87928    1478683 /usr/lib/httpd/modules/mod_dav.so
httpd      2460                  apache  mem      REG      253,0    34428    1477908 /usr/lib/httpd/modules/mod_autoindex.so
httpd      2460                  apache  mem      REG      253,0    9632    1480835 /usr/lib/httpd/modules/mod_vhost_alias.so
httpd      2460                  apache  mem      REG      253,0    30336    1480799 /usr/lib/httpd/modules/mod_negotiation.so
httpd      2460                  apache  mem      REG      253,0    9628    1480832 /usr/lib/httpd/modules/mod_userdir.so
httpd      2460                  apache  mem      REG      253,0    13752    1475852 /usr/lib/httpd/modules/mod_alias.so
httpd      2460                  apache  mem      REG      253,0    26144    1480808 /usr/lib/httpd/modules/mod_proxy_balancer.so
httpd      2460                  apache  mem      REG      253,0    34460    1480813 /usr/lib/httpd/modules/mod_proxy_ftp.so
httpd      2460                  apache  mem      REG      253,0    30396    1480839 /usr/lib/httpd/modules/mod_proxy_http.so
httpd      2460                  apache  mem      REG      253,0    22136    1478677 /usr/lib/httpd/modules/mod_cgi.so
httpd      2460                  apache  mem      REG      253,0  240388    1483595 /usr/lib/httpd/modules/mod_fcgid.so
httpd      2460                  apache  mem      REG      253,0  109740    1343623 /lib/libnsl-2.5.so
httpd      2460                  apache  mem      REG      253,0    15308    1343643 /lib/libutil-2.5.so
httpd      2460                  apache  mem      REG      253,0    9668    1475884 /usr/lib/httpd/modules/mod_authz_groupfile.so
httpd      2460                  apache  mem      REG      253,0    4324    1324828 /usr/lib/libpspell.so.15.1.3
httpd      2460                  apache  mem      REG      253,0    71852    1324862 /usr/lib/libbz2.so.1.0.3
httpd      2460                  apache  mem      REG      253,0    10908    1475498 /usr/lib/httpd/modules/mod_authz_svn.so
httpd      2460                  apache  mem      REG      253,0  129900    1343560 /lib/ld-2.5.so
httpd      2460                  apache  mem      REG      253,0    34428    1480804 /usr/lib/httpd/modules/mod_proxy_ajp.so
httpd      2460                  apache  mem      REG      253,0    93080    1483562 /usr/lib/httpd/modules/mod_python.so
httpd      2460                  apache  mem      REG      253,0    15716    1330000 /usr/lib/libdistcache.so.1.0.1
httpd      2460                  apache  mem      REG      253,0    9628    1475919 /usr/lib/httpd/modules/mod_authz_host.so
httpd      2460                  apache  mem      REG      253,0    34148    1323849 /usr/lib/libnal.so.1.0.1
httpd      2460                  apache  mem      REG      253,0    25540    1324975 /usr/lib/libsvn_fs-1.so.0.0.0
httpd      2460                  apache  mem      REG      253,0    41264    1324971 /usr/lib/libsvn_delta-1.so.0.0.0
httpd      2460                  apache  mem      REG      253,0    9632    1480809 /usr/lib/httpd/modules/mod_proxy_connect.so
httpd      2460                  apache  mem      REG      253,0  217160    1479903 /usr/lib/httpd/modules/mod_perl.so
httpd      2460                  apache  mem      REG      253,0  248028    1328815 /usr/lib/libcurl.so.3.0.0
httpd      2460                  apache  mem      REG      253,0  198584    1325989 /usr/lib/libidn.so.11.5.19
httpd      2460                  apache  mem      REG      253,0  143608    1475499 /usr/lib/httpd/modules/mod_dav_svn.so
httpd      2460                  apache  mem      REG      253,0    22044    1479036 /usr/lib/httpd/modules/mod_disk_cache.so
httpd      2460                  apache  mem      REG      253,0    5500    1475875 /usr/lib/httpd/modules/mod_authn_file.so
httpd      2460                  apache  mem      REG      253,0    50848    1343548 /lib/libnss_files-2.5.so
httpd      2460                  apache  mem      REG      253,0    7880    1343567 /lib/libkeyutils-1.2.so
httpd      2460                  apache  mem      REG      253,0    59196    1480841 /usr/lib/httpd/modules/mod_rewrite.so
httpd      2460                  apache  mem      REG      253,0  171184    1478718 /usr/lib/httpd/modules/mod_ssl.so
httpd      2460                  apache  mem      REG      253,0    45432    1343622 /lib/libcrypt-2.5.so
httpd      2460                  apache  mem      REG      253,0  159736    1324990 /usr/lib/libsvn_repos-1.so.0.0.0
httpd      2460                  apache  mem      REG      253,0    46676    1343630 /lib/libgcc_s-4.1.2-20080825.so.1
httpd      2460                  apache  mem      REG      253,0  105712    1321712 /usr/lib/libaprutil-1.so.0.2.7
httpd      2460                  apache  mem      REG      253,0    9592    1478711 /usr/lib/httpd/modules/mod_dir.so
httpd      2460                  apache  mem      REG      253,0    22084    1480229 /usr/lib/httpd/modules/mod_log_config.so
httpd      2460                  apache  mem      REG      253,0  156580    1326035 /usr/lib/libsvn_fs_fs-1.so.0.0.0
httpd      2460                  apache  mem      REG      253,0    4372    1326303 /usr/lib/libsvn_fs_util-1.so.0.0.0
httpd      2460                  apache  mem      REG      253,0    53792    1319207 /usr/lib/liblber-2.3.so.0.2.31
httpd      2460                  apache  mem      REG      253,0    9660    1479399 /usr/lib/httpd/modules/mod_expires.so
httpd      2460                  apache  mem      REG      253,0  183620    1324978 /usr/lib/libsvn_fs_base-1.so.0.0.0
httpd      2460                  apache  mem      REG      253,0    99060    1329996 /usr/lib/libsasl2.so.2.0.22
httpd      2460                  apache  mem      REG      253,0  162724    1322297 /usr/lib/libapr-1.so.0.2.7
httpd      2460                  apache  mem      REG      253,0    48156    1343632 /lib/librt-2.5.so
httpd      2460                  apache  mem      REG      253,0    17980    1479400 /usr/lib/httpd/modules/mod_ext_filter.so
httpd      2460                  apache  mem      REG      253,0  240584    1324430 /usr/lib/libldap-2.3.so.0.2.31
httpd      2460                  apache  mem      REG      253,0    46776    1478687 /usr/lib/httpd/modules/mod_dav_fs.so
httpd      2460                  apache  mem      REG      253,0    9660    1480843 /usr/lib/httpd/modules/mod_speling.so
httpd      2460                  apache  mem      REG      253,0    9600    1476006 /usr/lib/httpd/modules/mod_authz_owner.so
httpd      2460                  apache  mem      REG      253,0    33968    1316833 /usr/lib/libkrb5support.so.0.1
httpd      2460                  apache  mem      REG      253,0    5496    1480830 /usr/lib/httpd/modules/mod_suexec.so
httpd      2460                  apache  mem      REG      253,0    80636    1343585 /lib/libresolv-2.5.so
httpd      2460                  apache  mem      REG      253,0    75120    1343616 /lib/libz.so.1.2.3
httpd      2460                  apache  mem      REG      253,0    22108    1480308 /usr/lib/httpd/modules/mod_mem_cache.so
httpd      2460                  apache  mem      REG      253,0    9772    1479669 /usr/lib/httpd/modules/mod_file_cache.so
httpd      2460                  apache  mem      REG      253,0    17992    1480002 /usr/lib/httpd/modules/mod_info.so
httpd      2460                  apache  mem      REG      253,0  157336    1317251 /usr/lib/libk5crypto.so.3.1
httpd      2460                  apache  mem      REG      253,0    9660    1480847 /usr/lib/httpd/modules/mod_usertrack.so
httpd      2460                  apache  mem      REG      253,0  881927    1325290 /usr/lib/sse2/libgmp.so.3.3.3
httpd      2460                  apache  mem      REG      253,0  216544    1343629 /lib/libm-2.5.so
httpd      2460                  apache  mem      REG      253,0    75704    1480801 /usr/lib/httpd/modules/mod_proxy.so
httpd      2460                  apache  mem      REG      253,0    9628    1475882 /usr/lib/httpd/modules/mod_authz_dbm.so
httpd      2460                  apache  mem      REG      253,0    9632    1475867 /usr/lib/httpd/modules/mod_authn_alias.so
httpd      2460                  apache  mem      REG      253,0    5504    1475874 /usr/lib/httpd/modules/mod_authn_default.so
httpd      2460                  apache  mem      REG      253,0    30272    1475864 /usr/lib/httpd/modules/mod_auth_digest.so
httpd      2460                  apache  mem      REG      253,0    17880    1480844 /usr/lib/httpd/modules/mod_status.so
httpd      2460                  apache  mem      REG      253,0    16032    1343519 /lib/libuuid.so.1.2
httpd      2460                  apache  mem      REG      253,0    30328    1477948 /usr/lib/httpd/modules/mod_cache.so
httpd      2460                  apache  mem      REG      253,0  1241976    1409507 /usr/lib/perl5/5.8.8/i386-linux-thread-multi/CORE/libperl.so
httpd      2460                  apache  mem      REG      253,0  936908    1323130 /usr/lib/libstdc++.so.6.0.8
httpd      2460                  apache  mem      REG      253,0  744132    1324992 /usr/lib/libsvn_subr-1.so.0.0.0
httpd      2460                  apache  mem      REG      253,0  770056    1326402 /usr/lib/libaspell.so.15.1.3
httpd      2460                  apache  mem      REG      253,0  1148752    1318361 /usr/lib/libpython2.4.so.1.0
httpd      2460                  apache  mem      REG      253,0  1250176    1324843 /usr/lib/libxml2.so.2.6.26
httpd      2460                  apache  mem      REG      253,0  3265404    1479659 /usr/lib/httpd/modules/libphp5.so
httpd      2460                  apache  DEL      REG        0,9                8481 /dev/zero
httpd      2460                  apache  DEL      REG        0,9            4620295 /SYSV010080de
httpd      2460                  apache  DEL      REG        0,9              803743 /dev/zero
httpd      2460                  apache    0r      CHR        1,3                1285 /dev/null
httpd      2460                  apache    1w      CHR        1,3                1285 /dev/null
httpd      2460                  apache    2w      REG      253,0    16958      34093 /var/log/httpd/error_log.1
httpd      2460                  apache    3r      CHR        1,9                773 /dev/urandom
httpd      2460                  apache    4u    IPv6      8158                TCP *:http (LISTEN)
httpd      2460                  apache    5u    sock        0,5                8159 can't identify protocol
httpd      2460                  apache    6u    IPv6      8163                TCP *:https (LISTEN)
httpd      2460                  apache    7u    sock        0,5                8164 can't identify protocol
httpd      2460                  apache    8r    FIFO        0,6              803742 pipe
httpd      2460                  apache    9w    FIFO        0,6              803742 pipe
httpd      2460                  apache  10w      REG      253,0    16958      34093 /var/log/httpd/error_log.1
httpd      2460                  apache  11w      REG        3,2        0    3170576 /home/test/logs/error_log.1 (deleted)
httpd      2460                  apache  12w      REG        3,2        0    294248 /home/dom5/logs/error_log.1 (deleted)
httpd      2460                  apache  13w      REG        3,2        0    2058396 /home/dom4/logs/error_log.1 (deleted)
httpd      2460                  apache  14w      REG        3,2        0    5260212 /home/dom6/domains/yald.dom3.com/logs/error_log.1 (deleted)
httpd      2460                  apache  15w      REG        3,2  815652    1407794 /home/dom3/logs/error_log.1 (deleted)
httpd      2460                  apache  16w      REG        3,2        0    2940729 /home/dom2/logs/error_log.1 (deleted)
httpd      2460                  apache  17w      REG        3,2        0    3243803 /home/dom1/logs/error_log.1 (deleted)
httpd      2460                  apache  18w      REG        3,2  204039    6145422 /home/dom7/logs/error_log.1 (deleted)
httpd      2460                  apache  19w      REG        3,2    11592    1241764 /home/dom6/logs/error_log.1 (deleted)
httpd      2460                  apache  20w      REG      253,0      220      34097 /var/log/httpd/ssl_error_log.1
httpd      2460                  apache  21w      REG      253,0    1200      33925 /var/log/httpd/access_log.1
httpd      2460                  apache  22w      REG        3,2        0    3169462 /home/test/logs/access_log.1 (deleted)
httpd      2460                  apache  23w      REG        3,2        0    294133 /home/dom5/logs/access_log.1 (deleted)
httpd      2460                  apache  24w      REG        3,2        0    2058394 /home/dom4/logs/access_log.1 (deleted)
httpd      2460                  apache  25w      REG        3,2    3283    5261263 /home/dom6/domains/yald.dom3.com/logs/access_log.1 (deleted)
httpd      2460                  apache  26w      REG        3,2  182470    1406372 /home/dom3/logs/access_log.1 (deleted)
httpd      2460                  apache  27w      REG        3,2    32108    2940726 /home/dom2/logs/access_log.1 (deleted)
httpd      2460                  apache  28w      REG        3,2    1401    3243781 /home/dom1/logs/access_log.1 (deleted)
httpd      2460                  apache  29w      REG        3,2  535974    6145411 /home/dom7/logs/access_log.1 (deleted)
httpd      2460                  apache  30w      REG        3,2  274443    1241762 /home/dom6/logs/access_log.1 (deleted)
httpd      2460                  apache  31w      REG      253,0      159      34096 /var/log/httpd/ssl_access_log.1
httpd      2460                  apache  32w      REG      253,0      193      34098 /var/log/httpd/ssl_request_log.1
httpd      2460                  apache  33r    FIFO        0,6              803746 pipe
httpd      2460                  apache  36w    FIFO        0,6              803747 pipe

Thanks

timetraveler 12-04-2011 07:22 PM

I don't have an answer, I don't know centos. I would take that POLLHUP info and search the apache maillist.
Are you doing a graceful shutdown? If I recall the graceful shutdown waits until some conditions are met.
Possibly some sockets are misbehaving.

I see you have selinux too, wheter or not it's a culprit here I don't know. It can get in the way sometimes.

newuser455 12-04-2011 08:36 PM

Well, I didn't try to do any type of shutdown until it was already crashed. In the error log there is a line indicating a graceful restart

[Sun Nov 27 04:02:17 2011] [notice] Graceful restart requested, doing restart

Is it possible that apache is doing graceful restarts on its own and maybe the conditions are not being met so the process becomes halted? In that case wouldn't it at least log the request?

All I really know is that I started having this issue about a year ago and cannot for the life of me figure out what is causing it. I've been using apache for a number of years, and every other issue I've had has been indicated in the log and relatively straightforward to resolve.


All times are GMT -5. The time now is 08:04 AM.