Spring Framework
  1. Spring Framework
  2. SPR-10854

SockJS: fallback to http-streaming fails after 128K of data

    Details

    • Type: Bug Bug
    • Status: Closed
    • Priority: Critical Critical
    • Resolution: Complete
    • Affects Version/s: 4.0 M2
    • Fix Version/s: 4.0 RC1
    • Component/s: Messaging:WebSocket
    • Labels:
      None
    • Last commented by a User:
      false

      Description

      Use the sample application spring-websocket-portfolio with IE9
      IE9 issues a HTTP get to receive all updates.
      After 128K, the server completes the async request:

      09:22:03 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-922\ndestination:/topic/pric...(truncated)'
      09:22:03 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-922
      ndestina...(truncated)'
      09:22:03 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 131090 bytes written so far, 0 more messages not flushed
      09:22:03 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Streamed bytes limit reached. Recycling current request
      09:22:03 DEBUG [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Completing asynchronous request

      IE9 issues another http GET and server starts to send data
      in the console of IE9, the following error is logged multiple times:
      SCRIPT5009: 'p' is undefined
      htmlfile?c=_jp.afmmijq, line 35 character 1

        Activity

        Hide
        Rossen Stoyanchev added a comment -

        IE9 issues another http GET and server starts to send data

        So is there any more server-side logging? Or is the above the last line? The above shows the server had completed the request ok, all messages were flushed, and would process the next request.

        Show
        Rossen Stoyanchev added a comment - IE9 issues another http GET and server starts to send data So is there any more server-side logging? Or is the above the last line? The above shows the server had completed the request ok, all messages were flushed, and would process the next request.
        Hide
        Pieter added a comment -

        The server then starts sending updates (these is the logging immediately following the logging above).
        IE9 receives the messages which are then send by the server, but it fails to process them with the error message: SCRIPT5009: 'p' is undefined

        09:22:04 TRACE [qtp1585222203-22] [DispatcherServlet] - Bound request context to thread: SecurityContextHolderAwareRequestWrapper[ org.springframework.security.web.context.HttpSessionSecurityContextRepository$Servlet3SaveToSessionRequestWrapper@2f0ee38]
        09:22:04 DEBUG [qtp1585222203-22] [DispatcherServlet] - DispatcherServlet with name 'dispatcher' processing GET request for [/spring-websocket-portfolio/portfolio/288/17kxgwnl/htmlfile]
        09:22:04 TRACE [qtp1585222203-22] [DispatcherServlet] - Testing handler map [org.springframework.web.servlet.handler.SimpleUrlHandlerMapping@5ed9dfe] in DispatcherServlet with name 'dispatcher'
        09:22:04 DEBUG [qtp1585222203-22] [SimpleUrlHandlerMapping] - Matching patterns for request [/portfolio/288/17kxgwnl/htmlfile] are [/portfolio/**]
        09:22:04 DEBUG [qtp1585222203-22] [SimpleUrlHandlerMapping] - URI Template variables for request [/portfolio/288/17kxgwnl/htmlfile] are {}
        09:22:04 DEBUG [qtp1585222203-22] [SimpleUrlHandlerMapping] - Mapping [/portfolio/288/17kxgwnl/htmlfile] to HandlerExecutionChain with handler [org.springframework.web.socket.sockjs.SockJsHttpRequestHandler@362eb6c] and 1 interceptor
        09:22:04 TRACE [qtp1585222203-22] [DispatcherServlet] - Testing handler adapter [org.springframework.web.servlet.mvc.HttpRequestHandlerAdapter@78016c69]
        09:22:04 DEBUG [qtp1585222203-22] [DispatcherServlet] - Last-Modified value for [/spring-websocket-portfolio/portfolio/288/17kxgwnl/htmlfile] is: -1
        09:22:04 DEBUG [qtp1585222203-22] [DefaultSockJsService] - GET with SockJS path [/288/17kxgwnl/htmlfile]
        09:22:04 DEBUG [qtp1585222203-22] [HtmlFileTransportHandler] - starting htmlfile async request
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-928\ndestination:/topic/pric...(truncated)'
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-928
        ndestina...(truncated)'
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 142 bytes written so far, 3 more messages not flushed
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-927\ndestination:/topic/pric...(truncated)'
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-927
        ndestina...(truncated)'
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 284 bytes written so far, 2 more messages not flushed
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-926\ndestination:/topic/pric...(truncated)'
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-926
        ndestina...(truncated)'
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 426 bytes written so far, 1 more messages not flushed
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-924\ndestination:/topic/pric...(truncated)'
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-924
        ndestina...(truncated)'
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 568 bytes written so far, 0 more messages not flushed
        09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        09:22:04 DEBUG [qtp1585222203-22] [DispatcherServlet] - Null ModelAndView returned to DispatcherServlet with name 'dispatcher': assuming HandlerAdapter completed request handling
        09:22:04 TRACE [qtp1585222203-22] [DispatcherServlet] - Cleared thread-bound request context: SecurityContextHolderAwareRequestWrapper[ org.springframework.security.web.context.HttpSessionSecurityContextRepository$Servlet3SaveToSessionRequestWrapper@2f0ee38]
        09:22:04 DEBUG [qtp1585222203-22] [DispatcherServlet] - Successfully completed request
        09:22:04 TRACE [qtp1585222203-22] [AnnotationConfigWebApplicationContext] - Publishing event in WebApplicationContext for namespace 'dispatcher-servlet': ServletRequestHandledEvent: url=[/spring-websocket-portfolio/portfolio/288/17kxgwnl/htmlfile]; client=[10.232.8.163]; method=[GET]; servlet=[dispatcher]; session=[8cttnyjzmzmgknepgj831t3v]; user=[fabrice]; time=[3ms]; status=[OK]
        09:22:04 TRACE [qtp1585222203-22] [AnnotationConfigWebApplicationContext] - Publishing event in Root WebApplicationContext: ServletRequestHandledEvent: url=[/spring-websocket-portfolio/portfolio/288/17kxgwnl/htmlfile]; client=[10.232.8.163]; method=[GET]; servlet=[dispatcher]; session=[8cttnyjzmzmgknepgj831t3v]; user=[fabrice]; time=[3ms]; status=[OK]
        09:22:08 TRACE [SockJS-4] [DefaultSockJsService] - Checking 1 session(s) for timeouts [SockJSService@7fc99326]
        09:22:08 TRACE [SockJS-4] [DefaultSockJsService] - 1 remaining session(s) [SockJSService@7fc99326]
        09:22:13 TRACE [SockJS-2] [DefaultSockJsService] - Checking 1 session(s) for timeouts [SockJSService@7fc99326]
        09:22:13 TRACE [SockJS-2] [DefaultSockJsService] - 1 remaining session(s) [SockJSService@7fc99326]
        09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=MSFT, this.price=34.63]
        09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload=[B@4cd0491a][Headers={id=1c4d7464-7b4d-45f8-998a-26cef883c57f, timestamp=1377156133990, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.MSFT}]
        09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=ORCL, this.price=31.14]
        09:22:13 TRACE [MessageChannel-4] [SimpleBrokerMessageHandler] - Processing [Payload=[B@4cd0491a][Headers={id=1c4d7464-7b4d-45f8-998a-26cef883c57f, timestamp=1377156133990, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.MSFT}]
        09:22:13 TRACE [MessageChannel-4] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.MSFT
        09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload=[B@7e6f715b][Headers={id=c3515437-8274-429d-a9df-fcd626fba250, timestamp=1377156133990, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.ORCL}]
        09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=CTXS, this.price=24.26]
        09:22:13 TRACE [MessageChannel-1] [SimpleBrokerMessageHandler] - Processing [Payload=[B@7e6f715b][Headers={id=c3515437-8274-429d-a9df-fcd626fba250, timestamp=1377156133990, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.ORCL}]
        09:22:13 TRACE [MessageChannel-4] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload=[B@4cd0491a][Headers={timestamp=1377156133991, id=574cee11-beb2-4fd3-846a-aa9036800485, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.MSFT}]
        09:22:13 TRACE [MessageChannel-1] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.ORCL
        09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload=[B@467b4e36][Headers={id=a59b8f87-7cb9-4d72-9e05-701e72e7593e, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.CTXS}]
        09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=GOOG, this.price=895.5]
        09:22:13 TRACE [MessageChannel-1] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload=[B@7e6f715b][Headers={timestamp=1377156133991, id=d0d54777-aa63-428b-b2df-2eafeace2f0d, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.ORCL}]
        09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload=[B@494bdf60][Headers={id=e2fd5abe-59cc-4aa2-b2bf-38efc154d371, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.GOOG}]
        09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=DELL, this.price=13.028]
        09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload=[B@14363d38][Headers={id=1ab83140-fd1c-4c45-8ee8-efcffb014b94, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.DELL}]
        09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=EMC, this.price=24.39]
        09:22:13 TRACE [MessageChannel-2] [SimpleBrokerMessageHandler] - Processing [Payload=[B@494bdf60][Headers={id=e2fd5abe-59cc-4aa2-b2bf-38efc154d371, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.GOOG}]
        09:22:13 TRACE [MessageChannel-2] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.GOOG
        09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload=[B@74ed31e5][Headers={id=333b0443-c534-48e0-bb09-29042624fb28, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.EMC}]
        09:22:13 TRACE [MessageChannel-2] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload=[B@494bdf60][Headers={timestamp=1377156133991, id=1e90b6d9-a5a9-4085-aece-2b422c900f1e, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.GOOG}]
        09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=VMW, this.price=67.24]
        09:22:13 TRACE [MessageChannel-2] [SimpleBrokerMessageHandler] - Processing [Payload=[B@14363d38][Headers={id=1ab83140-fd1c-4c45-8ee8-efcffb014b94, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.DELL}]
        09:22:13 TRACE [MessageChannel-2] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.DELL
        09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload=[B@66ed5c][Headers={id=02ff90a8-c316-448a-8e60-2d9e774b95e3, timestamp=1377156133992, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.VMW}]
        09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=RHT, this.price=48.57]
        09:22:13 TRACE [MessageChannel-1] [SimpleBrokerMessageHandler] - Processing [Payload=[B@467b4e36][Headers={id=a59b8f87-7cb9-4d72-9e05-701e72e7593e, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.CTXS}]
        09:22:13 TRACE [MessageChannel-1] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.CTXS
        09:22:13 TRACE [MessageChannel-8] [SimpleBrokerMessageHandler] - Processing [Payload=[B@74ed31e5][Headers={id=333b0443-c534-48e0-bb09-29042624fb28, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.EMC}]
        09:22:13 TRACE [MessageChannel-8] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.EMC
        09:22:13 TRACE [MessageChannel-8] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload=[B@74ed31e5][Headers={timestamp=1377156133992, id=a5ec0ae1-81ff-4804-9df7-0b91d1b7cb7a, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.EMC}]
        09:22:13 TRACE [MessageChannel-2] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload=[B@14363d38][Headers={timestamp=1377156133992, id=1d0e00d8-5fce-410f-b272-106c957e68f0, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.DELL}]
        09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages
        09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat
        09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-929\ndestination:/topic/pric...(truncated)'
        09:22:13 TRACE [MessageChannel-7] [SimpleBrokerMessageHandler] - Processing [Payload=[B@66ed5c][Headers={id=02ff90a8-c316-448a-8e60-2d9e774b95e3, timestamp=1377156133992, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.VMW}]
        09:22:13 TRACE [MessageChannel-7] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.VMW
        09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-929
        ndestina...(truncated)'
        09:22:13 TRACE [MessageChannel-7] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload=[B@66ed5c][Headers={timestamp=1377156133993, id=ac64b2df-fc9d-4a09-927c-f98a66cf2be9, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.VMW}]
        09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload=[B@65693338][Headers={id=7373ceeb-f708-47f9-a2a7-b568f914622f, timestamp=1377156133992, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.RHT}]
        09:22:13 TRACE [MessageChannel-1] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload=[B@467b4e36][Headers={timestamp=1377156133992, id=dc720ac8-06d1-4118-9210-60a436252278, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.CTXS}]
        09:22:13 TRACE [MessageChannel-3] [SimpleBrokerMessageHandler] - Processing [Payload=[B@65693338][Headers={id=7373ceeb-f708-47f9-a2a7-b568f914622f, timestamp=1377156133992, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.RHT}]
        09:22:13 TRACE [MessageChannel-3] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.RHT
        09:22:13 TRACE [MessageChannel-3] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload=[B@65693338][Headers={timestamp=1377156133994, id=f31e7a57-c3e3-4cbf-b62e-b766b61f9b59, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.RHT}]
        09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 710 bytes written so far, 0 more messages not flushed
        09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages
        09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat
        09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-936\ndestination:/topic/pric...(truncated)'
        09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-936
        ndestina...(truncated)'
        09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 850 bytes written so far, 0 more messages not flushed
        09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages
        09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat
        09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-935\ndestination:/topic/pric...(truncated)'
        09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-935
        ndestina...(truncated)'
        09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 992 bytes written so far, 0 more messages not flushed
        09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages
        09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat
        09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-934\ndestination:/topic/pric...(truncated)'
        09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-934
        ndestina...(truncated)'
        09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1132 bytes written so far, 0 more messages not flushed
        09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages
        09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat
        09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-932\ndestination:/topic/pric...(truncated)'
        09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-932
        ndestina...(truncated)'
        09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1272 bytes written so far, 0 more messages not flushed
        09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages
        09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat
        09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-933\ndestination:/topic/pric...(truncated)'
        09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-933
        ndestina...(truncated)'
        09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1415 bytes written so far, 0 more messages not flushed
        09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages
        09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat
        09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-931\ndestination:/topic/pric...(truncated)'
        09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-931
        ndestina...(truncated)'
        09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1557 bytes written so far, 0 more messages not flushed
        09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages
        09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat
        09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-930\ndestination:/topic/pric...(truncated)'
        09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-930
        ndestina...(truncated)'
        09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1699 bytes written so far, 0 more messages not flushed
        09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds

        Show
        Pieter added a comment - The server then starts sending updates (these is the logging immediately following the logging above). IE9 receives the messages which are then send by the server, but it fails to process them with the error message: SCRIPT5009: 'p' is undefined 09:22:04 TRACE [qtp1585222203-22] [DispatcherServlet] - Bound request context to thread: SecurityContextHolderAwareRequestWrapper[ org.springframework.security.web.context.HttpSessionSecurityContextRepository$Servlet3SaveToSessionRequestWrapper@2f0ee38] 09:22:04 DEBUG [qtp1585222203-22] [DispatcherServlet] - DispatcherServlet with name 'dispatcher' processing GET request for [/spring-websocket-portfolio/portfolio/288/17kxgwnl/htmlfile] 09:22:04 TRACE [qtp1585222203-22] [DispatcherServlet] - Testing handler map [org.springframework.web.servlet.handler.SimpleUrlHandlerMapping@5ed9dfe] in DispatcherServlet with name 'dispatcher' 09:22:04 DEBUG [qtp1585222203-22] [SimpleUrlHandlerMapping] - Matching patterns for request [/portfolio/288/17kxgwnl/htmlfile] are [/portfolio/**] 09:22:04 DEBUG [qtp1585222203-22] [SimpleUrlHandlerMapping] - URI Template variables for request [/portfolio/288/17kxgwnl/htmlfile] are {} 09:22:04 DEBUG [qtp1585222203-22] [SimpleUrlHandlerMapping] - Mapping [/portfolio/288/17kxgwnl/htmlfile] to HandlerExecutionChain with handler [org.springframework.web.socket.sockjs.SockJsHttpRequestHandler@362eb6c] and 1 interceptor 09:22:04 TRACE [qtp1585222203-22] [DispatcherServlet] - Testing handler adapter [org.springframework.web.servlet.mvc.HttpRequestHandlerAdapter@78016c69] 09:22:04 DEBUG [qtp1585222203-22] [DispatcherServlet] - Last-Modified value for [/spring-websocket-portfolio/portfolio/288/17kxgwnl/htmlfile] is: -1 09:22:04 DEBUG [qtp1585222203-22] [DefaultSockJsService] - GET with SockJS path [/288/17kxgwnl/htmlfile] 09:22:04 DEBUG [qtp1585222203-22] [HtmlFileTransportHandler] - starting htmlfile async request 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-928\ndestination:/topic/pric...(truncated)' 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-928 ndestina...(truncated)' 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 142 bytes written so far, 3 more messages not flushed 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-927\ndestination:/topic/pric...(truncated)' 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-927 ndestina...(truncated)' 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 284 bytes written so far, 2 more messages not flushed 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-926\ndestination:/topic/pric...(truncated)' 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-926 ndestina...(truncated)' 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 426 bytes written so far, 1 more messages not flushed 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-924\ndestination:/topic/pric...(truncated)' 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-924 ndestina...(truncated)' 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 568 bytes written so far, 0 more messages not flushed 09:22:04 TRACE [qtp1585222203-22] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds 09:22:04 DEBUG [qtp1585222203-22] [DispatcherServlet] - Null ModelAndView returned to DispatcherServlet with name 'dispatcher': assuming HandlerAdapter completed request handling 09:22:04 TRACE [qtp1585222203-22] [DispatcherServlet] - Cleared thread-bound request context: SecurityContextHolderAwareRequestWrapper[ org.springframework.security.web.context.HttpSessionSecurityContextRepository$Servlet3SaveToSessionRequestWrapper@2f0ee38] 09:22:04 DEBUG [qtp1585222203-22] [DispatcherServlet] - Successfully completed request 09:22:04 TRACE [qtp1585222203-22] [AnnotationConfigWebApplicationContext] - Publishing event in WebApplicationContext for namespace 'dispatcher-servlet': ServletRequestHandledEvent: url= [/spring-websocket-portfolio/portfolio/288/17kxgwnl/htmlfile] ; client= [10.232.8.163] ; method= [GET] ; servlet= [dispatcher] ; session= [8cttnyjzmzmgknepgj831t3v] ; user= [fabrice] ; time= [3ms] ; status= [OK] 09:22:04 TRACE [qtp1585222203-22] [AnnotationConfigWebApplicationContext] - Publishing event in Root WebApplicationContext: ServletRequestHandledEvent: url= [/spring-websocket-portfolio/portfolio/288/17kxgwnl/htmlfile] ; client= [10.232.8.163] ; method= [GET] ; servlet= [dispatcher] ; session= [8cttnyjzmzmgknepgj831t3v] ; user= [fabrice] ; time= [3ms] ; status= [OK] 09:22:08 TRACE [SockJS-4] [DefaultSockJsService] - Checking 1 session(s) for timeouts [SockJSService@7fc99326] 09:22:08 TRACE [SockJS-4] [DefaultSockJsService] - 1 remaining session(s) [SockJSService@7fc99326] 09:22:13 TRACE [SockJS-2] [DefaultSockJsService] - Checking 1 session(s) for timeouts [SockJSService@7fc99326] 09:22:13 TRACE [SockJS-2] [DefaultSockJsService] - 1 remaining session(s) [SockJSService@7fc99326] 09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=MSFT, this.price=34.63] 09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload= [B@4cd0491a] [Headers={id=1c4d7464-7b4d-45f8-998a-26cef883c57f, timestamp=1377156133990, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.MSFT}] 09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=ORCL, this.price=31.14] 09:22:13 TRACE [MessageChannel-4] [SimpleBrokerMessageHandler] - Processing [Payload= [B@4cd0491a] [Headers={id=1c4d7464-7b4d-45f8-998a-26cef883c57f, timestamp=1377156133990, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.MSFT}] 09:22:13 TRACE [MessageChannel-4] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.MSFT 09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload= [B@7e6f715b] [Headers={id=c3515437-8274-429d-a9df-fcd626fba250, timestamp=1377156133990, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.ORCL}] 09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=CTXS, this.price=24.26] 09:22:13 TRACE [MessageChannel-1] [SimpleBrokerMessageHandler] - Processing [Payload= [B@7e6f715b] [Headers={id=c3515437-8274-429d-a9df-fcd626fba250, timestamp=1377156133990, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.ORCL}] 09:22:13 TRACE [MessageChannel-4] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload= [B@4cd0491a] [Headers={timestamp=1377156133991, id=574cee11-beb2-4fd3-846a-aa9036800485, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.MSFT}] 09:22:13 TRACE [MessageChannel-1] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.ORCL 09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload= [B@467b4e36] [Headers={id=a59b8f87-7cb9-4d72-9e05-701e72e7593e, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.CTXS}] 09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=GOOG, this.price=895.5] 09:22:13 TRACE [MessageChannel-1] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload= [B@7e6f715b] [Headers={timestamp=1377156133991, id=d0d54777-aa63-428b-b2df-2eafeace2f0d, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.ORCL}] 09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload= [B@494bdf60] [Headers={id=e2fd5abe-59cc-4aa2-b2bf-38efc154d371, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.GOOG}] 09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=DELL, this.price=13.028] 09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload= [B@14363d38] [Headers={id=1ab83140-fd1c-4c45-8ee8-efcffb014b94, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.DELL}] 09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=EMC, this.price=24.39] 09:22:13 TRACE [MessageChannel-2] [SimpleBrokerMessageHandler] - Processing [Payload= [B@494bdf60] [Headers={id=e2fd5abe-59cc-4aa2-b2bf-38efc154d371, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.GOOG}] 09:22:13 TRACE [MessageChannel-2] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.GOOG 09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload= [B@74ed31e5] [Headers={id=333b0443-c534-48e0-bb09-29042624fb28, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.EMC}] 09:22:13 TRACE [MessageChannel-2] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload= [B@494bdf60] [Headers={timestamp=1377156133991, id=1e90b6d9-a5a9-4085-aece-2b422c900f1e, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.GOOG}] 09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=VMW, this.price=67.24] 09:22:13 TRACE [MessageChannel-2] [SimpleBrokerMessageHandler] - Processing [Payload= [B@14363d38] [Headers={id=1ab83140-fd1c-4c45-8ee8-efcffb014b94, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.DELL}] 09:22:13 TRACE [MessageChannel-2] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.DELL 09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload= [B@66ed5c] [Headers={id=02ff90a8-c316-448a-8e60-2d9e774b95e3, timestamp=1377156133992, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.VMW}] 09:22:13 TRACE [SockJS-4] [QuoteService] - Sending quote Quote [ticker=RHT, this.price=48.57] 09:22:13 TRACE [MessageChannel-1] [SimpleBrokerMessageHandler] - Processing [Payload= [B@467b4e36] [Headers={id=a59b8f87-7cb9-4d72-9e05-701e72e7593e, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.CTXS}] 09:22:13 TRACE [MessageChannel-1] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.CTXS 09:22:13 TRACE [MessageChannel-8] [SimpleBrokerMessageHandler] - Processing [Payload= [B@74ed31e5] [Headers={id=333b0443-c534-48e0-bb09-29042624fb28, timestamp=1377156133991, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.EMC}] 09:22:13 TRACE [MessageChannel-8] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.EMC 09:22:13 TRACE [MessageChannel-8] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload= [B@74ed31e5] [Headers={timestamp=1377156133992, id=a5ec0ae1-81ff-4804-9df7-0b91d1b7cb7a, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.EMC}] 09:22:13 TRACE [MessageChannel-2] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload= [B@14363d38] [Headers={timestamp=1377156133992, id=1d0e00d8-5fce-410f-b272-106c957e68f0, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.DELL}] 09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages 09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat 09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-929\ndestination:/topic/pric...(truncated)' 09:22:13 TRACE [MessageChannel-7] [SimpleBrokerMessageHandler] - Processing [Payload= [B@66ed5c] [Headers={id=02ff90a8-c316-448a-8e60-2d9e774b95e3, timestamp=1377156133992, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.VMW}] 09:22:13 TRACE [MessageChannel-7] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.VMW 09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-929 ndestina...(truncated)' 09:22:13 TRACE [MessageChannel-7] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload= [B@66ed5c] [Headers={timestamp=1377156133993, id=ac64b2df-fc9d-4a09-927c-f98a66cf2be9, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.VMW}] 09:22:13 TRACE [SockJS-4] [ExecutorSubscribableChannel] - [dispatchChannel] sending message [Payload= [B@65693338] [Headers={id=7373ceeb-f708-47f9-a2a7-b568f914622f, timestamp=1377156133992, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.RHT}] 09:22:13 TRACE [MessageChannel-1] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload= [B@467b4e36] [Headers={timestamp=1377156133992, id=dc720ac8-06d1-4118-9210-60a436252278, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.CTXS}] 09:22:13 TRACE [MessageChannel-3] [SimpleBrokerMessageHandler] - Processing [Payload= [B@65693338] [Headers={id=7373ceeb-f708-47f9-a2a7-b568f914622f, timestamp=1377156133992, nativeHeaders={}, messageType=MESSAGE, destination=/topic/price.stock.RHT}] 09:22:13 TRACE [MessageChannel-3] [DefaultSubscriptionRegistry] - Find subscriptions, destination=/topic/price.stock.RHT 09:22:13 TRACE [MessageChannel-3] [ExecutorSubscribableChannel] - [webSocketHandlerChannel] sending message [Payload= [B@65693338] [Headers={timestamp=1377156133994, id=f31e7a57-c3e3-4cbf-b62e-b766b61f9b59, nativeHeaders={}, sessionId=17kxgwnl, messageType=MESSAGE, subscriptionId=sub-1, destination=/topic/price.stock.RHT}] 09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 710 bytes written so far, 0 more messages not flushed 09:22:13 TRACE [MessageChannel-4] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds 09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages 09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat 09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-936\ndestination:/topic/pric...(truncated)' 09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-936 ndestina...(truncated)' 09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 850 bytes written so far, 0 more messages not flushed 09:22:13 TRACE [MessageChannel-3] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds 09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages 09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat 09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-935\ndestination:/topic/pric...(truncated)' 09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-935 ndestina...(truncated)' 09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 992 bytes written so far, 0 more messages not flushed 09:22:13 TRACE [MessageChannel-1] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds 09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages 09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat 09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-934\ndestination:/topic/pric...(truncated)' 09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-934 ndestina...(truncated)' 09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1132 bytes written so far, 0 more messages not flushed 09:22:13 TRACE [MessageChannel-7] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds 09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages 09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat 09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-932\ndestination:/topic/pric...(truncated)' 09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-932 ndestina...(truncated)' 09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1272 bytes written so far, 0 more messages not flushed 09:22:13 TRACE [MessageChannel-8] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds 09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages 09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat 09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-933\ndestination:/topic/pric...(truncated)' 09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-933 ndestina...(truncated)' 09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1415 bytes written so far, 0 more messages not flushed 09:22:13 TRACE [MessageChannel-2] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds 09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages 09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat 09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-931\ndestination:/topic/pric...(truncated)' 09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-931 ndestina...(truncated)' 09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1557 bytes written so far, 0 more messages not flushed 09:22:13 TRACE [MessageChannel-5] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds 09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Flushing messages 09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Cancelling heartbeat 09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Preparing to write SockJsFrame content='a["MESSAGE\nsubscription:sub-1\nmessage-id:17kxgwnl-930\ndestination:/topic/pric...(truncated)' 09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Writing SockJsFrame content='<script>\np("a[\"MESSAGE\\nsubscription:sub-1\\nmessage-id:17kxgwnl-930 ndestina...(truncated)' 09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - 1699 bytes written so far, 0 more messages not flushed 09:22:13 TRACE [MessageChannel-6] [HtmlFileTransportHandler$HtmlFileStreamingSockJsSession] - Scheduled heartbeat after 25 seconds
        Hide
        Brian Clozel added a comment -

        Pieter Could you tell us exactly which versions do you use (i.e. application git SHA, IE version)?
        Could you also try with the latest version of the application?
        I'm trying to reproduce this issue.

        Thanks!

        Show
        Brian Clozel added a comment - Pieter Could you tell us exactly which versions do you use (i.e. application git SHA, IE version)? Could you also try with the latest version of the application? I'm trying to reproduce this issue. Thanks!
        Hide
        Brian Clozel added a comment -

        Hi Pieter, I managed to reproduce that issue. We'll keep you posted.
        Thanks a lot!

        Show
        Brian Clozel added a comment - Hi Pieter , I managed to reproduce that issue. We'll keep you posted. Thanks a lot!
        Hide
        Rossen Stoyanchev added a comment -

        This should be fixed now.

        Show
        Rossen Stoyanchev added a comment - This should be fixed now .

          People

          • Assignee:
            Rossen Stoyanchev
            Reporter:
            Pieter
            Last updater:
            Phil Webb
          • Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

            Dates

            • Created:
              Updated:
              Resolved:
              Days since last comment:
              29 weeks, 3 days ago