[Server@5eb489]: [Thread[main,5,main]]: checkRunning(false) entered
[Server@5eb489]: [Thread[main,5,main]]: checkRunning(false) exited
[Server@5eb489]: Startup sequence initiated from main() method
[Server@5eb489]: Loaded properties from [C:\amit\office-workspace\built-in-ds\war\server.properties]
[Server@5eb489]: Initiating startup sequence...
[Server@5eb489]: Server socket opened successfully in 0 ms.
[Server@5eb489]: Database [index=0, id=0, db=file:C:\amit\office-workspace\built-in-ds\war\WEB-INF\db\hsqldb\isomorphic, alias=isomorphic] opened sucessfully in 1203 ms.
[Server@5eb489]: Startup sequence completed in 1203 ms.
[Server@5eb489]: 2011-02-16 14:15:25.325 HSQLDB server 1.8.0 is online
[Server@5eb489]: To close normally, connect and execute SHUTDOWN SQL
[Server@5eb489]: From command line, use [Ctrl]+[C] to abort abruptly
ISC: Configuring log4j from: file:/C:/amit/office-workspace/built-in-ds/war/WEB-INF/classes/log4j.isc.config.xml
=== 2011-02-16 14:15:25,762 [main] INFO  ISCInit - Isomorphic SmartClient Framework - Initializing
=== 2011-02-16 14:15:25,778 [main] INFO  ConfigLoader - Attempting to load framework.properties from CLASSPATH
=== 2011-02-16 14:15:25,856 [main] INFO  ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/C:/amit/office-workspace/built-in-ds/war/WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties
=== 2011-02-16 14:15:25,856 [main] INFO  ConfigLoader - Attempting to load project.properties from CLASSPATH
=== 2011-02-16 14:15:25,871 [main] INFO  ConfigLoader - Unable to locate project.properties in CLASSPATH
=== 2011-02-16 14:15:25,871 [main] INFO  ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/C:/amit/office-workspace/built-in-ds/war/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
=== 2011-02-16 14:15:25,871 [main] INFO  ConfigLoader - Attempting to load server.properties from CLASSPATH
=== 2011-02-16 14:15:25,871 [main] INFO  ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/C:/amit/office-workspace/built-in-ds/war/WEB-INF/classes/server.properties
=== 2011-02-16 14:15:25,871 [main] INFO  Logger - Logging system started.
=== 2011-02-16 14:15:25,887 [main] INFO  ISCInit - Isomorphic SmartClient Framework (SC_SNAPSHOT-2010-12-07/EVAL Deployment 2010-12-07) - Initialization Complete
=== 2011-02-16 14:15:25,887 [main] INFO  ISCInit - Auto-detected webRoot - using: C:\amit\office-workspace\built-in-ds\war
log4j:WARN No appenders could be found for logger (org.apache.jasper.compiler.JspRuntimeContext).
log4j:WARN Please initialize the log4j system properly.
=== 2011-02-16 14:15:26,355 [main] INFO  PreCache - Isomorphic PreCache servlet loading
=== 2011-02-16 14:15:26,387 [main] INFO  PoolManager - SmartClient pooling disabled for 'DataSource' objects
=== 2011-02-16 14:15:26,465 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\DataSource.ds.xml: 16ms
=== 2011-02-16 14:15:26,480 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\builtinTypes.xml: 15ms
=== 2011-02-16 14:15:26,574 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\DataSourceField.ds.xml: 0ms
=== 2011-02-16 14:15:26,605 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\Validator.ds.xml: 0ms
=== 2011-02-16 14:15:26,621 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\ValueMap.ds.xml: 0ms
=== 2011-02-16 14:15:26,637 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\InstantDataApp.ds.xml: 16ms
=== 2011-02-16 14:15:26,683 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\Application.ds.xml: 0ms
=== 2011-02-16 14:15:26,699 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\IDAPage.ds.xml: 0ms
=== 2011-02-16 14:15:26,699 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\IDAUserType.ds.xml: 0ms
=== 2011-02-16 14:15:26,715 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\IDAOperation.ds.xml: 0ms
=== 2011-02-16 14:15:26,746 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\Canvas.ds.xml: 16ms
=== 2011-02-16 14:15:26,808 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\MethodDeclaration.ds.xml: 0ms
=== 2011-02-16 14:15:26,824 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\Img.ds.xml: 0ms
=== 2011-02-16 14:15:26,824 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\StatefulCanvas.ds.xml: 0ms
=== 2011-02-16 14:15:26,855 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\Button.ds.xml: 15ms
=== 2011-02-16 14:15:26,855 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\ListViewer.ds.xml: 0ms
=== 2011-02-16 14:15:26,871 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\ListGrid.ds.xml: 16ms
=== 2011-02-16 14:15:26,902 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\DynamicForm.ds.xml: 0ms
=== 2011-02-16 14:15:26,918 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\FormItem.ds.xml: 0ms
=== 2011-02-16 14:15:26,933 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\DetailViewer.ds.xml: 0ms
=== 2011-02-16 14:15:26,949 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\DetailViewerField.ds.xml: 0ms
=== 2011-02-16 14:15:26,949 [main] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\isomorphicXML.ds.xml: 0ms
=== 2011-02-16 14:15:26,949 [main] INFO  PreCache - Isomorphic PreCache complete (594ms)
=== 2011-02-16 14:16:24,469 [l0-0] INFO  RequestContext - URL: '/BuiltInDS.html', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,485 [l0-0] INFO  Compression - /BuiltInDS.html: 1923 -> 870 bytes
=== 2011-02-16 14:16:24,781 [l0-0] INFO  RequestContext - URL: '/builtinds/builtinds.nocache.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,781 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/DataSourceLoader', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,781 [l0-0] INFO  Compression - /builtinds/builtinds.nocache.js: 6536 -> 2580 bytes
Looking for ID:supplyItem
=== 2011-02-16 14:16:24,797 [l0-2] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\ds\supplyItem.ds.xml: 0ms
Looking for ID:DataSource
Looking for ID:DataSource
=== 2011-02-16 14:16:24,813 [l0-0] INFO  RequestContext - URL: '/builtinds/builtinds.nocache.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
Looking for ID:DataSourceField
Looking for ID:field
=== 2011-02-16 14:16:24,813 [l0-0] INFO  Compression - /builtinds/builtinds.nocache.js: 6536 -> 2580 bytes
Looking for ID:ValueMap
Looking for ID:Validator
Looking for ID:validator
=== 2011-02-16 14:16:24,828 [l0-0] INFO  RequestContext - URL: '/builtinds/clear.cache.gif', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,844 [l0-3] INFO  RequestContext - URL: '/builtinds/sc/initsc.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,844 [l0-4] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Foundation.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,844 [l0-3] INFO  Compression - /builtinds/sc/initsc.js: 47 -> 72 bytes
=== 2011-02-16 14:16:24,859 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Grids.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,859 [l0-0] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Calendar.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,859 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Containers.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,875 [l0-3] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_RichTextEditor.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,891 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Forms.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 8.0; Windows NT 5.1; Trident/4.0; .NET CLR 1.0.3705; .NET CLR 1.1.4322; InfoPath.1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-02-16 14:16:24,922 [l0-6] INFO  Compression - /builtinds/sc/modules/ISC_Containers.js: 110119 -> 27228 bytes
=== 2011-02-16 14:16:24,906 [l0-0] INFO  Compression - /builtinds/sc/modules/ISC_Calendar.js: 102519 -> 24581 bytes
=== 2011-02-16 14:16:24,906 [l0-4] INFO  Compression - /builtinds/sc/modules/ISC_Foundation.js: 219230 -> 52149 bytes
=== 2011-02-16 14:16:24,906 [l0-3] INFO  Compression - /builtinds/sc/modules/ISC_RichTextEditor.js: 38915 -> 10930 bytes
=== 2011-02-16 14:16:25,109 [l0-5] INFO  Compression - /builtinds/sc/modules/ISC_Grids.js: 660431 -> 167339 bytes
Looking for ID:animals
=== 2011-02-16 14:16:25,125 [l0-2] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\ds\animals.ds.xml: 0ms
Looking for ID:DataSource
Looking for ID:DataSource
Looking for ID:DataSourceField
Looking for ID:field
Looking for ID:ValueMap
Looking for ID:employees
Xml:<DataSource    ID="employees"    serverType="sql"    tableName="employeeTable"    recordName="employee"    testFileName="/examples/shared/ds/test_data/employees.data.xml"    titleField="Name">    <fields>        <field name="EmployeeId"      title="Employee ID"     type="integer"  primaryKey="true"  required="true"/>        <field name="ReportsTo"       title="Manager"         type="integer"  required="true"                foreignKey="employees.EmployeeId"  rootValue="1" detail="true"/>        <field name="Job"             title="Title"           type="text"     length="128"/>         <field name="EmployeeType"    title="Employee Type"   type="text"     length="40"/>        <field name="EmployeeStatus"  title="Status"          type="text"     length="40"/>        <field name="OrgUnit"         title="Org Unit"        type="text"     length="128"/>        <field name="Gender"          title="Gender"          type="text"     length="7">            <valueMap>                <value>male</value>                <value>female</value>            </valueMap>        </field>        <field name="MaritalStatus"   title="Marital Status"  type="text"     length="10">            <valueMap>                <value>married</value>                <value>single</value>            </valueMap>        </field>    </fields><operationBindings><operationBinding operationType="fetch"><whereClause> Gender = 'male' </whereClause></operationBinding></operationBindings></DataSource>
=== 2011-02-16 14:16:25,140 [l0-2] DEBUG XML - Parsed XML from (in memory stream): 15ms
Looking for ID:DataSource
Looking for ID:DataSource
Looking for ID:DataSourceField
Looking for ID:field
Looking for ID:ValueMap
Looking for ID:ValueMap
Looking for ID:operationBinding
Looking for ID:OperationBinding
=== 2011-02-16 14:16:25,140 [l0-2] DEBUG XML - Parsed XML from C:\amit\office-workspace\built-in-ds\war\builtinds\sc\system\schema\OperationBinding.ds.xml: 0ms
Looking for ID:Object
Looking for ID:Object
Looking for ID:Object
Looking for ID:fields
Looking for ID:Object
Looking for ID:operationType
Looking for ID:Object
Looking for ID:customSQL
Looking for ID:valueMap
Looking for ID:Object
Looking for ID:dataFormat
Looking for ID:Object
Looking for ID:Object
Looking for ID:wsOperation
Looking for ID:Object
Looking for ID:recordName
Looking for ID:Object
Looking for ID:recordXPath
Looking for ID:Object
Looking for ID:dropExtraFields
Looking for ID:Object
Looking for ID:responseDataSchema
Looking for ID:Object
Looking for ID:operationId
Looking for ID:Object
Looking for ID:useFlatFields
Looking for ID:Object
Looking for ID:dataURL
Looking for ID:Object
Looking for ID:dataTransport
Looking for ID:Object
Looking for ID:defaultParams
Looking for ID:Object
Looking for ID:soapAction
Looking for ID:Object
Looking for ID:dataProtocol
Looking for ID:Object
Looking for ID:callbackParam
Looking for ID:Object
Looking for ID:requestProperties
Looking for ID:Object
Looking for ID:serverObject
Looking for ID:Object
Looking for ID:serverMethod
Looking for ID:Object
Looking for ID:methodArguments
Looking for ID:Object
Looking for ID:xmlNamespaces
Looking for ID:Object
Looking for ID:preventHTTPCaching
Looking for ID:Object
Looking for ID:spoofResponses
Looking for ID:Object
Looking for ID:exportResults
Looking for ID:Object
Looking for ID:useForCacheSync
Looking for ID:Object
Looking for ID:canSyncCache