Default PHP Server return 500 randomly

Discussion in 'Installation/Configuration' started by ProtocolNebula, Mar 29, 2017.

  1. ProtocolNebula

    ProtocolNebula New Member

    Hi.

    OS: Debian 8
    ISPCONFIG: Latest (3.12)

    I have installed ispconfig with Apache and PHP 5.6 (default), after added 5.3 and 7.1.
    This only occurs with PHP 5.6 (default installation). After few hours with the vhost working, it crashes PHP (.html files works fine but not .php).

    Logs:
    [Wed Mar 29 06:25:18.313299 2017] [auth_digest:notice] [pid 1186] AH01757: generating secret for digest authentication ...
    [Wed Mar 29 06:25:18.319241 2017] [:notice] [pid 19933] FastCGI: process manager initialized (pid 19933)
    [ 2017-03-29 06:25:18.3235 19935/7fc70de7c740 agents/Watchdog/Main.cpp:538 ]: Options: { 'analytics_log_user' => 'nobody', 'default_group' => 'nogroup', 'default_python' => 'python', 'default_ruby' => '/usr/bin/ruby', 'default_user' => 'nobody', 'log_level' => '0', 'max_pool_size' => '6', 'passenger_root' => '/usr/lib/ruby/vendor_ruby/phusion_passenger/locations.ini', 'passenger_version' => '4.0.53', 'pool_idle_time' => '300', 'temp_dir' => '/tmp', 'union_station_gateway_address' => 'gateway.unionstationapp.com', 'union_station_gateway_port' => '443', 'user_switching' => 'true', 'web_server_passenger_version' => '4.0.53', 'web_server_pid' => '1186', 'web_server_type' => 'apache', 'web_server_worker_gid' => '33', 'web_server_worker_uid' => '33' }
    [ 2017-03-29 06:25:18.3297 19938/7f6f1c11b740 agents/HelperAgent/Main.cpp:650 ]: PassengerHelperAgent online, listening at unix:/tmp/passenger.1.0.1186/generation-1/request
    [ 2017-03-29 06:25:18.3432 19946/7fdd4d03a780 agents/LoggingAgent/Main.cpp:321 ]: PassengerLoggingAgent online, listening at unix:/tmp/passenger.1.0.1186/generation-1/logging
    [ 2017-03-29 06:25:18.3433 19935/7fc70de7c740 agents/Watchdog/Main.cpp:728 ]: All Phusion Passenger agents started!
    [Wed Mar 29 06:25:18.390455 2017] [:error] [pid 1186] python_init: Python version mismatch, expected '2.7.5+', found '2.7.9'.
    [Wed Mar 29 06:25:18.390581 2017] [:error] [pid 1186] python_init: Python executable found '/usr/bin/python'.
    [Wed Mar 29 06:25:18.390586 2017] [:error] [pid 1186] python_init: Python path being used '/usr/lib/python2.7/:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload'.
    [Wed Mar 29 06:25:18.390602 2017] [:notice] [pid 1186] mod_python: Creating 8 session mutexes based on 150 max processes and 0 max threads.
    [Wed Mar 29 06:25:18.390614 2017] [:notice] [pid 1186] mod_python: using mutex_directory /tmp
    [Wed Mar 29 06:25:18.408219 2017] [ssl:warn] [pid 1186] AH01906: ns1. arrayzone. org:8080:0 server certificate is a CA certificate (BasicConstraints: CA == TRUE !?)
    [Wed Mar 29 06:25:18.408248 2017] [ssl:warn] [pid 1186] AH01909: ns1. arrayzone. org:8080:0 server certificate does NOT include an ID which matches the server name
    [Wed Mar 29 06:25:18.408308 2017] [ssl:error] [pid 1186] AH02217: ssl_stapling_init_cert: can't retrieve issuer certificate! [subject: emailAddress=info@ arrayzone. org,CN= arrayzone. org,OU=IT Department,O=ArrayZone,L=Barcelona,ST=Spain,C=ES / issuer: emailAddress=info@ arrayzone. org,CN= arrayzone. org,OU=IT Department,O=ArrayZone,L=Barcelona,ST=Spain,C=ES / serial: ACDDE7DBF00DA766 / notbefore: Feb 16 11:26:56 2017 GMT / notafter: Feb 14 11:26:56 2027 GMT]
    [Wed Mar 29 06:25:18.408313 2017] [ssl:error] [pid 1186] AH02567: Unable to configure certificate ns1. arrayzone. org:8080:0 for stapling
    [Wed Mar 29 06:25:18.408592 2017] [mpm_prefork:notice] [pid 1186] AH00163: Apache/2.4.10 (Debian) mod_fastcgi/mod_fastcgi-SNAP-0910052141 mod_fcgid/2.3.9 Phusion_Passenger/4.0.53 mod_python/3.3.1 Python/2.7.9 OpenSSL/1.0.1t configured -- resuming normal operations
    [Wed Mar 29 06:25:18.408608 2017] [core:notice] [pid 1186] AH00094: Command line: '/usr/sbin/apache2'
    [Wed Mar 29 06:25:18.408696 2017] [mpm_prefork:warn] [pid 1186] AH00167: long lost child came home! (pid 1189)
    suexec policy violation: see suexec log for more details

    [Wed Mar 29 08:06:33.037989 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_LOCK_DIR} is not defined
    [Wed Mar 29 08:06:33.038238 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_PID_FILE} is not defined
    [Wed Mar 29 08:06:33.038325 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_RUN_USER} is not defined
    [Wed Mar 29 08:06:33.038424 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_RUN_GROUP} is not defined
    [Wed Mar 29 08:06:33.038503 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_LOG_DIR} is not defined
    [Wed Mar 29 08:06:33.071588 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_LOCK_DIR} is not defined
    [Wed Mar 29 08:06:33.072750 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_RUN_DIR} is not defined
    [Wed Mar 29 08:06:33.073410 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_LOG_DIR} is not defined
    [Wed Mar 29 08:06:33.074548 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_LOG_DIR} is not defined
    [Wed Mar 29 08:06:33.074667 2017] [core:warn] [pid 25307] AH00111: Config variable ${APACHE_LOG_DIR} is not defined
    AH00526: Syntax error on line 74 of /etc/apache2/apache2.conf:
    Invalid Mutex directory in argument file:${APACHE_LOCK_DIR}

    [2017-03-29 06:32:25]: uid: (5013/web43) gid: (5010/client8) cmd: .php-fcgi-starter
    [2017-03-29 06:36:29]: uid: (5005/web3) gid: (5006/client4) cmd: .php-fcgi-starter
    [2017-03-29 06:44:04]: uid: (5018/web50) gid: (5017/client15) cmd: .php-fcgi-starter
    [2017-03-29 06:55:37]: uid: (5018/web50) gid: (5017/client15) cmd: .php-fcgi-starter
    [2017-03-29 07:07:10]: uid: (5014/web44) gid: (5014/client12) cmd: .php-fcgi-starter
    [2017-03-29 07:13:17]: uid: (5012/web40) gid: (5010/client8) cmd: .php-fcgi-starter
    [2017-03-29 07:22:17]: uid: (5017/web48) gid: (5016/client16) cmd: .php-fcgi-starter
    [2017-03-29 07:24:51]: uid: (5005/web3) gid: (5006/client4) cmd: .php-fcgi-starter
    [2017-03-29 07:28:00]: uid: (5014/web44) gid: (5014/client12) cmd: .php-fcgi-starter
    [2017-03-29 07:34:32]: uid: (5010/web28) gid: (5005/client1) cmd: .php-fcgi-starter_web35
    [2017-03-29 07:34:32]: target uid/gid (5010/5005) mismatch with directory (5010/5010) or program (5010/5005)
    [2017-03-29 07:44:43]: uid: (5010/web28) gid: (5010/client8) cmd: .php-fcgi-starter_web42
    [2017-03-29 07:44:53]: uid: (5010/web28) gid: (5010/client8) cmd: .php-fcgi-starter_web42

    I tried:
    Code:
    source /etc/apache2/envvars
    apache2 -V
    sudo service apache2 restart
    
    But it not working.

    It work again if I change PHP version and return to Default. It seems to fail in both CGI PHP 5.6 (default) configs (I don't know in others configurations like ModPHP).

    Thanks in advanced!
     
  2. till

    till Super Moderator Staff Member ISPConfig Developer

    Take a look at into error.log of the website, not the global one.
     
  3. ProtocolNebula

    ProtocolNebula New Member

    Sorry, I forget put the log.

    /var/www/clients/client8/web28/log/error.log:
    Code:
    [Wed Mar 01 20:33:41.922886 2017] [fcgid:warn] [pid 6783] (104)Connection reset by peer: [client 85.236.28.154:55560] mod_fcgid: error reading data from FastCGI server
    [Wed Mar 01 20:33:41.922985 2017] [core:error] [pid 6783] [client 85.236.28.154:55560] End of script output before headers: wp-login.php
    
    BUT: This is a subdomain located in /var/www/clients/client8/web28/manager/error, this folder is empty and "web" has no wordpress installation.

    I guess that correct log is
    [Wed Mar 22 17:57:30.544572 2017] [core:error] [pid 25719] [client 144.76.38.73:37785] End of script output before headers: index.php
    [Wed Mar 22 17:57:36.050132 2017] [fcgid:warn] [pid 25514] (104)Connection reset by peer: [client 144.76.38.73:39743] mod_fcgid: error reading data from FastCGI server
    [Wed Mar 22 17:57:36.050196 2017] [core:error] [pid 25514] [client 144.76.38.73:39743] End of script output before headers: index.php
    [Wed Mar 29 07:34:32.587619 2017] [fcgid:warn] [pid 23417] (104)Connection reset by peer: [client 81.47.168.119:1331] mod_fcgid: error reading data from FastCGI server, referer: https:// manager. arrayzone. org/
    [Wed Mar 29 07:34:32.587707 2017] [core:error] [pid 23417] [client 81.47.168.119:1331] End of script output before headers: index.php, referer: https:// manager. arrayzone. org/
    [Wed Mar 29 08:10:38.260527 2017] [fcgid:warn] [pid 25710] (104)Connection reset by peer: [client 81.47.168.119:2363] mod_fcgid: error reading data from FastCGI server, referer: https:// manager. arrayzone. org/
    [Wed Mar 29 08:10:38.260592 2017] [core:error] [pid 25710] [client 81.47.168.119:2363] End of script output before headers: index.php, referer: https:// manager. arrayzone. org/
    [Wed Mar 29 08:10:39.497132 2017] [fcgid:warn] [pid 25574] (104)Connection reset by peer: [client 81.47.168.119:2364] mod_fcgid: error reading data from FastCGI server, referer: https:// manager. arrayzone. org/
    [Wed Mar 29 08:10:39.497178 2017] [core:error] [pid 25574] [client 81.47.168.119:2364] End of script output before headers: index.php, referer: https:// manager. arrayzone. org/
     
    Last edited: Mar 29, 2017
  4. ProtocolNebula

    ProtocolNebula New Member

    Any suggestions or ideas?
    Do I reinstall the default PHP? (I'm not sure how it's done without breaking ispconfig).

    Thanks.
     

Share This Page