=== 2013-01-18 19:57:34,394 [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-18 19:57:34,394 [l0-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:52635
=== 2013-01-18 19:57:34,395 [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-18 19:57:34,395 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-01-18 19:57:34,395 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-01-18 19:57:34,395 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-18 19:57:34,396 [l0-6] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:52635/Register.html?gwt.codesvr=127.0.0.1:52615
=== 2013-01-18 19:57:34,396 [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=1kpgxw35hlmyu
=== 2013-01-18 19:57:34,396 [l0-6] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-18 19:57:34,396 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-18 19:57:34,396 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Length:943
=== 2013-01-18 19:57:34,396 [l0-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-18 19:57:34,396 [l0-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-18 19:57:34,396 [l0-6] DEBUG IDACall - session exists: 1kpgxw35hlmyu
=== 2013-01-18 19:57:34,396 [l0-6] DEBUG IDACall - remote user: null
=== 2013-01-18 19:57:34,400 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 3ms
=== 2013-01-18 19:57:34,402 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2013-01-18 19:57:34,406 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        typ_id:"27"
    },
    operationConfig:{
        dataSource:"biViewObj",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    componentId:"isc_TreeGrid_0",
    appID:"builtinApplication",
    operation:"biViewObj_fetch",
    oldValues:{
        typ_id:"27"
    }
}
=== 2013-01-18 19:57:34,406 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-18 19:57:34,408 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-18 19:57:34,408 [l0-6] DEBUG DeclarativeSecurity - DataSource biViewObj is not in the pre-checked list, processing...
=== 2013-01-18 19:57:34,409 [l0-6] DEBUG AppBase - [builtinApplication.biViewObj_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-18 19:57:34,409 [l0-6] DEBUG AppBase - [builtinApplication.biViewObj_fetch] No public zero-argument method named '_biViewObj_fetch' found, performing generic datasource operation
=== 2013-01-18 19:57:34,409 [l0-6] INFO  SQLDataSource - [builtinApplication.biViewObj_fetch] Performing fetch operation with
	criteria: {typ_id:"27"}	values: {typ_id:"27"}
=== 2013-01-18 19:57:34,411 [l0-6] INFO  SQLDataSource - [builtinApplication.biViewObj_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE BI_view_obj.typ_base = $criteria.typ_id
=== 2013-01-18 19:57:34,421 [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 = '27'
=== 2013-01-18 19:57:34,437 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.biViewObj_fetch] Returning borrowed connection '769536516'
=== 2013-01-18 19:57:34,437 [l0-6] DEBUG SQLDriver - [builtinApplication.biViewObj_fetch] About to execute SQL query in 'SQLServer' using connection '769536516'
=== 2013-01-18 19:57:34,437 [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 = '27'
=== 2013-01-18 19:57:34,486 [l0-6] INFO  DSResponse - [builtinApplication.biViewObj_fetch] DSResponse: List with 10 items
=== 2013-01-18 19:57:34,486 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "769536516"
=== 2013-01-18 19:57:34,487 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-18 19:57:34,488 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: true
=== 2013-01-18 19:57:34,495 [l0-6] INFO  Compression - /register/sc/IDACall: 4841 -> 796 bytes
=== 2013-01-18 19:57:34,928 [l0-6] 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-18 19:57:34,930 [l0-6] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/opener_closed.png
=== 2013-01-18 19:57:34,933 [l0-4] 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-18 19:57:34,934 [l0-4] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/folder_closed.png
=== 2013-01-18 19:57:36,350 [l0-4] 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-18 19:57:36,352 [l0-4] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/opener_opened.png
=== 2013-01-18 19:57:36,362 [l0-6] 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-18 19:57:36,364 [l0-6] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/folder_open.png
=== 2013-01-18 19:57:36,366 [l0-6] 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-18 19:57:36,368 [l0-6] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/file.png
=== 2013-01-18 19:57:38,174 [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-18 19:57:38,175 [l0-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:52635
=== 2013-01-18 19:57:38,175 [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-18 19:57:38,175 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-01-18 19:57:38,175 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-01-18 19:57:38,175 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-18 19:57:38,175 [l0-6] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:52635/Register.html?gwt.codesvr=127.0.0.1:52615
=== 2013-01-18 19:57:38,175 [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=1kpgxw35hlmyu
=== 2013-01-18 19:57:38,175 [l0-6] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-18 19:57:38,176 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-18 19:57:38,176 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Length:1092
=== 2013-01-18 19:57:38,176 [l0-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-18 19:57:38,176 [l0-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-18 19:57:38,176 [l0-6] DEBUG IDACall - session exists: 1kpgxw35hlmyu
=== 2013-01-18 19:57:38,176 [l0-6] DEBUG IDACall - remote user: null
=== 2013-01-18 19:57:38,179 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 2ms
=== 2013-01-18 19:57:38,181 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2013-01-18 19:57:38,183 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        obj_base:"55753"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_ListGrid_1",
    appID:"builtinApplication",
    operation:"fillPropertObj_fetch",
    oldValues:{
        obj_base:"55753"
    }
}
=== 2013-01-18 19:57:38,184 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-18 19:57:38,184 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-18 19:57:38,185 [l0-6] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-01-18 19:57:38,226 [l0-6] DEBUG AppBase - [builtinApplication.fillPropertObj_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-18 19:57:38,228 [l0-6] DEBUG AppBase - [builtinApplication.fillPropertObj_fetch] No public zero-argument method named '_fillPropertObj_fetch' found, performing generic datasource operation
=== 2013-01-18 19:57:38,229 [l0-6] INFO  SQLDataSource - [builtinApplication.fillPropertObj_fetch] Performing fetch operation with
	criteria: {obj_base:"55753"}	values: {obj_base:"55753"}
=== 2013-01-18 19:57:38,229 [l0-6] WARN  SQLSelectClause - [builtinApplication.fillPropertObj_fetch] SQLSelectClause is null or zero size, remap and conversions won't work!
=== 2013-01-18 19:57:38,232 [l0-6] INFO  SQLDataSource - [builtinApplication.fillPropertObj_fetch] Executing SQL query on 'SQLServer': call sp_FillPropertObj1(213272)
=== 2013-01-18 19:57:38,233 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.fillPropertObj_fetch] Returning borrowed connection '769536516'
=== 2013-01-18 19:57:38,233 [l0-6] DEBUG SQLDriver - [builtinApplication.fillPropertObj_fetch] About to execute SQL query in 'SQLServer' using connection '769536516'
=== 2013-01-18 19:57:38,233 [l0-6] INFO  SQLDriver - [builtinApplication.fillPropertObj_fetch] Executing SQL query on 'SQLServer': call sp_FillPropertObj1(213272)
=== 2013-01-18 19:57:38,237 [l0-6] INFO  SQLDriver - [builtinApplication.fillPropertObj_fetch] Execute of select: call sp_FillPropertObj1(213272) on db: SQLServer threw exception: com.microsoft.sqlserver.jdbc.SQLServerException: Неправильный синтаксис около конструкции "213272". - assuming stale connection and retrying query.
=== 2013-01-18 19:57:38,237 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.fillPropertObj_fetch] About to close PoolableConnection with hashcode "769536516"
=== 2013-01-18 19:57:38,239 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fillPropertObj_fetch] DriverManager fetching connection for SQLServer via jdbc url jdbc:sqlserver://192.168.1.156:1433;DatabaseName=AC_new;User=sa;Password=sasa
=== 2013-01-18 19:57:38,239 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fillPropertObj_fetch] Passing JDBC URL only to getConnection
=== 2013-01-18 19:57:38,240 [l0-4] 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-18 19:57:38,240 [l0-4] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:52635
=== 2013-01-18 19:57:38,240 [l0-4] 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-18 19:57:38,245 [l0-4] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-01-18 19:57:38,245 [l0-4] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-01-18 19:57:38,245 [l0-4] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-18 19:57:38,245 [l0-4] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:52635/Register.html?gwt.codesvr=127.0.0.1:52615
=== 2013-01-18 19:57:38,245 [l0-4] 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=1kpgxw35hlmyu
=== 2013-01-18 19:57:38,248 [l0-4] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-18 19:57:38,248 [l0-4] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-18 19:57:38,248 [l0-4] DEBUG IDACall - Header Name:Value pair: Content-Length:1094
=== 2013-01-18 19:57:38,248 [l0-4] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-18 19:57:38,248 [l0-4] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-18 19:57:38,248 [l0-4] DEBUG IDACall - session exists: 1kpgxw35hlmyu
=== 2013-01-18 19:57:38,248 [l0-4] DEBUG IDACall - remote user: null
=== 2013-01-18 19:57:38,252 [l0-4] DEBUG XML - Parsed XML from (in memory stream): 2ms
=== 2013-01-18 19:57:38,254 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fillPropertObj_fetch] makeObject() created a pooled Connection '233099294'
=== 2013-01-18 19:57:38,254 [l0-4] DEBUG RPCManager - Processing 1 requests.
=== 2013-01-18 19:57:38,255 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "233099294"
=== 2013-01-18 19:57:38,255 [l0-4] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        obj_base:"55753"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:1000,
    componentId:"isc_ListGrid_1",
    appID:"builtinApplication",
    operation:"fillPropertObj_fetch",
    oldValues:{
        obj_base:"55753"
    }
}
=== 2013-01-18 19:57:38,256 [l0-4] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-18 19:57:38,256 [l0-4] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-18 19:57:38,256 [l0-4] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-01-18 19:57:38,256 [l0-4] DEBUG AppBase - [builtinApplication.fillPropertObj_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-18 19:57:38,256 [l0-4] DEBUG AppBase - [builtinApplication.fillPropertObj_fetch] No public zero-argument method named '_fillPropertObj_fetch' found, performing generic datasource operation
=== 2013-01-18 19:57:38,257 [l0-6] WARN  RequestContext - dsRequest.execute() failed: 
com.microsoft.sqlserver.jdbc.SQLServerException: Неправильный синтаксис около конструкции "213272".
	at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDatabaseError(SQLServerException.java:196)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.getNextResult(SQLServerStatement.java:1454)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.doExecuteStatement(SQLServerStatement.java:786)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement$StmtExecCmd.doExecute(SQLServerStatement.java:685)
	at com.microsoft.sqlserver.jdbc.TDSCommand.execute(IOBuffer.java:4026)
	at com.microsoft.sqlserver.jdbc.SQLServerConnection.executeCommand(SQLServerConnection.java:1416)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeCommand(SQLServerStatement.java:185)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeStatement(SQLServerStatement.java:160)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeQuery(SQLServerStatement.java:620)
	at org.apache.commons.dbcp.DelegatingStatement.executeQuery(DelegatingStatement.java:208)
	at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:592)
	at com.isomorphic.sql.SQLDriver.executeQuery(SQLDriver.java:848)
	at com.isomorphic.sql.SQLDataSource.executeNativeQuery(SQLDataSource.java:407)
	at com.isomorphic.sql.SQLDataSource.executeNativeQuery(SQLDataSource.java:401)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1394)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:306)
	at com.isomorphic.sql.SQLDataSource.executeFetch(SQLDataSource.java:250)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1368)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:726)
	at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658)
	at com.isomorphic.application.AppBase.execute(AppBase.java:491)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2017)
	at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:216)
	at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:173)
	at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:138)
	at com.isomorphic.servlet.IDACall.doPost(IDACall.java:74)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:637)
	at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
	at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:487)
	at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1097)
	at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:259)
	at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1088)
	at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
	at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
	at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
	at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:729)
	at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.handler.RequestLogHandler.handle(RequestLogHandler.java:49)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.Server.handle(Server.java:324)
	at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:505)
	at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:843)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:647)
	at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
	at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:380)
	at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:395)
	at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:488)
=== 2013-01-18 19:57:38,257 [l0-4] INFO  SQLDataSource - [builtinApplication.fillPropertObj_fetch] Performing fetch operation with
	criteria: {obj_base:"55753"}	values: {obj_base:"55753"}
=== 2013-01-18 19:57:38,257 [l0-4] WARN  SQLSelectClause - [builtinApplication.fillPropertObj_fetch] SQLSelectClause is null or zero size, remap and conversions won't work!
=== 2013-01-18 19:57:38,258 [l0-4] INFO  SQLDataSource - [builtinApplication.fillPropertObj_fetch] Executing SQL query on 'SQLServer': call sp_FillPropertObj1(213272)
=== 2013-01-18 19:57:38,258 [l0-4] DEBUG SQLConnectionManager - [builtinApplication.fillPropertObj_fetch] Returning borrowed connection '233099294'
=== 2013-01-18 19:57:38,258 [l0-4] DEBUG SQLDriver - [builtinApplication.fillPropertObj_fetch] About to execute SQL query in 'SQLServer' using connection '233099294'
=== 2013-01-18 19:57:38,258 [l0-4] INFO  SQLDriver - [builtinApplication.fillPropertObj_fetch] Executing SQL query on 'SQLServer': call sp_FillPropertObj1(213272)
=== 2013-01-18 19:57:38,259 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-18 19:57:38,260 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-01-18 19:57:38,261 [l0-6] INFO  Compression - /register/sc/IDACall: 210 -> 195 bytes
=== 2013-01-18 19:57:38,261 [l0-4] INFO  SQLDriver - [builtinApplication.fillPropertObj_fetch] Execute of select: call sp_FillPropertObj1(213272) on db: SQLServer threw exception: com.microsoft.sqlserver.jdbc.SQLServerException: Неправильный синтаксис около конструкции "213272". - assuming stale connection and retrying query.
=== 2013-01-18 19:57:38,261 [l0-4] DEBUG SQLConnectionManager - [builtinApplication.fillPropertObj_fetch] About to close PoolableConnection with hashcode "233099294"
=== 2013-01-18 19:57:38,261 [l0-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fillPropertObj_fetch] DriverManager fetching connection for SQLServer via jdbc url jdbc:sqlserver://192.168.1.156:1433;DatabaseName=AC_new;User=sa;Password=sasa
=== 2013-01-18 19:57:38,261 [l0-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fillPropertObj_fetch] Passing JDBC URL only to getConnection
=== 2013-01-18 19:57:38,272 [l0-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fillPropertObj_fetch] makeObject() created a pooled Connection '1358105213'
=== 2013-01-18 19:57:38,275 [l0-4] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1358105213"
=== 2013-01-18 19:57:38,275 [l0-4] WARN  RequestContext - dsRequest.execute() failed: 
com.microsoft.sqlserver.jdbc.SQLServerException: Неправильный синтаксис около конструкции "213272".
	at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDatabaseError(SQLServerException.java:196)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.getNextResult(SQLServerStatement.java:1454)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.doExecuteStatement(SQLServerStatement.java:786)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement$StmtExecCmd.doExecute(SQLServerStatement.java:685)
	at com.microsoft.sqlserver.jdbc.TDSCommand.execute(IOBuffer.java:4026)
	at com.microsoft.sqlserver.jdbc.SQLServerConnection.executeCommand(SQLServerConnection.java:1416)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeCommand(SQLServerStatement.java:185)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeStatement(SQLServerStatement.java:160)
	at com.microsoft.sqlserver.jdbc.SQLServerStatement.executeQuery(SQLServerStatement.java:620)
	at org.apache.commons.dbcp.DelegatingStatement.executeQuery(DelegatingStatement.java:208)
	at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:592)
	at com.isomorphic.sql.SQLDriver.executeQuery(SQLDriver.java:848)
	at com.isomorphic.sql.SQLDataSource.executeNativeQuery(SQLDataSource.java:407)
	at com.isomorphic.sql.SQLDataSource.executeNativeQuery(SQLDataSource.java:401)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1394)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:306)
	at com.isomorphic.sql.SQLDataSource.executeFetch(SQLDataSource.java:250)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1368)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:726)
	at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658)
	at com.isomorphic.application.AppBase.execute(AppBase.java:491)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:2017)
	at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:216)
	at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:173)
	at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:138)
	at com.isomorphic.servlet.IDACall.doPost(IDACall.java:74)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:637)
	at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
	at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:487)
	at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1097)
	at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:259)
	at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1088)
	at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
	at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
	at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
	at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:729)
	at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.handler.RequestLogHandler.handle(RequestLogHandler.java:49)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.Server.handle(Server.java:324)
	at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:505)
	at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:843)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:647)
	at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
	at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:380)
	at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:395)
	at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:488)
=== 2013-01-18 19:57:38,277 [l0-4] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-18 19:57:38,279 [l0-4] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-01-18 19:57:38,279 [l0-4] INFO  Compression - /register/sc/IDACall: 210 -> 195 bytes
=== 2013-01-18 19:57:38,576 [l0-4] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/Dialog/warn.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-18 19:57:38,578 [l0-4] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/Dialog/warn.png
=== 2013-01-18 19:57:38,586 [l0-4] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/headerIcons/close.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-18 19:57:38,589 [l0-4] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/headerIcons/close.png
=== 2013-01-18 19:57:42,705 [l0-4] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/Scrollbar/vscroll_Over_track.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-18 19:57:42,707 [l0-4] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/Scrollbar/vscroll_Over_track.png
=== 2013-01-18 19:57:42,978 [l0-4] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/Scrollbar/vscroll_Over_start.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-18 19:57:42,982 [l0-4] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/Scrollbar/vscroll_Over_start.png
=== 2013-01-18 19:57:43,161 [l0-4] INFO  RequestContext - URL: '/register/sc/skins/Enterprise/images/ListGrid/sort_descending.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-18 19:57:43,167 [l0-4] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/ListGrid/sort_descending.png