[jboss-user] [JBoss Seam] - Timer issue just broke my site after a week of no issues. Pl

modoc do-not-reply at jboss.com
Sun Nov 26 21:06:11 EST 2006 

I'm in a bit of a panic, as my newly launched site, which just made the front page of digg.com yesterday, and as such is seeing a ton of traffic, just broke, and I can't figure out how to fix it.

The site was up and working fine for a week.  It still works perfectly on my dev box, with no changes anywhere.  I've restarted, blown out the tmp dir, re-pushed the ear, etc... no changes.

I'm using seam, a timer on a POJO, and have the timers configured to not persist.

Essentially, every 60 seconds, an e-mail account is checked, and all incoming e-mails are processed.  The timed component code looks like this:


  | /**
  |  * 
  |  */
  | package com.digitalsanctuary.seam;
  | 
  | import java.util.Date;
  | 
  | import javax.ejb.Timer;
  | 
  | import org.jboss.seam.ScopeType;
  | import org.jboss.seam.annotations.Asynchronous;
  | import org.jboss.seam.annotations.In;
  | import org.jboss.seam.annotations.Logger;
  | import org.jboss.seam.annotations.Name;
  | import org.jboss.seam.annotations.Scope;
  | import org.jboss.seam.annotations.Synchronized;
  | import org.jboss.seam.annotations.timer.Expiration;
  | import org.jboss.seam.annotations.timer.IntervalDuration;
  | import org.jboss.seam.log.Log;
  | 
  | import com.digitalsanctuary.mail.IMAPClient;
  | 
  | @Name("emailService")
  | @Scope(ScopeType.APPLICATION)
  | @Synchronized
  | public class EmailService {
  | 
  |     @Logger
  |     private Log mLog;
  | 
  |     @In(value = "emailManager", create = true)
  |     private EmailManager mEmailManager;
  | 
  |     @In(value = "IMAPClient", create = true)
  |     private IMAPClient mIMAPClient;
  | 
  |     /**
  |      * This method is called on a schedule determined by the passed in parameters. It gets new messages using the
  |      * IMAPClient, and passes them into the EmailManager for processing.
  |      * 
  |      * @param pDate
  |      *            the Date to start the scheduled job.
  |      * @param pInterval
  |      *            the number of millisecond between each run.
  |      * @return the EJB Timer instance that handles the scheduling of this task.
  |      */
  |     @Asynchronous
  |     public Timer processEmailsRecurring(@Expiration
  |     Date pDate, @IntervalDuration
  |     long pInterval) {
  |         mLog.info("proccessEmailsReccurring running...");
  |         this.mEmailManager.processNewEmails(mIMAPClient.getNewMessages());
  |         return null;
  |     }
  | }
  | 


It gets invoked in the create method of another class:


  |     @Create
  |     public void doStartService() {
  |         mLog.info("Starting up...");
  |         mClientMap = new HashMap<String, SessionMailQueue>();
  |         mLog.info("Kicking off recurring email processor.");
  |         this.mEmailService.processEmailsRecurring(new Date(), 60000);
  |     }
  | 


as you can see, the method that gets called every 60 seconds is:

        this.mEmailManager.processNewEmails(mIMAPClient.getNewMessages());

The inner method, getNewMessages gets called, and I can see it's log messages at it invokes successfully.  however, the outer method, processNetEmails, doesn't seem to get called.  Instead, immediately after the log lines from the getNewMessages execution, I get this:

17:56:56,804 ERROR [TimerImpl] Error invoking ejbTimeout: javax.ejb.EJBException: java.lang.NullPointerException

I don't know why it would be trying to timeout.  The processMailsRecurring method, does return null instead of a valid Timer, so that might cause a NPE, but I took that code from the example in SeamPay, where it returns null.  It also works totally fine in my dev environment, and worked in my production environment for a week without issue.

The server.log with all the debug info is below.

I'd appreciate ANY advice, as my site looks like it's working, but none of the e-mail ever gets processed correctly, so this is a huge problem for me.

It worked fine, and now it's broken and I don't know how to fix it.

Thanks to anyone who can help!


Modoc



  | 2006-11-26 17:20:47,798 INFO  [com.digitalsanctuary.seam.EmailManager] Starting up...
  | 2006-11-26 17:20:47,798 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:47,798 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:47,798 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:47,798 INFO  [com.digitalsanctuary.seam.EmailManager] Kicking off recurring email processor.
  | 2006-11-26 17:20:47,799 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.dispatcher
  | 2006-11-26 17:20:47,804 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-11-26 17:20:47,804 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at 586403{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:47,966 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.dispatcher
  | 2006-11-26 17:20:47,966 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.dispatcher
  | 2006-11-26 17:20:48,001 DEBUG [org.jboss.ejb.txtimer.TimerImpl] setTimerState: created
  | 2006-11-26 17:20:48,001 DEBUG [org.jboss.ejb.txtimer.NoopPersistencePolicy] Noop on insertTimer
  | 2006-11-26 17:20:48,005 DEBUG [org.jboss.ejb.txtimer.TimerImpl] setTimerState: started_in_tx
  | 2006-11-26 17:20:48,010 DEBUG [org.jboss.ejb.txtimer.TimerImpl] commit: [id=1,target=[target=jboss.j2ee:service=EJB3,ear=10MinuteMail.ear,jar=jboss-seam.jar,name=Dispatcher],remaining=-212,periode=60000,started_in_tx]
  | 2006-11-26 17:20:48,011 DEBUG [org.jboss.ejb.txtimer.TimerImpl] run: [id=1,target=[target=jboss.j2ee:service=EJB3,ear=10MinuteMail.ear,jar=jboss-seam.jar,name=Dispatcher],remaining=-213,periode=60000,started_in_tx]
  | 2006-11-26 17:20:48,011 DEBUG [org.jboss.ejb.txtimer.TimerImpl] setTimerState: in_timeout
  | 2006-11-26 17:20:48,012 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.dispatcher
  | 2006-11-26 17:20:48,012 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.dispatcher
  | 2006-11-26 17:20:48,012 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin call
  | 2006-11-26 17:20:48,017 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context
  | 2006-11-26 17:20:48,017 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager
  | 2006-11-26 17:20:48,017 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager
  | 2006-11-26 17:20:48,017 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager
  | 2006-11-26 17:20:48,017 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying conversation context
  | 2006-11-26 17:20:48,017 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context
  | 2006-11-26 17:20:48,018 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:20:48,018 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:20:48,018 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:20:48,018 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context
  | 2006-11-26 17:20:48,018 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context
  | 2006-11-26 17:20:48,018 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager
  | 2006-11-26 17:20:48,018 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End call
  | 2006-11-26 17:20:48,019 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin call
  | 2006-11-26 17:20:48,019 DEBUG [org.jboss.seam.contexts.Contexts] found in application context: emailService
  | 2006-11-26 17:20:48,019 DEBUG [org.jboss.ejb.txtimer.TimerImpl] setTimerState: active
  | 2006-11-26 17:20:48,028 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager
  | 2006-11-26 17:20:48,028 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager
  | 2006-11-26 17:20:48,028 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager
  | 2006-11-26 17:20:48,029 DEBUG [org.jboss.seam.Component] trying to inject with hierarchical context search: emailManager
  | 2006-11-26 17:20:48,029 DEBUG [org.jboss.seam.contexts.Contexts] found in application context: emailManager
  | 2006-11-26 17:20:48,029 DEBUG [org.jboss.seam.Component] trying to inject with hierarchical context search: IMAPClient
  | 2006-11-26 17:20:48,029 DEBUG [org.jboss.seam.Component] instantiating Seam component: IMAPClient
  | 2006-11-26 17:20:48,078 DEBUG [org.jboss.seam.Component] initializing new instance of: IMAPClient
  | 2006-11-26 17:20:48,078 DEBUG [org.jboss.seam.Component] done initializing: IMAPClient
  | 2006-11-26 17:20:48,078 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:48,079 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:48,079 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:48,079 INFO  [com.digitalsanctuary.mail.IMAPClient] Starting up...
  | 2006-11-26 17:20:48,079 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:20:48,079 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:20:48,079 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:20:48,080 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at 174a144{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:48,080 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at 1fe500a{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:48,081 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:48,081 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:48,081 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:48,081 INFO  [com.digitalsanctuary.seam.EmailService] proccessEmailsReccurring running...
  | 2006-11-26 17:20:48,081 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:48,081 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:48,081 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:48,081 INFO  [com.digitalsanctuary.mail.IMAPClient] entering getNewMessages...
  | 2006-11-26 17:20:48,082 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at 138d2fc{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:48,147 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at 1b1deea{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:48,213 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager
  | 2006-11-26 17:20:48,213 INFO  [org.jboss.seam.init.Initialization] done initializing Seam
  | 2006-11-26 17:20:50,241 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at ce82cc{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:50,655 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,655 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,655 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,655 INFO  [com.digitalsanctuary.mail.IMAPClient] E-mail picked up with content type=TEXT/PLAIN; charset=UTF-8
  | 2006-11-26 17:20:50,656 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,656 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,656 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,656 INFO  [com.digitalsanctuary.mail.IMAPClient] setting up plain message
  | 2006-11-26 17:20:50,811 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at 1b57dcb{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:50,813 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,813 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,813 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,813 INFO  [com.digitalsanctuary.mail.IMAPClient] E-mail picked up with content type=multipart/ALTERNATIVE; boundary="(AlternativeBoundary)"
  | 2006-11-26 17:20:50,813 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,813 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,813 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,813 INFO  [com.digitalsanctuary.mail.IMAPClient] setting up rich message
  | 2006-11-26 17:20:50,814 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at 55a58f{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:50,834 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at d2bb53{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:50,873 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,873 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,873 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,873 INFO  [com.digitalsanctuary.mail.IMAPClient] E-mail picked up with content type=TEXT/PLAIN; charset=ISO-8859-2; format=flowed
  | 2006-11-26 17:20:50,873 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,873 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,873 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,873 INFO  [com.digitalsanctuary.mail.IMAPClient] setting up plain message
  | 2006-11-26 17:20:50,879 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,879 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,879 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,879 INFO  [com.digitalsanctuary.mail.IMAPClient] E-mail picked up with content type=multipart/ALTERNATIVE; 
  | 
  | 	boundary="----=_Part_91406_17400087.1164590059328"
  | 2006-11-26 17:20:50,879 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,879 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,879 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,879 INFO  [com.digitalsanctuary.mail.IMAPClient] setting up rich message
  | 2006-11-26 17:20:50,887 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,887 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,887 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,887 INFO  [com.digitalsanctuary.mail.IMAPClient] E-mail picked up with content type=TEXT/PLAIN; charset=iso-8859-1
  | 2006-11-26 17:20:50,887 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,887 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,887 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:20:50,887 INFO  [com.digitalsanctuary.mail.IMAPClient] setting up plain message
  | 2006-11-26 17:20:51,001 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:20:51,003 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-11-26 17:20:51,007 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3 at e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader at 16b4e30{ url=null ,addedOrder=0}
  | 2006-11-26 17:20:51,008 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:20:51,008 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-11-26 17:20:51,008 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying conversation context
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.contexts.Contexts] destroying: timer
  | 2006-11-26 17:20:51,009 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End call
  | 2006-11-26 17:20:51,010 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin call
  | 2006-11-26 17:20:51,010 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context
  | 2006-11-26 17:20:51,010 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager
  | 2006-11-26 17:20:51,010 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying conversation context
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager
  | 2006-11-26 17:20:51,011 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End call
  | 2006-11-26 17:20:51,011 ERROR [org.jboss.ejb.txtimer.TimerImpl] Error invoking ejbTimeout: javax.ejb.EJBException: java.lang.NullPointerException
  | 

and another one after the timer has already been running for a while:



  | 2006-11-26 17:21:48,016 DEBUG [org.jboss.ejb.txtimer.TimerImpl] run: [id=1,target=[target=jboss.j2ee:service=EJB3,ear=10MinuteMail.ear,jar=jboss-seam.jar,name=Dispatcher],remaining=-218,periode=60000,active]
  | 2006-11-26 17:21:48,016 DEBUG [org.jboss.ejb.txtimer.TimerImpl] setTimerState: in_timeout
  | 2006-11-26 17:21:48,016 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.dispatcher
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.dispatcher
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin call
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.SESSION
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.SESSION
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.BUSINESS_PROCESS
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.BUSINESS_PROCESS
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postSetVariable.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postCreate.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,017 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying conversation context
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.CONVERSATION
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.CONVERSATION
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postSetVariable.org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postCreate.org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.EVENT
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroy.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.EVENT
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End call
  | 2006-11-26 17:21:48,018 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin call
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.org.jboss.seam.core.executingAsynchronousCall
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postSetVariable.org.jboss.seam.core.executingAsynchronousCall
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.timer
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postSetVariable.timer
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.contexts.Contexts] found in application context: emailService
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postSetVariable.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,019 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postCreate.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.Component] trying to inject with hierarchical context search: emailManager
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.contexts.Contexts] found in application context: emailManager
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.Component] trying to inject with hierarchical context search: IMAPClient
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.contexts.Contexts] found in application context: IMAPClient
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,020 INFO  [com.digitalsanctuary.seam.EmailService] proccessEmailsReccurring running...
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,020 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,020 INFO  [com.digitalsanctuary.mail.IMAPClient] entering getNewMessages...
  | 2006-11-26 17:21:48,281 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,281 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,281 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,281 INFO  [com.digitalsanctuary.mail.IMAPClient] E-mail picked up with content type=TEXT/PLAIN; charset=US-ASCII; format=flowed
  | 2006-11-26 17:21:48,281 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,281 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,281 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,281 INFO  [com.digitalsanctuary.mail.IMAPClient] setting up plain message
  | 2006-11-26 17:21:48,285 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,286 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,286 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,286 INFO  [com.digitalsanctuary.mail.IMAPClient] E-mail picked up with content type=TEXT/PLAIN; charset=iso-8859-1
  | 2006-11-26 17:21:48,286 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,286 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,286 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.interpolator
  | 2006-11-26 17:21:48,286 INFO  [com.digitalsanctuary.mail.IMAPClient] setting up plain message
  | 2006-11-26 17:21:48,317 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:21:48,317 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:21:48,317 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:21:48,317 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:21:48,317 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postSetVariable.org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:21:48,317 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postCreate.org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:21:48,318 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-11-26 17:21:48,318 DEBUG [org.jboss.seam.contexts.Contexts] found in conversation context: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:21:48,318 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-11-26 17:21:48,319 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preRemoveVariable.org.jboss.seam.core.executingAsynchronousCall
  | 2006-11-26 17:21:48,319 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postRemoveVariable.org.jboss.seam.core.executingAsynchronousCall
  | 2006-11-26 17:21:48,319 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.SESSION
  | 2006-11-26 17:21:48,319 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.SESSION
  | 2006-11-26 17:21:48,319 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context
  | 2006-11-26 17:21:48,319 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.BUSINESS_PROCESS
  | 2006-11-26 17:21:48,319 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.BUSINESS_PROCESS
  | 2006-11-26 17:21:48,319 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying conversation context
  | 2006-11-26 17:21:48,319 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.CONVERSATION
  | 2006-11-26 17:21:48,319 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroy.org.jboss.seam.core.persistenceContexts
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.CONVERSATION
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postSetVariable.org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postCreate.org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.EVENT
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroy.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.contexts.Contexts] destroying: timer
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.EVENT
  | 2006-11-26 17:21:48,320 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End call
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.contexts.Lifecycle] >>> Begin call
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.SESSION
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.SESSION
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying business process context
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.BUSINESS_PROCESS
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.BUSINESS_PROCESS
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postSetVariable.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postCreate.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying conversation context
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.CONVERSATION
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.CONVERSATION
  | 2006-11-26 17:21:48,321 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing server-side conversation context
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.Component] instantiating Seam component: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.Component] initializing new instance of: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.Component] done initializing: org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preSetVariable.org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postSetVariable.org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postCreate.org.jboss.seam.core.conversationEntries
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.contexts.Lifecycle] flushing session context
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.contexts.Lifecycle] destroying event context
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroyContext.EVENT
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.contexts.Contexts] destroying: org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.preDestroy.org.jboss.seam.core.manager
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.core.Events] Processing event:org.jboss.seam.postDestroyContext.EVENT
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.seam.contexts.Lifecycle] <<< End call
  | 2006-11-26 17:21:48,322 ERROR [org.jboss.ejb.txtimer.TimerImpl] Error invoking ejbTimeout: javax.ejb.EJBException: java.lang.NullPointerException
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.ejb.txtimer.TimerImpl] Timer was not registered with Tx, resetting state: [id=1,target=[target=jboss.j2ee:service=EJB3,ear=10MinuteMail.ear,jar=jboss-seam.jar,name=Dispatcher],remaining=59476,periode=60000,in_timeout]
  | 2006-11-26 17:21:48,322 DEBUG [org.jboss.ejb.txtimer.TimerImpl] setTimerState: active
  | 

View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=3988712#3988712

Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=3988712

More information about the jboss-user mailing list