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

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

  1. MikeDVB

    MikeDVB Well-Known 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 Well-Known 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 Well-Known Member

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

    webizen Well-Known Member

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

Share This Page