Greetings all,
So I have vCenter appliance with external PSC and I am having some really bad performance problems with vRO workflows presented in the web-client from the context action menu. The context action menu is available but once I click on the workflow I am getting extremely wait times before the workflow presentation is displayed. It has been taking anywhere from 60 to 90 seconds to present.
the vRO, VCs and PSCs are all on the same vLAN
Anyone got any suggestions?
Steve
Here is what I am seeing on the vsphere_client_virgo_client.log once I select the workflow in the web client Orchestration action menu:
[2016-01-15T21:04:25.853Z] [INFO ] http-bio-9090-exec-52 70000807 100006 ###### org.springframework.flex.servlet.MessageBrokerHandlerAdapter Channel endpoint amf received request.
[2016-01-15T21:04:25.854Z] [ERROR] http-bio-9090-exec-52 70000807 100006 ###### com.vmware.vise.util.session.SessionUtil Tried to associate a session to an invalid clientId. (sessionId 100006, clientId null, the actual clientId is not null, the new clientId doesn't exist (no history data available))
[2016-01-15T21:04:25.854Z] [ERROR] http-bio-9090-exec-52 70000807 100006 ###### com.vmware.vise.messaging.remoting.JavaAdapterEx The clientId is invalid. This is probably because a different session associated with the clientId has expired or logged out.
[2016-01-15T21:04:25.856Z] [WARN ] http-bio-9090-exec-52 70000807 100006 ###### org.springframework.flex.core.DefaultExceptionLogger The following exception occurred during request processing by the BlazeDS MessageBroker and will be serialized back to the client: flex.messaging.MessageException: The session is not authenticated.
at flex.messaging.services.RemotingService.serviceMessage(RemotingService.java:225)
at flex.messaging.MessageBroker.routeMessageToService(MessageBroker.java:1400)
at flex.messaging.endpoints.AbstractEndpoint.serviceMessage(AbstractEndpoint.java:1011)
at flex.messaging.endpoints.AbstractEndpoint$$FastClassByCGLIB$$1a3ef066.invoke(<generated>)
at net.sf.cglib.proxy.MethodProxy.invoke(MethodProxy.java:149)
at org.springframework.aop.framework.Cglib2AopProxy$CglibMethodInvocation.invokeJoinpoint(Cglib2AopProxy.java:689)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at org.springframework.flex.core.MessageInterceptionAdvice.invoke(MessageInterceptionAdvice.java:66)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.adapter.ThrowsAdviceInterceptor.invoke(ThrowsAdviceInterceptor.java:124)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.Cglib2AopProxy$FixedChainStaticTargetInterceptor.intercept(Cglib2AopProxy.java:573)
at com.vmware.vise.messaging.endpoints.AMFEndpoint$$EnhancerByCGLIB$$c3be72e0.serviceMessage(<generated>)
at flex.messaging.endpoints.amf.MessageBrokerFilter.invoke(MessageBrokerFilter.java:103)
at flex.messaging.endpoints.amf.LegacyFilter.invoke(LegacyFilter.java:158)
at flex.messaging.endpoints.amf.SessionFilter.invoke(SessionFilter.java:44)
at flex.messaging.endpoints.amf.BatchProcessFilter.invoke(BatchProcessFilter.java:67)
at flex.messaging.endpoints.amf.SerializationFilter.invoke(SerializationFilter.java:166)
at flex.messaging.endpoints.BaseHTTPEndpoint.service(BaseHTTPEndpoint.java:291)
at com.vmware.vise.messaging.endpoints.AMFEndpoint$$EnhancerByCGLIB$$c3be72e0.service(<generated>)
at org.springframework.flex.servlet.MessageBrokerHandlerAdapter.handle(MessageBrokerHandlerAdapter.java:109)
at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:923)
at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:852)
at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:789)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:755)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at com.vmware.vise.security.SessionManagementFilter.doFilterInternal(SessionManagementFilter.java:82)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at com.vmware.vsphere.client.logging.MDCLogFilter.doFilterInternal(MDCLogFilter.java:41)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98)
at org.eclipse.virgo.web.tomcat.support.ApplicationNameTrackingValve.invoke(ApplicationNameTrackingValve.java:33)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1040)
at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607)
at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:313)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: com.vmware.vise.vim.commons.security.NotAuthenticatedError: The session is not authenticated.
at com.vmware.vise.messaging.remoting.JavaAdapterEx.associateSessionWithClientId(JavaAdapterEx.java:105)
at com.vmware.vise.messaging.remoting.JavaAdapterEx.invoke(JavaAdapterEx.java:48)
at flex.messaging.services.RemotingService.serviceMessage(RemotingService.java:183)
... 54 common frames omitted
[2016-01-15T21:04:25.957Z] [INFO ] http-bio-9090-exec-66 org.springframework.flex.servlet.MessageBrokerHandlerAdapter Channel endpoint amf received request.
[2016-01-15T21:04:25.957Z] [ERROR] http-bio-9090-exec-66 com.vmware.vise.util.session.SessionUtil Tried to associate a session to an invalid clientId. (sessionId 100025, clientId null, the actual clientId is not null, the new clientId doesn't exist (no history data available))
[2016-01-15T21:04:25.957Z] [ERROR] http-bio-9090-exec-66 com.vmware.vise.messaging.remoting.JavaAdapterEx The clientId is invalid. This is probably because a different session associated with the clientId has expired or logged out.
[2016-01-15T21:04:25.959Z] [WARN ] http-bio-9090-exec-66 org.springframework.flex.core.DefaultExceptionLogger The following exception occurred during request processing by the BlazeDS MessageBroker and will be serialized back to the client: flex.messaging.MessageException: The session is not authenticated.
at flex.messaging.services.RemotingService.serviceMessage(RemotingService.java:225)
at flex.messaging.MessageBroker.routeMessageToService(MessageBroker.java:1400)
at flex.messaging.endpoints.AbstractEndpoint.serviceMessage(AbstractEndpoint.java:1011)
at flex.messaging.endpoints.AbstractEndpoint$$FastClassByCGLIB$$1a3ef066.invoke(<generated>)
at net.sf.cglib.proxy.MethodProxy.invoke(MethodProxy.java:149)
at org.springframework.aop.framework.Cglib2AopProxy$CglibMethodInvocation.invokeJoinpoint(Cglib2AopProxy.java:689)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at org.springframework.flex.core.MessageInterceptionAdvice.invoke(MessageInterceptionAdvice.java:66)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.adapter.ThrowsAdviceInterceptor.invoke(ThrowsAdviceInterceptor.java:124)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.Cglib2AopProxy$FixedChainStaticTargetInterceptor.intercept(Cglib2AopProxy.java:573)
at flex.messaging.endpoints.AMFEndpoint$$EnhancerByCGLIB$$89bb4755.serviceMessage(<generated>)
at flex.messaging.endpoints.amf.MessageBrokerFilter.invoke(MessageBrokerFilter.java:103)
at flex.messaging.endpoints.amf.LegacyFilter.invoke(LegacyFilter.java:158)
at flex.messaging.endpoints.amf.SessionFilter.invoke(SessionFilter.java:44)
at flex.messaging.endpoints.amf.BatchProcessFilter.invoke(BatchProcessFilter.java:67)
at flex.messaging.endpoints.amf.SerializationFilter.invoke(SerializationFilter.java:166)
at flex.messaging.endpoints.BaseHTTPEndpoint.service(BaseHTTPEndpoint.java:291)
at flex.messaging.endpoints.AMFEndpoint$$EnhancerByCGLIB$$89bb4755.service(<generated>)
at org.springframework.flex.servlet.MessageBrokerHandlerAdapter.handle(MessageBrokerHandlerAdapter.java:109)
at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:923)
at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:852)
at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:789)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:755)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98)
at org.eclipse.virgo.web.tomcat.support.ApplicationNameTrackingValve.invoke(ApplicationNameTrackingValve.java:33)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1040)
at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607)
at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:315)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: com.vmware.vise.vim.commons.security.NotAuthenticatedError: The session is not authenticated.
at com.vmware.vise.messaging.remoting.JavaAdapterEx.associateSessionWithClientId(JavaAdapterEx.java:105)
at com.vmware.vise.messaging.remoting.JavaAdapterEx.invoke(JavaAdapterEx.java:48)
at flex.messaging.services.RemotingService.serviceMessage(RemotingService.java:183)
... 46 common frames omitted
[2016-01-15T21:04:35.094Z] [WARN ] data-service-pool-24318 70000799 100018 200001 com.vmware.vise.data.query.profiling.ProfilingUtil The Data Service detected slow execution:
The query batch execution took too long: 19826 milliseconds.
A DataProviderAdapter execution took too long: 19825 milliseconds. ([Adapter: com.vmware.vco.adapter.dataservice.WorkflowCategoryProcessor@69085ae3, typeInfo: [vco:WorkflowItem, [*], vco:WorkflowCategory, [*]]]).
The names of the queries processed by this adapter are: [ds-auto-generated-name-151]
The queries in the batch sent to the Data Service are:
Query[0]:
QuerySpec
QueryName: ds-auto-generated-name-151
ResourceSpec
Constraint: VcoWorkflowsConstraint
TargetType: vco:WorkflowItem
PropertySpec[1]
ProviderType: null
Relationship: null
Properties[2]
PropertyName: name
PropertyName: description
ResultSpec:
Offset: null
MaxResultCount: null
OrderingCriteria
OrderPropertySpec[1]
ProviderType: Object
Relationship: null
SortType: ASCENDING
OrderingProperties[1]
PropertyName: id
Facets: null
Options: null
[2016-01-15T21:04:35.094Z] [WARN ] data-service-pool-24318 70000799 100018 200001 com.vmware.vise.data.query.impl.ServicePropertyProviderAdapter A PropertyProviderBean method's execution took too long: 19826 milliseconds. (Provider instance: com.vmware.vco.adapter.dataservice.RootVcoNodePropertyProvider@7d1efd23, method: public java.util.List com.vmware.vco.adapter.dataservice.RootVcoNodePropertyProvider.getActionItems(com.vmware.vco.adapter.dataservice.type.VcoRefType), property: ref: vco:a8acf0e5-52a6-454a-baa4-44d76db6ba46,vco:RootVcoNode,https://server.starwave.com:8281/vco/api/, method args: [vco:a8acf0e5-52a6-454a-baa4-44d76db6ba46,vco:RootVcoNode,https://server.domain.com:8281/vco/api/])
[2016-01-15T21:04:35.094Z] [WARN ] http-bio-9090-exec-63 70000799 100018 200001 com.vmware.vise.data.query.profiling.ProfilingUtil The Data Service detected slow execution:
The query batch execution took too long: 36658 milliseconds.
A PropertyProviderAdapter execution took too long: 36657 milliseconds. (com.vmware.vise.data.query.impl.ServicePropertyProviderAdapter@6981ecbe(com.vmware.vco.adapter.dataservice.RootVcoNodePropertyProvider)). The properties were: [[type: vco:RootVcoNode, relation: null, propertyNames: [actionItems], parameters: []]]
The queries in the batch sent to the Data Service are:
Query[0]:
QuerySpec
QueryName: dam-auto-generated: CatalogActionResolver:dr-453
ResourceSpec
Constraint: ObjectIdentityConstraint
TargetType: vco:RootVcoNode
Target: vco:a8acf0e5-52a6-454a-baa4-44d76db6ba46,vco:RootVcoNode,https://server.domain.com:8281/vco/api/
PropertySpec[1]
ProviderType: vco:RootVcoNode
Relationship:
Properties[1]
PropertyName: actionItems
ResultSpec:
Offset: 0
MaxResultCount: -1
OrderingCriteria
OrderPropertySpec[1]
ProviderType: Object
Relationship: null
SortType: ASCENDING
OrderingProperties[1]
PropertyName: id
Facets: null
Options:
REDUCE_QUERIES=true
[2016-01-15T21:04:35.355Z] [INFO ] http-bio-9090-exec-63 70000808 100018 200001 org.springframework.flex.servlet.MessageBrokerHandlerAdapter Channel endpoint amf received request.
[2016-01-15T21:04:35.388Z] [INFO ] http-bio-9090-exec-63 70000808 100018 200001 com.vmware.vco.adapter.dataservice.WorkflowRunInfoProcessor Delegated token requested for vCO host: server.domain.com
[2016-01-15T21:04:35.608Z] [INFO ] http-bio-9090-exec-63 70000808 100018 200001 com.vmware.vco.adapter.dataservice.WorkflowRunInfoProcessor Delegated token requested for vCO host: server.domain.com
[2016-01-15T21:04:36.288Z] [INFO ] http-bio-9090-exec-63 70000809 100018 200001 org.springframework.flex.servlet.MessageBrokerHandlerAdapter Channel endpoint amf received request.
[2016-01-15T21:04:36.299Z] [INFO ] http-bio-9090-exec-63 70000809 100018 200001 com.vmware.vco.adapter.dataservice.WorkflowRunInfoProcessor Delegated token requested for vCO host: server.domain.com
[2016-01-15T21:04:38.567Z] [INFO ] http-bio-9090-exec-63 70000810 100018 200001 org.springframework.flex.servlet.MessageBrokerHandlerAdapter Channel endpoint amf received request.
[2016-01-15T21:04:45.689Z] [INFO ] http-bio-9090-exec-21 70000811 100007 ###### org.springframework.flex.servlet.MessageBrokerHandlerAdapter Channel endpoint amf received request.
[2016-01-15T21:04:45.690Z] [ERROR] http-bio-9090-exec-21 70000811 100007 ###### com.vmware.vise.util.session.SessionUtil Tried to associate a session to an invalid clientId. (sessionId 100007, clientId null, the actual clientId is not null, the new clientId doesn't exist (no history data available))
[2016-01-15T21:04:45.690Z] [ERROR] http-bio-9090-exec-21 70000811 100007 ###### com.vmware.vise.messaging.remoting.JavaAdapterEx The clientId is invalid. This is probably because a different session associated with the clientId has expired or logged out.
[2016-01-15T21:04:45.692Z] [WARN ] http-bio-9090-exec-21 70000811 100007 ###### org.springframework.flex.core.DefaultExceptionLogger The following exception occurred during request processing by the BlazeDS MessageBroker and will be serialized back to the client: flex.messaging.MessageException: The session is not authenticated.
at flex.messaging.services.RemotingService.serviceMessage(RemotingService.java:225)
at flex.messaging.MessageBroker.routeMessageToService(MessageBroker.java:1400)
at flex.messaging.endpoints.AbstractEndpoint.serviceMessage(AbstractEndpoint.java:1011)
at flex.messaging.endpoints.AbstractEndpoint$$FastClassByCGLIB$$1a3ef066.invoke(<generated>)
at net.sf.cglib.proxy.MethodProxy.invoke(MethodProxy.java:149)
at org.springframework.aop.framework.Cglib2AopProxy$CglibMethodInvocation.invokeJoinpoint(Cglib2AopProxy.java:689)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at org.springframework.flex.core.MessageInterceptionAdvice.invoke(MessageInterceptionAdvice.java:66)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.adapter.ThrowsAdviceInterceptor.invoke(ThrowsAdviceInterceptor.java:124)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.Cglib2AopProxy$FixedChainStaticTargetInterceptor.intercept(Cglib2AopProxy.java:573)
at com.vmware.vise.messaging.endpoints.AMFEndpoint$$EnhancerByCGLIB$$c3be72e0.serviceMessage(<generated>)
at flex.messaging.endpoints.amf.MessageBrokerFilter.invoke(MessageBrokerFilter.java:103)
at flex.messaging.endpoints.amf.LegacyFilter.invoke(LegacyFilter.java:158)
at flex.messaging.endpoints.amf.SessionFilter.invoke(SessionFilter.java:44)
at flex.messaging.endpoints.amf.BatchProcessFilter.invoke(BatchProcessFilter.java:67)
at flex.messaging.endpoints.amf.SerializationFilter.invoke(SerializationFilter.java:166)
at flex.messaging.endpoints.BaseHTTPEndpoint.service(BaseHTTPEndpoint.java:291)
at com.vmware.vise.messaging.endpoints.AMFEndpoint$$EnhancerByCGLIB$$c3be72e0.service(<generated>)
at org.springframework.flex.servlet.MessageBrokerHandlerAdapter.handle(MessageBrokerHandlerAdapter.java:109)
at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:923)
at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:852)
at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:789)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:755)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at com.vmware.vise.security.SessionManagementFilter.doFilterInternal(SessionManagementFilter.java:82)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at com.vmware.vsphere.client.logging.MDCLogFilter.doFilterInternal(MDCLogFilter.java:41)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98)
at org.eclipse.virgo.web.tomcat.support.ApplicationNameTrackingValve.invoke(ApplicationNameTrackingValve.java:33)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1040)
at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607)
at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:313)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: com.vmware.vise.vim.commons.security.NotAuthenticatedError: The session is not authenticated.
at com.vmware.vise.messaging.remoting.JavaAdapterEx.associateSessionWithClientId(JavaAdapterEx.java:105)
at com.vmware.vise.messaging.remoting.JavaAdapterEx.invoke(JavaAdapterEx.java:48)
at flex.messaging.services.RemotingService.serviceMessage(RemotingService.java:183)
... 54 common frames omitted
[2016-01-15T21:04:45.991Z] [INFO ] http-bio-9090-exec-59 70000812 100017 ###### com.vmware.identity.token.impl.SamlTokenImpl SAML token for SubjectNameId [value=machine-1b5edd2b-b616-410b-b4ad-c763063e6b44@psc-nvl2.starwave.com, format=http://schemas.xmlsoap.org/claims/UPN] successfully parsed from XML
[2016-01-15T21:04:46.206Z] [INFO ] http-bio-9090-exec-64 70000806 100005 ###### com.vmware.identity.token.impl.SamlTokenImpl SAML token for SubjectNameId [value=machine-1b5edd2b-b616-410b-b4ad-c763063e6b44@psc-nvl2.starwave.com, format=http://schemas.xmlsoap.org/claims/UPN] successfully parsed from XML
[2016-01-15T21:04:50.485Z] [INFO ] http-bio-9090-exec-61 70000813 100008 ###### org.springframework.flex.servlet.MessageBrokerHandlerAdapter Channel endpoint amf received request.
[2016-01-15T21:04:50.489Z] [ERROR] http-bio-9090-exec-61 70000813 100008 ###### com.vmware.vise.util.session.SessionUtil Tried to associate a session to an invalid clientId. (sessionId 100008, clientId null, the actual clientId is not null, the new clientId doesn't exist (no history data available))
[2016-01-15T21:04:50.489Z] [ERROR] http-bio-9090-exec-61 70000813 100008 ###### com.vmware.vise.messaging.remoting.JavaAdapterEx The clientId is invalid. This is probably because a different session associated with the clientId has expired or logged out.
[2016-01-15T21:04:50.491Z] [WARN ] http-bio-9090-exec-61 70000813 100008 ###### org.springframework.flex.core.DefaultExceptionLogger The following exception occurred during request processing by the BlazeDS MessageBroker and will be serialized back to the client: flex.messaging.MessageException: The session is not authenticated.
at flex.messaging.services.RemotingService.serviceMessage(RemotingService.java:225)
at flex.messaging.MessageBroker.routeMessageToService(MessageBroker.java:1400)
at flex.messaging.endpoints.AbstractEndpoint.serviceMessage(AbstractEndpoint.java:1011)
at flex.messaging.endpoints.AbstractEndpoint$$FastClassByCGLIB$$1a3ef066.invoke(<generated>)
at net.sf.cglib.proxy.MethodProxy.invoke(MethodProxy.java:149)
at org.springframework.aop.framework.Cglib2AopProxy$CglibMethodInvocation.invokeJoinpoint(Cglib2AopProxy.java:689)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at org.springframework.flex.core.MessageInterceptionAdvice.invoke(MessageInterceptionAdvice.java:66)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.adapter.ThrowsAdviceInterceptor.invoke(ThrowsAdviceInterceptor.java:124)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.Cglib2AopProxy$FixedChainStaticTargetInterceptor.intercept(Cglib2AopProxy.java:573)
at com.vmware.vise.messaging.endpoints.AMFEndpoint$$EnhancerByCGLIB$$c3be72e0.serviceMessage(<generated>)
at flex.messaging.endpoints.amf.MessageBrokerFilter.invoke(MessageBrokerFilter.java:103)
at flex.messaging.endpoints.amf.LegacyFilter.invoke(LegacyFilter.java:158)
at flex.messaging.endpoints.amf.SessionFilter.invoke(SessionFilter.java:44)
at flex.messaging.endpoints.amf.BatchProcessFilter.invoke(BatchProcessFilter.java:67)
at flex.messaging.endpoints.amf.SerializationFilter.invoke(SerializationFilter.java:166)
at flex.messaging.endpoints.BaseHTTPEndpoint.service(BaseHTTPEndpoint.java:291)
at com.vmware.vise.messaging.endpoints.AMFEndpoint$$EnhancerByCGLIB$$c3be72e0.service(<generated>)
at org.springframework.flex.servlet.MessageBrokerHandlerAdapter.handle(MessageBrokerHandlerAdapter.java:109)
at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:923)
at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:852)
at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:789)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:755)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at com.vmware.vise.security.SessionManagementFilter.doFilterInternal(SessionManagementFilter.java:82)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at com.vmware.vsphere.client.logging.MDCLogFilter.doFilterInternal(MDCLogFilter.java:41)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98)
at org.eclipse.virgo.web.tomcat.support.ApplicationNameTrackingValve.invoke(ApplicationNameTrackingValve.java:33)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:950)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1040)
at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607)
at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:313)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: com.vmware.vise.vim.commons.security.NotAuthenticatedError: The session is not authenticated.
at com.vmware.vise.messaging.remoting.JavaAdapterEx.associateSessionWithClientId(JavaAdapterEx.java:105)
at com.vmware.vise.messaging.remoting.JavaAdapterEx.invoke(JavaAdapterEx.java:48)
at flex.messaging.services.RemotingService.serviceMessage(RemotingService.java:183)
... 54 common frames omitted
Hi Steve,
I've seen similar performance issue in some of our environments (queries taking tens of seconds). Usually it happens the first time I start a workflow; the next attempts to start the workflow appear faster.
Unfortunately, at the moment I don't have any suggestions how this can be resolved.