"pObj->m_pSession NULL" error log on WordPress Elementor

ego · Apr 13, 2020

i've searched for a solution to no avail, so perhaps someone may know where to go next?

This issue happens when opening multiple browser windows to posts using "Edit with Elementor" link on the admin for the post.

When opening 2 posts (despite slow loading) they fully load within their page editors.

However, when i try to open 6 different posts with "Edit with Elementor", the tabs appear to be loading ... then freeze mid-load and the server VM hangs endlessly. The only correction appears to be a force stop and start on the VM (cannot SSH in).

Specs:
Ubuntu 18.04.3 LTS
OpenLiteSpeed 1.6.12
PHP 7.3.13-2+bionic
WordPress 5.4
Elementor 2.9.7
Elementor Pro 2.9.2

Code:

2020-04-12 22:06:23.758314 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com]     Last-Modified: Sun, 12 Apr 2020 15:36:14 GMT
2020-04-12 22:06:23.758316 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com]     Content-Type: application/x-javascript
2020-04-12 22:06:23.758319 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com]     Content-Length: 15486
2020-04-12 22:06:23.758322 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com]     Accept-Ranges: bytes
2020-04-12 22:06:23.758325 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com]     Content-Encoding: br
2020-04-12 22:06:23.758327 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com]     Vary: Accept-Encoding
2020-04-12 22:06:23.758330 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com]     Date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.758333 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com]     Alt-Svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.758339 [DEBUG] [192.168.1.1:55674:HTTP2-635] sendRespHeaders()
2020-04-12 22:06:23.758345 [DEBUG] hpack encode: cache-control: public, max-age=604800
2020-04-12 22:06:23.758349 [DEBUG] hpack encode: expires: Sun, 19 Apr 2020 22:06:22 GMT
2020-04-12 22:06:23.758352 [DEBUG] hpack encode: etag: "15799-5e93356e-1bb4b3;br"
2020-04-12 22:06:23.758356 [DEBUG] hpack encode: last-modified: Sun, 12 Apr 2020 15:36:14 GMT
2020-04-12 22:06:23.758360 [DEBUG] hpack encode: content-type: application/x-javascript
2020-04-12 22:06:23.758363 [DEBUG] hpack encode: content-length: 15486
2020-04-12 22:06:23.758366 [DEBUG] hpack encode: accept-ranges: bytes
2020-04-12 22:06:23.758369 [DEBUG] hpack encode: content-encoding: br
2020-04-12 22:06:23.758372 [DEBUG] hpack encode: vary: Accept-Encoding
2020-04-12 22:06:23.758375 [DEBUG] hpack encode: date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.758379 [DEBUG] hpack encode: alt-svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.758387 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(50), buffered: 50, total buffer size = 15790
2020-04-12 22:06:23.758390 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] SendStaticFile()
2020-04-12 22:06:23.758401 [DEBUG] [192.168.1.1:55674:HTTP2-635] H2Stream::write(0xd1ee80, 15486, 0), weight: 1
2020-04-12 22:06:23.758404 [DEBUG] [192.168.1.1:55674:HTTP2-635] send DATA frame, FIN: 0, data: 0xd1ee80, len: 15486
2020-04-12 22:06:23.758408 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 15799
2020-04-12 22:06:23.758430 [DEBUG] [FDBIO] bio_fd_write: 0x148aae8, 16406 bytes on 25
2020-04-12 22:06:23.758433 [DEBUG] [FDBIO] ls_fdbio_buff_write, to write: 16406, used: 32812, sent: 0
2020-04-12 22:06:23.758440 [DEBUG] [FDBIO] lstls_buff_write, to write: 16406, finished: 16406, used: 49218, sent: 0
2020-04-12 22:06:23.758443 [DEBUG] SSL_write( 0x1489be8, 0x153b880, 16384) return 16384, pending 49218
2020-04-12 22:06:23.758447 [DEBUG] [192.168.1.1:55674:HTTP2] Need to write 16384 bytes, wrote 16384 bytes.
2020-04-12 22:06:23.758453 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(15486), buffered: 15486, total buffer size = 14901
2020-04-12 22:06:23.758456 [DEBUG] [192.168.1.1:55674:HTTP2-635] H2Stream::write(0xd1ee80, 15486, 0) return 15486
2020-04-12 22:06:23.758459 [DEBUG] [192.168.1.1:55674:HTTP2-635] sent: 15486, current window: 6275970
2020-04-12 22:06:23.758462 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] writeRespBodyDirect(): write(0xd1ee80, 15486) written 15486, total sent: 15486
2020-04-12 22:06:23.758465 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] writeRespBodyDirect() tried 15486 return 15486.
2020-04-12 22:06:23.758468 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] flushBody() return 0
2020-04-12 22:06:23.758471 [DEBUG] [192.168.1.1:55674:HTTP2-635] H2Stream::flush()
2020-04-12 22:06:23.758474 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] Set the HSS_COMPLETE flag.
2020-04-12 22:06:23.758477 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] mark HSS_COMPLETE.
2020-04-12 22:06:23.758480 [DEBUG] [192.168.1.1:55674:HTTP2-635] H2Stream::shutdown()
2020-04-12 22:06:23.758484 [DEBUG] [192.168.1.1:55674:HTTP2-635] send DATA frame, with Flag: 1
2020-04-12 22:06:23.758487 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 14910
2020-04-12 22:06:23.758490 [DEBUG] [192.168.1.1:55674:HTTP2-635] add to priority queue: 0
2020-04-12 22:06:23.758495 [DEBUG] [T0 evtcbnode_s* EvtcbQue::getNodeObj(evtcb_pf, const evtcbtail_t*, long int, void*)] returning pObj 0x1696b10 session 0x171be28 tailnode (nil)
2020-04-12 22:06:23.758499 [DEBUG] [T0 evtcbnode_s* EvtcbQue::schedule(evtcb_pf, const evtcbtail_t*, long int, void*, bool)] sched evtq cb for session 0x171be28 sn 2
2020-04-12 22:06:23.758503 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)(null)] Obj=0x1696b10 Session=0x171be28 lParam=2 pParam=(nil) cb=0x4fa230 seq=643 slot=3 sn=2
2020-04-12 22:06:23.758506 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] locking event slot 3
2020-04-12 22:06:23.758510 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] schedule cb for session 0x171be28 tail (nil) seq 643 slot 3 sn 2
2020-04-12 22:06:23.758513 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] 0x1696b10 appended
2020-04-12 22:06:23.758516 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] Tail for session 0x171be28 slot 3 was (nil) set to 0x1696b10
2020-04-12 22:06:23.758521 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] [static file cache] create cache.
2020-04-12 22:06:23.758526 [DEBUG] [T0 void HttpSession::closeSession()] sess seq now 643
2020-04-12 22:06:23.758530 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] [HTTP_END] run Hook function for [Module:cache] session=0x171be28
2020-04-12 22:06:23.758533 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] [HTTP_END] [Module:cache]  session=0x171be28 ret 0
2020-04-12 22:06:23.758544 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] checking m_curRunQ size 0
2020-04-12 22:06:23.758551 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)][header state] Obj=0x1696b10 Session=0x171be28 lParam=2 pParam=(nil) cb=0x4fa230 seq=643 slot=3 sn=3
2020-04-12 22:06:23.758555 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)][header state] Obj=0x1696a10 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.758559 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] removed 2 evtq cbs for session 0x171be28 tail 0x1696b10 sn 3 set Tail to NULL
2020-04-12 22:06:23.758562 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] calling removeSessionCb on this 0x171be20
2020-04-12 22:06:23.758565 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x171be28 seq 643 slot 3 tailnode NULL!
2020-04-12 22:06:23.758578 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] calling removeSessionCb on this 0x171be20
2020-04-12 22:06:23.758581 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x171be28 seq 643 slot 3 tailnode NULL!
2020-04-12 22:06:23.758584 [DEBUG] [T0 virtual void HttpSession::recycle()] sess seq now 644
2020-04-12 22:06:23.758587 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] HttpSession::recycle() 0x171be20
2020-04-12 22:06:23.758590 [DEBUG] [192.168.1.1:55674:HTTP2-635#example.com] [Tm] failed trylockMtRace.
2020-04-12 22:06:23.758598 [DEBUG] [192.168.1.1:55674:HTTP2-635] recycleStream(), stream map size: 14
2020-04-12 22:06:23.758602 [DEBUG] [192.168.1.1:55674:HTTP2] process priority queue [2]
2020-04-12 22:06:23.758605 [DEBUG] [192.168.1.1:55674:HTTP2] process priority queue [3]
2020-04-12 22:06:23.758607 [DEBUG] [192.168.1.1:55674:HTTP2] process priority queue [4]
2020-04-12 22:06:23.758610 [DEBUG] [192.168.1.1:55674:HTTP2] process priority queue [5]
2020-04-12 22:06:23.758613 [DEBUG] [192.168.1.1:55674:HTTP2] process priority queue [6]
2020-04-12 22:06:23.758616 [DEBUG] [192.168.1.1:55674:HTTP2] process priority queue [7]
2020-04-12 22:06:23.758627 [DEBUG] [FDBIO] bio_fd_write: 0x148aae8, 14932 bytes on 25
2020-04-12 22:06:23.758630 [DEBUG] [FDBIO] ls_fdbio_buff_write, to write: 14932, used: 49218, sent: 0
2020-04-12 22:06:23.758636 [DEBUG] [FDBIO] lstls_buff_write, to write: 14932, finished: 14932, used: 64150, sent: 0
2020-04-12 22:06:23.758639 [DEBUG] SSL_write( 0x1489be8, 0x153b880, 14910) return 14910, pending 64150
2020-04-12 22:06:23.758642 [DEBUG] [192.168.1.1:55674:HTTP2] Need to write 14910 bytes, wrote 14910 bytes.
2020-04-12 22:06:23.758646 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::flush...
2020-04-12 22:06:23.758649 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::flushSslWpending()...
2020-04-12 22:06:23.758651 [DEBUG] [192.168.1.1:55674:HTTP2] SSL wpending: 64150
2020-04-12 22:06:23.758654 [DEBUG] [SSL: 0x14899f8] flush
2020-04-12 22:06:23.758712 [DEBUG] [FDBIO] flush_ex write(25, 0x1755d40, 64150) return 64150, errno: 0
2020-04-12 22:06:23.758715 [DEBUG] [FDBIO] FLUSHED
2020-04-12 22:06:23.758719 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::flushSslWpending()...
2020-04-12 22:06:23.758722 [DEBUG] [192.168.1.1:55674:HTTP2] SSL wpending: 0
2020-04-12 22:06:23.758766 [DEBUG] [*:443] New connection from 192.168.1.2:58866.
2020-04-12 22:06:23.758816 [DEBUG] NtwkIOLink::setLink called pInfo is m_pClientInfo 0x1367600,  m_pCrypto 0x1493548, m_pServerAddrInfo 0x1489ba0, m_remotePort 58866
2020-04-12 22:06:23.758835 [DEBUG] [SSL: 0x1489768] setfd: 193
2020-04-12 22:06:23.758840 [DEBUG] [BIO] ls_fdbio_create bio: 0x1737198
2020-04-12 22:06:23.758843 [DEBUG] [BIO] setup_writes
2020-04-12 22:06:23.758846 [DEBUG] [SSL: 0x1489768] toAccept
2020-04-12 22:06:23.758849 [DEBUG] [192.168.1.2:58864:HTTP2] concurrent conn: 1
2020-04-12 22:06:23.758852 [DEBUG] [192.168.1.2:58864:HTTP2] NtwkIOLink::handleEvents() fd: 193, mask=25, events=1!
2020-04-12 22:06:23.758855 [DEBUG] [192.168.1.2:58864:HTTP2] [SSL] SSLAgain()!
2020-04-12 22:06:23.758858 [DEBUG] [SSL: 0x1489768] accept SSL: 0x1493548
2020-04-12 22:06:23.758861 [DEBUG] [SSL: 0x1489768] Call SSL_do_handshake, ssl: 0x1493548, ctx: 0x140ca38
2020-04-12 22:06:23.758873 [DEBUG] [BIO] bio_fd_read: 0x1737198, 5 bytes on 193
2020-04-12 22:06:23.758876 [DEBUG] [FDBIO] alloc read buf 4096
2020-04-12 22:06:23.758878 [DEBUG] [BIO] bio_fd_read: Read into buffer
2020-04-12 22:06:23.758888 [DEBUG] [BIO] bio_fd_read: Preserve read: 519
2020-04-12 22:06:23.758891 [DEBUG] [BIO] bio_fd_read: Use buffered 5 of 5
2020-04-12 22:06:23.758894 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 5
2020-04-12 22:06:23.758902 [DEBUG] [BIO] bio_fd_read: 0x1737198, 514 bytes on 193
2020-04-12 22:06:23.758906 [DEBUG] [BIO] bio_fd_read: Use buffered 514 of 514
2020-04-12 22:06:23.758910 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 514
2020-04-12 22:06:23.759242 [DEBUG] [FDBIO] bio_fd_write: 0x1737198, 212 bytes on 193
2020-04-12 22:06:23.759273 [DEBUG] [BIO] bio_fd_read: 0x1737198, 5 bytes on 193
2020-04-12 22:06:23.759277 [DEBUG] [BIO] bio_fd_read: Read into buffer
2020-04-12 22:06:23.759281 [DEBUG] [BIO] bio_fd_read: set retry read errno: 11
2020-04-12 22:06:23.759284 [DEBUG] [SSL: 0x1489768] SSL_accept SSL_do_handshake rc: -1
2020-04-12 22:06:23.759289 [DEBUG] [SSL: 0x1489768] checkError returned 2, first error: error:00000000:invalid library (0):OPENSSL_internal:invalid library (0), last error: error:00000000:invalid library (0):OPENSSL_internal:invalid library (0)
2020-04-12 22:06:23.759295 [DEBUG] [192.168.1.2:58864:HTTP2] 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
2020-04-12 22:06:23.759300 [DEBUG] [192.168.1.2:58864:HTTP2] 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
2020-04-12 22:06:23.759303 [DEBUG] [*:443] 1 connections accepted!
2020-04-12 22:06:23.759307 [DEBUG] EdStream::handleEvent(), fd: 119, event: 1
2020-04-12 22:06:23.759318 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] ExtConn::onRead()
2020-04-12 22:06:23.759326 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] LsapiConn::doRead()
2020-04-12 22:06:23.759334 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process packet header 8 bytes.
2020-04-12 22:06:23.759337 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] received LSAPI_RESP_HEADER, packetLen: 350.
2020-04-12 22:06:23.759341 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process response header 342 bytes
2020-04-12 22:06:23.759374 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Response content type: [text/html], pMIME: 0x13d1c80
2020-04-12 22:06:23.759379 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Response header finished!
2020-04-12 22:06:23.759383 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] [RECV_RESP_HEADER] run Hook function for [Module:cache] session=0x1601ca8
2020-04-12 22:06:23.759399 [DEBUG] [cache] enable_hook, enable 0, num indices 1, return 0
2020-04-12 22:06:23.759409 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] [Module:Cache]createEntry abort, code 1.
2020-04-12 22:06:23.759413 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] [RECV_RESP_HEADER] [Module:cache]  session=0x1601ca8 ret 0
2020-04-12 22:06:23.759418 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process packet header 8 bytes.
2020-04-12 22:06:23.759421 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] received LSAPI_RESP_STREAM, packetLen: 16392.
2020-04-12 22:06:23.759428 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.759431 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.759434 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] setupDynRespBodyBuf()
2020-04-12 22:06:23.759437 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Allocate response body buffer.
2020-04-12 22:06:23.759445 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] GZIP the response body in the buffer.
2020-04-12 22:06:23.759538 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] setupGzipBuf() begin GZIP stream.
2020-04-12 22:06:23.759834 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.759839 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.759846 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.759849 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.760111 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.760116 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.771098 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.771129 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.776310 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.776342 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.776364 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.776368 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.777039 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.777045 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.777052 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.777055 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.777422 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.777427 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 14786
2020-04-12 22:06:23.777437 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process response stream 14786 bytes, packet left: 14778
2020-04-12 22:06:23.777441 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] HttpExtConnector::processRespBodyData(14778)
2020-04-12 22:06:23.777593 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.777597 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Received LSAPI_RESP_END, packetLen: 8
2020-04-12 22:06:23.777601 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Process packet header 0 bytes.
2020-04-12 22:06:23.777604 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] [EXT] EndResponse( endCode=0, protocolStatus=0 )
2020-04-12 22:06:23.777613 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Release ExtProcessor!
2020-04-12 22:06:23.777633 [DEBUG] [uds://tmp/lshttpd/lsphp.sock.330] assign pending request [192.168.1.1:55674:HTTP2-325#example.com:lsapi] to recycled connection!
2020-04-12 22:06:23.777657 [DEBUG] [192.168.1.1:55674:HTTP2-325#example.com:lsapi] Request header is done
2020-04-12 22:06:23.777662 [DEBUG] [192.168.1.1:55674:HTTP2-325#example.com:lsapi] ExtConn::continueRead()
2020-04-12 22:06:23.801150 [DEBUG] [192.168.1.1:55674:HTTP2-325#example.com:lsapi] Processor sent request body 126 bytes, total sent: 126
2020-04-12 22:06:23.801200 [DEBUG] [192.168.1.1:55674:HTTP2-325#example.com:lsapi] Request body done!
2020-04-12 22:06:23.801206 [DEBUG] [192.168.1.1:55674:HTTP2-325#example.com:lsapi] ExtConn::suspendWrite()
2020-04-12 22:06:23.801211 [DEBUG] [192.168.1.1:55674:HTTP2-325#example.com:lsapi] ExtConn::onEventDone(), fd: 119, state: 2, event: -1, revents: 0.
2020-04-12 22:06:23.801223 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] endResponse( 1 )
2020-04-12 22:06:23.801226 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] endResponseInternal()
2020-04-12 22:06:23.801522 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] endResponse() end GZIP stream.
2020-04-12 22:06:23.801527 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] HttpSession::flush()!
2020-04-12 22:06:23.801531 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] sendRespHeaders()
2020-04-12 22:06:23.801541 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Resp headers, status: 4, total: 12, removed: 0, unique:11, has hole: 0, buffer size: 615
2020-04-12 22:06:23.801544 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Content-Type: text/html; charset=UTF-8
2020-04-12 22:06:23.801547 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     X-Pingback: https://example.com/xmlrpc.php
2020-04-12 22:06:23.801550 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Expires: Wed, 11 Jan 1984 05:00:00 GMT
2020-04-12 22:06:23.801553 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Cache-Control: no-cache, must-revalidate, max-age=0
2020-04-12 22:06:23.801556 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Link: <https://example.com/wp-json/>; rel="https://api.w.org/"
2020-04-12 22:06:23.801558 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Link: <https://example.com/?p=9247>; rel=shortlink
2020-04-12 22:06:23.801561 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     X-Litespeed-Cache-Control: no-cache
2020-04-12 22:06:23.801564 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Content-Encoding: gzip
2020-04-12 22:06:23.801567 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Vary: Accept-Encoding
2020-04-12 22:06:23.801574 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Content-Length: 16917
2020-04-12 22:06:23.801577 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.801580 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Alt-Svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.801584 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Resp headers, status: 4, total: 12, removed: 0, unique:11, has hole: 0, buffer size: 615
2020-04-12 22:06:23.801587 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Content-Type: text/html; charset=UTF-8
2020-04-12 22:06:23.801590 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     X-Pingback: https://example.com/xmlrpc.php
2020-04-12 22:06:23.801593 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Expires: Wed, 11 Jan 1984 05:00:00 GMT
2020-04-12 22:06:23.801595 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Cache-Control: no-cache, must-revalidate, max-age=0
2020-04-12 22:06:23.801598 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Link: <https://example.com/wp-json/>; rel="https://api.w.org/"
2020-04-12 22:06:23.801601 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Link: <https://example.com/?p=9247>; rel=shortlink
2020-04-12 22:06:23.801604 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     X-Litespeed-Cache-Control: no-cache
2020-04-12 22:06:23.801606 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Content-Encoding: gzip
2020-04-12 22:06:23.801609 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Vary: Accept-Encoding
2020-04-12 22:06:23.801612 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Content-Length: 16917
2020-04-12 22:06:23.801614 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.801617 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi]     Alt-Svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.801627 [DEBUG] [192.168.1.1:55674:HTTP2-283] sendRespHeaders()
2020-04-12 22:06:23.801633 [DEBUG] hpack encode: content-type: text/html; charset=UTF-8
2020-04-12 22:06:23.801638 [DEBUG] hpack encode: x-pingback: https://example.com/xmlrpc.php
2020-04-12 22:06:23.801641 [DEBUG] hpack encode: expires: Wed, 11 Jan 1984 05:00:00 GMT
2020-04-12 22:06:23.801645 [DEBUG] hpack encode: cache-control: no-cache, must-revalidate, max-age=0
2020-04-12 22:06:23.801648 [DEBUG] hpack encode: link: <https://example.com/wp-json/>; rel="https://api.w.org/"
2020-04-12 22:06:23.801652 [DEBUG] hpack encode: link: <https://example.com/?p=9247>; rel=shortlink
2020-04-12 22:06:23.801655 [DEBUG] hpack encode: x-litespeed-cache-control: no-cache
2020-04-12 22:06:23.801658 [DEBUG] hpack encode: content-encoding: gzip
2020-04-12 22:06:23.801667 [DEBUG] hpack encode: vary: Accept-Encoding
2020-04-12 22:06:23.801672 [DEBUG] hpack encode: content-length: 16917
2020-04-12 22:06:23.801675 [DEBUG] hpack encode: date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.801678 [DEBUG] hpack encode: alt-svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.801684 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(166), buffered: 166, total buffer size = 166
2020-04-12 22:06:23.801694 [DEBUG] [192.168.1.1:55674:HTTP2-283] H2Stream::write(0x7fb6a797c000, 8192, 0), weight: 1
2020-04-12 22:06:23.801699 [DEBUG] [192.168.1.1:55674:HTTP2-283] send DATA frame, FIN: 0, data: 0x7fb6a797c000, len: 8192
2020-04-12 22:06:23.810526 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 175
2020-04-12 22:06:23.810563 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(8192), buffered: 8192, total buffer size = 8367
2020-04-12 22:06:23.810579 [DEBUG] [192.168.1.1:55674:HTTP2-283] H2Stream::write(0x7fb6a797c000, 8192, 0) return 8192
2020-04-12 22:06:23.810584 [DEBUG] [192.168.1.1:55674:HTTP2-283] sent: 8192, current window: 6283264
2020-04-12 22:06:23.810589 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] writeRespBodyDirect(): write(0x7fb6a797c000, 8192) written 8192, total sent: 8192
2020-04-12 22:06:23.810593 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] writeRespBody() len = 8192, returned 8192.
2020-04-12 22:06:23.810599 [DEBUG] [192.168.1.1:55674:HTTP2-283] H2Stream::write(0x7fb6a797e000, 8192, 0), weight: 1
2020-04-12 22:06:23.810603 [DEBUG] [192.168.1.1:55674:HTTP2-283] send DATA frame, FIN: 0, data: 0x7fb6a797e000, len: 8192
2020-04-12 22:06:23.810606 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 8376
2020-04-12 22:06:23.810633 [DEBUG] [FDBIO] bio_fd_write: 0x148aae8, 16406 bytes on 25
2020-04-12 22:06:23.810637 [DEBUG] [FDBIO] ls_fdbio_buff_write, to write: 16406, used: 0, sent: 0
2020-04-12 22:06:23.810644 [DEBUG] [FDBIO] lstls_buff_write, to write: 16406, finished: 16406, used: 16406, sent: 0
2020-04-12 22:06:23.810649 [DEBUG] SSL_write( 0x1489be8, 0x153b880, 16384) return 16384, pending 16406
2020-04-12 22:06:23.810652 [DEBUG] [192.168.1.1:55674:HTTP2] Need to write 16384 bytes, wrote 16384 bytes.
2020-04-12 22:06:23.810657 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(8192), buffered: 8192, total buffer size = 184
2020-04-12 22:06:23.810660 [DEBUG] [192.168.1.1:55674:HTTP2-283] H2Stream::write(0x7fb6a797e000, 8192, 0) return 8192
2020-04-12 22:06:23.810663 [DEBUG] [192.168.1.1:55674:HTTP2-283] sent: 16384, current window: 6275072
2020-04-12 22:06:23.810666 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] writeRespBodyDirect(): write(0x7fb6a797e000, 8192) written 8192, total sent: 16384
2020-04-12 22:06:23.810670 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] writeRespBody() len = 8192, returned 8192.
2020-04-12 22:06:23.810687 [DEBUG] [192.168.1.1:55674:HTTP2-283] H2Stream::write(0x7fb6a7978000, 533, 0), weight: 1
2020-04-12 22:06:23.810690 [DEBUG] [192.168.1.1:55674:HTTP2-283] send DATA frame, FIN: 0, data: 0x7fb6a7978000, len: 533
2020-04-12 22:06:23.810694 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 193
2020-04-12 22:06:23.810697 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(533), buffered: 533, total buffer size = 726
2020-04-12 22:06:23.810700 [DEBUG] [192.168.1.1:55674:HTTP2-283] H2Stream::write(0x7fb6a7978000, 533, 0) return 533
2020-04-12 22:06:23.810703 [DEBUG] [192.168.1.1:55674:HTTP2-283] sent: 16917, current window: 6274539
2020-04-12 22:06:23.810706 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] writeRespBodyDirect(): write(0x7fb6a7978000, 533) written 533, total sent: 16917
2020-04-12 22:06:23.810710 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] writeRespBody() len = 533, returned 533.
2020-04-12 22:06:23.810713 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] flushBody() return 0
2020-04-12 22:06:23.810717 [DEBUG] [192.168.1.1:55674:HTTP2-283] H2Stream::flush()
2020-04-12 22:06:23.810720 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.810723 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.810726 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.810745 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Set the HSS_COMPLETE flag.
2020-04-12 22:06:23.810750 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] mark HSS_COMPLETE.
2020-04-12 22:06:23.810753 [DEBUG] [192.168.1.1:55674:HTTP2-283] H2Stream::shutdown()
2020-04-12 22:06:23.810757 [DEBUG] [192.168.1.1:55674:HTTP2-283] send DATA frame, with Flag: 1
2020-04-12 22:06:23.810760 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 735
2020-04-12 22:06:23.810762 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.810765 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.810768 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.810770 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.810773 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.810776 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.810779 [DEBUG] [192.168.1.1:55674:HTTP2-283] add to priority queue: 0
2020-04-12 22:06:23.810783 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] HttpExtConnector::cleanUp() ...
2020-04-12 22:06:23.810794 [DEBUG] [T0 evtcbnode_s* EvtcbQue::getNodeObj(evtcb_pf, const evtcbtail_t*, long int, void*)] returning pObj 0x1696b90 session 0x1601ca8 tailnode (nil)
2020-04-12 22:06:23.810799 [DEBUG] [T0 evtcbnode_s* EvtcbQue::schedule(evtcb_pf, const evtcbtail_t*, long int, void*, bool)] sched evtq cb for session 0x1601ca8 sn 2
2020-04-12 22:06:23.810803 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)(null)] Obj=0x1696b90 Session=0x1601ca8 lParam=2 pParam=(nil) cb=0x4fa230 seq=302 slot=2 sn=2
2020-04-12 22:06:23.810807 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] locking event slot 2
2020-04-12 22:06:23.810810 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] schedule cb for session 0x1601ca8 tail (nil) seq 302 slot 2 sn 2
2020-04-12 22:06:23.810813 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] 0x1696b90 appended
2020-04-12 22:06:23.810816 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] Tail for session 0x1601ca8 slot 2 was (nil) set to 0x1696b90
2020-04-12 22:06:23.810822 [DEBUG] [192.168.1.1:55674:HTTP2-325#example.com:lsapi] ExtConn::onEventDone(), fd: 119, state: 2, event: 1, revents: 0.
2020-04-12 22:06:23.810826 [DEBUG] EdStream::handleEvent(), fd: 118, event: 1
2020-04-12 22:06:23.810830 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] ExtConn::onRead()
2020-04-12 22:06:23.810833 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] LsapiConn::doRead()
2020-04-12 22:06:23.810843 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process packet header 8 bytes.
2020-04-12 22:06:23.810847 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] received LSAPI_RESP_HEADER, packetLen: 350.
2020-04-12 22:06:23.810851 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process response header 342 bytes
2020-04-12 22:06:23.810881 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Response content type: [text/html], pMIME: 0x13d1c80
2020-04-12 22:06:23.810886 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Response header finished!
2020-04-12 22:06:23.810890 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] [RECV_RESP_HEADER] run Hook function for [Module:cache] session=0x160fc18
2020-04-12 22:06:23.810903 [DEBUG] [cache] enable_hook, enable 0, num indices 1, return 0
2020-04-12 22:06:23.810913 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] [Module:Cache]createEntry abort, code 1.
2020-04-12 22:06:23.810918 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] [RECV_RESP_HEADER] [Module:cache]  session=0x160fc18 ret 0
2020-04-12 22:06:23.810923 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process packet header 8 bytes.
2020-04-12 22:06:23.810926 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] received LSAPI_RESP_STREAM, packetLen: 16392.
2020-04-12 22:06:23.810933 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.810937 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.810940 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] setupDynRespBodyBuf()
2020-04-12 22:06:23.810943 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Allocate response body buffer.
2020-04-12 22:06:23.810947 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] GZIP the response body in the buffer.
2020-04-12 22:06:23.810992 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] setupGzipBuf() begin GZIP stream.
2020-04-12 22:06:23.811216 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.811220 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.811226 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.811229 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.811471 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.811475 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.811487 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.811490 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.811778 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.811782 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.811791 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.811794 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.829969 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.830005 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.830017 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.830036 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.830377 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.830381 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 15227
2020-04-12 22:06:23.830394 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process response stream 15227 bytes, packet left: 15219
2020-04-12 22:06:23.830397 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] HttpExtConnector::processRespBodyData(15219)
2020-04-12 22:06:23.830580 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.830584 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Received LSAPI_RESP_END, packetLen: 8
2020-04-12 22:06:23.830588 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Process packet header 0 bytes.
2020-04-12 22:06:23.830592 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] [EXT] EndResponse( endCode=0, protocolStatus=0 )
2020-04-12 22:06:23.830595 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Release ExtProcessor!
2020-04-12 22:06:23.830608 [DEBUG] [uds://tmp/lshttpd/lsphp.sock.330] assign pending request [192.168.1.1:55674:HTTP2-327#example.com:lsapi] to recycled connection!
2020-04-12 22:06:23.830631 [DEBUG] [192.168.1.1:55674:HTTP2-327#example.com:lsapi] Request header is done
2020-04-12 22:06:23.830636 [DEBUG] [192.168.1.1:55674:HTTP2-327#example.com:lsapi] ExtConn::continueRead()
2020-04-12 22:06:23.830639 [DEBUG] [192.168.1.1:55674:HTTP2-327#example.com:lsapi] Request body done!
2020-04-12 22:06:23.849221 [DEBUG] [192.168.1.1:55674:HTTP2-327#example.com:lsapi] ExtConn::suspendWrite()
2020-04-12 22:06:23.849258 [DEBUG] [192.168.1.1:55674:HTTP2-327#example.com:lsapi] ExtConn::onEventDone(), fd: 118, state: 2, event: -1, revents: 0.
2020-04-12 22:06:23.849265 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] endResponse( 1 )
2020-04-12 22:06:23.849268 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] endResponseInternal()
2020-04-12 22:06:23.849555 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] endResponse() end GZIP stream.
2020-04-12 22:06:23.849558 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] HttpSession::flush()!
2020-04-12 22:06:23.849562 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] sendRespHeaders()
2020-04-12 22:06:23.849584 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Resp headers, status: 4, total: 12, removed: 0, unique:11, has hole: 0, buffer size: 615
2020-04-12 22:06:23.849588 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Content-Type: text/html; charset=UTF-8
2020-04-12 22:06:23.849591 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     X-Pingback: https://example.com/xmlrpc.php
2020-04-12 22:06:23.849594 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Expires: Wed, 11 Jan 1984 05:00:00 GMT
2020-04-12 22:06:23.849596 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Cache-Control: no-cache, must-revalidate, max-age=0
2020-04-12 22:06:23.849600 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Link: <https://example.com/wp-json/>; rel="https://api.w.org/"
2020-04-12 22:06:23.849602 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Link: <https://example.com/?p=8620>; rel=shortlink
2020-04-12 22:06:23.849605 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     X-Litespeed-Cache-Control: no-cache
2020-04-12 22:06:23.849608 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Content-Encoding: gzip
2020-04-12 22:06:23.849611 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Vary: Accept-Encoding
2020-04-12 22:06:23.849614 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Content-Length: 17099
2020-04-12 22:06:23.849616 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.849619 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Alt-Svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.849637 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Resp headers, status: 4, total: 12, removed: 0, unique:11, has hole: 0, buffer size: 615
2020-04-12 22:06:23.849640 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Content-Type: text/html; charset=UTF-8
2020-04-12 22:06:23.849643 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     X-Pingback: https://example.com/xmlrpc.php
2020-04-12 22:06:23.849645 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Expires: Wed, 11 Jan 1984 05:00:00 GMT
2020-04-12 22:06:23.849648 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Cache-Control: no-cache, must-revalidate, max-age=0
2020-04-12 22:06:23.849651 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Link: <https://example.com/wp-json/>; rel="https://api.w.org/"
2020-04-12 22:06:23.849654 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Link: <https://example.com/?p=8620>; rel=shortlink
2020-04-12 22:06:23.849656 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     X-Litespeed-Cache-Control: no-cache
2020-04-12 22:06:23.849659 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Content-Encoding: gzip
2020-04-12 22:06:23.849662 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Vary: Accept-Encoding
2020-04-12 22:06:23.849665 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Content-Length: 17099
2020-04-12 22:06:23.849667 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.849670 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi]     Alt-Svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.849676 [DEBUG] [192.168.1.1:55674:HTTP2-281] sendRespHeaders()
2020-04-12 22:06:23.849682 [DEBUG] hpack encode: content-type: text/html; charset=UTF-8
2020-04-12 22:06:23.849701 [DEBUG] hpack encode: x-pingback: https://example.com/xmlrpc.php
2020-04-12 22:06:23.849706 [DEBUG] hpack encode: expires: Wed, 11 Jan 1984 05:00:00 GMT
2020-04-12 22:06:23.849710 [DEBUG] hpack encode: cache-control: no-cache, must-revalidate, max-age=0
2020-04-12 22:06:23.849713 [DEBUG] hpack encode: link: <https://example.com/wp-json/>; rel="https://api.w.org/"
2020-04-12 22:06:23.849717 [DEBUG] hpack encode: link: <https://example.com/?p=8620>; rel=shortlink
2020-04-12 22:06:23.849721 [DEBUG] hpack encode: x-litespeed-cache-control: no-cache
2020-04-12 22:06:23.849724 [DEBUG] hpack encode: content-encoding: gzip
2020-04-12 22:06:23.849727 [DEBUG] hpack encode: vary: Accept-Encoding
2020-04-12 22:06:23.849731 [DEBUG] hpack encode: content-length: 17099
2020-04-12 22:06:23.849734 [DEBUG] hpack encode: date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.849737 [DEBUG] hpack encode: alt-svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.849742 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(160), buffered: 160, total buffer size = 895
2020-04-12 22:06:23.849748 [DEBUG] [192.168.1.1:55674:HTTP2-281] H2Stream::write(0x7fb6a7976000, 8192, 0), weight: 2
2020-04-12 22:06:23.849752 [DEBUG] [192.168.1.1:55674:HTTP2-281] send DATA frame, FIN: 0, data: 0x7fb6a7976000, len: 8192
2020-04-12 22:06:23.849755 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 904
2020-04-12 22:06:23.849761 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(8192), buffered: 8192, total buffer size = 9096
2020-04-12 22:06:23.849764 [DEBUG] [192.168.1.1:55674:HTTP2-281] H2Stream::write(0x7fb6a7976000, 8192, 0) return 8192
2020-04-12 22:06:23.849771 [DEBUG] [192.168.1.1:55674:HTTP2-281] sent: 8192, current window: 6283264
2020-04-12 22:06:23.849774 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] writeRespBodyDirect(): write(0x7fb6a7976000, 8192) written 8192, total sent: 8192
2020-04-12 22:06:23.849778 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] writeRespBody() len = 8192, returned 8192.
2020-04-12 22:06:23.849781 [DEBUG] [192.168.1.1:55674:HTTP2-281] H2Stream::write(0x7fb6a7974000, 8192, 0), weight: 2
2020-04-12 22:06:23.849784 [DEBUG] [192.168.1.1:55674:HTTP2-281] send DATA frame, FIN: 0, data: 0x7fb6a7974000, len: 8192
2020-04-12 22:06:23.849787 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 9105
2020-04-12 22:06:23.849833 [DEBUG] [FDBIO] bio_fd_write: 0x148aae8, 16406 bytes on 25
2020-04-12 22:06:23.849839 [DEBUG] [FDBIO] ls_fdbio_buff_write, to write: 16406, used: 16406, sent: 0
2020-04-12 22:06:23.849846 [DEBUG] [FDBIO] lstls_buff_write, to write: 16406, finished: 16406, used: 32812, sent: 0
2020-04-12 22:06:23.849850 [DEBUG] SSL_write( 0x1489be8, 0x153b880, 16384) return 16384, pending 32812
2020-04-12 22:06:23.849853 [DEBUG] [192.168.1.1:55674:HTTP2] Need to write 16384 bytes, wrote 16384 bytes.
2020-04-12 22:06:23.849858 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(8192), buffered: 8192, total buffer size = 913
2020-04-12 22:06:23.849861 [DEBUG] [192.168.1.1:55674:HTTP2-281] H2Stream::write(0x7fb6a7974000, 8192, 0) return 8192
2020-04-12 22:06:23.849864 [DEBUG] [192.168.1.1:55674:HTTP2-281] sent: 16384, current window: 6275072
2020-04-12 22:06:23.849867 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] writeRespBodyDirect(): write(0x7fb6a7974000, 8192) written 8192, total sent: 16384
2020-04-12 22:06:23.849871 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] writeRespBody() len = 8192, returned 8192.
2020-04-12 22:06:23.849874 [DEBUG] [192.168.1.1:55674:HTTP2-281] H2Stream::write(0x7fb6a7972000, 715, 0), weight: 2
2020-04-12 22:06:23.849877 [DEBUG] [192.168.1.1:55674:HTTP2-281] send DATA frame, FIN: 0, data: 0x7fb6a7972000, len: 715
2020-04-12 22:06:23.849880 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 922
2020-04-12 22:06:23.849884 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(715), buffered: 715, total buffer size = 1637
2020-04-12 22:06:23.849887 [DEBUG] [192.168.1.1:55674:HTTP2-281] H2Stream::write(0x7fb6a7972000, 715, 0) return 715
2020-04-12 22:06:23.849890 [DEBUG] [192.168.1.1:55674:HTTP2-281] sent: 17099, current window: 6274357
2020-04-12 22:06:23.849893 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] writeRespBodyDirect(): write(0x7fb6a7972000, 715) written 715, total sent: 17099
2020-04-12 22:06:23.849896 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] writeRespBody() len = 715, returned 715.
2020-04-12 22:06:23.849899 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] flushBody() return 0
2020-04-12 22:06:23.849902 [DEBUG] [192.168.1.1:55674:HTTP2-281] H2Stream::flush()
2020-04-12 22:06:23.849905 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.849908 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.849911 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.849914 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Set the HSS_COMPLETE flag.
2020-04-12 22:06:23.849917 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] mark HSS_COMPLETE.
2020-04-12 22:06:23.849920 [DEBUG] [192.168.1.1:55674:HTTP2-281] H2Stream::shutdown()
2020-04-12 22:06:23.849924 [DEBUG] [192.168.1.1:55674:HTTP2-281] send DATA frame, with Flag: 1
2020-04-12 22:06:23.849927 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 1646
2020-04-12 22:06:23.849929 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.849932 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.849935 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.849941 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.849943 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.849946 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.849949 [DEBUG] [192.168.1.1:55674:HTTP2-281] add to priority queue: 0
2020-04-12 22:06:23.849953 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] HttpExtConnector::cleanUp() ...
2020-04-12 22:06:23.849963 [DEBUG] [T0 evtcbnode_s* EvtcbQue::getNodeObj(evtcb_pf, const evtcbtail_t*, long int, void*)] returning pObj 0x1685d50 session 0x160fc18 tailnode (nil)
2020-04-12 22:06:23.849968 [DEBUG] [T0 evtcbnode_s* EvtcbQue::schedule(evtcb_pf, const evtcbtail_t*, long int, void*, bool)] sched evtq cb for session 0x160fc18 sn 2
2020-04-12 22:06:23.849973 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)(null)] Obj=0x1685d50 Session=0x160fc18 lParam=2 pParam=(nil) cb=0x4fa230 seq=301 slot=1 sn=2
2020-04-12 22:06:23.849976 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] locking event slot 1
2020-04-12 22:06:23.849979 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] schedule cb for session 0x160fc18 tail (nil) seq 301 slot 1 sn 2
2020-04-12 22:06:23.849983 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] 0x1685d50 appended
2020-04-12 22:06:23.849986 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] Tail for session 0x160fc18 slot 1 was (nil) set to 0x1685d50
2020-04-12 22:06:23.849990 [DEBUG] [192.168.1.1:55674:HTTP2-327#example.com:lsapi] ExtConn::onEventDone(), fd: 118, state: 2, event: 1, revents: 0.
2020-04-12 22:06:23.849994 [DEBUG] EdStream::handleEvent(), fd: 36, event: 1
2020-04-12 22:06:23.850002 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] ExtConn::onRead()
2020-04-12 22:06:23.850010 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] LsapiConn::doRead()
2020-04-12 22:06:23.850019 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process packet header 8 bytes.
2020-04-12 22:06:23.850022 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] received LSAPI_RESP_HEADER, packetLen: 350.
2020-04-12 22:06:23.850026 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process response header 342 bytes
2020-04-12 22:06:23.850059 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Response content type: [text/html], pMIME: 0x13d1c80
2020-04-12 22:06:23.850063 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Response header finished!
2020-04-12 22:06:23.850068 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] [RECV_RESP_HEADER] run Hook function for [Module:cache] session=0x15e0b08
2020-04-12 22:06:23.850084 [DEBUG] [cache] enable_hook, enable 0, num indices 1, return 0
2020-04-12 22:06:23.850099 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] [Module:Cache]createEntry abort, code 1.
2020-04-12 22:06:23.850103 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] [RECV_RESP_HEADER] [Module:cache]  session=0x15e0b08 ret 0
2020-04-12 22:06:23.850108 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process packet header 8 bytes.
2020-04-12 22:06:23.850111 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] received LSAPI_RESP_STREAM, packetLen: 16392.
2020-04-12 22:06:23.850120 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.850123 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.850126 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] setupDynRespBodyBuf()
2020-04-12 22:06:23.850129 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Allocate response body buffer.
2020-04-12 22:06:23.850134 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] GZIP the response body in the buffer.
2020-04-12 22:06:23.850222 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] setupGzipBuf() begin GZIP stream.
2020-04-12 22:06:23.850486 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.850496 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.850502 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.850505 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.850785 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.850790 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.850800 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.850803 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.851103 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.851108 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.851118 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.851121 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.851784 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.851791 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 16392
2020-04-12 22:06:23.851798 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process response stream 16392 bytes, packet left: 16384
2020-04-12 22:06:23.851801 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] HttpExtConnector::processRespBodyData(16384)
2020-04-12 22:06:23.888376 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.888411 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Received LSAPI_RESP_STREAM, packetLen: 15503
2020-04-12 22:06:23.888428 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process response stream 15503 bytes, packet left: 15495
2020-04-12 22:06:23.888432 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] HttpExtConnector::processRespBodyData(15495)
2020-04-12 22:06:23.888659 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process Packet header 8 bytes
2020-04-12 22:06:23.888664 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Received LSAPI_RESP_END, packetLen: 8
2020-04-12 22:06:23.888669 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Process packet header 0 bytes.
2020-04-12 22:06:23.888672 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] [EXT] EndResponse( endCode=0, protocolStatus=0 )
2020-04-12 22:06:23.888676 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Release ExtProcessor!
2020-04-12 22:06:23.888687 [DEBUG] [uds://tmp/lshttpd/lsphp.sock.330] assign pending request [192.168.1.1:55674:HTTP2-329#example.com:lsapi] to recycled connection!
2020-04-12 22:06:23.888710 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] Request header is done
2020-04-12 22:06:23.888715 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] ExtConn::continueRead()
2020-04-12 22:06:23.888719 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] ExtConn::suspendWrite()
2020-04-12 22:06:23.888727 [DEBUG] [192.168.1.1:55674:HTTP2-329] H2Stream::continueRead()
2020-04-12 22:06:23.888732 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] HttpSession::onReadEx(), state: 6!
2020-04-12 22:06:23.888736 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] Read Request Body!
2020-04-12 22:06:23.888741 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] Read 126/126 bytes of request body!
2020-04-12 22:06:23.888744 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] Finished request body 126 bytes!
2020-04-12 22:06:23.888759 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] HttpSession::reqBodyDone().
2020-04-12 22:06:23.888763 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] reqBodyDone(): HSPS_WEBSOCKET -> HSPS_HKPT_RCVD_REQ_BODY_PROCESSING
2020-04-12 22:06:23.888767 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] Run State: HSPS_HKPT_RCVD_REQ_BODY_PROCESSING
2020-04-12 22:06:23.888771 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] runEventHkpt(): HSPS_HKPT_RCVD_REQ_BODY_PROCESSING -> HSPS_WEBSOCKET
2020-04-12 22:06:23.894077 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] Processor sent request body 126 bytes, total sent: 126
2020-04-12 22:06:23.894110 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] Request body done!
2020-04-12 22:06:23.894116 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] ExtConn::suspendWrite()
2020-04-12 22:06:23.894122 [DEBUG] [T0 void EvtcbQue::run(evtcbtail_t*)] slot 2 tail NULL!
2020-04-12 22:06:23.894126 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] All received request body sent, suspend write
2020-04-12 22:06:23.894131 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] ExtConn::onEventDone(), fd: 36, state: 2, event: -1, revents: 0.
2020-04-12 22:06:23.894137 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] endResponse( 1 )
2020-04-12 22:06:23.894141 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] endResponseInternal()
2020-04-12 22:06:23.894471 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] endResponse() end GZIP stream.
2020-04-12 22:06:23.894478 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] HttpSession::flush()!
2020-04-12 22:06:23.894482 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] sendRespHeaders()
2020-04-12 22:06:23.894492 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Resp headers, status: 4, total: 12, removed: 0, unique:11, has hole: 0, buffer size: 615
2020-04-12 22:06:23.894496 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Content-Type: text/html; charset=UTF-8
2020-04-12 22:06:23.894499 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     X-Pingback: https://example.com/xmlrpc.php
2020-04-12 22:06:23.894502 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Expires: Wed, 11 Jan 1984 05:00:00 GMT
2020-04-12 22:06:23.894505 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Cache-Control: no-cache, must-revalidate, max-age=0
2020-04-12 22:06:23.894508 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Link: <https://example.com/wp-json/>; rel="https://api.w.org/"
2020-04-12 22:06:23.894511 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Link: <https://example.com/?p=8623>; rel=shortlink
2020-04-12 22:06:23.894514 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     X-Litespeed-Cache-Control: no-cache
2020-04-12 22:06:23.894516 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Content-Encoding: gzip
2020-04-12 22:06:23.894519 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Vary: Accept-Encoding
2020-04-12 22:06:23.894522 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Content-Length: 17114
2020-04-12 22:06:23.894525 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.894528 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Alt-Svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.894531 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Resp headers, status: 4, total: 12, removed: 0, unique:11, has hole: 0, buffer size: 615
2020-04-12 22:06:23.894534 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Content-Type: text/html; charset=UTF-8
2020-04-12 22:06:23.894537 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     X-Pingback: https://example.com/xmlrpc.php
2020-04-12 22:06:23.894551 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Expires: Wed, 11 Jan 1984 05:00:00 GMT
2020-04-12 22:06:23.894554 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Cache-Control: no-cache, must-revalidate, max-age=0
2020-04-12 22:06:23.894556 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Link: <https://example.com/wp-json/>; rel="https://api.w.org/"
2020-04-12 22:06:23.894559 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Link: <https://example.com/?p=8623>; rel=shortlink
2020-04-12 22:06:23.894562 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     X-Litespeed-Cache-Control: no-cache
2020-04-12 22:06:23.894565 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Content-Encoding: gzip
2020-04-12 22:06:23.894573 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Vary: Accept-Encoding
2020-04-12 22:06:23.894576 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Content-Length: 17114
2020-04-12 22:06:23.894579 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.894582 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi]     Alt-Svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.894587 [DEBUG] [192.168.1.1:55674:HTTP2-277] sendRespHeaders()
2020-04-12 22:06:23.894594 [DEBUG] hpack encode: content-type: text/html; charset=UTF-8
2020-04-12 22:06:23.894598 [DEBUG] hpack encode: x-pingback: https://example.com/xmlrpc.php
2020-04-12 22:06:23.894601 [DEBUG] hpack encode: expires: Wed, 11 Jan 1984 05:00:00 GMT
2020-04-12 22:06:23.894605 [DEBUG] hpack encode: cache-control: no-cache, must-revalidate, max-age=0
2020-04-12 22:06:23.894608 [DEBUG] hpack encode: link: <https://example.com/wp-json/>; rel="https://api.w.org/"
2020-04-12 22:06:23.894611 [DEBUG] hpack encode: link: <https://example.com/?p=8623>; rel=shortlink
2020-04-12 22:06:23.894615 [DEBUG] hpack encode: x-litespeed-cache-control: no-cache
2020-04-12 22:06:23.894618 [DEBUG] hpack encode: content-encoding: gzip
2020-04-12 22:06:23.894621 [DEBUG] hpack encode: vary: Accept-Encoding
2020-04-12 22:06:23.894625 [DEBUG] hpack encode: content-length: 17114
2020-04-12 22:06:23.894628 [DEBUG] hpack encode: date: Sun, 12 Apr 2020 22:06:21 GMT
2020-04-12 22:06:23.894631 [DEBUG] hpack encode: alt-svc: quic=":443"; ma=2592000; v="43,46", h3-Q043=":443"; ma=2592000, h3-Q046=":443"; ma=2592000, h3-Q050=":443"; ma=2592000, h3-25=":443"; ma=2592000, h3-27=":443"; ma=2592000
2020-04-12 22:06:23.894636 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(64), buffered: 64, total buffer size = 1710
2020-04-12 22:06:23.894642 [DEBUG] [192.168.1.1:55674:HTTP2-277] H2Stream::write(0x7fb6a7970000, 8192, 0), weight: 3
2020-04-12 22:06:23.894645 [DEBUG] [192.168.1.1:55674:HTTP2-277] send DATA frame, FIN: 0, data: 0x7fb6a7970000, len: 8192
2020-04-12 22:06:23.894649 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 1719
2020-04-12 22:06:23.894654 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(8192), buffered: 8192, total buffer size = 9911
2020-04-12 22:06:23.894657 [DEBUG] [192.168.1.1:55674:HTTP2-277] H2Stream::write(0x7fb6a7970000, 8192, 0) return 8192
2020-04-12 22:06:23.894661 [DEBUG] [192.168.1.1:55674:HTTP2-277] sent: 8192, current window: 6283264
2020-04-12 22:06:23.894664 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] writeRespBodyDirect(): write(0x7fb6a7970000, 8192) written 8192, total sent: 8192
2020-04-12 22:06:23.894667 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] writeRespBody() len = 8192, returned 8192.
2020-04-12 22:06:23.894671 [DEBUG] [192.168.1.1:55674:HTTP2-277] H2Stream::write(0x7fb6a796e000, 8192, 0), weight: 3
2020-04-12 22:06:23.894674 [DEBUG] [192.168.1.1:55674:HTTP2-277] send DATA frame, FIN: 0, data: 0x7fb6a796e000, len: 8192
2020-04-12 22:06:23.894680 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 9920
2020-04-12 22:06:23.894723 [DEBUG] [FDBIO] bio_fd_write: 0x148aae8, 16406 bytes on 25
2020-04-12 22:06:23.894729 [DEBUG] [FDBIO] ls_fdbio_buff_write, to write: 16406, used: 32812, sent: 0
2020-04-12 22:06:23.894735 [DEBUG] [FDBIO] lstls_buff_write, to write: 16406, finished: 16406, used: 49218, sent: 0
2020-04-12 22:06:23.894740 [DEBUG] SSL_write( 0x1489be8, 0x153b880, 16384) return 16384, pending 49218
2020-04-12 22:06:23.894743 [DEBUG] [192.168.1.1:55674:HTTP2] Need to write 16384 bytes, wrote 16384 bytes.
2020-04-12 22:06:23.894748 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(8192), buffered: 8192, total buffer size = 1728
2020-04-12 22:06:23.894751 [DEBUG] [192.168.1.1:55674:HTTP2-277] H2Stream::write(0x7fb6a796e000, 8192, 0) return 8192
2020-04-12 22:06:23.894754 [DEBUG] [192.168.1.1:55674:HTTP2-277] sent: 16384, current window: 6275072
2020-04-12 22:06:23.894757 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] writeRespBodyDirect(): write(0x7fb6a796e000, 8192) written 8192, total sent: 16384
2020-04-12 22:06:23.894761 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] writeRespBody() len = 8192, returned 8192.
2020-04-12 22:06:23.894764 [DEBUG] [192.168.1.1:55674:HTTP2-277] H2Stream::write(0x7fb6a796c000, 730, 0), weight: 3
2020-04-12 22:06:23.894767 [DEBUG] [192.168.1.1:55674:HTTP2-277] send DATA frame, FIN: 0, data: 0x7fb6a796c000, len: 730
2020-04-12 22:06:23.894770 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 1737
2020-04-12 22:06:23.894774 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(730), buffered: 730, total buffer size = 2467
2020-04-12 22:06:23.894777 [DEBUG] [192.168.1.1:55674:HTTP2-277] H2Stream::write(0x7fb6a796c000, 730, 0) return 730
2020-04-12 22:06:23.894780 [DEBUG] [192.168.1.1:55674:HTTP2-277] sent: 17114, current window: 6274342
2020-04-12 22:06:23.894783 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] writeRespBodyDirect(): write(0x7fb6a796c000, 730) written 730, total sent: 17114
2020-04-12 22:06:23.894786 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] writeRespBody() len = 730, returned 730.
2020-04-12 22:06:23.894789 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] flushBody() return 0
2020-04-12 22:06:23.894793 [DEBUG] [192.168.1.1:55674:HTTP2-277] H2Stream::flush()
2020-04-12 22:06:23.894796 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.894799 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.894802 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.894805 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Set the HSS_COMPLETE flag.
2020-04-12 22:06:23.894808 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] mark HSS_COMPLETE.
2020-04-12 22:06:23.894811 [DEBUG] [192.168.1.1:55674:HTTP2-277] H2Stream::shutdown()
2020-04-12 22:06:23.894815 [DEBUG] [192.168.1.1:55674:HTTP2-277] send DATA frame, with Flag: 1
2020-04-12 22:06:23.894818 [DEBUG] [192.168.1.1:55674:HTTP2] [H2] bufferOutput(9), buffered: 9, total buffer size = 2476
2020-04-12 22:06:23.894820 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.894823 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.894826 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.894829 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.894831 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.894834 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.894837 [DEBUG] [192.168.1.1:55674:HTTP2-277] add to priority queue: 0
2020-04-12 22:06:23.894841 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] HttpExtConnector::cleanUp() ...
2020-04-12 22:06:23.894851 [DEBUG] [T0 evtcbnode_s* EvtcbQue::getNodeObj(evtcb_pf, const evtcbtail_t*, long int, void*)] returning pObj 0x16bbfc0 session 0x15e0b08 tailnode (nil)
2020-04-12 22:06:23.894860 [DEBUG] [T0 evtcbnode_s* EvtcbQue::schedule(evtcb_pf, const evtcbtail_t*, long int, void*, bool)] sched evtq cb for session 0x15e0b08 sn 2
2020-04-12 22:06:23.894864 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)(null)] Obj=0x16bbfc0 Session=0x15e0b08 lParam=2 pParam=(nil) cb=0x4fa230 seq=299 slot=3 sn=2
2020-04-12 22:06:23.894868 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] locking event slot 3
2020-04-12 22:06:23.894871 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] schedule cb for session 0x15e0b08 tail (nil) seq 299 slot 3 sn 2
2020-04-12 22:06:23.894874 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] 0x16bbfc0 appended
2020-04-12 22:06:23.894877 [DEBUG] [T0 void EvtcbQue::schedule(evtcbnode_s*, bool)] Tail for session 0x15e0b08 slot 3 was (nil) set to 0x16bbfc0
2020-04-12 22:06:23.894883 [DEBUG] [192.168.1.1:55674:HTTP2-329#example.com:lsapi] ExtConn::onEventDone(), fd: 36, state: 2, event: 1, revents: 0.
2020-04-12 22:06:23.894907 [DEBUG] [T0 void EvtcbQue::run()] slot 1 begin 0x1700a20 end 0x1685d50
2020-04-12 22:06:23.894913 [DEBUG] [T0 void EvtcbQue::run() starting] Obj=0x1700a20 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.894916 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1700a20 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.894924 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1700a20 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.894933 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1700b20 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.894942 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1700b20 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.894946 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f33d0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.894953 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f33d0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.894958 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1703010 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.894961 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1703010 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.894964 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1703110 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.894971 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1703110 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.894975 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f1d50 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.894978 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f1d50 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.894981 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f1e50 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.894985 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f1e50 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.894987 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1695520 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.894991 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1695520 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.894994 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1694890 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.894997 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1694890 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895000 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1694990 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895003 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1694990 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895009 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16969d0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895012 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16969d0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895015 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1696ad0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895018 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1696ad0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895021 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16b8890 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895028 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16b8890 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895032 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16bc000 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895036 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16bc000 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895038 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16bc100 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895042 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16bc100 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895044 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1493f20 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895053 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1493f20 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895057 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x14b4970 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895060 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x14b4970 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895063 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1455bb0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895067 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1455bb0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895069 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1457220 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895073 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1457220 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895075 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1457260 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895079 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1457260 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895081 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1700ae0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895084 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1700ae0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895087 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1700be0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895091 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1700be0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895093 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1702fd0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895096 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1702fd0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895099 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x17030d0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895107 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x17030d0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895113 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16906e0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895117 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16906e0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895120 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f1e10 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895123 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f1e10 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895125 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f1f70 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895129 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f1f70 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895131 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1694e50 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895135 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1694e50 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895137 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1694950 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895141 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1694950 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895143 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16bf4f0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895146 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16bf4f0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895149 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1696a90 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.895153 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1696a90 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.895156 [DEBUG] [T0 void EvtcbQue::run()] running evtq cb for slot 1 session 0x160fc18 tail 0x1685d50 sn 2
2020-04-12 22:06:23.895159 [DEBUG] [T0 void EvtcbQue::run()] Tail was 0x1685d50 setting to NULL
2020-04-12 22:06:23.895162 [DEBUG] [T0 void EvtcbQue::run()] unlocked event slot 1
2020-04-12 22:06:23.895165 [DEBUG] [T0 void EvtcbQue::run()] appending 1 evtq cbs for slot 1
2020-04-12 22:06:23.895168 [DEBUG] [T0 void EvtcbQue::appendCur(TDLinkQueue<evtcbnode_s>*)] append 1 evtq to m_curRunQ
2020-04-12 22:06:23.895170 [DEBUG] [T0 void EvtcbQue::runCur()] locking runQ
2020-04-12 22:06:23.895173 [DEBUG] [T0 void EvtcbQue::runCur()] locked runQ
2020-04-12 22:06:23.895176 [DEBUG] [T0 void EvtcbQue::runCur()] calling pop_front on m_curRunQ, pre pop size 1
2020-04-12 22:06:23.895179 [DEBUG] [T0 void EvtcbQue::runCur()] popped pObj 0x1685d50 from m_curRunQ, new size 0, pObj->m_pSession 0x160fc18
2020-04-12 22:06:23.895182 [DEBUG] [T0 void EvtcbQue::runCur()] unlocked runQ
2020-04-12 22:06:23.895185 [DEBUG] [T0 void EvtcbQue::runOne(evtcbnode_s*)(null)] Obj=0x1685d50 Session=0x160fc18 lParam=2 pParam=(nil) cb=0x4fa230 seq=301 slot=1 sn=2
2020-04-12 22:06:23.895189 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x160fc18 seq 301 slot 1 tailnode NULL!
2020-04-12 22:06:23.895192 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] HttpSession::nextRequest()!
2020-04-12 22:06:23.895195 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] calling removeSessionCb on this 0x160fc10
2020-04-12 22:06:23.895198 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x160fc18 seq 301 slot 1 tailnode NULL!
2020-04-12 22:06:23.895201 [DEBUG] [192.168.1.1:55674:HTTP2-281] H2Stream::flush()
2020-04-12 22:06:23.895204 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.895206 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.895209 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.895215 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] Non-KeepAlive, CLOSING!
2020-04-12 22:06:23.895218 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] nextRequest(): HSPS_WEBSOCKET -> HSPS_CLOSE_SESSION
2020-04-12 22:06:23.895221 [DEBUG] [T0 void HttpSession::closeSession()] sess seq now 301
2020-04-12 22:06:23.895225 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] [HTTP_END] run Hook function for [Module:cache] session=0x160fc18
2020-04-12 22:06:23.895231 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] [HTTP_END] [Module:cache]  session=0x160fc18 ret 0
2020-04-12 22:06:23.928221 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x160fc18 seq 301 slot 1 tailnode NULL!
2020-04-12 22:06:23.928257 [DEBUG] [192.168.1.1:55674:HTTP2-281#example.com:lsapi] calling removeSessionCb on this 0x160fc10
2020-04-12 22:06:23.928261 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x160fc18 seq 301 slot 1 tailnode NULL!
2020-04-12 22:06:23.928268 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1685d50 Session=0x160fc18 lParam=2 pParam=(nil) cb=0x4fa230 seq=301 slot=1 sn=3
2020-04-12 22:06:23.928272 [DEBUG] [T0 void EvtcbQue::runCur()] locking runQ
2020-04-12 22:06:23.928275 [DEBUG] [T0 void EvtcbQue::runCur()] locked runQ
2020-04-12 22:06:23.928278 [DEBUG] [T0 void EvtcbQue::runCur()] calling pop_front on m_curRunQ, pre pop size 0
2020-04-12 22:06:23.928281 [DEBUG] [T0 void EvtcbQue::runCur()] NULL pop_front on m_curRunQ, size after pop 0
2020-04-12 22:06:23.928284 [DEBUG] [T0 void EvtcbQue::runCur()] unlocked runQ
2020-04-12 22:06:23.928288 [DEBUG] [T0 void EvtcbQue::run()] slot 2 begin 0x1696b90 end 0x1696b90
2020-04-12 22:06:23.928292 [DEBUG] [T0 void EvtcbQue::run() starting] Obj=0x1696b90 Session=0x1601ca8 lParam=2 pParam=(nil) cb=0x4fa230 seq=302 slot=2 sn=2
2020-04-12 22:06:23.928295 [DEBUG] [T0 void EvtcbQue::run()] running evtq cb for slot 2 session 0x1601ca8 tail 0x1696b90 sn 2
2020-04-12 22:06:23.928298 [DEBUG] [T0 void EvtcbQue::run()] Tail was 0x1696b90 setting to NULL
2020-04-12 22:06:23.928301 [DEBUG] [T0 void EvtcbQue::run()] unlocked event slot 2
2020-04-12 22:06:23.928304 [DEBUG] [T0 void EvtcbQue::run()] appending 1 evtq cbs for slot 2
2020-04-12 22:06:23.928307 [DEBUG] [T0 void EvtcbQue::appendCur(TDLinkQueue<evtcbnode_s>*)] append 1 evtq to m_curRunQ
2020-04-12 22:06:23.928310 [DEBUG] [T0 void EvtcbQue::runCur()] locking runQ
2020-04-12 22:06:23.928313 [DEBUG] [T0 void EvtcbQue::runCur()] locked runQ
2020-04-12 22:06:23.928316 [DEBUG] [T0 void EvtcbQue::runCur()] calling pop_front on m_curRunQ, pre pop size 1
2020-04-12 22:06:23.928319 [DEBUG] [T0 void EvtcbQue::runCur()] popped pObj 0x1696b90 from m_curRunQ, new size 0, pObj->m_pSession 0x1601ca8
2020-04-12 22:06:23.928321 [DEBUG] [T0 void EvtcbQue::runCur()] unlocked runQ
2020-04-12 22:06:23.928325 [DEBUG] [T0 void EvtcbQue::runOne(evtcbnode_s*)(null)] Obj=0x1696b90 Session=0x1601ca8 lParam=2 pParam=(nil) cb=0x4fa230 seq=302 slot=2 sn=2
2020-04-12 22:06:23.928328 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x1601ca8 seq 302 slot 2 tailnode NULL!
2020-04-12 22:06:23.928332 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] HttpSession::nextRequest()!
2020-04-12 22:06:23.928335 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] calling removeSessionCb on this 0x1601ca0
2020-04-12 22:06:23.928337 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x1601ca8 seq 302 slot 2 tailnode NULL!
2020-04-12 22:06:23.928341 [DEBUG] [192.168.1.1:55674:HTTP2-283] H2Stream::flush()
2020-04-12 22:06:23.928345 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.928349 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.928351 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.928355 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] Non-KeepAlive, CLOSING!
2020-04-12 22:06:23.928359 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] nextRequest(): HSPS_WEBSOCKET -> HSPS_CLOSE_SESSION
2020-04-12 22:06:23.928373 [DEBUG] [T0 void HttpSession::closeSession()] sess seq now 302
2020-04-12 22:06:23.928378 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] [HTTP_END] run Hook function for [Module:cache] session=0x1601ca8
2020-04-12 22:06:23.928384 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] [HTTP_END] [Module:cache]  session=0x1601ca8 ret 0
2020-04-12 22:06:23.928407 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x1601ca8 seq 302 slot 2 tailnode NULL!
2020-04-12 22:06:23.928412 [DEBUG] [192.168.1.1:55674:HTTP2-283#example.com:lsapi] calling removeSessionCb on this 0x1601ca0
2020-04-12 22:06:23.928415 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x1601ca8 seq 302 slot 2 tailnode NULL!
2020-04-12 22:06:23.928418 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1696b90 Session=0x1601ca8 lParam=2 pParam=(nil) cb=0x4fa230 seq=302 slot=2 sn=3
2020-04-12 22:06:23.928422 [DEBUG] [T0 void EvtcbQue::runCur()] locking runQ
2020-04-12 22:06:23.928424 [DEBUG] [T0 void EvtcbQue::runCur()] locked runQ
2020-04-12 22:06:23.928427 [DEBUG] [T0 void EvtcbQue::runCur()] calling pop_front on m_curRunQ, pre pop size 0
2020-04-12 22:06:23.928430 [DEBUG] [T0 void EvtcbQue::runCur()] NULL pop_front on m_curRunQ, size after pop 0
2020-04-12 22:06:23.928433 [DEBUG] [T0 void EvtcbQue::runCur()] unlocked runQ
2020-04-12 22:06:23.928436 [DEBUG] [T0 void EvtcbQue::run()] slot 3 begin 0x1700aa0 end 0x16bbfc0
2020-04-12 22:06:23.928439 [DEBUG] [T0 void EvtcbQue::run() starting] Obj=0x1700aa0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928443 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1700aa0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928446 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1700aa0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928449 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1700ba0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928456 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1700ba0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928461 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1704220 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928464 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1704220 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928467 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1703090 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928470 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1703090 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928473 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x17048c0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928476 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x17048c0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928479 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f1dd0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928482 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f1dd0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928485 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f1f30 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928489 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f1f30 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928492 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1694e10 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928495 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1694e10 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928498 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1694910 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928504 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1694910 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928507 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16bf3a0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928511 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16bf3a0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928513 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1696a50 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928517 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1696a50 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928520 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1696b50 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928523 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1696b50 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928526 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16bf8c0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928529 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16bf8c0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928532 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16bc080 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928535 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16bc080 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928538 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1494060 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928541 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1494060 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928544 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x14b4780 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928555 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x14b4780 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928559 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x168e140 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928562 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x168e140 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928565 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x145e040 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928574 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x145e040 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928577 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x145e080 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928581 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x145e080 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928584 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1700a60 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928587 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1700a60 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928590 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1700b60 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928597 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1700b60 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928602 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f9980 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928605 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f9980 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928608 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1703050 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928615 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1703050 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928618 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1703150 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928621 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1703150 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928624 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f1d90 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928627 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f1d90 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928630 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16f1e90 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928633 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16f1e90 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928636 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1696120 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928639 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1696120 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928642 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16948d0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928645 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16948d0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928648 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x16949d0 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928651 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16949d0 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928654 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1696a10 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928657 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1696a10 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928660 [DEBUG] [T0 void EvtcbQue::run()] called with pObj 0x1696b10 pObj->m_pSession NULL not allowed! SKIPPING
2020-04-12 22:06:23.928664 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x1696b10 Session=(nil) lParam=2 pParam=(nil) cb=(nil) seq=0 slot=0 sn=0
2020-04-12 22:06:23.928668 [DEBUG] [T0 void EvtcbQue::run()] running evtq cb for slot 3 session 0x15e0b08 tail 0x16bbfc0 sn 2
2020-04-12 22:06:23.928670 [DEBUG] [T0 void EvtcbQue::run()] Tail was 0x16bbfc0 setting to NULL
2020-04-12 22:06:23.928673 [DEBUG] [T0 void EvtcbQue::run()] unlocked event slot 3
2020-04-12 22:06:23.928676 [DEBUG] [T0 void EvtcbQue::run()] appending 1 evtq cbs for slot 3
2020-04-12 22:06:23.928679 [DEBUG] [T0 void EvtcbQue::appendCur(TDLinkQueue<evtcbnode_s>*)] append 1 evtq to m_curRunQ
2020-04-12 22:06:23.928681 [DEBUG] [T0 void EvtcbQue::runCur()] locking runQ
2020-04-12 22:06:23.928684 [DEBUG] [T0 void EvtcbQue::runCur()] locked runQ
2020-04-12 22:06:23.928687 [DEBUG] [T0 void EvtcbQue::runCur()] calling pop_front on m_curRunQ, pre pop size 1
2020-04-12 22:06:23.928690 [DEBUG] [T0 void EvtcbQue::runCur()] popped pObj 0x16bbfc0 from m_curRunQ, new size 0, pObj->m_pSession 0x15e0b08
2020-04-12 22:06:23.928693 [DEBUG] [T0 void EvtcbQue::runCur()] unlocked runQ
2020-04-12 22:06:23.928696 [DEBUG] [T0 void EvtcbQue::runOne(evtcbnode_s*)(null)] Obj=0x16bbfc0 Session=0x15e0b08 lParam=2 pParam=(nil) cb=0x4fa230 seq=299 slot=3 sn=2
2020-04-12 22:06:23.928699 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x15e0b08 seq 299 slot 3 tailnode NULL!
2020-04-12 22:06:23.928702 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] HttpSession::nextRequest()!
2020-04-12 22:06:23.928705 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] calling removeSessionCb on this 0x15e0b00
2020-04-12 22:06:23.928711 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x15e0b08 seq 299 slot 3 tailnode NULL!
2020-04-12 22:06:23.928714 [DEBUG] [192.168.1.1:55674:HTTP2-277] H2Stream::flush()
2020-04-12 22:06:23.928717 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::continueWrite()...
2020-04-12 22:06:23.928720 [DEBUG] [192.168.1.1:55674:HTTP2] Throttled!
2020-04-12 22:06:23.928722 [DEBUG] [192.168.1.1:55674:HTTP2] write resumed!
2020-04-12 22:06:23.928725 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] Non-KeepAlive, CLOSING!
2020-04-12 22:06:23.928728 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] nextRequest(): HSPS_WEBSOCKET -> HSPS_CLOSE_SESSION
2020-04-12 22:06:23.928731 [DEBUG] [T0 void HttpSession::closeSession()] sess seq now 299
2020-04-12 22:06:23.928734 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] [HTTP_END] run Hook function for [Module:cache] session=0x15e0b08
2020-04-12 22:06:23.928738 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] [HTTP_END] [Module:cache]  session=0x15e0b08 ret 0
2020-04-12 22:06:23.928758 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x15e0b08 seq 299 slot 3 tailnode NULL!
2020-04-12 22:06:23.928763 [DEBUG] [192.168.1.1:55674:HTTP2-277#example.com:lsapi] calling removeSessionCb on this 0x15e0b00
2020-04-12 22:06:23.928766 [DEBUG] [T0 int EvtcbQue::removeSessionCb(evtcbtail_t*)] session 0x15e0b08 seq 299 slot 3 tailnode NULL!
2020-04-12 22:06:23.928769 [DEBUG] [T0 void EvtcbQue::recycle(evtcbnode_s*)(null)] Obj=0x16bbfc0 Session=0x15e0b08 lParam=2 pParam=(nil) cb=0x4fa230 seq=299 slot=3 sn=3
2020-04-12 22:06:23.928773 [DEBUG] [T0 void EvtcbQue::runCur()] locking runQ
2020-04-12 22:06:23.928775 [DEBUG] [T0 void EvtcbQue::runCur()] locked runQ
2020-04-12 22:06:23.928778 [DEBUG] [T0 void EvtcbQue::runCur()] calling pop_front on m_curRunQ, pre pop size 0
2020-04-12 22:06:23.928781 [DEBUG] [T0 void EvtcbQue::runCur()] NULL pop_front on m_curRunQ, size after pop 0
2020-04-12 22:06:23.928784 [DEBUG] [T0 void EvtcbQue::runCur()] unlocked runQ
2020-04-12 22:06:23.928804 [DEBUG] [192.168.1.1:55674:HTTP2] NtwkIOLink::handleEvents() fd: 25, mask=29, events=5!
2020-04-12 22:06:23.928810 [DEBUG] [SSL: 0x14899f8] SSL_read
2020-04-12 22:06:23.928821 [DEBUG] [BIO] bio_fd_read: 0x148aae8, 5 bytes on 25
2020-04-12 22:06:23.928824 [DEBUG] [BIO] bio_fd_read: Read into buffer
2020-04-12 22:06:23.928841 [DEBUG] [BIO] bio_fd_read: Preserve read: 478
2020-04-12 22:06:23.928845 [DEBUG] [BIO] bio_fd_read: Use buffered 5 of 5
2020-04-12 22:06:23.928848 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 5
2020-04-12 22:06:23.928852 [DEBUG] [BIO] bio_fd_read: 0x148aae8, 81 bytes on 25
2020-04-12 22:06:23.928855 [DEBUG] [BIO] bio_fd_read: Use buffered 81 of 81
2020-04-12 22:06:23.928858 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 81
2020-04-12 22:06:23.928867 [DEBUG] [SSL: 0x14899f8] SSL_read returned 64
2020-04-12 22:06:23.928870 [DEBUG] [SSL: 0x14899f8] SSL_read
2020-04-12 22:06:23.928873 [DEBUG] [BIO] bio_fd_read: 0x148aae8, 5 bytes on 25
2020-04-12 22:06:23.928875 [DEBUG] [BIO] bio_fd_read: Use buffered 5 of 5
2020-04-12 22:06:23.928878 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 5
2020-04-12 22:06:23.928881 [DEBUG] [BIO] bio_fd_read: 0x148aae8, 241 bytes on 25
2020-04-12 22:06:23.928884 [DEBUG] [BIO] bio_fd_read: Use buffered 241 of 241
2020-04-12 22:06:23.928887 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 241
2020-04-12 22:06:23.928891 [DEBUG] [SSL: 0x14899f8] SSL_read returned 224
2020-04-12 22:06:23.928894 [DEBUG] [SSL: 0x14899f8] SSL_read
2020-04-12 22:06:23.928897 [DEBUG] [BIO] bio_fd_read: 0x148aae8, 5 bytes on 25
2020-04-12 22:06:23.928900 [DEBUG] [BIO] bio_fd_read: Use buffered 5 of 5
2020-04-12 22:06:23.928902 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 5
2020-04-12 22:06:23.928906 [DEBUG] [BIO] bio_fd_read: 0x148aae8, 68 bytes on 25
2020-04-12 22:06:23.928908 [DEBUG] [BIO] bio_fd_read: Use buffered 68 of 68
2020-04-12 22:06:23.928911 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 68
2020-04-12 22:06:23.928914 [DEBUG] [SSL: 0x14899f8] SSL_read returned 51
2020-04-12 22:06:23.928917 [DEBUG] [SSL: 0x14899f8] SSL_read
2020-04-12 22:06:23.928924 [DEBUG] [BIO] bio_fd_read: 0x148aae8, 5 bytes on 25
2020-04-12 22:06:23.928927 [DEBUG] [BIO] bio_fd_read: Use buffered 5 of 5
2020-04-12 22:06:23.928929 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 5
2020-04-12 22:06:23.928932 [DEBUG] [BIO] bio_fd_read: 0x148aae8, 68 bytes on 25
2020-04-12 22:06:23.928935 [DEBUG] [BIO] bio_fd_read: Use buffered 68 of 68
2020-04-12 22:06:23.928937 [DEBUG] [BIO] bio_fd_read: GOT TOTAL 68
2020-04-12 22:06:23.928941 [DEBUG] [SSL: 0x14899f8] SSL_read returned 51
2020-04-12 22:06:23.928944 [DEBUG] [192.168.1.1:55674:HTTP2] read() return 390
2020-04-12 22:06:23.928947 [DEBUG] [192.168.1.1:55674:HTTP2] frame type 1, size: 55
2020-04-12 22:06:23.928952 [DEBUG] [192.168.1.1:55674:HTTP2-637] Received HEADERS, size: 55, flag: 0x24, Message: ''
2020-04-12 22:06:23.928956 [DEBUG] [192.168.1.1:55674:HTTP2-637] stream priority, execlusive: 1, depend sid: 635, weight: 220
2020-04-12 22:06:23.928960 [DEBUG] [192.168.1.1:55674:HTTP2-637] getNewStream(), stream map size: 14, push stream: 0, shutdown streams: 3, flag: 36
2020-04-12 22:06:23.928970 [DEBUG] [192.168.1.1:55674:HTTP2-637] H2Stream::init(), id: 637, priority: 1, flag: 196608.
2020-04-12 22:06:23.929007 [DEBUG] [192.168.1.1:55674:HTTP2] decodeHeaders():
POST /wp-admin/admin-ajax.php HTTP/1.1
host: example.com
content-length: 215
accept: application/json, text/javascript, */*; q=0.01
sec-fetch-dest: empty
x-requested-with: XMLHttpRequest
user-agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.163 Safari/537.36
dnt: 1
content-type: application/x-www-form-urlencoded; charset=UTF-8
origin: https://example.com
sec-fetch-site: same-origin
sec-fetch-mode: cors
referer: https://example.com/wp-admin/post.php?post=8630&action=elementor
accept-encoding: gzip, deflate, br
accept-language: en-US,en;q=0.9,he;q=0.8
cookie: wordpress_sec_c6de0a2ce59b7fc2ccde2a19d7e48a68=example%7C1586894009%7C0C7iHlTCeo1W6Ovx9b7KjvPnuZpftjxDzGVvdDJE2Ap%7C063cb35dcc1bb6471eb721b4e7b985e9279004daa2859e61833ceef153661492; HappyLocalTimeZone=GMT-0400 (Eastern Daylight Time); wordpress_test_cookie=WP+Cookie+check; _lscache_vary=4c8bd2d3042808b64ef20e8d6e97aef4; wordpress_logged_in_c6de0a2ce59b7fc2ccde2a19d7e48a68=example%7C1586894009%7C0C7iHlTCeo1W6Ovx9b7KjvPnuZpftjxDzGVvdDJE2Ap%7C54371d747dc79afb2550f247dc2f17a6ee62e2ceded76cd74f6278074afd072c; wp-settings-1=libraryContent%3Dbrowse%26editor%3Dtinymce%26hidetb%3D1%26post_dfw%3Doff%26editor_plain_text_paste_warning%3D2%26posts_list_mode%3Dlist%26mfold%3Do; wp-settings-time-1=1586721210

2020-04-12 22:06:23.929012 [DEBUG] [192.168.1.1:55674:HTTP2-637] add to priority queue: 1
2020-04-12 22:06:23.929016 [DEBUG] [192.168.1.1:55674:HTTP2] frame type 0, size: 215
2020-04-12 22:06:23.929020 [DEBUG] [192.168.1.1:55674:HTTP2-637] Received DATA, size: 215, flag: 0x1, Message: ''
2020-04-12 22:06:23.929034 [DEBUG] resetEvtcb calling resetEvtcbTail on this 0x17374b0
2020-04-12 22:06:23.929039 [DEBUG] [T0 void EvtcbQue::resetEvtcbTail(evtcbtail_t*)] session 0x17374b8 tail 0x7fb6a6fd0210 sn 1 set Tail to NULL
2020-04-12 22:06:23.929042 [DEBUG] [T0 HttpSession::HttpSession()] sess seq now 645
2020-04-12 22:06:23.929047 [DEBUG] [192.168.1.1:55674:HTTP2-637] onInitConnected(): HSPS_START -> HSPS_READ_REQ_HEADER
2020-04-12 22:06:23.929050 [DEBUG] [T0 virtual int HttpSession::onInitConnected()] sess seq now 645

Pong · Apr 13, 2020

You should check your server load and PHP progress to see if they jumped too high during the process. If so, could possibly some bug on elementor to trigger some inifinte loop etc.

ego · Apr 14, 2020

Okay, i will try to review and report. Hopefully this will help another.

If PHP hangs on an infinite loop, likely i can experience this problem on any script. What are the configs to terminate infinite loops?

Pong · Apr 14, 2020

You just need to kill all lsphp. like

Code:

pkill lsphp

"pObj->m_pSession NULL" error log on WordPress Elementor

ego

New Member

Pong

Administrator

ego

New Member

Pong

Administrator