-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'exportDatesAsFormattedString' in the pooling flow
=== 2015-02-09 13:20:27,361 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'exportAs'
=== 2015-02-09 13:20:27,362 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'exportAs' but null was returned
=== 2015-02-09 13:20:27,362 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'exportAs' in the pooling flow
=== 2015-02-09 13:20:27,362 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'exportFilename'
=== 2015-02-09 13:20:27,362 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'exportFilename' but null was returned
=== 2015-02-09 13:20:27,362 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'exportFilename' in the pooling flow
=== 2015-02-09 13:20:27,362 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'lineBreakStyle'
=== 2015-02-09 13:20:27,362 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'lineBreakStyle' but null was returned
=== 2015-02-09 13:20:27,362 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'lineBreakStyle' in the pooling flow
=== 2015-02-09 13:20:27,363 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'useForCacheSync'
=== 2015-02-09 13:20:27,363 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'useForCacheSync' but null was returned
=== 2015-02-09 13:20:27,363 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'useForCacheSync' in the pooling flow
=== 2015-02-09 13:20:27,363 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'canSyncCache'
=== 2015-02-09 13:20:27,363 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'canSyncCache' but null was returned
=== 2015-02-09 13:20:27,363 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'canSyncCache' in the pooling flow
=== 2015-02-09 13:20:27,363 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'allowMultiUpdate'
=== 2015-02-09 13:20:27,363 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'allowMultiUpdate' but null was returned
=== 2015-02-09 13:20:27,363 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'allowMultiUpdate' in the pooling flow
=== 2015-02-09 13:20:27,364 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'providesMissingKeys'
=== 2015-02-09 13:20:27,364 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'providesMissingKeys' but null was returned
=== 2015-02-09 13:20:27,364 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'providesMissingKeys' in the pooling flow
=== 2015-02-09 13:20:27,364 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'requiresAuthentication'
=== 2015-02-09 13:20:27,364 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'requiresAuthentication' but null was returned
=== 2015-02-09 13:20:27,364 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'requiresAuthentication' in the pooling flow
=== 2015-02-09 13:20:27,364 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'requiresRole'
=== 2015-02-09 13:20:27,365 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'requiresRole' but null was returned
=== 2015-02-09 13:20:27,365 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'requiresRole' in the pooling flow
=== 2015-02-09 13:20:27,365 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'requires'
=== 2015-02-09 13:20:27,365 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'requires' but null was returned
=== 2015-02-09 13:20:27,365 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'requires' in the pooling flow
=== 2015-02-09 13:20:27,365 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'ownerIdField'
=== 2015-02-09 13:20:27,365 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'ownerIdField' but null was returned
=== 2015-02-09 13:20:27,365 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'ownerIdField' in the pooling flow
=== 2015-02-09 13:20:27,366 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'guestUserId'
=== 2015-02-09 13:20:27,366 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'guestUserId' but null was returned
=== 2015-02-09 13:20:27,366 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'guestUserId' in the pooling flow
=== 2015-02-09 13:20:27,366 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'mail'
=== 2015-02-09 13:20:27,366 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'mail' but null was returned
=== 2015-02-09 13:20:27,366 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'mail' in the pooling flow
=== 2015-02-09 13:20:27,366 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'beanClassName'
=== 2015-02-09 13:20:27,367 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'beanClassName' but null was returned
=== 2015-02-09 13:20:27,367 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'beanClassName' in the pooling flow
=== 2015-02-09 13:20:27,367 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'sqlType'
=== 2015-02-09 13:20:27,367 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'sqlType' but null was returned
=== 2015-02-09 13:20:27,367 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'sqlType' in the pooling flow
=== 2015-02-09 13:20:27,367 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'outputs'
=== 2015-02-09 13:20:27,367 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'outputs' but null was returned
=== 2015-02-09 13:20:27,367 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'outputs' in the pooling flow
=== 2015-02-09 13:20:27,368 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'autoJoinTransactions'
=== 2015-02-09 13:20:27,368 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'autoJoinTransactions' but null was returned
=== 2015-02-09 13:20:27,368 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'autoJoinTransactions' in the pooling flow
=== 2015-02-09 13:20:27,368 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'useSpringTransaction'
=== 2015-02-09 13:20:27,368 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'useSpringTransaction' but null was returned
=== 2015-02-09 13:20:27,368 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'useSpringTransaction' in the pooling flow
=== 2015-02-09 13:20:27,368 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'progressiveLoading'
=== 2015-02-09 13:20:27,369 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'progressiveLoading' but null was returned
=== 2015-02-09 13:20:27,369 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'progressiveLoading' in the pooling flow
=== 2015-02-09 13:20:27,369 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'allowAdvancedCriteria'
=== 2015-02-09 13:20:27,369 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'allowAdvancedCriteria' but null was returned
=== 2015-02-09 13:20:27,369 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'allowAdvancedCriteria' in the pooling flow
=== 2015-02-09 13:20:27,369 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'scriptImport'
=== 2015-02-09 13:20:27,369 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'scriptImport' but null was returned
=== 2015-02-09 13:20:27,369 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'scriptImport' in the pooling flow
=== 2015-02-09 13:20:27,370 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'script'
=== 2015-02-09 13:20:27,370 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'script' but null was returned
=== 2015-02-09 13:20:27,370 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'script' in the pooling flow
=== 2015-02-09 13:20:27,370 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'summaryFunctions'
=== 2015-02-09 13:20:27,370 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'summaryFunctions' but null was returned
=== 2015-02-09 13:20:27,370 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'summaryFunctions' in the pooling flow
=== 2015-02-09 13:20:27,370 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'groupBy'
=== 2015-02-09 13:20:27,371 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'groupBy' but null was returned
=== 2015-02-09 13:20:27,371 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'groupBy' in the pooling flow
=== 2015-02-09 13:20:27,371 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'allowClientRequestedSummaries'
=== 2015-02-09 13:20:27,371 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'allowClientRequestedSummaries' but null was returned
=== 2015-02-09 13:20:27,371 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'allowClientRequestedSummaries' in the pooling flow
=== 2015-02-09 13:20:27,371 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'skipRowCount'
=== 2015-02-09 13:20:27,372 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'skipRowCount' but null was returned
=== 2015-02-09 13:20:27,372 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'skipRowCount' in the pooling flow
=== 2015-02-09 13:20:27,372 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'language'
=== 2015-02-09 13:20:27,372 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'language' but null was returned
=== 2015-02-09 13:20:27,372 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'language' in the pooling flow
=== 2015-02-09 13:20:27,372 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'values'
=== 2015-02-09 13:20:27,372 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'values' but null was returned
=== 2015-02-09 13:20:27,372 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'values' in the pooling flow
=== 2015-02-09 13:20:27,373 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'criteria'
=== 2015-02-09 13:20:27,373 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'criteria' but null was returned
=== 2015-02-09 13:20:27,373 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'criteria' in the pooling flow
=== 2015-02-09 13:20:27,373 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'cacheSyncOperation'
=== 2015-02-09 13:20:27,373 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'cacheSyncOperation' but null was returned
=== 2015-02-09 13:20:27,373 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'cacheSyncOperation' in the pooling flow
=== 2015-02-09 13:20:27,373 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'relaxSecurityForCacheSync'
=== 2015-02-09 13:20:27,374 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'relaxSecurityForCacheSync' but null was returned
=== 2015-02-09 13:20:27,374 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'relaxSecurityForCacheSync' in the pooling flow
=== 2015-02-09 13:20:27,374 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'customCriteriaFields'
=== 2015-02-09 13:20:27,374 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'customCriteriaFields' but null was returned
=== 2015-02-09 13:20:27,374 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'customCriteriaFields' in the pooling flow
=== 2015-02-09 13:20:27,374 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'excludeCriteriaFields'
=== 2015-02-09 13:20:27,374 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'excludeCriteriaFields' but null was returned
=== 2015-02-09 13:20:27,374 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'excludeCriteriaFields' in the pooling flow
=== 2015-02-09 13:20:27,375 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'customValueFields'
=== 2015-02-09 13:20:27,375 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'customValueFields' but null was returned
=== 2015-02-09 13:20:27,375 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'customValueFields' in the pooling flow
=== 2015-02-09 13:20:27,375 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'creatorOverrides'
=== 2015-02-09 13:20:27,375 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'creatorOverrides' but null was returned
=== 2015-02-09 13:20:27,375 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'creatorOverrides' in the pooling flow
=== 2015-02-09 13:20:27,375 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'qualifyColumnNames'
=== 2015-02-09 13:20:27,376 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'qualifyColumnNames' but null was returned
=== 2015-02-09 13:20:27,376 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'qualifyColumnNames' in the pooling flow
=== 2015-02-09 13:20:27,376 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'customFields'
=== 2015-02-09 13:20:27,376 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'customFields' but null was returned
=== 2015-02-09 13:20:27,376 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'customFields' in the pooling flow
=== 2015-02-09 13:20:27,377 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'type'
=== 2015-02-09 13:20:27,377 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'type' but null was returned
=== 2015-02-09 13:20:27,377 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'type' in the pooling flow
=== 2015-02-09 13:20:27,377 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'types'
=== 2015-02-09 13:20:27,378 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'types' but null was returned
=== 2015-02-09 13:20:27,378 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'types' in the pooling flow
=== 2015-02-09 13:20:27,380 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 7 of type 'OperationBinding' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,380 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 7 of type 'OperationBinding' in the pooling flow
=== 2015-02-09 13:20:27,380 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 7 of type 'OperationBinding'
=== 2015-02-09 13:20:27,415 [ec-2] INFO  SQLDataSource - Deriving dataSource Administrator from table: Administrator
=== 2015-02-09 13:20:27,481 [ec-2] INFO  PoolManager - SmartClient pooling started for 'Mysql' objects
=== 2015-02-09 13:20:27,487 [ec-2] DEBUG PoolableSQLConnectionFactory - Initializing SQL config for 'Mysql' from system config - using DriverManager:  com.mysql.jdbc.Driver
=== 2015-02-09 13:20:27,492 [ec-2] DEBUG PoolableSQLConnectionFactory - com.mysql.jdbc.Driver lookup successful
=== 2015-02-09 13:20:27,492 [ec-2] DEBUG PoolableSQLConnectionFactory - DriverManager fetching connection for Mysql via jdbc url jdbc:mysql://mysql1.fcm.net/fermat?user=fermatcapital&password=fermatwandq&zeroDateTimeBehavior=convertToNull
=== 2015-02-09 13:20:27,493 [ec-2] DEBUG PoolableSQLConnectionFactory - Passing JDBC URL only to getConnection
=== 2015-02-09 13:20:27,682 [ec-2] DEBUG PoolableSQLConnectionFactory - makeObject() created a pooled Connection '787168388'
=== 2015-02-09 13:20:27,689 [ec-2] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 787168388
=== 2015-02-09 13:20:27,692 [ec-2] DEBUG SQLConnectionManager - Borrowed connection '787168388'
=== 2015-02-09 13:20:27,695 [ec-2] DEBUG SQLMetaData - Created SQLMetaData instnace with hashCode='2111261699' with passed Connection object hashCode='787168388'
=== 2015-02-09 13:20:27,695 [ec-2] INFO  SQLDSGenerator - Fetching column metadata for table: Administrator
=== 2015-02-09 13:20:27,695 [ec-2] INFO  SQLDSGenerator - =============Using catalog: fermat
=== 2015-02-09 13:20:27,712 [ec-2] INFO  SQLDSGenerator - Fetching column metadata for Administrator complete
=== 2015-02-09 13:20:27,717 [ec-2] DEBUG SQLConnectionManager - About to close  with hashcode "787168388"
=== 2015-02-09 13:20:27,717 [ec-2] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 787168388
=== 2015-02-09 13:20:27,719 [ec-2] DEBUG SQLDataSource - DataSource 8 acquired SQLDriver instance 1917008778 during initialization
=== 2015-02-09 13:20:27,720 [ec-2] DEBUG SQLDataSource - DataSource 9 acquired SQLDriver instance 469375905 during initialization
=== 2015-02-09 13:20:27,720 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 9 of type 'Administrator' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,720 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 9 of type 'Administrator' in the pooling flow
=== 2015-02-09 13:20:27,720 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 9 of type 'Administrator'
=== 2015-02-09 13:20:27,720 [ec-2] DEBUG DataSourceLoader - For DataSource 'Administrator', got instanceId 9
=== 2015-02-09 13:20:27,734 [ec-2] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 9
=== 2015-02-09 13:20:27,734 [ec-2] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 9
=== 2015-02-09 13:20:27,734 [ec-2] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 9 of type 'Administrator'
=== 2015-02-09 13:20:27,735 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'AMBest'
=== 2015-02-09 13:20:27,737 [ec-2] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/AMBest.ds.xml: 2ms
=== 2015-02-09 13:20:27,737 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-09 13:20:27,740 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 10 of type 'DataSource' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,740 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 10 of type 'DataSource' in the pooling flow
=== 2015-02-09 13:20:27,740 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 10 of type 'DataSource'
=== 2015-02-09 13:20:27,740 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
=== 2015-02-09 13:20:27,742 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 11 of type 'DataSourceField' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,743 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 11 of type 'DataSourceField' in the pooling flow
=== 2015-02-09 13:20:27,743 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 11 of type 'DataSourceField'
=== 2015-02-09 13:20:27,743 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
=== 2015-02-09 13:20:27,743 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'field' but null was returned
=== 2015-02-09 13:20:27,743 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'field' in the pooling flow
=== 2015-02-09 13:20:27,745 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
=== 2015-02-09 13:20:27,745 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'operationBinding' but null was returned
=== 2015-02-09 13:20:27,745 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'operationBinding' in the pooling flow
=== 2015-02-09 13:20:27,745 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'OperationBinding'
=== 2015-02-09 13:20:27,747 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 12 of type 'OperationBinding' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,747 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 12 of type 'OperationBinding' in the pooling flow
=== 2015-02-09 13:20:27,747 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 12 of type 'OperationBinding'
=== 2015-02-09 13:20:27,748 [ec-2] INFO  SQLDataSource - Deriving dataSource AMBest from table: AMBest
=== 2015-02-09 13:20:27,748 [ec-2] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 787168388
=== 2015-02-09 13:20:27,749 [ec-2] DEBUG SQLConnectionManager - Borrowed connection '787168388'
=== 2015-02-09 13:20:27,750 [ec-2] DEBUG SQLMetaData - Created SQLMetaData instnace with hashCode='845061684' with passed Connection object hashCode='787168388'
=== 2015-02-09 13:20:27,750 [ec-2] INFO  SQLDSGenerator - Fetching column metadata for table: AMBest
=== 2015-02-09 13:20:27,750 [ec-2] INFO  SQLDSGenerator - =============Using catalog: fermat
=== 2015-02-09 13:20:27,755 [ec-2] INFO  SQLDSGenerator - Fetching column metadata for AMBest complete
=== 2015-02-09 13:20:27,758 [ec-2] DEBUG SQLConnectionManager - About to close  with hashcode "787168388"
=== 2015-02-09 13:20:27,758 [ec-2] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 787168388
=== 2015-02-09 13:20:27,759 [ec-2] DEBUG SQLDataSource - DataSource 13 acquired SQLDriver instance 2022437173 during initialization
=== 2015-02-09 13:20:27,760 [ec-2] DEBUG SQLDataSource - DataSource 14 acquired SQLDriver instance 730401895 during initialization
=== 2015-02-09 13:20:27,760 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 14 of type 'AMBest' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,760 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 14 of type 'AMBest' in the pooling flow
=== 2015-02-09 13:20:27,760 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 14 of type 'AMBest'
=== 2015-02-09 13:20:27,760 [ec-2] DEBUG DataSourceLoader - For DataSource 'AMBest', got instanceId 14
=== 2015-02-09 13:20:27,763 [ec-2] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 14
=== 2015-02-09 13:20:27,763 [ec-2] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 14
=== 2015-02-09 13:20:27,763 [ec-2] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 14 of type 'AMBest'
=== 2015-02-09 13:20:27,763 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'auditor'
=== 2015-02-09 13:20:27,766 [ec-2] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/auditor.ds.xml: 2ms
=== 2015-02-09 13:20:27,766 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-09 13:20:27,768 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 15 of type 'DataSource' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,768 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 15 of type 'DataSource' in the pooling flow
=== 2015-02-09 13:20:27,768 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 15 of type 'DataSource'
=== 2015-02-09 13:20:27,769 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
=== 2015-02-09 13:20:27,771 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 16 of type 'DataSourceField' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,771 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 16 of type 'DataSourceField' in the pooling flow
=== 2015-02-09 13:20:27,771 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 16 of type 'DataSourceField'
=== 2015-02-09 13:20:27,771 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
=== 2015-02-09 13:20:27,772 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'field' but null was returned
=== 2015-02-09 13:20:27,772 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'field' in the pooling flow
=== 2015-02-09 13:20:27,773 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
=== 2015-02-09 13:20:27,773 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'operationBinding' but null was returned
=== 2015-02-09 13:20:27,773 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'operationBinding' in the pooling flow
=== 2015-02-09 13:20:27,773 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'OperationBinding'
=== 2015-02-09 13:20:27,775 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 17 of type 'OperationBinding' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,775 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 17 of type 'OperationBinding' in the pooling flow
=== 2015-02-09 13:20:27,775 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 17 of type 'OperationBinding'
=== 2015-02-09 13:20:27,776 [ec-2] INFO  SQLDataSource - Deriving dataSource auditor from table: auditor
=== 2015-02-09 13:20:27,776 [ec-2] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 787168388
=== 2015-02-09 13:20:27,777 [ec-2] DEBUG SQLConnectionManager - Borrowed connection '787168388'
=== 2015-02-09 13:20:27,777 [ec-2] DEBUG SQLMetaData - Created SQLMetaData instnace with hashCode='1788012908' with passed Connection object hashCode='787168388'
=== 2015-02-09 13:20:27,777 [ec-2] INFO  SQLDSGenerator - Fetching column metadata for table: auditor
=== 2015-02-09 13:20:27,777 [ec-2] INFO  SQLDSGenerator - =============Using catalog: fermat
=== 2015-02-09 13:20:27,783 [ec-2] INFO  SQLDSGenerator - Fetching column metadata for auditor complete
=== 2015-02-09 13:20:27,786 [ec-2] DEBUG SQLConnectionManager - About to close  with hashcode "787168388"
=== 2015-02-09 13:20:27,786 [ec-2] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 787168388
=== 2015-02-09 13:20:27,787 [ec-2] DEBUG SQLDataSource - DataSource 18 acquired SQLDriver instance 2039244428 during initialization
=== 2015-02-09 13:20:27,788 [ec-2] DEBUG SQLDataSource - DataSource 19 acquired SQLDriver instance 1889578923 during initialization
=== 2015-02-09 13:20:27,788 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 19 of type 'auditor' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,788 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 19 of type 'auditor' in the pooling flow
=== 2015-02-09 13:20:27,788 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 19 of type 'auditor'
=== 2015-02-09 13:20:27,788 [ec-2] DEBUG DataSourceLoader - For DataSource 'auditor', got instanceId 19
=== 2015-02-09 13:20:27,791 [ec-2] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 19
=== 2015-02-09 13:20:27,791 [ec-2] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 19
=== 2015-02-09 13:20:27,792 [ec-2] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 19 of type 'auditor'
=== 2015-02-09 13:20:27,792 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'author'
=== 2015-02-09 13:20:27,794 [ec-2] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/author.ds.xml: 2ms
=== 2015-02-09 13:20:27,794 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-09 13:20:27,797 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 20 of type 'DataSource' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,797 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 20 of type 'DataSource' in the pooling flow
=== 2015-02-09 13:20:27,797 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 20 of type 'DataSource'
=== 2015-02-09 13:20:27,797 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
=== 2015-02-09 13:20:27,799 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 21 of type 'DataSourceField' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,799 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 21 of type 'DataSourceField' in the pooling flow
=== 2015-02-09 13:20:27,799 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 21 of type 'DataSourceField'
=== 2015-02-09 13:20:27,799 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
=== 2015-02-09 13:20:27,800 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'field' but null was returned
=== 2015-02-09 13:20:27,800 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'field' in the pooling flow
=== 2015-02-09 13:20:27,801 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
=== 2015-02-09 13:20:27,801 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'operationBinding' but null was returned
=== 2015-02-09 13:20:27,801 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'operationBinding' in the pooling flow
=== 2015-02-09 13:20:27,801 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'OperationBinding'
=== 2015-02-09 13:20:27,803 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 22 of type 'OperationBinding' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,803 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 22 of type 'OperationBinding' in the pooling flow
=== 2015-02-09 13:20:27,803 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 22 of type 'OperationBinding'
=== 2015-02-09 13:20:27,804 [ec-2] INFO  SQLDataSource - Deriving dataSource author from table: author
=== 2015-02-09 13:20:27,804 [ec-2] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 787168388
=== 2015-02-09 13:20:27,805 [ec-2] DEBUG SQLConnectionManager - Borrowed connection '787168388'
=== 2015-02-09 13:20:27,805 [ec-2] DEBUG SQLMetaData - Created SQLMetaData instnace with hashCode='808135809' with passed Connection object hashCode='787168388'
=== 2015-02-09 13:20:27,805 [ec-2] INFO  SQLDSGenerator - Fetching column metadata for table: author
=== 2015-02-09 13:20:27,805 [ec-2] INFO  SQLDSGenerator - =============Using catalog: fermat
=== 2015-02-09 13:20:27,810 [ec-2] INFO  SQLDSGenerator - Fetching column metadata for author complete
=== 2015-02-09 13:20:27,812 [ec-2] DEBUG SQLConnectionManager - About to close  with hashcode "787168388"
=== 2015-02-09 13:20:27,812 [ec-2] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 787168388
=== 2015-02-09 13:20:27,814 [ec-2] DEBUG SQLDataSource - DataSource 23 acquired SQLDriver instance 105186895 during initialization
=== 2015-02-09 13:20:27,814 [ec-2] DEBUG SQLDataSource - DataSource 24 acquired SQLDriver instance 497102784 during initialization
=== 2015-02-09 13:20:27,814 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 24 of type 'author' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,815 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 24 of type 'author' in the pooling flow
=== 2015-02-09 13:20:27,815 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 24 of type 'author'
=== 2015-02-09 13:20:27,815 [ec-2] DEBUG DataSourceLoader - For DataSource 'author', got instanceId 24
=== 2015-02-09 13:20:27,821 [izer] DEBUG SQLMetaData - In SQLMetadata (hashcode "845061684") finalizer, about to close  with hashcode "787168388"
=== 2015-02-09 13:20:27,822 [izer] DEBUG SQLMetaData - In SQLMetadata (hashcode "1788012908") finalizer, about to close  with hashcode "787168388"
=== 2015-02-09 13:20:27,823 [izer] DEBUG SQLMetaData - In SQLMetadata (hashcode "808135809") finalizer, about to close  with hashcode "787168388"
=== 2015-02-09 13:20:27,823 [izer] DEBUG SQLMetaData - In SQLMetadata (hashcode "2111261699") finalizer, about to close  with hashcode "787168388"
=== 2015-02-09 13:20:27,824 [ec-2] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 24
=== 2015-02-09 13:20:27,824 [ec-2] DEBUG SQLDataSource - About to clear SQLDriver state for DS instance 24
=== 2015-02-09 13:20:27,824 [ec-2] DEBUG PoolableDataSourceFactory - Cleared and passivated DataSource 24 of type 'author'
=== 2015-02-09 13:20:27,824 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'basket'
=== 2015-02-09 13:20:27,827 [ec-2] DEBUG XML - Parsed XML from /Users/mike/Library/Tomcat/apache-tomcat-7.0.27/webapps/Tables/ds/basket.ds.xml: 2ms
=== 2015-02-09 13:20:27,827 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
=== 2015-02-09 13:20:27,829 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 25 of type 'DataSource' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,829 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 25 of type 'DataSource' in the pooling flow
=== 2015-02-09 13:20:27,829 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 25 of type 'DataSource'
=== 2015-02-09 13:20:27,830 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
=== 2015-02-09 13:20:27,830 [ec-2] DEBUG PoolableDataSourceFactory - Tried to create DataSource  of type 'field' but null was returned
=== 2015-02-09 13:20:27,830 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource null of type 'field' in the pooling flow
=== 2015-02-09 13:20:27,830 [ec-2] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
=== 2015-02-09 13:20:27,832 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 26 of type 'DataSourceField' and assigned it to thread http-bio-8080-exec-2
=== 2015-02-09 13:20:27,832 [ec-2] DEBUG PoolableDataSourceFactory - Created DataSource 26 of type 'DataSourceField' in the pooling flow
=== 2015-02-09 13:20:27,832 [ec-2] DEBUG PoolableDataSourceFactory - Activated DataSource 26 of type 'DataSourceField'
=== 2015-02-09 13:20:27,832 [ec-2] INFO  SQLDataSource - Deriving dataSource basket from table: basket
=== 2015-02-09 13:20:27,833 [ec-2] DEBUG PoolableSQLConnectionFact