Seam remoting conversation - possible problem?
chris.morrisette Dec 19, 2006 12:45 PMI am still having problems with Seam Remoting, within a conversation the state of a SFSB is lost. I had isolated some cases to being because my application restarted and not restarting the browsers session, but have found other unrelated cases.
I was able to capture the log of an instance of this happening. The conversation id is '6' and the SFSB (with a scope of 'conversation') is 'ServiceBusiness'.
At time datestamp '2006-12-19 12:20:33,656' you can see that seam conversation 6 is invoking a method on ServiceBusiness instance ServiceBusinessImpl@9403b0.
At time datestamp '2006-12-19 12:20:37,750' you can see that seam conversation 6 instantiates a new ServiceBusiness object and invokes a method on ServiceBusiness instance ServiceBusinessImpl@b73feb.
You can see that earlier invocations of conversation 6 all operate on ServiceBusinessImpl@9403b0.
In looking at the log, the only thing that pops out at me is that Conversation id '2' times out at time datestamp 2006-12-19 12:20:36,468, but this shouldn't have any impact on conversation 6, should it?
Am I doing something wrong?
I am about to jump into the source, but wanted to see if anybody had any suggestions first.
Thanks for any help,
Chris
jboss-4.0.5.GA
Seam 1.1.0.GA
jdk1.5.0_09
2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin web request 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.core.Manager] No stored conversation, or concurrent call to the stored conversation 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] instantiating Seam component: serviceBusiness 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] initializing new instance of: serviceBusiness 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] done initializing: serviceBusiness 2006-12-19 12:20:25,453 DEBUG [org.jboss.ejb3.entity.ExtendedPersistenceContextPropagationInterceptor] ++++ LongLivedSessionPropagationInterceptor 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator 2006-12-19 12:20:25,453 ERROR [com.zua.business.ServiceBusinessImpl] Instantiating Service Business object: com.zua.business.ServiceBusinessImpl@9403b0 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.core.Manager] Beginning long-running conversation 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.conversation 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.conversation 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.conversation 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.core.Manager] Storing conversation state: 6 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.contexts.Lifecycle] After request, destroying contexts 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End web request 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.remoting.ExecutionHandler] ended request 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:25,453 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:25,593 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:25,593 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:25,593 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:25,593 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:25,609 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:25,609 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:25,625 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:25,625 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:25,656 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:25,656 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin web request 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.core.Manager] Restoring conversation with id: 6 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: serviceBusiness 2006-12-19 12:20:25,734 DEBUG [org.jboss.ejb3.entity.ExtendedPersistenceContextPropagationInterceptor] ++++ LongLivedSessionPropagationInterceptor 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator 2006-12-19 12:20:25,734 ERROR [com.zua.business.ServiceBusinessImpl] Executing method on Service Business: com.zua.business.ServiceBusinessImpl@9403b0 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.core.Manager] Storing conversation state: 6 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Lifecycle] After request, destroying contexts 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End web request 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.remoting.ExecutionHandler] ended request 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:25,734 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:28,187 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin web request 2006-12-19 12:20:28,187 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager 2006-12-19 12:20:28,187 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager 2006-12-19 12:20:28,187 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager 2006-12-19 12:20:28,187 DEBUG [org.jboss.seam.core.Manager] Restoring conversation with id: 6 2006-12-19 12:20:28,187 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: serviceBusiness 2006-12-19 12:20:28,187 DEBUG [org.jboss.ejb3.entity.ExtendedPersistenceContextPropagationInterceptor] ++++ LongLivedSessionPropagationInterceptor 2006-12-19 12:20:28,187 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator 2006-12-19 12:20:28,187 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator 2006-12-19 12:20:28,187 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator 2006-12-19 12:20:28,187 ERROR [com.zua.business.ServiceBusinessImpl] Executing method on Service Business: com.zua.business.ServiceBusinessImpl@9403b0 2006-12-19 12:20:28,203 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:28,203 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] ************** closing entity managersession ************** 2006-12-19 12:20:28,203 DEBUG [org.jboss.seam.core.Manager] Storing conversation state: 6 2006-12-19 12:20:28,203 DEBUG [org.jboss.seam.contexts.Lifecycle] After request, destroying contexts 2006-12-19 12:20:28,203 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context 2006-12-19 12:20:28,203 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context 2006-12-19 12:20:28,203 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context 2006-12-19 12:20:28,203 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context 2006-12-19 12:20:28,203 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager 2006-12-19 12:20:28,203 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End web request 2006-12-19 12:20:28,250 DEBUG [org.jboss.seam.remoting.ExecutionHandler] ended request 2006-12-19 12:20:28,250 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:28,250 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:33,656 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin web request 2006-12-19 12:20:33,656 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager 2006-12-19 12:20:33,656 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager 2006-12-19 12:20:33,656 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager 2006-12-19 12:20:33,656 DEBUG [org.jboss.seam.core.Manager] Restoring conversation with id: 6 2006-12-19 12:20:33,656 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: serviceBusiness 2006-12-19 12:20:33,656 DEBUG [org.jboss.ejb3.entity.ExtendedPersistenceContextPropagationInterceptor] ++++ LongLivedSessionPropagationInterceptor 2006-12-19 12:20:33,656 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator 2006-12-19 12:20:33,656 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator 2006-12-19 12:20:33,656 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator 2006-12-19 12:20:33,656 ERROR [com.zua.business.ServiceBusinessImpl] Executing method on Service Business: com.zua.business.ServiceBusinessImpl@9403b0 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:36,468 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] ************** closing entity managersession ************** 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.core.Manager] Storing conversation state: 6 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.core.Manager] conversation timeout for conversation: 2 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.conversation 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Contexts] destroying: serviceBusiness 2006-12-19 12:20:36,468 DEBUG [org.jboss.ejb3.entity.ExtendedPersistenceContextPropagationInterceptor] ++++ LongLivedSessionPropagationInterceptor 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator 2006-12-19 12:20:36,468 ERROR [com.zua.business.ServiceBusinessImpl] Destroying Service Business object: com.zua.business.ServiceBusinessImpl@1aaf3cb 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.interceptors.RemoveInterceptor] Stateful component was removed: serviceBusiness 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Lifecycle] After request, destroying contexts 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager 2006-12-19 12:20:36,468 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End web request 2006-12-19 12:20:36,500 DEBUG [org.jboss.seam.remoting.ExecutionHandler] ended request 2006-12-19 12:20:36,500 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:36,500 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:36,531 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:36,531 DEBUG [org.jboss.seam.servlet.SeamExceptionFilter] ended request 2006-12-19 12:20:37,734 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin web request 2006-12-19 12:20:37,734 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager 2006-12-19 12:20:37,734 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager 2006-12-19 12:20:37,750 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager 2006-12-19 12:20:37,750 DEBUG [org.jboss.seam.core.Manager] Restoring conversation with id: 6 2006-12-19 12:20:37,750 DEBUG [org.jboss.seam.Component] instantiating Seam component: serviceBusiness 2006-12-19 12:20:37,750 DEBUG [org.jboss.seam.util.Naming] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 2006-12-19 12:20:37,750 DEBUG [org.jboss.seam.Component] initializing new instance of: serviceBusiness 2006-12-19 12:20:37,750 DEBUG [org.jboss.seam.Component] done initializing: serviceBusiness 2006-12-19 12:20:37,750 DEBUG [org.jboss.ejb3.entity.ExtendedPersistenceContextPropagationInterceptor] ++++ LongLivedSessionPropagationInterceptor 2006-12-19 12:20:37,750 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator 2006-12-19 12:20:37,750 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator 2006-12-19 12:20:37,750 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator 2006-12-19 12:20:37,750 ERROR [com.zua.business.ServiceBusinessImpl] Executing method on Service Business: com.zua.business.ServiceBusinessImpl@b73feb 2006-12-19 12:20:37,781 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: org.jboss.seam.core.persistenceContexts 2006-12-19 12:20:37,781 DEBUG [org.jboss.ejb3.entity.ManagedEntityManagerFactory] ************** closing entity managersession ************** 2006-12-19 12:20:37,781 DEBUG [org.jboss.seam.core.Manager] Storing conversation state: 6 2006-12-19 12:20:37,781 DEBUG [org.jboss.seam.contexts.Lifecycle] After request, destroying contexts 2006-12-19 12:20:37,781 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context 2006-12-19 12:20:37,781 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context 2006-12-19 12:20:37,781 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context 2006-12-19 12:20:37,781 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context 2006-12-19 12:20:37,781 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager 2006-12-19 12:20:37,781 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End web request