ce - DataSource 9 acquired SQLDriver instance 1127555950 during initialization
=== 2015-02-06 16:31:53,722 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 9 of type 'Administrator' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,722 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 9 of type 'Administrator' in the pooling flow
=== 2015-02-06 16:31:53,722 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 9 of type 'Administrator'
=== 2015-02-06 16:31:53,722 [ec-4] DEBUG DataSourceLoader - For DataSource 'Administrator', got instanceId 9
=== 2015-02-06 16:31:53,735 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 9
=== 2015-02-06 16:31:53,735 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 9
=== 2015-02-06 16:31:53,735 [ec-4] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 9 of type 'Administrator'
=== 2015-02-06 16:31:53,735 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'AMBest'
=== 2015-02-06 16:31:53,737 [ec-4] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/AMBest.ds.xml: 2ms
=== 2015-02-06 16:31:53,737 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-06 16:31:53,740 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 10 of type 'DataSource' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,740 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 10 of type 'DataSource' in the pooling flow
=== 2015-02-06 16:31:53,740 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 10 of type 'DataSource'
=== 2015-02-06 16:31:53,740 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
=== 2015-02-06 16:31:53,742 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 11 of type 'DataSourceField' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,742 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 11 of type 'DataSourceField' in the pooling flow
=== 2015-02-06 16:31:53,742 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 11 of type 'DataSourceField'
=== 2015-02-06 16:31:53,743 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
=== 2015-02-06 16:31:53,743 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'field' but null was returned
=== 2015-02-06 16:31:53,743 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'field' in the pooling flow
=== 2015-02-06 16:31:53,744 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
=== 2015-02-06 16:31:53,744 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'operationBinding' but null was returned
=== 2015-02-06 16:31:53,744 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'operationBinding' in the pooling flow
=== 2015-02-06 16:31:53,744 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'OperationBinding'
=== 2015-02-06 16:31:53,746 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 12 of type 'OperationBinding' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,746 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 12 of type 'OperationBinding' in the pooling flow
=== 2015-02-06 16:31:53,746 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 12 of type 'OperationBinding'
=== 2015-02-06 16:31:53,747 [ec-4] INFO  SQLDataSource - Deriving dataSource AMBest from table: AMBest
=== 2015-02-06 16:31:53,747 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:53,759 [ec-4] DEBUG SQLConnectionManager - Borrowed connection '1290082656'
=== 2015-02-06 16:31:53,760 [ec-4] DEBUG SQLMetaData - Created SQLMetaData instnace with hashCode='257087824' with passed Connection object hashCode='1290082656'
=== 2015-02-06 16:31:53,760 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for table: AMBest
=== 2015-02-06 16:31:53,760 [ec-4] INFO  SQLDSGenerator - =============Using catalog: fermat
=== 2015-02-06 16:31:53,791 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for AMBest complete
=== 2015-02-06 16:31:53,815 [ec-4] DEBUG SQLConnectionManager - About to close  with hashcode "1290082656"
=== 2015-02-06 16:31:53,815 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:53,834 [ec-4] DEBUG SQLDataSource - DataSource 13 acquired SQLDriver instance 1431496612 during initialization
=== 2015-02-06 16:31:53,835 [ec-4] DEBUG SQLDataSource - DataSource 14 acquired SQLDriver instance 904260543 during initialization
=== 2015-02-06 16:31:53,835 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 14 of type 'AMBest' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,835 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 14 of type 'AMBest' in the pooling flow
=== 2015-02-06 16:31:53,835 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 14 of type 'AMBest'
=== 2015-02-06 16:31:53,835 [ec-4] DEBUG DataSourceLoader - For DataSource 'AMBest', got instanceId 14
=== 2015-02-06 16:31:53,837 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 14
=== 2015-02-06 16:31:53,837 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 14
=== 2015-02-06 16:31:53,837 [ec-4] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 14 of type 'AMBest'
=== 2015-02-06 16:31:53,838 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'auditor'
=== 2015-02-06 16:31:53,840 [ec-4] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/auditor.ds.xml: 2ms
=== 2015-02-06 16:31:53,840 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-06 16:31:53,842 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 15 of type 'DataSource' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,842 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 15 of type 'DataSource' in the pooling flow
=== 2015-02-06 16:31:53,842 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 15 of type 'DataSource'
=== 2015-02-06 16:31:53,842 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
=== 2015-02-06 16:31:53,844 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 16 of type 'DataSourceField' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,844 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 16 of type 'DataSourceField' in the pooling flow
=== 2015-02-06 16:31:53,844 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 16 of type 'DataSourceField'
=== 2015-02-06 16:31:53,845 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
=== 2015-02-06 16:31:53,845 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'field' but null was returned
=== 2015-02-06 16:31:53,845 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'field' in the pooling flow
=== 2015-02-06 16:31:53,846 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
=== 2015-02-06 16:31:53,846 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'operationBinding' but null was returned
=== 2015-02-06 16:31:53,846 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'operationBinding' in the pooling flow
=== 2015-02-06 16:31:53,846 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'OperationBinding'
=== 2015-02-06 16:31:53,848 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 17 of type 'OperationBinding' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,848 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 17 of type 'OperationBinding' in the pooling flow
=== 2015-02-06 16:31:53,848 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 17 of type 'OperationBinding'
=== 2015-02-06 16:31:53,849 [ec-4] INFO  SQLDataSource - Deriving dataSource auditor from table: auditor
=== 2015-02-06 16:31:53,849 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:53,881 [ec-4] DEBUG SQLConnectionManager - Borrowed connection '1290082656'
=== 2015-02-06 16:31:53,882 [ec-4] DEBUG SQLMetaData - Created SQLMetaData instnace with hashCode='436664662' with passed Connection object hashCode='1290082656'
=== 2015-02-06 16:31:53,882 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for table: auditor
=== 2015-02-06 16:31:53,882 [ec-4] INFO  SQLDSGenerator - =============Using catalog: fermat
=== 2015-02-06 16:31:53,908 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for auditor complete
=== 2015-02-06 16:31:53,933 [ec-4] DEBUG SQLConnectionManager - About to close  with hashcode "1290082656"
=== 2015-02-06 16:31:53,933 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:53,944 [ec-4] DEBUG SQLDataSource - DataSource 18 acquired SQLDriver instance 2057505358 during initialization
=== 2015-02-06 16:31:53,945 [ec-4] DEBUG SQLDataSource - DataSource 19 acquired SQLDriver instance 1710867912 during initialization
=== 2015-02-06 16:31:53,945 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 19 of type 'auditor' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,945 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 19 of type 'auditor' in the pooling flow
=== 2015-02-06 16:31:53,945 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 19 of type 'auditor'
=== 2015-02-06 16:31:53,945 [ec-4] DEBUG DataSourceLoader - For DataSource 'auditor', got instanceId 19
=== 2015-02-06 16:31:53,948 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 19
=== 2015-02-06 16:31:53,948 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 19
=== 2015-02-06 16:31:53,948 [ec-4] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 19 of type 'auditor'
=== 2015-02-06 16:31:53,948 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'author'
=== 2015-02-06 16:31:53,954 [ec-4] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/author.ds.xml: 5ms
=== 2015-02-06 16:31:53,955 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-06 16:31:53,957 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 20 of type 'DataSource' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,957 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 20 of type 'DataSource' in the pooling flow
=== 2015-02-06 16:31:53,957 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 20 of type 'DataSource'
=== 2015-02-06 16:31:53,957 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
=== 2015-02-06 16:31:53,959 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 21 of type 'DataSourceField' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,959 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 21 of type 'DataSourceField' in the pooling flow
=== 2015-02-06 16:31:53,959 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 21 of type 'DataSourceField'
=== 2015-02-06 16:31:53,959 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
=== 2015-02-06 16:31:53,959 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'field' but null was returned
=== 2015-02-06 16:31:53,960 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'field' in the pooling flow
=== 2015-02-06 16:31:53,961 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
=== 2015-02-06 16:31:53,961 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'operationBinding' but null was returned
=== 2015-02-06 16:31:53,961 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'operationBinding' in the pooling flow
=== 2015-02-06 16:31:53,961 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'OperationBinding'
=== 2015-02-06 16:31:53,962 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 22 of type 'OperationBinding' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:53,963 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 22 of type 'OperationBinding' in the pooling flow
=== 2015-02-06 16:31:53,963 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 22 of type 'OperationBinding'
=== 2015-02-06 16:31:53,963 [ec-4] INFO  SQLDataSource - Deriving dataSource author from table: author
=== 2015-02-06 16:31:53,963 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:53,975 [ec-4] DEBUG SQLConnectionManager - Borrowed connection '1290082656'
=== 2015-02-06 16:31:53,976 [ec-4] DEBUG SQLMetaData - Created SQLMetaData instnace with hashCode='1310308732' with passed Connection object hashCode='1290082656'
=== 2015-02-06 16:31:53,976 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for table: author
=== 2015-02-06 16:31:53,976 [ec-4] INFO  SQLDSGenerator - =============Using catalog: fermat
=== 2015-02-06 16:31:54,001 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for author complete
=== 2015-02-06 16:31:54,030 [ec-4] DEBUG SQLConnectionManager - About to close  with hashcode "1290082656"
=== 2015-02-06 16:31:54,030 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:54,045 [ec-4] DEBUG SQLDataSource - DataSource 23 acquired SQLDriver instance 1856288651 during initialization
=== 2015-02-06 16:31:54,046 [ec-4] DEBUG SQLDataSource - DataSource 24 acquired SQLDriver instance 933741 during initialization
=== 2015-02-06 16:31:54,046 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 24 of type 'author' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,046 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 24 of type 'author' in the pooling flow
=== 2015-02-06 16:31:54,046 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 24 of type 'author'
=== 2015-02-06 16:31:54,046 [ec-4] DEBUG DataSourceLoader - For DataSource 'author', got instanceId 24
=== 2015-02-06 16:31:54,049 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 24
=== 2015-02-06 16:31:54,049 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 24
=== 2015-02-06 16:31:54,049 [ec-4] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 24 of type 'author'
=== 2015-02-06 16:31:54,049 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'basket'
=== 2015-02-06 16:31:54,051 [ec-4] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/basket.ds.xml: 1ms
=== 2015-02-06 16:31:54,051 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-06 16:31:54,053 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 25 of type 'DataSource' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,054 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 25 of type 'DataSource' in the pooling flow
=== 2015-02-06 16:31:54,054 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 25 of type 'DataSource'
=== 2015-02-06 16:31:54,054 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
=== 2015-02-06 16:31:54,054 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'field' but null was returned
=== 2015-02-06 16:31:54,054 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'field' in the pooling flow
=== 2015-02-06 16:31:54,054 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
=== 2015-02-06 16:31:54,056 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 26 of type 'DataSourceField' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,056 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 26 of type 'DataSourceField' in the pooling flow
=== 2015-02-06 16:31:54,056 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 26 of type 'DataSourceField'
=== 2015-02-06 16:31:54,057 [ec-4] INFO  SQLDataSource - Deriving dataSource basket from table: basket
=== 2015-02-06 16:31:54,057 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:54,080 [ec-4] DEBUG SQLConnectionManager - Borrowed connection '1290082656'
=== 2015-02-06 16:31:54,081 [ec-4] DEBUG SQLMetaData - Created SQLMetaData instnace with hashCode='364215755' with passed Connection object hashCode='1290082656'
=== 2015-02-06 16:31:54,081 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for table: basket
=== 2015-02-06 16:31:54,081 [ec-4] INFO  SQLDSGenerator - =============Using catalog: fermat
=== 2015-02-06 16:31:54,109 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for basket complete
=== 2015-02-06 16:31:54,135 [ec-4] DEBUG SQLConnectionManager - About to close  with hashcode "1290082656"
=== 2015-02-06 16:31:54,135 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:54,152 [ec-4] DEBUG SQLDataSource - DataSource 27 acquired SQLDriver instance 725211281 during initialization
=== 2015-02-06 16:31:54,153 [ec-4] DEBUG SQLDataSource - DataSource 28 acquired SQLDriver instance 1656303042 during initialization
=== 2015-02-06 16:31:54,153 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 28 of type 'basket' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,153 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 28 of type 'basket' in the pooling flow
=== 2015-02-06 16:31:54,153 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 28 of type 'basket'
=== 2015-02-06 16:31:54,153 [ec-4] DEBUG DataSourceLoader - For DataSource 'basket', got instanceId 28
=== 2015-02-06 16:31:54,155 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 28
=== 2015-02-06 16:31:54,156 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 28
=== 2015-02-06 16:31:54,156 [ec-4] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 28 of type 'basket'
=== 2015-02-06 16:31:54,156 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'basketdifftype'
=== 2015-02-06 16:31:54,159 [ec-4] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/basketdifftype.ds.xml: 2ms
=== 2015-02-06 16:31:54,159 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-06 16:31:54,161 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 29 of type 'DataSource' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,161 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 29 of type 'DataSource' in the pooling flow
=== 2015-02-06 16:31:54,161 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 29 of type 'DataSource'
=== 2015-02-06 16:31:54,162 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
=== 2015-02-06 16:31:54,164 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 30 of type 'DataSourceField' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,164 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 30 of type 'DataSourceField' in the pooling flow
=== 2015-02-06 16:31:54,164 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 30 of type 'DataSourceField'
=== 2015-02-06 16:31:54,165 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
=== 2015-02-06 16:31:54,165 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'field' but null was returned
=== 2015-02-06 16:31:54,165 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'field' in the pooling flow
=== 2015-02-06 16:31:54,166 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
=== 2015-02-06 16:31:54,166 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'operationBinding' but null was returned
=== 2015-02-06 16:31:54,166 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'operationBinding' in the pooling flow
=== 2015-02-06 16:31:54,166 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'OperationBinding'
=== 2015-02-06 16:31:54,167 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 31 of type 'OperationBinding' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,167 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 31 of type 'OperationBinding' in the pooling flow
=== 2015-02-06 16:31:54,168 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 31 of type 'OperationBinding'
=== 2015-02-06 16:31:54,168 [ec-4] INFO  SQLDataSource - Deriving dataSource basketdifftype from table: basketdifftype
=== 2015-02-06 16:31:54,169 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:54,179 [ec-4] DEBUG SQLConnectionManager - Borrowed connection '1290082656'
=== 2015-02-06 16:31:54,180 [ec-4] DEBUG SQLMetaData - Created SQLMetaData instnace with hashCode='2031038268' with passed Connection object hashCode='1290082656'
=== 2015-02-06 16:31:54,180 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for table: basketdifftype
=== 2015-02-06 16:31:54,180 [ec-4] INFO  SQLDSGenerator - =============Using catalog: fermat
=== 2015-02-06 16:31:54,211 [ec-4] INFO  SQLDSGenerator - Fetching column metadata for basketdifftype complete
=== 2015-02-06 16:31:54,243 [ec-4] DEBUG SQLConnectionManager - About to close  with hashcode "1290082656"
=== 2015-02-06 16:31:54,243 [ec-4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 1290082656
=== 2015-02-06 16:31:54,257 [ec-4] DEBUG SQLDataSource - DataSource 32 acquired SQLDriver instance 1494094604 during initialization
=== 2015-02-06 16:31:54,257 [ec-4] DEBUG SQLDataSource - DataSource 33 acquired SQLDriver instance 721725057 during initialization
=== 2015-02-06 16:31:54,257 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 33 of type 'basketdifftype' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,258 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 33 of type 'basketdifftype' in the pooling flow
=== 2015-02-06 16:31:54,258 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 33 of type 'basketdifftype'
=== 2015-02-06 16:31:54,258 [ec-4] DEBUG DataSourceLoader - For DataSource 'basketdifftype', got instanceId 33
=== 2015-02-06 16:31:54,260 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 33
=== 2015-02-06 16:31:54,260 [ec-4] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 33
=== 2015-02-06 16:31:54,260 [ec-4] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 33 of type 'basketdifftype'
=== 2015-02-06 16:31:54,260 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'batchUpload'
=== 2015-02-06 16:31:54,262 [ec-4] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/batchUpload.ds.xml: 2ms
=== 2015-02-06 16:31:54,262 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-06 16:31:54,265 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 34 of type 'DataSource' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,265 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 34 of type 'DataSource' in the pooling flow
=== 2015-02-06 16:31:54,265 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 34 of type 'DataSource'
=== 2015-02-06 16:31:54,265 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
=== 2015-02-06 16:31:54,265 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'operationBinding' but null was returned
=== 2015-02-06 16:31:54,266 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'operationBinding' in the pooling flow
=== 2015-02-06 16:31:54,266 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'OperationBinding'
=== 2015-02-06 16:31:54,267 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 35 of type 'OperationBinding' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,267 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 35 of type 'OperationBinding' in the pooling flow
=== 2015-02-06 16:31:54,267 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 35 of type 'OperationBinding'
=== 2015-02-06 16:31:54,267 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'visibleMethods'
=== 2015-02-06 16:31:54,267 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'visibleMethods' but null was returned
=== 2015-02-06 16:31:54,267 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'visibleMethods' in the pooling flow
=== 2015-02-06 16:31:54,268 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'ServerObject'
=== 2015-02-06 16:31:54,269 [ec-4] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/tables/sc/system/schema/ServerObject.ds.xml: 1ms
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 36 of type 'Object' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 36 of type 'Object' in the pooling flow
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 36 of type 'Object'
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'fields'
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'fields' but null was returned
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'fields' in the pooling flow
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'ID'
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'ID' but null was returned
=== 2015-02-06 16:31:54,270 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'ID' in the pooling flow
=== 2015-02-06 16:31:54,271 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'methodName'
=== 2015-02-06 16:31:54,271 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'methodName' but null was returned
=== 2015-02-06 16:31:54,271 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'methodName' in the pooling flow
=== 2015-02-06 16:31:54,271 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'valueMap'
=== 2015-02-06 16:31:54,271 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'valueMap' but null was returned
=== 2015-02-06 16:31:54,271 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'valueMap' in the pooling flow
=== 2015-02-06 16:31:54,271 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'lookupStyle'
=== 2015-02-06 16:31:54,272 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'lookupStyle' but null was returned
=== 2015-02-06 16:31:54,272 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'lookupStyle' in the pooling flow
=== 2015-02-06 16:31:54,273 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'attributeName'
=== 2015-02-06 16:31:54,273 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'attributeName' but null was returned
=== 2015-02-06 16:31:54,273 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'attributeName' in the pooling flow
=== 2015-02-06 16:31:54,274 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'attributeScope'
=== 2015-02-06 16:31:54,274 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'attributeScope' but null was returned
=== 2015-02-06 16:31:54,274 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'attributeScope' in the pooling flow
=== 2015-02-06 16:31:54,274 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'bean'
=== 2015-02-06 16:31:54,275 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'bean' but null was returned
=== 2015-02-06 16:31:54,275 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'bean' in the pooling flow
=== 2015-02-06 16:31:54,275 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'visibleMethods'
=== 2015-02-06 16:31:54,275 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'visibleMethods' but null was returned
=== 2015-02-06 16:31:54,275 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'visibleMethods' in the pooling flow
=== 2015-02-06 16:31:54,276 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'dropExtraFields'
=== 2015-02-06 16:31:54,276 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'dropExtraFields' but null was returned
=== 2015-02-06 16:31:54,276 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'dropExtraFields' in the pooling flow
=== 2015-02-06 16:31:54,276 [ec-4] DEBUG ISCKeyedObjectPool - Borrowing object for 'crudOnly'
=== 2015-02-06 16:31:54,276 [ec-4] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'crudOnly' but null was returned
=== 2015-02-06 16:31:54,277 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'crudOnly' in the pooling flow
=== 2015-02-06 16:31:54,277 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 37 of type 'ServerObject' and assigned it to thread http-bio-8080-exec-4
=== 2015-02-06 16:31:54,277 [ec-4] DEBUG PoolableDataSourceFactory - Created DataSource 37 of type 'ServerObject' in the pooling flow
=== 2015-02-06 16:31:54,277 [ec-4] DEBUG PoolableDataSourceFactory - Activated DataSource 37 of type 'ServerOb