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

sth · Mar 20, 2008

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:

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:

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:

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:

nError()
2008-03-20 19:18:43.014 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn:

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:

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:

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:

nError()
2008-03-20 19:18:43.068 DEBUG [127.0.0.1:44800-0#www.sth.it:lsapi] ExtConn:

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:

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!

mistwang · Mar 20, 2008

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

sth · Mar 21, 2008

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".

mistwang · Mar 21, 2008

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

sth · Mar 21, 2008

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

sth · Mar 21, 2008

I prepare to you a copy of the production server.

mistwang · Mar 21, 2008

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

sth · Mar 21, 2008

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

mistwang · Mar 21, 2008

Library dependency problem, try installed the corresponding devel library.

sth · Mar 21, 2008

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"

mistwang · Mar 21, 2008

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

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

sth

Member

mistwang

LiteSpeed Staff

sth

Member

mistwang

LiteSpeed Staff

sth

Member

sth

Member

mistwang

LiteSpeed Staff

sth

Member

mistwang

LiteSpeed Staff

sth

Member

mistwang

LiteSpeed Staff