14:01:07.351:MUP7:WARN:Log:ClassFactory.addGlobalID: ID:'wAdvancedFind' for object '[Window ID:wAdvancedFind]' collides with ID of existing object '[Window ID:wAdvancedFind]'. The pre-existing widget will be destroyed.
14:01:11.669:KPR2:WARN:ListGrid:isc_ListGrid_633:unable to look up DataSource: , databinding will not be used
14:01:11.913:KPR2:INFO:RPCManager:sendQueue[36]: 1 RPCRequest(s); transport: xmlHttpRequest; target: coreservices/CountryService/countries/
14:01:11.916:KPR2:DEBUG:RPCManager:XMLHttpRequest GET from coreservices/CountryService/countries/ with fields: {_dataSource: "countryDS"} full URL string: coreservices/CountryService/countries/?_dataSource=countryDS
14:01:11.927:KPR2:INFO:RPCManager:sendQueue[37]: 1 RPCRequest(s); transport: xmlHttpRequest; target: coreservices/CurrencyService/currencies/
14:01:11.930:KPR2:DEBUG:RPCManager:XMLHttpRequest GET from coreservices/CurrencyService/currencies/ with fields: {_dataSource: "currencyDS"} full URL string: coreservices/CurrencyService/currencies/?_dataSource=currencyDS
14:01:11.941:KPR2:WARN:RPCManager:sendQueue called with no current queue, ignoring
14:01:12.070:INFO:ResultSet:isc_ListGrid_633:Creating new isc.ResultSet for operation 'organizationDS_fetch' with filterValues: {
    _constructor:"AdvancedCriteria", 
    operator:"and", 
    criteria:[
        {
            fieldName:"code", 
            operator:"equals", 
            value:"TSO"
        }
    ]
}
14:01:12.075:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):setCriteria: filter criteria changed, invalidating cache
14:01:12.079:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):Invalidating cache
14:01:12.088:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):getRange(0,38), cache check: 0,43 firstMissingRow: 0 lastMissingRow: 43
14:01:12.093:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):getRange: guessing forward scrolling
14:01:12.098:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):getRange(0, 38) will fetch from 0 to 50
14:01:12.104:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):fetching rows 0,50 from server
14:01:12.113:DEBUG:RPCManager:Grabbed prompt from first request that defined one: Finding records that match your criteria...
14:01:12.120:INFO:RPCManager:sendQueue[38]: 1 RPCRequest(s); transport: xmlHttpRequest; target: coreservices/OrganizationService/organizations/
14:01:12.127:DEBUG:RPCManager:XMLHttpRequest GET from coreservices/OrganizationService/organizations/ with fields: {_startRow: 0,
_endRow: 50,
_textMatchStyle: "exact",
_dataSource: "organizationDS",
_constructor: "AdvancedCriteria",
operator: "and",
criteria: Array[1]} full URL string: coreservices/OrganizationService/organizations/?_startRow=0&_endRow=50&_textMatchStyle=exact&_dataSource=organizationDS&_constructor=AdvancedCriteria&operator=and&criteria=%7B%0D%20%20%20%20%22fieldName%22%3A%22code%22%2C%20%0D%20%20%20%20%22operator%22%3A%22equals%22%2C%20%0D%20%20%20%20%22value%22%3A%22TSO%22%0D%7D
14:01:12.549:TMR8[E]:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):getRange(0, 32) satisfied from cache
14:01:12.628:XRP5:INFO:RPCManager:transaction 36 arrived after 713ms
14:01:12.690:XRP0:INFO:RPCManager:transaction 38 arrived after 565ms
14:01:12.701:XRP0:DEBUG:RPCManager:Result string for transaction 38: "{"OrganizationDTO":{"endRow":50,"startRow":0,"status":200,"totalRows":1,"data":{"dbid":671,"code":"TSO","name":"A. dddTsourekaraeffss","isActive":false,"currencyCode":"GEL","countryCode":"AW","parentOrganizationCode":2131,"parentOrganizationDbid":2105,"duns":0,"crudStatus":true,"version":19}}}"
14:01:12.713:XRP0:INFO:RPCManager:rpcResponse(unstructured) results -->"{"OrganizationDTO":{"endRow":50,"startRow":0,"status":200,"totalRows":1,"data":{"dbid":671,"code":"TSO","name":"A. dddTsourekaraeffss","isActive":false,"currencyCode":"GEL","countryCode":"AW","parentOrganizationCode":2131,"parentOrganizationDbid":2105,"duns":0,"crudStatus":true,"version":19}}}"<--
14:01:12.727:XRP0:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):Received 1 records from server
14:01:12.738:XRP0:WARN:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):fetchData callback: dsResponse.endRow set to:50. dsResponse.totalRows set to:1. endRow cannot exceed total dataset size. Clamping endRow to the end of the dataset (1).
14:01:12.750:XRP0:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):full length set to: 1
14:01:12.762:XRP0:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):integrating 1 rows into cache at position 0
14:01:12.774:XRP0:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):cached 1 rows, from 0 to 1 (1 total rows, 1 cached)
14:01:12.786:XRP0:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):Cache for current criteria complete
14:01:12.806:XRP1:INFO:RPCManager:transaction 37 arrived after 876ms
14:01:12.878:RDQ3:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):getRange(0, 0): returning empty list
14:01:17.632:MUP5:WARN:DynamicForm:isc_DynamicForm_660:unable to look up DataSource: , databinding will not be used
14:01:17.739:MUP5:WARN:DynamicForm:isc_DynamicForm_660:colWidths Array shorter than numCols, remaining columns get '*' size
14:01:17.837:MUP5:INFO:RPCManager:sendQueue[39]: 1 RPCRequest(s); transport: xmlHttpRequest; target: coreservices/CurrencyService/currencies/USD
14:01:17.856:MUP5:DEBUG:RPCManager:XMLHttpRequest GET from coreservices/CurrencyService/currencies/USD with fields: {_dataSource: "currencyDS"} full URL string: coreservices/CurrencyService/currencies/USD?_dataSource=currencyDS
14:01:17.986:MUP5:WARN:ListGrid:isc_ListGrid_708:unable to look up DataSource: , databinding will not be used
14:01:18.169:MUP5:WARN:Canvas:Auto allocation of tab-indices has reached native browser ceiling - tab-order cannot be guaranteed for widgets on this page.
14:01:18.234:MUP5:WARN:ListGrid:isc_ListGrid_729:unable to look up DataSource: , databinding will not be used
14:01:18.520:MUP5:WARN:ListGrid:isc_ListGrid_762:unable to look up DataSource: , databinding will not be used
14:01:18.809:MUP5:WARN:ListGrid:isc_ListGrid_795:unable to look up DataSource: , databinding will not be used
14:01:19.097:MUP5:WARN:ListGrid:isc_ListGrid_828:unable to look up DataSource: , databinding will not be used
14:01:19.383:MUP5:WARN:ListGrid:isc_ListGrid_860:unable to look up DataSource: , databinding will not be used
14:01:19.641:MUP5:WARN:ListGrid:isc_ListGrid_888:unable to look up DataSource: , databinding will not be used
14:01:19.881:MUP5:WARN:ListGrid:isc_ListGrid_909:unable to look up DataSource: , databinding will not be used
14:01:20.232:RDQ1:WARN:TextItem:isc_TextItem_670:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
14:01:20.252:RDQ1:WARN:TextItem:isc_TextItem_671:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
14:01:20.542:TMR7[E]:INFO:ResultSet:isc_ListGrid_708:Creating new isc.ResultSet for operation 'organizationDS_fetch' with filterValues: {
    _constructor:"String", 
    operator:"and", 
    criteria:[
        {
            fieldName:"dbid", 
            operator:"and", 
            value:749
        }, 
        {
            fieldName:"foreignKey", 
            operator:"and", 
            value:"true"
        }, 
        {
            fieldName:"parentRecordName", 
            operator:"and", 
            value:"OrganizationDTO"
        }, 
        {
            fieldName:"subTabTitle", 
            operator:"and", 
            value:"Divisions"
        }
    ]
}
14:01:20.567:TMR7[E]:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):setCriteria: filter criteria changed, invalidating cache
14:01:20.590:TMR7[E]:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):Invalidating cache
14:01:20.617:TMR7[E]:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):getRange(0,38), cache check: 0,43 firstMissingRow: 0 lastMissingRow: 43
14:01:20.639:TMR7[E]:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):getRange: guessing forward scrolling
14:01:20.662:TMR7[E]:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):getRange(0, 38) will fetch from 0 to 50
14:01:20.685:TMR7[E]:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):fetching rows 0,50 from server
14:01:20.712:TMR7[E]:DEBUG:RPCManager:Grabbed prompt from first request that defined one: Finding records that match your criteria...
14:01:20.736:TMR7[E]:INFO:RPCManager:sendQueue[40]: 1 RPCRequest(s); transport: xmlHttpRequest; target: coreservices/OrganizationService/organizations/
14:01:20.762:TMR7[E]:DEBUG:RPCManager:XMLHttpRequest GET from coreservices/OrganizationService/organizations/ with fields: {_startRow: 0,
_endRow: 50,
_textMatchStyle: "exact",
_dataSource: "organizationDS",
_constructor: "String",
operator: "and",
criteria: Array[4]} full URL string: coreservices/OrganizationService/organizations/?_startRow=0&_endRow=50&_textMatchStyle=exact&_dataSource=organizationDS&_constructor=String&operator=and&criteria=%7B%0D%20%20%20%20%22fieldName%22%3A%22dbid%22%2C%20%0D%20%20%20%20%22operator%22%3A%22and%22%2C%20%0D%20%20%20%20%22value%22%3A749%0D%7D&criteria=%7B%0D%20%20%20%20%22fieldName%22%3A%22foreignKey%22%2C%20%0D%20%20%20%20%22operator%22%3A%22and%22%2C%20%0D%20%20%20%20%22value%22%3A%22true%22%0D%7D&criteria=%7B%0D%20%20%20%20%22fieldName%22%3A%22parentRecordName%22%2C%20%0D%20%20%20%20%22operator%22%3A%22and%22%2C%20%0D%20%20%20%20%22value%22%3A%22OrganizationDTO%22%0D%7D&criteria=%7B%0D%20%20%20%20%22fieldName%22%3A%22subTabTitle%22%2C%20%0D%20%20%20%20%22operator%22%3A%22and%22%2C%20%0D%20%20%20%20%22value%22%3A%22Divisions%22%0D%7D
14:01:21.164:TMR7[E]:INFO:RPCManager:sendQueue[41]: 1 RPCRequest(s); transport: xmlHttpRequest; target: coreservices/CountryService/countries/
14:01:21.189:TMR7[E]:DEBUG:RPCManager:XMLHttpRequest GET from coreservices/CountryService/countries/ with fields: {_dataSource: "countryDS"} full URL string: coreservices/CountryService/countries/?_dataSource=countryDS
14:01:21.225:TMR7[E]:INFO:RPCManager:sendQueue[42]: 1 RPCRequest(s); transport: xmlHttpRequest; target: coreservices/CurrencyService/currencies/
14:01:21.254:TMR7[E]:DEBUG:RPCManager:XMLHttpRequest GET from coreservices/CurrencyService/currencies/ with fields: {_dataSource: "currencyDS"} full URL string: coreservices/CurrencyService/currencies/?_dataSource=currencyDS
14:01:21.290:TMR7[E]:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):getRange(0, 24) satisfied from cache
14:01:21.319:TMR7[E]:WARN:RPCManager:sendQueue called with no current queue, ignoring
14:01:21.449:TMR7[E]:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):getRange(0, 13) satisfied from cache
14:01:21.606:XRP3:INFO:RPCManager:transaction 39 arrived after 3751ms
14:01:21.635:XRP3:DEBUG:RPCManager:Result string for transaction 39: "{"currency":{"endRow":0,"startRow":0,"status":200,"totalRows":1,"data":{"code":"USD","name":"United States Dollars","symbol":"$"}}}"
14:01:21.663:XRP3:INFO:RPCManager:rpcResponse(unstructured) results -->"{"currency":{"endRow":0,"startRow":0,"status":200,"totalRows":1,"data":{"code":"USD","name":"United States Dollars","symbol":"$"}}}"<--
14:01:22.812:RDQ7:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):getRange(0, 13) satisfied from cache
14:01:22.862:XRP6:INFO:RPCManager:transaction 40 arrived after 2102ms
14:01:22.889:XRP6:DEBUG:RPCManager:Result string for transaction 40: "{"OrganizationDTO":{"endRow":50,"startRow":0,"status":200,"totalRows":2,"data":[{"dbid":2088,"code":"LLTPP","name":"LLT","isActive":false,"parentOrganizationCode":"AFR","parentOrganizationDbid":749,"crudStatus":true,"version":1},{"dbid":1187,"code":"TTS","name":"Trailco Terminal Services","address1":"1015 Airport Road","city":"Salisbury","postalCode":28147,"phone":"704-633-9733","fax":"704-633-9693","isActive":false,"countryCode":"US","parentOrganizationCode":"AFR","parentOrganizationDbid":749,"crudStatus":true,"version":1}]}}"
14:01:22.918:XRP6:INFO:RPCManager:rpcResponse(unstructured) results -->"{"OrganizationDTO":{"endRow":50,"startRow":0,"status":200,"totalRows":2,"data":[{"dbid":2088,"code":"LLTPP","name":"LLT","isActive":false,"parentOrganizationCode":"AFR","parentOrganizationDbid":749,"crudStatus":true,"version":1},{"dbid":1187,"code":"TTS","name":"Trailco Terminal Services","address1":"1015 Airport Road","city":"Salisbury","postalCode":28147,"phone":"704-633-9733","fax":"704-633-9693","isActive":false,"countryCode":"US","parentOrganizationCode":"AFR","parentOrganizationDbid":749,"crudStatus":true,"version":1}]}}"<--
14:01:22.950:XRP6:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):Received 2 records from server
14:01:22.977:XRP6:WARN:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):fetchData callback: dsResponse.endRow set to:50. dsResponse.totalRows set to:2. endRow cannot exceed total dataset size. Clamping endRow to the end of the dataset (2).
14:01:23.005:XRP6:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):full length set to: 2
14:01:23.033:XRP6:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):integrating 2 rows into cache at position 0
14:01:23.061:XRP6:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):cached 2 rows, from 0 to 2 (2 total rows, 2 cached)
14:01:23.089:XRP6:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):Cache for current criteria complete
14:01:23.129:XRP9:INFO:RPCManager:transaction 42 arrived after 1876ms


14:01:23.246:XRP2:INFO:RPCManager:transaction 41 arrived after 2057ms


14:01:23.387:RDQ8:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):getRange(0, 1) satisfied from cache
14:01:25.634:MUP2:DEBUG:RPCManager:Grabbed prompt from first request that defined one: Saving form...
14:01:25.776:MUP2:INFO:RPCManager:sendQueue[43]: 1 RPCRequest(s); transport: xmlHttpRequest; target: coreservices/OrganizationService/organizations/749
14:01:25.815:MUP2:DEBUG:RPCManager:XMLHttpRequest POST to coreservices/OrganizationService/organizations/749 contentType: application/json with body -->{
    "OrganizationDTO":{
        "dbid":749, 
        "code":"AFR", 
        "name":"ACA Fort Rileyddd", 
        "address1":"ddd", 
        "address2":"sss", 
        "isActive":false, 
        "currencyCode":"USD", 
        "crudStatus":true, 
        "version":1
    }
}<--
14:01:25.860:MUP2:WARN:FormPane:isc_FormPane_648:couldn't find member to remove: [HLayout ID:isc_HLayout_649]
14:01:25.907:MUP2:WARN:FormPane:isc_FormPane_648:couldn't find member to remove: [HLayout ID:isc_HLayout_651]
14:01:26.560:XRP9:INFO:RPCManager:transaction 43 arrived after 746ms
14:01:26.603:XRP9:DEBUG:RPCManager:Result string for transaction 43: "{"OrganizationDTO":{"endRow":0,"startRow":0,"status":200,"totalRows":2213,"data":{"dbid":749,"code":"AFR","name":"ACA Fort Rileyddd","address1":"ddd","address2":"sss","isActive":false,"currencyCode":"USD","crudStatus":true,"version":1}}}"
14:01:26.644:XRP9:INFO:RPCManager:rpcResponse(unstructured) results -->"{"OrganizationDTO":{"endRow":0,"startRow":0,"status":200,"totalRows":2213,"data":{"dbid":749,"code":"AFR","name":"ACA Fort Rileyddd","address1":"ddd","address2":"sss","isActive":false,"currencyCode":"USD","crudStatus":true,"version":1}}}"<--
14:01:26.688:XRP9:DEBUG:ResultSet:isc_ResultSet_934 (created by: isc_ListGrid_612):dataSource data changed firing
14:01:26.729:XRP9:INFO:ResultSet:isc_ResultSet_934 (created by: isc_ListGrid_612):updating cache in place after operationType: update, cached rows: 50, total rows: 2213
14:01:26.771:XRP9:INFO:ResultSet:isc_ResultSet_934 (created by: isc_ListGrid_612):Updating cache: operationType 'update' submitted by 'isc_DynamicForm_660',1 rows update data:
[
{dbid: 749,
code: "AFR",
name: "ACA Fort Rileyddd",
address1: "ddd",
address2: "sss",
isActive: false,
currencyCode: "USD"
}
]
14:01:26.817:XRP9:DEBUG:ResultSet:isc_ResultSet_934 (created by: isc_ListGrid_612):updated cache: 0 row(s) added, 1 row(s) updated, 0 row(s) removed.
14:01:26.863:XRP9:DEBUG:ResultSet:isc_ResultSet_934 (created by: isc_ListGrid_612):full length set to: 2213
14:01:26.910:XRP9:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):dataSource data changed firing
14:01:26.957:XRP9:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):updating cache in place after operationType: update, allMatchingRowsCached true
14:01:27.006:XRP9:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):Updating cache: operationType 'update' submitted by 'isc_DynamicForm_660',1 rows update data:
[
{dbid: 749,
code: "AFR",
name: "ACA Fort Rileyddd",
address1: "ddd",
address2: "sss",
isActive: false,
currencyCode: "USD"
}
]
14:01:27.056:XRP9:WARN:Log:findByKeys: passed record does not have a value for key field 'dbid'
14:01:27.109:XRP9:INFO:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):updated row returned by server doesn't match any cached row,  adding as new row.  Primary key values: {dbid: 749}, complete row: {dbid: 749,
code: "AFR",
name: "ACA Fort Rileyddd",
address1: "ddd",
address2: "sss",
isActive: false,
currencyCode: "USD"
}
14:01:27.164:XRP9:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):updated cache: 1 row(s) added, 0 row(s) updated, 0 row(s) removed.
14:01:27.220:XRP9:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):full length set to: 2
14:01:27.277:XRP9:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):dataSource data changed firing
14:01:27.334:XRP9:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):updating cache in place after operationType: update, allMatchingRowsCached true
14:01:27.392:XRP9:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):Updating cache: operationType 'update' submitted by 'isc_DynamicForm_660',1 rows update data:
[
{dbid: 749,
code: "AFR",
name: "ACA Fort Rileyddd",
address1: "ddd",
address2: "sss",
isActive: false,
currencyCode: "USD"
}
]
14:01:27.452:XRP9:WARN:Log:findByKeys: passed record does not have a value for key field 'dbid'
14:01:27.515:XRP9:INFO:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):updated row returned by server doesn't match any cached row,  adding as new row.  Primary key values: {dbid: 749}, complete row: {dbid: 749,
code: "AFR",
name: "ACA Fort Rileyddd",
address1: "ddd",
address2: "sss",
isActive: false,
currencyCode: "USD"
}
14:01:27.579:XRP9:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):updated cache: 1 row(s) added, 0 row(s) updated, 0 row(s) removed.
14:01:27.646:XRP9:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):full length set to: 3
14:01:27.779:XRP9[E]:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):getRange(0, 2) satisfied from cache
14:01:27.899:XRP9[E]:WARN:Scrollbar:isc_ListGrid_708_body_vscroll:setImage: image 'corner' couldn't be found
14:01:28.291:XRP9[E]:DEBUG:ResultSet:isc_ResultSet_946 (created by: isc_ListGrid_708):getRange(0, 2) satisfied from cache
14:01:28.549:RDQ4:DEBUG:ResultSet:isc_ResultSet_934 (created by: isc_ListGrid_612):getRange(0, 32) satisfied from cache
14:01:28.756:RDQ4:DEBUG:ResultSet:isc_ResultSet_935 (created by: isc_ListGrid_633):getRange(0, 1) satisfied from cache