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

JNDI lookup of EJB3 SLSB from Quartz

jharby1 Dec 16, 2008 4:30 PM

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 Dec 17, 2008 1:03 AM (in response to jharby1)

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.
Actions

2. Re: JNDI lookup of EJB3 SLSB from Quartz

jharby1 Dec 17, 2008 3:47 PM (in response to jharby1)

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 Dec 18, 2008 12:52 AM (in response to jharby1)

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.
Actions

4. Re: JNDI lookup of EJB3 SLSB from Quartz

jharby1 Dec 18, 2008 12:10 PM (in response to jharby1)

"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

jharby1 Dec 18, 2008 5:34 PM (in response to jharby1)

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

jharby1 Jan 20, 2009 3:22 PM (in response to jharby1)

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.
Actions

Go to original post