Comet Listener in "pending" state on apache tomcat 8.5.6
67 views
Skip to first unread message
pshrey...@gmail.com
Apr 10, 2019, 7:34:12 AM4/10/19
to cometd-dev
Hi,
We were using cometd-java-*-2.3.2.jar with jetty versions 7.44 jars and bayeux-api-2.3.1.jar.
Now we have upgraded to come cometd-java-*-4.0.2 jar with jetty versions 9.4.1.4 and bayeux-api-4.0.2.jar.
But now our long polling listener is working fine with wildfly server. But with apache tomcat 8.5.6, after the handshake every time the listener is taking too much time and going in pending state.
Below is the network details when I inspect in Chrome browser,
As seen above, after handshake, first time "connect" is happening in 4ms.
But the subsequent requests are taking long,
Why it is happening in tomcat?
Thanks And Regards,
Shreyas Holla
Simone Bordet
Apr 10, 2019, 7:41:22 AM4/10/19
to cometd-dev
Hi,
This is normal behavior, it is how CometD works.
You will see the same behavior with Wildfly and Jetty.
There is nothing to worry about: wait 10-20 seconds (depending on the `timeout` parameter you have configured on the server) and you will see that connect request returning normally, and another being sent.
Simone Bordet
----
http://cometd.org
http://webtide.com
Developer advice, training, services and support
from the Jetty & CometD experts.
----
http://cometd.org
http://webtide.com
Developer advice, training, services and support
from the Jetty & CometD experts.
pshrey...@gmail.com
Apr 10, 2019, 8:07:27 AM4/10/19
to cometd-dev
Hi,
We are expecting the cometd listener to pick the request sent from server and process as and when received. The delay we are encountering for the first time that to only in tomcat. Before upgrade and after upgrade in Wildfly its happening in milliseconds which is our expected functionality. Can you tell me how to overcome this delay?
Thanks And Regards,
Shreyas Holla P.
Simone Bordet
Apr 10, 2019, 8:12:51 AM4/10/19
to cometd-dev
Hi,
On Wed, Apr 10, 2019 at 2:07 PM <pshrey...@gmail.com> wrote:
>
> We are expecting the cometd listener to pick the request sent from server
This does not make sense. Servers don't send requests.
> and process as and when received. The delay we are encountering for the first time that to only in tomcat.
I can assure you it happens in all servers.
> Before upgrade and after upgrade in Wildfly its happening in milliseconds which is our expected functionality.
If Wildfly returns such connect requests immediately, then it's an
abnormal behavior and should be fixed in Wildfly.
> Can you tell me how to overcome this delay?
You want this behavior, it's how long-polling works.
On Wed, Apr 10, 2019 at 2:07 PM <pshrey...@gmail.com> wrote:
>
> We are expecting the cometd listener to pick the request sent from server
> and process as and when received. The delay we are encountering for the first time that to only in tomcat.
> Before upgrade and after upgrade in Wildfly its happening in milliseconds which is our expected functionality.
abnormal behavior and should be fixed in Wildfly.
> Can you tell me how to overcome this delay?
pshrey...@gmail.com
Apr 10, 2019, 8:12:59 AM4/10/19
to cometd-dev
Hi,We are expecting the cometd listener to pick the request sent from server and process as and when received. The delay we are encountering for the first time that to only in tomcat. Before upgrade and after upgrade in Wildfly its happening in milliseconds which is our expected functionality. Can you tell me how to overcome this delay in case of tomcat?
pshrey...@gmail.com
Apr 10, 2019, 8:57:46 AM4/10/19
to cometd-dev
Maybe the way I conveyed is wrong. What I meant was it is. Publish subscribe model. So the server publishes the message tiding bayeux and that is happening fine. And the cometd on the client is used to make a handshake and establish the connection. So getting this published message is having the delay on the client side (Cometd listener in the JavaScript file) . Please let me know if you need more details
Simone Bordet
Apr 10, 2019, 9:18:16 AM4/10/19
to cometd-dev
Hi,
On Wed, Apr 10, 2019 at 2:57 PM <pshrey...@gmail.com> wrote:
>
> Maybe the way I conveyed is wrong. What I meant was it is. Publish subscribe model. So the server publishes the message tiding bayeux and that is happening fine. And the cometd on the client is used to make a handshake and establish the connection. So getting this published message is having the delay on the client side (Cometd listener in the JavaScript file) . Please let me know if you need more details
Try it out, and you will see that the message published by the server
will not have any delay on the client side.
The request that is pending will be resumed by the server as soon as
it has the message to send, and the response will arrive at the client
immediately afterward.
This is how long polling works, so what you see is normal and expected behavior.
On Wed, Apr 10, 2019 at 2:57 PM <pshrey...@gmail.com> wrote:
>
> Maybe the way I conveyed is wrong. What I meant was it is. Publish subscribe model. So the server publishes the message tiding bayeux and that is happening fine. And the cometd on the client is used to make a handshake and establish the connection. So getting this published message is having the delay on the client side (Cometd listener in the JavaScript file) . Please let me know if you need more details
will not have any delay on the client side.
The request that is pending will be resumed by the server as soon as
it has the message to send, and the response will arrive at the client
immediately afterward.
This is how long polling works, so what you see is normal and expected behavior.
André Oliveira
Apr 10, 2019, 9:22:57 AM4/10/19
to cometd-dev
The delay on the connect is normal, client send the connect and if the server have no message to send, this message will return 200 OK after an ammount of time (in our scenario is 30ms). As soon as the server have message to send, the connect message will return 200 Ok and the message is published by the server on the channel. All the subscribed listeners will get this message. This is the normal way cometd works.
When you say "delay" receiving the published messages how much time are you talking about?
BR
pshrey...@gmail.com
Apr 10, 2019, 9:25:33 AM4/10/19
to cometd-dev
The screen shots attached is of the same thing,
The message is published by the server already, but since the client "connect" is in "pending" state{In the earlier screenshot} the message consumption is delayed by over a minute on majority of occasions and it breaks our functionality. As mentioned earlier on Wildfly there is no delay.
pshrey...@gmail.com
Apr 10, 2019, 9:28:27 AM4/10/19
to cometd-dev
The delay here is inconsistent, on most occasions it is more than a minute before client receives.
Simone Bordet
Apr 10, 2019, 9:38:20 AM4/10/19
to cometd-dev
Hi,
On Wed, Apr 10, 2019 at 3:25 PM <pshrey...@gmail.com> wrote:
>
> The screen shots attached is of the same thing,
> The message is published by the server already,
How do you know?
> but since the client "connect" is in "pending" state{In the earlier screenshot} the message consumption is delayed by over a minute on majority of occasions and it breaks our functionality. As mentioned earlier on Wildfly there is no delay.
The "pending" state is normal when the server is idle.
If you publish a message from the server and that particular "pending"
request is not resumed, then that is a problem - could be a Tomcat
bug.
What do you see if you enable DEBUG logging on both the client and the server?
See https://docs.cometd.org/current/reference/#_troubleshooting
On Wed, Apr 10, 2019 at 3:25 PM <pshrey...@gmail.com> wrote:
>
> The screen shots attached is of the same thing,
> The message is published by the server already,
> but since the client "connect" is in "pending" state{In the earlier screenshot} the message consumption is delayed by over a minute on majority of occasions and it breaks our functionality. As mentioned earlier on Wildfly there is no delay.
If you publish a message from the server and that particular "pending"
request is not resumed, then that is a problem - could be a Tomcat
bug.
What do you see if you enable DEBUG logging on both the client and the server?
See https://docs.cometd.org/current/reference/#_troubleshooting
André Oliveira
Apr 10, 2019, 9:41:42 AM4/10/19
to cometd-dev
Are you sure that the message is already published? The 1min wait time in the connect messages seems normal, i guess... But if the message takes a long time to be received in the client listener you can verify if the problem is in the publishing process.
pshrey...@gmail.com
Apr 11, 2019, 3:38:45 AM4/11/19
to cometd-dev
What do you see if you enable DEBUG logging on both the client and the server?
See https://docs.cometd.org/current/reference/#_troubleshooting
As suggested I enabled "debug" logs at client side and server side. On client side there was no logging on browser console.
On server side below are the logs,
[http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=uxp_2ynhmogqz52ra19yrn4df28g7a, data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DemoReferral","action":"","Unpark":"false","recipient":"publish","displayname":"Demo - Referral","stepid":"current","taskId":"0b4c1f0a00HJ1fff","Id":"0b4ba93d00BJ1fff"},"acl":"No ACL","message":"notification_received","type":"Unpark","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/publish, id=10} uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=17245,expire=0
2019-04-11 12:58:19,212 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=0,expire=0
2019-04-11 12:58:19,213 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:775) - No authorizers, PUBLISH for channel /notification/publish granted
2019-04-11 12:58:19,214 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=uxp_2ynhmogqz52ra19yrn4df28g7a, data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DemoReferral","action":"","Unpark":"false","recipient":"publish","displayname":"Demo - Referral","stepid":"current","taskId":"0b4c1f0a00HJ1fff","Id":"0b4ba93d00BJ1fff"},"acl":"No ACL","message":"notification_received","type":"Unpark","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/publish, id=10} uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=2,expire=0
2019-04-11 12:58:19,214 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /notification/publish
2019-04-11 12:58:19,214 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:924) - Notifying 1 subscribers on /notification/publish
2019-04-11 12:58:19,215 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {channel=/notification/publish, id=10, successful=true} uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=3,expire=0
2019-04-11 12:58:19,215 [BayeuxServer@32574b36-Scheduler] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:535) - Swept browserId 1n1ae1bqmb2qg10s
2019-04-11 12:58:21,572 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 135 bytes from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad: [{"channel":"/meta/connect","connectionType":"long-polling","advice":{"timeout":0},"id":"51","clientId":"41j3ny0ookpank1uighlr5y4rd6"}]
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=72990,expire=0
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=41j3ny0ookpank1uighlr5y4rd6, advice={timeout=0}, channel=/meta/connect, id=51, connectionType=long-polling}
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=41j3ny0ookpank1uighlr5y4rd6, advice={timeout=0}, channel=/meta/connect, id=51, connectionType=long-polling} 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=72990,expire=0
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=0,expire=0
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=41j3ny0ookpank1uighlr5y4rd6, advice={timeout=0}, channel=/meta/connect, id=51, connectionType=long-polling} 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=0,expire=0
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:466) - client 1n1ae1bqmb2qg10s 1 sessions for 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=0,expire=0
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:490) - client 1n1ae1bqmb2qg10s 0 sessions for 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=0,expire=0
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=41j3ny0ookpank1uighlr5y4rd6, advice={timeout=0}, channel=/meta/connect, id=51, connectionType=long-polling}
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=51, successful=true} 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=0,expire=0
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=0,expire=0, replies=[{advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=51, successful=true}], messages=[]
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 0/1 to write for session 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=0,expire=0
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=0,expire=10000
2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
2019-04-11 12:58:33,515 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad
2019-04-11 12:58:34,522 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 112 bytes from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad
2019-04-11 12:58:34,522 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad: [{"channel":"/meta/connect","connectionType":"long-polling","id":"52","clientId":"41j3ny0ookpank1uighlr5y4rd6"}]
2019-04-11 12:58:34,523 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
2019-04-11 12:58:34,524 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=1009,expire=8991
2019-04-11 12:58:34,524 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=41j3ny0ookpank1uighlr5y4rd6, channel=/meta/connect, id=52, connectionType=long-polling}
2019-04-11 12:58:34,524 [http-nio-8080-exec-5] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=41j3ny0ookpank1uighlr5y4rd6, channel=/meta/connect, id=52, connectionType=long-polling} 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=1009,expire=8991
2019-04-11 12:58:34,524 [http-nio-8080-exec-5] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=0,expire=0
2019-04-11 12:58:34,524 [http-nio-8080-exec-5] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=41j3ny0ookpank1uighlr5y4rd6, channel=/meta/connect, id=52, connectionType=long-polling} 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=1,expire=0
2019-04-11 12:58:34,525 [http-nio-8080-exec-5] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-11 12:58:34,525 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:466) - client 1n1ae1bqmb2qg10s 1 sessions for 41j3ny0ookpank1uighlr5y4rd6,CONNECTED,last=1,expire=0
2019-04-11 12:58:34,525 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:125) - Suspended {clientId=41j3ny0ookpank1uighlr5y4rd6, channel=/meta/connect, id=52, connectionType=long-polling}
2019-04-11 12:58:34,525 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 133 bytes from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@669e0a16: [{"channel":"/meta/connect","connectionType":"long-polling","advice":{"timeout":0},"id":"9","clientId":"5mg0sns3hyooa1t6pdjx1hs01o"}]
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=72013,expire=0
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling}
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=72013,expire=0
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling}
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=9, successful=true} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0, replies=[{advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=9, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DemoReferral","action":"","Unpark":"false","recipient":"publish","displayname":"Demo - Referral","stepid":"current","taskId":"0b4c1f0a00HJ1fff","Id":"0b4ba93d00BJ1fff"},"acl":"No ACL","message":"notification_received","type":"Unpark","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/publish}]
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 1/1 to write for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=10000
2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
2019-04-11 12:59:06,521 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@60e474fc
2019-04-11 12:59:06,543 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 111 bytes from org.apache.catalina.connector.CoyoteInputStream@60e474fc
2019-04-11 12:59:06,544 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@60e474fc: [{"channel":"/meta/connect","connectionType":"long-polling","id":"10","clientId":"5mg0sns3hyooa1t6pdjx1hs01o"}]
2019-04-11 12:59:06,544 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
2019-04-11 12:59:06,544 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=23,expire=9977
2019-04-11 12:59:06,544 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=10, connectionType=long-polling}
2019-04-11 12:59:06,545 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=10, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=24,expire=9976
2019-04-11 12:59:06,545 [http-nio-8080-exec-4] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:06,545 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=10, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:06,545 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-11 12:59:06,547 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:466) - client 1n1ae1bqmb2qg10s 1 sessions for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2,expire=0
2019-04-11 12:59:06,547 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=10, connectionType=long-polling}
2019-04-11 12:59:06,547 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=10, successful=true} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=6,expire=0
2019-04-11 12:59:06,551 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=7,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=10, successful=true}], messages=[]
2019-04-11 12:59:06,552 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 0/1 to write for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=7,expire=0
2019-04-11 12:59:06,552 [http-nio-8080-exec-4] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=7,expire=10000
2019-04-11 12:59:06,552 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
2019-04-11 12:59:06,553 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 111 bytes from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@669e0a16: [{"channel":"/meta/connect","connectionType":"long-polling","id":"11","clientId":"5mg0sns3hyooa1t6pdjx1hs01o"}]
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2015,expire=7992
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=11, connectionType=long-polling}
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=11, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2015,expire=7992
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=11, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:466) - client 1n1ae1bqmb2qg10s 1 sessions for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=11, connectionType=long-polling}
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=11, successful=true} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=11, successful=true}], messages=[]
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 0/1 to write for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=10000
2019-04-11 12:59:08,560 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
2019-04-11 12:59:08,560 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:10,583 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 111 bytes from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:10,584 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@669e0a16: [{"channel":"/meta/connect","connectionType":"long-polling","id":"12","clientId":"5mg0sns3hyooa1t6pdjx1hs01o"}]
2019-04-11 12:59:10,586 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
2019-04-11 12:59:10,586 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2026,expire=7974
2019-04-11 12:59:10,586 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=12, connectionType=long-polling}
2019-04-11 12:59:10,587 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=12, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2027,expire=7973
2019-04-11 12:59:10,587 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:10,588 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=12, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:10,588 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-11 12:59:10,588 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:466) - client 1n1ae1bqmb2qg10s 1 sessions for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=1,expire=0
2019-04-11 12:59:10,589 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=12, connectionType=long-polling}
2019-04-11 12:59:10,589 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=12, successful=true} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=1,expire=0
2019-04-11 12:59:10,589 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=12, successful=true}], messages=[]
2019-04-11 12:59:10,590 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 0/1 to write for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2,expire=0
2019-04-11 12:59:10,590 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2,expire=10000
2019-04-11 12:59:10,590 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
2019-04-11 12:59:10,591 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:12,597 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 111 bytes from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:12,597 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@669e0a16: [{"channel":"/meta/connect","connectionType":"long-polling","id":"13","clientId":"5mg0sns3hyooa1t6pdjx1hs01o"}]
2019-04-11 12:59:12,598 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
2019-04-11 12:59:12,598 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2010,expire=7992
2019-04-11 12:59:12,598 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=13, connectionType=long-polling}
2019-04-11 12:59:12,599 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=13, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2011,expire=7991
2019-04-11 12:59:12,599 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:12,599 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=13, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:12,599 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-11 12:59:12,599 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:466) - client 1n1ae1bqmb2qg10s 1 sessions for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=1,expire=0
2019-04-11 12:59:12,600 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=13, connectionType=long-polling}
2019-04-11 12:59:12,600 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=13, successful=true} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=1,expire=0
2019-04-11 12:59:12,600 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=1,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=13, successful=true}], messages=[]
2019-04-11 12:59:12,600 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 0/1 to write for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2,expire=0
2019-04-11 12:59:12,601 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2,expire=10000
2019-04-11 12:59:12,601 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
2019-04-11 12:59:12,601 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 111 bytes from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@669e0a16: [{"channel":"/meta/connect","connectionType":"long-polling","id":"14","clientId":"5mg0sns3hyooa1t6pdjx1hs01o"}]
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2016,expire=7986
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=14, connectionType=long-polling}
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=14, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2016,expire=7986
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=14, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:466) - client 1n1ae1bqmb2qg10s 1 sessions for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=14, connectionType=long-polling}
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=14, successful=true} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=14, successful=true}], messages=[]
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 0/1 to write for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=10000
2019-04-11 12:59:14,615 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
2019-04-11 12:59:14,615 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:16,631 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 111 bytes from org.apache.catalina.connector.CoyoteInputStream@669e0a16
2019-04-11 12:59:16,631 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@669e0a16: [{"channel":"/meta/connect","connectionType":"long-polling","id":"15","clientId":"5mg0sns3hyooa1t6pdjx1hs01o"}]
2019-04-11 12:59:16,632 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
2019-04-11 12:59:16,632 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2017,expire=7983
2019-04-11 12:59:16,633 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=15, connectionType=long-polling}
2019-04-11 12:59:16,633 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=15, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2018,expire=7982
2019-04-11 12:59:16,633 [http-nio-8080-exec-6] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:16,633 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=15, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
2019-04-11 12:59:16,633 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-11 12:59:16,634 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:466) - client 1n1ae1bqmb2qg10s 1 sessions for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=1,expire=0
2019-04-11 12:59:16,634 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=5mg0sns3hyooa1t6pdjx1hs01o, channel=/meta/connect, id=15, connectionType=long-polling}
2019-04-11 12:59:16,634 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=15, successful=true} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=1,expire=0
2019-04-11 12:59:16,634 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=1,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=15, successful=true}], messages=[]
2019-04-11 12:59:16,634 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 0/1 to write for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2,expire=0
2019-04-11 12:59:16,635 [http-nio-8080-exec-6] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=2,expire=10000
2019-04-11 12:59:16,635 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
2019-04-11 12:59:16,635
Again the message published had a minute delay. Please let me know if you need more details.
Thanks And regards,
Shreyas Holla P.
pshrey...@gmail.com
Apr 15, 2019, 12:38:30 AM4/15/19
to cometd-dev
Hi Simone/Oliviera,
The shared logs are enough or do you need more details?
Thanks And Regards,
Shreyas Holla P.
Simone Bordet
Apr 15, 2019, 4:36:33 AM4/15/19
to cometd-dev
Hi,
On Thu, Apr 11, 2019 at 9:38 AM <pshrey...@gmail.com> wrote:
>>
>>
>> What do you see if you enable DEBUG logging on both the client and the server?
>> See https://docs.cometd.org/current/reference/#_troubleshooting
>>
>> As suggested I enabled "debug" logs at client side and server side. On client side there was no logging on browser console.
>
> On server side below are the logs,
>
> [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=uxp_2ynhmogqz52ra19yrn4df28g7a, data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DemoReferral","action":"","Unpark":"false","recipient":"publish","displayname":"Demo - Referral","stepid":"current","taskId":"0b4c1f0a00HJ1fff","Id":"0b4ba93d00BJ1fff"},"acl":"No ACL","message":"notification_received","type":"Unpark","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/publish, id=10} uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=17245,expire=0
Here a LocalSession publishes the message.
> 2019-04-11 12:58:19,212 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=0,expire=0
> 2019-04-11 12:58:19,213 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:775) - No authorizers, PUBLISH for channel /notification/publish granted
> 2019-04-11 12:58:19,214 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=uxp_2ynhmogqz52ra19yrn4df28g7a, data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DemoReferral","action":"","Unpark":"false","recipient":"publish","displayname":"Demo - Referral","stepid":"current","taskId":"0b4c1f0a00HJ1fff","Id":"0b4ba93d00BJ1fff"},"acl":"No ACL","message":"notification_received","type":"Unpark","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/publish, id=10} uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=2,expire=0
> 2019-04-11 12:58:19,214 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /notification/publish
> 2019-04-11 12:58:19,214 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:924) - Notifying 1 subscribers on /notification/publish
There is one subscriber to notify.
> 2019-04-11 12:58:19,215 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {channel=/notification/publish, id=10, successful=true} uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=3,expire=0
The publish reply is processed.
At this point I would expect the message for the subscriber to be
flushed, but there are no log lines that show this.
This can only happen if the communication with the client is
interrupted, and the server has no outstanding /meta/connect to
resume: the server is waiting for the client to send a /meta/connect.
> 2019-04-11 12:58:19,215 [BayeuxServer@32574b36-Scheduler] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:535) - Swept browserId 1n1ae1bqmb2qg10s
> 2019-04-11 12:58:21,572 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad
> 2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 135 bytes from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad
These 2 lines above are very strange. Tomcat is supposed to to an
asynchronous call, so there should be no pause between "Asynchronous
read start" and "Asynchronous read 135 bytes".
Instead, we see a 12 second pause as if the read was blocking.
> 2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad: [{"channel":"/meta/connect","connectionType":"long-polling","advice":{"timeout":0},"id":"51","clientId":"41j3ny0ookpank1uighlr5y4rd6"}]
[snip]
> 2019-04-11 12:58:34,525 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@669e0a16
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 133 bytes from org.apache.catalina.connector.CoyoteInputStream@669e0a16
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@669e0a16: [{"channel":"/meta/connect","connectionType":"long-polling","advice":{"timeout":0},"id":"9","clientId":"5mg0sns3hyooa1t6pdjx1hs01o"}]
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=72013,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling}
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=72013,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling}
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=9, successful=true} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0, replies=[{advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=9, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DemoReferral","action":"","Unpark":"false","recipient":"publish","displayname":"Demo - Referral","stepid":"current","taskId":"0b4c1f0a00HJ1fff","Id":"0b4ba93d00BJ1fff"},"acl":"No ACL","message":"notification_received","type":"Unpark","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/publish}]
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 1/1 to write for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=10000
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
In these log lines above we see a /meta/connect coming from the client
(while the message was published 47 seconds earlier).
The message is in the session queue and it's correctly sent to the client.
I'm surprised that the server kept this session around for so long:
what's your setting for `maxInterval`? I recommend that you keep it at
default.
The problem for this particular set of log lines was that the server
had knowledge of a session, but the communication between client and
server was somehow broken, so that the server had no connection to
send the message to the client, until 47 seconds later.
We have seen this before with Tomcat, and frankly I don't know what to tell you.
I'm pretty sure if you use anything different from Tomcat your
problems will be solved.
On Thu, Apr 11, 2019 at 9:38 AM <pshrey...@gmail.com> wrote:
>>
>>
>> What do you see if you enable DEBUG logging on both the client and the server?
>> See https://docs.cometd.org/current/reference/#_troubleshooting
>>
>> As suggested I enabled "debug" logs at client side and server side. On client side there was no logging on browser console.
>
> On server side below are the logs,
>
> [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=uxp_2ynhmogqz52ra19yrn4df28g7a, data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DemoReferral","action":"","Unpark":"false","recipient":"publish","displayname":"Demo - Referral","stepid":"current","taskId":"0b4c1f0a00HJ1fff","Id":"0b4ba93d00BJ1fff"},"acl":"No ACL","message":"notification_received","type":"Unpark","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/publish, id=10} uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=17245,expire=0
> 2019-04-11 12:58:19,212 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=0,expire=0
> 2019-04-11 12:58:19,213 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:775) - No authorizers, PUBLISH for channel /notification/publish granted
> 2019-04-11 12:58:19,214 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=uxp_2ynhmogqz52ra19yrn4df28g7a, data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DemoReferral","action":"","Unpark":"false","recipient":"publish","displayname":"Demo - Referral","stepid":"current","taskId":"0b4c1f0a00HJ1fff","Id":"0b4ba93d00BJ1fff"},"acl":"No ACL","message":"notification_received","type":"Unpark","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/publish, id=10} uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=2,expire=0
> 2019-04-11 12:58:19,214 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /notification/publish
> 2019-04-11 12:58:19,214 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:924) - Notifying 1 subscribers on /notification/publish
> 2019-04-11 12:58:19,215 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {channel=/notification/publish, id=10, successful=true} uxp_2ynhmogqz52ra19yrn4df28g7a,CONNECTED,last=3,expire=0
At this point I would expect the message for the subscriber to be
flushed, but there are no log lines that show this.
This can only happen if the communication with the client is
interrupted, and the server has no outstanding /meta/connect to
resume: the server is waiting for the client to send a /meta/connect.
> 2019-04-11 12:58:19,215 [BayeuxServer@32574b36-Scheduler] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:535) - Swept browserId 1n1ae1bqmb2qg10s
> 2019-04-11 12:58:21,572 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad
> 2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 135 bytes from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad
asynchronous call, so there should be no pause between "Asynchronous
read start" and "Asynchronous read 135 bytes".
Instead, we see a 12 second pause as if the read was blocking.
> 2019-04-11 12:58:33,515 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@3ee5d6ad: [{"channel":"/meta/connect","connectionType":"long-polling","advice":{"timeout":0},"id":"51","clientId":"41j3ny0ookpank1uighlr5y4rd6"}]
> 2019-04-11 12:58:34,525 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:172) - Asynchronous read start from org.apache.catalina.connector.CoyoteInputStream@669e0a16
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:179) - Asynchronous read 133 bytes from org.apache.catalina.connector.CoyoteInputStream@669e0a16
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:207) - Asynchronous read end from org.apache.catalina.connector.CoyoteInputStream@669e0a16: [{"channel":"/meta/connect","connectionType":"long-polling","advice":{"timeout":0},"id":"9","clientId":"5mg0sns3hyooa1t6pdjx1hs01o"}]
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:106) - Parsed 1 messages
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=72013,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling}
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:651) - > {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=72013,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:841) - < {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=5mg0sns3hyooa1t6pdjx1hs01o, advice={timeout=0}, channel=/meta/connect, id=9, connectionType=long-polling}
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.32574b36 (BayeuxServerImpl.java:1064) - << {advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=9, successful=true} 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AbstractHttpTransport.java:350) - Flushing 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0, replies=[{advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=9, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DemoReferral","action":"","Unpark":"false","recipient":"publish","displayname":"Demo - Referral","stepid":"current","taskId":"0b4c1f0a00HJ1fff","Id":"0b4ba93d00BJ1fff"},"acl":"No ACL","message":"notification_received","type":"Unpark","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/publish}]
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:296) - Messages/replies 1/1 to write for session 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=0
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 5mg0sns3hyooa1t6pdjx1hs01o,CONNECTED,last=0,expire=10000
> 2019-04-11 12:59:06,521 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.AsyncJSONTransport (AsyncJSONTransport.java:73) - Handling successful
(while the message was published 47 seconds earlier).
The message is in the session queue and it's correctly sent to the client.
I'm surprised that the server kept this session around for so long:
what's your setting for `maxInterval`? I recommend that you keep it at
default.
The problem for this particular set of log lines was that the server
had knowledge of a session, but the communication between client and
server was somehow broken, so that the server had no connection to
send the message to the client, until 47 seconds later.
We have seen this before with Tomcat, and frankly I don't know what to tell you.
I'm pretty sure if you use anything different from Tomcat your
problems will be solved.
André Oliveira
Apr 15, 2019, 5:02:50 AM4/15/19
to cometd-dev
Hi, what version of cometd are you using? Could be the browser throttling that causes the broken connection? If you are using a recent version try to set useWorkerScheduler property true in the config.
pshrey...@gmail.com
Apr 15, 2019, 6:25:41 AM4/15/19
to cometd-dev
We are using cometd version 4.0.2. We are not setting useWorkerScheduler property. From https://docs.cometd.org/current/reference/, it says default value is true. So it will be true in our case by default right?
pshrey...@gmail.com
Apr 15, 2019, 6:28:19 AM4/15/19
to cometd-dev
From https://docs.cometd.org/current/reference/, the maxInterval property is set at web.xml level. Is that the property you are talking about. If yes, we have not set that property explicitly, so it will be default right?
pshrey...@gmail.com
Apr 15, 2019, 6:29:07 AM4/15/19
to cometd-dev
From https://docs.cometd.org/current/reference/, the maxInterval property is set at web.xml level. Is that the property you are talking about. If yes, we have not set that property explicitly, so it will be default right?
Simone Bordet
Apr 15, 2019, 6:34:56 AM4/15/19
to cometd-dev
Hi,
On Mon, Apr 15, 2019 at 12:29 PM <pshrey...@gmail.com> wrote:
>
> From https://docs.cometd.org/current/reference/, the maxInterval property is set at web.xml level. Is that the property you are talking about. If yes, we have not set that property explicitly, so it will be default right?
Correct.
On Mon, Apr 15, 2019 at 12:29 PM <pshrey...@gmail.com> wrote:
>
> From https://docs.cometd.org/current/reference/, the maxInterval property is set at web.xml level. Is that the property you are talking about. If yes, we have not set that property explicitly, so it will be default right?
pshrey...@gmail.com
Apr 15, 2019, 7:14:22 AM4/15/19
to cometd-dev
So what else can I check then. Our application is certified on tomcat and we have to find solution here?
Simone Bordet
Apr 15, 2019, 9:11:44 AM4/15/19
to cometd-dev
Hi,
On Mon, Apr 15, 2019 at 1:14 PM <pshrey...@gmail.com> wrote:
>
> So what else can I check then. Our application is certified on tomcat and we have to find solution here?
The other option you may have is to use the blocking JSONTransport
instead of the AsyncJSONTransport.
See the "transports" parameter here:
https://docs.cometd.org/current4/reference/#_java_server_configuration
On Mon, Apr 15, 2019 at 1:14 PM <pshrey...@gmail.com> wrote:
>
> So what else can I check then. Our application is certified on tomcat and we have to find solution here?
instead of the AsyncJSONTransport.
See the "transports" parameter here:
https://docs.cometd.org/current4/reference/#_java_server_configuration
pshrey...@gmail.com
Apr 16, 2019, 8:01:13 AM4/16/19
to cometd-dev
Hi Simone,
Below is our web.xml cometD configuration,
<servlet>
<servlet-name>cometd</servlet-name>
<servlet-class>org.cometd.server.CometDServlet</servlet-class>
<init-param>
<param-name>allowMultiSessionsNoBrowser</param-name>
<param-value>true</param-value>
</init-param>
<init-param>
<param-name>ws.cometdURLMapping</param-name>
<param-value>/cometd/*</param-value>
</init-param>
<init-param>
<param-name>com.ibm.ws.webcontainer.async-supported</param-name>
<param-value>true</param-value>
</init-param>
<load-on-startup>1</load-on-startup>
<async-supported>true</async-supported>
</servlet>
Does JSONTransport should be configured in web.xml? If yes, please provide a sample configuration.
Thanks And Regards,
Shreyas Holla
Simone Bordet
Apr 16, 2019, 9:41:34 AM4/16/19
to cometd-dev
Hi,
On Tue, Apr 16, 2019 at 2:01 PM <pshrey...@gmail.com> wrote:
> Does JSONTransport should be configured in web.xml?
Yes, like explained in the documentation link.
> If yes, please provide a sample configuration.
It's in the documentation and below for clarity.
<init-param>
<param-name>transports</param-name>
<param-value>org.cometd.server.transport.JSONTransport</param-value>
</init-param>
On Tue, Apr 16, 2019 at 2:01 PM <pshrey...@gmail.com> wrote:
> Does JSONTransport should be configured in web.xml?
> If yes, please provide a sample configuration.
<init-param>
<param-name>transports</param-name>
<param-value>org.cometd.server.transport.JSONTransport</param-value>
</init-param>
pshrey...@gmail.com
Apr 16, 2019, 12:58:04 PM4/16/19
to cometd-dev
Thanks for the sample, will let you know the result.
pshrey...@gmail.com
Apr 16, 2019, 1:41:06 PM4/16/19
to cometd-dev
Hi Simone,
As suggested now my web.xml looks like,
<servlet>
<servlet-name>cometd</servlet-name>
<servlet-class>org.cometd.server.CometDServlet</servlet-class>
<init-param>
<param-name>transports</param-name>
<param-value>org.cometd.server.transport.JSONTransport</param-value>
</init-param>
<init-param>
<param-name>timeout</param-name>
<param-value>60000</param-value>
</init-param>
<init-param>
<param-name>ws.cometdURLMapping</param-name>
<param-value>/cometd/*</param-value>
</init-param>
<init-param>
<param-name>logLevel</param-name>
<param-value>1</param-value>
</init-param>
<init-param>
<param-name>requestAvailable</param-name>
<param-value>true</param-value>
</init-param>
<init-param>
<param-name>allowMultiSessionsNoBrowser</param-name>
<param-value>true</param-value>
</init-param>
<init-param>
<param-name>com.ibm.ws.webcontainer.async-supported</param-name>
<param-value>true</param-value>
</init-param>
<load-on-startup>1</load-on-startup>
<async-supported>true</async-supported>
</servlet>
So, after making the above change, for some scenarios the delay came down from around 1 minute to 1-2 seconds. Below are the logs,
[http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 23:01:30,876 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2025,expire=7975
2019-04-16 23:01:30,876 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling}
2019-04-16 23:01:30,878 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2028,expire=7972
2019-04-16 23:01:30,879 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 23:01:30,879 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=1,expire=0
2019-04-16 23:01:30,880 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 23:01:30,880 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2,expire=0
2019-04-16 23:01:30,881 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling}
2019-04-16 23:01:30,881 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=369, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2,expire=0
2019-04-16 23:01:30,881 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=3,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=369, successful=true}], messages=[]
2019-04-16 23:01:30,882 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=3,expire=10000
2019-04-16 23:01:30,882 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 23:01:30,884 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DEMO","action":"","UNPARK":"false","recipient":"brad","display":"demo-ref"},"acl":"No ACL","message":"notification_received","type":"UNPARK","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/brad, id=20} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=18830,expire=0
2019-04-16 23:01:31,588 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=0,expire=0
2019-04-16 23:01:31,589 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, PUBLISH for channel /notification/brad granted
2019-04-16 23:01:31,590 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DEMO","action":"","UNPARK":"false","recipient":"brad","display":"demo-ref"},"acl":"No ACL","message":"notification_received","type":"UNPARK","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/brad, id=20} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=1,expire=0
2019-04-16 23:01:31,590 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /notification/brad
2019-04-16 23:01:31,591 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:924) - Notifying 1 subscribers on /notification/brad
2019-04-16 23:01:31,591 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/notification/brad, id=20, successful=true} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=2,expire=0
2019-04-16 23:01:31,591 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2013,expire=7990
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=370, connectionType=long-polling}
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=370, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2013,expire=7990
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=370, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=370, connectionType=long-polling}
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/connect, id=370, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0, replies=[{channel=/meta/connect, id=370, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"name":"DEMO","action":"","UNPARK":"false","recipient":"brad","display":"demo-ref"},"acl":"No ACL","message":"notification_received","type":"UNPARK","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/brad}]
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=10000
2019-04-16 23:01:32,892 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 23:01:32,892
So what is the difference between these transports?
But in one of the scenario the message receiving in the client fails most of time,
Working scenario logs,
[localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOAddTransactionFeatureHBM
2019-04-16 22:45:21,028 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : AddTransactionFeature as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOAddTransactionFeatureHBM
2019-04-16 22:45:21,031 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOAvailableBalanceHBM
2019-04-16 22:45:21,342 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : AvailableBalance as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOAvailableBalanceHBM
2019-04-16 22:45:21,343 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOLoanRepaymentCreditHBM
2019-04-16 22:45:21,684 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : LoanRepaymentCredit as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOLoanRepaymentCreditHBM
2019-04-16 22:45:21,685 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOLoanRepaymentDebitHBM
2019-04-16 22:45:21,689 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : LoanRepaymentDebit as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOLoanRepaymentDebitHBM
2019-04-16 22:45:21,689 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOMinimumBalanceCheckHBM
2019-04-16 22:45:21,702 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : MinimumBalanceCheck as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOMinimumBalanceCheckHBM
2019-04-16 22:45:21,704 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOUpdateAccountBalanceHBM
2019-04-16 22:45:22,066 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : UpdateAccountBalance as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOUpdateAccountBalanceHBM
2019-04-16 22:45:22,067 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOUpdateInternalAccountFeatureHBM
2019-04-16 22:45:22,075 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : UpdateInternalAccountFeature as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOUpdateInternalAccountFeatureHBM
2019-04-16 22:45:22,075 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOAddTransactionFeatureHBM
2019-04-16 22:45:22,564 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : AddTransactionFeature as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOAddTransactionFeatureHBM
2019-04-16 22:45:22,565 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOAvailableBalanceHBM
2019-04-16 22:45:22,639 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : AvailableBalance as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOAvailableBalanceHBM
2019-04-16 22:45:22,639 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOLoanRepaymentCreditHBM
2019-04-16 22:45:22,809 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : LoanRepaymentCredit as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOLoanRepaymentCreditHBM
2019-04-16 22:45:22,810 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOLoanRepaymentDebitHBM
2019-04-16 22:45:22,815 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : LoanRepaymentDebit as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOLoanRepaymentDebitHBM
2019-04-16 22:45:22,816 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOMinimumBalanceCheckHBM
2019-04-16 22:45:22,819 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : MinimumBalanceCheck as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOMinimumBalanceCheckHBM
2019-04-16 22:45:22,819 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOUpdateAccountBalanceHBM
2019-04-16 22:45:23,010 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : UpdateAccountBalance as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOUpdateAccountBalanceHBM
2019-04-16 22:45:23,011 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.repository.runtime.impl.DynamicClassLoader (BankFusionLogger.java:295) - Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOUpdateInternalAccountFeatureHBM
2019-04-16 22:45:23,019 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceLoader (BankFusionLogger.java:295) - Error in loading BO : UpdateInternalAccountFeature as com.trapedza.bankfusion.core.BankFusionException: Class Data Empty : DynamicClassLoader.loadClasscom.trapedza.bankfusion.bo.refimpl.BOUpdateInternalAccountFeatureHBM
2019-04-16 22:45:23,020 [localhost-startStop-1] ERROR com.misys.bankfusion.subsystem.persistence.runtime.impl.HibernatePersistenceObjectsFactory (BankFusionLogger.java:295) - Query :QueryImpl( SELECT f_ARTEFACTID AS ARTEFACTID FROM Resource WHERE f_TYPE =? )Microflow :nullParameters :[1]Resut Set Size :1052
2019-04-16 22:45:23,825 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:409) - Added channel /meta/handshake
2019-04-16 22:45:30,679 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:409) - Added channel /meta/connect
2019-04-16 22:45:30,691 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:409) - Added channel /meta/subscribe
2019-04-16 22:45:30,694 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:409) - Added channel /meta/unsubscribe
2019-04-16 22:45:30,694 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:409) - Added channel /meta/disconnect
2019-04-16 22:45:30,695 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1119) - Added transport long-polling from class org.cometd.server.transport.JSONTransport
2019-04-16 22:45:30,709 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:261) - Active transports: [long-polling]
2019-04-16 22:45:30,716 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=BFTask_11ukslwvsgokol15fb2un0sg7zm, channel=/meta/handshake, id=1} BFTask_11ukslwvsgokol15fb2un0sg7zm,NEW,last=1555434930828,expire=0
2019-04-16 22:45:30,828 [localhost-startStop-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for BFTask_11ukslwvsgokol15fb2un0sg7zm,NEW,last=0,expire=0
2019-04-16 22:45:30,828 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=BFTask_11ukslwvsgokol15fb2un0sg7zm, channel=/meta/handshake, id=1} BFTask_11ukslwvsgokol15fb2un0sg7zm,NEW,last=15,expire=0
2019-04-16 22:45:30,843 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/handshake
2019-04-16 22:45:30,845 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:479) - Adding session BFTask_11ukslwvsgokol15fb2un0sg7zm,HANDSHAKEN,last=22,expire=0
2019-04-16 22:45:30,850 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {minimumVersion=1.0, clientId=BFTask_11ukslwvsgokol15fb2un0sg7zm, supportedConnectionTypes=[long-polling], advice=null, channel=/meta/handshake, id=1, version=1.0, successful=true} BFTask_11ukslwvsgokol15fb2un0sg7zm,HANDSHAKEN,last=23,expire=0
2019-04-16 22:45:30,851 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=BFTask_11ukslwvsgokol15fb2un0sg7zm, advice={interval=-1}, channel=/meta/connect, id=2} BFTask_11ukslwvsgokol15fb2un0sg7zm,HANDSHAKEN,last=28,expire=0
2019-04-16 22:45:30,856 [localhost-startStop-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for BFTask_11ukslwvsgokol15fb2un0sg7zm,HANDSHAKEN,last=0,expire=0
2019-04-16 22:45:30,858 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=BFTask_11ukslwvsgokol15fb2un0sg7zm, advice={interval=-1}, channel=/meta/connect, id=2} BFTask_11ukslwvsgokol15fb2un0sg7zm,HANDSHAKEN,last=2,expire=0
2019-04-16 22:45:30,861 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:45:30,861 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/connect, id=2, successful=true} BFTask_11ukslwvsgokol15fb2un0sg7zm,CONNECTED,last=4,expire=0
2019-04-16 22:45:30,862 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, channel=/meta/handshake, id=3} uxp_21jbw3r0i7sra2d0n2enmfp9m0,NEW,last=1555434932184,expire=0
2019-04-16 22:45:32,184 [localhost-startStop-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for uxp_21jbw3r0i7sra2d0n2enmfp9m0,NEW,last=0,expire=0
2019-04-16 22:45:32,193 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, channel=/meta/handshake, id=3} uxp_21jbw3r0i7sra2d0n2enmfp9m0,NEW,last=1,expire=0
2019-04-16 22:45:32,194 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/handshake
2019-04-16 22:45:32,194 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:479) - Adding session uxp_21jbw3r0i7sra2d0n2enmfp9m0,HANDSHAKEN,last=3,expire=0
2019-04-16 22:45:32,196 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {minimumVersion=1.0, clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, supportedConnectionTypes=[long-polling], advice=null, channel=/meta/handshake, id=3, version=1.0, successful=true} uxp_21jbw3r0i7sra2d0n2enmfp9m0,HANDSHAKEN,last=3,expire=0
2019-04-16 22:45:32,196 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, advice={interval=-1}, channel=/meta/connect, id=4} uxp_21jbw3r0i7sra2d0n2enmfp9m0,HANDSHAKEN,last=4,expire=0
2019-04-16 22:45:32,197 [localhost-startStop-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for uxp_21jbw3r0i7sra2d0n2enmfp9m0,HANDSHAKEN,last=0,expire=0
2019-04-16 22:45:32,197 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, advice={interval=-1}, channel=/meta/connect, id=4} uxp_21jbw3r0i7sra2d0n2enmfp9m0,HANDSHAKEN,last=1,expire=0
2019-04-16 22:45:32,198 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:45:32,199 [localhost-startStop-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/connect, id=4, successful=true} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=3,expire=0
2019-04-16 22:45:32,200 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:27,479 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 310loag1powaerlx6ktx61crkh,NEW,last=1555435107482,expire=0
2019-04-16 22:48:27,482 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {ext={authentication={userToken=TGT-1-kk8ZKcQqQPdyW5V0rQTMiXf-Q9BW7R0ZU8KLj-nMJgf734z39yQHzyxdU1kTMIhv3z0-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@4f52bba2, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0}
2019-04-16 22:48:27,485 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {ext={authentication={userToken=TGT-1-kk8ZKcQqQPdyW5V0rQTMiXf-Q9BW7R0ZU8KLj-nMJgf734z39yQHzyxdU1kTMIhv3z0-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@4f52bba2, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 310loag1powaerlx6ktx61crkh,NEW,last=1555435107487,expire=0
2019-04-16 22:48:27,487 [http-nio-8080-exec-7] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 310loag1powaerlx6ktx61crkh,NEW,last=0,expire=0
2019-04-16 22:48:27,488 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {ext={authentication={userToken=TGT-1-kk8ZKcQqQPdyW5V0rQTMiXf-Q9BW7R0ZU8KLj-nMJgf734z39yQHzyxdU1kTMIhv3z0-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@4f52bba2, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 310loag1powaerlx6ktx61crkh,NEW,last=1,expire=0
2019-04-16 22:48:27,489 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/handshake
2019-04-16 22:48:27,489 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:479) - Adding session 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=11,expire=0
2019-04-16 22:48:27,499 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {minimumVersion=1.0, clientId=310loag1powaerlx6ktx61crkh, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true} 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=15,expire=0
2019-04-16 22:48:27,503 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=16,expire=0, replies=[{minimumVersion=1.0, clientId=310loag1powaerlx6ktx61crkh, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true}], messages=[]
2019-04-16 22:48:27,504 [http-nio-8080-exec-7] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=17,expire=10000
2019-04-16 22:48:27,505 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:27,515 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 2 messages
2019-04-16 22:48:27,523 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:27,524 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 2 messages for session 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=36,expire=9981
2019-04-16 22:48:27,525 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=38,expire=9979
2019-04-16 22:48:27,526 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:409) - Added channel /notification/superit
2019-04-16 22:48:27,531 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/subscribe, subscription=/notification/superit, id=2}
2019-04-16 22:48:27,527 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=310loag1powaerlx6ktx61crkh, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-16 22:48:27,533 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/subscribe, subscription=/notification/superit, id=2} 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=49,expire=9968
2019-04-16 22:48:27,537 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit, id=5} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=175339,expire=0
2019-04-16 22:48:27,536 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=0,expire=10000
2019-04-16 22:48:27,546 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=310loag1powaerlx6ktx61crkh, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=3,expire=9997
2019-04-16 22:48:27,549 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=0,expire=0
2019-04-16 22:48:27,553 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=0,expire=0
2019-04-16 22:48:27,546 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=310loag1powaerlx6ktx61crkh, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=1,expire=0
2019-04-16 22:48:27,554 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/subscribe, subscription=/notification/superit, id=2} 310loag1powaerlx6ktx61crkh,HANDSHAKEN,last=7,expire=9993
2019-04-16 22:48:27,553 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:48:27,555 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, PUBLISH for channel /notification/superit granted
2019-04-16 22:48:27,561 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-16 22:48:27,557 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit, id=5} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=18,expire=0
2019-04-16 22:48:27,564 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /notification/superit
2019-04-16 22:48:27,565 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /notification/superit granted
2019-04-16 22:48:27,565 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 310loag1powaerlx6ktx61crkh,CONNECTED,last=10,expire=0
2019-04-16 22:48:27,563 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true} 310loag1powaerlx6ktx61crkh,CONNECTED,last=15,expire=0
2019-04-16 22:48:27,568 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:924) - Notifying 0 subscribers on /notification/superit
2019-04-16 22:48:27,566 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/subscribe, subscription=/hostChannel, id=3}
2019-04-16 22:48:27,574 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:490) - client 1kojmw710k9euyy3 0 sessions for 310loag1powaerlx6ktx61crkh,CONNECTED,last=20,expire=0
2019-04-16 22:48:27,573 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/subscribe, subscription=/hostChannel, id=3} 310loag1powaerlx6ktx61crkh,CONNECTED,last=22,expire=0
2019-04-16 22:48:27,575 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=310loag1powaerlx6ktx61crkh, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-16 22:48:27,575 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 310loag1powaerlx6ktx61crkh,CONNECTED,last=0,expire=0
2019-04-16 22:48:27,576 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=4, successful=true} 310loag1powaerlx6ktx61crkh,CONNECTED,last=3,expire=0
2019-04-16 22:48:27,579 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/notification/superit, id=5, successful=true} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=33,expire=0
2019-04-16 22:48:27,579 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/subscribe, subscription=/hostChannel, id=3} 310loag1powaerlx6ktx61crkh,CONNECTED,last=8,expire=0
2019-04-16 22:48:27,584 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-16 22:48:27,586 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, CREATE for channel /hostChannel granted
2019-04-16 22:48:27,587 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 310loag1powaerlx6ktx61crkh,CONNECTED,last=10,expire=0, replies=[{advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=4, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit}]
2019-04-16 22:48:27,589 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:409) - Added channel /hostChannel
2019-04-16 22:48:27,593 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 310loag1powaerlx6ktx61crkh,CONNECTED,last=17,expire=10000
2019-04-16 22:48:27,593 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /hostChannel granted
2019-04-16 22:48:27,595 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true} 310loag1powaerlx6ktx61crkh,CONNECTED,last=20,expire=9997
2019-04-16 22:48:27,596 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:27,596 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 310loag1powaerlx6ktx61crkh,CONNECTED,last=20,expire=9997, replies=[{channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true}, {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true}], messages=[]
2019-04-16 22:48:27,596 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:27,598 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:27,606 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 310loag1powaerlx6ktx61crkh,CONNECTED,last=30,expire=9987
2019-04-16 22:48:27,606 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-16 22:48:27,609 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/connect, id=5, connectionType=long-polling} 310loag1powaerlx6ktx61crkh,CONNECTED,last=34,expire=9983
2019-04-16 22:48:27,610 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 310loag1powaerlx6ktx61crkh,CONNECTED,last=0,expire=0
2019-04-16 22:48:27,610 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/connect, id=5, connectionType=long-polling} 310loag1powaerlx6ktx61crkh,CONNECTED,last=0,expire=0
2019-04-16 22:48:27,610 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:48:27,611 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 310loag1powaerlx6ktx61crkh,CONNECTED,last=2,expire=0
2019-04-16 22:48:27,612 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:115) - Suspended {clientId=310loag1powaerlx6ktx61crkh, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-16 22:48:27,615 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:33,045 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,NEW,last=1555435113046,expire=0
2019-04-16 22:48:33,046 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {ext={authentication={userToken=TGT-2-hIRxuNh0vMs2GgkNCMfGFGCt434nfVW8OYYg8426oxCzZDhqPHHkMkbJopPaL90RmWo-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@2535285e, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0}
2019-04-16 22:48:33,049 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {ext={authentication={userToken=TGT-2-hIRxuNh0vMs2GgkNCMfGFGCt434nfVW8OYYg8426oxCzZDhqPHHkMkbJopPaL90RmWo-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@2535285e, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 4hi87jfiw5pacm3q2pc1zja9v,NEW,last=1555435113055,expire=0
2019-04-16 22:48:33,055 [http-nio-8080-exec-6] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 4hi87jfiw5pacm3q2pc1zja9v,NEW,last=0,expire=0
2019-04-16 22:48:33,056 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {ext={authentication={userToken=TGT-2-hIRxuNh0vMs2GgkNCMfGFGCt434nfVW8OYYg8426oxCzZDhqPHHkMkbJopPaL90RmWo-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@2535285e, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 4hi87jfiw5pacm3q2pc1zja9v,NEW,last=0,expire=0
2019-04-16 22:48:33,056 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/handshake
2019-04-16 22:48:33,057 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:479) - Adding session 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=8,expire=0
2019-04-16 22:48:33,065 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {minimumVersion=1.0, clientId=4hi87jfiw5pacm3q2pc1zja9v, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=9,expire=0
2019-04-16 22:48:33,066 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=12,expire=0, replies=[{minimumVersion=1.0, clientId=4hi87jfiw5pacm3q2pc1zja9v, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true}], messages=[]
2019-04-16 22:48:33,068 [http-nio-8080-exec-6] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=12,expire=10000
2019-04-16 22:48:33,068 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:33,069 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 2 messages
2019-04-16 22:48:33,076 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 2 messages for session 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=21,expire=9991
2019-04-16 22:48:33,077 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:33,082 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/subscribe, subscription=/notification/brad, id=2}
2019-04-16 22:48:33,085 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=31,expire=9981
2019-04-16 22:48:33,087 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-16 22:48:33,099 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/subscribe, subscription=/notification/brad, id=2} 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=41,expire=9971
2019-04-16 22:48:33,097 [http-nio-8080-exec-7] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=0,expire=10000
2019-04-16 22:48:33,106 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=50,expire=9962
2019-04-16 22:48:33,106 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/subscribe, subscription=/notification/brad, id=2} 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=0,expire=10000
2019-04-16 22:48:33,106 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=0,expire=0
2019-04-16 22:48:33,107 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-16 22:48:33,114 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,HANDSHAKEN,last=8,expire=0
2019-04-16 22:48:33,115 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, CREATE for channel /notification/brad granted
2019-04-16 22:48:33,115 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:48:33,116 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:409) - Added channel /notification/brad
2019-04-16 22:48:33,116 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=9,expire=0
2019-04-16 22:48:33,116 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /notification/brad granted
2019-04-16 22:48:33,117 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-16 22:48:33,117 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=2, subscription=/notification/brad, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=10,expire=0
2019-04-16 22:48:33,117 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={interval=2000, multiple-clients=true, reconnect=retry, timeout=60000}, channel=/meta/connect, id=4, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=11,expire=0
2019-04-16 22:48:33,118 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/subscribe, subscription=/hostChannel, id=3}
2019-04-16 22:48:33,118 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=12,expire=0, replies=[{advice={interval=2000, multiple-clients=true, reconnect=retry, timeout=60000}, channel=/meta/connect, id=4, successful=true}], messages=[]
2019-04-16 22:48:33,119 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/subscribe, subscription=/hostChannel, id=3} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=12,expire=0
2019-04-16 22:48:33,119 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=13,expire=10000
2019-04-16 22:48:33,120 [http-nio-8080-exec-7] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=10000
2019-04-16 22:48:33,120 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:33,121 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/subscribe, subscription=/hostChannel, id=3} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=1,expire=9999
2019-04-16 22:48:33,121 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-16 22:48:33,122 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /hostChannel granted
2019-04-16 22:48:33,123 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=7,expire=9993
2019-04-16 22:48:33,127 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=8,expire=9992, replies=[{channel=/meta/subscribe, id=2, subscription=/notification/brad, successful=true}, {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true}], messages=[]
2019-04-16 22:48:33,128 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:33,129 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:35,125 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2006,expire=7994
2019-04-16 22:48:35,126 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-16 22:48:35,127 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=5, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2017,expire=7983
2019-04-16 22:48:35,137 [http-nio-8080-exec-9] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:35,139 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=5, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=1,expire=0
2019-04-16 22:48:35,140 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:48:35,146 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=7,expire=0
2019-04-16 22:48:35,146 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-16 22:48:35,147 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=5, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=10,expire=0
2019-04-16 22:48:35,149 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=17,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=5, successful=true}], messages=[]
2019-04-16 22:48:35,156 [http-nio-8080-exec-9] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=17,expire=10000
2019-04-16 22:48:35,157 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:35,159 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:37,182 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2044,expire=7973
2019-04-16 22:48:37,184 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=6, connectionType=long-polling}
2019-04-16 22:48:37,196 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=6, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2058,expire=7959
2019-04-16 22:48:37,197 [http-nio-8080-exec-7] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:37,199 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=6, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=1,expire=0
2019-04-16 22:48:37,201 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:48:37,202 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=5,expire=0
2019-04-16 22:48:37,204 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=6, connectionType=long-polling}
2019-04-16 22:48:37,205 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=6, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=23,expire=0
2019-04-16 22:48:37,222 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=26,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=6, successful=true}], messages=[]
2019-04-16 22:48:37,225 [http-nio-8080-exec-7] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=30,expire=10000
2019-04-16 22:48:37,229 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:37,231 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:39,247 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2048,expire=7982
2019-04-16 22:48:39,247 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=7, connectionType=long-polling}
2019-04-16 22:48:39,249 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=7, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2059,expire=7971
2019-04-16 22:48:39,258 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:39,259 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=7, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2,expire=0
2019-04-16 22:48:39,261 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:48:39,268 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=9,expire=0
2019-04-16 22:48:39,268 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=7, connectionType=long-polling}
2019-04-16 22:48:39,269 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=7, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=11,expire=0
2019-04-16 22:48:39,270 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=11,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=7, successful=true}], messages=[]
2019-04-16 22:48:39,270 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=12,expire=10000
2019-04-16 22:48:39,271 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:39,272 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:41,276 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2017,expire=7995
2019-04-16 22:48:41,276 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=8, connectionType=long-polling}
2019-04-16 22:48:41,280 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=8, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2021,expire=7991
2019-04-16 22:48:41,280 [http-nio-8080-exec-4] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:41,286 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=8, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:41,286 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:48:41,286 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:41,286 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=8, connectionType=long-polling}
2019-04-16 22:48:41,286 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=8, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:41,286 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=8, successful=true}], messages=[]
2019-04-16 22:48:41,286 [http-nio-8080-exec-4] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=10000
2019-04-16 22:48:41,286 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:41,302 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2022,expire=7978
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=9, connectionType=long-polling}
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=9, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2022,expire=7978
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=9, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=9, connectionType=long-polling}
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=9, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=9, successful=true}], messages=[]
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=10000
2019-04-16 22:48:43,308 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:48:43,308 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:48:45,346 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2038,expire=7962
2019-04-16 22:48:45,346 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=10, connectionType=long-polling}
2019-04-16 22:48:45,346 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=10, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2038,expire=7962
2019-04-16 22:48:45,346 [http-nio-8080-exec-9] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:45,346 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=10, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:48:45,346 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:48:45,346 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=16,expire=0
2019-04-16 22:48:45,362 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=10, connectionType=long-polling}
2019-04-16 22:48:45,362 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=10, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=16,expire=0
2019-04-16 22:48:45,362 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=16,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=10, successful=true}], messages=[]
2019-04-16 22:48:45,362 [http-nio-8080-exec-9] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=16,expire=10000
2019-04-16 22:48:45,362 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
Non-Working scenario logs,
[http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:53:41,011 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2041,expire=7975
2019-04-16 22:53:41,011 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=155, connectionType=long-polling}
2019-04-16 22:53:41,011 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=155, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2041,expire=7975
2019-04-16 22:53:41,011 [http-nio-8080-exec-6] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:41,027 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=155, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:41,027 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:53:41,027 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:41,027 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=155, connectionType=long-polling}
2019-04-16 22:53:41,027 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=155, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:41,027 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=155, successful=true}], messages=[]
2019-04-16 22:53:41,027 [http-nio-8080-exec-6] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=10000
2019-04-16 22:53:41,027 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:53:41,027 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit, id=11} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=20861,expire=0
2019-04-16 22:53:42,579 [http-nio-8080-exec-7] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=0,expire=0
2019-04-16 22:53:42,580 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, PUBLISH for channel /notification/superit granted
2019-04-16 22:53:42,582 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:53:42,584 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=uxp_21jbw3r0i7sra2d0n2enmfp9m0, data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit, id=11} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=9,expire=0
2019-04-16 22:53:42,589 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 7169o9hzaofagn1s3vqc6wwv6so,NEW,last=1555435422590,expire=0
2019-04-16 22:53:42,590 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /notification/superit
2019-04-16 22:53:42,592 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {ext={authentication={userToken=TGT-5-KlgueCrD45qA5PC4Vu74CaVano-Jaf9pYin1oSKXZEaH3Wp3b-3n42uMrOznkyXUbQs-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@360bc356, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0}
2019-04-16 22:53:42,593 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:924) - Notifying 1 subscribers on /notification/superit
2019-04-16 22:53:42,600 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {ext={authentication={userToken=TGT-5-KlgueCrD45qA5PC4Vu74CaVano-Jaf9pYin1oSKXZEaH3Wp3b-3n42uMrOznkyXUbQs-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@360bc356, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 7169o9hzaofagn1s3vqc6wwv6so,NEW,last=1555435422601,expire=0
2019-04-16 22:53:42,601 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:738) - Resuming /meta/connect after schedule
2019-04-16 22:53:42,603 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 7169o9hzaofagn1s3vqc6wwv6so,NEW,last=0,expire=0
2019-04-16 22:53:42,613 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {ext={authentication={userToken=TGT-5-KlgueCrD45qA5PC4Vu74CaVano-Jaf9pYin1oSKXZEaH3Wp3b-3n42uMrOznkyXUbQs-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@360bc356, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 7169o9hzaofagn1s3vqc6wwv6so,NEW,last=2,expire=0
2019-04-16 22:53:42,615 [http-nio-8080-exec-7] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:490) - client 1kojmw710k9euyy3 0 sessions for 6tuxejzsbygh51icny8p7371br,CONNECTED,last=20785,expire=0
2019-04-16 22:53:42,615 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/handshake
2019-04-16 22:53:42,622 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=6tuxejzsbygh51icny8p7371br, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-16 22:53:42,623 [http-nio-8080-exec-6] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/connect, id=5, successful=true} 6tuxejzsbygh51icny8p7371br,CONNECTED,last=20802,expire=0
2019-04-16 22:53:42,632 [http-nio-8080-exec-7] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/notification/superit, id=11, successful=true} uxp_21jbw3r0i7sra2d0n2enmfp9m0,CONNECTED,last=43,expire=0
2019-04-16 22:53:42,623 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 6tuxejzsbygh51icny8p7371br,CONNECTED,last=20803,expire=0, replies=[{channel=/meta/connect, id=5, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit}]
2019-04-16 22:53:42,633 [http-nio-8080-exec-6] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 6tuxejzsbygh51icny8p7371br,CONNECTED,last=20804,expire=10000
2019-04-16 22:53:42,634 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:479) - Adding session 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=19,expire=0
2019-04-16 22:53:42,632 [http-nio-8080-exec-6] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:53:42,636 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {minimumVersion=1.0, clientId=7169o9hzaofagn1s3vqc6wwv6so, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true} 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=26,expire=0
2019-04-16 22:53:42,639 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=26,expire=0, replies=[{minimumVersion=1.0, clientId=7169o9hzaofagn1s3vqc6wwv6so, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true}], messages=[]
2019-04-16 22:53:42,639 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=26,expire=10000
2019-04-16 22:53:42,639 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:53:42,639 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 2 messages
2019-04-16 22:53:42,648 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 2 messages for session 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=36,expire=9990
2019-04-16 22:53:42,649 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:53:42,650 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/subscribe, subscription=/notification/superit, id=2}
2019-04-16 22:53:42,655 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=42,expire=9984
2019-04-16 22:53:42,655 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/subscribe, subscription=/notification/superit, id=2} 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=43,expire=9983
2019-04-16 22:53:42,656 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=7169o9hzaofagn1s3vqc6wwv6so, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-16 22:53:42,656 [http-nio-8080-exec-9] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=0,expire=10000
2019-04-16 22:53:42,657 [http-nio-8080-exec-5] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=7169o9hzaofagn1s3vqc6wwv6so, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=0,expire=10000
2019-04-16 22:53:42,657 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/subscribe, subscription=/notification/superit, id=2} 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=0,expire=10000
2019-04-16 22:53:42,658 [http-nio-8080-exec-5] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=0,expire=0
2019-04-16 22:53:42,660 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-16 22:53:42,665 [http-nio-8080-exec-5] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=7169o9hzaofagn1s3vqc6wwv6so, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=6,expire=0
2019-04-16 22:53:42,666 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /notification/superit granted
2019-04-16 22:53:42,666 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true} 7169o9hzaofagn1s3vqc6wwv6so,HANDSHAKEN,last=7,expire=0
2019-04-16 22:53:42,667 [http-nio-8080-exec-5] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:53:42,666 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/subscribe, subscription=/hostChannel, id=3}
2019-04-16 22:53:42,667 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=7,expire=0
2019-04-16 22:53:42,667 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/subscribe, subscription=/hostChannel, id=3} 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=8,expire=0
2019-04-16 22:53:42,668 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:490) - client 1kojmw710k9euyy3 0 sessions for 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=8,expire=0
2019-04-16 22:53:42,668 [http-nio-8080-exec-9] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=0,expire=0
2019-04-16 22:53:42,668 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=7169o9hzaofagn1s3vqc6wwv6so, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-16 22:53:42,669 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/subscribe, subscription=/hostChannel, id=3} 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=8,expire=0
2019-04-16 22:53:42,676 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-16 22:53:42,677 [http-nio-8080-exec-5] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=4, successful=true} 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=8,expire=0
2019-04-16 22:53:42,676 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /hostChannel granted
2019-04-16 22:53:42,678 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=10,expire=0, replies=[{advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=4, successful=true}], messages=[]
2019-04-16 22:53:42,678 [http-nio-8080-exec-5] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=11,expire=10000
2019-04-16 22:53:42,679 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true} 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=11,expire=0
2019-04-16 22:53:42,679 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=12,expire=9999, replies=[{channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true}, {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true}], messages=[]
2019-04-16 22:53:42,680 [http-nio-8080-exec-5] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:53:42,680 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:53:42,681 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:53:42,685 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=18,expire=9993
2019-04-16 22:53:42,686 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-16 22:53:42,691 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/connect, id=5, connectionType=long-polling} 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=23,expire=9988
2019-04-16 22:53:42,691 [http-nio-8080-exec-3] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=0,expire=0
2019-04-16 22:53:42,692 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/connect, id=5, connectionType=long-polling} 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=1,expire=0
2019-04-16 22:53:42,693 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:53:42,693 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 7169o9hzaofagn1s3vqc6wwv6so,CONNECTED,last=2,expire=0
2019-04-16 22:53:42,694 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:115) - Suspended {clientId=7169o9hzaofagn1s3vqc6wwv6so, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-16 22:53:42,694 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:53:43,048 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2021,expire=7979
2019-04-16 22:53:43,048 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=156, connectionType=long-polling}
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=156, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2036,expire=7964
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=156, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=156, connectionType=long-polling}
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=156, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=156, successful=true}], messages=[]
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=10000
2019-04-16 22:53:43,063 [http-nio-8080-exec-4] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:53:43,063 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-16 22:53:45,079 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2016,expire=7984
2019-04-16 22:53:45,079 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=157, connectionType=long-polling}
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=157, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2032,expire=7968
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=157, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=157, connectionType=long-polling}
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=157, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0, replies=[{advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=157, successful=true}], messages=[]
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=10000
2019-04-16 22:53:45,095 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-16 22:53:45,095
Please provide your inputs?
Thanks And Regards,
Shreyas Holla P.
Simone Bordet
Apr 16, 2019, 3:31:55 PM4/16/19
to cometd-dev
Hi,
On Tue, Apr 16, 2019 at 7:41 PM <pshrey...@gmail.com> wrote:
> So, after making the above change, for some scenarios the delay came down from around 1 minute to 1-2 seconds. Below are the logs,
>
> [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
> 2019-04-16 23:01:30,876 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2025,expire=7975
> 2019-04-16 23:01:30,876 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling}
> 2019-04-16 23:01:30,878 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2028,expire=7972
> 2019-04-16 23:01:30,879 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
> 2019-04-16 23:01:30,879 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=1,expire=0
> 2019-04-16 23:01:30,880 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
> 2019-04-16 23:01:30,880 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2,expire=0
> 2019-04-16 23:01:30,881 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling}
> 2019-04-16 23:01:30,881 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=369, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2,expire=0
So here you have `multiple-clients=true, interval=2000`, so you have
more than 1 tab open for the application and this particular
connection went into multiple-clients polling mode every 2 seconds.
That's why the next /meta/connect is delayed by 2 seconds.
See https://docs.cometd.org/current4/reference/#_multiple_sessions_with_http1_1.
> So what is the difference between these transports?
JSONTransport uses blocking I/O.
AsyncJSONTransport uses non-blocking I/O. Apparently Tomcat's
implementation of async I/O has issues.
> But in one of the scenario the message receiving in the client fails most of time,
>
> Working scenario logs,
[snip]
> Non-Working scenario logs,
[snip]
I don't see anything wrong.
On Tue, Apr 16, 2019 at 7:41 PM <pshrey...@gmail.com> wrote:
> So, after making the above change, for some scenarios the delay came down from around 1 minute to 1-2 seconds. Below are the logs,
>
> [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
> 2019-04-16 23:01:30,876 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2025,expire=7975
> 2019-04-16 23:01:30,876 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling}
> 2019-04-16 23:01:30,878 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:651) - > {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2028,expire=7972
> 2019-04-16 23:01:30,879 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=0,expire=0
> 2019-04-16 23:01:30,879 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:841) - < {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=1,expire=0
> 2019-04-16 23:01:30,880 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
> 2019-04-16 23:01:30,880 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client 1kojmw710k9euyy3 1 sessions for 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2,expire=0
> 2019-04-16 23:01:30,881 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=4hi87jfiw5pacm3q2pc1zja9v, channel=/meta/connect, id=369, connectionType=long-polling}
> 2019-04-16 23:01:30,881 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.56e2a746 (BayeuxServerImpl.java:1064) - << {advice={multiple-clients=true, interval=2000, reconnect=retry}, channel=/meta/connect, id=369, successful=true} 4hi87jfiw5pacm3q2pc1zja9v,CONNECTED,last=2,expire=0
more than 1 tab open for the application and this particular
connection went into multiple-clients polling mode every 2 seconds.
That's why the next /meta/connect is delayed by 2 seconds.
See https://docs.cometd.org/current4/reference/#_multiple_sessions_with_http1_1.
> So what is the difference between these transports?
AsyncJSONTransport uses non-blocking I/O. Apparently Tomcat's
implementation of async I/O has issues.
> But in one of the scenario the message receiving in the client fails most of time,
>
> Working scenario logs,
> Non-Working scenario logs,
[snip]
I don't see anything wrong.
pshrey...@gmail.com
Apr 17, 2019, 4:13:02 AM4/17/19
to cometd-dev
Hi Simone,
JSONTransport uses blocking I/O.
AsyncJSONTransport uses non-blocking I/O. Apparently Tomcat's
implementation of async I/O has issues.
Since we are using JSONTransport now instead of AsyncJSONTransport, will this cause performance issues?
And for the scenario its not working, I got the pattern. Its working only once after server startup, below are logs,
[http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-17 12:14:31,951 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 31vafkie6ho4g1bwylf18oql23,NEW,last=1555483471957,expire=0
2019-04-17 12:14:31,957 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {ext={authentication={userToken=TGT-1-e4kCTHiR8zgSQm14nIJvguwpjbWa1rqPUV0CwDC3nRf3XQ7yspXkahYUrTaV-TZ8kNc-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@18a27447, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0}
2019-04-17 12:14:31,962 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {ext={authentication={userToken=TGT-1-e4kCTHiR8zgSQm14nIJvguwpjbWa1rqPUV0CwDC3nRf3XQ7yspXkahYUrTaV-TZ8kNc-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@18a27447, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 31vafkie6ho4g1bwylf18oql23,NEW,last=1555483471969,expire=0
2019-04-17 12:14:31,969 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 31vafkie6ho4g1bwylf18oql23,NEW,last=0,expire=0
2019-04-17 12:14:31,970 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {ext={authentication={userToken=TGT-1-e4kCTHiR8zgSQm14nIJvguwpjbWa1rqPUV0CwDC3nRf3XQ7yspXkahYUrTaV-TZ8kNc-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@18a27447, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 31vafkie6ho4g1bwylf18oql23,NEW,last=0,expire=0
2019-04-17 12:14:31,970 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/handshake
2019-04-17 12:14:31,977 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:479) - Adding session 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=15,expire=0
2019-04-17 12:14:31,986 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {minimumVersion=1.0, clientId=31vafkie6ho4g1bwylf18oql23, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true} 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=18,expire=0
2019-04-17 12:14:31,988 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=19,expire=0, replies=[{minimumVersion=1.0, clientId=31vafkie6ho4g1bwylf18oql23, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true}], messages=[]
2019-04-17 12:14:31,989 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=21,expire=10000
2019-04-17 12:14:31,991 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-17 12:14:31,997 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 2 messages
2019-04-17 12:14:32,002 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 2 messages for session 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=33,expire=9988
2019-04-17 12:14:32,003 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-17 12:14:32,005 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/subscribe, subscription=/notification/superit, id=2}
2019-04-17 12:14:32,005 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=36,expire=9985
2019-04-17 12:14:32,006 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=31vafkie6ho4g1bwylf18oql23, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-17 12:14:32,007 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/subscribe, subscription=/notification/superit, id=2} 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=37,expire=9984
2019-04-17 12:14:32,007 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=0,expire=10000
2019-04-17 12:14:32,008 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=31vafkie6ho4g1bwylf18oql23, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=1,expire=9999
2019-04-17 12:14:32,009 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/subscribe, subscription=/notification/superit, id=2} 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=1,expire=9999
2019-04-17 12:14:32,009 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=0,expire=0
2019-04-17 12:14:32,009 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-17 12:14:32,009 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=31vafkie6ho4g1bwylf18oql23, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 31vafkie6ho4g1bwylf18oql23,HANDSHAKEN,last=0,expire=0
2019-04-17 12:14:32,009 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-17 12:14:32,009 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client mwspwa8saojjnspn 1 sessions for 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0
2019-04-17 12:14:32,009 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:490) - client mwspwa8saojjnspn 0 sessions for 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0
2019-04-17 12:14:32,009 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=31vafkie6ho4g1bwylf18oql23, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-17 12:14:32,009 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=4, successful=true} 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0
2019-04-17 12:14:32,009 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=16,expire=0, replies=[{advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=4, successful=true}], messages=[]
2019-04-17 12:14:32,025 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:775) - No authorizers, CREATE for channel /notification/superit granted
2019-04-17 12:14:32,025 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:409) - Added channel /notification/superit
2019-04-17 12:14:32,025 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=16,expire=10000
2019-04-17 12:14:32,025 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=uxp_230k12jk9aj1sb7i94rj9eztp, data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit, id=5} uxp_230k12jk9aj1sb7i94rj9eztp,CONNECTED,last=79932,expire=0
2019-04-17 12:14:32,025 [http-nio-8080-exec-9] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for uxp_230k12jk9aj1sb7i94rj9eztp,CONNECTED,last=0,expire=0
2019-04-17 12:14:32,025 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-17 12:14:32,025 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /notification/superit granted
2019-04-17 12:14:32,025 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:775) - No authorizers, PUBLISH for channel /notification/superit granted
2019-04-17 12:14:32,037 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-17 12:14:32,040 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true} 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=30,expire=9986
2019-04-17 12:14:32,039 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=uxp_230k12jk9aj1sb7i94rj9eztp, data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit, id=5} uxp_230k12jk9aj1sb7i94rj9eztp,CONNECTED,last=20,expire=0
2019-04-17 12:14:32,045 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=36,expire=9980
2019-04-17 12:14:32,045 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /notification/superit
2019-04-17 12:14:32,045 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/subscribe, subscription=/hostChannel, id=3}
2019-04-17 12:14:32,045 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:924) - Notifying 1 subscribers on /notification/superit
2019-04-17 12:14:32,048 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-17 12:14:32,046 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/subscribe, subscription=/hostChannel, id=3} 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=40,expire=9976
2019-04-17 12:14:32,049 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/connect, id=5, connectionType=long-polling} 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=40,expire=9976
2019-04-17 12:14:32,049 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0
2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=10000
2019-04-17 12:14:32,049 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/connect, id=5, connectionType=long-polling} 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0
2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/subscribe, subscription=/hostChannel, id=3} 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0
2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-17 12:14:32,049 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:775) - No authorizers, CREATE for channel /hostChannel granted
2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:409) - Added channel /hostChannel
2019-04-17 12:14:32,049 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client mwspwa8saojjnspn 1 sessions for 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0
2019-04-17 12:14:32,049 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {channel=/notification/superit, id=5, successful=true} uxp_230k12jk9aj1sb7i94rj9eztp,CONNECTED,last=24,expire=0
2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /hostChannel granted
2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true} 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0
2019-04-17 12:14:32,049 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:115) - Suspended {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0, replies=[{channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true}, {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit}]
2019-04-17 12:14:32,064 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-17 12:14:32,064
And after that every subsequent request, the client does not receive published message. Below are the logs,
[http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-17 12:15:11,587 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 47u3enl9oku6y1aww4q4v7rga,NEW,last=1555483511588,expire=0
2019-04-17 12:15:11,588 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {ext={authentication={userToken=TGT-2-0A6JoGFvyNJGRzMf0UyX3oVkH3vNJ3ZDthwY7vGwvCyV1e5ZMwtf8v7NYSc1CeD3F8A-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@2ca02af5, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0}
2019-04-17 12:15:11,589 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {ext={authentication={userToken=TGT-2-0A6JoGFvyNJGRzMf0UyX3oVkH3vNJ3ZDthwY7vGwvCyV1e5ZMwtf8v7NYSc1CeD3F8A-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@2ca02af5, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 47u3enl9oku6y1aww4q4v7rga,NEW,last=1555483511600,expire=0
2019-04-17 12:15:11,600 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 47u3enl9oku6y1aww4q4v7rga,NEW,last=0,expire=0
2019-04-17 12:15:11,602 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {ext={authentication={userToken=TGT-2-0A6JoGFvyNJGRzMf0UyX3oVkH3vNJ3ZDthwY7vGwvCyV1e5ZMwtf8v7NYSc1CeD3F8A-localhost}}, minimumVersion=0.9, supportedConnectionTypes=[Ljava.lang.Object;@2ca02af5, advice={interval=0, timeout=60000}, channel=/meta/handshake, id=1, version=1.0} 47u3enl9oku6y1aww4q4v7rga,NEW,last=1,expire=0
2019-04-17 12:15:11,603 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/handshake
2019-04-17 12:15:11,603 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:479) - Adding session 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=1,expire=0
2019-04-17 12:15:11,603 [http-nio-8080-exec-8] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {minimumVersion=1.0, clientId=47u3enl9oku6y1aww4q4v7rga, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true} 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=2,expire=0
2019-04-17 12:15:11,604 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=uxp_230k12jk9aj1sb7i94rj9eztp, data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit, id=6} uxp_230k12jk9aj1sb7i94rj9eztp,CONNECTED,last=39590,expire=0
2019-04-17 12:15:11,615 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=13,expire=0, replies=[{minimumVersion=1.0, clientId=47u3enl9oku6y1aww4q4v7rga, supportedConnectionTypes=[long-polling], advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/handshake, id=1, version=1.0, successful=true}], messages=[]
2019-04-17 12:15:11,615 [http-nio-8080-exec-2] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for uxp_230k12jk9aj1sb7i94rj9eztp,CONNECTED,last=0,expire=0
2019-04-17 12:15:11,627 [http-nio-8080-exec-8] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=27,expire=10000
2019-04-17 12:15:11,629 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:775) - No authorizers, PUBLISH for channel /notification/superit granted
2019-04-17 12:15:11,629 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=uxp_230k12jk9aj1sb7i94rj9eztp, data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit, id=6} uxp_230k12jk9aj1sb7i94rj9eztp,CONNECTED,last=4,expire=0
2019-04-17 12:15:11,631 [http-nio-8080-exec-8] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-17 12:15:11,631 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /notification/superit
2019-04-17 12:15:11,632 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:924) - Notifying 1 subscribers on /notification/superit
2019-04-17 12:15:11,634 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:738) - Resuming /meta/connect after schedule
2019-04-17 12:15:11,635 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-17 12:15:11,641 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 2 messages
2019-04-17 12:15:11,639 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=43,expire=9984
2019-04-17 12:15:11,646 [http-nio-8080-exec-2] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:490) - client mwspwa8saojjnspn 0 sessions for 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=39595,expire=0
2019-04-17 12:15:11,644 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=47u3enl9oku6y1aww4q4v7rga, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-17 12:15:11,646 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 2 messages for session 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=44,expire=9983
2019-04-17 12:15:11,646 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=47u3enl9oku6y1aww4q4v7rga, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=45,expire=9982
2019-04-17 12:15:11,647 [http-nio-8080-exec-2] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {channel=/notification/superit, id=6, successful=true} uxp_230k12jk9aj1sb7i94rj9eztp,CONNECTED,last=21,expire=0
2019-04-17 12:15:11,648 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/subscribe, subscription=/notification/superit, id=2}
2019-04-17 12:15:11,647 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=31vafkie6ho4g1bwylf18oql23, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-17 12:15:11,651 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=0,expire=0
2019-04-17 12:15:11,650 [http-nio-8080-exec-9] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {channel=/meta/connect, id=5, successful=true} 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=39608,expire=0
2019-04-17 12:15:11,657 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/subscribe, subscription=/notification/superit, id=2} 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=6,expire=0
2019-04-17 12:15:11,656 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=39609,expire=0, replies=[{channel=/meta/connect, id=5, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit}]
2019-04-17 12:15:11,658 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=47u3enl9oku6y1aww4q4v7rga, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling} 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=7,expire=0
2019-04-17 12:15:11,657 [http-nio-8080-exec-9] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=39611,expire=10000
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=0,expire=0
2019-04-17 12:15:11,660 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-17 12:15:11,660 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/subscribe, subscription=/notification/superit, id=2} 47u3enl9oku6y1aww4q4v7rga,HANDSHAKEN,last=0,expire=0
2019-04-17 12:15:11,660 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client mwspwa8saojjnspn 1 sessions for 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=0
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-17 12:15:11,660 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:490) - client mwspwa8saojjnspn 0 sessions for 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=0
2019-04-17 12:15:11,660 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:357) - Resumed {clientId=47u3enl9oku6y1aww4q4v7rga, advice={timeout=0}, channel=/meta/connect, id=4, connectionType=long-polling}
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /notification/superit granted
2019-04-17 12:15:11,660 [http-nio-8080-exec-1] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=4, successful=true} 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=0
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true} 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=0
2019-04-17 12:15:11,660 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=0, replies=[{advice={interval=0, timeout=60000, reconnect=retry}, channel=/meta/connect, id=4, successful=true}], messages=[]
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/subscribe, subscription=/hostChannel, id=3}
2019-04-17 12:15:11,660 [http-nio-8080-exec-1] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=10000
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/subscribe, subscription=/hostChannel, id=3} 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=10000
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Delaying expiration for 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=10000
2019-04-17 12:15:11,660 [http-nio-8080-exec-1] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/subscribe, subscription=/hostChannel, id=3} 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=10000
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/subscribe
2019-04-17 12:15:11,660 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:775) - No authorizers, SUBSCRIBE for channel /hostChannel granted
2019-04-17 12:15:11,676 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:93) - Parsed 1 messages
2019-04-17 12:15:11,681 [http-nio-8080-exec-10] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:1064) - << {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true} 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=22,expire=9978
2019-04-17 12:15:11,682 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:130) - Processing 1 messages for session 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=23,expire=9977
2019-04-17 12:15:11,683 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=24,expire=9976, replies=[{channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true}, {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true}], messages=[]
2019-04-17 12:15:11,684 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:150) - Processing {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-17 12:15:11,685 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
2019-04-17 12:15:11,686 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:651) - > {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/connect, id=5, connectionType=long-polling} 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=26,expire=9974
2019-04-17 12:15:11,686 [http-nio-8080-exec-3] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:629) - Cancelling expiration for 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=0
2019-04-17 12:15:11,688 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:841) - < {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/connect, id=5, connectionType=long-polling} 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=0,expire=0
2019-04-17 12:15:11,688 [http-nio-8080-exec-3] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:950) - Notifying 0 listeners on /meta/connect
2019-04-17 12:15:11,689 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:466) - client mwspwa8saojjnspn 1 sessions for 47u3enl9oku6y1aww4q4v7rga,CONNECTED,last=1,expire=0
2019-04-17 12:15:11,689 [http-nio-8080-exec-3] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:115) - Suspended {clientId=47u3enl9oku6y1aww4q4v7rga, channel=/meta/connect, id=5, connectionType=long-polling}
2019-04-17 12:15:11,690 [BayeuxServer@577fc04e-Scheduler] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:166) - Sweeping session 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=49926,expire=-315
2019-04-17 12:15:21,975 [BayeuxServer@577fc04e-Scheduler] DEBUG org.cometd.server.BayeuxServerImpl.577fc04e (BayeuxServerImpl.java:509) - Removing session 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=49926,expire=-315, timed out: true
2019-04-17 12:15:21,975
Please let me know if you need more details,
Shreyas Holla
Simone Bordet
Apr 18, 2019, 4:39:00 AM4/18/19
to cometd-dev
Hi,
On Wed, Apr 17, 2019 at 10:13 AM <pshrey...@gmail.com> wrote:
> Since we are using JSONTransport now instead of AsyncJSONTransport, will this cause performance issues?
Typically you will have a much higher thread usage, and you are
subject to thread blocking if the clients don't read, which may
exhaust the thread pool and lead to server hangs.
> And for the scenario its not working, I got the pattern. Its working only once after server startup, below are logs,
[snip]
> 2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0, replies=[{channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true}, {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit}]
> 2019-04-17 12:14:32,064 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
As you can see the message has been flushed.
> And after that every subsequent request, the client does not receive published message. Below are the logs,
[snip]
> 2019-04-17 12:15:11,656 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=39609,expire=0, replies=[{channel=/meta/connect, id=5, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit}]
The problem is that after returning the /meta/connect with id=5, the
client does not send another /meta/connect message and the server-side
session is then expired.
What client are you using? The official CometD 4.0.x JavaScript client?
On Wed, Apr 17, 2019 at 10:13 AM <pshrey...@gmail.com> wrote:
> Since we are using JSONTransport now instead of AsyncJSONTransport, will this cause performance issues?
subject to thread blocking if the clients don't read, which may
exhaust the thread pool and lead to server hangs.
> And for the scenario its not working, I got the pattern. Its working only once after server startup, below are logs,
> 2019-04-17 12:14:32,049 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=0,expire=0, replies=[{channel=/meta/subscribe, id=2, subscription=/notification/superit, successful=true}, {channel=/meta/subscribe, id=3, subscription=/hostChannel, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit}]
> 2019-04-17 12:14:32,064 [http-nio-8080-exec-10] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
> And after that every subsequent request, the client does not receive published message. Below are the logs,
> 2019-04-17 12:15:11,656 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractHttpTransport.java:350) - Flushing 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=39609,expire=0, replies=[{channel=/meta/connect, id=5, successful=true}], messages=[{data={"data":{"msgDesc":"","act":"No Action","payload":{"recipient":"superit"},"acl":"No ACL","message":"notification_received","type":"Information","renderType":"Toaster","serviceId":"","serviceName":""}}, channel=/notification/superit}]
> 2019-04-17 12:15:11,657 [http-nio-8080-exec-9] DEBUG org.cometd.bayeux.server.ServerSession (ServerSessionImpl.java:641) - Scheduled expiration for 31vafkie6ho4g1bwylf18oql23,CONNECTED,last=39611,expire=10000
> 2019-04-17 12:15:11,660 [http-nio-8080-exec-9] DEBUG org.cometd.server.transport.JSONTransport (AbstractStreamHttpTransport.java:60) - Handling successful
And here also the message has been flushed.
The problem is that after returning the /meta/connect with id=5, the
client does not send another /meta/connect message and the server-side
session is then expired.
What client are you using? The official CometD 4.0.x JavaScript client?
pshrey...@gmail.com
Apr 20, 2019, 6:24:43 AM4/20/19
to cometd-dev
Hi,
So if JSONTransport may lead to thread exhaustion due to client connectivity, then it is risky to use it instead of AsyncJSONTransport.
We are using cometd that comes as part of dojo release.
So what else can I try now?
Simone Bordet
Apr 20, 2019, 8:46:36 AM4/20/19
to cometd-dev
Hi,
On Sat, Apr 20, 2019 at 12:24 PM <pshrey...@gmail.com> wrote:
> So if JSONTransport may lead to thread exhaustion due to client connectivity, then it is risky to use it instead of AsyncJSONTransport.
That is more risky but controllable via setting a proper idle timeout.
> We are using cometd that comes as part of dojo release.
CometD does not ship with Dojo since 8 or so years now.
I recommend that you use the CometD release, not what comes with Dojo.
> So what else can I try now?
Try the official CometD release.
On Sat, Apr 20, 2019 at 12:24 PM <pshrey...@gmail.com> wrote:
> So if JSONTransport may lead to thread exhaustion due to client connectivity, then it is risky to use it instead of AsyncJSONTransport.
> We are using cometd that comes as part of dojo release.
I recommend that you use the CometD release, not what comes with Dojo.
> So what else can I try now?
pshrey...@gmail.com
Apr 22, 2019, 10:50:23 AM4/22/19
to cometd-dev
Hi Simone,
> So if JSONTransport may lead to thread exhaustion due to client connectivity, then it is risky to use it instead of AsyncJSONTransport.
That is more risky but controllable via setting a proper idle timeout. So where and how much is the recommended one to set for idle timeout?
> So what else can I try now?
Try the official CometD release. Ok, I will try the official CometD release. Will that have fixes for AsyncJSONTransport?
Thanks And Regards,
Shreyas Holla P
Simone Bordet
Apr 22, 2019, 5:23:10 PM4/22/19
to cometd-dev
Hi,
On Mon, Apr 22, 2019 at 4:50 PM <pshrey...@gmail.com> wrote:
> So where and how much is the recommended one to set for idle timeout?
Leave it at default.
> Ok, I will try the official CometD release. Will that have fixes for AsyncJSONTransport?
There is nothing to fix in AsyncJSONTransport.
It's the Servlet Container you are using that seems to have bugs.
On Mon, Apr 22, 2019 at 4:50 PM <pshrey...@gmail.com> wrote:
> So where and how much is the recommended one to set for idle timeout?
> Ok, I will try the official CometD release. Will that have fixes for AsyncJSONTransport?
It's the Servlet Container you are using that seems to have bugs.
pshrey...@gmail.com
Apr 23, 2019, 11:45:08 AM4/23/19
to cometd-dev
> So where and how much is the recommended one to set for idle timeout?
Leave it at default.
Thanks, we will go ahead with JSONTransport for tomcat.
> Ok, I will try the official CometD release. Will that have fixes for AsyncJSONTransport?
There is nothing to fix in AsyncJSONTransport.
It's the Servlet Container you are using that seems to have bugs.
This, I will take the cometD 4.0.2 release and will get back to you on the result.
Reply all
Reply to author
Forward
0 new messages