ccss log

gistfile1.txt

2018-12-20 14:55:41.316160 [DEBUG] [*:443] HttpListener::handleEvents(), fd: 18, event: 1, revents: 1.
2018-12-20 14:55:41.316266 [DEBUG] [100.1.239.195:61947] concurrent conn: 1
2018-12-20 14:55:41.316273 [DEBUG] [100.1.239.195:61947] NtwkIOLink::handleEvents() events=1!
2018-12-20 14:55:41.316275 [DEBUG] [100.1.239.195:61947] [SSL] SSLAgain()!
2018-12-20 14:55:41.325822 [DEBUG] [100.1.239.195:61947] NtwkIOLink::handleEvents() events=1!
2018-12-20 14:55:41.325833 [DEBUG] [100.1.239.195:61947] [SSL] SSLAgain()!
2018-12-20 14:55:41.325984 [DEBUG] [100.1.239.195:61947] [SSL] accepted!
2018-12-20 14:55:41.325989 [DEBUG] [100.1.239.195:61947] Next Protocol Negociation result: HTTP2
2018-12-20 14:55:41.326001 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::continueRead()...
2018-12-20 14:55:41.326003 [DEBUG] [100.1.239.195:61947:HTTP2] read resumed!
2018-12-20 14:55:41.326013 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::handleEvents() events=1!
2018-12-20 14:55:41.326025 [DEBUG] [100.1.239.195:61947:HTTP2] read() return 64
2018-12-20 14:55:41.326030 [DEBUG] [100.1.239.195:61947:HTTP2] frame type 4, size: 18
2018-12-20 14:55:41.326035 [DEBUG] [100.1.239.195:61947:HTTP2-0] Received SETTINGS, size: 18, flag: 0x0, Message: ''
2018-12-20 14:55:41.326038 [DEBUG] [100.1.239.195:61947:HTTP2] SETTINGS_HEADER_TABLE_SIZE(1) value: 65536
2018-12-20 14:55:41.326040 [DEBUG] [100.1.239.195:61947:HTTP2] SETTINGS_MAX_CONCURRENT_STREAMS(3) value: 1000
2018-12-20 14:55:41.326042 [DEBUG] [100.1.239.195:61947:HTTP2] SETTINGS_INITIAL_WINDOW_SIZE(4) value: 6291456
2018-12-20 14:55:41.326045 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::continueWrite()...
2018-12-20 14:55:41.326046 [DEBUG] [100.1.239.195:61947:HTTP2] write resumed!
2018-12-20 14:55:41.326049 [DEBUG] [100.1.239.195:61947:HTTP2] frame type 8, size: 4
2018-12-20 14:55:41.326052 [DEBUG] [100.1.239.195:61947:HTTP2-0] Received WINDOW_UPDATE, size: 4, flag: 0x0, Message: ''
2018-12-20 14:55:41.326055 [DEBUG] [100.1.239.195:61947:HTTP2] session WINDOW_UPDATE: 15663105, current window size: 65535, new: 15728640 
2018-12-20 14:55:41.326063 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::handleEvents() events=5!
2018-12-20 14:55:41.326073 [DEBUG] [100.1.239.195:61947:HTTP2] read() return 958
2018-12-20 14:55:41.326075 [DEBUG] [100.1.239.195:61947:HTTP2] frame type 1, size: 949
2018-12-20 14:55:41.326077 [DEBUG] [100.1.239.195:61947:HTTP2-1] Received HEADERS, size: 949, flag: 0x25, Message: ''
2018-12-20 14:55:41.326145 [DEBUG] [100.1.239.195:61947:HTTP2-1] H2Stream::init(), id: 1, priority: 0, flag: 0. 
2018-12-20 14:55:41.326152 [DEBUG] [100.1.239.195:61947:HTTP2-1] onInitConnected(): HSPS_START -> HSPS_READ_REQ_HEADER
2018-12-20 14:55:41.326154 [DEBUG] [100.1.239.195:61947:HTTP2-1] H2Stream::continueRead()
2018-12-20 14:55:41.326156 [DEBUG] [100.1.239.195:61947:HTTP2-1] HttpSession::onReadEx(), state: 1!
2018-12-20 14:55:41.326158 [DEBUG] [100.1.239.195:61947:HTTP2-1] Run State: HSPS_READ_REQ_HEADER
2018-12-20 14:55:41.326160 [DEBUG] [100.1.239.195:61947:HTTP2-1] readToHeaderBuf(). 
2018-12-20 14:55:41.326164 [DEBUG] [100.1.239.195:61947:HTTP2-1] read 1331 bytes to header buffer
2018-12-20 14:55:41.326174 [DEBUG] [100.1.239.195:61947:HTTP2-1] client accept GZIP encoding.
2018-12-20 14:55:41.326177 [DEBUG] [100.1.239.195:61947:HTTP2-1] client accept BROTLI encoding.
2018-12-20 14:55:41.326180 [DEBUG] [100.1.239.195:61947:HTTP2-1] processHeader() return 0, header state: 3. 
2018-12-20 14:55:41.326182 [DEBUG] [100.1.239.195:61947:HTTP2-1] readToHeaderBuf(): HSPS_READ_REQ_HEADER -> HSPS_NEW_REQ
2018-12-20 14:55:41.326185 [DEBUG] [100.1.239.195:61947:HTTP2-1] processNewReq(), request header buffer size: 1335, header used: 1335, processed: 1335.
2018-12-20 14:55:41.326189 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] processNewReqInit(): HSPS_NEW_REQ -> HSPS_HKPT_HTTP_BEGIN
2018-12-20 14:55:41.326192 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] runEventHkpt(): HSPS_HKPT_HTTP_BEGIN -> HSPS_HKPT_RCVD_REQ_HEADER
2018-12-20 14:55:41.326194 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] runEventHkpt(): HSPS_HKPT_RCVD_REQ_HEADER -> HSPS_TRY_FINISH_REQ_BODY
2018-12-20 14:55:41.326196 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] reqBodyDone(): HSPS_TRY_FINISH_REQ_BODY -> HSPS_HKPT_RCVD_REQ_BODY
2018-12-20 14:55:41.326198 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] runEventHkpt(): HSPS_HKPT_RCVD_REQ_BODY -> HSPS_PROCESS_NEW_URI
2018-12-20 14:55:41.326202 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] [CACHE] Assign no cache store, cache root: N/A
2018-12-20 14:55:41.326205 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] [CACHE] Early lookup, cache config: 0x10fe710, private: 0, public: 0
2018-12-20 14:55:41.326207 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] processVHostRewrite(): HSPS_PROCESS_NEW_URI -> HSPS_CONTEXT_MAP
2018-12-20 14:55:41.326212 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] processContext() returned -2.
2018-12-20 14:55:41.326214 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] [CACHE] turned off cache by context cache config
2018-12-20 14:55:41.326216 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] processContextMap(): HSPS_CONTEXT_MAP -> HSPS_TEST_MODSEC
2018-12-20 14:55:41.326218 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] Run State: HSPS_TEST_MODSEC
2018-12-20 14:55:41.326220 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] [CACHE] tryCacheHandler()
2018-12-20 14:55:41.326222 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] testModSecReqHeaderPhase(): HSPS_TEST_MODSEC -> HSPS_CHECK_AUTH_ACCESS
2018-12-20 14:55:41.326225 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] checkAuthAccess(): HSPS_CHECK_AUTH_ACCESS -> HSPS_HKPT_HTTP_AUTH
2018-12-20 14:55:41.326226 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] Run State: HSPS_HKPT_HTTP_AUTH
2018-12-20 14:55:41.326228 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] runEventHkpt(): HSPS_HKPT_HTTP_AUTH -> HSPS_AUTH_DONE
2018-12-20 14:55:41.326230 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] authAccessDone(): HSPS_AUTH_DONE -> HSPS_BEGIN_HANDLER_PROCESS
2018-12-20 14:55:41.326233 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] handlerProcess(): HSPS_BEGIN_HANDLER_PROCESS -> HSPS_HANDLER_PROCESSING
2018-12-20 14:55:41.326236 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls] run proxy processor.
2018-12-20 14:55:41.326241 [DEBUG] [127.0.0.1:3030] connection available!
2018-12-20 14:55:41.326244 [DEBUG] [127.0.0.1:3030] request [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] is assigned with connection [0x11272b0]!
2018-12-20 14:55:41.326247 [DEBUG] [idle] assignReq to ExtConn: 0x11272b0
2018-12-20 14:55:41.326250 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] [ExtConn] reconnect()
2018-12-20 14:55:41.326337 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] [ExtConn] connecting to [127.0.0.1:3030]...
2018-12-20 14:55:41.326348 [DEBUG] [100.1.239.195:61947:HTTP2] SSL wpending: 0
2018-12-20 14:55:41.326373 [DEBUG] SSL_write( 0x110fc48, 0x7ffddd6df560, 49) return 49, pending 0
2018-12-20 14:55:41.326376 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::flushSSL()...
2018-12-20 14:55:41.326379 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::suspendWrite()...
2018-12-20 14:55:41.326386 [DEBUG] EdStream::handleEvent(), fd: 31, event: 4
2018-12-20 14:55:41.326388 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] ExtConn::onWrite()
2018-12-20 14:55:41.326397 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] connected to [127.0.0.1:3030] on local addres [127.0.0.1:48322]!
2018-12-20 14:55:41.326403 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] request header is done
2018-12-20 14:55:41.326406 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] ProxyConn::continueRead(), fd: 31
2018-12-20 14:55:41.326407 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Request body done!
2018-12-20 14:55:41.326427 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] ExtConn::onEventDone(), fd: 31, state: 3, event: 4, revents: 4.
2018-12-20 14:55:41.327277 [DEBUG] EdStream::handleEvent(), fd: 31, event: 1
2018-12-20 14:55:41.327283 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] ExtConn::onRead(), state: 3
2018-12-20 14:55:41.327286 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] ProxyConn::doRead()
2018-12-20 14:55:41.327295 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] read Response 392 bytes, total resp header read: 392
2018-12-20 14:55:41.327299 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Response Header: 'X-Powered-By: Express'
2018-12-20 14:55:41.327304 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Response Header: 'Content-Security-Policy: default-src 'self''
2018-12-20 14:55:41.327306 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Response Header: 'X-Content-Type-Options: nosniff'
2018-12-20 14:55:41.327309 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Response Header: 'Content-Type: text/html; charset=utf-8'
2018-12-20 14:55:41.327317 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] content type: [text/html], pMIME: 0x108a7e0
2018-12-20 14:55:41.327320 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Response Header: 'Content-Length: 143'
2018-12-20 14:55:41.327323 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Response Header: 'Date: Thu, 20 Dec 2018 19:55:41 GMT'
2018-12-20 14:55:41.327326 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Response Header: 'Connection: keep-alive'
2018-12-20 14:55:41.327328 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] response header finished!
2018-12-20 14:55:41.327331 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Response body size of proxy reply is 143
2018-12-20 14:55:41.327333 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] process Response body 143 bytes
2018-12-20 14:55:41.327336 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] total response body read: 143
2018-12-20 14:55:41.327337 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] HttpExtConnector::processRespBodyData( 143 )
2018-12-20 14:55:41.327341 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] [CACHE] prepare page cache
2018-12-20 14:55:41.327343 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] [CACHE] store is not available
2018-12-20 14:55:41.327345 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] gzip flag: 3
2018-12-20 14:55:41.327349 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Append dyn resp body: 143 bytes, current buffer size: 143.
2018-12-20 14:55:41.327351 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] [EXT] EndResponse( endCode=0, protocolStatus=0 )
2018-12-20 14:55:41.327353 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] release ExtProcessor!
2018-12-20 14:55:41.327356 [DEBUG] [127.0.0.1:3030] add recycled connection to connection pool!
2018-12-20 14:55:41.327358 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] endResponse( 1 )
2018-12-20 14:55:41.327360 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] endResponseInternal()
2018-12-20 14:55:41.327362 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] HttpSession::flush()!
2018-12-20 14:55:41.327364 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] sendRespHeaders()
2018-12-20 14:55:41.327369 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::continueWrite()...
2018-12-20 14:55:41.327371 [DEBUG] [100.1.239.195:61947:HTTP2] write resumed!
2018-12-20 14:55:41.327399 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] sendDynBody() buffer: 0x7f028a5f4000, len: 143, sent 0
2018-12-20 14:55:41.327403 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] sent: 143, current window: 6291313
2018-12-20 14:55:41.327405 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] writeRespBody() sent: 143 
2018-12-20 14:55:41.327407 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] writeRespBody() return 143, Dyn response body sent: 143 
2018-12-20 14:55:41.327409 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] sendDynBody() buffer: 0x7f028a5f408f, len: 0, sent 143
2018-12-20 14:55:41.327411 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] flushBody() return 0
2018-12-20 14:55:41.327413 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] mark COMPLETE.
2018-12-20 14:55:41.327415 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] H2Stream::shutdown()
2018-12-20 14:55:41.327445 [DEBUG] SSL_write( 0x110fc48, 0x7ffddd6df4d0, 345) return 345, pending 0
2018-12-20 14:55:41.327450 [DEBUG] [EvtcbQue:schedule()] Obj=0x1103ff0 Session= 0x112c860 Param=(nil)
2018-12-20 14:55:41.327452 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] H2Stream::continueWrite()
2018-12-20 14:55:41.327455 [DEBUG] [idle] ExtConn::onEventDone(), fd: 31, state: 3, event: 1, revents: 0.
2018-12-20 14:55:41.327460 [DEBUG] [EvtcbQue:run() starts] Obj=0x1103ff0 Session= 0x112c860 Param=(nil)
2018-12-20 14:55:41.327462 [DEBUG] [EvtcbQue:runOne()] Obj=0x1103ff0 Session= 0x112c860 Param=(nil)
2018-12-20 14:55:41.327464 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] HttpSession::nextRequest()!
2018-12-20 14:55:41.327465 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] H2Stream::flush()
2018-12-20 14:55:41.327468 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] dyn processor in use 1073741823/1073741823
2018-12-20 14:55:41.327470 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] HttpExtConnector::cleanUp() ...
2018-12-20 14:55:41.327472 [DEBUG] [100.1.239.195:61947:HTTP2-1#cloud.wp.api.ls:proxy] Non-KeepAlive, CLOSING!
2018-12-20 14:55:41.327476 [DEBUG] [EvtcbQue:recycle()] Obj=0x1103ff0 Session= 0x112c860 Param=(nil)
2018-12-20 14:55:41.327481 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::handleEvents() events=4!
2018-12-20 14:55:41.327484 [DEBUG] [100.1.239.195:61947:HTTP2] SSL wpending: 0
2018-12-20 14:55:41.327489 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::suspendWrite()...
2018-12-20 14:55:41.335603 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::handleEvents() events=1!
2018-12-20 14:55:41.335650 [DEBUG] [100.1.239.195:61947:HTTP2] read() return 9
2018-12-20 14:55:41.335655 [DEBUG] [100.1.239.195:61947:HTTP2] frame type 4, size: 0
2018-12-20 14:55:41.335659 [DEBUG] [100.1.239.195:61947:HTTP2-0] Received SETTINGS, size: 0, flag: 0x1, Message: ''
2018-12-20 14:55:41.463704 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::handleEvents() events=1!
2018-12-20 14:55:41.463751 [DEBUG] [100.1.239.195:61947:HTTP2] read() return 133
2018-12-20 14:55:41.463755 [DEBUG] [100.1.239.195:61947:HTTP2] frame type 1, size: 124
2018-12-20 14:55:41.463762 [DEBUG] [100.1.239.195:61947:HTTP2-3] Received HEADERS, size: 124, flag: 0x25, Message: ''
2018-12-20 14:55:41.463802 [DEBUG] [100.1.239.195:61947:HTTP2-3] H2Stream::init(), id: 3, priority: 1, flag: 0. 
2018-12-20 14:55:41.463807 [DEBUG] [100.1.239.195:61947:HTTP2-3] onInitConnected(): HSPS_START -> HSPS_READ_REQ_HEADER
2018-12-20 14:55:41.463809 [DEBUG] [100.1.239.195:61947:HTTP2-3] H2Stream::continueRead()
2018-12-20 14:55:41.463811 [DEBUG] [100.1.239.195:61947:HTTP2-3] HttpSession::onReadEx(), state: 1!
2018-12-20 14:55:41.463814 [DEBUG] [100.1.239.195:61947:HTTP2-3] Run State: HSPS_READ_REQ_HEADER
2018-12-20 14:55:41.463816 [DEBUG] [100.1.239.195:61947:HTTP2-3] readToHeaderBuf(). 
2018-12-20 14:55:41.463820 [DEBUG] [100.1.239.195:61947:HTTP2-3] read 1330 bytes to header buffer
2018-12-20 14:55:41.463830 [DEBUG] [100.1.239.195:61947:HTTP2-3] client accept GZIP encoding.
2018-12-20 14:55:41.463832 [DEBUG] [100.1.239.195:61947:HTTP2-3] client accept BROTLI encoding.
2018-12-20 14:55:41.463836 [DEBUG] [100.1.239.195:61947:HTTP2-3] processHeader() return 0, header state: 3. 
2018-12-20 14:55:41.463838 [DEBUG] [100.1.239.195:61947:HTTP2-3] readToHeaderBuf(): HSPS_READ_REQ_HEADER -> HSPS_NEW_REQ
2018-12-20 14:55:41.463841 [DEBUG] [100.1.239.195:61947:HTTP2-3] processNewReq(), request header buffer size: 1334, header used: 1334, processed: 1334.
2018-12-20 14:55:41.463846 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] processNewReqInit(): HSPS_NEW_REQ -> HSPS_HKPT_HTTP_BEGIN
2018-12-20 14:55:41.463848 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] runEventHkpt(): HSPS_HKPT_HTTP_BEGIN -> HSPS_HKPT_RCVD_REQ_HEADER
2018-12-20 14:55:41.463850 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] runEventHkpt(): HSPS_HKPT_RCVD_REQ_HEADER -> HSPS_TRY_FINISH_REQ_BODY
2018-12-20 14:55:41.463852 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] reqBodyDone(): HSPS_TRY_FINISH_REQ_BODY -> HSPS_HKPT_RCVD_REQ_BODY
2018-12-20 14:55:41.463855 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] runEventHkpt(): HSPS_HKPT_RCVD_REQ_BODY -> HSPS_PROCESS_NEW_URI
2018-12-20 14:55:41.463858 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] processVHostRewrite(): HSPS_PROCESS_NEW_URI -> HSPS_CONTEXT_MAP
2018-12-20 14:55:41.463902 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] processContext() returned 0.
2018-12-20 14:55:41.463904 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] [CACHE] turned off cache by context cache config
2018-12-20 14:55:41.463911 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] processContextMap(): HSPS_CONTEXT_MAP -> HSPS_HKPT_URI_MAP
2018-12-20 14:55:41.463913 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] Run State: HSPS_HKPT_URI_MAP
2018-12-20 14:55:41.463915 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] runEventHkpt(): HSPS_HKPT_URI_MAP -> HSPS_FILE_MAP
2018-12-20 14:55:41.463922 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] processContextPath() return 0
2018-12-20 14:55:41.463925 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] processFileMap(): HSPS_FILE_MAP -> HSPS_TEST_MODSEC
2018-12-20 14:55:41.463927 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] testModSecReqHeaderPhase(): HSPS_TEST_MODSEC -> HSPS_CHECK_AUTH_ACCESS
2018-12-20 14:55:41.463930 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] checkAuthAccess(): HSPS_CHECK_AUTH_ACCESS -> HSPS_HKPT_HTTP_AUTH
2018-12-20 14:55:41.463932 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] Run State: HSPS_HKPT_HTTP_AUTH
2018-12-20 14:55:41.463934 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] runEventHkpt(): HSPS_HKPT_HTTP_AUTH -> HSPS_AUTH_DONE
2018-12-20 14:55:41.463936 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] authAccessDone(): HSPS_AUTH_DONE -> HSPS_BEGIN_HANDLER_PROCESS
2018-12-20 14:55:41.463938 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] handlerProcess(): HSPS_BEGIN_HANDLER_PROCESS -> HSPS_HANDLER_PROCESSING
2018-12-20 14:55:41.463978 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] readyCacheData() return 0
2018-12-20 14:55:41.463981 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] response header finished!
2018-12-20 14:55:41.463984 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] endResponse( 1 )
2018-12-20 14:55:41.463986 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] endResponseInternal()
2018-12-20 14:55:41.463988 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] HttpSession::flush()!
2018-12-20 14:55:41.463990 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] sendRespHeaders()
2018-12-20 14:55:41.463995 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::continueWrite()...
2018-12-20 14:55:41.463996 [DEBUG] [100.1.239.195:61947:HTTP2] write resumed!
2018-12-20 14:55:41.464020 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] sent: 470, current window: 6290986
2018-12-20 14:55:41.464022 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] writeRespBody() sent: 470 
2018-12-20 14:55:41.464024 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] flushBody() return 0
2018-12-20 14:55:41.464026 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] mark COMPLETE.
2018-12-20 14:55:41.464028 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] H2Stream::shutdown()
2018-12-20 14:55:41.464067 [DEBUG] SSL_write( 0x110fc48, 0x7ffddd6df1e0, 617) return 617, pending 0
2018-12-20 14:55:41.464071 [DEBUG] [EvtcbQue:schedule()] Obj=0x1103ff0 Session= 0x112a310 Param=(nil)
2018-12-20 14:55:41.464073 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] H2Stream::continueWrite()
2018-12-20 14:55:41.464076 [DEBUG] [EvtcbQue:runOne()] Obj=0x1103ff0 Session= 0x112a310 Param=(nil)
2018-12-20 14:55:41.464078 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] HttpSession::nextRequest()!
2018-12-20 14:55:41.464080 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] H2Stream::flush()
2018-12-20 14:55:41.464082 [DEBUG] [100.1.239.195:61947:HTTP2-3#cloud.wp.api.ls] Non-KeepAlive, CLOSING!
2018-12-20 14:55:41.464085 [DEBUG] [EvtcbQue:recycle()] Obj=0x1103ff0 Session= 0x112a310 Param=(nil)
2018-12-20 14:55:41.464092 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::suspendWrite()...
2018-12-20 14:55:41.464094 [DEBUG] [100.1.239.195:61947:HTTP2] NtwkIOLink::flushSSL()...