Upgrade from 3.2.x to 3.3.x - 503 service unavailable

Discussion in 'Bug Reports' started by sth, Mar 20, 2008.

  1. sth

    sth New Member

    On all my servers (5 servers) if I upgrade from 3.2.x to 3.3.x I receive the following error, whne I open .php pages:

    "503 Service Unavailable"

    This is the debug log:

    2008-03-20 19:18:37.916 NOTICE [127.0.0.1:44800-0#www.sth.it] Content len: 0, Request line:
    GET /__phpinfo.php HTTP/1.1
    2008-03-20 19:18:37.916 DEBUG [127.0.0.1:44800-0#www.sth.it] Find context with URI: [/], location: [/WWW/www.sth.it/]
    2008-03-20 19:18:37.916 DEBUG [HTAccess] Updating configuration file [/WWW/www.sth.it/.htaccess]
    2008-03-20 19:18:37.918 INFO [HTAccess] Updating configuration from [/WWW/www.sth.it/.htaccess]
    2008-03-20 19:18:37.918 DEBUG [127.0.0.1:44800-0#www.sth.it] Find .htaccess context with URI: [/], location: [/WWW/www.sth.it/]
    2008-03-20 19:18:37.918 DEBUG [127.0.0.1:44800-0#www.sth.it] Find handler [phpLsapi] for [.php]
    2008-03-20 19:18:37.918 DEBUG [127.0.0.1:44800-0#www.sth.it] processContextPath() return 0
    2008-03-20 19:18:37.918 DEBUG [127.0.0.1:44800-0#www.sth.it] run lsapi processor.
    2008-03-20 19:18:37.918 DEBUG [uds://tmp/lshttpd/lsphp.sock] create new connection succeed!
    2008-03-20 19:18:37.918 DEBUG [uds://tmp/lshttpd/lsphp.sock] request [127.0.0.1:44800-0#www.sth.it:lsapi] is assigned with connection!
    2008-03-20 19:18:37.918 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] [ExtConn] reconnect()
    2008-03-20 19:18:37.928 INFO [phpLsapi] pid list size: 1
    2008-03-20 19:18:37.928 INFO [phpLsapi] add child process pid: 8114
    2008-03-20 19:18:37.928 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] [ExtConn] connecting to [uds://tmp/lshttpd/lsphp.sock]...
    2008-03-20 19:18:37.928 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nWrite()
    2008-03-20 19:18:37.928 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] request header is done
    2008-03-20 19:18:37.928 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::continueRead()
    2008-03-20 19:18:37.928 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] Request body done!
    2008-03-20 19:18:37.928 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::suspendWrite()
    2008-03-20 19:18:37.928 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] processNewReq() return 0.
    2008-03-20 19:18:41.008 DEBUG [idle] Idle connection timed out, close!
    2008-03-20 19:18:41.008 DEBUG [idle] [ExtConn] close()
    2008-03-20 19:18:42.980 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nRead()
    2008-03-20 19:18:42.980 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] LsapiConn::doRead()
    2008-03-20 19:18:42.980 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] process packet header -1 bytes
    2008-03-20 19:18:42.980 INFO [127.0.0.1:44800-0#www.sth.it:lsapi] connection to [uds://tmp/lshttpd/lsphp.sock] on request #0, error: Connection reset by peer!
    2008-03-20 19:18:42.980 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] [ExtConn] close()
    2008-03-20 19:18:43.014 INFO [phpLsapi] pid list size: 2
    2008-03-20 19:18:43.014 INFO [127.0.0.1:44800-0#www.sth.it:lsapi] add child process pid: 8119
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] [ExtConn] reconnect()
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] [ExtConn] connecting to [uds://tmp/lshttpd/lsphp.sock]...
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nWrite()
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] request header is done
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::continueRead()
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] Request body done!
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::suspendWrite()
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nError()
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nRead()
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] LsapiConn::doRead()
    2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] process packet header 0 bytes
    2008-03-20 19:18:43.015 INFO Remove pid: 8114
    2008-03-20 19:18:43.015 INFO Pid: 8114 associated with [phpLsapi]
    2008-03-20 19:18:43.015 INFO [phpLsapi] pid list size: 1, pid stop list size: 0
    2008-03-20 19:18:43.039 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nRead()
    2008-03-20 19:18:43.039 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] LsapiConn::doRead()
    2008-03-20 19:18:43.039 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] process packet header -1 bytes
    2008-03-20 19:18:43.039 INFO [127.0.0.1:44800-0#www.sth.it:lsapi] connection to [uds://tmp/lshttpd/lsphp.sock] on request #0, error: Connection reset by peer!
    2008-03-20 19:18:43.039 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] [ExtConn] close()
    2008-03-20 19:18:43.068 INFO [phpLsapi] pid list size: 2
    2008-03-20 19:18:43.068 INFO [127.0.0.1:44800-0#www.sth.it:lsapi] add child process pid: 8120
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] [ExtConn] reconnect()
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] [ExtConn] connecting to [uds://tmp/lshttpd/lsphp.sock]...
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nWrite()
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] request header is done
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::continueRead()
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] Request body done!
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::suspendWrite()
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nError()
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nRead()
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] LsapiConn::doRead()
    2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] process packet header 0 bytes
    2008-03-20 19:18:43.068 INFO Remove pid: 8119
    2008-03-20 19:18:43.068 INFO Pid: 8119 associated with [phpLsapi]
    2008-03-20 19:18:43.068 INFO [phpLsapi] pid list size: 1, pid stop list size: 0
    2008-03-20 19:18:43.095 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn::eek:nRead()
    2008-03-20 19:18:43.095 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] LsapiConn::doRead()
    2008-03-20 19:18:43.095 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] process packet header -1 bytes
    2008-03-20 19:18:43.095 INFO [127.0.0.1:44800-0#www.sth.it:lsapi] connection to [uds://tmp/lshttpd/lsphp.sock] on request #0, error: Connection reset by peer!
    2008-03-20 19:18:43.095 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] [ExtConn] close()
    2008-03-20 19:18:43.095 DEBUG [uds://tmp/lshttpd/lsphp.sock] No Request has been processed successfully through this connection, the maximum connections allowed will be reduced!
    2008-03-20 19:18:43.095 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] HttpExtConnector::tryRecover()...
    2008-03-20 19:18:43.095 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] HttpIOLink::continueWrite()...
    2008-03-20 19:18:43.095 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] write resumed!
    2008-03-20 19:18:43.095 INFO Remove pid: 8120
    2008-03-20 19:18:43.095 INFO Pid: 8120 associated with [phpLsapi]
    2008-03-20 19:18:43.095 INFO [phpLsapi] pid list size: 0, pid stop list size: 0
    2008-03-20 19:18:43.095 NOTICE [phpLsapi] stop worker processes
    2008-03-20 19:18:43.095 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] HttpIOLink::handleEvents() events=4!
    2008-03-20 19:18:43.095 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] HttpIOLink::suspendWrite()...
    2008-03-20 19:18:43.096 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] HttpConnection::sendHttpError(),code=503 Service Unavailable
    2008-03-20 19:18:43.096 NOTICE [127.0.0.1:44800-0#www.sth.it:lsapi] oops! 503 Service Unavailable
    2008-03-20 19:18:43.096 NOTICE [127.0.0.1:44800-0#www.sth.it:lsapi] Content len: 0, Request line:
    GET /__phpinfo.php HTTP/1.1
    2008-03-20 19:18:43.096 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] HttpConnection::flush()!
    2008-03-20 19:18:43.097 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] Written to client: 621
    2008-03-20 19:18:43.097 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] HttpConnection::nextRequest()!
    2008-03-20 19:18:43.097 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] HttpExtConnector::cleanUp() ...
    2008-03-20 19:18:43.097 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] abort request...
    2008-03-20 19:18:43.097 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] release ExtProcessor!
    2008-03-20 19:18:43.097 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] Non-KeepAlive, CLOSING!
    2008-03-20 19:18:43.097 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] Shutting down out-bound socket ...
    2008-03-20 19:18:43.097 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] HttpIOLink::handleEvents() events=17!
  2. mistwang

    mistwang LiteSpeed Staff

    You probably need to increase the "Process soft/hard limit" "Memory soft/hard limit" for your lsphp external app configurations.
  3. sth

    sth New Member

    I have set this value:

    Server/Security:
    Memory soft/hard 500M/700M
    Process soft/hard 800/1000

    Server/External app/phplsapi:
    Memory soft/hard 500M/700M
    Process soft/hard 800/1000

    Virtual Host Template/External app/phplsapi:
    Memory soft/hard 500M/700M
    Process soft/hard 800/1000

    I got the same error "503 Service Unavailable".
  4. mistwang

    mistwang LiteSpeed Staff

    Have you update your lsphp binary with the latest LSAPI code?
    If you PM me a temp root access, I can take a look.
  5. sth

    sth New Member

    The LSAPI php code is a custom compiled code.
    I have this problem on all my servers.
  6. sth

    sth New Member

    I prepare to you a copy of the production server.
  7. mistwang

    mistwang LiteSpeed Staff

    PHP LSAPI has been updated, make sure to recompile your PHP binary when new LSAPI is available.
  8. sth

    sth New Member

    when I try to recompile my php I receive the following error:

    /usr/bin/ld: cannot find -lgssapi_krb5
    collect2: ld returned 1 exit status
    make: *** [sapi/litespeed/php] Error 1
  9. mistwang

    mistwang LiteSpeed Staff

    Library dependency problem, try installed the corresponding devel library.
  10. sth

    sth New Member

    I have all the libraries already installed.
    I have solved the problem with this command:

    root# export LDFLAGS="-L/usr/kerberos/lib -lkrb5 -lgssapi_krb5 -lpam"
  11. mistwang

    mistwang LiteSpeed Staff

    Maybe that related to your 503 errors. Please check error.log and stderr.log for related error messages.

Share This Page