Graceful Restart sometimes works, sometimes fails

Discussion in 'Bug Reports' started by sid137, Sep 7, 2006.

  1. sid137

    sid137 New Member

    Hi,

    I'm running LiteSpeed 2.2 Standard on a CentOS 4 VPS. Often, when I go to configure a rails or php app using the included templates, I hit "apply changes" and then try to do a "graceful restart". The server shuts down, but it never starts back up, so I hav eto log in manually to restart it. Any idea whats happening? I've posted the log files below, and there are no core dump files

    Thanks

    The stderr.log is full othese entries:

    Error: /proc must be mounted
    To mount /proc at boot you need an /etc/fstab line like:
    /proc /proc proc defaults
    In the meantime, mount /proc /proc -t proc

    Here is error.log after a failed restart:

    2006-09-07 10:47:51.425 [NOTICE] Loading LiteSpeed/2.2 Standard ...
    2006-09-07 10:47:51.530 [NOTICE] [ADMIN] server socket: 127.0.0.1:7338
    2006-09-07 10:47:51.530 [NOTICE] Loading configuration from /opt/lsws/conf/httpd_config.xml ...
    2006-09-07 10:47:51.531 [NOTICE] Recovering server socket: [209.135.157.125:80]
    2006-09-07 10:47:51.531 [NOTICE] Recovering server socket: [209.135.157.125:7080]
    2006-09-07 10:47:51.531 [INFO] old priority: 0, new priority: 0
    2006-09-07 10:47:51.531 [INFO] [config:server:basic] For better obscurity, server version number is hidden in the response header.
    2006-09-07 10:47:51.848 [ERROR] HttpListener::start(): Can't listen at address adminListener: Address already in use!
    2006-09-07 10:47:51.848 [ERROR] HttpServer::addListener(adminListener) failed to create new listener
    2006-09-07 10:47:51.848 [ERROR] [config:admin:listener:adminListener] failed to start listener on address *:7080!
    2006-09-07 10:47:51.848 [ERROR] [config:admin:listener] No listener is available for admin virtual host!
    2006-09-07 10:47:51.848 [ERROR] Fatal error in configuration, exit!
    2006-09-07 10:47:52.201 [NOTICE] [Child: 4316] Shut down successfully!
    2006-09-07 10:47:52.201 [NOTICE] [wordpress_lsphp] stop worker processes
    2006-09-07 10:47:52.201 [NOTICE] [phpLsapi] stop worker processes
    2006-09-07 10:47:52.201 [NOTICE] [Rails:insane:/] stop worker processes
    2006-09-07 10:47:52.201 [NOTICE] [youtube_lsphp] stop worker processes
    2006-09-07 10:47:52.201 [NOTICE] [AdminPHP] stop worker processes


    And here is another error file that was created:

    2006-09-07 09:07:57.077 [NOTICE] Loading LiteSpeed/2.2 Standard ...
    2006-09-07 09:07:57.180 [NOTICE] [ADMIN] server socket: 127.0.0.1:7520
    2006-09-07 09:07:57.180 [NOTICE] Loading configuration from /opt/lsws/conf/httpd_config.xml ...
    2006-09-07 09:07:57.181 [INFO] old priority: 0, new priority: 0
    2006-09-07 09:07:57.181 [INFO] [config:server:basic] For better obscurity, server version number is hidden in the response header.
    2006-09-07 09:07:57.184 [NOTICE] The maximum number of file descriptor limit is set to 1024.
    2006-09-07 09:07:57.214 [WARN] [config:vhost:wordpress] Uid of /opt/lsws/www/wordpress/public_html/ is smaller than minimum requirement 11, use server uid!
    2006-09-07 09:07:57.214 [WARN] [config:vhost:wordpress] Gid of /opt/lsws/www/wordpress/public_html/ is smaller than minimum requirement 10, use server gid!
    2006-09-07 09:07:57.214 [ERROR] [config:vhost:wordpress:context:/cgi-bin/] Path for context location is invalid: /opt/lsws/www/wordpress/cgi-bin/
    2006-09-07 09:07:57.215 [WARN] [config:vhost:youtube] Uid of /opt/lsws/www/youtube/public_html/ is smaller than minimum requirement 11, use server uid!
    2006-09-07 09:07:57.215 [WARN] [config:vhost:youtube] Gid of /opt/lsws/www/youtube/public_html/ is smaller than minimum requirement 10, use server gid!
    2006-09-07 09:07:57.215 [ERROR] [config:vhost:youtube:context:/cgi-bin/] Path for context location is invalid: /opt/lsws/www/youtube/cgi-bin/
    2006-09-07 09:07:57.220 [NOTICE] [child: 4316] Successfully change current user to nobody
    2006-09-07 09:07:57.221 [NOTICE] [AutoRestarter] new child process with pid=4316 is forked!
    2006-09-07 09:07:57.221 [NOTICE] [Child: 4316] Core dump is enabled.
    2006-09-07 09:07:57.222 [NOTICE] [Child: 4316] Setup swapping space...
    2006-09-07 09:07:57.233 [NOTICE] [Child: 4316] LiteSpeed/2.2 Standard starts successfully!
    2006-09-07 09:07:58.000 [NOTICE] [AutoUpdate] Checking for new releases...
    2006-09-07 09:07:58.288 [INFO] [AutoUpdate] No new update.
    2006-09-07 09:08:16.425 [INFO] [82.120.76.254:3128-0#youtube] Index file is not available in [/opt/lsws/www/youtube/public_html/]
    2006-09-07 09:10:41.492 [INFO] [82.120.76.254:3204-0#youtube] File not found [/opt/lsws/www/youtube/public_html/test_ffmpeg.pp]
    2006-09-07 09:10:44.922 [NOTICE] [82.120.76.254:3204-1#youtube:lsapi] [STDERR] PHP Warning: PHP Startup: Unable to load dynamic library './msql.so' - ./msql.so: cannot open shared object file: No such file or directory in Unknown on line 0
    2006-09-07 09:10:44.966 [NOTICE] [82.120.76.254:3204-1#youtube:lsapi] [STDERR] PHP Warning: dl() [<a href='function.dl'>function.dl</a>]: Unable to load dynamic library './ffmpeg.so' - ./ffmpeg.so: cannot open shared object file: No such file or directory in /opt/lsws/www/youtube/public_html/test_ffmpeg.php on line 17
    2006-09-07 09:11:45.007 [NOTICE] [youtube_lsphp] stop worker processes
    2006-09-07 09:22:29.969 [INFO] [82.120.76.254:3547-0#youtube] Index file is not available in [/opt/lsws/www/youtube/public_html/]
    2006-09-07 09:22:36.299 [NOTICE] [82.120.76.254:3549-0#youtube] [STDERR] PHP Warning: PHP Startup: Unable to load dynamic library './msql.so' - ./msql.so: cannot open shared object file: No such file or directory in Unknown on line 0
    2006-09-07 09:22:36.303 [NOTICE] [82.120.76.254:3549-0#youtube] [STDERR] PHP Warning: dl() [<a href='function.dl'>function.dl</a>]: Unable to load dynamic library './ffmpeg.so' - ./ffmpeg.so: cannot open shared object file: No such file or directory in /opt/lsws/www/youtube/public_html/test_ffmpeg.php on line 17
    2006-09-07 09:23:37.004 [NOTICE] [youtube_lsphp] stop worker processes
    2006-09-07 10:47:51.409 [NOTICE] Server restart request from admin interface!
    2006-09-07 10:47:51.409 [NOTICE] [Child: 4316] Start shutting down gracefully ...
    2006-09-07 10:47:51.409 [INFO] Stop listener *:80.
    2006-09-07 10:47:51.409 [INFO] Stop listener *:7080.
    2006-09-07 10:47:52.202 [NOTICE] [AutoRestarter] cleanup children processes and unix sockets belong to process 4316 !
    2006-09-07 10:47:52.305 [NOTICE] [AutoRestarter] child process with pid=4316 exited with status=0!
    2006-09-07 10:47:52.305 [NOTICE] [PID:4311] Server Stopped!
  2. mistwang

    mistwang LiteSpeed Staff

    Have you added a listener at 209.135.157.125:7080? it conflicts with the default listener "*:7080". And when you change a listener from a wildcard address to a specific IP on the same port, you need to restart it manually. After the manual restart, the "graceful restart" via web interface will work properly.
  3. sid137

    sid137 New Member

    hmm.. I don't see any explicit listing for new listeners under in Server Configuration -> Listeners, or Server Configuration -> Listeners -> Default (which is set to port 80)

    The only place where I see something that explicitly says 7080 is under WebAdmin Configuration -> Admin Listeners Where there's an entry "Admin Listener set to port 7080

    Should I erase the entry under Webadmin Config, or is there perhaps something messed up in a configuration file somewhere that I can change?

    Thanks,
    -Sidney
  4. mistwang

    mistwang LiteSpeed Staff

    Maybe it has something to do with the VPS. bind to "*:7080" actually only bind to "209.135.157.125:7080", maybe changing the listener configuration from "*:<port>" to "209.135.157.125:<port>" can fix this problem.
  5. sid137

    sid137 New Member

    It looks like that might have fixed it - specifying the IP address. Thank you Misty!

    -Sidney

Share This Page