Announcement

Collapse
No announcement yet.
X
Collapse
  • Page of 1
  • Filter
  • Time
Clear All
new posts
Previous template Next
    #1

    hsqldb issue

    Hi,
    I integrated the builtds sample app to a websphere server. It loads the initial builtinds.html page correctly. But, when i click one of the items (animals, officesupplies,.), I get the following:


    java.sql.SQLSyntaxErrorException: user lacks privilege or object not found: ANIMALS

    The database seems to be starting up without any errors with the use of the HSQLServletContextListener servlet.

    Is this a privilege issue or some thing else?

    thanks for your help.
    Tags: None
    #2
    Remember to always post your complete version number, and the complete server log for any failing request.

    Comment

      #3
      version

      version:
      smartgwtee-4.0p


      stacktrace:



      8/12/13 16:37:17:043 CDT] 0000001b SystemOut O === 2013-08-12 16:37:17,043 [ : 0] WARN RequestContext - dsRequest.execute() failed:
      java.sql.SQLSyntaxErrorException: user lacks privilege or object not found: ANIMALS
      at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
      at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
      at org.hsqldb.jdbc.JDBCStatement.fetchResult(Unknown Source)
      at org.hsqldb.jdbc.JDBCStatement.executeQuery(Unknown Source)
      at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:656)
      at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:533)
      at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:526)
      at com.isomorphic.sql.SQLDriver.getScalarResult(SQLDriver.java:690)
      at com.isomorphic.sql.SQLDriver.executeScalar(SQLDriver.java:920)
      at com.isomorphic.sql.SQLDataSource.runRowCountQuery(SQLDataSource.java:2460)
      at com.isomorphic.sql.SQLDataSource.executeWindowedSelect(SQLDataSource.java:1884)
      at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1539)
      at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
      at com.isomorphic.sql.SQLDataSource.executeFetch(SQLDataSource.java:352)
      at com.isomorphic.datasource.DataSource.execute(DataSource.java:1444)
      at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:726)
      at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658)
      at com.isomorphic.application.AppBase.execute(AppBase.java:491)
      at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2412)
      at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:215)
      at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:172)
      at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:137)
      at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73)
      at javax.servlet.http.HttpServlet.service(HttpServlet.java:738)
      at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
      at javax.servlet.http.HttpServlet.service(HttpServlet.java:831)
      at com.ibm.ws.webcontainer.servlet.ServletWrapper.service(ServletWrapper.java:1657)
      at com.ibm.ws.webcontainer.servlet.ServletWrapper.handleRequest(ServletWrapper.java:939)
      at com.ibm.ws.webcontainer.servlet.ServletWrapper.handleRequest(ServletWrapper.java:502)
      at com.ibm.ws.webcontainer.servlet.ServletWrapperImpl.handleRequest(ServletWrapperImpl.java:179)
      at com.ibm.ws.webcontainer.servlet.CacheServletWrapper.handleRequest(CacheServletWrapper.java:91)
      at com.ibm.ws.webcontainer.WebContainer.handleRequest(WebContainer.java:864)
      at com.ibm.ws.webcontainer.WSWebContainer.handleRequest(WSWebContainer.java:1583)
      at com.ibm.ws.webcontainer.channel.WCChannelLink.ready(WCChannelLink.java:186)
      at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.handleDiscrimination(HttpInboundLink.java:452)
      at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.handleNewRequest(HttpInboundLink.java:511)
      at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.processRequest(HttpInboundLink.java:305)
      at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.ready(HttpInboundLink.java:276)
      at com.ibm.ws.tcp.channel.impl.NewConnectionInitialReadCallback.sendToDiscriminators(NewConnectionInitialReadCallback.java:214)
      at com.ibm.ws.tcp.channel.impl.NewConnectionInitialReadCallback.complete(NewConnectionInitialReadCallback.java:113)
      at com.ibm.ws.tcp.channel.impl.AioReadCompletionListener.futureCompleted(AioReadCompletionListener.java:165)
      at com.ibm.io.async.AbstractAsyncFuture.invokeCallback(AbstractAsyncFuture.java:217)
      at com.ibm.io.async.AsyncChannelFuture.fireCompletionActions(AsyncChannelFuture.java:161)
      at com.ibm.io.async.AsyncFuture.completed(AsyncFuture.java:138)
      at com.ibm.io.async.ResultHandler.complete(ResultHandler.java:204)
      at com.ibm.io.async.ResultHandler.runEventProcessingLoop(ResultHandler.java:775)
      at com.ibm.io.async.ResultHandler$2.run(ResultHandler.java:905)
      at com.ibm.ws.util.ThreadPool$Worker.run(ThreadPool.java:1604)
      [8/12/13 16:37:17:043 CDT] 0000001b SystemOut O === 2013-08-12 16:37:17,043 [ : 0] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
      [8/12/13 16:37:17:043 CDT] 0000001b SystemOut O === 2013-08-12 16:37:17,043 [ : 0] DEBUG RPCManager - non-DMI response, dropExtraFields: false
      [8/12/13 16:37:17:043 CDT] 0000001b SystemOut O === 2013-08-12 16:37:17,043 [ : 0] DEBUG SQLDriver - Freeing SQLDriver dbConnection 254349097
      [8/12/13 16:37:17:043 CDT] 0000001b SystemOut O === 2013-08-12 16:37:17,043 [ : 0] DEBUG SQLConnectionManager - About to close JDBCConnection with hashcode "254349097"

      Comment

        #4
        *Complete* server log for the request, not partial.

        You cut off the logs that can help to troubleshoot this issue.

        Comment

          #5
          server logs

          Here is the server logs starting with server startup till the exception occurrence.


          ************ Start Display Current Environment ************
          WebSphere Platform 7.0.0.19 [BASE 7.0.0.19 cf191132.09][WEB2MOBILE 1.1.0.0 web21122.15] running with process name XPD57151Node11Cell\XPD57151Node11\server1 and process id 7252
          Host Operating System is Windows XP, version 5.1 build 2600 Service Pack 3
          Java version = 1.6.0, Java Compiler = j9jit24, Java VM name = IBM J9 VM
          was.install.root = C:\Program Files\IBM\SDP\runtimes\base_v7
          user.install.root = D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile
          Java Home = C:\Program Files\IBM\SDP\runtimes\base_v7\java\jre
          ws.ext.dirs = C:\Program Files\IBM\SDP\runtimes\base_v7/java/lib;D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile/classes;C:\Program Files\IBM\SDP\runtimes\base_v7/classes;C:\Program Files\IBM\SDP\runtimes\base_v7/lib;C:\Program Files\IBM\SDP\runtimes\base_v7/installedChannels;C:\Program Files\IBM\SDP\runtimes\base_v7/lib/ext;C:\Program Files\IBM\SDP\runtimes\base_v7/web/help;C:\Program Files\IBM\SDP\runtimes\base_v7/deploytool/itp/plugins/com.ibm.etools.ejbdeploy/runtime
          Classpath = D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile/properties;C:\Program Files\IBM\SDP\runtimes\base_v7/properties;C:\Program Files\IBM\SDP\runtimes\base_v7/lib/startup.jar;C:\Program Files\IBM\SDP\runtimes\base_v7/lib/bootstrap.jar;C:\Program Files\IBM\SDP\runtimes\base_v7/lib/jsf-nls.jar;C:\Program Files\IBM\SDP\runtimes\base_v7/lib/lmproxy.jar;C:\Program Files\IBM\SDP\runtimes\base_v7/lib/urlprotocols.jar;C:\Program Files\IBM\SDP\runtimes\base_v7/deploytool/itp/batchboot.jar;C:\Program Files\IBM\SDP\runtimes\base_v7/deploytool/itp/batch2.jar;C:\Program Files\IBM\SDP\runtimes\base_v7/java/lib/tools.jar
          Java Library path = C:\Program Files\IBM\SDP\runtimes\base_v7\java\jre\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\Program Files\IBM\SDP\runtimes\base_v7\bin;C:\Program Files\IBM\SDP\runtimes\base_v7\java\bin;C:\Program Files\IBM\SDP\runtimes\base_v7\java\jre\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32\wbem;c:\program files\windows imaging\;c:\program files\microsoft sql server\90\tools\binn\;D:\grails\bin;c:\program files\tortoisesvn\bin;d:\apps\maven3\apache-maven-3.0.3\bin;c:\program files\android\android-sdk\tools;c:\program files\android\android-sdk\platform-tools;c:\program files\java\jdk1.6.0_26\bin;c:\apps\ant\apache-ant-1.8.4\bin;c:\program files\ibm\gsk8\lib;D:\PROGRA~1\IBM\SQLLIB~1\BIN;D:\PROGRA~1\IBM\SQLLIB~1\FUNCTION;D:\PROGRA~1\IBM\SQLLIB~1\SAMPLES\REPL;.
          ************* End Display Current Environment *************
          [8/13/13 8:00:41:515 CDT] 00000000 ManagerAdmin I TRAS0017I: The startup trace state is *=info.
          [8/13/13 8:00:41:733 CDT] 00000000 ManagerAdmin I TRAS0111I: The message IDs that are in use are deprecated
          [8/13/13 8:00:41:843 CDT] 00000000 ModelMgr I WSVR0800I: Initializing core configuration models
          [8/13/13 8:00:42:249 CDT] 00000000 ComponentMeta I WSVR0179I: The runtime provisioning feature is disabled. All components will be started.
          [8/13/13 8:00:42:499 CDT] 00000000 ProviderTrack I com.ibm.ffdc.osgi.ProviderTracker AddingService FFDC1007I: FFDC Provider Installed: com.ibm.ffdc.EmptyProvider(silent)
          [8/13/13 8:00:42:530 CDT] 00000000 ProviderTrack I com.ibm.ffdc.osgi.ProviderTracker AddingService FFDC1007I: FFDC Provider Installed: com.ibm.ws.ffdc.impl.FfdcProvider@65826582
          [8/13/13 8:00:42:639 CDT] 00000000 AdminInitiali A ADMN0015I: The administration service is initialized.
          [8/13/13 8:00:43:264 CDT] 00000000 PluginConfigS I PLGC0057I: The plug-in configuration service started successfully.
          [8/13/13 8:00:43:764 CDT] 00000000 SSLComponentI I CWPKI0001I: SSL service is initializing the configuration
          [8/13/13 8:00:43:764 CDT] 00000000 WSKeyStore W CWPKI0041W: One or more key stores are using the default password.
          [8/13/13 8:00:43:780 CDT] 00000000 SSLConfigMana I CWPKI0027I: Disabling default hostname verification for HTTPS URL connections.
          [8/13/13 8:00:43:796 CDT] 00000000 SSLDiagnostic I CWPKI0014I: The SSL component's FFDC Diagnostic Module com.ibm.ws.ssl.core.SSLDiagnosticModule registered successfully: true.
          [8/13/13 8:00:43:796 CDT] 00000000 SSLComponentI I CWPKI0002I: SSL service initialization completed successfully
          [8/13/13 8:00:43:811 CDT] 00000000 DiagnosticCon I com.ibm.wsspi.rasdiag.DiagnosticConfigHome setStateCollectionSpec RASD0012I: Updating State Collection Spec from Uninitialized Value to .*:.*=0
          [8/13/13 8:00:43:811 CDT] 00000000 PMIImpl A CWPMI1001I: PMI is enabled
          [8/13/13 8:00:44:311 CDT] 00000000 SibMessage I [:] CWSIU0000I: Release: WAS70.SIB Level: o1132.06
          [8/13/13 8:00:44:389 CDT] 00000000 SecurityDM I SECJ0231I: The Security component's FFDC Diagnostic Module com.ibm.ws.security.core.SecurityDM registered successfully: true.
          [8/13/13 8:00:44:514 CDT] 00000000 distSecurityC I SECJ6004I: Security Auditing is disabled.
          [8/13/13 8:00:44:514 CDT] 00000000 distSecurityC I SECJ0309I: Java 2 Security is disabled.
          [8/13/13 8:00:44:514 CDT] 00000000 Configuration A SECJ0215I: Successfully set JAAS login provider configuration class to com.ibm.ws.security.auth.login.Configuration.
          [8/13/13 8:00:44:561 CDT] 00000000 distSecurityC I SECJ0240I: Security service initialization completed successfully
          [8/13/13 8:00:45:233 CDT] 00000000 JAASLoginConf I SECJ0212I: WCCM JAAS configuration information successfully pushed to login provider class.
          [8/13/13 8:00:45:264 CDT] 00000000 J2EEServiceMa I ASYN0059I: Work Manager service initialized successfully.
          [8/13/13 8:00:45:280 CDT] 00000000 CScopeCompone I CSCP0002I: Compensation service is disabled.
          [8/13/13 8:00:45:327 CDT] 00000000 SibMessage I [:] CWSID0006I: The SIB service was not enabled and will not be started.
          [8/13/13 8:00:45:327 CDT] 00000000 ActivitySessi I WACS0045I: ActivitySession service is disabled.
          [8/13/13 8:00:45:374 CDT] 00000000 AppProfileCom I ACIN0030I: Application Profiling service is disabled.
          [8/13/13 8:00:45:389 CDT] 00000000 SOAPContainer I WSWS1062I: The SOAP Container service has been initialized.
          [8/13/13 8:00:45:452 CDT] 00000000 SchedulerServ I SCHD0036I: The Scheduler Service is initializing.
          [8/13/13 8:00:45:452 CDT] 00000000 SchedulerServ I SCHD0037I: The Scheduler Service has been initialized.
          [8/13/13 8:00:45:468 CDT] 00000000 JPAComponentI I CWWJP0026I: The Java Persistence API (JPA) component is initializing.
          [8/13/13 8:00:45:499 CDT] 00000000 JPAComponentI I CWWJP0006I: The com.ibm.websphere.persistence.PersistenceProviderImpl class is loaded as the default Java Persistence API (JPA) provider.
          [8/13/13 8:00:45:499 CDT] 00000000 JPAComponentI I CWWJP0027I: The Java Persistence API (JPA) component has initialized.
          [8/13/13 8:00:45:718 CDT] 00000000 ApplicationRe I ApplicationRepositoryFactory CWSCT0405I: The application router is configured to select applications by the weight.
          [8/13/13 8:00:45:733 CDT] 00000000 DefaultApplic I DefaultApplicationRouter CWSCT0401I: Default application router has been initialized.
          [8/13/13 8:00:45:733 CDT] 00000000 WebsphereComm I WebsphereCommonLauncher CWSCT0332I: SIP Container was launched successfully but will only be initialized when first SIP application is loaded.
          [8/13/13 8:00:45:780 CDT] 00000000 StartUpServic I STUP0008I: The Startup Beans service is disabled.
          [8/13/13 8:00:45:796 CDT] 00000000 I18nService I I18N0010I: The Internationalization service is created on server1.
          [8/13/13 8:00:45:796 CDT] 00000000 I18nServiceSe I I18N0010I: The Internationalization service is disabled on server1.
          [8/13/13 8:00:45:936 CDT] 00000000 ThreadPoolMgr W WSVR0626W: The ThreadPool setting on the ObjectRequestBroker service is deprecated.
          [8/13/13 8:00:45:936 CDT] 00000000 SSLComponentI I CWPKI0003I: SSL service is starting
          [8/13/13 8:00:45:936 CDT] 00000000 SSLComponentI I CWPKI0004I: SSL service started successfully
          [8/13/13 8:00:46:186 CDT] 00000000 SecurityConne A JSAS0006I: Security connection interceptor initialized.
          [8/13/13 8:00:46:186 CDT] 00000000 ServiceInit A JSAS0001I: Security configuration initialized.
          [8/13/13 8:00:46:186 CDT] 00000000 ServiceInit A JSAS0003I: Authentication mechanism: LTPA
          [8/13/13 8:00:46:186 CDT] 00000000 ServiceInit A JSAS0004I: Principal name: defaultWIMFileBasedRealm/
          [8/13/13 8:00:46:202 CDT] 00000000 CSIClientRI A JSAS0007I: Client request interceptor registered.
          [8/13/13 8:00:46:218 CDT] 00000000 CSIServerRI A JSAS0008I: Server request interceptor registered.
          [8/13/13 8:00:46:233 CDT] 00000000 SecurityCompo A JSAS0009I: IOR interceptor registered.
          [8/13/13 8:00:46:702 CDT] 00000000 CoordinatorIm I HMGR0206I: The Coordinator is an Active Coordinator for core group DefaultCoreGroup. The active coordinator set is [XPD57151Node11Cell\XPD57151Node11\server1].
          [8/13/13 8:00:46:718 CDT] 00000000 DCSPluginSing I HMGR0005I: The Single Server DCS Core Stack transport has been started for core group DefaultCoreGroup.
          [8/13/13 8:00:46:843 CDT] 00000000 NameServerImp A NMSV0018I: Name server available on bootstrap port 2819.
          [8/13/13 8:00:46:858 CDT] 00000000 CGBridgeSubsc I CWRCB0102I: The core group bridge service has started the subscription router.
          [8/13/13 8:00:48:233 CDT] 00000006 DMAdapter I com.ibm.ws.ffdc.impl.DMAdapter getAnalysisEngine FFDC1009I: Analysis Engine using data base: D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\properties\logbr\ffdc\adv\ffdcdb.xml
          [8/13/13 8:00:48:327 CDT] 00000006 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_4f944f94_13.08.13_08.00.48.1558833192054615729354.txt com.ibm.ws.security.auth.ContextManagerImpl.login 4732
          [8/13/13 8:00:48:467 CDT] 00000006 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_4f944f94_13.08.13_08.00.48.3588183512365967291945.txt com.ibm.websphere.security.auth.WSLoginFailedException 250
          [8/13/13 8:00:48:686 CDT] 00000006 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_4f944f94_13.08.13_08.00.48.3278010110805676538005.txt com.ibm.ISecurityLocalObjectGSSUPImpl.WSSecurityContextImpl.acceptSecContext 380
          [8/13/13 8:00:48:686 CDT] 00000006 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_4f944f94_13.08.13_08.00.48.686606021502233004597.txt com.ibm.websphere.security.auth.WSSecurityContextException 190
          [8/13/13 8:00:48:780 CDT] 00000006 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_4f944f94_13.08.13_08.00.48.6867672763002029076705.txt com.ibm.ISecurityLocalObjectGSSUPImpl.WSSecurityContextImpl.acceptSecContext 423
          [8/13/13 8:00:48:827 CDT] 00000006 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_4f944f94_13.08.13_08.00.48.7804759194245935749692.txt com.ibm.ISecurityLocalObjectGSSUPImpl.SecurityContextImpl.csi_initialize 458
          [8/13/13 8:00:48:842 CDT] 00000006 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_4f944f94_13.08.13_08.00.48.8426433540765054679099.txt com.ibm.ISecurityLocalObjectGSSUPImpl.SecurityContextImpl.csi_initialize 694
          [8/13/13 8:00:48:858 CDT] 00000000 authz I CWWIM2000I Initialization of the authorization component completed successfully.
          [8/13/13 8:00:48:874 CDT] 00000006 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_4f944f94_13.08.13_08.00.48.8428119486074496542793.txt com.ibm.websphere.security.auth.WSLoginFailedException 250
          [8/13/13 8:00:48:905 CDT] 00000000 UserRegistryI A SECJ0136I: Custom Registry:com.ibm.ws.wim.registry.WIMUserRegistry has been initialized
          [8/13/13 8:00:48:921 CDT] 00000000 JMSRegistrati A WMSG1611I: The installed level of the WebSphere MQ messaging provider is 7.0.1.5.
          [8/13/13 8:00:48:921 CDT] 00000000 JMSRegistrati I WMSG1703I: RAR implementation version 7.0.1.5-k701-105-110419
          [8/13/13 8:00:48:983 CDT] 00000000 ObjectPoolSer I OBPL0010I: The Object Pool service started successfully.
          [8/13/13 8:00:48:983 CDT] 00000006 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_4f944f94_13.08.13_08.00.48.8421381560589840678185.txt com.ibm.ISecurityLocalObjectBaseL13Impl.CSIServerRIBase.authenticateSecurityTokens 4519
          [8/13/13 8:00:52:217 CDT] 00000000 JMXSoapAdapte A ADMC0013I: The SOAP connector is available at port 8890
          [8/13/13 8:00:52:327 CDT] 00000000 TCPChannel I TCPC0001I: TCP Channel TCPInboundChannel_ipcc.Default_IPC_Connector_Name is listening on host localhost (IPv4: 127.0.0.1) port 9642.
          [8/13/13 8:00:52:327 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain IPCCInboundChain.Default_IPC_Connector_Name.
          [8/13/13 8:00:52:327 CDT] 00000000 IPCConnectorS I ADMC0070I: The IPC connector is available at port 9642
          [8/13/13 8:00:52:327 CDT] 00000000 distSecurityC I securityServiceStarted is true
          [8/13/13 8:00:52:327 CDT] 00000000 distSecurityC I SECJ0243I: Security service started successfully
          [8/13/13 8:00:52:327 CDT] 00000000 distSecurityC I SECJ0210I: Security enabled true
          [8/13/13 8:00:52:342 CDT] 00000000 J2EEServiceMa I ASYN0055I: The Work Manager service started successfully.
          [8/13/13 8:00:52:374 CDT] 00000000 RecoveryDirec I CWRLS0010I: Performing recovery processing for local WebSphere server (XPD57151Node11Cell\XPD57151Node11\server1).
          [8/13/13 8:00:52:389 CDT] 00000000 RecoveryDirec I CWRLS0012I: All persistent services have been directed to perform recovery processing for this WebSphere server (XPD57151Node11Cell\XPD57151Node11\server1).
          [8/13/13 8:00:52:483 CDT] 00000009 RecoveryManag A WTRN0132I: Transaction recovery for XPD57151Node11Cell\XPD57151Node11\server1 initiated with server uuid c87cfd03bd4b43357773dcbb8554138eff60f32a and restart epoch 0
          [8/13/13 8:00:52:483 CDT] 00000009 RecoveryManag A WTRN0135I: Transaction service recovering no transactions.
          [8/13/13 8:00:52:483 CDT] 00000009 RecoveryManag A WTRN0134I: Recovering 0 XA resource manager(s) from the transaction partner logs
          [8/13/13 8:00:52:483 CDT] 00000009 RecoveryManag A WTRN0133I: Transaction recovery processing for this server is complete
          [8/13/13 8:00:52:858 CDT] 00000000 ResourceMgrIm I WSVR0049I: Binding AsyncRequestDispatcherWorkManager as wm/ard
          [8/13/13 8:00:52:905 CDT] 00000000 ResourceMgrIm I WSVR0049I: Binding DefaultEJBTimerDataSource as jdbc/DefaultEJBTimerDataSource
          [8/13/13 8:00:53:702 CDT] 00000000 ResourceMgrIm I WSVR0049I: Binding LotControlMQCF as jms/LotControlMQCF
          [8/13/13 8:00:53:733 CDT] 00000000 ResourceMgrIm I WSVR0049I: Binding LotContolHMA_NGTQueue as jms/LotContolHMA_NGTQueue
          [8/13/13 8:00:53:749 CDT] 00000000 ResourceMgrIm I WSVR0049I: Binding testCF as jms/testCF
          [8/13/13 8:00:53:764 CDT] 00000000 ResourceMgrIm I WSVR0049I: Binding galc datasource_CF as eis/jdbc/galdb-ds5_CMP
          [8/13/13 8:00:53:764 CDT] 00000000 ResourceMgrIm I WSVR0049I: Binding galc datasource as jdbc/galdb-ds5
          [8/13/13 8:00:53:780 CDT] 00000000 ResourceMgrIm I WSVR0049I: Binding DefaultWorkManager as wm/default
          [8/13/13 8:00:53:780 CDT] 00000000 ResourceMgrIm I WSVR0049I: Binding DefaultTimerManager as tm/default
          [8/13/13 8:01:12:201 CDT] 00000000 WorkAreaServi A ACWA0002I: WorkArea service not enabled on server1.
          [8/13/13 8:01:12:232 CDT] 00000000 SOAPAcceptorC I WSSC1019I: The SOAP Acceptor Channel has been configured.
          [8/13/13 8:01:12:326 CDT] 00000000 CacheServiceI I DYNA1054I: Dynamic Cache (core cache) initialized successfully.
          [8/13/13 8:01:12:342 CDT] 00000000 ObjectCacheSe I DYNA1056I: Dynamic Cache (object cache) initialized successfully.
          [8/13/13 8:01:12:561 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0239I: Extension Factory [class com.ibm.ws.websvcs.extension.WASAxis2ExtensionFactory] was registered successfully.
          [8/13/13 8:01:12:561 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0240I: Extension Factory [class com.ibm.ws.websvcs.extension.WASAxis2ExtensionFactory] has been associated with patterns [""].
          [8/13/13 8:01:12:576 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0239I: Extension Factory [class com.ibm.ws.websvcs.extension.AsyncServletExtensionFactory] was registered successfully.
          [8/13/13 8:01:12:576 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0240I: Extension Factory [class com.ibm.ws.websvcs.extension.AsyncServletExtensionFactory] has been associated with patterns [""].
          [8/13/13 8:01:12:701 CDT] 00000000 WASAxis2Compo I WSWS7001I: The Axis2 service has been started.
          [8/13/13 8:01:13:467 CDT] 00000000 SchedulerServ I SCHD0031I: The Scheduler Service is starting.
          [8/13/13 8:01:13:467 CDT] 00000000 SchedulerServ I SCHD0001I: The Scheduler Service has started.
          [8/13/13 8:01:13:467 CDT] 00000000 JPAComponentI I CWWJP0028I: The Java Persistence API (JPA) component is starting.
          [8/13/13 8:01:13:467 CDT] 00000000 JPAComponentI I CWWJP0017I: The Java Persistence API (JPA) component has started.
          [8/13/13 8:01:13:576 CDT] 00000000 ServerCache I DYNA1001I: WebSphere Dynamic Cache instance named ws/com.ibm.ws.wssecurity.sctClientCacheMap initialized successfully.
          [8/13/13 8:01:13:576 CDT] 00000000 ServerCache I DYNA1071I: The cache provider "default" is being used.
          [8/13/13 8:01:13:576 CDT] 00000000 ServerCache I DYNA1001I: WebSphere Dynamic Cache instance named ws/com.ibm.ws.wssecurity.sctServiceCacheMap initialized successfully.
          [8/13/13 8:01:13:576 CDT] 00000000 ServerCache I DYNA1071I: The cache provider "default" is being used.
          [8/13/13 8:01:13:607 CDT] 00000000 RegistryConfi I CWXRS0017I: Extension Registry objects storage: D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\temp\XPD57151Node11\server1\_extensionregistry
          [8/13/13 8:01:13:670 CDT] 00000000 ServerCache I DYNA1001I: WebSphere Dynamic Cache instance named ws/com.ibm.workplace/ExtensionRegistryCache initialized successfully.
          [8/13/13 8:01:13:670 CDT] 00000000 ServerCache I DYNA1071I: The cache provider "default" is being used.
          [8/13/13 8:01:13:670 CDT] 00000000 RegistryCache I CWXRS0002I: DynaCache instance for Extension Registry created with CACHE_SIZE: 5000
          [8/13/13 8:01:13:951 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0239I: Extension Factory [class com.ibm.ws.portletcontainer.webextension.PortletExtensionFactory] was registered successfully.
          [8/13/13 8:01:13:967 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0240I: Extension Factory [class com.ibm.ws.portletcontainer.webextension.PortletExtensionFactory] has been associated with patterns [""].
          [8/13/13 8:01:13:967 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0239I: Extension Factory [class com.ibm.ws.portletcontainer.portletserving.PortletServingExtensionFactory] was registered successfully.
          [8/13/13 8:01:13:967 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0240I: Extension Factory [class com.ibm.ws.portletcontainer.portletserving.PortletServingExtensionFactory] has been associated with patterns [""].
          [8/13/13 8:01:14:014 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0239I: Extension Factory [class com.ibm.ws.jsf.extprocessor.JSFExtensionFactory] was registered successfully.
          [8/13/13 8:01:14:014 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0240I: Extension Factory [class com.ibm.ws.jsf.extprocessor.JSFExtensionFactory] has been associated with patterns [""].
          [8/13/13 8:01:14:076 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0239I: Extension Factory [class com.ibm.ws.jsp.webcontainerext.ws.WASJSPExtensionFactory] was registered successfully.
          [8/13/13 8:01:14:076 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0240I: Extension Factory [class com.ibm.ws.jsp.webcontainerext.ws.WASJSPExtensionFactory] has been associated with patterns [*.jsp *.jspx *.jsw *.jsv ].
          [8/13/13 8:01:14:264 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0239I: Extension Factory [class com.ibm.ws.ard.extension.ARDExtensionFactory] was registered successfully.
          [8/13/13 8:01:14:264 CDT] 00000000 webcontainer I com.ibm.ws.webcontainer.WebContainer addExtensionFactory SRVE0240I: Extension Factory [class com.ibm.ws.ard.extension.ARDExtensionFactory] has been associated with patterns [""].
          [8/13/13 8:01:15:186 CDT] 0000000e ApplicationMg A WSVR0200I: Starting application: ManagementEJB
          [8/13/13 8:01:15:186 CDT] 00000010 ApplicationMg A WSVR0200I: Starting application: SchedulerCalendars
          [8/13/13 8:01:15:186 CDT] 0000000e ApplicationMg A WSVR0204I: Application: ManagementEJB Application build level: Unknown
          [8/13/13 8:01:15:186 CDT] 00000010 ApplicationMg A WSVR0204I: Application: SchedulerCalendars Application build level: Unknown
          [8/13/13 8:01:15:186 CDT] 0000000f ApplicationMg A WSVR0200I: Starting application: filetransferSecured
          [8/13/13 8:01:15:201 CDT] 0000000f ApplicationMg A WSVR0204I: Application: filetransferSecured Application build level: Unknown
          [8/13/13 8:01:15:982 CDT] 0000000e EJBContainerI I WSVR0037I: Starting EJB jar: mejb.jar
          [8/13/13 8:01:15:998 CDT] 0000000e EJBContainerI I CNTR0167I: The server is binding the javax.management.j2ee.ManagementHome interface of the Management enterprise bean in the mejb.jar module of the ManagementEJB application. The binding location is: ejb/mgmt/MEJB
          [8/13/13 8:01:16:029 CDT] 0000000f webcontainer I com.ibm.ws.webcontainer.WSWebContainer initialize SRVE0161I: IBM WebSphere Application Server - Web Container. Copyright IBM Corp. 1998-2008
          [8/13/13 8:01:16:029 CDT] 0000000f webcontainer I com.ibm.ws.webcontainer.WSWebContainer initialize SRVE0162I: Servlet Specification Level: 2.5
          [8/13/13 8:01:16:029 CDT] 0000000f webcontainer I com.ibm.ws.webcontainer.WSWebContainer initialize SRVE0163I: Supported JSP Specification Level: 2.1
          [8/13/13 8:01:16:045 CDT] 0000000e EJBContainerI I WSVR0057I: EJB jar started: mejb.jar
          [8/13/13 8:01:16:092 CDT] 0000000f collaborator I com.ibm.ws.webcontainer.collaborator.WebAppSecurityCollaboratorImpl createSecurityCollabImpl using default ejs collaborator since there is nothing in the extension points
          [8/13/13 8:01:16:139 CDT] 00000010 EJBContainerI I WSVR0037I: Starting EJB jar: Calendars.jar
          [8/13/13 8:01:16:154 CDT] 00000010 EJBContainerI I CNTR0167I: The server is binding the com.ibm.websphere.scheduler.UserCalendarHome interface of the DefaultUserCalendar enterprise bean in the Calendars.jar module of the SchedulerCalendars application. The binding location is: com/ibm/websphere/scheduler/calendar/DefaultUserCalendarHome
          [8/13/13 8:01:16:201 CDT] 00000010 EJBContainerI I WSVR0057I: EJB jar started: Calendars.jar
          [8/13/13 8:01:16:279 CDT] 0000000f webcontainer I com.ibm.ws.webcontainer.WSWebContainer addVirtualHostExtensionFactory SRVE0239I: Extension Factory [class com.ibm.ws.webcontainer.extension.ExtHandshakeVHostExtensionFactory] was registered successfully.
          [8/13/13 8:01:16:295 CDT] 0000000f webcontainer I com.ibm.ws.webcontainer.WSWebContainer addVirtualHostExtensionFactory SRVE0240I: Extension Factory [class com.ibm.ws.webcontainer.extension.ExtHandshakeVHostExtensionFactory] has been associated with patterns [VH:_WS_EH* ].
          [8/13/13 8:01:16:295 CDT] 00000010 ApplicationMg A WSVR0221I: Application started: SchedulerCalendars
          [8/13/13 8:01:16:295 CDT] 0000000e ApplicationMg A WSVR0221I: Application started: ManagementEJB
          [8/13/13 8:01:16:295 CDT] 00000010 CompositionUn A WSVR0190I: Starting composition unit WebSphere:cuname=IBMUTC in BLA WebSphere:blaname=IBMUTC.
          [8/13/13 8:01:16:295 CDT] 0000000e CompositionUn A WSVR0190I: Starting composition unit WebSphere:cuname=SGWTEAR3 in BLA WebSphere:blaname=SGWTEAR3.
          [8/13/13 8:01:16:295 CDT] 0000000f webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: WebSphere Admin File Transfer Application.
          [8/13/13 8:01:16:373 CDT] 00000010 ApplicationMg A WSVR0200I: Starting application: IBMUTC
          [8/13/13 8:01:16:373 CDT] 00000010 ApplicationMg A WSVR0204I: Application: IBMUTC Application build level: Unknown
          [8/13/13 8:01:16:389 CDT] 0000000f WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key admin_host/FileTransfer
          [8/13/13 8:01:16:764 CDT] 0000000f servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper init SRVE0242I: [filetransferSecured] [/FileTransfer] [transfer]: Initialization successful.
          [8/13/13 8:01:16:795 CDT] 0000000f webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module WebSphere Admin File Transfer Application has been bound to admin_host[*:9070,*:9053,*:9066,*:9049].
          [8/13/13 8:01:16:795 CDT] 0000000f ApplicationMg A WSVR0221I: Application started: filetransferSecured
          [8/13/13 8:01:16:795 CDT] 0000000f CompositionUn A WSVR0190I: Starting composition unit WebSphere:cuname=ivtApp in BLA WebSphere:blaname=ivtApp.
          [8/13/13 8:01:21:232 CDT] 0000000e ApplicationMg A WSVR0200I: Starting application: SGWTEAR3
          [8/13/13 8:01:21:232 CDT] 0000000e ApplicationMg A WSVR0204I: Application: SGWTEAR3 Application build level: Unknown
          [8/13/13 8:01:21:482 CDT] 0000000f ApplicationMg A WSVR0200I: Starting application: ivtApp
          [8/13/13 8:01:21:482 CDT] 0000000f ApplicationMg A WSVR0203I: Application: ivtApp Application build level: WASX.SERV1 [qq0723.30]
          [8/13/13 8:01:22:029 CDT] 00000010 webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: Universal Test Client.
          [8/13/13 8:01:22:029 CDT] 0000000f EJBContainerI I WSVR0037I: Starting EJB jar: ivtEJB.jar
          [8/13/13 8:01:22:045 CDT] 0000000f EJBContainerI I CNTR0167I: The server is binding the com.ibm.websphere.ivt.ivtEJB.ivtEJBHome interface of the ivtEJBObject enterprise bean in the ivtEJB.jar module of the ivtApp application. The binding location is: ejb/ivtEJBObject
          [8/13/13 8:01:22:045 CDT] 0000000f EJBContainerI I WSVR0057I: EJB jar started: ivtEJB.jar
          [8/13/13 8:01:22:060 CDT] 00000010 WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key default_hostUTC
          [8/13/13 8:01:22:607 CDT] 00000010 webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module Universal Test Client has been bound to default_host[*:9090,*:80,*:9453,*:5081,*:5080,*:443,*:9086,*:9449,*:5073,*:5072].
          [8/13/13 8:01:22:607 CDT] 0000000f webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: IVT Application.
          [8/13/13 8:01:22:607 CDT] 00000010 ApplicationMg A WSVR0221I: Application started: IBMUTC
          [8/13/13 8:01:22:607 CDT] 00000010 CompositionUn A WSVR0191I: Composition unit WebSphere:cuname=IBMUTC in BLA WebSphere:blaname=IBMUTC started.
          [8/13/13 8:01:22:607 CDT] 00000010 CompositionUn A WSVR0190I: Starting composition unit WebSphere:cuname=query in BLA WebSphere:blaname=query.
          [8/13/13 8:01:22:654 CDT] 00000010 ApplicationMg A WSVR0200I: Starting application: query
          [8/13/13 8:01:22:654 CDT] 00000010 ApplicationMg A WSVR0204I: Application: query Application build level: Unknown
          [8/13/13 8:01:22:701 CDT] 0000000f WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key default_host/ivt
          [8/13/13 8:01:22:717 CDT] 0000000f webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module IVT Application has been bound to default_host[*:9090,*:80,*:9453,*:5081,*:5080,*:443,*:9086,*:9449,*:5073,*:5072].
          [8/13/13 8:01:22:732 CDT] 0000000e webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: TestWeb.
          [8/13/13 8:01:22:732 CDT] 0000000f ApplicationMg A WSVR0221I: Application started: ivtApp
          [8/13/13 8:01:22:732 CDT] 0000000f CompositionUn A WSVR0191I: Composition unit WebSphere:cuname=ivtApp in BLA WebSphere:blaname=ivtApp started.
          [8/13/13 8:01:22:732 CDT] 00000010 EJBContainerI I WSVR0037I: Starting EJB jar: querybean.jar
          [8/13/13 8:01:22:732 CDT] 0000000f CompositionUn A WSVR0190I: Starting composition unit WebSphere:cuname=ibmasyncrsp in BLA WebSphere:blaname=ibmasyncrsp.
          [8/13/13 8:01:22:732 CDT] 00000010 EJBContainerI I CNTR0167I: The server is binding the com.ibm.websphere.ejbquery.QueryHome interface of the Query enterprise bean in the querybean.jar module of the query application. The binding location is: com/ibm/websphere/ejbquery/Query
          [8/13/13 8:01:22:748 CDT] 00000010 EJBContainerI I CNTR0167I: The server is binding the com.ibm.websphere.ejbquery.QueryLocalHome interface of the Query enterprise bean in the querybean.jar module of the query application. The binding location is: ejblocal:com/ibm/websphere/ejbquery/Query
          [8/13/13 8:01:22:748 CDT] 00000010 EJBContainerI I WSVR0057I: EJB jar started: querybean.jar
          [8/13/13 8:01:22:795 CDT] 00000010 ApplicationMg A WSVR0221I: Application started: query
          [8/13/13 8:01:22:795 CDT] 00000010 CompositionUn A WSVR0191I: Composition unit WebSphere:cuname=query in BLA WebSphere:blaname=query started.
          [8/13/13 8:01:22:795 CDT] 00000010 CompositionUn A WSVR0190I: Starting composition unit WebSphere:cuname=isclite in BLA WebSphere:blaname=isclite.
          [8/13/13 8:01:22:810 CDT] 0000000e WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key default_hostTestWeb
          [8/13/13 8:01:22:810 CDT] 0000000f ApplicationMg A WSVR0200I: Starting application: ibmasyncrsp
          [8/13/13 8:01:22:810 CDT] 0000000f ApplicationMg A WSVR0203I: Application: ibmasyncrsp Application build level: 1 [2]
          [8/13/13 8:01:22:920 CDT] 00000010 ApplicationMg A WSVR0200I: Starting application: isclite
          [8/13/13 8:01:22:920 CDT] 00000010 ApplicationMg A WSVR0203I: Application: isclite Application build level: WAS70.WSC [cf191132.09]
          [8/13/13 8:01:23:045 CDT] 0000000e SystemOut O ISC: Configuring log4j from: file:/D:/rad8workspaces/rgalc-smartgwt/TestWeb/WebContent/WEB-INF/classes/log4j.isc.config.xml
          [8/13/13 8:01:23:701 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,701 [ : 0] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework - Initializing
          [8/13/13 8:01:23:717 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,717 [ : 0] INFO ConfigLoader - Attempting to load framework.properties from CLASSPATH
          [8/13/13 8:01:23:810 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,810 [ : 0] INFO ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: wsjar:file:/D:/rad8workspaces/rgalc-smartgwt/TestWeb/WebContent/WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties
          [8/13/13 8:01:23:810 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,810 [ : 0] INFO ConfigLoader - Attempting to load project.properties from CLASSPATH
          [8/13/13 8:01:23:810 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,810 [ : 0] INFO ConfigLoader - Unable to locate project.properties in CLASSPATH
          [8/13/13 8:01:23:810 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,810 [ : 0] INFO ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: wsjar:file:/D:/rad8workspaces/rgalc-smartgwt/TestWeb/WebContent/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
          [8/13/13 8:01:23:810 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,810 [ : 0] INFO ConfigLoader - Attempting to load server.properties from CLASSPATH
          [8/13/13 8:01:23:842 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,842 [ : 0] INFO ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/D:/rad8workspaces/rgalc-smartgwt/TestWeb/WebContent/WEB-INF/classes/server.properties
          [8/13/13 8:01:23:857 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,857 [ : 0] INFO Logger - Logging system started.
          [8/13/13 8:01:23:857 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,857 [ : 0] INFO ISCInit - Auto-detected webRoot - using: D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent
          [8/13/13 8:01:23:857 CDT] 0000000e SystemOut O === 2013-08-13 08:01:23,857 [ : 0] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework (v9.0p_2013-07-15/EVAL Deployment 2013-07-15) - Initialization Complete
          [8/13/13 8:01:23:951 CDT] 0000000e SystemOut O [Server@51305130]: [Thread[server.startup : 0,5,main]]: checkRunning(false) entered
          [8/13/13 8:01:23:951 CDT] 0000000e SystemOut O [Server@51305130]: [Thread[server.startup : 0,5,main]]: checkRunning(false) exited
          [8/13/13 8:01:23:951 CDT] 0000000e SystemOut O [Server@51305130]: Startup sequence initiated from main() method
          [8/13/13 8:01:23:951 CDT] 0000000e SystemOut O [Server@51305130]: Could not load properties from file
          [8/13/13 8:01:23:951 CDT] 0000000e SystemOut O [Server@51305130]: Using cli/default properties only
          [8/13/13 8:01:23:951 CDT] 00000018 SystemOut O [Server@51305130]: Initiating startup sequence...
          [8/13/13 8:01:23:951 CDT] 00000018 SystemOut O [Server@51305130]: Server socket opened successfully in 0 ms.
          [8/13/13 8:01:24:326 CDT] 00000018 SystemOut O [Server@51305130]: Database [index=0, id=0, db=file:D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\WEB-INF\db\hsqldb\isomorphic, alias=isomorphic] opened sucessfully in 375 ms.
          [8/13/13 8:01:24:326 CDT] 00000018 SystemOut O [Server@51305130]: Startup sequence completed in 375 ms.
          [8/13/13 8:01:24:326 CDT] 00000018 SystemOut O [Server@51305130]: 2013-08-13 08:01:24.326 HSQLDB server 2.2.9 is online on port 9001
          [8/13/13 8:01:24:326 CDT] 00000018 SystemOut O [Server@51305130]: To close normally, connect and execute SHUTDOWN SQL
          [8/13/13 8:01:24:326 CDT] 00000018 SystemOut O [Server@51305130]: From command line, use [Ctrl]+[C] to abort abruptly
          [8/13/13 8:01:24:373 CDT] 0000000e servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper init SRVE0242I: [SGWTEAR3] [/TestWeb] [Init]: Initialization successful.
          [8/13/13 8:01:24:373 CDT] 0000000e webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module TestWeb has been bound to default_host[*:9090,*:80,*:9453,*:5081,*:5080,*:443,*:9086,*:9449,*:5073,*:5072].
          [8/13/13 8:01:24:388 CDT] 0000000f webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: WebSphere ASYNC Response Servlet Application.
          [8/13/13 8:01:24:435 CDT] 0000000f WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key default_host/IBM_WS_SYS_RESPONSESERVLET
          [8/13/13 8:01:24:467 CDT] 0000000e ApplicationMg A WSVR0221I: Application started: SGWTEAR3
          [8/13/13 8:01:24:467 CDT] 0000000e CompositionUn A WSVR0191I: Composition unit WebSphere:cuname=SGWTEAR3 in BLA WebSphere:blaname=SGWTEAR3 started.
          [8/13/13 8:01:24:685 CDT] 0000000f servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper init SRVE0242I: [ibmasyncrsp] [/IBM_WS_SYS_RESPONSESERVLET] [rspservlet]: Initialization successful.
          [8/13/13 8:01:24:685 CDT] 0000000f webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module WebSphere ASYNC Response Servlet Application has been bound to default_host[*:9090,*:80,*:9453,*:5081,*:5080,*:443,*:9086,*:9449,*:5073,*:5072].
          [8/13/13 8:01:24:701 CDT] 0000000f ApplicationMg A WSVR0221I: Application started: ibmasyncrsp
          [8/13/13 8:01:24:701 CDT] 0000000f CompositionUn A WSVR0191I: Composition unit WebSphere:cuname=ibmasyncrsp in BLA WebSphere:blaname=ibmasyncrsp started.
          [8/13/13 8:01:25:857 CDT] 00000010 webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: isclite.
          [8/13/13 8:01:25:951 CDT] 00000010 WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key admin_host/ibm/console
          [8/13/13 8:01:29:576 CDT] 00000010 servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper init SRVE0242I: [isclite] [/ibm/console] [EventInitializer]: Initialization successful.
          [8/13/13 8:01:29:576 CDT] 00000010 webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module isclite has been bound to admin_host[*:9070,*:9053,*:9066,*:9049].
          [8/13/13 8:01:29:623 CDT] 00000010 webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: adminconsole redirector.
          [8/13/13 8:01:29:670 CDT] 00000010 WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key admin_host/admin
          [8/13/13 8:01:29:701 CDT] 00000010 webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module adminconsole redirector has been bound to admin_host[*:9070,*:9053,*:9066,*:9049].
          [8/13/13 8:01:29:716 CDT] 00000010 webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: EHS3.01.
          [8/13/13 8:01:29:748 CDT] 00000010 WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key admin_host/ibm/help
          [8/13/13 8:01:29:888 CDT] 00000010 webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module EHS3.01 has been bound to admin_host[*:9070,*:9053,*:9066,*:9049].
          [8/13/13 8:01:30:576 CDT] 00000010 webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: Integrated Solutions Console.
          [8/13/13 8:01:30:638 CDT] 00000010 WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key admin_host/ISCAdminPortlet
          [8/13/13 8:01:30:685 CDT] 00000010 MBeanDescript I ADMN1216I: One or more methods in Portlet mbean is excluded from access check.
          [8/13/13 8:01:30:685 CDT] 00000010 MBeanDescript I ADMN1216I: One or more methods in PortletApplication mbean is excluded from access check.
          [8/13/13 8:01:30:748 CDT] 00000010 webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module Integrated Solutions Console has been bound to admin_host[*:9070,*:9053,*:9066,*:9049].
          [8/13/13 8:01:31:576 CDT] 00000010 webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: WIM.
          [8/13/13 8:01:31:607 CDT] 00000010 WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key admin_host/wim
          [8/13/13 8:01:31:623 CDT] 00000010 webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module WIM has been bound to admin_host[*:9070,*:9053,*:9066,*:9049].
          [8/13/13 8:01:31:763 CDT] 00000010 webapp I com.ibm.ws.webcontainer.webapp.WebGroupImpl WebGroup SRVE0169I: Loading Web Module: WebSphere Application Server.
          [8/13/13 8:01:31:794 CDT] 00000010 WASSessionCor I SessionContextRegistry getSessionContext SESN0176I: Will create a new session context for application key admin_host/wasportlet
          [8/13/13 8:01:31:810 CDT] 00000010 webcontainer I com.ibm.ws.wswebcontainer.VirtualHost addWebApplication SRVE0250I: Web Module WebSphere Application Server has been bound to admin_host[*:9070,*:9053,*:9066,*:9049].
          [8/13/13 8:01:31:810 CDT] 00000010 ApplicationMg A WSVR0221I: Application started: isclite
          [8/13/13 8:01:31:810 CDT] 00000010 CompositionUn A WSVR0191I: Composition unit WebSphere:cuname=isclite in BLA WebSphere:blaname=isclite started.
          [8/13/13 8:01:31:857 CDT] 00000000 TCPChannel I TCPC0001I: TCP Channel TCP_1 is listening on host * (IPv4) port 9070.
          [8/13/13 8:01:31:873 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain WCInboundAdmin.
          [8/13/13 8:01:31:873 CDT] 00000000 TCPChannel I TCPC0001I: TCP Channel TCP_2 is listening on host * (IPv4) port 9090.
          [8/13/13 8:01:31:888 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain WCInboundDefault.
          [8/13/13 8:01:31:904 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain HttpQueueInboundDefault.
          [8/13/13 8:01:31:904 CDT] 00000000 TCPChannel I TCPC0001I: TCP Channel TCP_4 is listening on host * (IPv4) port 9453.
          [8/13/13 8:01:31:919 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain HttpQueueInboundDefaultSecure.
          [8/13/13 8:01:31:919 CDT] 00000000 TCPChannel I TCPC0001I: TCP Channel TCP_3 is listening on host * (IPv4) port 9053.
          [8/13/13 8:01:31:919 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain WCInboundAdminSecure.
          [8/13/13 8:01:31:935 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain WCInboundDefaultSecure.
          [8/13/13 8:01:31:935 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain SOAPAcceptorChain1.
          [8/13/13 8:01:31:935 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain SOAPAcceptorChain2.
          [8/13/13 8:01:31:935 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain SOAPAcceptorChain3.
          [8/13/13 8:01:31:951 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain SOAPAcceptorChain4.
          [8/13/13 8:01:31:951 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain SOAPAcceptorChain5.
          [8/13/13 8:01:31:951 CDT] 00000000 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain SOAPAcceptorChain6.
          [8/13/13 8:01:31:951 CDT] 0000000e SchedulerServ I SCHD0077I: The Scheduler Service is starting the Schedulers.
          [8/13/13 8:01:31:951 CDT] 0000000e SchedulerServ I SCHD0078I: The Scheduler Service has completed starting the Schedulers.
          [8/13/13 8:01:31:998 CDT] 00000000 RMIConnectorC A ADMC0026I: The RMI Connector is available at port 2819
          [8/13/13 8:01:32:060 CDT] 00000000 JMXConnectors I ADMC0058I: The JMX JSR160RMI connector is available at port 2819
          [8/13/13 8:01:32:388 CDT] 00000000 WsServerImpl A WSVR0001I: Server server1 open for e-business
          [8/13/13 8:01:32:451 CDT] 00000008 FfdcProvider W com.ibm.ws.ffdc.impl.FfdcProvider logIncident FFDC1003I: FFDC Incident emitted on D:\Program Files\IBM\SDP\runtimes\base_v7\profiles\rgalcprofile\logs\ffdc\server1_43fc43fc_13.08.13_08.01.32.3103300074522875740367.txt com.ibm.ws.management.AdminServiceImpl.invoke 679
          [8/13/13 9:09:22:587 CDT] 0000001c servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper init SRVE0242I: [SGWTEAR3] [/TestWeb] [DataSourceLoader]: Initialization successful.
          [8/13/13 9:09:22:977 CDT] 0000001c SystemOut O === 2013-08-13 09:09:22,977 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\builtinds\sc\system\schema\builtinTypes.xml: 109ms
          [8/13/13 9:09:23:040 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,040 [ : 1] INFO PoolManager - SmartClient pooling started for 'simpleTypes' objects
          [8/13/13 9:09:23:149 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,149 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\ds\supplyItem.ds.xml: 31ms
          [8/13/13 9:09:23:165 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,165 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\builtinds\sc\system\schema\DataSource.ds.xml: 16ms
          [8/13/13 9:09:23:227 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,227 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\builtinds\sc\system\schema\DataSourceField.ds.xml: 31ms
          [8/13/13 9:09:23:243 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,243 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\builtinds\sc\system\schema\ValueMap.ds.xml: 0ms
          [8/13/13 9:09:23:274 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,274 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\builtinds\sc\system\schema\Validator.ds.xml: 31ms
          [8/13/13 9:09:23:305 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,305 [ : 1] DEBUG DataSourceLoader - For DataSource 'supplyItem', got instanceId 10
          [8/13/13 9:09:23:337 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,337 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\ds\animals.ds.xml: 16ms
          [8/13/13 9:09:23:352 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,352 [ : 1] DEBUG DataSourceLoader - For DataSource 'animals', got instanceId 14
          [8/13/13 9:09:23:368 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,368 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\ds\employees.ds.xml: 16ms
          [8/13/13 9:09:23:383 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,383 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\builtinds\sc\system\schema\OperationBinding.ds.xml: 0ms
          [8/13/13 9:09:23:399 CDT] 0000001c SystemOut O === 2013-08-13 09:09:23,399 [ : 1] DEBUG DataSourceLoader - For DataSource 'employees', got instanceId 20
          [8/13/13 9:09:23:477 CDT] 0000001c webcontainer E com.ibm.ws.webcontainer.WebContainer handleRequest SRVE0255E: A WebGroup/Virtual Host to handle /favicon.ico has not been defined.
          [8/13/13 9:09:23:665 CDT] 0000001c webcontainer E com.ibm.ws.webcontainer.WebContainer handleRequest SRVE0255E: A WebGroup/Virtual Host to handle /favicon.ico has not been defined.
          [8/13/13 9:09:25:508 CDT] 0000001c webcontainer E com.ibm.ws.webcontainer.WebContainer handleRequest SRVE0255E: A WebGroup/Virtual Host to handle /favicon.ico has not been defined.
          [8/13/13 9:09:28:196 CDT] 0000001c servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper init SRVE0242I: [SGWTEAR3] [/TestWeb] [IDACall]: Initialization successful.
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Host:10.142.118.154:9090
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Content-Length:1006
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Origin:http://10.142.118.154:9090
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 5.1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.95 Safari/537.36
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Accept:*/*
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Referer:http://10.142.118.154:9090/TestWeb/BuiltInDS.html
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip,deflate,sdch
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.8
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - Header Name:Value pair: Cookie:JSESSIONID=0000fSIlxOmU19fAsRi4LbLrsSu:-1
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - session exists: fSIlxOmU19fAsRi4LbLrsSu
          [8/13/13 9:09:28:196 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,196 [ : 1] DEBUG IDACall - remote user: null
          [8/13/13 9:09:28:258 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,258 [ : 1] INFO RequestContext - URL: '/TestWeb/builtinds/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 5.1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.95 Safari/537.36': Safari with Accept-Encoding header
          [8/13/13 9:09:28:305 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,305 [ : 1] DEBUG XML - Parsed XML from (in memory stream): 16ms
          [8/13/13 9:09:28:305 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,305 [ : 1] DEBUG XML - Parsed XML from D:\rad8workspaces\rgalc-smartgwt\TestWeb\WebContent\builtinds\sc\system\schema\List.ds.xml: 0ms
          [8/13/13 9:09:28:305 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,305 [ : 1] DEBUG RPCManager - Processing 1 requests.
          [8/13/13 9:09:28:305 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,305 [ : 1] DEBUG DSRequest - Caching instance 14 of DS animals from DSRequest.getDataSource()
          [8/13/13 9:09:28:305 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,305 [ : 1] DEBUG RPCManager - Request #1 (DSRequest) payload: {
          criteria:{
          },
          operationConfig:{
          dataSource:"animals",
          operationType:"fetch",
          textMatchStyle:"exact"
          },
          startRow:0,
          endRow:75,
          componentId:"isc_ListGrid_1",
          appID:"builtinApplication",
          operation:"animals_fetch",
          oldValues:{
          }
          }
          [8/13/13 9:09:28:305 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,305 [ : 1] INFO IDACall - Performing 1 operation(s)
          [8/13/13 9:09:28:305 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,305 [ : 1] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
          [8/13/13 9:09:28:305 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,305 [ : 1] DEBUG DeclarativeSecurity - DataSource animals is not in the pre-checked list, processing...
          [8/13/13 9:09:28:321 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,321 [ : 1] DEBUG AppBase - [builtinApplication.animals_fetch] No userTypes defined, allowing anyone access to all operations for this application
          [8/13/13 9:09:28:321 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,321 [ : 1] DEBUG AppBase - [builtinApplication.animals_fetch] No public zero-argument method named '_animals_fetch' found, performing generic datasource operation
          [8/13/13 9:09:28:321 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,321 [ : 1] INFO SQLDataSource - [builtinApplication.animals_fetch] Performing fetch operation with
          criteria: {} values: {}
          [8/13/13 9:09:28:321 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,321 [ : 1] INFO SQLWhereClause - [builtinApplication.animals_fetch] empty condition
          [8/13/13 9:09:28:321 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,321 [ : 1] INFO SQLDataSource - [builtinApplication.animals_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
          [8/13/13 9:09:28:461 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,461 [ : 1] DEBUG SQLDataSource - [builtinApplication.animals_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause
          [8/13/13 9:09:28:461 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,461 [ : 1] DEBUG SQLDataSource - [builtinApplication.animals_fetch] Eval'd row count query: SELECT COUNT(*) FROM animals WHERE ('1'='1')
          [8/13/13 9:09:28:492 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,492 [ : 1] INFO PoolManager - [builtinApplication.animals_fetch] SmartClient pooling disabled for 'HSQLDB' objects
          [8/13/13 9:09:28:492 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,492 [ : 1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] Initializing SQL config for 'HSQLDB' from system config - using DriverManager: org.hsqldb.jdbcDriver
          [8/13/13 9:09:28:492 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,492 [ : 1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] org.hsqldb.jdbcDriver lookup successful
          [8/13/13 9:09:28:492 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,492 [ : 1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:hsql://localhost/isomorphic
          [8/13/13 9:09:28:492 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,492 [ : 1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] Passing JDBC URL only to getConnection
          [8/13/13 9:09:28:649 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,649 [ : 1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] makeObject() created an unpooled Connection '201853960'
          [8/13/13 9:09:28:649 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,649 [ : 1] DEBUG SQLConnectionManager - [builtinApplication.animals_fetch] Borrowed connection '201853960'
          [8/13/13 9:09:28:649 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,649 [ : 1] DEBUG SQLDriver - [builtinApplication.animals_fetch] About to execute SQL query in 'HSQLDB' using connection '201853960'
          [8/13/13 9:09:28:649 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,649 [ : 1] INFO SQLDriver - [builtinApplication.animals_fetch] Executing SQL query on 'HSQLDB': SELECT COUNT(*) FROM animals WHERE ('1'='1')
          [8/13/13 9:09:28:664 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,664 [ : 1] INFO SQLDriver - [builtinApplication.animals_fetch] Execute of select: SELECT COUNT(*) FROM animals WHERE ('1'='1') on db: HSQLDB threw exception: java.sql.SQLSyntaxErrorException: user lacks privilege or object not found: ANIMALS - assuming stale connection and retrying query.
          [8/13/13 9:09:28:664 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,664 [ : 1] DEBUG SQLConnectionManager - [builtinApplication.animals_fetch] About to close JDBCConnection with hashcode "201853960"
          [8/13/13 9:09:28:664 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,664 [ : 1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:hsql://localhost/isomorphic
          [8/13/13 9:09:28:664 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,664 [ : 1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] Passing JDBC URL only to getConnection
          [8/13/13 9:09:28:758 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,758 [ : 1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] makeObject() created an unpooled Connection '516824782'
          [8/13/13 9:09:28:758 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,758 [ : 1] WARN RequestContext - dsRequest.execute() failed:
          java.sql.SQLSyntaxErrorException: user lacks privilege or object not found: ANIMALS
          at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
          at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
          at org.hsqldb.jdbc.JDBCStatement.fetchResult(Unknown Source)
          at org.hsqldb.jdbc.JDBCStatement.executeQuery(Unknown Source)
          at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:656)
          at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:533)
          at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:526)
          at com.isomorphic.sql.SQLDriver.getScalarResult(SQLDriver.java:690)
          at com.isomorphic.sql.SQLDriver.executeScalar(SQLDriver.java:920)
          at com.isomorphic.sql.SQLDataSource.runRowCountQuery(SQLDataSource.java:2460)
          at com.isomorphic.sql.SQLDataSource.executeWindowedSelect(SQLDataSource.java:1884)
          at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1539)
          at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
          at com.isomorphic.sql.SQLDataSource.executeFetch(SQLDataSource.java:352)
          at com.isomorphic.datasource.DataSource.execute(DataSource.java:1444)
          at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:726)
          at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658)
          at com.isomorphic.application.AppBase.execute(AppBase.java:491)
          at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2412)
          at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:215)
          at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:172)
          at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:137)
          at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73)
          at javax.servlet.http.HttpServlet.service(HttpServlet.java:738)
          at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
          at javax.servlet.http.HttpServlet.service(HttpServlet.java:831)
          at com.ibm.ws.webcontainer.servlet.ServletWrapper.service(ServletWrapper.java:1657)
          at com.ibm.ws.webcontainer.servlet.ServletWrapper.handleRequest(ServletWrapper.java:939)
          at com.ibm.ws.webcontainer.servlet.ServletWrapper.handleRequest(ServletWrapper.java:502)
          at com.ibm.ws.webcontainer.servlet.ServletWrapperImpl.handleRequest(ServletWrapperImpl.java:179)
          at com.ibm.ws.webcontainer.webapp.WebApp.handleRequest(WebApp.java:3935)
          at com.ibm.ws.webcontainer.webapp.WebGroup.handleRequest(WebGroup.java:276)
          at com.ibm.ws.webcontainer.WebContainer.handleRequest(WebContainer.java:931)
          at com.ibm.ws.webcontainer.WSWebContainer.handleRequest(WSWebContainer.java:1583)
          at com.ibm.ws.webcontainer.channel.WCChannelLink.ready(WCChannelLink.java:186)
          at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.handleDiscrimination(HttpInboundLink.java:452)
          at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.handleNewRequest(HttpInboundLink.java:511)
          at com.ibm.ws.http.channel.inbound.impl.HttpInboundLink.processRequest(HttpInboundLink.java:305)
          at com.ibm.ws.http.channel.inbound.impl.HttpICLReadCallback.complete(HttpICLReadCallback.java:83)
          at com.ibm.ws.tcp.channel.impl.AioReadCompletionListener.futureCompleted(AioReadCompletionListener.java:165)
          at com.ibm.io.async.AbstractAsyncFuture.invokeCallback(AbstractAsyncFuture.java:217)
          at com.ibm.io.async.AsyncChannelFuture.fireCompletionActions(AsyncChannelFuture.java:161)
          at com.ibm.io.async.AsyncFuture.completed(AsyncFuture.java:138)
          at com.ibm.io.async.ResultHandler.complete(ResultHandler.java:204)
          at com.ibm.io.async.ResultHandler.runEventProcessingLoop(ResultHandler.java:775)
          at com.ibm.io.async.ResultHandler$2.run(ResultHandler.java:905)
          at com.ibm.ws.util.ThreadPool$Worker.run(ThreadPool.java:1604)
          [8/13/13 9:09:28:758 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,758 [ : 1] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
          [8/13/13 9:09:28:758 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,758 [ : 1] DEBUG RPCManager - non-DMI response, dropExtraFields: false
          [8/13/13 9:09:28:758 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,758 [ : 1] DEBUG SQLDriver - Freeing SQLDriver dbConnection 516824782
          [8/13/13 9:09:28:774 CDT] 0000001c SystemOut O === 2013-08-13 09:09:28,774 [ : 1] DEBUG SQLConnectionManager - About to close JDBCConnection with hashcode "516824782"

          Comment

            #6
            That's now far more logs than we asked for :)

            Note that 4.0p is not a full version number - as the forums tell you every time you post, the full version includes a date stamp.

            As far as the issue you're having, it suggests that somehow your HSQL instance just isn't loading the files where the tables are. You might have forgotten to deploy them, or they might be unreadable.

            Either way, nothing here suggests an issue beyond missing sample data, so we'd advise you to just proceed with integrating with whatever DB or Java logic is the actual target.

            If you want to see the sample running for some reason, you can use the Admin Console to create the sample data tables and populate them with data.

            Comment

              #7
              ok. thanks.

              Comment

              Previous template Next
              Working...
              X