6 Replies Latest reply on Jan 20, 2009 3:22 PM by John Harby

    JNDI lookup of EJB3 SLSB from Quartz

    John Harby Newbie

      We are on JBoss 4.2.2GA. Was there something we needed to configure for Quartz to do a JNDI lookup for an EJB3 SLSB? We have a Quartz job (using the AnnotatedMDB approach) and a EJB3 SLSB in separate ears and are having problems with JNDI looking up the EJB from the Quartz job. This is my stack trace from the server console:

      12:56:20,361 INFO [SessionFactoryObjectFactory] Not binding factory to JNDI, no JNDI name configured
      
      12:56:40,018 ERROR [JobRunShell] Job default.job.0.1229459136826 threw an unhandled Exception:
      
      java.lang.NullPointerException
      
       at org.jboss.ejb3.JndiUtil.lookup(JndiUtil.java:49)
      
       at org.jboss.injection.JndiPropertyInjector.lookup(JndiPropertyInjector.java:87)
      
       at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:112)
      
       at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:107)
      
       at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:65)
      
       at org.jboss.ejb3.AbstractPool.create(AbstractPool.java:111)
      
       at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:141)
      
       at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
      
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
      
       at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
      
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
      
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
      
       at $Proxy71.execute(Unknown Source)
      
       at org.jboss.resource.adapter.quartz.inflow.QuartzJob.execute(QuartzJob.java:57)
      
       at org.quartz.core.JobRunShell.run(JobRunShell.java:203)
      
       at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:520)
      
      12:56:40,018 ERROR [ErrorLogger] Job (default.job.0.1229459136826 threw an exception.
      
      org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: java.lang.NullPointerException]
      
       at org.quartz.core.JobRunShell.run(JobRunShell.java:214)
      
       at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:520)
      
      * Nested Exception (Underlying Cause) ---------------
      
      java.lang.NullPointerException
      
       at org.jboss.ejb3.JndiUtil.lookup(JndiUtil.java:49)
      
       at org.jboss.injection.JndiPropertyInjector.lookup(JndiPropertyInjector.java:87)
      
       at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:112)
      
       at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:107)
      
       at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:65)
      
       at org.jboss.ejb3.AbstractPool.create(AbstractPool.java:111)
      
       at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:141)
      
       at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
      
       at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
      
       at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
      
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
      
       at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
      
       at $Proxy71.execute(Unknown Source)
      
       at org.jboss.resource.adapter.quartz.inflow.QuartzJob.execute(QuartzJob.java:57)
      
       at org.quartz.core.JobRunShell.run(JobRunShell.java:203)
      
       at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:520)
      


        • 1. Re: JNDI lookup of EJB3 SLSB from Quartz
          jaikiran pai Master

          Please post your code. I remember that there was a bug where if the jndi name used for injection was incorrect, then instead of a NameNotFoundException, this NullPointerException was being raised. I don't remember which version had this problem and which version had the fix. Let me see if i can find that issue.

          In the meantime, please ensure that if you are using a mappedName to inject the EJB, then the mappedName is correct.

          • 2. Re: JNDI lookup of EJB3 SLSB from Quartz
            John Harby Newbie

            Ok, thanks here is the Quartz job execute method.

             public void execute(JobExecutionContext jobExecutionContext) throws JobExecutionException {
             try {
             Context ctx = getInitialContext("jnp://localhost:1099");
             FundingService fs = (FundingService) ctx.lookup("policyService-1.2/FundingServiceBean/remote");
             List<Check> checks = fs.getAllActiveChecks();
             System.out.println("RUNNING");
             }
             catch (Exception e) {
             e.printStackTrace();
             throw new JobExecutionException("Unable to execute a scheduled job", e, false);
             }
             }
            
            


            • 3. Re: JNDI lookup of EJB3 SLSB from Quartz
              jaikiran pai Master

              I don't think this is the code that is throwing the exception. Going by the exception stacktrace you posted, the exception is being thrown when some object is being injected

              at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:65)
              at org.jboss.ejb3.AbstractPool.create(AbstractPool.java:111)
              at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:141)




              When exactly does this exception occur. Please post the entire console logs along with the complete exception stacktrace.

              • 4. Re: JNDI lookup of EJB3 SLSB from Quartz
                John Harby Newbie

                 

                "jaikiran" wrote:
                I don't think this is the code that is throwing the exception. Going by the exception stacktrace you posted, the exception is being thrown when some object is being injected

                at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:65)
                at org.jboss.ejb3.AbstractPool.create(AbstractPool.java:111)
                at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:141)




                When exactly does this exception occur. Please post the entire console logs along with the complete exception stacktrace.


                Ok, here is all of the code for the Quartz MDB along with the console log:

                package com.csatp.application.checkHandler;
                
                import com.csatp.service.funding.Check;
                import com.csatp.service.funding.FundingServiceClient;
                import org.apache.commons.logging.Log;
                import org.apache.commons.logging.LogFactory;
                import org.jboss.annotation.ejb.ResourceAdapter;
                import org.quartz.Job;
                import org.quartz.JobExecutionContext;
                import org.quartz.JobExecutionException;
                
                import javax.ejb.ActivationConfigProperty;
                import javax.ejb.MessageDriven;
                import java.util.List;
                
                @MessageDriven(activationConfig = {
                @ActivationConfigProperty(propertyName = "cronTrigger", propertyValue = "0/90 * * * * ?")
                 }, name = "CheckHandlerProcessorEJB")
                
                /**
                 * AnnotatedQuartzMDBBean runs the Quartz scheduler and provides an execute method which
                 * is executed when the Quartz trigger fires. This method invokes a query on the database
                 * which retrieves all records satisfying the query in the getLetterQuery method. It then
                 * composes a NotificationMessage for each record and sends a message to the NotificationQueue
                 * for each one.
                 *
                 * @version 1.0 10 June 2008
                 * @author JMH
                 */
                @ResourceAdapter("quartz-ra.rar")
                public class CheckHandlerProcessor implements Job {
                
                
                
                 // private static final Logger log = Logger.getLogger(AnnotatedQuartzMDBBean.class);
                
                 private Log log = LogFactory.getLog(CheckHandlerProcessor.class);
                
                 /**
                 * The execute method will be called by the Quartz scheduler when its trigger fires.
                 * This job will call the getLetterInfo method to get a collection of results from the
                 * SQL in the getLetterQuery method below. Then it will call the sendLetters method to
                 * create a JMS session to the notifier queue and along with the information acquired
                 * from calling buildNotification will send the messages to the notifier queue.
                 *
                 * @param jobExecutionContext the Quartz job execution context object
                 * @throws JobExecutionException thrown if any exceptions occur within this method
                 */
                 public void execute(JobExecutionContext jobExecutionContext) throws JobExecutionException {
                 try {
                 FundingServiceClient fsc = new FundingServiceClient();
                 List<Check> checksToBeProcessed = fsc.getAllActiveChecks();
                 fsc.processIncomingChecks( checksToBeProcessed );
                 System.out.println("RUNNING");
                 }
                 catch (Exception e) {
                
                 throw new JobExecutionException("Unable to execute a scheduled job", e, false);
                 }
                 }
                
                }
                
                
                
                


                
                cmd /c C:\jboss-4.2.2.GA\bin\run.bat -c default
                ===============================================================================
                
                 JBoss Bootstrap Environment
                
                 JBOSS_HOME: C:\jboss-4.2.2.GA
                
                 JAVA: C:\Program Files\Java\jdk1.6.0_10\bin\java
                
                 JAVA_OPTS: -Xdebug -Xrunjdwp:transport=dt_socket,address=127.0.0.1:1524,suspend=y,server=n -XX:+UseConcMarkSweepGC -XX:+CMSClassUnloadingEnabled -XX:MaxPermSize=128m -Dprogram.name=run.bat -server -Xms128m -Xmx512m -Dsun.rmi.dgc.client.gcInterval=3600000 -Dsun.rmi.dgc.server.gcInterval=3600000
                
                 CLASSPATH: C:\Program Files\Java\jdk1.6.0_10\lib\tools.jar;C:\jboss-4.2.2.GA\bin\run.jar
                
                ===============================================================================
                
                Connected to the target VM, address: '127.0.0.1:1524', transport: 'socket'
                09:04:29,223 INFO [Server] Starting JBoss (MX MicroKernel)...
                09:04:29,223 INFO [Server] Release ID: JBoss [Trinity] 4.2.2.GA (build: SVNTag=JBoss_4_2_2_GA date=200710221139)
                09:04:29,223 INFO [Server] Home Dir: C:\jboss-4.2.2.GA
                09:04:29,223 INFO [Server] Home URL: file:/C:/jboss-4.2.2.GA/
                09:04:29,223 INFO [Server] Patch URL: null
                09:04:29,223 INFO [Server] Server Name: default
                09:04:29,223 INFO [Server] Server Home Dir: C:\jboss-4.2.2.GA\server\default
                09:04:29,223 INFO [Server] Server Home URL: file:/C:/jboss-4.2.2.GA/server/default/
                09:04:29,223 INFO [Server] Server Log Dir: C:\jboss-4.2.2.GA\server\default\log
                09:04:29,223 INFO [Server] Server Temp Dir: C:\jboss-4.2.2.GA\server\default\tmp
                09:04:29,223 INFO [Server] Root Deployment Filename: jboss-service.xml
                09:04:29,567 INFO [ServerInfo] Java version: 1.6.0_10-beta,Sun Microsystems Inc.
                09:04:29,567 INFO [ServerInfo] Java VM: Java HotSpot(TM) Server VM 11.0-b12,Sun Microsystems Inc.
                09:04:29,567 INFO [ServerInfo] OS-System: Windows XP 5.1,x86
                09:04:30,177 INFO [Server] Core system initialized
                09:04:32,505 INFO [WebService] Using RMI server codebase: http://127.0.0.1:8083/
                09:04:32,505 INFO [Log4jService$URLWatchTimerTask] Configuring from URL: resource:jboss-log4j.xml
                09:04:33,052 INFO [TransactionManagerService] JBossTS Transaction Service (JTA version) - JBoss Inc.
                09:04:33,052 INFO [TransactionManagerService] Setting up property manager MBean and JMX layer
                09:04:33,239 INFO [TransactionManagerService] Starting recovery manager
                09:04:33,395 INFO [TransactionManagerService] Recovery manager started
                09:04:33,395 INFO [TransactionManagerService] Binding TransactionManager JNDI Reference
                09:04:36,505 INFO [EJB3Deployer] Starting java:comp multiplexer
                09:04:37,427 INFO [VelocityEngine] **************************************************************
                09:04:37,427 INFO [VelocityEngine] Starting Jakarta Velocity v1.4
                09:04:37,427 INFO [VelocityEngine] RuntimeInstance initializing.
                09:04:37,427 INFO [VelocityEngine] Default Properties File: org\apache\velocity\runtime\defaults\velocity.properties
                09:04:37,427 INFO [VelocityEngine] Trying to use logger class org.apache.velocity.runtime.log.SimpleLog4JLogSystem
                09:04:37,427 INFO [VelocityEngine] Using logger class org.apache.velocity.runtime.log.SimpleLog4JLogSystem
                09:04:37,442 INFO [VelocityEngine] Default ResourceManager initializing. (class org.apache.velocity.runtime.resource.ResourceManagerImpl)
                09:04:37,442 INFO [VelocityEngine] Resource Loader Instantiated: org.apache.velocity.runtime.resource.loader.FileResourceLoader
                09:04:37,442 INFO [VelocityEngine] FileResourceLoader : initialization starting.
                09:04:37,442 INFO [VelocityEngine] FileResourceLoader : adding path 'C:\jboss-4.2.2.GA\server\default\conf\templates'
                09:04:37,442 INFO [VelocityEngine] FileResourceLoader : initialization complete.
                09:04:37,442 INFO [VelocityEngine] ResourceCache : initialized. (class org.apache.velocity.runtime.resource.ResourceCacheImpl)
                09:04:37,442 INFO [VelocityEngine] Default ResourceManager initialization complete.
                09:04:37,442 INFO [VelocityEngine] Loaded System Directive: org.apache.velocity.runtime.directive.Literal
                09:04:37,442 INFO [VelocityEngine] Loaded System Directive: org.apache.velocity.runtime.directive.Macro
                09:04:37,442 INFO [VelocityEngine] Loaded System Directive: org.apache.velocity.runtime.directive.Parse
                09:04:37,458 INFO [VelocityEngine] Loaded System Directive: org.apache.velocity.runtime.directive.Include
                09:04:37,458 INFO [VelocityEngine] Loaded System Directive: org.apache.velocity.runtime.directive.Foreach
                09:04:37,489 INFO [VelocityEngine] Created: 20 parsers.
                09:04:37,505 INFO [VelocityEngine] Velocimacro : initialization starting.
                09:04:37,505 INFO [VelocityEngine] Velocimacro : adding VMs from VM library template : VM_global_library.vm
                09:04:37,552 INFO [VelocityEngine] Velocimacro : added new VM : #ifDefReplace( string substr replace ) : source = VM_global_library.vm
                09:04:37,552 INFO [VelocityEngine] Velocimacro : added new VM : #firstToken( string delim ) : source = VM_global_library.vm
                09:04:37,552 INFO [VelocityEngine] ResourceManager : found VM_global_library.vm with loader org.apache.velocity.runtime.resource.loader.FileResourceLoader
                09:04:37,552 INFO [VelocityEngine] Velocimacro : VM library template macro registration complete.
                09:04:37,552 INFO [VelocityEngine] Velocimacro : allowInline = true : VMs can be defined inline in templates
                09:04:37,552 INFO [VelocityEngine] Velocimacro : allowInlineToOverride = false : VMs defined inline may NOT replace previous VM definitions
                09:04:37,552 INFO [VelocityEngine] Velocimacro : allowInlineLocal = false : VMs defined inline will be global in scope if allowed.
                09:04:37,552 INFO [VelocityEngine] Velocimacro : messages on : VM system will output logging messages
                09:04:37,552 INFO [VelocityEngine] Velocimacro : autoload off : VM system will not automatically reload global library macros
                09:04:37,552 INFO [VelocityEngine] Velocimacro : initialization complete.
                09:04:37,552 INFO [VelocityEngine] Velocity successfully started.
                09:04:37,692 INFO [STDOUT] no object for null
                09:04:37,692 INFO [STDOUT] no object for null
                09:04:37,724 INFO [STDOUT] no object for null
                09:04:37,739 INFO [STDOUT] no object for {urn:jboss:bean-deployer}supplyType
                09:04:37,755 INFO [STDOUT] no object for {urn:jboss:bean-deployer}dependsType
                09:04:39,755 INFO [NativeServerConfig] JBoss Web Services - Native
                09:04:39,755 INFO [NativeServerConfig] jbossws-native-2.0.1.SP2 (build=200710210837)
                09:04:40,927 INFO [Embedded] Catalina naming disabled
                09:04:41,130 INFO [AprLifecycleListener] The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: C:\Program Files\Java\jdk1.6.0_10\bin;.;C:\WINDOWS\Sun\Java\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\Program Files\Sybase\Shared\ASA802\win32;C:\Program Files\Windows Resource Kits\Tools\;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Microsoft SQL Server\80\Tools\BINN;c:\iris;c:\iris\Work;C:\WINDOWS\Desktop;C:\WINDOWS\system32;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\Program Files\Microsoft SQL Server\90\DTS\Binn\;C:\Program Files\Microsoft SQL Server\90\Tools\binn\;C:\Program Files\Microsoft SQL Server\90\Tools\Binn\VSShell\Common7\IDE\;C:\Program Files\Microsoft Visual Studio 8\Common7\IDE\PrivateAssemblies\;C:\Program Files\Common Files\Microsoft Shared\Integration Manager;C:\Program Files\Sybase\Jaguar CTS 3.5\dll;C:\Program Files\Sybase\Jaguar CTS 3.5\client\dll;C:\Program Files\Sybase\Shared\Sun\JDK122\bin;C:\Program Files\Sybase\Shared\PowerBuilder;C:\misc\apache-maven-2.0.9\bin;C:\cygwin\bin;C:\Program Files\TortoiseSVN\bin
                09:04:41,208 INFO [Http11Protocol] Initializing Coyote HTTP/1.1 on http-127.0.0.1-18080
                09:04:41,208 INFO [AjpProtocol] Initializing Coyote AJP/1.3 on ajp-127.0.0.1-18009
                09:04:41,208 INFO [Catalina] Initialization processed in 272 ms
                09:04:41,208 INFO [StandardService] Starting service jboss.web
                09:04:41,208 INFO [StandardEngine] Starting Servlet Engine: JBossWeb/2.0.1.GA
                09:04:41,286 INFO [Catalina] Server startup in 73 ms
                09:04:41,396 INFO [TomcatDeployer] deploy, ctxPath=/, warUrl=.../deploy/jboss-web.deployer/ROOT.war/
                09:04:42,208 INFO [TomcatDeployer] deploy, ctxPath=/invoker, warUrl=.../deploy/http-invoker.sar/invoker.war/
                09:04:42,380 INFO [TomcatDeployer] deploy, ctxPath=/jbossws, warUrl=.../deploy/jbossws.sar/jbossws-context.war/
                09:04:42,521 INFO [TomcatDeployer] deploy, ctxPath=/jbossmq-httpil, warUrl=.../deploy/jms/jbossmq-httpil.sar/jbossmq-httpil.war/
                09:04:43,599 INFO [TomcatDeployer] deploy, ctxPath=/web-console, warUrl=.../deploy/management/console-mgr.sar/web-console.war/
                09:04:44,255 INFO [MailService] Mail Service bound to java:/Mail
                09:04:44,442 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-ha-local-jdbc.rar
                09:04:44,489 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-ha-xa-jdbc.rar
                09:04:44,521 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-local-jdbc.rar
                09:04:44,567 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-xa-jdbc.rar
                09:04:44,630 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jms/jms-ra.rar
                09:04:44,661 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/mail-ra.rar
                09:04:44,724 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/quartz-ra.rar
                09:04:44,724 INFO [QuartzResourceAdapter] start quartz!!!
                09:04:44,833 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: main
                09:04:44,896 INFO [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
                09:04:44,911 INFO [RAMJobStore] RAMJobStore initialized.
                09:04:44,911 INFO [StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
                09:04:44,911 INFO [StdSchedulerFactory] Quartz scheduler version: 1.5.2
                09:04:44,911 INFO [QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
                09:04:45,958 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
                09:04:46,286 INFO [A] Bound to JNDI name: queue/A
                09:04:46,286 INFO [B] Bound to JNDI name: queue/B
                09:04:46,286 INFO [C] Bound to JNDI name: queue/C
                09:04:46,302 INFO [D] Bound to JNDI name: queue/D
                09:04:46,302 INFO [ex] Bound to JNDI name: queue/ex
                09:04:46,333 INFO [testTopic] Bound to JNDI name: topic/testTopic
                09:04:46,333 INFO [securedTopic] Bound to JNDI name: topic/securedTopic
                09:04:46,333 INFO [testDurableTopic] Bound to JNDI name: topic/testDurableTopic
                09:04:46,349 INFO [testQueue] Bound to JNDI name: queue/testQueue
                09:04:46,443 INFO [UILServerILService] JBossMQ UIL service available at : /127.0.0.1:8093
                09:04:46,521 INFO [DLQ] Bound to JNDI name: queue/DLQ
                09:04:46,739 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
                09:04:46,911 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=jdbc/PolicyDs' to JNDI name 'java:jdbc/PolicyDs'
                09:04:46,927 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=jdbc/InternetDb' to JNDI name 'java:jdbc/InternetDb'
                09:04:47,052 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=jdbc/XAloggingDb' to JNDI name 'java:jdbc/XAloggingDb'
                09:04:47,114 INFO [TomcatDeployer] deploy, ctxPath=/jmx-console, warUrl=.../deploy/jmx-console.war/
                09:04:47,552 INFO [Http11Protocol] Starting Coyote HTTP/1.1 on http-127.0.0.1-18080
                09:04:47,614 INFO [AjpProtocol] Starting Coyote AJP/1.3 on ajp-127.0.0.1-18009
                09:04:47,630 INFO [Server] JBoss (MX MicroKernel) [4.2.2.GA (build: SVNTag=JBoss_4_2_2_GA date=200710221139)] Started in 18s:407ms
                [2008-12-18 09:04:47,708] Facet JavaEE: Module is being deployed, please wait...
                [2008-12-18 09:04:47,708] Facet JavaEE: Module is being deployed, please wait...
                Connected to server
                09:04:47,802 INFO [EARDeployer] Init J2EE application: file:/C:/devel/demo/checkHandler/checkHandlerApp/target/checkHandlerApp-1.0.ear
                09:04:47,818 INFO [EARDeployer] Init J2EE application: file:/C:/devel/demo/policy/policyServiceApp/target/policyService-1.2.ear
                09:04:50,974 WARN [MainDeployer] Found non-jar deployer for persistenceService-1.2.jar: MBeanProxyExt[jboss.ejb3:service=EJB3Deployer]
                09:04:51,802 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.mdb.MDB
                09:04:51,802 INFO [JmxKernelAbstraction] installing MBean: jboss.j2ee:ear=checkHandlerApp-1.0.ear,jar=checkHandlerBean-1.0.jar,name=CheckHandlerProcessorEJB,service=EJB3 with dependencies:
                09:04:52,005 INFO [EJBContainer] STARTED EJB: com.csatp.application.checkHandler.CheckHandlerProcessor ejbName: CheckHandlerProcessorEJB
                09:04:52,068 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.mdb.MDB
                09:04:52,068 INFO [JmxKernelAbstraction] installing MBean: jboss.j2ee:ear=checkHandlerApp-1.0.ear,jar=checkHandlerBean-1.0.jar,name=IrisLetterProcessorEJB,service=EJB3 with dependencies:
                09:04:52,068 INFO [EJBContainer] STARTED EJB: com.csatp.application.letterscheduler.IrisLetterProcessor ejbName: IrisLetterProcessorEJB
                09:04:52,083 INFO [EJB3Deployer] Deployed: file:/C:/jboss-4.2.2.GA/server/default/tmp/deploy/tmp41805checkHandlerApp-1.0.ear-contents/checkHandlerBean-1.0.jar
                09:04:52,271 INFO [EARDeployer] Started J2EE application: file:/C:/devel/demo/checkHandler/checkHandlerApp/target/checkHandlerApp-1.0.ear
                [2008-12-18 09:04:52,271] Facet JavaEE: Module is deployed successfully
                09:04:52,271 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.entity.PersistenceUnitDeployment
                09:04:52,286 INFO [JmxKernelAbstraction] installing MBean: persistence.units:ear=policyService-1.2.ear,unitName=PolicyPersistenceUnit with dependencies:
                09:04:52,286 INFO [JmxKernelAbstraction] jboss.jca:name=jdbc/PolicyDs,service=DataSourceBinding
                09:04:52,286 INFO [PersistenceUnitDeployment] Starting persistence unit persistence.units:ear=policyService-1.2.ear,unitName=PolicyPersistenceUnit
                09:04:52,365 INFO [Version] Hibernate EntityManager 3.2.1.GA
                09:04:52,411 INFO [Version] Hibernate Annotations 3.2.1.GA
                09:04:52,427 INFO [Environment] Hibernate 3.2.4.sp1
                09:04:52,443 INFO [Environment] hibernate.properties not found
                09:04:52,443 INFO [Environment] Bytecode provider name : javassist
                09:04:52,443 INFO [Environment] using JDK 1.4 java.sql.Timestamp handling
                09:04:52,661 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.AcontrolEntity
                09:04:52,677 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.BacksvcreqEntity
                09:04:52,693 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.CertmainEntity
                09:04:52,693 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.CertpayEntity
                09:04:52,693 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.EventEntity
                09:04:52,693 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.RecordlockEntity
                09:04:52,693 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.SaopenacctEntity
                09:04:52,771 INFO [Configuration] Reading mappings from resource : META-INF/orm.xml
                09:04:52,786 INFO [Ejb3Configuration] [PersistenceUnit: PolicyPersistenceUnit] no META-INF/orm.xml found
                09:04:52,880 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.CertmainEntity
                09:04:52,958 INFO [EntityBinder] Bind entity com.csatp.service.CertmainEntity on table CERTMAIN
                09:04:53,115 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.CertpayEntity
                09:04:53,115 INFO [EntityBinder] Bind entity com.csatp.service.CertpayEntity on table CERTPAY
                09:04:53,130 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.SaopenacctEntity
                09:04:53,130 INFO [EntityBinder] Bind entity com.csatp.service.SaopenacctEntity on table SAOPENACCT
                09:04:53,130 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.AcontrolEntity
                09:04:53,130 INFO [EntityBinder] Bind entity com.csatp.service.AcontrolEntity on table ACONTROL
                09:04:53,146 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.RecordlockEntity
                09:04:53,146 INFO [EntityBinder] Bind entity com.csatp.service.RecordlockEntity on table RECORDLOCK
                09:04:53,161 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.BacksvcreqEntity
                09:04:53,161 INFO [EntityBinder] Bind entity com.csatp.service.BacksvcreqEntity on table BACKSVCREQ
                09:04:53,161 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.EventEntity
                09:04:53,161 INFO [EntityBinder] Bind entity com.csatp.service.EventEntity on table event
                09:04:53,505 INFO [ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
                09:04:53,505 INFO [InjectedDataSourceConnectionProvider] Using provided datasource
                09:04:53,693 INFO [SettingsFactory] RDBMS: Microsoft SQL Server, version: 08.00.2050
                09:04:53,693 INFO [SettingsFactory] JDBC driver: jTDS Type 4 JDBC Driver for MS SQL Server and Sybase, version: 1.2
                09:04:53,724 INFO [Dialect] Using dialect: org.hibernate.dialect.SQLServerDialect
                09:04:53,740 INFO [TransactionFactoryFactory] Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
                09:04:53,740 INFO [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
                09:04:53,740 INFO [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
                09:04:53,755 INFO [SettingsFactory] Automatic flush during beforeCompletion(): disabled
                09:04:53,755 INFO [SettingsFactory] Automatic session close at end of transaction: disabled
                09:04:53,755 INFO [SettingsFactory] Scrollable result sets: enabled
                09:04:53,755 INFO [SettingsFactory] JDBC3 getGeneratedKeys(): enabled
                09:04:53,755 INFO [SettingsFactory] Connection release mode: auto
                09:04:53,755 INFO [SettingsFactory] Default batch fetch size: 1
                09:04:53,755 INFO [SettingsFactory] Generate SQL with comments: disabled
                09:04:53,755 INFO [SettingsFactory] Order SQL updates by primary key: disabled
                09:04:53,755 INFO [SettingsFactory] Order SQL inserts for batching: disabled
                09:04:53,755 INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
                09:04:53,771 INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
                09:04:53,771 INFO [SettingsFactory] Query language substitutions: {}
                09:04:53,771 INFO [SettingsFactory] JPA-QL strict compliance: enabled
                09:04:53,771 INFO [SettingsFactory] Second-level cache: enabled
                09:04:53,771 INFO [SettingsFactory] Query cache: disabled
                09:04:53,771 INFO [SettingsFactory] Cache provider: org.hibernate.cache.HashtableCacheProvider
                09:04:53,771 INFO [SettingsFactory] Optimize cache for minimal puts: disabled
                09:04:53,771 INFO [SettingsFactory] Cache region prefix: policyService-1_2_ear,PolicyPersistenceUnit
                09:04:53,771 INFO [SettingsFactory] Structured second-level cache entries: disabled
                09:04:53,786 INFO [SettingsFactory] Statistics: disabled
                09:04:53,786 INFO [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
                09:04:53,786 INFO [SettingsFactory] Default entity-mode: pojo
                09:04:53,786 INFO [SettingsFactory] Named query checking : enabled
                09:04:53,865 INFO [SessionFactoryImpl] building session factory
                09:04:54,505 INFO [SessionFactoryObjectFactory] Factory name: persistence.units:ear=policyService-1.2.ear,unitName=PolicyPersistenceUnit
                09:04:54,505 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
                09:04:54,521 INFO [SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.units:ear=policyService-1.2.ear,unitName=PolicyPersistenceUnit
                09:04:54,521 WARN [SessionFactoryObjectFactory] InitialContext did not implement EventContext
                09:04:54,521 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
                09:04:54,521 WARN [SessionFactoryImpl] JTASessionContext being used with JDBCTransactionFactory; auto-flush will not operate correctly with getCurrentSession()
                09:04:54,536 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.entity.PersistenceUnitDeployment
                09:04:54,536 INFO [JmxKernelAbstraction] installing MBean: persistence.units:ear=policyService-1.2.ear,unitName=LoggingPersistenceUnit with dependencies:
                09:04:54,536 INFO [JmxKernelAbstraction] jboss.jca:name=jdbc/XAloggingDb,service=DataSourceBinding
                09:04:54,536 INFO [PersistenceUnitDeployment] Starting persistence unit persistence.units:ear=policyService-1.2.ear,unitName=LoggingPersistenceUnit
                09:04:54,536 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.AcontrolEntity
                09:04:54,552 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.BacksvcreqEntity
                09:04:54,568 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.CertmainEntity
                09:04:54,568 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.CertpayEntity
                09:04:54,568 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.EventEntity
                09:04:54,568 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.RecordlockEntity
                09:04:54,568 INFO [Ejb3Configuration] found EJB3 Entity bean: com.csatp.service.SaopenacctEntity
                09:04:54,583 INFO [Configuration] Reading mappings from resource : META-INF/orm.xml
                09:04:54,583 INFO [Ejb3Configuration] [PersistenceUnit: LoggingPersistenceUnit] no META-INF/orm.xml found
                09:04:54,583 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.EventEntity
                09:04:54,583 INFO [EntityBinder] Bind entity com.csatp.service.EventEntity on table event
                09:04:54,583 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.AcontrolEntity
                09:04:54,583 INFO [EntityBinder] Bind entity com.csatp.service.AcontrolEntity on table ACONTROL
                09:04:54,599 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.BacksvcreqEntity
                09:04:54,599 INFO [EntityBinder] Bind entity com.csatp.service.BacksvcreqEntity on table BACKSVCREQ
                09:04:54,615 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.CertmainEntity
                09:04:54,615 INFO [EntityBinder] Bind entity com.csatp.service.CertmainEntity on table CERTMAIN
                09:04:54,661 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.CertpayEntity
                09:04:54,661 INFO [EntityBinder] Bind entity com.csatp.service.CertpayEntity on table CERTPAY
                09:04:54,661 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.RecordlockEntity
                09:04:54,661 INFO [EntityBinder] Bind entity com.csatp.service.RecordlockEntity on table RECORDLOCK
                09:04:54,677 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.SaopenacctEntity
                09:04:54,677 INFO [EntityBinder] Bind entity com.csatp.service.SaopenacctEntity on table SAOPENACCT
                09:04:54,740 INFO [ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
                09:04:54,740 INFO [InjectedDataSourceConnectionProvider] Using provided datasource
                09:04:55,068 INFO [SettingsFactory] RDBMS: MySQL, version: 5.0.51b-community-nt
                09:04:55,068 INFO [SettingsFactory] JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.0.8 ( Revision: ${svn.Revision} )
                09:04:55,068 INFO [Dialect] Using dialect: org.hibernate.dialect.MySQLDialect
                09:04:55,068 INFO [TransactionFactoryFactory] Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
                09:04:55,068 INFO [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
                09:04:55,068 INFO [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
                09:04:55,068 INFO [SettingsFactory] Automatic flush during beforeCompletion(): disabled
                09:04:55,068 INFO [SettingsFactory] Automatic session close at end of transaction: disabled
                09:04:55,068 INFO [SettingsFactory] JDBC batch size: 15
                09:04:55,068 INFO [SettingsFactory] JDBC batch updates for versioned data: disabled
                09:04:55,068 INFO [SettingsFactory] Scrollable result sets: enabled
                09:04:55,068 INFO [SettingsFactory] JDBC3 getGeneratedKeys(): enabled
                09:04:55,068 INFO [SettingsFactory] Connection release mode: auto
                09:04:55,068 INFO [SettingsFactory] Maximum outer join fetch depth: 2
                09:04:55,068 INFO [SettingsFactory] Default batch fetch size: 1
                09:04:55,068 INFO [SettingsFactory] Generate SQL with comments: disabled
                09:04:55,068 INFO [SettingsFactory] Order SQL updates by primary key: disabled
                09:04:55,068 INFO [SettingsFactory] Order SQL inserts for batching: disabled
                09:04:55,068 INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
                09:04:55,068 INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
                09:04:55,068 INFO [SettingsFactory] Query language substitutions: {}
                09:04:55,068 INFO [SettingsFactory] JPA-QL strict compliance: enabled
                09:04:55,068 INFO [SettingsFactory] Second-level cache: enabled
                09:04:55,068 INFO [SettingsFactory] Query cache: disabled
                09:04:55,068 INFO [SettingsFactory] Cache provider: org.hibernate.cache.HashtableCacheProvider
                09:04:55,068 INFO [SettingsFactory] Optimize cache for minimal puts: disabled
                09:04:55,068 INFO [SettingsFactory] Cache region prefix: policyService-1_2_ear,LoggingPersistenceUnit
                09:04:55,083 INFO [SettingsFactory] Structured second-level cache entries: disabled
                09:04:55,083 INFO [SettingsFactory] Statistics: disabled
                09:04:55,083 INFO [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
                09:04:55,083 INFO [SettingsFactory] Default entity-mode: pojo
                09:04:55,083 INFO [SettingsFactory] Named query checking : enabled
                09:04:55,083 INFO [SessionFactoryImpl] building session factory
                09:04:55,177 INFO [SessionFactoryObjectFactory] Factory name: persistence.units:ear=policyService-1.2.ear,unitName=LoggingPersistenceUnit
                09:04:55,177 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
                09:04:55,177 INFO [SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.units:ear=policyService-1.2.ear,unitName=LoggingPersistenceUnit
                09:04:55,177 WARN [SessionFactoryObjectFactory] InitialContext did not implement EventContext
                09:04:55,177 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
                09:04:55,177 WARN [SessionFactoryImpl] JTASessionContext being used with JDBCTransactionFactory; auto-flush will not operate correctly with getCurrentSession()
                09:04:55,177 INFO [EJB3Deployer] Deployed: file:/C:/jboss-4.2.2.GA/server/default/tmp/deploy/tmp41804policyService-1.2.ear-contents/persistenceService-1.2.jar
                09:04:55,177 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.entity.PersistenceUnitDeployment
                09:04:55,177 INFO [JmxKernelAbstraction] installing MBean: persistence.units:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,unitName=PolicyPersistenceUnit with dependencies:
                09:04:55,177 INFO [JmxKernelAbstraction] jboss.jca:name=jdbc/PolicyDs,service=DataSourceBinding
                09:04:55,177 INFO [PersistenceUnitDeployment] Starting persistence unit persistence.units:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,unitName=PolicyPersistenceUnit
                09:04:55,443 INFO [Configuration] Reading mappings from resource : META-INF/orm.xml
                09:04:55,443 INFO [Ejb3Configuration] [PersistenceUnit: PolicyPersistenceUnit] no META-INF/orm.xml found
                09:04:55,443 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.CertmainEntity
                09:04:55,443 INFO [EntityBinder] Bind entity com.csatp.service.CertmainEntity on table CERTMAIN
                09:04:55,490 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.CertpayEntity
                09:04:55,490 INFO [EntityBinder] Bind entity com.csatp.service.CertpayEntity on table CERTPAY
                09:04:55,505 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.SaopenacctEntity
                09:04:55,505 INFO [EntityBinder] Bind entity com.csatp.service.SaopenacctEntity on table SAOPENACCT
                09:04:55,505 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.AcontrolEntity
                09:04:55,505 INFO [EntityBinder] Bind entity com.csatp.service.AcontrolEntity on table ACONTROL
                09:04:55,521 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.RecordlockEntity
                09:04:55,521 INFO [EntityBinder] Bind entity com.csatp.service.RecordlockEntity on table RECORDLOCK
                09:04:55,521 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.BacksvcreqEntity
                09:04:55,521 INFO [EntityBinder] Bind entity com.csatp.service.BacksvcreqEntity on table BACKSVCREQ
                09:04:55,615 INFO [ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
                09:04:55,615 INFO [InjectedDataSourceConnectionProvider] Using provided datasource
                09:04:55,615 INFO [SettingsFactory] RDBMS: Microsoft SQL Server, version: 08.00.2050
                09:04:55,615 INFO [SettingsFactory] JDBC driver: jTDS Type 4 JDBC Driver for MS SQL Server and Sybase, version: 1.2
                09:04:55,615 INFO [Dialect] Using dialect: org.hibernate.dialect.SQLServerDialect
                09:04:55,615 INFO [TransactionFactoryFactory] Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory
                09:04:55,615 INFO [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
                09:04:55,615 INFO [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
                09:04:55,615 INFO [SettingsFactory] Automatic flush during beforeCompletion(): disabled
                09:04:55,615 INFO [SettingsFactory] Automatic session close at end of transaction: disabled
                09:04:55,615 INFO [SettingsFactory] Scrollable result sets: enabled
                09:04:55,615 INFO [SettingsFactory] JDBC3 getGeneratedKeys(): enabled
                09:04:55,615 INFO [SettingsFactory] Connection release mode: auto
                09:04:55,615 INFO [SettingsFactory] Default batch fetch size: 1
                09:04:55,615 INFO [SettingsFactory] Generate SQL with comments: disabled
                09:04:55,615 INFO [SettingsFactory] Order SQL updates by primary key: disabled
                09:04:55,615 INFO [SettingsFactory] Order SQL inserts for batching: disabled
                09:04:55,615 INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
                09:04:55,615 INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
                09:04:55,615 INFO [SettingsFactory] Query language substitutions: {}
                09:04:55,615 INFO [SettingsFactory] JPA-QL strict compliance: enabled
                09:04:55,615 INFO [SettingsFactory] Second-level cache: enabled
                09:04:55,615 INFO [SettingsFactory] Query cache: disabled
                09:04:55,615 INFO [SettingsFactory] Cache provider: org.hibernate.cache.HashtableCacheProvider
                09:04:55,615 INFO [SettingsFactory] Optimize cache for minimal puts: disabled
                09:04:55,615 INFO [SettingsFactory] Cache region prefix: policyService-1_2_ear,policyServiceBean-1_2_jar,PolicyPersistenceUnit
                09:04:55,615 INFO [SettingsFactory] Structured second-level cache entries: disabled
                09:04:55,615 INFO [SettingsFactory] Echoing all SQL to stdout
                09:04:55,615 INFO [SettingsFactory] Statistics: disabled
                09:04:55,615 INFO [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
                09:04:55,615 INFO [SettingsFactory] Default entity-mode: pojo
                09:04:55,615 INFO [SettingsFactory] Named query checking : enabled
                09:04:55,630 INFO [SessionFactoryImpl] building session factory
                09:04:55,708 INFO [SessionFactoryObjectFactory] Factory name: persistence.units:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,unitName=PolicyPersistenceUnit
                09:04:55,708 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
                09:04:55,708 INFO [SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.units:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,unitName=PolicyPersistenceUnit
                09:04:55,708 WARN [SessionFactoryObjectFactory] InitialContext did not implement EventContext
                09:04:55,708 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
                09:04:55,708 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.entity.PersistenceUnitDeployment
                09:04:55,708 INFO [JmxKernelAbstraction] installing MBean: persistence.units:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,unitName=LoggingPersistenceUnit with dependencies:
                09:04:55,708 INFO [JmxKernelAbstraction] jboss.jca:name=jdbc/XAloggingDb,service=DataSourceBinding
                09:04:55,708 INFO [PersistenceUnitDeployment] Starting persistence unit persistence.units:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,unitName=LoggingPersistenceUnit
                09:04:55,927 INFO [Configuration] Reading mappings from resource : META-INF/orm.xml
                09:04:55,927 INFO [Ejb3Configuration] [PersistenceUnit: LoggingPersistenceUnit] no META-INF/orm.xml found
                09:04:55,927 INFO [AnnotationBinder] Binding entity from annotated class: com.csatp.service.EventEntity
                09:04:55,927 INFO [EntityBinder] Bind entity com.csatp.service.EventEntity on table event
                09:04:55,943 INFO [ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
                09:04:55,943 INFO [InjectedDataSourceConnectionProvider] Using provided datasource
                09:04:55,943 INFO [SettingsFactory] RDBMS: MySQL, version: 5.0.51b-community-nt
                09:04:55,943 INFO [SettingsFactory] JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.0.8 ( Revision: ${svn.Revision} )
                09:04:55,943 INFO [Dialect] Using dialect: org.hibernate.dialect.MySQLDialect
                09:04:55,943 INFO [TransactionFactoryFactory] Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory
                09:04:55,943 INFO [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
                09:04:55,943 INFO [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
                09:04:55,943 INFO [SettingsFactory] Automatic flush during beforeCompletion(): disabled
                09:04:55,943 INFO [SettingsFactory] Automatic session close at end of transaction: disabled
                09:04:55,943 INFO [SettingsFactory] JDBC batch size: 15
                09:04:55,943 INFO [SettingsFactory] JDBC batch updates for versioned data: disabled
                09:04:55,943 INFO [SettingsFactory] Scrollable result sets: enabled
                09:04:55,943 INFO [SettingsFactory] JDBC3 getGeneratedKeys(): enabled
                09:04:55,943 INFO [SettingsFactory] Connection release mode: auto
                09:04:55,943 INFO [SettingsFactory] Maximum outer join fetch depth: 2
                09:04:55,943 INFO [SettingsFactory] Default batch fetch size: 1
                09:04:55,943 INFO [SettingsFactory] Generate SQL with comments: disabled
                09:04:55,943 INFO [SettingsFactory] Order SQL updates by primary key: disabled
                09:04:55,943 INFO [SettingsFactory] Order SQL inserts for batching: disabled
                09:04:55,958 INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
                09:04:55,958 INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
                09:04:55,958 INFO [SettingsFactory] Query language substitutions: {}
                09:04:55,958 INFO [SettingsFactory] JPA-QL strict compliance: enabled
                09:04:55,958 INFO [SettingsFactory] Second-level cache: enabled
                09:04:55,958 INFO [SettingsFactory] Query cache: disabled
                09:04:55,958 INFO [SettingsFactory] Cache provider: org.hibernate.cache.HashtableCacheProvider
                09:04:55,958 INFO [SettingsFactory] Optimize cache for minimal puts: disabled
                09:04:55,958 INFO [SettingsFactory] Cache region prefix: policyService-1_2_ear,policyServiceBean-1_2_jar,LoggingPersistenceUnit
                09:04:55,958 INFO [SettingsFactory] Structured second-level cache entries: disabled
                09:04:55,958 INFO [SettingsFactory] Statistics: disabled
                09:04:55,958 INFO [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
                09:04:55,958 INFO [SettingsFactory] Default entity-mode: pojo
                09:04:55,958 INFO [SettingsFactory] Named query checking : enabled
                09:04:55,958 INFO [SessionFactoryImpl] building session factory
                09:04:55,958 INFO [SessionFactoryObjectFactory] Factory name: persistence.units:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,unitName=LoggingPersistenceUnit
                09:04:55,958 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
                09:04:55,958 INFO [SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.units:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,unitName=LoggingPersistenceUnit
                09:04:55,958 WARN [SessionFactoryObjectFactory] InitialContext did not implement EventContext
                09:04:55,958 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
                09:04:56,036 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.mdb.MDB
                09:04:56,036 INFO [JmxKernelAbstraction] installing MBean: jboss.j2ee:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,name=CheckHandlerProcessorEJB,service=EJB3 with dependencies:
                09:04:56,052 INFO [EJBContainer] STARTED EJB: com.csatp.service.scheduler.checkHandlerBean ejbName: CheckHandlerProcessorEJB
                09:04:56,052 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.stateless.StatelessContainer
                09:04:56,068 INFO [JmxKernelAbstraction] installing MBean: jboss.j2ee:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,name=FundingServiceBean,service=EJB3 with dependencies:
                09:04:56,068 INFO [JmxKernelAbstraction] persistence.units:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,unitName=PolicyPersistenceUnit
                09:04:56,146 INFO [EJBContainer] STARTED EJB: com.csatp.service.policy.FundingServiceBean ejbName: FundingServiceBean
                09:04:56,208 INFO [JmxKernelAbstraction] creating wrapper delegate for: org.jboss.ejb3.stateless.StatelessContainer
                09:04:56,208 INFO [JmxKernelAbstraction] installing MBean: jboss.j2ee:ear=policyService-1.2.ear,jar=policyServiceBean-1.2.jar,name=PolicyServiceBean,service=EJB3 with dependencies:
                09:04:56,224 INFO [EJBContainer] STARTED EJB: com.csatp.service.policy.PolicyServiceBean ejbName: PolicyServiceBean
                09:04:56,458 INFO [EJB3Deployer] Deployed: file:/C:/jboss-4.2.2.GA/server/default/tmp/deploy/tmp41804policyService-1.2.ear-contents/policyServiceBean-1.2.jar
                09:04:56,458 INFO [TomcatDeployer] deploy, ctxPath=/policy, warUrl=.../tmp/deploy/tmp41804policyService-1.2.ear-contents/policyServiceWeb-1.2-exp.war/
                09:04:56,615 INFO [EARDeployer] Started J2EE application: file:/C:/devel/demo/policy/policyServiceApp/target/policyService-1.2.ear
                [2008-12-18 09:04:56,615] Facet JavaEE: Module is deployed successfully
                09:05:00,099 ERROR [JobRunShell] Job default.job.2.1229619892083 threw an unhandled Exception:
                java.lang.NullPointerException
                 at org.jboss.ejb3.JndiUtil.lookup(JndiUtil.java:49)
                 at org.jboss.injection.JndiPropertyInjector.lookup(JndiPropertyInjector.java:87)
                 at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:112)
                 at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:107)
                 at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:65)
                 at org.jboss.ejb3.AbstractPool.create(AbstractPool.java:111)
                 at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:141)
                 at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
                 at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
                 at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
                 at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
                 at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
                 at $Proxy71.execute(Unknown Source)
                 at org.jboss.resource.adapter.quartz.inflow.QuartzJob.execute(QuartzJob.java:57)
                 at org.quartz.core.JobRunShell.run(JobRunShell.java:203)
                 at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:520)
                09:05:00,099 ERROR [ErrorLogger] Job (default.job.2.1229619892083 threw an exception.
                org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: java.lang.NullPointerException]
                 at org.quartz.core.JobRunShell.run(JobRunShell.java:214)
                 at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:520)
                * Nested Exception (Underlying Cause) ---------------
                java.lang.NullPointerException
                 at org.jboss.ejb3.JndiUtil.lookup(JndiUtil.java:49)
                 at org.jboss.injection.JndiPropertyInjector.lookup(JndiPropertyInjector.java:87)
                 at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:112)
                 at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:107)
                 at org.jboss.injection.JndiPropertyInjector.inject(JndiPropertyInjector.java:65)
                 at org.jboss.ejb3.AbstractPool.create(AbstractPool.java:111)
                 at org.jboss.ejb3.StrictMaxPool.get(StrictMaxPool.java:141)
                 at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:54)
                 at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
                 at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
                 at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
                 at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
                 at $Proxy71.execute(Unknown Source)
                 at org.jboss.resource.adapter.quartz.inflow.QuartzJob.execute(QuartzJob.java:57)
                 at org.quartz.core.JobRunShell.run(JobRunShell.java:203)
                 at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:520)
                


                • 5. Re: JNDI lookup of EJB3 SLSB from Quartz
                  John Harby Newbie

                  I also occasionally see this come up:

                  
                  14:33:07,135 ERROR [STDERR] java.lang.ClassCastException: $Proxy104 cannot be cast to com.csatp.service.policy.FundingService
                  14:33:07,135 ERROR [STDERR] at com.csatp.service.scheduler.checkHandlerBean.execute(checkHandlerBean.java:58)
                  14:33:07,151 ERROR [STDERR] at sun.reflect.GeneratedMethodAccessor281.invoke(Unknown Source)
                  14:33:07,151 ERROR [STDERR] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
                  14:33:07,151 ERROR [STDERR] at java.lang.reflect.Method.invoke(Method.java:597)
                  14:33:07,151 ERROR [STDERR] at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)
                  14:33:07,151 ERROR [STDERR] at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)
                  14:33:07,151 ERROR [STDERR] at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)
                  14:33:07,151 ERROR [STDERR] at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
                  14:33:07,151 ERROR [STDERR] at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)
                  14:33:07,151 ERROR [STDERR] at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
                  14:33:07,151 ERROR [STDERR] at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)
                  14:33:07,151 ERROR [STDERR] at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
                  14:33:07,151 ERROR [STDERR] at org.jboss.aspects.tx.TxPolicy.invokeInCallerTx(TxPolicy.java:126)
                  14:33:07,151 ERROR [STDERR] at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:195)
                  14:33:07,151 ERROR [STDERR] at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
                  14:33:07,151 ERROR [STDERR] at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:62)
                  14:33:07,151 ERROR [STDERR] at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
                  14:33:07,151 ERROR [STDERR] at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:249)
                  14:33:07,151 ERROR [STDERR] at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
                  14:33:07,151 ERROR [STDERR] at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
                  14:33:07,151 ERROR [STDERR] at $Proxy231.execute(Unknown Source)
                  14:33:07,151 ERROR [STDERR] at org.jboss.resource.adapter.quartz.inflow.QuartzJob.execute(QuartzJob.java:57)
                  14:33:07,151 ERROR [STDERR] at org.quartz.core.JobRunShell.run(JobRunShell.java:203)
                  14:33:07,151 ERROR [STDERR] at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:520)
                  
                  


                  • 6. Re: JNDI lookup of EJB3 SLSB from Quartz
                    John Harby Newbie

                    I figured this one out by downloading the JBoss source and stepping through everything. It turned out my Intellij build was picking up some old code that was trying to lookup an invalid JNDI name. You were right, I think that bug was coming into play. Thanks for your help.