Concurrency errors using server-side datasource

alius replied

3 Oct 2013, 02:29
Hi,

I did run following :

10 test runs for each "pooling on" and "pooling off", 100 thread pools, 100 threads, Thread.sleep(500) after each thread pool. Got no exceptions.

Regards,
Alius.
Leave a comment:
agalataud replied

2 Oct 2013, 22:54
Hi,

Thanks a lot for the fix and keeping me posted.
May I ask you if the fix corrects 'all' cases? Meaning I was able to reproduce even with DataSource pooling, is it also fixed in that case?

Regards
Leave a comment:
alius replied

2 Oct 2013, 18:20
Hi,

Issue is solved.
Try next nightly build.

Alius.
Leave a comment:

agalataud replied

24 Sep 2013, 00:28

About datasource pooling: I tried to activate it with

Code:

datasources.pool.enabled: true

Still gives me concurrency exceptions:

Code:

java.util.ConcurrentModificationException
	at java.util.Hashtable$Enumerator.next(Hashtable.java:1031)
	at java.util.HashMap.putAll(HashMap.java:523)
	at com.isomorphic.datasource.DataSource.getRelation(DataSource.java:3749)
	at com.isomorphic.datasource.DataSource.getRelation(DataSource.java:3730)
	at com.isomorphic.datasource.DataSource.getIncludeFromInfo(DataSource.java:4284)
	at com.isomorphic.datasource.BasicDataSource.init(BasicDataSource.java:511)
	at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:242)
	at com.isomorphic.datasource.DataSource.initialize(DataSource.java:452)
	at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:166)
	at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:437)
	at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:228)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:220)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:209)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:130)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:138)
	at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:1179)
	at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:95)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
	at com.isomorphic.datasource.DataSourceManager.get(DataSourceManager.java:118)
	at com.isomorphic.datasource.DataSource.getIncludeFromInfo(DataSource.java:4263)
	at com.isomorphic.datasource.BasicDataSource.init(BasicDataSource.java:511)
	at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:242)
	at com.isomorphic.datasource.DataSource.initialize(DataSource.java:452)
	at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:166)
	at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:437)
	at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:228)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:220)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:209)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:130)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:138)
	at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:1179)
	at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:95)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
	at com.isomorphic.datasource.DataSourceManager.get(DataSourceManager.java:118)
	at com.isomorphic.datasource.Relation.create(Relation.java:64)
	at com.isomorphic.datasource.IncludeFromDefinition.create(IncludeFromDefinition.java:82)
	at com.isomorphic.datasource.DSRequest.buildIncludeFromDefinitions(DSRequest.java:4303)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2266)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2431)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:63)
	at com.test.DSTestCase.access$0(DSTestCase.java:51)
	at com.test.DSTestCase$1.run(DSTestCase.java:45)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)

Last edited by agalataud; 24 Sep 2013, 00:32. Reason: can't attach full log (size too big)

Leave a comment:

Isomorphic replied

24 Sep 2013, 00:12
These properties control the pooling of database connections. We were referring to the pooling of DataSource instances. See the discussion of "datasources.pool" here
Leave a comment:

agalataud replied

23 Sep 2013, 23:09

Hi,

Here is my configuration for this test case (server.properties):

Code:

webRoot: C:\Users\agalataud\workspace\main\fatca\webapp\src\main\webapp\


gwtModuleName: application

sql.FIRCO.database.type: sqlserver
sql.FIRCO.interface.type: driverManager
sql.FIRCO.driver: com.microsoft.sqlserver.jdbc.SQLServerDriver
sql.FIRCO.driver.url: jdbc:sqlserver://127.0.0.1:1433;DatabaseName=FIRCO_CDB_V100;User=firco;Password=firco
sql.FIRCO.useGlobalIdentity = true

There is nowhere said that pooling is disabled.
In smartgwt generated logs I see traces like that:

Code:

=== 2013-09-24 09:01:14,386 [ad-6] INFO  PoolManager - [builtinApplication.null] SmartClient pooling started for 'FIRCO' objects

Code:

=== 2013-09-24 09:01:15,448 [ad-9] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '1280543092'
=== 2013-09-24 09:01:15,448 [ad-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '6637010'
=== 2013-09-24 09:01:15,448 [ad-5] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '6637010'
=== 2013-09-24 09:01:15,448 [ad-3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '6848169'

So I guess pooling is enabled here.

However, in the contained application, I intentionally disable pooling with following configuration:

Code:

sql.defaultDatabase: FIRCO
sql.FIRCO.driver.name: jdbc/fircocdb
sql.FIRCO.database.type: sqlserver
sql.FIRCO.interface.type: jndi
sql.FIRCO.useGlobalIdentity: true
sql.pool.enabled: false

Pooling should be managed by the JNDI resource;

Leave a comment:

Isomorphic replied

23 Sep 2013, 11:01
An update on this - we finally managed to reproduce this, but only if DataSource pooling is turned off.

We'll be looking at addressing this anyway, but, why do you have DataSource pooling turned off? This slows everything down - a *lot* in some cases.
Leave a comment:
agalataud replied

20 Sep 2013, 03:39
Here it is:

java version "1.6.0_45"
Java(TM) SE Runtime Environment (build 1.6.0_45-b06)
Java HotSpot(TM) 64-Bit Server VM (build 20.45-b01, mixed mode)
Leave a comment:
alius replied

20 Sep 2013, 03:00
Hi,

Just in case: can you tell me what java version do you use?
Please post output of following:

Code:

java -version

Alius
Leave a comment:

agalataud replied

20 Sep 2013, 01:06

Hi,

Thanks for giving my test case a try. I modified it a bit to see how often I reproduce it and validate it's a race condition (not happening every time on mys side).

Code:

while (true)
{
	ThreadPoolExecutor executor = new ThreadPoolExecutor(10, 10, 1, TimeUnit.MINUTES, new ArrayBlockingQueue<Runnable>(10, true));
	for (Runnable runnable : createJobs())
		executor.execute(runnable);

	Thread.sleep(1000);
}

Here is a log snapshot of execution:

Code:

executeDSRequest: pool-12-thread-1
executeDSRequest: pool-12-thread-2
executeDSRequest: pool-12-thread-4
executeDSRequest: pool-12-thread-5
executeDSRequest: pool-12-thread-6
executeDSRequest: pool-12-thread-10
executeDSRequest: pool-12-thread-8
executeDSRequest: pool-12-thread-7
executeDSRequest: pool-12-thread-3
executeDSRequest: pool-12-thread-9
java.lang.NullPointerException
	at com.isomorphic.sql.SQLJoinWhereClause.buildExpression(SQLJoinWhereClause.java:190)
	at com.isomorphic.sql.SQLJoinWhereClause.getSQLString(SQLJoinWhereClause.java:138)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2614)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
executeDSRequest: pool-13-thread-1
executeDSRequest: pool-13-thread-3
executeDSRequest: pool-13-thread-4
executeDSRequest: pool-13-thread-5
executeDSRequest: pool-13-thread-7
executeDSRequest: pool-13-thread-2
executeDSRequest: pool-13-thread-10
executeDSRequest: pool-13-thread-6
executeDSRequest: pool-13-thread-8
executeDSRequest: pool-13-thread-9
executeDSRequest: pool-14-thread-1
executeDSRequest: pool-14-thread-2
executeDSRequest: pool-14-thread-3
executeDSRequest: pool-14-thread-5
executeDSRequest: pool-14-thread-4
executeDSRequest: pool-14-thread-6
executeDSRequest: pool-14-thread-7
executeDSRequest: pool-14-thread-8
executeDSRequest: pool-14-thread-10
executeDSRequest: pool-14-thread-9
executeDSRequest: pool-15-thread-1
executeDSRequest: pool-15-thread-2
executeDSRequest: pool-15-thread-3
executeDSRequest: pool-15-thread-4
executeDSRequest: pool-15-thread-5
executeDSRequest: pool-15-thread-8
executeDSRequest: pool-15-thread-9
executeDSRequest: pool-15-thread-6
executeDSRequest: pool-15-thread-10
executeDSRequest: pool-15-thread-7
executeDSRequest: pool-16-thread-1
executeDSRequest: pool-16-thread-2
executeDSRequest: pool-16-thread-3
executeDSRequest: pool-16-thread-4
executeDSRequest: pool-16-thread-7
executeDSRequest: pool-16-thread-8
executeDSRequest: pool-16-thread-6
executeDSRequest: pool-16-thread-10
executeDSRequest: pool-16-thread-5
executeDSRequest: pool-16-thread-9
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
executeDSRequest: pool-17-thread-1
executeDSRequest: pool-17-thread-2
executeDSRequest: pool-17-thread-3
executeDSRequest: pool-17-thread-4
executeDSRequest: pool-17-thread-8
executeDSRequest: pool-17-thread-7
executeDSRequest: pool-17-thread-6
executeDSRequest: pool-17-thread-5
executeDSRequest: pool-17-thread-10
executeDSRequest: pool-17-thread-9
java.util.ConcurrentModificationException
	at java.util.Hashtable$Enumerator.next(Hashtable.java:1031)
	at java.util.HashMap.putAll(HashMap.java:523)
	at com.isomorphic.datasource.DataSource.getRelation(DataSource.java:3742)
	at com.isomorphic.datasource.DataSource.getRelation(DataSource.java:3723)
	at com.isomorphic.datasource.DataSource.getIncludeFromInfo(DataSource.java:4277)
	at com.isomorphic.datasource.BasicDataSource.init(BasicDataSource.java:511)
	at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:242)
	at com.isomorphic.datasource.DataSource.initialize(DataSource.java:452)
	at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:166)
	at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:437)
	at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:228)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:220)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:209)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:130)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:138)
	at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:1179)
	at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:95)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
	at com.isomorphic.datasource.DataSourceManager.get(DataSourceManager.java:118)
	at com.isomorphic.datasource.DataSource.getIncludeFromInfo(DataSource.java:4256)
	at com.isomorphic.datasource.BasicDataSource.init(BasicDataSource.java:511)
	at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:242)
	at com.isomorphic.datasource.DataSource.initialize(DataSource.java:452)
	at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:166)
	at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:437)
	at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:228)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:220)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:209)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:130)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:138)
	at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:1179)
	at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:95)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
	at com.isomorphic.datasource.DSRequest.getDataSource(DSRequest.java:2073)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2254)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
executeDSRequest: pool-18-thread-1
executeDSRequest: pool-18-thread-2
executeDSRequest: pool-18-thread-3
executeDSRequest: pool-18-thread-5
executeDSRequest: pool-18-thread-6
executeDSRequest: pool-18-thread-4
executeDSRequest: pool-18-thread-8
executeDSRequest: pool-18-thread-9
executeDSRequest: pool-18-thread-10
executeDSRequest: pool-18-thread-7
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
executeDSRequest: pool-19-thread-1
executeDSRequest: pool-19-thread-2
executeDSRequest: pool-19-thread-3
executeDSRequest: pool-19-thread-4
executeDSRequest: pool-19-thread-6
executeDSRequest: pool-19-thread-5
executeDSRequest: pool-19-thread-8
executeDSRequest: pool-19-thread-9
executeDSRequest: pool-19-thread-10
executeDSRequest: pool-19-thread-7
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
java.lang.NullPointerException
	at com.isomorphic.sql.SQLTableClause.getSQLString(SQLTableClause.java:127)
	at com.isomorphic.sql.SQLDataSource.getClausesContext(SQLDataSource.java:2525)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1426)
	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:723)
	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:2419)
	at com.fircosoft.cdb.server.audit.AuditLogServerObject.fetch(AuditLogServerObject.java:28)
	at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.isomorphic.base.Reflection.adaptArgsAndInvoke(Reflection.java:972)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:416)
	at com.isomorphic.datasource.DataSourceDMI.execute(DataSourceDMI.java:64)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2415)
	at com.isomorphic.sql.SQLDataSource.getLastRow(SQLDataSource.java:655)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1664)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
	at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
	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:2419)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:67)
	at com.test.DSTestCase.access$0(DSTestCase.java:55)
	at com.test.DSTestCase$1.run(DSTestCase.java:49)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
executeDSRequest: pool-20-thread-1
executeDSRequest: pool-20-thread-2
executeDSRequest: pool-20-thread-3
executeDSRequest: pool-20-thread-4
executeDSRequest: pool-20-thread-7
executeDSRequest: pool-20-thread-8
executeDSRequest: pool-20-thread-9
executeDSRequest: pool-20-thread-6
executeDSRequest: pool-20-thread-5
executeDSRequest: pool-20-thread-10

Regards

Leave a comment:

alius replied

18 Sep 2013, 13:46

Hi,

I was not able to reproduce an exception.
I've even increased job count to 20 - still no exception.

Here is a log for 10 jobs (looks normal) {

Code:

executeDSRequest: pool-75-thread-1
executeDSRequest: pool-75-thread-2
executeDSRequest: pool-75-thread-3
executeDSRequest: pool-75-thread-4
executeDSRequest: pool-75-thread-5
executeDSRequest: pool-75-thread-6
executeDSRequest: pool-75-thread-7
executeDSRequest: pool-75-thread-8
executeDSRequest: pool-75-thread-9
executeDSRequest: pool-75-thread-10
=== 2013-09-19 00:44:22,178 [ad-2] DEBUG XML - Parsed XML from /home/alius/proj/gwt_hello_world/build/web/ds/auditLog.ds.xml: 2ms
=== 2013-09-19 00:44:22,204 [ad-2] DEBUG XML - Parsed XML from /home/alius/proj/gwt_hello_world/build/web/ds/operator.ds.xml: 1ms
=== 2013-09-19 00:44:22,212 [ad-2] DEBUG DSRequest - Caching instance 18 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,214 [ad-2] DEBUG XML - Parsed XML from /home/alius/proj/gwt_hello_world/build/web/ds/eventDescriptor.ds.xml: 1ms
=== 2013-09-19 00:44:22,219 [ad-2] DEBUG XML - Parsed XML from /home/alius/proj/gwt_hello_world/build/web/JPADS_js/sc/system/schema/ValueMap.ds.xml: 1ms
=== 2013-09-19 00:44:22,220 [ad-2] DEBUG DSRequest - Caching instance 23 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,221 [ad-2] DEBUG DSRequest - Caching instance 15 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,223 [d-10] DEBUG DSRequest - Caching instance 25 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,224 [d-10] DEBUG DSRequest - Caching instance 26 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,224 [d-10] DEBUG DSRequest - Caching instance 24 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,224 [d-10] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,224 [ad-2] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,225 [ad-2] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,225 [d-10] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,226 [ad-2] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,226 [d-10] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,227 [ad-9] DEBUG DSRequest - Caching instance 28 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,227 [ad-2] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662225)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662225)}
=== 2013-09-19 00:44:22,227 [d-10] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662225)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662225)}
=== 2013-09-19 00:44:22,228 [ad-9] DEBUG DSRequest - Caching instance 29 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,228 [ad-9] DEBUG DSRequest - Caching instance 27 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,228 [ad-9] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,228 [ad-9] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,228 [ad-9] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,229 [ad-8] DEBUG DSRequest - Caching instance 31 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,229 [ad-9] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662228)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662228)}
=== 2013-09-19 00:44:22,230 [ad-8] DEBUG DSRequest - Caching instance 32 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,231 [d-10] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,231 [ad-2] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,231 [ad-9] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,231 [ad-8] DEBUG DSRequest - Caching instance 30 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,231 [ad-8] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,231 [ad-8] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,231 [ad-8] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,232 [ad-7] DEBUG DSRequest - Caching instance 34 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,232 [ad-8] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662231)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662231)}
=== 2013-09-19 00:44:22,232 [ad-7] DEBUG DSRequest - Caching instance 35 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,232 [ad-8] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,233 [ad-7] DEBUG DSRequest - Caching instance 33 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,233 [ad-7] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,233 [ad-7] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,233 [ad-7] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,234 [ad-7] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662233)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662233)}
=== 2013-09-19 00:44:22,234 [ad-7] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,236 [ad-6] DEBUG DSRequest - Caching instance 37 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,237 [ad-6] DEBUG DSRequest - Caching instance 38 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,238 [ad-6] DEBUG DSRequest - Caching instance 36 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,239 [ad-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,239 [ad-6] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,239 [ad-6] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,240 [ad-5] DEBUG DSRequest - Caching instance 40 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,241 [ad-6] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662239)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662239)}
=== 2013-09-19 00:44:22,241 [ad-5] DEBUG DSRequest - Caching instance 41 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,241 [ad-6] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,241 [ad-5] DEBUG DSRequest - Caching instance 39 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,242 [ad-5] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,242 [ad-5] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,242 [ad-5] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,243 [ad-4] DEBUG DSRequest - Caching instance 43 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,244 [ad-5] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662242)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662242)}
=== 2013-09-19 00:44:22,244 [ad-5] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,244 [ad-4] DEBUG DSRequest - Caching instance 44 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,244 [ad-4] DEBUG DSRequest - Caching instance 42 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,245 [ad-4] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,245 [ad-4] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,245 [ad-4] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,245 [ad-4] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662245)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662245)}
=== 2013-09-19 00:44:22,245 [ad-4] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,246 [ad-3] DEBUG DSRequest - Caching instance 46 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,246 [ad-3] DEBUG DSRequest - Caching instance 47 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,247 [ad-3] DEBUG DSRequest - Caching instance 45 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,247 [ad-3] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,247 [ad-3] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,247 [ad-3] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,247 [ad-1] DEBUG DSRequest - Caching instance 49 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,248 [ad-3] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662247)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662247)}
=== 2013-09-19 00:44:22,248 [ad-1] DEBUG DSRequest - Caching instance 50 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,248 [ad-3] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,248 [ad-1] DEBUG DSRequest - Caching instance 48 of DS auditLog from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,249 [ad-1] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,249 [ad-1] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,249 [ad-1] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,249 [ad-1] INFO  SQLDataSource - [builtinApplication.null] Performing add operation with
	criteria: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662249)}	values: {CREATED_BY:302,EVENT_ID:2101,RELOBJ_ID:1,RELOBJ_TYPE:"",RELOBJ_LABEL:"",DATA:"",CREATED:new Date(1379540662249)}
=== 2013-09-19 00:44:22,250 [ad-1] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {ID=__default}
=== 2013-09-19 00:44:22,302 [d-10] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,303 [ad-1] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,303 [ad-3] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,303 [ad-4] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,304 [ad-5] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,304 [ad-6] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,304 [ad-7] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,304 [ad-9] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,305 [ad-2] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,305 [ad-8] INFO  SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-09-19 00:44:22,315 [d-10] INFO  PoolManager - [builtinApplication.null] SmartClient pooling started for 'PostgreSQL' objects
=== 2013-09-19 00:44:22,316 [ad-7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Initializing SQL config for 'PostgreSQL' from system config - using DriverManager:  org.postgresql.Driver
=== 2013-09-19 00:44:22,316 [ad-7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] org.postgresql.Driver lookup successful
=== 2013-09-19 00:44:22,316 [ad-7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,316 [ad-7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,317 [ad-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,317 [ad-1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,317 [ad-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,317 [ad-1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,317 [ad-9] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,317 [ad-9] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,317 [ad-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,317 [ad-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,316 [ad-3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,317 [ad-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,316 [ad-5] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,317 [ad-5] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,317 [ad-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,317 [ad-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,318 [ad-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,317 [d-10] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for PostgreSQL via jdbc url jdbc:postgresql://localhost:5432/isomorphic?user=root&password=root
=== 2013-09-19 00:44:22,317 [ad-3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,318 [d-10] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2013-09-19 00:44:22,320 [ad-7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '354462166'
=== 2013-09-19 00:44:22,320 [ad-3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '325987184'
=== 2013-09-19 00:44:22,320 [ad-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '635817991'
=== 2013-09-19 00:44:22,320 [ad-1] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '314347265'
=== 2013-09-19 00:44:22,320 [ad-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '424711235'
=== 2013-09-19 00:44:22,321 [ad-5] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '2033567664'
=== 2013-09-19 00:44:22,322 [ad-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '1206940512'
=== 2013-09-19 00:44:22,323 [ad-9] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '2066139269'
=== 2013-09-19 00:44:22,323 [d-10] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '792122093'
=== 2013-09-19 00:44:22,323 [ad-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '944889298'
=== 2013-09-19 00:44:22,323 [ad-6] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '1206940512'
=== 2013-09-19 00:44:22,323 [d-10] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '792122093'
=== 2013-09-19 00:44:22,323 [ad-4] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '424711235'
=== 2013-09-19 00:44:22,323 [d-10] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'792122093'
=== 2013-09-19 00:44:22,323 [ad-6] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'1206940512'
=== 2013-09-19 00:44:22,323 [ad-7] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '354462166'
=== 2013-09-19 00:44:22,323 [ad-5] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '2033567664'
=== 2013-09-19 00:44:22,323 [ad-5] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'2033567664'
=== 2013-09-19 00:44:22,324 [ad-5] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,323 [ad-3] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '325987184'
=== 2013-09-19 00:44:22,323 [ad-2] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '944889298'
=== 2013-09-19 00:44:22,324 [ad-2] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'944889298'
=== 2013-09-19 00:44:22,324 [ad-2] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,323 [ad-9] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '2066139269'
=== 2013-09-19 00:44:22,323 [ad-8] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '635817991'
=== 2013-09-19 00:44:22,323 [ad-1] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '314347265'
=== 2013-09-19 00:44:22,324 [ad-8] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'635817991'
=== 2013-09-19 00:44:22,324 [ad-8] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,324 [ad-9] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'2066139269'
=== 2013-09-19 00:44:22,324 [ad-3] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'325987184'
=== 2013-09-19 00:44:22,324 [ad-3] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,323 [ad-7] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'354462166'
=== 2013-09-19 00:44:22,323 [ad-6] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,323 [d-10] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,323 [ad-4] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'424711235'
=== 2013-09-19 00:44:22,324 [ad-7] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,324 [ad-9] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,324 [ad-1] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'PostgreSQL' using connection'314347265'
=== 2013-09-19 00:44:22,324 [ad-4] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,324 [ad-1] INFO  SQLDriver - [builtinApplication.null] Executing SQL update on 'PostgreSQL': INSERT INTO AUD_APPLICATION_LOG (CREATED, CREATED_BY, DATA, EVENT_ID, RELOBJ_ID, RELOBJ_LABEL, RELOBJ_TYPE, ID) VALUES ('2013-09-19 00:44:22', 302, '', 2101, 1, '', '', nextval('AUD_APPLICATION_LOG_ID_seq'))
=== 2013-09-19 00:44:22,328 [ad-5] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,328 [ad-5] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '2033567664'
=== 2013-09-19 00:44:22,328 [ad-5] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,328 [ad-4] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,328 [ad-8] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,328 [ad-7] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,328 [ad-4] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '424711235'
=== 2013-09-19 00:44:22,328 [ad-8] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '635817991'
=== 2013-09-19 00:44:22,328 [ad-8] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,328 [ad-6] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,328 [ad-9] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,328 [ad-4] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,329 [ad-6] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '1206940512'
=== 2013-09-19 00:44:22,329 [ad-6] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,328 [ad-7] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '354462166'
=== 2013-09-19 00:44:22,328 [ad-1] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,328 [ad-2] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,328 [ad-3] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,328 [d-10] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-09-19 00:44:22,329 [ad-3] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '325987184'
=== 2013-09-19 00:44:22,329 [ad-1] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '314347265'
=== 2013-09-19 00:44:22,329 [d-10] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '792122093'
=== 2013-09-19 00:44:22,329 [ad-7] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,329 [ad-9] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '2066139269'
=== 2013-09-19 00:44:22,329 [d-10] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,329 [ad-1] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,329 [ad-3] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,329 [ad-9] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,330 [ad-2] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '944889298'
=== 2013-09-19 00:44:22,330 [ad-2] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT currval('AUD_APPLICATION_LOG_ID_seq')
=== 2013-09-19 00:44:22,339 [ad-2] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=160}
=== 2013-09-19 00:44:22,339 [ad-6] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=156}
=== 2013-09-19 00:44:22,339 [d-10] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=153}
=== 2013-09-19 00:44:22,339 [ad-5] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=151}
=== 2013-09-19 00:44:22,339 [ad-4] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=152}
=== 2013-09-19 00:44:22,339 [ad-9] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=157}
=== 2013-09-19 00:44:22,339 [ad-3] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=154}
=== 2013-09-19 00:44:22,339 [ad-7] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=159}
=== 2013-09-19 00:44:22,339 [ad-1] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=158}
=== 2013-09-19 00:44:22,339 [ad-8] INFO  SQLDataSource - [builtinApplication.null] primaryKeys: {ID=155}
=== 2013-09-19 00:44:22,340 [d-10] DEBUG DSRequest - [builtinApplication.null] Caching instance 52 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,340 [d-10] DEBUG DSRequest - [builtinApplication.null] Caching instance 53 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,341 [d-10] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,341 [d-10] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,341 [d-10] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,341 [d-10] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,341 [ad-8] DEBUG DSRequest - [builtinApplication.null] Caching instance 55 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,342 [d-10] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:153}	values: {ID:153}
=== 2013-09-19 00:44:22,342 [ad-8] DEBUG DSRequest - [builtinApplication.null] Caching instance 56 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,342 [ad-8] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,343 [ad-8] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,343 [ad-8] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,343 [ad-8] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,343 [ad-8] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:155}	values: {ID:155}
=== 2013-09-19 00:44:22,343 [ad-1] DEBUG DSRequest - [builtinApplication.null] Caching instance 58 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,344 [ad-1] DEBUG DSRequest - [builtinApplication.null] Caching instance 59 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,344 [ad-1] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,345 [ad-1] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,345 [ad-1] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,345 [ad-1] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,345 [ad-1] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:158}	values: {ID:158}
=== 2013-09-19 00:44:22,346 [ad-7] DEBUG DSRequest - [builtinApplication.null] Caching instance 61 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,346 [ad-7] DEBUG DSRequest - [builtinApplication.null] Caching instance 62 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,346 [ad-7] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,347 [ad-7] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,347 [ad-7] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,347 [ad-7] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,347 [ad-7] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:159}	values: {ID:159}
=== 2013-09-19 00:44:22,347 [ad-3] DEBUG DSRequest - [builtinApplication.null] Caching instance 64 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,348 [ad-3] DEBUG DSRequest - [builtinApplication.null] Caching instance 65 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,348 [ad-3] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,348 [ad-3] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,348 [ad-3] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,349 [ad-3] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,349 [ad-3] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:154}	values: {ID:154}
=== 2013-09-19 00:44:22,349 [ad-8] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,349 [ad-7] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,349 [d-10] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,349 [ad-1] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,350 [ad-9] DEBUG DSRequest - [builtinApplication.null] Caching instance 67 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,350 [ad-3] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,350 [ad-8] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 30: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=155) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,350 [ad-8] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '635817991'
=== 2013-09-19 00:44:22,350 [ad-8] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=155) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,350 [ad-1] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 48: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=158) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,350 [ad-1] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '314347265'
=== 2013-09-19 00:44:22,350 [ad-1] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=158) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,350 [d-10] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 24: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=153) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,351 [d-10] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '792122093'
=== 2013-09-19 00:44:22,351 [ad-9] DEBUG DSRequest - [builtinApplication.null] Caching instance 68 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,351 [d-10] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=153) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,351 [ad-7] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 33: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=159) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,351 [ad-7] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '354462166'
=== 2013-09-19 00:44:22,351 [ad-7] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=159) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,351 [ad-3] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 45: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=154) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,351 [ad-3] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '325987184'
=== 2013-09-19 00:44:22,351 [ad-3] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=154) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,352 [ad-1] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,352 [ad-1] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,352 [d-10] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,353 [ad-9] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,353 [ad-1] DEBUG SQLDriver - Freeing SQLDriver dbConnection 314347265
=== 2013-09-19 00:44:22,353 [d-10] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,353 [ad-1] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "314347265"
=== 2013-09-19 00:44:22,353 [ad-8] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,353 [d-10] DEBUG SQLDriver - Freeing SQLDriver dbConnection 792122093
=== 2013-09-19 00:44:22,353 [ad-9] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,353 [ad-8] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,353 [d-10] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "792122093"
=== 2013-09-19 00:44:22,353 [ad-7] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,353 [ad-4] DEBUG DSRequest - [builtinApplication.null] Caching instance 70 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,353 [ad-8] DEBUG SQLDriver - Freeing SQLDriver dbConnection 635817991
=== 2013-09-19 00:44:22,353 [ad-3] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,353 [ad-9] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,354 [ad-8] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "635817991"
=== 2013-09-19 00:44:22,354 [ad-3] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,354 [ad-7] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,354 [ad-9] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,354 [ad-7] DEBUG SQLDriver - Freeing SQLDriver dbConnection 354462166
=== 2013-09-19 00:44:22,354 [ad-3] DEBUG SQLDriver - Freeing SQLDriver dbConnection 325987184
=== 2013-09-19 00:44:22,354 [ad-7] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "354462166"
=== 2013-09-19 00:44:22,354 [ad-3] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "325987184"
=== 2013-09-19 00:44:22,354 [ad-4] DEBUG DSRequest - [builtinApplication.null] Caching instance 71 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,355 [ad-9] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:157}	values: {ID:157}
=== 2013-09-19 00:44:22,355 [ad-4] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,355 [ad-4] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,355 [ad-4] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,355 [ad-4] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,355 [ad-9] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,355 [ad-4] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:152}	values: {ID:152}
=== 2013-09-19 00:44:22,356 [ad-5] DEBUG DSRequest - [builtinApplication.null] Caching instance 73 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,356 [ad-4] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,356 [ad-9] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 27: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=157) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,356 [ad-9] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '2066139269'
=== 2013-09-19 00:44:22,356 [ad-9] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=157) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,356 [ad-4] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 42: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=152) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,357 [ad-4] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '424711235'
=== 2013-09-19 00:44:22,357 [ad-5] DEBUG DSRequest - [builtinApplication.null] Caching instance 74 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,357 [ad-4] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=152) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,357 [ad-5] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,357 [ad-5] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,357 [ad-5] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,358 [ad-5] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,358 [ad-9] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,358 [ad-9] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,358 [ad-9] DEBUG SQLDriver - Freeing SQLDriver dbConnection 2066139269
=== 2013-09-19 00:44:22,358 [ad-5] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:151}	values: {ID:151}
=== 2013-09-19 00:44:22,358 [ad-9] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "2066139269"
=== 2013-09-19 00:44:22,359 [ad-6] DEBUG DSRequest - [builtinApplication.null] Caching instance 76 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,359 [ad-4] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,359 [ad-5] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,359 [ad-4] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,360 [ad-4] DEBUG SQLDriver - Freeing SQLDriver dbConnection 424711235
=== 2013-09-19 00:44:22,360 [ad-4] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "424711235"
=== 2013-09-19 00:44:22,360 [ad-5] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 39: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=151) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,360 [ad-5] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '2033567664'
=== 2013-09-19 00:44:22,360 [ad-5] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=151) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,361 [ad-6] DEBUG DSRequest - [builtinApplication.null] Caching instance 77 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,361 [ad-6] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,362 [ad-6] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,362 [ad-6] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,362 [ad-5] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,362 [ad-6] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,362 [ad-5] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,362 [ad-5] DEBUG SQLDriver - Freeing SQLDriver dbConnection 2033567664
=== 2013-09-19 00:44:22,362 [ad-5] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "2033567664"
=== 2013-09-19 00:44:22,363 [ad-6] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:156}	values: {ID:156}
=== 2013-09-19 00:44:22,363 [ad-2] DEBUG DSRequest - [builtinApplication.null] Caching instance 79 of DS operator from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,364 [ad-6] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,364 [ad-2] DEBUG DSRequest - [builtinApplication.null] Caching instance 80 of DS eventDescriptor from DSRequest.getDataSource()
=== 2013-09-19 00:44:22,364 [ad-6] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 36: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=156) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,364 [ad-6] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '1206940512'
=== 2013-09-19 00:44:22,364 [ad-6] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=156) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,364 [ad-2] DEBUG Relation - [builtinApplication.null] Caching instance of fromDS 'auditLog' in the DSRequest map
=== 2013-09-19 00:44:22,364 [ad-2] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-09-19 00:44:22,365 [ad-2] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-09-19 00:44:22,365 [ad-2] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-09-19 00:44:22,365 [ad-2] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
	criteria: {ID:160}	values: {ID:160}
=== 2013-09-19 00:44:22,366 [ad-2] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause AND $defaultJoinWhereClause
=== 2013-09-19 00:44:22,366 [ad-6] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,366 [ad-6] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,366 [ad-6] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1206940512
=== 2013-09-19 00:44:22,366 [ad-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1206940512"
=== 2013-09-19 00:44:22,366 [ad-2] INFO  SQLDataSource - [builtinApplication.null, builtinApplication.null] 15: Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=160) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,366 [ad-2] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'PostgreSQL' using connection '944889298'
=== 2013-09-19 00:44:22,366 [ad-2] INFO  SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'PostgreSQL': SELECT AUD_APPLICATION_LOG.CREATED, AUD_APPLICATION_LOG.CREATED_BY, AUD_APPLICATION_LOG.DATA, AUD_APPLICATION_LOG.EVENT_ID, AUD_APPLICATION_LOG.ID, AUD_APPLICATION_LOG.RELOBJ_ID, AUD_APPLICATION_LOG.RELOBJ_LABEL, AUD_APPLICATION_LOG.RELOBJ_TYPE, SEC_OPERATOR.NAME, AUD_EVENT_DESCRIPTOR.SEVERITY FROM AUD_APPLICATION_LOG, AUD_EVENT_DESCRIPTOR, SEC_OPERATOR WHERE (AUD_APPLICATION_LOG.ID=160) AND AUD_APPLICATION_LOG.CREATED_BY = SEC_OPERATOR.ID AND AUD_APPLICATION_LOG.EVENT_ID = AUD_EVENT_DESCRIPTOR.ID
=== 2013-09-19 00:44:22,368 [ad-2] INFO  DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,368 [ad-2] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 0 items
=== 2013-09-19 00:44:22,368 [ad-2] DEBUG SQLDriver - Freeing SQLDriver dbConnection 944889298
=== 2013-09-19 00:44:22,368 [ad-2] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "944889298"

Alius.

Leave a comment:

Isomorphic replied

17 Sep 2013, 12:40
We'll look into this. Two things:

1. you look like you may be starting to implement something which overlaps with a new feature in 4.1d builds, DataSource.audit, so you might want to look into that instead

2. if you're on Windows, your DataSource "operator.ds.xml" may be overriding the built-in Component Schema DataSource "Operator.ds.xml" (because Windows filesystems aren't case sensitive). Regardless of whether this causes the concurrency issue, you probably want to use another DataSource ID.
Leave a comment:

agalataud replied

17 Sep 2013, 01:38

Thank you for this quick reply.

Here is what I already read:

http://www.smartclient.com/smartgwte...mentation.html

http://www.smartclient.com/smartgwte...urceUsage.html

From what I understood, my usage of server side DS is simple and safe enough: a new DSRequest is built every time, and I'm not making any reference to a locally cached DataSource (DS name specified in DSRequest constructor instead).

I'm not using SmartGWT pooling either, instead I have a JNDI resource that takes care of it. Anyway, pooling doesn't seem to be the issue as the exceptions are thrown in parts of code that seem to init DS or generate SQL.

I don't think I can use RPCManager either (mentioned in ServerDataSourceImplementation as the safest way to get a DS) as my executions are not necessarily tied to a HTTP request.

Below is the test case I've just built, which gives me the following traces on first execution:

Code:

=== SmartClient Init: log4j.isc.config.xml not found in CLASSPATH. Assuming log4j configuration for com.isomorphic is located in some other log4j configuration file.
executeDSRequest: pool-1-thread-1
executeDSRequest: pool-1-thread-2
executeDSRequest: pool-1-thread-5
executeDSRequest: pool-1-thread-3
executeDSRequest: pool-1-thread-4
executeDSRequest: pool-1-thread-9
executeDSRequest: pool-1-thread-8
executeDSRequest: pool-1-thread-6
executeDSRequest: pool-1-thread-10
executeDSRequest: pool-1-thread-7
java.util.ConcurrentModificationException
	at java.util.Hashtable$Enumerator.next(Hashtable.java:1031)
	at java.util.HashMap.putAll(HashMap.java:523)
	at com.isomorphic.datasource.DataSource.getRelation(DataSource.java:3742)
	at com.isomorphic.datasource.DataSource.getRelation(DataSource.java:3723)
	at com.isomorphic.datasource.DataSource.getIncludeFromInfo(DataSource.java:4277)
	at com.isomorphic.datasource.BasicDataSource.init(BasicDataSource.java:511)
	at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:242)
	at com.isomorphic.datasource.DataSource.initialize(DataSource.java:452)
	at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:166)
	at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:437)
	at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:228)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:220)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:209)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:130)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:138)
	at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:1179)
	at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:95)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
	at com.isomorphic.datasource.DataSourceManager.get(DataSourceManager.java:118)
	at com.isomorphic.datasource.DataSource.getIncludeFromInfo(DataSource.java:4256)
	at com.isomorphic.datasource.BasicDataSource.init(BasicDataSource.java:511)
	at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:242)
	at com.isomorphic.datasource.DataSource.initialize(DataSource.java:452)
	at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:166)
	at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:437)
	at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:228)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:220)
	at com.isomorphic.datasource.DataSource.forName(DataSource.java:209)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:130)
	at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:138)
	at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:1179)
	at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:95)
	at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
	at com.isomorphic.datasource.DSRequest.getDataSource(DSRequest.java:2073)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2254)
	at com.test.DSTestCase.executeDSRequest(DSTestCase.java:63)
	at com.test.DSTestCase.access$0(DSTestCase.java:51)
	at com.test.DSTestCase$1.run(DSTestCase.java:45)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)
log4j:WARN No appenders could be found for logger (org.apache.Velocity).
log4j:WARN Please initialize the log4j system properly.

Regards
Antoine

Attached Files

Leave a comment:

Isomorphic replied

17 Sep 2013, 00:46
Take a look at the DataSource Pooling overview and Standalone DataSource overviews.

If, after reviewing these docs, you believe your usage is correct, then please put together a minimal, ready-to-run test case demonstrating a framework issue, and we can review it.
Leave a comment:

agalataud started a topic Concurrency errors using server-side datasource

16 Sep 2013, 23:55