LSWS 4.1.13 + Kayako Support Suite (and possibly other scripts)

Discussion in 'Bug Reports' started by MikeDVB, Jul 15, 2012.

  1. MikeDVB

    MikeDVB New Member

    When LSWS 4.1.13 is running, none of the web forms in Kayako SupportSuite work - they all return error messages about not submitting a department ID, or not entering a search query, etc... When I roll back to 4.1.12 this issue disappears entirely and the site/script/forms work as expected.

    The PHP version and binaries are the same and have not changed, but we are running PHP 5.3.14.

    This *appears* from the debug logging to me, to be a mod_rewrite issue. The logs indicate in 4.1.13 that it's doing a 301 permanent move where as 4.1.12 does not indicate this and does further processing. There are no mod_rewrite rules in the .htaccess, however:
    Code:
    root@redacted [/home/redacted/public_html_other/redacted.com/support]# cat .htaccess
    php_flag display_errors on
    php_flag register_globals on
    php_value max_execution_time 600
    php_value memory_limit 512M
    php_value max_input_time 600
    php_flag file_uploads on
    php_value upload_max_filesize 20M
    php_value max_file_uploads 20
    Here is the POST request of one of the forms that doesn't work on 4.1.13:
    Code:
    4.1.13 - Apparently it's rewriting the URL
    ============
    2012-07-15 04:04:02.602 [NOTICE] [69.243.173.1:47802-1#APVH_members.redacted.com] Content len: 16, Request line: 'POST /index.php?/Base/Search/Index HTTP/1.1'
    2012-07-15 04:04:02.602 [INFO] [69.243.173.1:47802-1#APVH_members.redacted.com] Cookie len: 321, SWIFT_client=a%3A1%3A%7Bs%3A15%3A%22templategroupid%22%3Bs%3A1%3A%221%22%3B%7D; SWIFT_session
    id40=52kilommcpyxzpedhz5v8q66k7kxgqy7; __utma=41635134.465161496.1342338067.1342338067.1342338067.1; __utmb=41635134.39.10.1342338067; __utmc=41635134; __utmz=41635134.1342338067.1.1.utmcsr=(direct)
    |utmccn=(direct)|utmcmd=(none)
    2012-07-15 04:04:02.602 [DEBUG] [69.243.173.1:47802-1#APVH_members.redacted.com] Read Request Body!
    2012-07-15 04:04:02.602 [DEBUG] [69.243.173.1:47802-1#APVH_members.redacted.com] Finished request body 16 bytes!
    2012-07-15 04:04:02.602 [DEBUG] [69.243.173.1:47802-1#APVH_members.redacted.com] Find context with URI: [/], location: [/home/redacted/public_html_other/redacted.com/support/]
    2012-07-15 04:04:02.602 [DEBUG] [69.243.173.1:47802-1#APVH_members.redacted.com] Find .htaccess context with URI: [/], location: [/home/redacted/public_html_other/redacted.com/support/
    ]
    2012-07-15 04:04:02.602 [DEBUG] [69.243.173.1:47802-1#APVH_members.redacted.com] processNewReq() return 14.
    [B][I][U][COLOR="Red"]2012-07-15 04:04:02.602 [DEBUG] [69.243.173.1:47802-1#APVH_members.redacted.com] HttpConnection::sendHttpError(),code=301 Moved Permanently[/COLOR][/U][/I][/B]
    2012-07-15 04:04:02.602 [DEBUG] [69.243.173.1:47802-1#APVH_members.redacted.com] HttpConnection::flush()!
    2012-07-15 04:04:02.602 [DEBUG] [69.243.173.1:47802-1#APVH_members.redacted.com] Written to client: 688
    2012-07-15 04:04:02.602 [DEBUG] [69.243.173.1:47802-1#APVH_members.redacted.com] HttpConnection::nextRequest()!
    2012-07-15 04:04:02.660 [DEBUG] [69.243.173.1:47802-2] HttpIOLink::handleEvents() events=1!
    2012-07-15 04:04:02.660 [DEBUG] [69.243.173.1:47802-2] HttpConnection::onReadEx(), state: 0!
    2012-07-15 04:04:02.660 [DEBUG] [69.243.173.1:47802-2] readToHeaderBuf().
    2012-07-15 04:04:02.660 [DEBUG] [69.243.173.1:47802-2] Read from client: 716
    2012-07-15 04:04:02.660 [DEBUG] [69.243.173.1:47802-2] read 716 bytes to header buffer
    2012-07-15 04:04:02.660 [DEBUG] [69.243.173.1:47802-2] processHeader() return 0, header state: 3.
    2012-07-15 04:04:02.660 [DEBUG] [69.243.173.1:47802-2] readToHeaderBuf() return 0.
    Last edited: Jul 15, 2012
  2. MikeDVB

    MikeDVB New Member

    Now here is 4.1.12 when submitting that exact same form:
    Code:
    4.1.12 - Apparently it's not rewriting the URL
    ============
    2012-07-15 04:14:17.003 [NOTICE] [69.243.173.1:35368-0#APVH_members.redacted.com] Content len: 16, Request line: 'POST /index.php?/Base/Search/Index HTTP/1.1'
    2012-07-15 04:14:17.003 [INFO] [69.243.173.1:35368-0#APVH_members.redacted.com] Cookie len: 321, SWIFT_client=a%3A1%3A%7Bs%3A15%3A%22templategroupid%22%3Bs%3A1%3A%221%22%3B%7D; SWIFT_sessionid40=52kilommcpyxzpedhz5v8q66k7kxgqy7; __utma=41635134.465161496.1342338067.1342338067.1342338067.1; __utmb=41635134.49.10.1342338067; __utmc=41635134; __utmz=41635134.1342338067.1.1.utmcsr=(direct)|utmccn=(direct)|utmcmd=(none)
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com] Read Request Body!
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com] Finished request body 16 bytes!
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com] Find context with URI: [/], location: [/home/redacted/public_html_other/redacted.com/support/]
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com] Find .htaccess context with URI: [/], location: [/home/redacted/public_html_other/redacted.com/support/]
    [B][I][U][COLOR="Red"]2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com] [REWRITE] Rewrite engine is not enabled for context '/'[/COLOR][/U][/I][/B]
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com] Find handler [APVH_redacted_Suphp:] for [.php]
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com] processContextPath() return 0
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com] ScanPost: 0
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com] run lsapi processor.
    2012-07-15 04:14:17.003 [DEBUG] [uds://tmp/lshttpd/APVH_redactedmforums.net_Suphp.sock] request [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] is assigned with connection!
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] request header is done
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] processor sent request body 16 bytes, total sent: 16
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] Request body done!
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] ExtConn::onEventDone(), fd: 443, state: 2, event: -1, revents: 5.
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] processNewReq() return 0.
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] ExtConn::onRead(), state: 2
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] LsapiConn::doRead()
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] process packet header 8 bytes
    2012-07-15 04:14:17.003 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] process packet header 0 bytes
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] ExtConn::onRead(), state: 2
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] LsapiConn::doRead()
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] process packet header 8 bytes
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] process response header 61 bytes
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] response header finished!
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] prepare cache
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] neither public, nor private cache are enabled
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] GZIP response body!
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] setupGzipBuf() begin GZIP stream.
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] process packet header 8 bytes
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] process response stream 7502 bytes, packet left: 7494
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] HttpExtConnector::processRespBodyData()
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] process packet header 8 bytes
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] [EXT] EndResponse( endCode=0, protocolStatus=0 )
    2012-07-15 04:14:17.148 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] release ExtProcessor!
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] endDynResp() end GZIP stream.
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] Written to client: 2973
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] writeRespBody() return 2759
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] ReqBody: 16, RespBody: 2759, HEC_COMPLETE!
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] m_pHandler->onWrite() return 0
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] HttpConnection::flush()!
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] HttpConnection::nextRequest()!
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] HttpExtConnector::cleanUp() ...
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] Non-KeepAlive, CLOSING!
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] Shutting down out-bound socket ...
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] Available Connections: 19926, concurrent conn: 0
    2012-07-15 04:14:17.149 [DEBUG] [69.243.173.1:35368-0#APVH_members.redacted.com:lsapi] Close socket ...
    Last edited: Jul 15, 2012
  3. MikeDVB

    MikeDVB New Member

    I'm surprised no response has been made to this thread, or the bug report I sent in... Hmm....
  4. webizen

    webizen New Member

    We are at Hostingcon. Will look at this when time is allowed.

Share This Page