Announcement

Collapse
No announcement yet.
X
  • Filter
  • Time
Clear All
new posts

  • Blama
    replied
    Hi Isomorphic,

    I managed to reproduced the case where I don't get the simple criteria using your code in the BuiltInDS sample. It really wasn't easy to get this one. It seems to be related to the source of the request.
    • Normal clientside requests get the additional criteria from <criteria>.
    • New serverside DSRequests picking up the RPCManager from the clientside request get the additional criteria from <criteria>.
    • New serverside DSRequests without a RPCManager don't get the additional criteria.


    See this modified BuiltInDS:

    animals.ds.xml (add)
    Code:
    <operationBinding operationType="fetch">
    	<criteria fieldName="lifeSpan" value="20" />
    	<criteria fieldName="scientificName" value="Ateles panicus" />
    </operationBinding>
    employees.ds.xml (add)
    Code:
    <serverObject lookupStyle="new" className="com.smartgwt.sample.server.listener.Employees" />
    Employees.java (new, relevant part in bold)
    Code:
    package com.smartgwt.sample.server.listener;
    
    import com.isomorphic.datasource.DSRequest;
    import com.isomorphic.datasource.DSResponse;
    import com.isomorphic.datasource.DataSource;
    
    import javax.servlet.http.HttpServletRequest;
    
    public class Employees {
    
    	public DSResponse fetch(DSRequest request, HttpServletRequest servletRequest) throws Exception {
    		// Good
    		DSRequest goodTestRequest = new DSRequest("animals", DataSource.OP_FETCH[B], request.getRPCManager()[/B]);
    		DSResponse goodTestResponse = goodTestRequest.execute();
    
    		// Bad
    		DSRequest badTestRequest = new DSRequest("animals", DataSource.OP_FETCH);
    		DSResponse badTestResponse = badTestRequest.execute();
    
    		return request.execute();
    	}
    };

    Log after clicking "Employees" in the browser (relevant rows in bold)
    Code:
    === 2014-09-01 16:35:16,374 [4-31] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.3; WOW64; rv:26.0) Gecko/20100101 Firefox/26.0': Moz (Gecko) with Accept-Encoding header
    === 2014-09-01 16:35:16,385 [4-31] DEBUG IDACall - Header Name:Value pair: Cookie:isc_cState=ready; JSESSIONID=lkuo0nf97cyl1enkbjmv02c1j
    === 2014-09-01 16:35:16,386 [4-31] DEBUG IDACall - Header Name:Value pair: DNT:1
    === 2014-09-01 16:35:16,386 [4-31] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888
    === 2014-09-01 16:35:16,386 [4-31] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
    === 2014-09-01 16:35:16,386 [4-31] DEBUG IDACall - Header Name:Value pair: Content-Length:1010
    === 2014-09-01 16:35:16,387 [4-31] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
    === 2014-09-01 16:35:16,387 [4-31] DEBUG IDACall - Header Name:Value pair: Accept-Language:de-de,de;q=0.8,en-us;q=0.5,en;q=0.3
    === 2014-09-01 16:35:16,387 [4-31] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
    === 2014-09-01 16:35:16,387 [4-31] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
    === 2014-09-01 16:35:16,387 [4-31] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:8888/BuiltInDS.html?gwt.codesvr=127.0.0.1:9997
    === 2014-09-01 16:35:16,387 [4-31] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.3; WOW64; rv:26.0) Gecko/20100101 Firefox/26.0
    === 2014-09-01 16:35:16,388 [4-31] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
    === 2014-09-01 16:35:16,388 [4-31] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
    === 2014-09-01 16:35:16,388 [4-31] DEBUG IDACall - session exists: lkuo0nf97cyl1enkbjmv02c1j
    === 2014-09-01 16:35:16,388 [4-31] DEBUG IDACall - remote user: null
    === 2014-09-01 16:35:16,443 [4-31] DEBUG XML - Parsed XML from (in memory stream): 8ms
    === 2014-09-01 16:35:16,444 [4-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'transaction'
    === 2014-09-01 16:35:16,445 [4-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
    === 2014-09-01 16:35:16,447 [4-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'List'
    === 2014-09-01 16:35:16,458 [4-31] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\system\schema\List.ds.xml: 8ms
    === 2014-09-01 16:35:16,458 [4-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
    === 2014-09-01 16:35:16,468 [4-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'elem'
    === 2014-09-01 16:35:16,471 [4-31] DEBUG RPCManager - Processing 1 requests.
    === 2014-09-01 16:35:16,507 [4-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'employees'
    === 2014-09-01 16:35:16,507 [4-31] DEBUG DSRequest - Caching instance 23 of DS employees from DSRequest.getDataSource()
    === 2014-09-01 16:35:16,508 [4-31] DEBUG RPCManager - Request #1 (DSRequest) payload: {
        criteria:{
        },
        operationConfig:{
            dataSource:"employees",
            operationType:"fetch",
            textMatchStyle:"exact"
        },
        startRow:0,
        endRow:75,
        componentId:"isc_ListGrid_1",
        appID:"builtinApplication",
        operation:"employees_fetch",
        oldValues:{
        }
    }
    === 2014-09-01 16:35:16,509 [4-31] INFO  IDACall - Performing 1 operation(s)
    === 2014-09-01 16:35:16,510 [4-31] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
    === 2014-09-01 16:35:16,510 [4-31] DEBUG DeclarativeSecurity - DataSource employees is not in the pre-checked list, processing...
    === 2014-09-01 16:35:16,535 [4-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'animals'
    === 2014-09-01 16:35:16,536 [4-31] DEBUG DSRequest - Caching instance 17 of DS animals from DSRequest.getDataSource()
    === 2014-09-01 16:35:16,536 [4-31] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
    === 2014-09-01 16:35:16,540 [4-36] INFO  Download - Returning 304: Not modified on conditional get of: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\skins\Enterprise\images\loadingSmall.gif
    === 2014-09-01 16:35:16,541 [4-32] INFO  Download - Returning 304: Not modified on conditional get of: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\skins\Enterprise\images\pickers\pickers_sprite.png
    === 2014-09-01 16:35:16,751 [4-31] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
    === 2014-09-01 16:35:16,751 [4-31] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
    === 2014-09-01 16:35:16,752 [4-31] INFO  SQLDataSource - [builtinApplication.null] Performing fetch operation with
    	criteria: [B]{lifeSpan:"20",scientificName:"Ateles panicus"}	values: {lifeSpan:"20",scientificName:"Ateles panicus"}[/B]
    === 2014-09-01 16:35:16,782 [4-31] INFO  SQLDataSource - [builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
    === 2014-09-01 16:35:16,794 [4-31] INFO  SQLDataSource - [builtinApplication.null] 17: Executing SQL query on 'HSQLDB': SELECT animals.commonName, animals.scientificName, animals.lifeSpan, animals.status, animals.diet, animals.information, animals.picture FROM animals WHERE (animals.lifeSpan=20 AND LOWER(animals.scientificName)='ateles panicus')
    === 2014-09-01 16:35:16,899 [4-31] INFO  PoolManager - [builtinApplication.null] SmartClient pooling disabled for 'HSQLDB' objects
    === 2014-09-01 16:35:16,900 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Initializing SQL config for 'HSQLDB' from system config - using DriverManager:  org.hsqldb.jdbcDriver
    === 2014-09-01 16:35:16,901 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] org.hsqldb.jdbcDriver lookup successful
    === 2014-09-01 16:35:16,901 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:hsql://localhost/isomorphic
    === 2014-09-01 16:35:16,902 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
    === 2014-09-01 16:35:17,035 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created an unpooled Connection '2098416250'
    === 2014-09-01 16:35:17,036 [4-31] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '2098416250'
    === 2014-09-01 16:35:17,036 [4-31] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'HSQLDB' using connection '2098416250'
    === 2014-09-01 16:35:17,036 [4-31] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'HSQLDB': SELECT animals.commonName, animals.scientificName, animals.lifeSpan, animals.status, animals.diet, animals.information, animals.picture FROM animals WHERE (animals.lifeSpan=20 AND LOWER(animals.scientificName)='ateles panicus')
    === 2014-09-01 16:35:17,068 [4-31] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 1 items
    === 2014-09-01 16:35:17,069 [4-31] DEBUG ISCKeyedObjectPool - Borrowing object for 'animals'
    === 2014-09-01 16:35:17,073 [4-31] DEBUG DSRequest - Caching instance 27 of DS animals from DSRequest.getDataSource()
    === 2014-09-01 16:35:17,074 [4-31] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
    === 2014-09-01 16:35:17,074 [4-31] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
    === 2014-09-01 16:35:17,074 [4-31] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
    === 2014-09-01 16:35:17,075 [4-31] INFO  SQLDataSource - [builtinApplication.null] [B]Performing fetch operation with
    	criteria: {}	values: {}
    [/B]=== 2014-09-01 16:35:17,076 [4-31] INFO  SQLWhereClause - [builtinApplication.null] empty condition
    === 2014-09-01 16:35:17,077 [4-31] INFO  SQLDataSource - [builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
    === 2014-09-01 16:35:17,078 [4-31] INFO  SQLDataSource - [builtinApplication.null] 27: Executing SQL query on 'HSQLDB': SELECT animals.commonName, animals.scientificName, animals.lifeSpan, animals.status, animals.diet, animals.information, animals.picture FROM animals WHERE ('1'='1')
    === 2014-09-01 16:35:17,079 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:hsql://localhost/isomorphic
    === 2014-09-01 16:35:17,079 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
    === 2014-09-01 16:35:17,181 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created an unpooled Connection '1710796498'
    === 2014-09-01 16:35:17,182 [4-31] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '1710796498'
    === 2014-09-01 16:35:17,182 [4-31] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL query in 'HSQLDB' using connection '1710796498'
    === 2014-09-01 16:35:17,182 [4-31] INFO  SQLDriver - [builtinApplication.null] Executing SQL query on 'HSQLDB': SELECT animals.commonName, animals.scientificName, animals.lifeSpan, animals.status, animals.diet, animals.information, animals.picture FROM animals WHERE ('1'='1')
    === 2014-09-01 16:35:17,196 [4-31] INFO  DSResponse - [builtinApplication.null] DSResponse: List with 29 items
    === 2014-09-01 16:35:17,197 [4-31] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1710796498
    === 2014-09-01 16:35:17,198 [4-31] DEBUG SQLConnectionManager - About to close JDBCConnection with hashcode "1710796498"
    === 2014-09-01 16:35:17,199 [4-31] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
    === 2014-09-01 16:35:17,199 [4-31] DEBUG DeclarativeSecurity - DataSource employees is not in the pre-checked list, processing...
    === 2014-09-01 16:35:17,199 [4-31] DEBUG AppBase - [builtinApplication.employees_fetch] No userTypes defined, allowing anyone access to all operations for this application
    === 2014-09-01 16:35:17,199 [4-31] DEBUG AppBase - [builtinApplication.employees_fetch] No public zero-argument method named '_employees_fetch' found, performing generic datasource operation
    === 2014-09-01 16:35:17,200 [4-31] INFO  SQLDataSource - [builtinApplication.employees_fetch] Performing fetch operation with
    	criteria: {}	values: {}
    === 2014-09-01 16:35:17,201 [4-31] INFO  SQLWhereClause - [builtinApplication.employees_fetch] empty condition
    === 2014-09-01 16:35:17,201 [4-31] INFO  SQLDataSource - [builtinApplication.employees_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
    === 2014-09-01 16:35:17,204 [4-31] DEBUG SQLDataSource - [builtinApplication.employees_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause
    === 2014-09-01 16:35:17,205 [4-31] DEBUG SQLDataSource - [builtinApplication.employees_fetch] Eval'd row count query: SELECT COUNT(*) FROM employeeTable WHERE ('1'='1')
    === 2014-09-01 16:35:17,206 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_fetch] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:hsql://localhost/isomorphic
    === 2014-09-01 16:35:17,206 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_fetch] Passing JDBC URL only to getConnection
    === 2014-09-01 16:35:17,308 [4-31] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_fetch] makeObject() created an unpooled Connection '1783124385'
    === 2014-09-01 16:35:17,308 [4-31] DEBUG SQLConnectionManager - [builtinApplication.employees_fetch] Borrowed connection '1783124385'
    === 2014-09-01 16:35:17,309 [4-31] DEBUG SQLDriver - [builtinApplication.employees_fetch] About to execute SQL query in 'HSQLDB' using connection '1783124385'
    === 2014-09-01 16:35:17,309 [4-31] INFO  SQLDriver - [builtinApplication.employees_fetch] Executing SQL query on 'HSQLDB': SELECT COUNT(*) FROM employeeTable WHERE ('1'='1')
    === 2014-09-01 16:35:17,324 [4-31] DEBUG SQLDataSource - [builtinApplication.employees_fetch] Using SQL Limit query
    === 2014-09-01 16:35:17,324 [4-31] DEBUG SQLDataSource - [builtinApplication.employees_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT LIMIT 0 75  employeeTable.userOrder, employeeTable.Name, employeeTable.EmployeeId, employeeTable.ReportsTo, employeeTable.Job, employeeTable.Email, employeeTable.EmployeeType, employeeTable.EmployeeStatus, employeeTable.Salary, employeeTable.OrgUnit, employeeTable.Gender, employeeTable.MaritalStatus FROM employeeTable WHERE ('1'='1')
    === 2014-09-01 16:35:17,324 [4-31] DEBUG SQLDataSource - [builtinApplication.employees_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT LIMIT 0 75  employeeTable.userOrder, employeeTable.Name, employeeTable.EmployeeId, employeeTable.ReportsTo, employeeTable.Job, employeeTable.Email, employeeTable.EmployeeType, employeeTable.EmployeeStatus, employeeTable.Salary, employeeTable.OrgUnit, employeeTable.Gender, employeeTable.MaritalStatus FROM employeeTable WHERE ('1'='1')
    === 2014-09-01 16:35:17,354 [4-31] INFO  DSResponse - [builtinApplication.employees_fetch] DSResponse: List with 75 items
    === 2014-09-01 16:35:17,355 [4-31] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
    === 2014-09-01 16:35:17,356 [4-31] DEBUG RPCManager - DMI response, dropExtraFields: true
    === 2014-09-01 16:35:17,396 [4-31] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1783124385
    === 2014-09-01 16:35:17,396 [4-31] DEBUG SQLConnectionManager - About to close JDBCConnection with hashcode "1783124385"
    === 2014-09-01 16:35:17,397 [4-31] DEBUG SQLDriver - Freeing SQLDriver dbConnection 2098416250
    === 2014-09-01 16:35:17,398 [4-31] DEBUG SQLConnectionManager - About to close JDBCConnection with hashcode "2098416250"
    === 2014-09-01 16:35:17,400 [4-31] INFO  Compression - /builtinds/sc/IDACall: 17687 -> 2910 bytes
    === 2014-09-01 16:35:20,974 [4-36] INFO  Download - Returning 304: Not modified on conditional get of: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\skins\Enterprise\images\Scrollbar\vscroll_sprite.png
    This is not related to the question why I don't get the AdvancedCriteria from your 2nd example, so please look in that one was well (my previous post here).

    Thank you & Best regards,
    Blama

    Leave a comment:


  • Blama
    replied
    Hi Isomorphic,

    your first example works for me (had the same in my app, so I'll have to check why it did not work there. EDIT: Did so, see below).

    Your 2nd example does not work for me. Please check the .ds.xml file (only modification to the BuiltInDS example) and the server log (v9.1p_2014-09-01/EVAL Deployment 2014-09-01, Win8.1, FF26 Dev Mode).

    Unfortunately there are no error messages at all with respect to animal.ds.xml, everything seems to be OK.


    animals.ds.xml
    Code:
    <DataSource ID="animals" serverType="sql" tableName="animals" testFileName="animals.data.xml">
    	<fields>
    		<field name="commonName" title="Animal" type="text" />
    		<field name="scientificName" title="Scientific Name" type="text" primaryKey="true" required="true" />
    		<field name="lifeSpan" title="Life Span" type="integer" />
    		<field name="status" title="Endangered Status" type="text">
    			<valueMap>
    				<value>Threatened</value>
    				<value>Endangered</value>
    				<value>Not Endangered</value>
    				<value>Not currently listed</value>
    				<value>May become threatened</value>
    				<value>Protected</value>
    			</valueMap>
    		</field>
    		<field name="diet" title="Diet" type="text" />
    		<field name="information" title="Interesting Facts" type="text" length="1000" />
    		<field name="picture" title="Picture" type="image" detail="true" imageURLPrefix="/isomorphic/system/reference/inlineExamples/tiles/images/" />
    	</fields>
    	<operationBindings>
    		<operationBinding operationType="fetch">
    			
    			<criteria _constructor="AdvancedCriteria" operator="and">
    				<criterion fieldName="lifeSpan" operator="greaterThan" value="10" />
    				<criterion fieldName="scientificName" operator="iContains" value="ana" />
    			</criteria>
    			
    			<!--This version creates an exception on the client as well!
    			<advancedCriteria constructor="AdvancedCriteria" operator="and">
    				<criterion fieldName="lifeSpan" operator="greaterThan" value="10" />
    				<criterion fieldName="scientificName" operator="iContains" value="ana" />
    			</advancedCriteria>
    			 <advancedCriteria _constructor="AdvancedCriteria" operator="and">
    				<criterion fieldName="lifeSpan" operator="greaterThan" value="10" />
    				<criterion fieldName="scientificName" operator="iContains" value="ana" />
    			</advancedCriteria>
    			<criteria constructor="AdvancedCriteria" operator="and">
    				<criterion fieldName="lifeSpan" operator="greaterThan" value="10" />
    				<criterion fieldName="scientificName" operator="iContains" value="ana" />
    			</criteria>
    			--> <!-- -->
    		</operationBinding>
    	</operationBindings>
    </DataSource>

    Log (Start-up + request):
    Code:
    [Server@2f46ac03]: [Thread[main,5,main]]: checkRunning(false) entered
    [Server@2f46ac03]: [Thread[main,5,main]]: checkRunning(false) exited
    [Server@2f46ac03]: Startup sequence initiated from main() method
    [Server@2f46ac03]: Could not load properties from file
    [Server@2f46ac03]: Using cli/default properties only
    [Server@2f46ac03]: Initiating startup sequence...
    [Server@2f46ac03]: Server socket opened successfully in 2 ms.
    log4j:WARN No appenders could be found for logger (hsqldb.db.HSQLDB482F7D8DD0.ENGINE).
    log4j:WARN Please initialize the log4j system properly.
    [Server@2f46ac03]: Database [index=0, id=0, db=file:C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\WEB-INF\db\hsqldb\isomorphic, alias=isomorphic] opened sucessfully in 2302 ms.
    [Server@2f46ac03]: Startup sequence completed in 2306 ms.
    [Server@2f46ac03]: 2014-09-01 14:22:20.437 HSQLDB server 2.2.9 is online on port 9001
    [Server@2f46ac03]: To close normally, connect and execute SHUTDOWN SQL
    [Server@2f46ac03]: From command line, use [Ctrl]+[C] to abort abruptly
    ISC: Configuring log4j from: file:/C:/Users/me/workspace/lib/smartgwtee-4.1p/samples/built-in-ds/war/WEB-INF/classes/log4j.isc.config.xml
    === 2014-09-01 14:22:20,684 [main] INFO  ISCInit - Isomorphic SmartClient/SmartGWT Framework - Initializing
    === 2014-09-01 14:22:20,695 [main] INFO  ConfigLoader - Attempting to load framework.properties from CLASSPATH
    === 2014-09-01 14:22:20,924 [main] INFO  ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/C:/Users/me/workspace/lib/smartgwtee-4.1p/samples/built-in-ds/war/WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties
    === 2014-09-01 14:22:20,925 [main] INFO  ConfigLoader - Attempting to load project.properties from CLASSPATH
    === 2014-09-01 14:22:20,926 [main] INFO  ConfigLoader - Unable to locate project.properties in CLASSPATH
    === 2014-09-01 14:22:20,936 [main] INFO  ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/C:/Users/me/workspace/lib/smartgwtee-4.1p/samples/built-in-ds/war/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
    === 2014-09-01 14:22:20,936 [main] INFO  ConfigLoader - Attempting to load server.properties from CLASSPATH
    === 2014-09-01 14:22:20,941 [main] INFO  ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/C:/Users/me/workspace/lib/smartgwtee-4.1p/samples/built-in-ds/war/WEB-INF/classes/server.properties
    === 2014-09-01 14:22:20,954 [main] INFO  Logger - Logging system started.
    === 2014-09-01 14:22:20,961 [main] INFO  ISCInit - Auto-detected webRoot - using: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war
    === 2014-09-01 14:22:20,963 [main] INFO  ISCInit - Isomorphic SmartClient/SmartGWT Framework (v9.1p_2014-09-01/EVAL Deployment 2014-09-01) - Initialization Complete
    log4j:WARN No appenders could be found for logger (org.apache.jasper.compiler.JspRuntimeContext).
    log4j:WARN Please initialize the log4j system properly.
    === 2014-09-01 14:22:32,547 [6-31] INFO  Compression - /BuiltInDS.html: 0 -> 20 bytes
    === 2014-09-01 14:22:32,646 [6-33] INFO  Compression - /builtinds/builtinds.nocache.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,660 [6-34] INFO  RequestContext - URL: '/builtinds/sc/DataSourceLoader', User-Agent: 'Mozilla/5.0 (Windows NT 6.3; WOW64; rv:26.0) Gecko/20100101 Firefox/26.0': Moz (Gecko) with Accept-Encoding header
    === 2014-09-01 14:22:32,717 [6-33] INFO  Compression - /builtinds/sc/initsc.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,721 [6-27] INFO  Compression - /builtinds/sc/modules/ISC_Foundation.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,722 [6-31] INFO  Compression - /builtinds/sc/modules/ISC_Core.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,725 [6-36] INFO  Compression - /builtinds/sc/modules/ISC_Grids.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,729 [6-33] INFO  Compression - /builtinds/sc/modules/ISC_Forms.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,724 [6-37] INFO  Compression - /builtinds/sc/modules/ISC_Containers.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,735 [6-31] INFO  Compression - /builtinds/sc/modules/ISC_Calendar.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,744 [6-27] INFO  Compression - /builtinds/sc/modules/ISC_RichTextEditor.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,745 [6-37] INFO  Compression - /builtinds/sc/modules/ISC_DataBinding.js: 0 -> 36 bytes
    === 2014-09-01 14:22:32,788 [6-36] INFO  Download - Returning 304: Not modified on conditional get of: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\skins\Enterprise\load_skin.js
    === 2014-09-01 14:22:32,873 [6-34] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\system\schema\builtinTypes.xml: 20ms
    === 2014-09-01 14:22:32,939 [6-34] INFO  PoolManager - SmartClient pooling started for 'simpleTypes' objects
    === 2014-09-01 14:22:32,939 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'simpleTypes'
    === 2014-09-01 14:22:32,964 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
    === 2014-09-01 14:22:32,997 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'any'
    === 2014-09-01 14:22:33,003 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'boolean'
    === 2014-09-01 14:22:33,005 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'integer'
    === 2014-09-01 14:22:33,007 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'float'
    === 2014-09-01 14:22:33,008 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'date'
    === 2014-09-01 14:22:33,010 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'time'
    === 2014-09-01 14:22:33,012 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'text'
    === 2014-09-01 14:22:33,014 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'string'
    === 2014-09-01 14:22:33,016 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'link'
    === 2014-09-01 14:22:33,017 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'int'
    === 2014-09-01 14:22:33,019 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'long'
    === 2014-09-01 14:22:33,020 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'number'
    === 2014-09-01 14:22:33,022 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'decimal'
    === 2014-09-01 14:22:33,024 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'double'
    === 2014-09-01 14:22:33,026 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dateTime'
    === 2014-09-01 14:22:33,027 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'datetime'
    === 2014-09-01 14:22:33,028 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'validators'
    === 2014-09-01 14:22:33,029 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'positiveInteger'
    === 2014-09-01 14:22:33,031 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'integerPercent'
    === 2014-09-01 14:22:33,033 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'percent'
    === 2014-09-01 14:22:33,034 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'sequence'
    === 2014-09-01 14:22:33,035 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'enum'
    === 2014-09-01 14:22:33,036 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'intEnum'
    === 2014-09-01 14:22:33,038 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'char'
    === 2014-09-01 14:22:33,039 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'ntext'
    === 2014-09-01 14:22:33,041 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'password'
    === 2014-09-01 14:22:33,042 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'localeInt'
    === 2014-09-01 14:22:33,043 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'localeFloat'
    === 2014-09-01 14:22:33,044 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'localeCurrency'
    === 2014-09-01 14:22:33,045 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'phoneNumber'
    === 2014-09-01 14:22:33,047 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'regexp'
    === 2014-09-01 14:22:33,048 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'identifier'
    === 2014-09-01 14:22:33,050 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'URL'
    === 2014-09-01 14:22:33,051 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'url'
    === 2014-09-01 14:22:33,053 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'XPath'
    === 2014-09-01 14:22:33,055 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'HTMLString'
    === 2014-09-01 14:22:33,056 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'HTML'
    === 2014-09-01 14:22:33,057 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'html'
    === 2014-09-01 14:22:33,059 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'measure'
    === 2014-09-01 14:22:33,060 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'integerOrAuto'
    === 2014-09-01 14:22:33,061 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'integerOrIdentifier'
    === 2014-09-01 14:22:33,062 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'handler'
    === 2014-09-01 14:22:33,063 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'callback'
    === 2014-09-01 14:22:33,064 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'action'
    === 2014-09-01 14:22:33,066 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'setter'
    === 2014-09-01 14:22:33,067 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'getter'
    === 2014-09-01 14:22:33,068 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'tester'
    === 2014-09-01 14:22:33,069 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'method'
    === 2014-09-01 14:22:33,070 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'function'
    === 2014-09-01 14:22:33,072 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'expression'
    === 2014-09-01 14:22:33,073 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'className'
    === 2014-09-01 14:22:33,075 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'valueMap'
    === 2014-09-01 14:22:33,076 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'valignEnum'
    === 2014-09-01 14:22:33,077 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'alignEnum'
    === 2014-09-01 14:22:33,078 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'sideEnum'
    === 2014-09-01 14:22:33,081 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'color'
    === 2014-09-01 14:22:33,082 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'cssClass'
    === 2014-09-01 14:22:33,083 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'modifier'
    === 2014-09-01 14:22:33,084 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'modifierTimestamp'
    === 2014-09-01 14:22:33,085 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'creator'
    === 2014-09-01 14:22:33,086 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'creatorTimestamp'
    === 2014-09-01 14:22:33,088 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'binary'
    === 2014-09-01 14:22:33,089 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'imageFile'
    === 2014-09-01 14:22:33,090 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'blob'
    === 2014-09-01 14:22:33,090 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'clob'
    === 2014-09-01 14:22:33,091 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'image'
    === 2014-09-01 14:22:33,142 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'supplyItem'
    === 2014-09-01 14:22:33,153 [6-34] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\ds\supplyItem.ds.xml: 9ms
    === 2014-09-01 14:22:33,153 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
    === 2014-09-01 14:22:33,169 [6-34] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\system\schema\DataSource.ds.xml: 15ms
    === 2014-09-01 14:22:33,170 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
    === 2014-09-01 14:22:33,171 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'fields'
    === 2014-09-01 14:22:33,172 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'ID'
    === 2014-09-01 14:22:33,174 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'inheritsFrom'
    === 2014-09-01 14:22:33,175 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'useParentFieldOrder'
    === 2014-09-01 14:22:33,176 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'useLocalFieldsOnly'
    === 2014-09-01 14:22:33,178 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'restrictToParentFields'
    === 2014-09-01 14:22:33,179 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'valueMap'
    === 2014-09-01 14:22:33,181 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dataFormat'
    === 2014-09-01 14:22:33,183 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'useStrictJSON'
    === 2014-09-01 14:22:33,184 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'noAutoFetch'
    === 2014-09-01 14:22:33,186 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'serverType'
    === 2014-09-01 14:22:33,188 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'callbackParam'
    === 2014-09-01 14:22:33,190 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'requestProperties'
    === 2014-09-01 14:22:33,192 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'addGlobalId'
    === 2014-09-01 14:22:33,194 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'showPrompt'
    === 2014-09-01 14:22:33,195 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dataSourceVersion'
    === 2014-09-01 14:22:33,197 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dbName'
    === 2014-09-01 14:22:33,198 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'schema'
    === 2014-09-01 14:22:33,200 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'tableName'
    === 2014-09-01 14:22:33,201 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'quoteTableName'
    === 2014-09-01 14:22:33,203 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'serverObject'
    === 2014-09-01 14:22:33,204 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBindings'
    === 2014-09-01 14:22:33,206 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
    === 2014-09-01 14:22:33,207 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
    === 2014-09-01 14:22:33,209 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'serviceNamespace'
    === 2014-09-01 14:22:33,210 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dataURL'
    === 2014-09-01 14:22:33,212 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dataProtocol'
    === 2014-09-01 14:22:33,213 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dataTransport'
    === 2014-09-01 14:22:33,215 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'defaultParams'
    === 2014-09-01 14:22:33,216 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'soapAction'
    === 2014-09-01 14:22:33,218 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'jsonPrefix'
    === 2014-09-01 14:22:33,219 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'jsonSuffix'
    === 2014-09-01 14:22:33,219 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'messageTemplate'
    === 2014-09-01 14:22:33,220 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'defaultCriteria'
    === 2014-09-01 14:22:33,221 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'tagName'
    === 2014-09-01 14:22:33,222 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'recordXPath'
    === 2014-09-01 14:22:33,223 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'recordName'
    === 2014-09-01 14:22:33,224 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xmlNamespaces'
    === 2014-09-01 14:22:33,225 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dropExtraFields'
    === 2014-09-01 14:22:33,226 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'schemaNamespace'
    === 2014-09-01 14:22:33,227 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'mustQualify'
    === 2014-09-01 14:22:33,228 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xsdSimpleContent'
    === 2014-09-01 14:22:33,229 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xsdAnyElement'
    === 2014-09-01 14:22:33,230 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xsdAbstract'
    === 2014-09-01 14:22:33,231 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'title'
    === 2014-09-01 14:22:33,232 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'titleField'
    === 2014-09-01 14:22:33,233 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'pluralTitle'
    === 2014-09-01 14:22:33,234 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'clientOnly'
    === 2014-09-01 14:22:33,235 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'testFileName'
    === 2014-09-01 14:22:33,236 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'testData'
    === 2014-09-01 14:22:33,237 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'cacheData'
    === 2014-09-01 14:22:33,238 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'cacheAllData'
    === 2014-09-01 14:22:33,239 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'cacheAcrossOperationIds'
    === 2014-09-01 14:22:33,240 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'types'
    === 2014-09-01 14:22:33,241 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'groups'
    === 2014-09-01 14:22:33,242 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'methods'
    === 2014-09-01 14:22:33,243 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'showSuperClassActions'
    === 2014-09-01 14:22:33,244 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'createStandalone'
    === 2014-09-01 14:22:33,246 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'useFlatFields'
    === 2014-09-01 14:22:33,247 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'showLocalFieldsOnly'
    === 2014-09-01 14:22:33,248 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'globalNamespaces'
    === 2014-09-01 14:22:33,249 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'autoDeriveSchema'
    === 2014-09-01 14:22:33,250 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'autoDeriveFKs'
    === 2014-09-01 14:22:33,252 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'useLocalValidators'
    === 2014-09-01 14:22:33,253 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'autoDeriveTitles'
    === 2014-09-01 14:22:33,254 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'qualifyColumnNames'
    === 2014-09-01 14:22:33,256 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'validateRelatedRecords'
    === 2014-09-01 14:22:33,257 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'requiresAuthentication'
    === 2014-09-01 14:22:33,259 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'requiresRoles'
    === 2014-09-01 14:22:33,259 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'requires'
    === 2014-09-01 14:22:33,261 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'beanClassName'
    === 2014-09-01 14:22:33,261 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'autoJoinTransactions'
    === 2014-09-01 14:22:33,262 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'sparseUpdates'
    === 2014-09-01 14:22:33,264 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'noNullUpdates'
    === 2014-09-01 14:22:33,265 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'canExport'
    === 2014-09-01 14:22:33,266 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'progressiveLoading'
    === 2014-09-01 14:22:33,267 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'autoConvertRelativeDates'
    === 2014-09-01 14:22:33,268 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'allowAdvancedCriteria'
    === 2014-09-01 14:22:33,269 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'scriptImport'
    === 2014-09-01 14:22:33,270 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'script'
    === 2014-09-01 14:22:33,271 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'allowClientRequestedSummaries'
    === 2014-09-01 14:22:33,272 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'translatePatternOperators'
    === 2014-09-01 14:22:33,363 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
    === 2014-09-01 14:22:33,472 [6-34] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\system\schema\DataSourceField.ds.xml: 108ms
    === 2014-09-01 14:22:33,473 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
    === 2014-09-01 14:22:33,475 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'fields'
    === 2014-09-01 14:22:33,477 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'name'
    === 2014-09-01 14:22:33,479 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'type'
    === 2014-09-01 14:22:33,481 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'disabled'
    === 2014-09-01 14:22:33,483 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'idAllowed'
    === 2014-09-01 14:22:33,484 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'required'
    === 2014-09-01 14:22:33,488 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'valueMap'
    === 2014-09-01 14:22:33,490 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'validators'
    === 2014-09-01 14:22:33,492 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'length'
    === 2014-09-01 14:22:33,494 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'decimalPad'
    === 2014-09-01 14:22:33,495 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'decimalPrecision'
    === 2014-09-01 14:22:33,497 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xmlRequired'
    === 2014-09-01 14:22:33,499 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xmlMaxOccurs'
    === 2014-09-01 14:22:33,500 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xmlMinOccurs'
    === 2014-09-01 14:22:33,502 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xmlNonEmpty'
    === 2014-09-01 14:22:33,503 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xsElementRef'
    === 2014-09-01 14:22:33,505 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'canHide'
    === 2014-09-01 14:22:33,506 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xmlAttribute'
    === 2014-09-01 14:22:33,508 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'mustQualify'
    === 2014-09-01 14:22:33,509 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'valueXPath'
    === 2014-09-01 14:22:33,511 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'childrenProperty'
    === 2014-09-01 14:22:33,512 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'title'
    === 2014-09-01 14:22:33,513 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'prompt'
    === 2014-09-01 14:22:33,514 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'detail'
    === 2014-09-01 14:22:33,515 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'canEdit'
    === 2014-09-01 14:22:33,516 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'canSave'
    === 2014-09-01 14:22:33,518 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'canView'
    === 2014-09-01 14:22:33,519 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'inapplicable'
    === 2014-09-01 14:22:33,520 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'advanced'
    === 2014-09-01 14:22:33,521 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'visibility'
    === 2014-09-01 14:22:33,522 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'hidden'
    === 2014-09-01 14:22:33,523 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'primaryKey'
    === 2014-09-01 14:22:33,524 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'foreignKey'
    === 2014-09-01 14:22:33,525 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'rootValue'
    === 2014-09-01 14:22:33,527 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'includeFrom'
    === 2014-09-01 14:22:33,528 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'includeVia'
    === 2014-09-01 14:22:33,530 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'relatedTableAlias'
    === 2014-09-01 14:22:33,531 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'showFileInline'
    === 2014-09-01 14:22:33,533 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'escapeHTML'
    === 2014-09-01 14:22:33,534 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'imageWidth'
    === 2014-09-01 14:22:33,536 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'imageHeight'
    === 2014-09-01 14:22:33,538 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'nativeName'
    === 2014-09-01 14:22:33,539 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'nativeFK'
    === 2014-09-01 14:22:33,541 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'autoDeriveFKs'
    === 2014-09-01 14:22:33,543 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'fieldName'
    === 2014-09-01 14:22:33,545 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'multiple'
    === 2014-09-01 14:22:33,547 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'validateEachItem'
    === 2014-09-01 14:22:33,548 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'pickListFields'
    === 2014-09-01 14:22:33,550 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'canFilter'
    === 2014-09-01 14:22:33,552 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'ignore'
    === 2014-09-01 14:22:33,553 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'unknownType'
    === 2014-09-01 14:22:33,554 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'canSortClientOnly'
    === 2014-09-01 14:22:33,555 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'childTagName'
    === 2014-09-01 14:22:33,556 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'basic'
    === 2014-09-01 14:22:33,557 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'maxFileSize'
    === 2014-09-01 14:22:33,558 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'frozen'
    === 2014-09-01 14:22:33,559 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'canExport'
    === 2014-09-01 14:22:33,560 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'sqlStorageStrategy'
    === 2014-09-01 14:22:33,561 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'encodeInResponse'
    === 2014-09-01 14:22:33,562 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'mimeType'
    === 2014-09-01 14:22:33,563 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'ignoreTextMatchStyle'
    === 2014-09-01 14:22:33,564 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'lenientXPath'
    === 2014-09-01 14:22:33,565 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'summaryFunction'
    === 2014-09-01 14:22:33,567 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'joinString'
    === 2014-09-01 14:22:33,568 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'joinPrefix'
    === 2014-09-01 14:22:33,569 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'joinSuffix'
    === 2014-09-01 14:22:33,570 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'allowClientRequestedSummaries'
    === 2014-09-01 14:22:33,577 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
    === 2014-09-01 14:22:33,588 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'ValueMap'
    === 2014-09-01 14:22:33,596 [6-34] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\system\schema\ValueMap.ds.xml: 7ms
    === 2014-09-01 14:22:33,596 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
    === 2014-09-01 14:22:33,603 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'Validator'
    === 2014-09-01 14:22:33,614 [6-34] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\system\schema\Validator.ds.xml: 10ms
    === 2014-09-01 14:22:33,614 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
    === 2014-09-01 14:22:33,616 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'fields'
    === 2014-09-01 14:22:33,617 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'type'
    === 2014-09-01 14:22:33,618 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'name'
    === 2014-09-01 14:22:33,620 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'description'
    === 2014-09-01 14:22:33,621 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'stopIfFalse'
    === 2014-09-01 14:22:33,623 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'stopOnError'
    === 2014-09-01 14:22:33,624 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'validateOnChange'
    === 2014-09-01 14:22:33,626 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'clientOnly'
    === 2014-09-01 14:22:33,627 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'errorMessage'
    === 2014-09-01 14:22:33,628 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'max'
    === 2014-09-01 14:22:33,629 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'min'
    === 2014-09-01 14:22:33,630 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'exclusive'
    === 2014-09-01 14:22:33,630 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'mask'
    === 2014-09-01 14:22:33,631 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'transformTo'
    === 2014-09-01 14:22:33,632 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'precision'
    === 2014-09-01 14:22:33,634 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'otherField'
    === 2014-09-01 14:22:33,635 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'list'
    === 2014-09-01 14:22:33,636 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'valueMap'
    === 2014-09-01 14:22:33,637 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'substring'
    === 2014-09-01 14:22:33,638 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'operator'
    === 2014-09-01 14:22:33,639 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'count'
    === 2014-09-01 14:22:33,640 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'applyWhen'
    === 2014-09-01 14:22:33,641 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dependentFields'
    === 2014-09-01 14:22:33,642 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'scriptImport'
    === 2014-09-01 14:22:33,643 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'serverCondition'
    === 2014-09-01 14:22:33,644 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'serverObject'
    === 2014-09-01 14:22:33,645 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'fieldName'
    === 2014-09-01 14:22:33,646 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'triggerEvent'
    === 2014-09-01 14:22:33,647 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'message'
    === 2014-09-01 14:22:33,649 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'severity'
    === 2014-09-01 14:22:33,651 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'displayMode'
    === 2014-09-01 14:22:33,652 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'duration'
    === 2014-09-01 14:22:33,654 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'formula'
    === 2014-09-01 14:22:33,655 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'formulaVars'
    === 2014-09-01 14:22:33,659 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'validator'
    === 2014-09-01 14:22:33,769 [6-34] DEBUG DataSourceLoader - For DataSource 'supplyItem', got instanceId 10
    === 2014-09-01 14:22:33,822 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'animals'
    === 2014-09-01 14:22:33,834 [6-34] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\ds\animals.ds.xml: 10ms
    === 2014-09-01 14:22:33,834 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
    === 2014-09-01 14:22:33,842 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
    === 2014-09-01 14:22:33,849 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
    === 2014-09-01 14:22:33,853 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'ValueMap'
    === 2014-09-01 14:22:33,860 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationBinding'
    === 2014-09-01 14:22:33,862 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'OperationBinding'
    === 2014-09-01 14:22:33,876 [6-34] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\system\schema\OperationBinding.ds.xml: 13ms
    === 2014-09-01 14:22:33,877 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
    === 2014-09-01 14:22:33,877 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'fields'
    === 2014-09-01 14:22:33,879 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationType'
    === 2014-09-01 14:22:33,879 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'customSQL'
    === 2014-09-01 14:22:33,880 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'valueMap'
    === 2014-09-01 14:22:33,881 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dataFormat'
    === 2014-09-01 14:22:33,883 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'wsOperation'
    === 2014-09-01 14:22:33,885 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'recordName'
    === 2014-09-01 14:22:33,886 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'recordXPath'
    === 2014-09-01 14:22:33,887 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dropExtraFields'
    === 2014-09-01 14:22:33,888 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'responseDataSchema'
    === 2014-09-01 14:22:33,890 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'operationId'
    === 2014-09-01 14:22:33,891 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'useFlatFields'
    === 2014-09-01 14:22:33,892 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dataURL'
    === 2014-09-01 14:22:33,894 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dataTransport'
    === 2014-09-01 14:22:33,895 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'defaultParams'
    === 2014-09-01 14:22:33,896 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'soapAction'
    === 2014-09-01 14:22:33,897 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'dataProtocol'
    === 2014-09-01 14:22:33,899 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'callbackParam'
    === 2014-09-01 14:22:33,900 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'requestProperties'
    === 2014-09-01 14:22:33,901 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'serverObject'
    === 2014-09-01 14:22:33,902 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'serverMethod'
    === 2014-09-01 14:22:33,903 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'methodArguments'
    === 2014-09-01 14:22:33,905 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'xmlNamespaces'
    === 2014-09-01 14:22:33,906 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'preventHTTPCaching'
    === 2014-09-01 14:22:33,907 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'spoofResponses'
    === 2014-09-01 14:22:33,908 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'exportResults'
    === 2014-09-01 14:22:33,910 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'exportDatesAsFormattedString'
    === 2014-09-01 14:22:33,911 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'useForCacheSync'
    === 2014-09-01 14:22:33,912 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'canSyncCache'
    === 2014-09-01 14:22:33,914 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'allowMultiUpdate'
    === 2014-09-01 14:22:33,915 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'requiresAuthentication'
    === 2014-09-01 14:22:33,916 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'requiresRoles'
    === 2014-09-01 14:22:33,918 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'requires'
    === 2014-09-01 14:22:33,919 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'mail'
    === 2014-09-01 14:22:33,920 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'beanClassName'
    === 2014-09-01 14:22:33,921 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'sqlType'
    === 2014-09-01 14:22:33,922 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'outputs'
    === 2014-09-01 14:22:33,923 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'autoJoinTransactions'
    === 2014-09-01 14:22:33,924 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'progressiveLoading'
    === 2014-09-01 14:22:33,924 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'allowAdvancedCriteria'
    === 2014-09-01 14:22:33,925 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'scriptImport'
    === 2014-09-01 14:22:33,926 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'script'
    === 2014-09-01 14:22:33,927 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'summaryFunctions'
    === 2014-09-01 14:22:33,928 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'groupBy'
    === 2014-09-01 14:22:33,929 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'allowClientRequestedSummaries'
    === 2014-09-01 14:22:33,930 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'type'
    === 2014-09-01 14:22:33,931 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'types'
    === 2014-09-01 14:22:33,935 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
    === 2014-09-01 14:22:33,936 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'criteria'
    === 2014-09-01 14:22:33,937 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'criterion'
    === 2014-09-01 14:22:33,942 [6-34] DEBUG DataSourceLoader - For DataSource 'animals', got instanceId 17
    === 2014-09-01 14:22:33,958 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'employees'
    === 2014-09-01 14:22:33,967 [6-34] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\ds\employees.ds.xml: 8ms
    === 2014-09-01 14:22:33,968 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
    === 2014-09-01 14:22:33,973 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSourceField'
    === 2014-09-01 14:22:33,977 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'field'
    === 2014-09-01 14:22:33,993 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'ValueMap'
    === 2014-09-01 14:22:34,000 [6-34] DEBUG DataSourceLoader - For DataSource 'employees', got instanceId 21
    === 2014-09-01 14:22:34,020 [6-34] INFO  Compression - /builtinds/sc/DataSourceLoader: 7688 -> 2039 bytes
    === 2014-09-01 14:22:34,298 [6-31] INFO  Download - Returning 304: Not modified on conditional get of: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\skins\Enterprise\skin_styles.css
    === 2014-09-01 14:22:34,601 [6-27] INFO  Compression - /builtinds/hosted.html: 0 -> 20 bytes
    === 2014-09-01 14:22:56,127 [6-34] INFO  Download - Returning 304: Not modified on conditional get of: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\skins\Enterprise\images\blank.gif
    === 2014-09-01 14:23:09,536 [6-37] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.3; WOW64; rv:26.0) Gecko/20100101 Firefox/26.0': Moz (Gecko) with Accept-Encoding header
    === 2014-09-01 14:23:09,546 [6-37] DEBUG IDACall - Header Name:Value pair: Cookie:isc_cState=ready; JSESSIONID=1o1f1tg9pe2gdssg1ir5j9c93
    === 2014-09-01 14:23:09,546 [6-37] DEBUG IDACall - Header Name:Value pair: DNT:1
    === 2014-09-01 14:23:09,546 [6-37] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888
    === 2014-09-01 14:23:09,546 [6-37] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
    === 2014-09-01 14:23:09,546 [6-37] DEBUG IDACall - Header Name:Value pair: Content-Length:1006
    === 2014-09-01 14:23:09,547 [6-37] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
    === 2014-09-01 14:23:09,547 [6-37] DEBUG IDACall - Header Name:Value pair: Accept-Language:de-de,de;q=0.8,en-us;q=0.5,en;q=0.3
    === 2014-09-01 14:23:09,547 [6-37] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
    === 2014-09-01 14:23:09,547 [6-37] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
    === 2014-09-01 14:23:09,547 [6-37] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:8888/BuiltInDS.html?gwt.codesvr=127.0.0.1:9997
    === 2014-09-01 14:23:09,547 [6-37] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.3; WOW64; rv:26.0) Gecko/20100101 Firefox/26.0
    === 2014-09-01 14:23:09,547 [6-37] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
    === 2014-09-01 14:23:09,548 [6-37] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
    === 2014-09-01 14:23:09,548 [6-37] DEBUG IDACall - session exists: 1o1f1tg9pe2gdssg1ir5j9c93
    === 2014-09-01 14:23:09,548 [6-37] DEBUG IDACall - remote user: null
    === 2014-09-01 14:23:09,574 [6-31] INFO  Download - Returning 304: Not modified on conditional get of: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\skins\Enterprise\images\loadingSmall.gif
    === 2014-09-01 14:23:09,577 [6-36] INFO  Download - Returning 304: Not modified on conditional get of: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\skins\Enterprise\images\pickers\pickers_sprite.png
    === 2014-09-01 14:23:09,591 [6-37] DEBUG XML - Parsed XML from (in memory stream): 6ms
    === 2014-09-01 14:23:09,592 [6-37] DEBUG ISCKeyedObjectPool - Borrowing object for 'transaction'
    === 2014-09-01 14:23:09,593 [6-37] DEBUG ISCKeyedObjectPool - Borrowing object for 'Object'
    === 2014-09-01 14:23:09,595 [6-37] DEBUG ISCKeyedObjectPool - Borrowing object for 'List'
    === 2014-09-01 14:23:09,606 [6-37] DEBUG XML - Parsed XML from C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\system\schema\List.ds.xml: 8ms
    === 2014-09-01 14:23:09,606 [6-37] DEBUG ISCKeyedObjectPool - Borrowing object for 'DataSource'
    === 2014-09-01 14:23:09,614 [6-37] DEBUG ISCKeyedObjectPool - Borrowing object for 'elem'
    === 2014-09-01 14:23:09,616 [6-37] DEBUG RPCManager - Processing 1 requests.
    === 2014-09-01 14:23:09,643 [6-37] DEBUG ISCKeyedObjectPool - Borrowing object for 'animals'
    === 2014-09-01 14:23:09,643 [6-37] DEBUG DSRequest - Caching instance 17 of DS animals from DSRequest.getDataSource()
    === 2014-09-01 14:23:09,645 [6-37] DEBUG RPCManager - Request #1 (DSRequest) payload: {
        criteria:{
        },
        operationConfig:{
            dataSource:"animals",
            operationType:"fetch",
            textMatchStyle:"exact"
        },
        startRow:0,
        endRow:75,
        componentId:"isc_ListGrid_1",
        appID:"builtinApplication",
        operation:"animals_fetch",
        oldValues:{
        }
    }
    === 2014-09-01 14:23:09,645 [6-37] INFO  IDACall - Performing 1 operation(s)
    === 2014-09-01 14:23:09,647 [6-37] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
    === 2014-09-01 14:23:09,647 [6-37] DEBUG DeclarativeSecurity - DataSource animals is not in the pre-checked list, processing...
    === 2014-09-01 14:23:09,663 [6-37] DEBUG AppBase - [builtinApplication.animals_fetch] No userTypes defined, allowing anyone access to all operations for this application
    === 2014-09-01 14:23:09,664 [6-37] DEBUG AppBase - [builtinApplication.animals_fetch] No public zero-argument method named '_animals_fetch' found, performing generic datasource operation
    === 2014-09-01 14:23:09,665 [6-37] INFO  SQLDataSource - [builtinApplication.animals_fetch] Performing fetch operation with
    	criteria: {}	values: {}
    === 2014-09-01 14:23:09,688 [6-37] INFO  SQLWhereClause - [builtinApplication.animals_fetch] empty condition
    === 2014-09-01 14:23:09,690 [6-37] INFO  SQLDataSource - [builtinApplication.animals_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
    === 2014-09-01 14:23:09,894 [6-37] DEBUG SQLDataSource - [builtinApplication.animals_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause
    === 2014-09-01 14:23:09,895 [6-37] DEBUG SQLDataSource - [builtinApplication.animals_fetch] Eval'd row count query: SELECT COUNT(*) FROM animals WHERE ('1'='1')
    === 2014-09-01 14:23:09,994 [6-37] INFO  PoolManager - [builtinApplication.animals_fetch] SmartClient pooling disabled for 'HSQLDB' objects
    === 2014-09-01 14:23:09,995 [6-37] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] Initializing SQL config for 'HSQLDB' from system config - using DriverManager:  org.hsqldb.jdbcDriver
    === 2014-09-01 14:23:09,996 [6-37] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] org.hsqldb.jdbcDriver lookup successful
    === 2014-09-01 14:23:09,997 [6-37] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:hsql://localhost/isomorphic
    === 2014-09-01 14:23:09,997 [6-37] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] Passing JDBC URL only to getConnection
    === 2014-09-01 14:23:10,121 [6-37] DEBUG PoolableSQLConnectionFactory - [builtinApplication.animals_fetch] makeObject() created an unpooled Connection '1679112165'
    === 2014-09-01 14:23:10,122 [6-37] DEBUG SQLConnectionManager - [builtinApplication.animals_fetch] Borrowed connection '1679112165'
    === 2014-09-01 14:23:10,122 [6-37] DEBUG SQLDriver - [builtinApplication.animals_fetch] About to execute SQL query in 'HSQLDB' using connection '1679112165'
    === 2014-09-01 14:23:10,122 [6-37] INFO  SQLDriver - [builtinApplication.animals_fetch] Executing SQL query on 'HSQLDB': SELECT COUNT(*) FROM animals WHERE ('1'='1')
    === 2014-09-01 14:23:10,148 [6-37] DEBUG SQLDataSource - [builtinApplication.animals_fetch] Using SQL Limit query
    === 2014-09-01 14:23:10,148 [6-37] DEBUG SQLDataSource - [builtinApplication.animals_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT LIMIT 0 75  animals.commonName, animals.scientificName, animals.lifeSpan, animals.status, animals.diet, animals.information, animals.picture FROM animals WHERE ('1'='1')
    === 2014-09-01 14:23:10,148 [6-37] DEBUG SQLDataSource - [builtinApplication.animals_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT LIMIT 0 75  animals.commonName, animals.scientificName, animals.lifeSpan, animals.status, animals.diet, animals.information, animals.picture FROM animals WHERE ('1'='1')
    === 2014-09-01 14:23:10,159 [6-37] INFO  DSResponse - [builtinApplication.animals_fetch] DSResponse: List with 29 items
    === 2014-09-01 14:23:10,160 [6-37] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
    === 2014-09-01 14:23:10,162 [6-37] DEBUG RPCManager - non-DMI response, dropExtraFields: false
    === 2014-09-01 14:23:10,176 [6-37] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1679112165
    === 2014-09-01 14:23:10,177 [6-37] DEBUG SQLConnectionManager - About to close JDBCConnection with hashcode "1679112165"
    === 2014-09-01 14:23:10,179 [6-37] INFO  Compression - /builtinds/sc/IDACall: 10585 -> 4153 bytes
    === 2014-09-01 14:23:16,047 [6-36] INFO  Download - Returning 304: Not modified on conditional get of: C:\Users\me\workspace\lib\smartgwtee-4.1p\samples\built-in-ds\war\builtinds\sc\skins\Enterprise\images\Scrollbar\vscroll_sprite.png
    It's interesting to see the following lines:
    Code:
    === 2014-09-01 14:22:33,936 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'criteria'
    === 2014-09-01 14:22:33,937 [6-34] DEBUG ISCKeyedObjectPool - Borrowing object for 'criterion'
    === 2014-09-01 14:22:33,942 [6-34] DEBUG DataSourceLoader - For DataSource 'animals', got instanceId 17
    It seems the framework notices and processes the tags when processing the ds.xml-file but fails to apply the filter.

    I also tried the commented out versions of the criteria in the .ds.xml one after the other out of interest and because I thought they might work - they don't. In fact, the 2nd produces an hard exception in the client (Dev Mode Tab in Eclipse: "
    com.smartgwt.client.core.JsObject$SGWT_WARN: 14:45:00.874:TMR6:WARN:Log:Uncaught JavaScript exception: NS_ERROR_XPC_CANT_MODIFY_PROP_ON_WN: Cannot modify properties of a WrappedNative in http://127.0.0.1:8888/builtinds/sc/modules/ISC_Core.js, line 830"
    . This is probably OK and expected to happen.).

    Could you please look into this regarding the missing advanced criteria?

    Best regards,
    Blama
    Last edited by Blama; 1 Sep 2014, 06:46. Reason: Checked simple case difference as well.

    Leave a comment:


  • Isomorphic
    replied
    We've done some testing and this feature is working for us.

    We've been testing against the shipped "animals" data source.

    For simple criteria you can use this approach (for example):
    Code:
        <operationBindings>
            <operationBinding operationType="fetch">
                <criteria fieldName="lifeSpan" value="20"/>
                <criteria fieldName="scientificName" value="Ateles panicus"/>
            </operationBinding>
        </operationBindings>
    which generates the following criteria:
    log:
    === 2014-08-29 17:55:32,665 [l0-0] INFO SQLDataSource - [builtinApplication.animals_fetch] Performing fetch operation with
    criteria: {lifeSpan:"20",scientificName:"Ateles panicus"} values: {lifeSpan:"20",scientificName:"Ateles panicus"}
    ...
    For AdvancedCriteria, you can set the "_constructor" attribute, like this:
    Code:
    <operationBindings>
            <operationBinding operationType="fetch"  >
    
                <criteria _constructor="AdvancedCriteria" operator="and">
                    <criterion fieldName="lifeSpan" operator="greaterThan" value="10" />
                    <criterion fieldName="scientificName" operator="iContains" value="ana" />
                </criteria>
    
            </operationBinding>
        </operationBindings>
    which behaves as expected, generating output like this:
    === 2014-08-29 19:51:28,316 [l0-0] INFO SQLDataSource - [builtinApplication.animals_fetch] Performing fetch operation with
    criteria: {criteria:[{value:"10",fieldName:"lifeSpan",operator:"greaterThan"},{value:"ana",fieldName:"scientificName",operator:"iContains"}],operator:"and",_constructor:"AdvancedCriteria"} values: {criteria:[{value:"10",fieldName:"lifeSpan",operator:"greaterThan"},{value:"ana",fieldName:"scientificName",operator:"iContains"}],operator:"and",_constructor:"AdvancedCriteria"}
    === 2014-08-29 19:51:28,331 [l0-0] INFO SQLDataSource - [builtinApplication.animals_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
    === 2014-08-29 19:51:28,341 [l0-0] DEBUG SQLDataSource - [builtinApplication.animals_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause
    === 2014-08-29 19:51:28,342 [l0-0] DEBUG SQLDataSource - [builtinApplication.animals_fetch] Eval'd row count query: SELECT COUNT(*) FROM animals WHERE ((animals.lifeSpan > 10 AND animals.lifeSpan IS NOT NULL) AND (animals.scientificName LIKE '%ana%' AND animals.scientificName IS NOT NULL))
    Regards
    Isomorphic Software

    Leave a comment:


  • Isomorphic
    replied
    We're taking a look - we'll follow up soon

    Leave a comment:


  • Blama
    replied
    Hi Isomorphic,

    please see this .ds.xml file, where I try to use the feature. For me (4.1-p20140822), it does not work at all, no criteria is generated for me in no case, even not the simple one from the Quick Start Guide.
    See how I stripped down the .ds.xml to the most basic configuration.

    Code:
    <DataSource dbName="Oracle" tableName="V_MAIL" ID="V_MAIL" serverType="sql">
    	<!-- serverConstructor="com.mycompany.myproj.server.LMSSQLDataSource" -->
    	<fields>
    		<field name="TENANT_ID" type="integer" />
    		<field primaryKey="true" name="ID" type="integer" />
    		<field name="NAME" length="60" type="text" />
    		<field name="INPROCESS" length="1" type="boolean" escapeHTML="true" sqlStorageStrategy="singleCharYN" />
    		<field name="FIRST_DAYS_PASSED" type="integer" />
    	</fields>
    	<operationBindings>
    		<operationBinding operationType="fetch" operationId="fetchFirstRow" requiresRole="serverOnly">
    			<advancedcriteria operator="and">
    				<criterion fieldName="FIRST_DAYS_PASSED" operator="equals" value="1" />
    				<criterion fieldName="FIRST_DAYS_PASSED" operator="notEquals" value="2" />
    			</advancedcriteria>
    
    			<AdvancedCriteria operator="and">
    				<criterion fieldName="FIRST_DAYS_PASSED" operator="equals" value="1" />
    				<criterion fieldName="FIRST_DAYS_PASSED" operator="notEquals" value="2" />
    			</AdvancedCriteria>
    
    			<advancedCriteria operator="and">
    				<criterion fieldName="FIRST_DAYS_PASSED" operator="equals" value="1" />
    				<criterion fieldName="FIRST_DAYS_PASSED" operator="notEquals" value="2" />
    			</advancedCriteria>
    
    			<criteria fieldName="FIRST_DAYS_PASSED" operator="equals" value="1" />
    			<criterion fieldName="FIRST_DAYS_PASSED" operator="equals" value="1" />
    			<criteria fieldName="INPROCESS" operator="equals" value="false" />
    			<criterion fieldName="INPROCESS" operator="equals" value="false" />
    			<!-- <tableClause>(SELECT ROWNUM AS RN, V_MAIL.* FROM V_MAIL ORDER BY lastsent, name) V_MAIL</tableClause> -->
    			<!-- <whereClause>RN = 1 AND ($defaultWhereClause)</whereClause> -->
    		</operationBinding>
    
    		<operationBinding operationType="fetch" requiresRole="serverOnly" />
    		<operationBinding operationType="update" requiresRole="serverOnly" />
    		<operationBinding operationType="add" requiresRole="serverOnly" />
    		<operationBinding operationType="remove" requiresRole="serverOnly" />
    	</operationBindings>
    </DataSource>
    The resulting log is:
    Code:
    === 2014-08-28 23:28:17,888 [ec-5] INFO  SQLDataSource - [builtinApplication.fetchFirstRow] Performing fetch operation with
    	criteria: {}	values: {}
    === 2014-08-28 23:28:17,895 [ec-5] INFO  SQLWhereClause - [builtinApplication.fetchFirstRow] empty condition
    === 2014-08-28 23:28:17,900 [ec-5] INFO  SQLDataSource - [builtinApplication.fetchFirstRow] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
    === 2014-08-28 23:28:17,904 [ec-5] INFO  SQLDataSource - [builtinApplication.fetchFirstRow] 244: Executing SQL query on 'Oracle': SELECT ...fieldnames... FROM V_MAIL WHERE ('1'='1')
    =
    The .ds.xml is successfully parsed and up to date.

    Can you confirm that the feature is working in 4.1p (4.1-p20140822) and if so, post an example .ds.xml?

    Best regards,
    Blama

    Leave a comment:


  • Javadocs for com.smartgwt.client.docs.serverds.AdvancedCriteria/Criterion (examples)

    Hi Isomorphic,

    from the Quick Start Guide, chapter "Operation Bindings", I get that this works in .ds.xml files:
    Code:
    <operationBinding operationType="fetch">
    	<criteria fieldName="deleted" value="false"/>
    </operationBinding>
    I think that is pretty straight forward having seen the example. But I don't know about when it comes to more advanced cases as AdvancedCriteria+Criterion.

    The docs for serverds-AdvancedCriteria is only a copy of clientside-AdvancedCriteria besides the introductory sentence.

    How would one express nested criteria? Only with nested <criteria>-tags?

    I'll try that later but I think that this is definitely missing from the docs.

    Best regards,
    Blama
    Last edited by Blama; 1 Sep 2014, 04:09.
Working...
X