Adding a subdomain crashes Litespeed

#1
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?
 
#3
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
lsrestart log revealed:
0LSWS does not restart properly, check port 7080

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

Pong

Well-Known Member
Staff member
#7
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:
#8
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:

Tishu

Well-Known Member
#9
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
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:
Top