Adding a subdomain crashes Litespeed

Discussion in 'Install/Configuration' started by viperz, May 31, 2018.

  1. viperz

    viperz Member

    I have tried 4 times to add a sub domain on a production server which has no test environment and every time adding sub domain crashes the litespeed. The server ran earlier Apache2 and is migrated.

    I have done the following steps:
    1.) Added folders
    2.) Added listener
    3.) Added virtual host
    4.) Added A name record for subdomain

    Unfortunately this is a production server and debugging level had been set to none so I have only a clue that last thing what has happened is the reading of vconf file and after that there is no log.

    I am not an IT person so I am out of my depth here but currently only one who at least know how to use shell access o_O
    I wouldn't want to crash the server again because I did it all ready 4 times :p
    So how should I proceed? Put the debugging to high and try 5:th time?
     
  2. Tishu

    Tishu Well-Known Member

    Hello,
    Can you tell us your OS and did you use LS ent ot OLS?
     
  3. viperz

    viperz Member

    Hi,
    Distributor ID: Ubuntu
    Description: Ubuntu 16.04.3 LTS
    Release: 16.04
    Codename: xenial

    CURRENT VERSION:
    OpenLiteSpeed 1.4.29

    Performance seems very nice so perhaps next version is enterprise if we can sort this out.
     
  4. viperz

    viperz Member

    lsrestart log revealed:
    0LSWS does not restart properly, check port 7080

    But the port is open and everything works fine with 1 domain.
     
  5. Tishu

    Tishu Well-Known Member

    Hello again,

    Can you update to the latest version and check again.
     
  6. viperz

    viperz Member

    Hi,

    I upgraded to latest and tested and it crashed again. I don't know what I am missing.
     
  7. Pong

    Pong Well-Known Member Staff Member

    You have upgraded to 1.4.32, right? When you say "Crash", is there any core file generated? or LiteSpeed stop running (ps -ef | grep litespeed)? or domain unresponsive?
     
    Last edited by a moderator: Jun 1, 2018
  8. viperz

    viperz Member

    Hi,

    yes I upgraded to 1.4.32 and crash means Litespeed process died netstat -ntlp at least didn't show any Litespeed pids. Stderror log shows only an error related to backup processes related to backup program. lsrestart.log shows:
    Code:
    Thu May 31 01:27:23 PDT 2018
    start, LSWS running: 0
    LSWS does not restart properly, check port 7080
    check port 7080 after kill all litespeed processes
    Thu May 31 01:30:27 PDT 2018
    start, LSWS running: 0
    LSWS does not restart properly, check port 7080
    check port 7080 after kill all litespeed processes
    Thu May 31 01:31:52 PDT 2018
    start, LSWS running: 0
    LSWS does not restart properly, check port 7080
    check port 7080 after kill all litespeed processes
    Thu May 31 01:36:30 PDT 2018
    start, LSWS running: 0
    Thu May 31 06:57:16 PDT 2018
    stop, LSWS running: 1
    Thu May 31 06:57:54 PDT 2018
    start, LSWS running: 0
    Thu May 31 07:07:29 PDT 2018
    start, LSWS running: 0
    LSWS does not restart properly, check port 7080
    check port 7080 after kill all litespeed processes
    Thu May 31 07:09:11 PDT 2018
    start, LSWS running: 0
    LSWS does not restart properly, check port 7080
    check port 7080 after kill all litespeed processes
    Thu May 31 07:30:13 PDT 2018
    start, LSWS running: 0
    So not very much to start with. And port 7080 is open. Perhaps some Litespeed process was still hanging out there and restart caused it to spit that error.
     
    Last edited by a moderator: Jun 1, 2018
  9. Tishu

    Tishu Well-Known Member

    Hello again,

    From the server config -> log can you rise the debug log to High, stop LSWS, check if there is LS related processes open and restart it after that.
     
  10. viperz

    viperz Member

    Hello Tishu,

    sorry for my late answer but I was sick.

    I did like you asked and there was no litespeed related processes.

    Error log showed nothin suspicious:
    Code:
    lastRead: 0, m_ssl.lastWrite: 0
    2018-06-05 08:32:40.420033 [DEBUG] [84.253.221.66:17376] [SSL] SSLAgain()!
    2018-06-05 08:32:40.420036 [DEBUG] accept
    2018-06-05 08:32:40.420136 [DEBUG] SSL_accept rc: 1
    2018-06-05 08:32:40.420142 [DEBUG] SSL_accept worked - move to connected status
    2018-06-05 08:32:40.420145 [DEBUG] [84.253.221.66:17376] [SSL] accepted!
    2018-06-05 08:32:40.420150 [DEBUG] [84.253.221.66:17376] Next Protocol Negotiation result: HTTP/2
    2018-06-05 08:32:40.420168 [DEBUG] [84.253.221.66:17376:HTTP2] NtwkIOLink::continueRead()...
    2018-06-05 08:32:40.420171 [DEBUG] [84.253.221.66:17376:HTTP2] Read resumed!
    2018-06-05 08:32:40.420190 [DEBUG] [84.253.221.66:17376:HTTP2] NtwkIOLink::handleEvents() events=1!
    2018-06-05 08:32:40.420194 [DEBUG] [84.253.221.66:17376:HTTP2] onReadSSL(), none, wantRead: 4, wantWrite: 0, allowWrite: 1, allowRead: 1, m_ssl.wantRead: 1, m_ssl.wantWrite: 0, m_ssl.lastRead: 0, m_ssl.lastWrite: 0
    2018-06-05 08:32:40.420203 [DEBUG] [84.253.221.66:17376:HTTP2] Read() returned -1
    2018-06-05 08:32:40.420270 [DEBUG] [84.253.221.66:17376:HTTP2] NtwkIOLink::suspendRead()...
    2018-06-05 08:32:40.420275 [DEBUG] [84.253.221.66:17376:HTTP2] H2Connection::eek:nCloseEx()
    2018-06-05 08:32:40.420279 [DEBUG] [84.253.221.66:17376:HTTP2] H2Connection::recycle()
    2018-06-05 08:32:40.420286 [DEBUG] [84.253.221.66:17376:HTTP2] Shutting down SSL ...
    2018-06-05 08:32:40.420302 [DEBUG] [84.253.221.66:17376:HTTP2] Close socket ...
    2018-06-05 08:32:40.420311 [DEBUG] [84.253.221.66:17376:HTTP2] Available Connections: 2000, concurrent conn: 0
    2018-06-05 08:32:40.420350 [DEBUG] [84.253.221.66:17376:HTTP2] Recycle NtwkIoLink
    2018-06-05 08:32:40.642817 [DEBUG] [*:7080] New connection from 84.253.221.66:17377.
    2018-06-05 08:32:40.642886 [DEBUG] beginBIO, this: 0x10e9750
    2018-06-05 08:32:40.642897 [DEBUG] beginBIO - Ok
    2018-06-05 08:32:40.642904 [DEBUG] [84.253.221.66:17377] concurrent conn: 1
    2018-06-05 08:32:40.642908 [DEBUG] [84.253.221.66:17377] NtwkIOLink::handleEvents() events=1!
    2018-06-05 08:32:40.642914 [DEBUG] [84.253.221.66:17377] onReadSSL(), none, wantRead: 0, wantWrite: 0, allowWrite: 1, allowRead: 1, m_ssl.wantRead: 1, m_ssl.wantWrite: 0, m_ssl.lastRead: 0, m_ssl.lastWrite: 0
    2018-06-05 08:32:40.642918 [DEBUG] [84.253.221.66:17377] [SSL] SSLAgain()!
    2018-06-05 08:32:40.642941 [DEBUG] accept
    2018-06-05 08:32:40.642945 [DEBUG] Accept want to read more data, so read and save
    2018-06-05 08:32:40.642948 [DEBUG] readBIO state: 0x3000
    2018-06-05 08:32:40.642951 [DEBUG] readBIO buffered: 0
    2018-06-05 08:32:40.642961 [DEBUG] readBIO received 517 bytes
    2018-06-05 08:32:40.643111 [DEBUG] SSL_accept rc: -1
    2018-06-05 08:32:40.643121 [DEBUG] accept wants read more data
    2018-06-05 08:32:40.643128 [DEBUG] remove recv BIO layer.
    2018-06-05 08:32:40.643138 [DEBUG] [84.253.221.66:17377] setSSLAgain(), CR, wantRead: 0, wantWrite: 0, allowWrite: 1, allowRead: 1, m_ssl.wantRead: 1, m_ssl.wantWrite: 0, m_ssl.lastRead: 0, m_ssl.lastWrite: 0
    2018-06-05 08:32:40.643148 [DEBUG] [84.253.221.66:17377] setSSLAgain(), SW, wantRead: 0, wantWrite: 0, allowWrite: 1, allowRead: 1, m_ssl.wantRead: 1, m_ssl.wantWrite: 0, m_ssl.lastRead: 0, m_ssl.lastWrite: 0
    2018-06-05 08:32:40.643155 [DEBUG] [*:7080] 1 connections accepted!
    2018-06-05 08:32:40.856903 [DEBUG] [84.253.221.66:17377] NtwkIOLink::handleEvents() events=1!
    2018-06-05 08:32:40.856989 [DEBUG] [84.253.221.66:17377] onReadSSL(), none, wantRead: 0, wantWrite: 0, allowWrite: 1, allowRead: 1, m_ssl.wantRead: 1, m_ssl.wantWrite: 0, m_ssl.lastRead: 0, m_ssl.lastWrite: 0
    2018-06-05 08:32:40.856996 [DEBUG] [84.253.221.66:17377] [SSL] SSLAgain()!
    2018-06-05 08:32:40.857002 [DEBUG] accept
    2018-06-05 08:32:40.857120 [DEBUG] SSL_accept rc: 1
    2018-06-05 08:32:40.857127 [DEBUG] SSL_accept worked - move to connected status
    2018-06-05 08:32:40.857133 [DEBUG] [84.253.221.66:17377] [SSL] accepted!
    2018-06-05 08:32:40.857142 [DEBUG] [84.253.221.66:17377] Next Protocol Negotiation result: HTTP/2
    2018-06-05 08:32:40.857168 [DEBUG] [84.253.221.66:17377:HTTP2] NtwkIOLink::continueRead()...
    2018-06-05 08:32:40.857174 [DEBUG] [84.253.221.66:17377:HTTP2] Read resumed!
    2018-06-05 08:32:40.857189 [DEBUG] [84.253.221.66:17377:HTTP2] NtwkIOLink::handleEvents() events=1!
    2018-06-05 08:32:40.857197 [DEBUG] [84.253.221.66:17377:HTTP2] onReadSSL(), none, wantRead: 4, wantWrite: 0, allowWrite: 1, allowRead: 1, m_ssl.wantRead: 1, m_ssl.wantWrite: 0, m_ssl.lastRead: 0, m_ssl.lastWrite: 0
    2018-06-05 08:32:40.857232 [DEBUG] [84.253.221.66:17377:HTTP2] Read() returned 64
    2018-06-05 08:32:40.857241 [DEBUG] [84.253.221.66:17377:HTTP2] Frame type 4, size: 18
    2018-06-05 08:32:40.857250 [DEBUG] [84.253.221.66:17377:HTTP2-0] Received SETTINGS, size: 18, flag: 0x0, Message: ''
    2018-06-05 08:32:40.857258 [DEBUG] [84.253.221.66:17377:HTTP2] SETTINGS_HEADER_TABLE_SIZE(1) value: 65536
    2018-06-05 08:32:40.857266 [DEBUG] [84.253.221.66:17377:HTTP2] SETTINGS_MAX_CONCURRENT_STREAMS(3) value: 1000
    2018-06-05 08:32:40.857270 [DEBUG] [84.253.221.66:17377:HTTP2] SETTINGS_INITIAL_WINDOW_SIZE(4) value: 6291456
    2018-06-05 08:32:40.857276 [DEBUG] [84.253.221.66:17377:HTTP2] NtwkIOLink::continueWrite()...
    2018-06-05 08:32:40.857280 [DEBUG] [84.253.221.66:17377:HTTP2] Write resumed!
    2018-06-05 08:32:40.857285 [DEBUG] [84.253.221.66:17377:HTTP2] Send SETTING frame, MAX_CONCURRENT_STREAMS: 100, INITIAL_WINDOW_SIZE: 262140
    2018-06-05 08:32:40.857290 [DEBUG] [84.253.221.66:17377:HTTP2-0] send WINDOW_UPDATE frame, value: 196605
    2018-06-05 08:32:40.857295 [DEBUG] [84.253.221.66:17377:HTTP2-0] send SETTINGS frame, with Flag: 1
    2018-06-05 08:32:40.857299 [DEBUG] [84.253.221.66:17377:HTTP2] Frame type 8, size: 4
    2018-06-05 08:32:40.857304 [DEBUG] [84.253.221.66:17377:HTTP2-0] Received WINDOW_UPDATE, size: 4, flag: 0x0, Message: ''
    2018-06-05 08:32:40.857309 [DEBUG] [84.253.221.66:17377:HTTP2] Session WINDOW_UPDATE: 15663105, current window size: 65535, new: 15728640
    2018-06-05 08:32:40.857322 [DEBUG] [84.253.221.66:17377:HTTP2] Read() returned 414
    2018-06-05 08:32:40.857327 [DEBUG] [84.253.221.66:17377:HTTP2] Frame type 1, size: 405
    2018-06-05 08:32:40.857332 [DEBUG] [84.253.221.66:17377:HTTP2-1] Received HEADERS, size: 405, flag: 0x25, Message: ''
    2018-06-05 08:32:40.857423 [DEBUG] [84.253.221.66:17377:HTTP2-1] getNewStream(), stream map size: 0, shutdown streams: 0, flag: 37
    2018-06-05 08:32:40.857435 [DEBUG] [84.253.221.66:17377:HTTP2-1] H2Stream::init(), id: 1, priority: 1, flag: 0.
    2018-06-05 08:32:40.857477 [DEBUG] [84.253.221.66:17377:HTTP2-1] HttpSession::eek:nReadEx(), state: 1!
    2018-06-05 08:32:40.857483 [DEBUG] [84.253.221.66:17377:HTTP2-1] readToHeaderBuf().
    2018-06-05 08:32:40.857489 [DEBUG] [84.253.221.66:17377:HTTP2-1] Read 607 bytes to header buffer.
    2018-06-05 08:32:40.857512 [DEBUG] [84.253.221.66:17377:HTTP2-1] processHeader() returned 0, header state: 3.
    2018-06-05 08:32:40.857531 [DEBUG] [84.253.221.66:17377:HTTP2-1#_AdminVHost] New request:
        Method=[GET], URI=[/view/ajax_data.php],
        QueryString=[id=pid_load]
        Content Length=0
    2018-06-05 08:32:40.857544 [DEBUG] [84.253.221.66:17377:HTTP2-1#_AdminVHost] Find context with URI: [/], location: [/usr/local/lsws/admin/html/].
    2018-06-05 08:32:40.857591 [DEBUG] [84.253.221.66:17377:HTTP2-1#_AdminVHost] Check Symbolic link for [/usr/local/lsws/admin/html/view/ajax_data.php] is successful, access to target [/usr/local/lsws/admin/html/view/ajax_data.php] is granted
    2018-06-05 08:32:40.857606 [DEBUG] [84.253.221.66:17377:HTTP2-1#_AdminVHost] Find handler [AdminPHP] for [.php].
    2018-06-05 08:32:40.857615 [DEBUG] [84.253.221.66:17377:HTTP2-1#_AdminVHost] processContextPath() returned 0.
    2018-06-05 08:32:40.857625 [DEBUG] [84.253.221.66:17377:HTTP2-1#_AdminVHost] Run lsapi processor.
    2018-06-05 08:32:40.857634 [DEBUG] [UDS://tmp/lshttpd/admin_php.sock] connection available!
    2018-06-05 08:32:40.857641 [DEBUG] [UDS://tmp/lshttpd/admin_php.sock] request [84.253.221.66:17377:HTTP2-1#_AdminVHost:lsapi] is assigned with connection!
    2018-06-05 08:32:40.857651 [DEBUG] [84.253.221.66:17377:HTTP2-1#_AdminVHost:lsapi] [ExtConn] reconnect()
    2018-06-05 08:32:40.858820 [DEBUG] [AdminPHP] add child process pid: 16244
    Brgds,
    Viperz
     
    Last edited by a moderator: Jun 6, 2018
  11. Pong

    Pong Well-Known Member Staff Member

    Can you login a ticket with us with your tmp root access to your server to take a look?
     
  12. viperz

    viperz Member

    The problem is now somewhat solved. Seemed to be an corrupted php installation. Thank you for your help. I really appreciate it.
     
  13. Pong

    Pong Well-Known Member Staff Member

    Glad to hear the issue has been resolved. We are more than happy to assist you.
     

Share This Page