Menu
▾
▴
Re: [Opaltoolkit-users] Problem reloading opal
|
From: Sriram K. <sr...@sd...> - 2009-07-07 15:11:54
|
Hi Anthony,
I looks like you may have some old Opal classes from a previous
version lying around - basically because it can't find the
JobManagerType, which should be in the latest Opal jar.
There are two solutions to this problem. If you run "ant uninstall-
all", it will go ahead and clean up all the old Opal jars from inside
Tomcat. However, if you do this, all your deployed Opal services will
be gone. You will have to install Opal again, and manually deploy them
one by one.
The second option is you shut down tomcat, and run "ant upgrade". This
should replace the old Opal jar with the new one, along with other
prerequisite jars. I would try this option first, restart Tomcat and
see if everything works. If it doesn't, you may have to try the brute
force approach, which is option one.
Thanks, and let us know if that works.
Sriram
On Jul 7, 2009, at 2:34 AM, Anthony Bretaudeau wrote:
> Hi!
> Here's a more detailed log (debug level). Hope it will help you.
> Tell me if you need more info.
> Thanks
> Anthony
>
> Luca Clementi a écrit :
>> Anthony Bretaudeau wrote:
>>> Hi,
>>> We're using edu.sdsc.nbcr.opal.manager.DRMAAJobManager (slightly
>>> modified to work with DRMAA.jar 6.1, but the changes are minor).
>>> Issuing ant clean and ant install didn't solved the problem.
>>> Here's a full log of a tomcat session with the following actions:
>>> started tomcat, launched a job (which worked), stopped opal2,
>>> wait, restarted opal2, launched a job
>>>
>>
>> Dear Anthony,
>>
>>> Exception in thread "HSQLDB Timer @609a18a0"
>>> java.lang.NullPointerException <----- this is when I "Stop"
>>> opal2
>>> at org.hsqldb.lib.HsqlTimer.nextTask(Unknown Source)
>>> at org.hsqldb.lib.HsqlTimer$TaskRunner.run(Unknown Source)
>>> at java.lang.Thread.run(Thread.java:595)
>>
>> We know this error, and we have seen this in every installation we
>> have here, every time we shutdown tomcat.
>>
>> Since this is a very generic error and it doesn't have any impact
>> on our DBs, we haven't fixed it.
>>
>>> "Start" opal2 after a moment (no log) and I launch a job (no log
>>> here but a SOAP fault received by clients)
>>
>> This is very weird, if opal2 starts properly it must print some
>> log, on my installation I could get some ClassNotFound errors when
>> trying to restart a stopped application from the manager interface.
>>
>> Right now Im out of town when I will be back I'll take a look at
>> this problem.
>>
>>
>> Sincerely,
>> Luca
>>
>>
>>
>>
>>
>>
>>
>> 2009-07-03 06:57:34,043 ERROR
>> org
>> .apache
>> .axis
>> .configuration
>> .EngineConfigurationFactoryServlet
>> .getServerEngineConfig(EngineConfigurationFactoryServlet.java:162)
>> - Unable to find config file. Creating new servlet engine config
>> file: /WEB-INF/server-config.wsdd
>> 2009-07-03 06:57:34,093 ERROR
>> org
>> .apache
>> .axis
>> .deployment.wsdd.WSDDService.deployTypeMapping(WSDDService.java:
>> 560) - Unable to deploy typemapping: {http://nbcr.sdsc.edu/opal/
>> types}JobManagerType
>> java.lang.ClassNotFoundException: edu.sdsc.nbcr.opal.JobManagerType
>> at
>> org
>> .apache
>> .catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:
>> 1359)
>> at
>> org
>> .apache
>> .catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:
>> 1205)
>> at org.apache.axis.utils.ClassUtils$2.run(ClassUtils.java:186)
>> at java.security.AccessController.doPrivileged(Native Method)
>> at org.apache.axis.utils.ClassUtils.loadClass(ClassUtils.java:160)
>> at org.apache.axis.utils.ClassUtils.forName(ClassUtils.java:100)
>> at
>> org
>> .apache
>> .axis
>> .deployment
>> .wsdd.WSDDTypeMapping.getLanguageSpecificType(WSDDTypeMapping.java:
>> 183)
>> at
>> org
>> .apache
>> .axis
>> .deployment.wsdd.WSDDService.deployTypeMapping(WSDDService.java:542)
>> at
>> org
>> .apache.axis.deployment.wsdd.WSDDService.initTMR(WSDDService.java:
>> 253)
>> at
>> org.apache.axis.deployment.wsdd.WSDDService.<init>(WSDDService.java:
>> 233)
>> at
>> org
>> .apache
>> .axis.deployment.wsdd.WSDDDeployment.<init>(WSDDDeployment.java:192)
>> at
>> org
>> .apache
>> .axis.deployment.wsdd.WSDDDocument.setDocument(WSDDDocument.java:139)
>> at
>> org
>> .apache.axis.deployment.wsdd.WSDDDocument.<init>(WSDDDocument.java:
>> 65)
>> at
>> org
>> .apache
>> .axis.configuration.FileProvider.configureEngine(FileProvider.java:
>> 179)
>> at org.apache.axis.AxisEngine.init(AxisEngine.java:172)
>> at org.apache.axis.AxisEngine.<init>(AxisEngine.java:156)
>> at org.apache.axis.server.AxisServer.<init>(AxisServer.java:88)
>> at
>> org
>> .apache
>> .axis
>> .server
>> .DefaultAxisServerFactory
>> .createServer(DefaultAxisServerFactory.java:109)
>> at
>> org
>> .apache
>> .axis
>> .server
>> .DefaultAxisServerFactory.getServer(DefaultAxisServerFactory.java:73)
>> at org.apache.axis.server.AxisServer.getServer(AxisServer.java:73)
>> at
>> org
>> .apache
>> .axis.transport.http.AxisServletBase.getEngine(AxisServletBase.java:
>> 185)
>> at
>> org
>> .apache
>> .axis.transport.http.AxisServletBase.getOption(AxisServletBase.java:
>> 396)
>> at
>> org
>> .apache
>> .axis.transport.http.AxisServletBase.init(AxisServletBase.java:112)
>> at
>> org.apache.axis.transport.http.AxisServlet.init(AxisServlet.java:156)
>> at javax.servlet.GenericServlet.init(GenericServlet.java:212)
>> at
>> org
>> .apache
>> .catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1139)
>> at
>> org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:
>> 966)
>> at
>> org
>> .apache
>> .catalina.core.StandardContext.loadOnStartup(StandardContext.java:
>> 3956)
>> at
>> org.apache.catalina.core.StandardContext.start(StandardContext.java:
>> 4230)
>> at
>> org
>> .apache.catalina.manager.ManagerServlet.start(ManagerServlet.java:
>> 1176)
>> at
>> org
>> .apache
>> .catalina.manager.HTMLManagerServlet.start(HTMLManagerServlet.java:
>> 542)
>> at
>> org
>> .apache
>> .catalina.manager.HTMLManagerServlet.doGet(HTMLManagerServlet.java:
>> 105)
>> at javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
>> at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
>> at
>> org
>> .apache
>> .catalina
>> .core
>> .ApplicationFilterChain
>> .internalDoFilter(ApplicationFilterChain.java:269)
>> at
>> org
>> .apache
>> .catalina
>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>> 188)
>> at
>> org
>> .apache
>> .catalina
>> .core.StandardWrapperValve.invoke(StandardWrapperValve.java:210)
>> at
>> org
>> .apache
>> .catalina
>> .core.StandardContextValve.invoke(StandardContextValve.java:174)
>> at
>> org
>> .apache
>> .catalina
>> .authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:525)
>> at
>> org
>> .apache
>> .catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
>> at
>> org
>> .apache
>> .catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
>> at
>> org
>> .apache
>> .catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
>> 108)
>> at
>> org
>> .apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
>> 151)
>> at
>> org
>> .apache.coyote.http11.Http11Processor.process(Http11Processor.java:
>> 870)
>> at org.apache.coyote.http11.Http11BaseProtocol
>> $Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:
>> 665)
>> at
>> org
>> .apache
>> .tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:
>> 528)
>> at
>> org
>> .apache
>> .tomcat
>> .util
>> .net
>> .LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
>> at org.apache.tomcat.util.threads.ThreadPool
>> $ControlRunnable.run(ThreadPool.java:685)
>>
> [First try: successful job launching]
> 2009.07.07 08:59:42,355 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.launchJobBlocking(AppServiceImpl.java:
> 270) - called
> 2009.07.07 08:59:42,356 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1038) - called
> 2009.07.07 08:59:42,382 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.writeAppInput(AppServiceImpl.java:
> 944) - called
> 2009.07.07 08:59:42,405 INFO
> edu.sdsc.nbcr.opal.util.Util.getRemoteDN(Util.java:62) - Client's
> DN: Unknown client
> 2009.07.07 08:59:42,405 INFO
> edu.sdsc.nbcr.opal.util.Util.getRemoteIP(Util.java:43) - Client's
> IP: 127.0.0.1
> 2009.07.07 08:59:42,405 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.saveJobInfoInDatabase(HibernateUtil.java:
> 72) - called
> 2009.07.07 08:59:42,424 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.launchApp(AppServiceImpl.java:579)
> - Using job manager class: edu.sdsc.nbcr.opal.manager.DRMAAJobManager
> 2009.07.07 08:59:42,424 INFO
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.initialize(DRMAAJobManager.java:
> 64) - called
> 2009.07.07 08:59:42,424 INFO
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 103) - called
> 2009.07.07 08:59:42,425 DEBUG
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 125) - Argument list: [arg list not very interesting in this log]
> 2009.07.07 08:59:42,425 DEBUG
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 182) - CMD: [cmd line not very interesting in this log]
> 2009.07.07 08:59:42,425 INFO
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 207) - All jobs have a hard limit of 3600 seconds
> 2009.07.07 08:59:42,426 DEBUG
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 212) - Working directory: /[...]/opal/app1246957182356/
> 2009.07.07 08:59:42,426 ERROR
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 230) - Can't set hard limit - The hardRunDurationLimit attribute is
> not supported.
> 2009.07.07 08:59:42,434 INFO
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 235) - DRMAA job has been submitted with id 1183942
> 2009.07.07 08:59:42,434 INFO
> edu
> .sdsc
> .nbcr
> .opal.manager.DRMAAJobManager.waitForActivation(DRMAAJobManager.java:
> 259) - called
> 2009.07.07 08:59:57,460 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 161) - called
> 2009.07.07 08:59:57,460 DEBUG
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 162) - Updating status to: Execution in progress
> 2009.07.07 08:59:57,477 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 213) - Updated status for job: app1246957182356
> 2009.07.07 08:59:57,477 INFO
> edu
> .sdsc
> .nbcr
> .opal.manager.DRMAAJobManager.waitForCompletion(DRMAAJobManager.java:
> 304) - called
> 2009.07.07 09:00:06,655 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 136) - called
> 2009.07.07 09:00:06,655 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 161) - called
> 2009.07.07 09:00:06,656 DEBUG
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 162) - Updating status to: Writing output metadata
> 2009.07.07 09:00:06,668 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 213) - Updated status for job: app1246957182356
> 2009.07.07 09:00:06,672 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.saveOutputsInDatabase(HibernateUtil.java:
> 234) - called
> 2009.07.07 09:00:06,676 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 161) - called
> 2009.07.07 09:00:06,676 DEBUG
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 162) - Updating status to: Execution complete - check outputs to
> verify successful execution
> 2009.07.07 09:00:06,691 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 213) - Updated status for job: app1246957182356
> 2009.07.07 09:00:06,692 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.manageJob(AppServiceImpl.java:938)
> - Execution complete for job: app1246957182356
> 2009.07.07 09:00:06,692 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.queryStatus(AppServiceImpl.java:
> 300) - called for job: app1246957182356
> 2009.07.07 09:00:06,692 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1038) - called
> 2009.07.07 09:00:06,693 INFO
> edu.sdsc.nbcr.opal.state.HibernateUtil.getStatus(HibernateUtil.java:
> 291) - called
> 2009.07.07 09:00:06,694 DEBUG
> edu.sdsc.nbcr.opal.AppServiceImpl.queryStatus(AppServiceImpl.java:
> 316) - Query execution time: 2 ms
> 2009.07.07 09:00:06,694 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.getOutputs(AppServiceImpl.java:
> 361) - called for job: app1246957182356
> 2009.07.07 09:00:06,695 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1038) - called
> 2009.07.07 09:00:06,695 INFO
> edu.sdsc.nbcr.opal.state.HibernateUtil.getOutputs(HibernateUtil.java:
> 404) - called
> 2009.07.07 09:00:06,706 DEBUG
> edu.sdsc.nbcr.opal.AppServiceImpl.getOutputs(AppServiceImpl.java:
> 384) - Output retrieval time: 12 ms
> 2009.07.07 09:00:06,706 DEBUG
> edu
> .sdsc.nbcr.opal.AppServiceImpl.launchJobBlocking(AppServiceImpl.java:
> 286) - Server execution time: 24351 ms
>
>
> [stop then restart opal2 webapp]
> 2009.07.07 09:01:29,518 INFO
> edu
> .sdsc
> .nbcr.opal.dashboard.servlet.OpalServices.init(OpalServices.java:68)
> - Loading OpalServices (init method).
> 2009.07.07 09:01:29,523 INFO
> edu
> .sdsc
> .nbcr
> .opal.dashboard.servlet.OpalInfoServlet.init(OpalInfoServlet.java:
> 61) - Loading OpalInfoServlet (init method).
>
>
> [first job launching: fails]
> 2009.07.07 09:02:02,900 DEBUG
> edu.sdsc.nbcr.opal.AppServiceImpl.<clinit>(AppServiceImpl.java:133)
> - Checking if there are any zombie jobs
> 2009.07.07 09:02:02,901 INFO
> edu
> .sdsc
> .nbcr.opal.state.HibernateUtil.markZombieJobs(HibernateUtil.java:94)
> - called
> 2009.07.07 09:02:03,089 DEBUG
> edu.sdsc.nbcr.opal.AppServiceImpl.<clinit>(AppServiceImpl.java:137)
> - Number of DB entries for zombie jobs cleaned up: 0
> 2009.07.07 09:02:03,133 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.<init>(AppServiceImpl.java:172) -
> called
> 2009.07.07 09:02:03,162 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.launchJobBlocking(AppServiceImpl.java:
> 270) - called
> 2009.07.07 09:02:03,162 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1038) - called
> 2009.07.07 09:02:03,163 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1068) - Configuring service for the first time
> 2009.07.07 09:02:03,163 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1072) - Reading application config: /[...]/opal-ws-2.0/configs/
> bioquali_config.xml
> 2009.07.07 09:02:03,164 DEBUG
> edu.sdsc.nbcr.common.TypeDeserializer.getValue(TypeDeserializer.java:
> 50) - called
> 2009.07.07 09:02:03,220 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.writeAppInput(AppServiceImpl.java:
> 944) - called
> 2009.07.07 09:02:03,229 INFO
> edu.sdsc.nbcr.opal.util.Util.getRemoteDN(Util.java:62) - Client's
> DN: Unknown client
> 2009.07.07 09:02:03,229 INFO
> edu.sdsc.nbcr.opal.util.Util.getRemoteIP(Util.java:43) - Client's
> IP: 127.0.0.1
> 2009.07.07 09:02:03,230 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.saveJobInfoInDatabase(HibernateUtil.java:
> 72) - called
> 2009.07.07 09:02:03,247 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.launchApp(AppServiceImpl.java:579)
> - Using job manager class: edu.sdsc.nbcr.opal.manager.DRMAAJobManager
>
>
> [second job launching: fails]
> 2009.07.07 09:03:10,656 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.launchJobBlocking(AppServiceImpl.java:
> 270) - called
> 2009.07.07 09:03:10,656 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1038) - called
> 2009.07.07 09:03:10,660 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.writeAppInput(AppServiceImpl.java:
> 944) - called
> 2009.07.07 09:03:10,667 INFO
> edu.sdsc.nbcr.opal.util.Util.getRemoteDN(Util.java:62) - Client's
> DN: Unknown client
> 2009.07.07 09:03:10,667 INFO
> edu.sdsc.nbcr.opal.util.Util.getRemoteIP(Util.java:43) - Client's
> IP: 127.0.0.1
> 2009.07.07 09:03:10,668 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.saveJobInfoInDatabase(HibernateUtil.java:
> 72) - called
> 2009.07.07 09:03:10,669 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.launchApp(AppServiceImpl.java:579)
> - Using job manager class: edu.sdsc.nbcr.opal.manager.DRMAAJobManager
>
> [tomcat restart]
> 2009.07.07 09:08:33,652 INFO
> edu
> .sdsc
> .nbcr.opal.dashboard.servlet.OpalServices.init(OpalServices.java:68)
> - Loading OpalServices (init method).
> 2009.07.07 09:08:33,657 INFO
> edu
> .sdsc
> .nbcr
> .opal.dashboard.servlet.OpalInfoServlet.init(OpalInfoServlet.java:
> 61) - Loading OpalInfoServlet (init method).
>
> [successful job launching after tomcat restart]
> 2009.07.07 09:11:51,266 DEBUG
> edu.sdsc.nbcr.opal.AppServiceImpl.<clinit>(AppServiceImpl.java:133)
> - Checking if there are any zombie jobs
> 2009.07.07 09:11:51,267 INFO
> edu
> .sdsc
> .nbcr.opal.state.HibernateUtil.markZombieJobs(HibernateUtil.java:94)
> - called
> 2009.07.07 09:11:51,394 DEBUG
> edu.sdsc.nbcr.opal.AppServiceImpl.<clinit>(AppServiceImpl.java:137)
> - Number of DB entries for zombie jobs cleaned up: 2
> 2009.07.07 09:11:51,443 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.<init>(AppServiceImpl.java:172) -
> called
> 2009.07.07 09:11:51,476 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.launchJobBlocking(AppServiceImpl.java:
> 270) - called
> 2009.07.07 09:11:51,476 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1038) - called
> 2009.07.07 09:11:51,477 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1068) - Configuring service for the first time
> 2009.07.07 09:11:51,477 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1072) - Reading application config: /[...]/opal-ws-2.0/configs/
> bioquali_config.xml
> 2009.07.07 09:11:51,478 DEBUG
> edu.sdsc.nbcr.common.TypeDeserializer.getValue(TypeDeserializer.java:
> 50) - called
> 2009.07.07 09:11:51,590 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.writeAppInput(AppServiceImpl.java:
> 944) - called
> 2009.07.07 09:11:51,600 INFO
> edu.sdsc.nbcr.opal.util.Util.getRemoteDN(Util.java:62) - Client's
> DN: Unknown client
> 2009.07.07 09:11:51,600 INFO
> edu.sdsc.nbcr.opal.util.Util.getRemoteIP(Util.java:43) - Client's
> IP: 127.0.0.1
> 2009.07.07 09:11:51,600 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.saveJobInfoInDatabase(HibernateUtil.java:
> 72) - called
> 2009.07.07 09:11:51,620 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.launchApp(AppServiceImpl.java:579)
> - Using job manager class: edu.sdsc.nbcr.opal.manager.DRMAAJobManager
> 2009.07.07 09:11:51,846 INFO
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.initialize(DRMAAJobManager.java:
> 64) - called
> 2009.07.07 09:11:51,847 INFO
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 103) - called
> 2009.07.07 09:11:51,847 DEBUG
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 125) - Argument list: [arg list not very interesting in this log]
> 2009.07.07 09:11:51,847 DEBUG
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 182) - CMD: [cmd line not very interesting in this log]
> 2009.07.07 09:11:51,848 INFO
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 207) - All jobs have a hard limit of 3600 seconds
> 2009.07.07 09:11:51,849 DEBUG
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 212) - Working directory: /[...]/www-tmp/opal/app1246957911532/
> 2009.07.07 09:11:51,853 ERROR
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 230) - Can't set hard limit - The hardRunDurationLimit attribute is
> not supported.
> 2009.07.07 09:11:51,858 INFO
> edu
> .sdsc
> .nbcr.opal.manager.DRMAAJobManager.launchJob(DRMAAJobManager.java:
> 235) - DRMAA job has been submitted with id 1183943
> 2009.07.07 09:11:51,858 INFO
> edu
> .sdsc
> .nbcr
> .opal.manager.DRMAAJobManager.waitForActivation(DRMAAJobManager.java:
> 259) - called
> 2009.07.07 09:11:57,870 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 161) - called
> 2009.07.07 09:11:57,870 DEBUG
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 162) - Updating status to: Execution in progress
> 2009.07.07 09:11:57,890 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 213) - Updated status for job: app1246957911532
> 2009.07.07 09:11:57,890 INFO
> edu
> .sdsc
> .nbcr
> .opal.manager.DRMAAJobManager.waitForCompletion(DRMAAJobManager.java:
> 304) - called
> 2009.07.07 09:12:02,763 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 136) - called
> 2009.07.07 09:12:02,763 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 161) - called
> 2009.07.07 09:12:02,764 DEBUG
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 162) - Updating status to: Writing output metadata
> 2009.07.07 09:12:02,778 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 213) - Updated status for job: app1246957911532
> 2009.07.07 09:12:02,781 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.saveOutputsInDatabase(HibernateUtil.java:
> 234) - called
> 2009.07.07 09:12:02,801 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 161) - called
> 2009.07.07 09:12:02,801 DEBUG
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 162) - Updating status to: Execution complete - check outputs to
> verify successful execution
> 2009.07.07 09:12:02,819 INFO
> edu
> .sdsc
> .nbcr
> .opal.state.HibernateUtil.updateJobInfoInDatabase(HibernateUtil.java:
> 213) - Updated status for job: app1246957911532
> 2009.07.07 09:12:02,820 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.manageJob(AppServiceImpl.java:938)
> - Execution complete for job: app1246957911532
> 2009.07.07 09:12:02,820 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.queryStatus(AppServiceImpl.java:
> 300) - called for job: app1246957911532
> 2009.07.07 09:12:02,821 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1038) - called
> 2009.07.07 09:12:02,821 INFO
> edu.sdsc.nbcr.opal.state.HibernateUtil.getStatus(HibernateUtil.java:
> 291) - called
> 2009.07.07 09:12:02,823 DEBUG
> edu.sdsc.nbcr.opal.AppServiceImpl.queryStatus(AppServiceImpl.java:
> 316) - Query execution time: 3 ms
> 2009.07.07 09:12:02,823 INFO
> edu.sdsc.nbcr.opal.AppServiceImpl.getOutputs(AppServiceImpl.java:
> 361) - called for job: app1246957911532
> 2009.07.07 09:12:02,823 INFO
> edu
> .sdsc.nbcr.opal.AppServiceImpl.retrieveAppConfig(AppServiceImpl.java:
> 1038) - called
> 2009.07.07 09:12:02,824 INFO
> edu.sdsc.nbcr.opal.state.HibernateUtil.getOutputs(HibernateUtil.java:
> 404) - called
> 2009.07.07 09:12:02,849 DEBUG
> edu.sdsc.nbcr.opal.AppServiceImpl.getOutputs(AppServiceImpl.java:
> 384) - Output retrieval time: 26 ms
> 2009.07.07 09:12:02,850 DEBUG
> edu
> .sdsc.nbcr.opal.AppServiceImpl.launchJobBlocking(AppServiceImpl.java:
> 286) - Server execution time: 11374 ms
> ------------------------------------------------------------------------------
> Enter the BlackBerry Developer Challenge
> This is your chance to win up to $100,000 in prizes! For a limited
> time,
> vendors submitting new applications to BlackBerry App World(TM) will
> have
> the opportunity to enter the BlackBerry Developer Challenge. See
> full prize
> details at: http://p.sf.net/sfu/blackberry_______________________________________________
> Opaltoolkit-users mailing list
> Opa...@li...
> https://lists.sourceforge.net/lists/listinfo/opaltoolkit-users
|
