=== 2013-01-30 10:31:28,844 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        typ_id:"1049"
    },
    operationConfig:{
        dataSource:"biViewObj",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    componentId:"isc_TreeGrid_0",
    appID:"builtinApplication",
    operation:"biViewObj_fetch",
    oldValues:{
        typ_id:"1049"
    }
}
=== 2013-01-30 10:31:28,844 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-30 10:31:28,844 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-30 10:31:28,844 [l0-6] DEBUG DeclarativeSecurity - DataSource biViewObj is not in the pre-checked list, processing...
=== 2013-01-30 10:31:28,845 [l0-6] DEBUG AppBase - [builtinApplication.biViewObj_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-30 10:31:28,846 [l0-6] DEBUG AppBase - [builtinApplication.biViewObj_fetch] No public zero-argument method named '_biViewObj_fetch' found, performing generic datasource operation
=== 2013-01-30 10:31:28,886 [l0-6] INFO  SQLDataSource - [builtinApplication.biViewObj_fetch] Performing fetch operation with
	criteria: {typ_id:"1049"}	values: {typ_id:"1049"}
=== 2013-01-30 10:31:28,890 [l0-6] INFO  SQLDataSource - [builtinApplication.biViewObj_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE BI_view_obj.typ_base = $criteria.typ_id
=== 2013-01-30 10:31:28,923 [l0-6] INFO  SQLDataSource - [builtinApplication.biViewObj_fetch] Executing SQL query on 'SQLServer': SELECT BI_view_obj.ID, BI_view_obj.Measure, BI_view_obj.class_version_id, BI_view_obj.dbeg, BI_view_obj.dend, BI_view_obj.full_cod, BI_view_obj.fullname, BI_view_obj.fullnamekz, BI_view_obj.lev, BI_view_obj.level_code, BI_view_obj.ms_name, BI_view_obj.ms_namekz, BI_view_obj.obj_base, BI_view_obj.obj_ver, BI_view_obj.otrasl, BI_view_obj.par_id, BI_view_obj.par_obj_base, BI_view_obj.tree_id, BI_view_obj.tree_name, BI_view_obj.tree_namekz, BI_view_obj.typ_base, BI_view_obj.typ_name, BI_view_obj.typ_ver, BI_view_obj.typreln_version FROM dbo.BI_view_obj WHERE BI_view_obj.typ_base = '1049'
=== 2013-01-30 10:31:28,923 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.biViewObj_fetch] Returning borrowed connection '745346830'
=== 2013-01-30 10:31:28,924 [l0-6] DEBUG SQLDriver - [builtinApplication.biViewObj_fetch] About to execute SQL query in 'SQLServer' using connection '745346830'
=== 2013-01-30 10:31:28,924 [l0-6] INFO  SQLDriver - [builtinApplication.biViewObj_fetch] Executing SQL query on 'SQLServer': SELECT BI_view_obj.ID, BI_view_obj.Measure, BI_view_obj.class_version_id, BI_view_obj.dbeg, BI_view_obj.dend, BI_view_obj.full_cod, BI_view_obj.fullname, BI_view_obj.fullnamekz, BI_view_obj.lev, BI_view_obj.level_code, BI_view_obj.ms_name, BI_view_obj.ms_namekz, BI_view_obj.obj_base, BI_view_obj.obj_ver, BI_view_obj.otrasl, BI_view_obj.par_id, BI_view_obj.par_obj_base, BI_view_obj.tree_id, BI_view_obj.tree_name, BI_view_obj.tree_namekz, BI_view_obj.typ_base, BI_view_obj.typ_name, BI_view_obj.typ_ver, BI_view_obj.typreln_version FROM dbo.BI_view_obj WHERE BI_view_obj.typ_base = '1049'
=== 2013-01-30 10:31:28,957 [l0-6] INFO  DSResponse - [builtinApplication.biViewObj_fetch] DSResponse: List with 23 items
=== 2013-01-30 10:31:28,960 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "745346830"
=== 2013-01-30 10:31:28,960 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-30 10:31:28,961 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: true
=== 2013-01-30 10:31:28,993 [l0-6] INFO  Compression - /register/sc/IDACall: 16120 -> 1830 bytes
=== 2013-01-30 10:31:29,504 [l0-6] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/TreeGrid/folder_closed.png', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0': Moz (Gecko) with Accept-Encoding header
=== 2013-01-30 10:31:29,508 [l0-7] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/TreeGrid/opener_closed.png', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0': Moz (Gecko) with Accept-Encoding header
=== 2013-01-30 10:31:29,516 [l0-6] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/folder_closed.png
=== 2013-01-30 10:31:29,527 [l0-7] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/opener_closed.png
=== 2013-01-30 10:31:31,191 [l0-7] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/TreeGrid/file.png', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0': Moz (Gecko) with Accept-Encoding header
=== 2013-01-30 10:31:31,193 [l0-7] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/file.png
=== 2013-01-30 10:31:31,194 [l0-7] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/TreeGrid/folder_open.png', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0': Moz (Gecko) with Accept-Encoding header
=== 2013-01-30 10:31:31,250 [l0-6] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/TreeGrid/opener_opened.png', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0': Moz (Gecko) with Accept-Encoding header
=== 2013-01-30 10:31:31,342 [l0-7] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/folder_open.png
=== 2013-01-30 10:31:31,524 [l0-6] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/opener_opened.png
=== 2013-01-30 10:31:38,662 [l0-6] INFO  RequestContext - URL: '/register/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0': Moz (Gecko) with Accept-Encoding header
=== 2013-01-30 10:31:38,662 [l0-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:57269
=== 2013-01-30 10:31:38,662 [l0-6] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0
=== 2013-01-30 10:31:38,663 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-01-30 10:31:38,663 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-01-30 10:31:38,663 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-30 10:31:38,663 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-30 10:31:38,663 [l0-6] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:57269/Register.html?gwt.codesvr=127.0.0.1:57201
=== 2013-01-30 10:31:38,663 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Length:1089
=== 2013-01-30 10:31:38,663 [l0-6] DEBUG IDACall - Header Name:Value pair: Cookie:GLog=%7B%0D%20%20%20%20left%3A43%2C%20%0D%20%20%20%20top%3A44%2C%20%0D%20%20%20%20width%3A767%2C%20%0D%20%20%20%20height%3A480%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A4%0D%20%20%20%20%7D%2C%20%0D%20%20%20%20defaultPriority%3A3%2C%20%0D%20%20%20%20trackRPC%3Anull%0D%7D; isc_cState=ready; JSESSIONID=1xu1nybtx49w
=== 2013-01-30 10:31:38,664 [l0-6] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-30 10:31:38,664 [l0-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-30 10:31:38,664 [l0-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-30 10:31:38,664 [l0-6] DEBUG IDACall - session exists: 1xu1nybtx49w
=== 2013-01-30 10:31:38,664 [l0-6] DEBUG IDACall - remote user: null
=== 2013-01-30 10:31:38,668 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 2ms

=== 2013-01-30 10:31:38,673 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        obj_base:"213283"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_ListGrid_1",
    appID:"builtinApplication",
    operation:"FillPropertObj1",
    oldValues:{
        obj_base:"213283"
    }
}
=== 2013-01-30 10:31:38,674 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-30 10:31:38,674 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-30 10:31:38,674 [l0-6] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-01-30 10:31:38,674 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-30 10:31:38,674 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No public zero-argument method named '_FillPropertObj1' found, performing generic datasource operation
=== 2013-01-30 10:31:38,675 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Performing fetch operation with
	criteria: {obj_base:"213283"}	values: {obj_base:"213283"}
=== 2013-01-30 10:31:38,677 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213283'
=== 2013-01-30 10:31:38,677 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.FillPropertObj1] Returning borrowed connection '745346830'
=== 2013-01-30 10:31:38,677 [l0-6] DEBUG SQLDriver - [builtinApplication.FillPropertObj1] About to execute SQL query in 'SQLServer' using connection '745346830'
=== 2013-01-30 10:31:38,677 [l0-6] INFO  SQLDriver - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213283'
=== 2013-01-30 10:31:38,792 [l0-6] INFO  DSResponse - [builtinApplication.FillPropertObj1] DSResponse: List with 8 items
=== 2013-01-30 10:31:38,793 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "745346830"
=== 2013-01-30 10:31:38,793 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-30 10:31:38,795 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-01-30 10:31:38,797 [l0-6] INFO  Compression - /register/sc/IDACall: 1247 -> 555 bytes
=== 2013-01-30 10:31:52,448 [l0-6] INFO  RequestContext - URL: '/register/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0': Moz (Gecko) with Accept-Encoding header
=== 2013-01-30 10:31:52,448 [l0-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:57269
=== 2013-01-30 10:31:52,448 [l0-6] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0
=== 2013-01-30 10:31:52,448 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-01-30 10:31:52,448 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-01-30 10:31:52,448 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-30 10:31:52,449 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-30 10:31:52,449 [l0-6] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:57269/Register.html?gwt.codesvr=127.0.0.1:57201
=== 2013-01-30 10:31:52,449 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Length:1089
=== 2013-01-30 10:31:52,449 [l0-6] DEBUG IDACall - Header Name:Value pair: Cookie:GLog=%7B%0D%20%20%20%20left%3A43%2C%20%0D%20%20%20%20top%3A44%2C%20%0D%20%20%20%20width%3A767%2C%20%0D%20%20%20%20height%3A480%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A4%0D%20%20%20%20%7D%2C%20%0D%20%20%20%20defaultPriority%3A3%2C%20%0D%20%20%20%20trackRPC%3Anull%0D%7D; isc_cState=ready; JSESSIONID=1xu1nybtx49w
=== 2013-01-30 10:31:52,449 [l0-6] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-30 10:31:52,449 [l0-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-30 10:31:52,449 [l0-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-30 10:31:52,451 [l0-6] DEBUG IDACall - session exists: 1xu1nybtx49w
=== 2013-01-30 10:31:52,451 [l0-6] DEBUG IDACall - remote user: null
=== 2013-01-30 10:31:52,455 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 3ms
=== 2013-01-30 10:31:52,457 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2013-01-30 10:31:52,462 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        obj_base:"213272"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_ListGrid_2",
    appID:"builtinApplication",
    operation:"FillPropertObj1",
    oldValues:{
        obj_base:"213272"
    }
}
=== 2013-01-30 10:31:52,462 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-30 10:31:52,462 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-30 10:31:52,462 [l0-6] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-01-30 10:31:52,463 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-30 10:31:52,463 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No public zero-argument method named '_FillPropertObj1' found, performing generic datasource operation
=== 2013-01-30 10:31:52,463 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Performing fetch operation with
	criteria: {obj_base:"213272"}	values: {obj_base:"213272"}

=== 2013-01-30 10:31:52,464 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213272'
=== 2013-01-30 10:31:52,464 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.FillPropertObj1] Returning borrowed connection '745346830'
=== 2013-01-30 10:31:52,464 [l0-6] DEBUG SQLDriver - [builtinApplication.FillPropertObj1] About to execute SQL query in 'SQLServer' using connection '745346830'
=== 2013-01-30 10:31:52,465 [l0-6] INFO  SQLDriver - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213272'
=== 2013-01-30 10:31:52,622 [l0-6] INFO  DSResponse - [builtinApplication.FillPropertObj1] DSResponse: List with 19 items
=== 2013-01-30 10:31:52,622 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "745346830"
=== 2013-01-30 10:31:52,622 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-30 10:31:52,624 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-01-30 10:31:52,626 [l0-6] INFO  Compression - /register/sc/IDACall: 2600 -> 646 bytes
=== 2013-01-30 10:31:57,716 [l0-6] INFO  RequestContext - URL: '/register/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0': Moz (Gecko) with Accept-Encoding header
=== 2013-01-30 10:31:57,716 [l0-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:57269
=== 2013-01-30 10:31:57,716 [l0-6] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0
=== 2013-01-30 10:31:57,716 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-01-30 10:31:57,716 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-01-30 10:31:57,716 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-30 10:31:57,717 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-30 10:31:57,717 [l0-6] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:57269/Register.html?gwt.codesvr=127.0.0.1:57201
=== 2013-01-30 10:31:57,717 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Length:1089
=== 2013-01-30 10:31:57,717 [l0-6] DEBUG IDACall - Header Name:Value pair: Cookie:GLog=%7B%0D%20%20%20%20left%3A43%2C%20%0D%20%20%20%20top%3A44%2C%20%0D%20%20%20%20width%3A767%2C%20%0D%20%20%20%20height%3A480%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A4%0D%20%20%20%20%7D%2C%20%0D%20%20%20%20defaultPriority%3A3%2C%20%0D%20%20%20%20trackRPC%3Anull%0D%7D; isc_cState=ready; JSESSIONID=1xu1nybtx49w
=== 2013-01-30 10:31:57,717 [l0-6] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-30 10:31:57,717 [l0-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-30 10:31:57,717 [l0-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-30 10:31:57,717 [l0-6] DEBUG IDACall - session exists: 1xu1nybtx49w
=== 2013-01-30 10:31:57,717 [l0-6] DEBUG IDACall - remote user: null
=== 2013-01-30 10:31:57,721 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 3ms
=== 2013-01-30 10:31:57,724 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2013-01-30 10:31:57,725 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        obj_base:"213281"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_ListGrid_3",
    appID:"builtinApplication",
    operation:"FillPropertObj1",
    oldValues:{
        obj_base:"213281"
    }
}
=== 2013-01-30 10:31:57,725 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-30 10:31:57,725 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-30 10:31:57,725 [l0-6] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-01-30 10:31:57,726 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-30 10:31:57,726 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No public zero-argument method named '_FillPropertObj1' found, performing generic datasource operation
=== 2013-01-30 10:31:57,726 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Performing fetch operation with
	criteria: {obj_base:"213281"}	values: {obj_base:"213281"}
=== 2013-01-30 10:31:57,727 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213281'
=== 2013-01-30 10:31:57,727 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.FillPropertObj1] Returning borrowed connection '745346830'
=== 2013-01-30 10:31:57,727 [l0-6] DEBUG SQLDriver - [builtinApplication.FillPropertObj1] About to execute SQL query in 'SQLServer' using connection '745346830'
=== 2013-01-30 10:31:57,727 [l0-6] INFO  SQLDriver - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213281'
=== 2013-01-30 10:31:57,767 [l0-6] INFO  DSResponse - [builtinApplication.FillPropertObj1] DSResponse: List with 9 items
=== 2013-01-30 10:31:57,767 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "745346830"
=== 2013-01-30 10:31:57,767 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-30 10:31:57,769 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-01-30 10:31:57,771 [l0-6] INFO  Compression - /register/sc/IDACall: 1338 -> 573 bytes
=== 2013-01-30 10:32:01,913 [l0-6] INFO  RequestContext - URL: '/register/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0': Moz (Gecko) with Accept-Encoding header
=== 2013-01-30 10:32:01,913 [l0-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:57269
=== 2013-01-30 10:32:01,913 [l0-6] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0
=== 2013-01-30 10:32:01,913 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-01-30 10:32:01,913 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-01-30 10:32:01,913 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-30 10:32:01,914 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-30 10:32:01,914 [l0-6] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:57269/Register.html?gwt.codesvr=127.0.0.1:57201
=== 2013-01-30 10:32:01,914 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Length:1352
=== 2013-01-30 10:32:01,915 [l0-6] DEBUG IDACall - Header Name:Value pair: Cookie:GLog=%7B%0D%20%20%20%20left%3A43%2C%20%0D%20%20%20%20top%3A44%2C%20%0D%20%20%20%20width%3A767%2C%20%0D%20%20%20%20height%3A480%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A4%0D%20%20%20%20%7D%2C%20%0D%20%20%20%20defaultPriority%3A3%2C%20%0D%20%20%20%20trackRPC%3Anull%0D%7D; isc_cState=ready; JSESSIONID=1xu1nybtx49w
=== 2013-01-30 10:32:01,915 [l0-6] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-30 10:32:01,915 [l0-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-30 10:32:01,915 [l0-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-30 10:32:01,915 [l0-6] DEBUG IDACall - session exists: 1xu1nybtx49w
=== 2013-01-30 10:32:01,915 [l0-6] DEBUG IDACall - remote user: null
=== 2013-01-30 10:32:01,921 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 5ms
=== 2013-01-30 10:32:01,924 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2013-01-30 10:32:01,925 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
    },
    values:{
        value:"1995"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"update"
    },
    componentId:"isc_ListGrid_3",
    appID:"builtinApplication",
    operation:"fillPropertObj_update",
    oldValues:{
        typpropert:1,
        gritem_name:"Год образования",
        value:"1994",
        gritem_id:33,
        obj:213281,
        chargr_id:9
    }
}
=== 2013-01-30 10:32:01,926 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-30 10:32:01,926 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-30 10:32:01,926 [l0-6] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-01-30 10:32:01,927 [l0-6] DEBUG AppBase - [builtinApplication.fillPropertObj_update] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-30 10:32:01,927 [l0-6] DEBUG AppBase - [builtinApplication.fillPropertObj_update] No public zero-argument method named '_fillPropertObj_update' found, performing generic datasource operation
=== 2013-01-30 10:32:01,928 [l0-6] INFO  SQLDataSource - [builtinApplication.fillPropertObj_update] Performing update operation with
	criteria: {}	values: {value:"1995"}
=== 2013-01-30 10:32:01,931 [l0-6] INFO  SQLWhereClause - [builtinApplication.fillPropertObj_update] empty condition
=== 2013-01-30 10:32:13,619 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5