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