=== 2011-02-16 14:16:56,423 [l0-5] INFO  Compression - /builtinds/sc/IDACall: 8213 -> 1069 bytes
=== 2011-02-16 14:17:00,390 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_start.png', 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:17:00,390 [l0-5] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_start.png
=== 2011-02-16 14:17:00,405 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_track.png', 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:17:00,405 [l0-5] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_track.png
=== 2011-02-16 14:17:00,421 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_end.png', 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:17:00,421 [l0-5] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_end.png
=== 2011-02-16 14:17:00,436 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vthumb_grip.png', 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:17:00,436 [l0-5] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vthumb_grip.png
=== 2011-02-16 14:17:00,452 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vthumb_start.png', 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:17:00,452 [l0-5] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vthumb_start.png
=== 2011-02-16 14:17:00,452 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vthumb_stretch.png', 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:17:00,468 [l0-5] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vthumb_stretch.png
=== 2011-02-16 14:17:00,468 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vthumb_end.png', 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:17:00,468 [l0-5] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vthumb_end.png
=== 2011-02-16 14:21:14,833 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/button/button_Down_start.png', 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:21:14,833 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/button/button_Down_start.png
=== 2011-02-16 14:21:14,833 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/button/button_Down_stretch.png', 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:21:14,833 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/button/button_Down_stretch.png
=== 2011-02-16 14:21:14,849 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/button/button_Down_end.png', 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:21:14,849 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/button/button_Down_end.png
=== 2011-02-16 14:21:16,551 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/DynamicForm/unchecked_Down.png', 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:21:16,567 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/DynamicForm/unchecked_Down.png
=== 2011-02-16 14:21:16,645 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/DynamicForm/checked.png', 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:21:16,645 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/DynamicForm/checked.png
=== 2011-02-16 14:21:17,676 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/cssButton/button_Over_stretch.png', 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:21:17,676 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/cssButton/button_Over_stretch.png
=== 2011-02-16 14:21:34,793 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/cssButton/button_Down_stretch.png', 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:21:34,793 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/cssButton/button_Down_stretch.png
=== 2011-02-16 14:21:35,089 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/IDACall', 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:21:35,105 [l0-8] DEBUG XML - Parsed XML from (in memory stream): 0ms
Looking for ID:transaction
Looking for ID:Object
Looking for ID:Object
Looking for ID:List
Looking for ID:elem
Looking for ID:Object
Looking for ID:Object
Looking for ID:Object
Looking for ID:Object
Looking for ID:Object
Looking for ID:Object
Looking for ID:Object
Looking for ID:Object
=== 2011-02-16 14:21:35,105 [l0-8] DEBUG RPCManager - Processing 1 requests.
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> <field name="Name"   type="text"     length="128"/> <field name="Email"   type="text"     length="128"/> <field name="Salary"   type="text"     length="128"/>    </fields><operationBindings><operationBinding operationType="fetch"><whereClause> Gender = 'male' </whereClause></operationBinding></operationBindings></DataSource>
=== 2011-02-16 14:21:35,121 [l0-8] DEBUG XML - Parsed XML from (in memory stream): 16ms
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:21:35,121 [l0-8] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        Name:true,
        Email:true,
        Salary:true
    },
    operationConfig:{
        dataSource:"employees",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_ListGrid_0",
    appID:"builtinApplication",
    operation:"employees_fetch",
    oldValues:{
        Name:true,
        Email:true,
        Salary:true
    }
}
=== 2011-02-16 14:21:35,121 [l0-8] INFO  IDACall - Performing 1 operation(s)
=== 2011-02-16 14:21:35,121 [l0-8] DEBUG AppBase - [builtinApplication.employees_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2011-02-16 14:21:35,121 [l0-8] DEBUG AppBase - [builtinApplication.employees_fetch] No public zero-argument method named '_employees_fetch' found, performing generic datasource operation
=== 2011-02-16 14:21:35,121 [l0-8] INFO  SQLDataSource - [builtinApplication.employees_fetch] Performing fetch operation with
	criteria: {Name:true,Email:true,Salary:true}	values: {Name:true,Email:true,Salary:true}
=== 2011-02-16 14:21:35,121 [l0-8] INFO  SQLDataSource - [builtinApplication.employees_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE  Gender = 'male' 
=== 2011-02-16 14:21:35,121 [l0-8] DEBUG SQLDataSource - [builtinApplication.employees_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE  Gender = 'male' 
=== 2011-02-16 14:21:35,121 [l0-8] DEBUG SQLDataSource - [builtinApplication.employees_fetch] Eval'd row count query: SELECT COUNT(*) FROM employeeTable WHERE  Gender = 'male' 
=== 2011-02-16 14:21:35,121 [l0-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_fetch] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:hsql://localhost/isomorphic
=== 2011-02-16 14:21:35,121 [l0-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_fetch] Passing JDBC URL only to getConnection
=== 2011-02-16 14:21:35,136 [l0-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_fetch] Returning unpooled Connection
=== 2011-02-16 14:21:35,136 [l0-8] INFO  SQLDriver - [builtinApplication.employees_fetch] Executing SQL query on 'HSQLDB': SELECT COUNT(*) FROM employeeTable WHERE  Gender = 'male' 
=== 2011-02-16 14:21:35,136 [l0-8] DEBUG SQLDataSource - [builtinApplication.employees_fetch] Using SQL Limit query
=== 2011-02-16 14:21:35,136 [l0-8] DEBUG SQLDataSource - [builtinApplication.employees_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT LIMIT 0 75  employeeTable.Job, employeeTable.Name, employeeTable.EmployeeType, employeeTable.EmployeeStatus, employeeTable.Email, employeeTable.Gender, employeeTable.OrgUnit, employeeTable.Salary, employeeTable.ReportsTo, employeeTable.MaritalStatus, employeeTable.EmployeeId FROM employeeTable WHERE  Gender = 'male' 
=== 2011-02-16 14:21:35,136 [l0-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_fetch] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:hsql://localhost/isomorphic
=== 2011-02-16 14:21:35,136 [l0-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_fetch] Passing JDBC URL only to getConnection
=== 2011-02-16 14:21:35,136 [l0-8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.employees_fetch] Returning unpooled Connection
=== 2011-02-16 14:21:35,136 [l0-8] INFO  DSResponse - [builtinApplication.employees_fetch] DSResponse: List with 51 items
=== 2011-02-16 14:21:35,152 [l0-8] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
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> <field name="Name"   type="text"     length="128"/> <field name="Email"   type="text"     length="128"/> <field name="Salary"   type="text"     length="128"/>    </fields><operationBindings><operationBinding operationType="fetch"><whereClause> Gender = 'male' </whereClause></operationBinding></operationBindings></DataSource>
=== 2011-02-16 14:21:35,152 [l0-8] DEBUG XML - Parsed XML from (in memory stream): 0ms
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:21:35,152 [l0-8] DEBUG RPCManager - non-DMI response, dropExtraFields: false
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> <field name="Name"   type="text"     length="128"/> <field name="Email"   type="text"     length="128"/> <field name="Salary"   type="text"     length="128"/>    </fields><operationBindings><operationBinding operationType="fetch"><whereClause> Gender = 'male' </whereClause></operationBinding></operationBindings></DataSource>
=== 2011-02-16 14:21:35,152 [l0-8] DEBUG XML - Parsed XML from (in memory stream): 0ms
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
Looking for ID:text
Looking for ID:integer
=== 2011-02-16 14:21:35,167 [l0-8] INFO  Compression - /builtinds/sc/IDACall: 11322 -> 1951 bytes
=== 2011-02-16 14:21:35,199 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_Disabled_start.png', 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:21:35,199 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_Disabled_start.png
=== 2011-02-16 14:21:35,214 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_Disabled_track.png', 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:21:35,214 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_Disabled_track.png
=== 2011-02-16 14:21:35,214 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_Disabled_end.png', 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:21:35,230 [l0-8] INFO  Download - done streaming: C:/amit/office-workspace/built-in-ds/war/builtinds/sc/skins/Enterprise/images/Scrollbar/vscroll_Disabled_end.png