FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Apache, Lighttpd, nginx, Cherokee
tomotom
Posts: 330
Joined: 2006-09-22 13:37

FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by tomotom » 2007-03-20 10:46

Kann mir jemand erklären was diese Logs zu bedeuten haben bzw. was die Ursache sein könnte? Das eigenartige ist, dass ich gar kein web gibt das php in einer fastcgi Umgebung nutzt. Es werden dort vom Apache 2 z.Z. lediglich statische Seiten ausgeliefert.

Code: Select all

[Tue Mar 20 09:53:20 2007] [notice] SIGUSR1 received.  Doing graceful restart
[Tue Mar 20 09:53:21 2007] [alert] (4)Interrupted system call: FastCGI: read() from pipe failed (0)
[Tue Mar 20 09:53:21 2007] [alert] (4)Interrupted system call: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye
[Tue Mar 20 09:53:21 2007] [notice] FastCGI: wrapper mechanism enabled (wrapper: /usr/lib/apache2/suexec2)
[Tue Mar 20 09:53:21 2007] [notice] FastCGI: process manager initialized (pid 7074)
[Tue Mar 20 09:53:21 2007] [notice] Apache/2.0.54 (Debian GNU/Linux) mod_fastcgi/2.4.2 mod_ssl/2.0.54 OpenSSL/0.9.7e mod_perl/1.999.21 Perl/v5.8.4 configured -- resuming normal operations
[Tue Mar 20 09:53:21 2007] [warn] long lost child came home! (pid 24189)
[Tue Mar 20 09:53:29 2007] [error] [client 88.73.70.170] File does not exist: /var/www/sharedip/favicon.ico
[Tue Mar 20 09:54:48 2007] [notice] SIGUSR1 received.  Doing graceful restart
[Tue Mar 20 09:54:48 2007] [emerg] (22)Invalid argument: apr_proc_mutex_unlock failed. Attempting to shutdown process gracefully.
[Tue Mar 20 09:54:48 2007] [notice] FastCGI: wrapper mechanism enabled (wrapper: /usr/lib/apache2/suexec2)
[Tue Mar 20 09:54:48 2007] [notice] FastCGI: process manager initialized (pid 7546)
[Tue Mar 20 09:54:49 2007] [notice] Apache/2.0.54 (Debian GNU/Linux) mod_fastcgi/2.4.2 mod_ssl/2.0.54 OpenSSL/0.9.7e mod_perl/1.999.21 Perl/v5.8.4 configured -- resuming normal operations

User avatar
Joe User
Project Manager
Project Manager
Posts: 11138
Joined: 2003-02-27 01:00
Location: Hamburg

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by Joe User » 2007-03-20 16:00

Wenn Du mod_[fastcgi|perl|ssl|...] nicht nutzt, warum lädst Du diese(s) (fehlkonfigurierten) Modul(e) überhaupt? Zudem ist Dein Apache buggy, bitte updaten...
PayPal.Me/JoeUserFreeBSD Remote Installation
Wings for LifeWings for Life World Run

„If there’s more than one possible outcome of a job or task, and one
of those outcomes will result in disaster or an undesirable consequence,
then somebody will do it that way.“ -- Edward Aloysius Murphy Jr.

tomotom
Posts: 330
Joined: 2006-09-22 13:37

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by tomotom » 2007-03-20 16:14

Joe User wrote:Wenn Du mod_[fastcgi|perl|ssl|...] nicht nutzt, warum lädst Du diese(s) (fehlkonfigurierten) Modul(e) überhaupt? Zudem ist Dein Apache buggy, bitte updaten...
Das ist ja meine Frage. Die Module werden geladen weil sie auch laufen sollen. Z.Z. laufen aber nur statische Seiten. Darüber, dass trotz der statischen Seiten so etwas passiert:

Code: Select all

[Tue Mar 20 14:45:41 2007] [warn] long lost child came home! (pid 14721)
[Tue Mar 20 14:52:06 2007] [notice] SIGUSR1 received.  Doing graceful restart
wunder mich. Ich hätte gerne gewußt warum.

Woran siehst Du, dass der Apache buggy ist?

User avatar
Joe User
Project Manager
Project Manager
Posts: 11138
Joined: 2003-02-27 01:00
Location: Hamburg

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by Joe User » 2007-03-20 16:23

tomotom wrote:Die Module werden geladen weil sie auch laufen sollen. Z.Z. laufen aber nur statische Seiten.
Die geladenen Module müssen passend konfiguriert sein, auch wenn sie nicht genutzt werden.
tomotom wrote:Woran siehst Du, dass der Apache buggy ist?

Code: Select all

[Tue Mar 20 09:54:48 2007] [emerg] (22)Invalid argument: apr_proc_mutex_unlock failed. Attempting to shutdown process gracefully.
PayPal.Me/JoeUserFreeBSD Remote Installation
Wings for LifeWings for Life World Run

„If there’s more than one possible outcome of a job or task, and one
of those outcomes will result in disaster or an undesirable consequence,
then somebody will do it that way.“ -- Edward Aloysius Murphy Jr.

tomotom
Posts: 330
Joined: 2006-09-22 13:37

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by tomotom » 2007-03-21 02:09

Wie kann denn fcgi-php aufgerufen werden und zu so einem Eintrag führen

Code: Select all

[Tue Mar 20 09:54:48 2007] [notice] FastCGI: wrapper mechanism enabled (wrapper: /usr/lib/apache2/suexec2) 
wenn gar kein phpscript läuft und auch das fcgi-php nicht in er httpd.conf zum starten eingetragen ist? Das verstehe ich wirklich nicht?

Für was ist "apr_proc_mutex_unlock" verantwortlich?

ghoster1970
Posts: 71
Joined: 2006-06-21 13:31
Location: Potsdam

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by ghoster1970 » 2007-03-21 11:26

http://tigress.com/nobs/apache2-error-messages.html

Solange du keine Config lieferst kann man dir nicht weiterhelfen.
PS: hast du das Teil selbst kompiliert?

tomotom
Posts: 330
Joined: 2006-09-22 13:37

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by tomotom » 2007-03-21 13:22

ghoster1970 wrote:http://tigress.com/nobs/apache2-error-messages.html

Solange du keine Config lieferst kann man dir nicht weiterhelfen.
PS: hast du das Teil selbst kompiliert?
Ja, kompeliert für fcgi.

Die auffälligen Logs stehen och oben. Es gibt aus noch tausende von folgenden Einträgen(bei 95% cpu Last, deshalb wurde ich stutzig), die scheinen aber nicht mehr aufzutreten:

Code: Select all

[Sun Mar 18 06:27:29 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
[Sun Mar 18 06:27:29 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
[Sun Mar 18 06:27:29 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
[Sun Mar 18 06:27:35 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
[Sun Mar 18 06:27:38 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
[Sun Mar 18 06:27:39 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
[Sun Mar 18 06:27:40 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
[Sun Mar 18 06:27:45 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
[Sun Mar 18 06:27:46 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
[Sun Mar 18 06:27:48 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket)
Ich habe Fehler bzw. doppelte Einträge in der httpd.conf entdeckt und den Apachen nach Korrektur neu gestartet. Z.Z. sieht es normal aus also ohne verlohrende Kinder etc wie:

Code: Select all

[Wed Mar 21 00:24:23 2007] [warn] long lost child came home! (pid 941)
[Wed Mar 21 01:59:52 2007] [notice] caught SIGTERM, shutting down
[Wed Mar 21 01:59:52 2007] [alert] (4)Interrupted system call: FastCGI: read() from pipe failed (0)
Evt. hat es auch damit etwas zu tun http://www.rootforum.org/forum/viewtopic.php?t=45157
Ich verstehe nicht wie fcgi Fehler machen kann, wo doch kein script darauf zugreift. Das ist was ich verstehen möchte.

Roger Wilco
Administrator
Administrator
Posts: 5924
Joined: 2004-05-23 12:53

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by Roger Wilco » 2007-03-21 16:23

tomotom wrote:Ja, kompeliert für fcgi.
Warum?
tomotom wrote:Ich verstehe nicht wie fcgi Fehler machen kann, wo doch kein script darauf zugreift. Das ist was ich verstehen möchte.
Weil die FastCGI-Prozesse im Voraus geforked werden, damit das später nicht passieren muss. Das ist ja gerade der Vorteil von FastCGI gegenüber der normalen CGI-Schnittstelle.

tomotom
Posts: 330
Joined: 2006-09-22 13:37

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by tomotom » 2007-03-21 17:13

Roger Wilco wrote:
tomotom wrote:Ja, kompeliert für fcgi.
Warum?
Nein, sorry falsch, da habe ich zu schnell geantwortet. Das fcgi Modul für den apache habe ich als Modul geladen. Nur php habe ich für fcgi compiliert.


Jetzt kommt folgendes und das sieht doch ok aus oder nicht?

Code: Select all

[Wed Mar 21 16:53:47 2007] [notice] caught SIGTERM, shutting down
[Wed Mar 21 16:53:49 2007] [notice] suEXEC mechanism enabled (wrapper: /usr/lib/apache2/suexec2)
[Wed Mar 21 16:53:49 2007] [notice] FastCGI: wrapper mechanism enabled (wrapper: /usr/lib/apache2/suexec2)
[Wed Mar 21 16:53:49 2007] [notice] FastCGI: process manager initialized (pid 17205)
[Wed Mar 21 16:53:49 2007] [notice] Apache/2.0.54 (Debian GNU/Linux) mod_fastcgi/2.4.2 mod_ssl/2.0.54 OpenSSL/0.9.7e mod_perl/1.999.21 Perl/v5.8.4 configured -- resuming normal operations
Aber warum passiert das:

Code: Select all

... caught SIGTERM, shutting down
?

Roger Wilco
Administrator
Administrator
Posts: 5924
Joined: 2004-05-23 12:53

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by Roger Wilco » 2007-03-21 17:18

tomotom wrote:Aber warum passiert das:

Code: Select all

... caught SIGTERM, shutting down
?
Du hast den Apache httpd neugestartet...

tomotom
Posts: 330
Joined: 2006-09-22 13:37

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by tomotom » 2007-03-21 17:27

Roger Wilco wrote:
tomotom wrote:Aber warum passiert das:

Code: Select all

... caught SIGTERM, shutting down
?
Du hast den Apache httpd neugestartet...
Da hätte ich ja fast selber drauf kommen können ... Danke

tomotom
Posts: 330
Joined: 2006-09-22 13:37

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by tomotom » 2007-03-21 18:32

Und ist das auch "normal" bzw. worauf deutet "Bad file descriptor:"hin?

Code: Select all

[Wed Mar 21 18:17:37 2007] [notice] caught SIGTERM, shutting down
[Wed Mar 21 18:17:37 2007] [alert] (9)Bad file descriptor: FastCGI: read() from pipe failed (0)
[Wed Mar 21 18:17:37 2007] [alert] (9)Bad file descriptor: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

kase
Posts: 1031
Joined: 2002-10-14 22:56

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by kase » 2007-03-21 21:07

Für mich sieht das danach aus, als hättest du den Apache mit bzw gegen irgendwelche falschen Libs gebaut.

Die letzte Fehlermeldung bedeutet einfach nur, dass du den Apache beendet hast. Der Apache konnte den Shutdown dem fastcgi Modul (warum auch immer, evtl wegen falscher Libs) nicht richtig mitteilen. Irgendwann hat dann fastcgi gemerkt, dass der Apache gar nicht mehr da ist (Bad file desc) und hat sich selbst beendet.

Code: Select all

[Tue Mar 20 09:54:48 2007] [emerg] (22)Invalid argument: apr_proc_mutex_unlock failed. Attempting to shutdown process gracefully.
und

Code: Select all

[Wed Mar 21 00:24:23 2007] [warn] long lost child came home! (pid 941)
und

Code: Select all

[Sun Mar 18 06:27:29 2007] [error] (88)Socket operation on non-socket: apr_accept: (client socket) 
können außerdem die Folgen von inkompatiblen Libs sein, muss aber nicht unbedingt.

tomotom
Posts: 330
Joined: 2006-09-22 13:37

Re: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye

Post by tomotom » 2007-03-21 22:40

Diese Meldung habe ich seitdem ich die httpd.conf korrigiert habe nicht mehr erhalten:

Code: Select all

[Tue Mar 20 09:54:48 2007] [emerg] (22)Invalid argument: apr_proc_mutex_unlock failed. Attempting to shutdown process gracefully.
Ich habe folgenden Zusammenhang feststellen können. Im folgenden die apache Anweidung und die entsprechenden Logeinträge:

apache2ctl stop verursacht:

Code: Select all

[Wed Mar 21 22:25:22 2007] [alert] (9)Bad file descriptor: FastCGI: read() from pipe failed (0)
[Wed Mar 21 22:25:22 2007] [alert] (9)Bad file descriptor: FastCGI: the PM is shutting down, Apache seems to have disappeared - bye
apache2ctl start verursacht:

Code: Select all

[Wed Mar 21 22:25:36 2007] [notice] suEXEC mechanism enabled (wrapper: /usr/lib/apache2/suexec2)
[Wed Mar 21 22:25:36 2007] [notice] FastCGI: wrapper mechanism enabled (wrapper: /usr/lib/apache2/suexec2)
[Wed Mar 21 22:25:36 2007] [notice] FastCGI: process manager initialized (pid 23812)
[Wed Mar 21 22:25:37 2007] [notice] Apache configured -- resuming normal operations
apache2ctl restart verursacht:

Code: Select all

[Wed Mar 21 22:25:50 2007] [notice] SIGHUP received.  Attempting to restart
[Wed Mar 21 22:25:50 2007] [notice] FastCGI: wrapper mechanism enabled (wrapper: /usr/lib/apache2/suexec2)
[Wed Mar 21 22:25:50 2007] [notice] FastCGI: process manager initialized (pid 23896)
[Wed Mar 21 22:25:51 2007] [notice] Apache configured -- resuming normal operations
apache2ctl graceful verusacht:

Code: Select all

[Wed Mar 21 22:34:55 2007] [notice] SIGUSR1 received.  Doing graceful restart
[Wed Mar 21 22:34:55 2007] [notice] FastCGI: wrapper mechanism enabled (wrapper: /usr/lib/apache2/suexec2)
[Wed Mar 21 22:34:55 2007] [notice] FastCGI: process manager initialized (pid 24202)
[Wed Mar 21 22:34:56 2007] [notice] Apache configured -- resuming normal operations
[Wed Mar 21 22:34:56 2007] [warn] long lost child came home! (pid 24118)
Ist das bei Euch anders, wie sieht es bei Euch aus?