Timer issue just broke my site after a week of no issues. Pl
modoc Nov 26, 2006 9:06 PMI'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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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@e2d858, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@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