=== 2013-01-30 10:32:01,934 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.fillPropertObj_update] Returning borrowed connection '745346830'
=== 2013-01-30 10:32:01,935 [l0-6] DEBUG SQLTransaction - [builtinApplication.fillPropertObj_update] Started new SQLServer transaction "745346830"
=== 2013-01-30 10:32:01,935 [l0-6] DEBUG SQLDriver - [builtinApplication.fillPropertObj_update] About to execute SQL update in 'SQLServer' using connection'745346830'
=== 2013-01-30 10:32:01,936 [l0-6] INFO  SQLDriver - [builtinApplication.fillPropertObj_update] Executing SQL update on 'SQLServer': EXEC sp_FillPropertObjUpdAttrib '1995', 213281, 9 , 33
=== 2013-01-30 10:32:01,967 [l0-6] DEBUG SQLDriver - [builtinApplication.fillPropertObj_update] FAILED to execute SQL update in 'SQLServer' using connection'745346830'
=== 2013-01-30 10:32:01,969 [l0-6] WARN  RequestContext - dsRequest.execute() failed: 
com.microsoft.sqlserver.jdbc.SQLServerException: A result set was generated for update.
	at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDriverError(SQLServerException.java:170)
	at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement.doExecutePreparedStatement(SQLServerPreparedStatement.java:401)
	at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement$PrepStmtExecCmd.doExecute(SQLServerPreparedStatement.java:338)
	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.SQLServerPreparedStatement.executeUpdate(SQLServerPreparedStatement.java:306)
	at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:102)
	at com.isomorphic.sql.SQLDriver.doUpdate(SQLDriver.java:750)
	at com.isomorphic.sql.SQLDriver.update(SQLDriver.java:700)
	at com.isomorphic.sql.SQLDriver.executeUpdate(SQLDriver.java:824)
	at com.isomorphic.sql.SQLDataSource.executeNativeUpdate(SQLDataSource.java:419)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1464)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:306)
	at com.isomorphic.sql.SQLDataSource.executeUpdate(SQLDataSource.java:254)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1384)
	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-30 10:32:01,971 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-30 10:32:01,971 [l0-6] DEBUG SQLTransaction - Rolling back SQLServer transaction "745346830"
=== 2013-01-30 10:32:01,989 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-01-30 10:32:01,990 [l0-6] DEBUG SQLTransaction - Returning transactional connection for SQLServer with hashcode "745346830"
=== 2013-01-30 10:32:01,990 [l0-6] DEBUG SQLTransaction - Ending SQLServer transaction "745346830"
=== 2013-01-30 10:32:01,991 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "745346830"
=== 2013-01-30 10:32:01,992 [l0-6] INFO  Compression - /register/sc/IDACall: 159 -> 142 bytes
=== 2013-01-30 10:32:02,297 [l0-6] 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-30 10:32:02,316 [l0-6] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/Dialog/warn.png
=== 2013-01-30 10:32:12,154 [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:12,154 [l0-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:57269
=== 2013-01-30 10:32:12,154 [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:12,154 [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:12,154 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-01-30 10:32:12,155 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-30 10:32:12,155 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-30 10:32:12,155 [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:12,155 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Length:1089
=== 2013-01-30 10:32:12,155 [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:12,156 [l0-6] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-30 10:32:12,156 [l0-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-30 10:32:12,156 [l0-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-30 10:32:12,156 [l0-6] DEBUG IDACall - session exists: 1xu1nybtx49w
=== 2013-01-30 10:32:12,156 [l0-6] DEBUG IDACall - remote user: null
=== 2013-01-30 10:32:12,164 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 7ms
=== 2013-01-30 10:32:12,166 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2013-01-30 10:32:12,168 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        obj_base:"213274"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_ListGrid_4",
    appID:"builtinApplication",
    operation:"FillPropertObj1",
    oldValues:{
        obj_base:"213274"
    }
}
=== 2013-01-30 10:32:12,169 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-30 10:32:12,169 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-30 10:32:12,169 [l0-6] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-01-30 10:32:12,169 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-30 10:32:12,169 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No public zero-argument method named '_FillPropertObj1' found, performing generic datasource operation
=== 2013-01-30 10:32:12,170 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Performing fetch operation with
	criteria: {obj_base:"213274"}	values: {obj_base:"213274"}
=== 2013-01-30 10:32:12,170 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213274'
=== 2013-01-30 10:32:12,171 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.FillPropertObj1] Returning borrowed connection '745346830'
=== 2013-01-30 10:32:12,171 [l0-6] DEBUG SQLDriver - [builtinApplication.FillPropertObj1] About to execute SQL query in 'SQLServer' using connection '745346830'
=== 2013-01-30 10:32:12,171 [l0-6] INFO  SQLDriver - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213274'
=== 2013-01-30 10:32:12,225 [l0-6] INFO  DSResponse - [builtinApplication.FillPropertObj1] DSResponse: List with 23 items
=== 2013-01-30 10:32:12,226 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "745346830"
=== 2013-01-30 10:32:12,226 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-30 10:32:12,227 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-01-30 10:32:12,230 [l0-6] INFO  Compression - /register/sc/IDACall: 3786 -> 913 bytes
=== 2013-01-30 10:32:13,619 [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:13,619 [l0-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:57269
=== 2013-01-30 10:32:13,619 [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:13,619 [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:13,619 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-30 10:32:13,620 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-30 10:32:13,620 [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:13,620 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Length:1089
=== 2013-01-30 10:32:13,620 [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:13,621 [l0-6] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-30 10:32:13,621 [l0-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-30 10:32:13,621 [l0-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-30 10:32:13,621 [l0-6] DEBUG IDACall - session exists: 1xu1nybtx49w
=== 2013-01-30 10:32:13,621 [l0-6] DEBUG IDACall - remote user: null
=== 2013-01-30 10:32:13,625 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 2ms
=== 2013-01-30 10:32:13,628 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2013-01-30 10:32:13,643 [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_5",
    appID:"builtinApplication",
    operation:"FillPropertObj1",
    oldValues:{
        obj_base:"213281"
    }
}
=== 2013-01-30 10:32:13,643 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-30 10:32:13,644 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-30 10:32:13,644 [l0-6] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-01-30 10:32:13,644 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-30 10:32:13,644 [l0-6] DEBUG AppBase - [builtinApplication.FillPropertObj1] No public zero-argument method named '_FillPropertObj1' found, performing generic datasource operation
=== 2013-01-30 10:32:13,646 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Performing fetch operation with
	criteria: {obj_base:"213281"}	values: {obj_base:"213281"}
=== 2013-01-30 10:32:13,646 [l0-6] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213281'
=== 2013-01-30 10:32:13,646 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.FillPropertObj1] Returning borrowed connection '745346830'
=== 2013-01-30 10:32:13,647 [l0-6] DEBUG SQLDriver - [builtinApplication.FillPropertObj1] About to execute SQL query in 'SQLServer' using connection '745346830'
=== 2013-01-30 10:32:13,647 [l0-6] INFO  SQLDriver - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213281'
=== 2013-01-30 10:32:13,681 [l0-6] INFO  DSResponse - [builtinApplication.FillPropertObj1] DSResponse: List with 9 items
=== 2013-01-30 10:32:13,682 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "745346830"
=== 2013-01-30 10:32:13,682 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-30 10:32:13,683 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-01-30 10:32:13,686 [l0-6] INFO  Compression - /register/sc/IDACall: 1338 -> 573 bytes
=== 2013-01-30 10:32:19,446 [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:19,446 [l0-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:57269
=== 2013-01-30 10:32:19,446 [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:19,446 [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:19,446 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-01-30 10:32:19,446 [l0-6] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-01-30 10:32:19,447 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-01-30 10:32:19,447 [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:19,447 [l0-6] DEBUG IDACall - Header Name:Value pair: Content-Length:1352
=== 2013-01-30 10:32:19,447 [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:19,448 [l0-6] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-01-30 10:32:19,449 [l0-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-01-30 10:32:19,449 [l0-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-01-30 10:32:19,449 [l0-6] DEBUG IDACall - session exists: 1xu1nybtx49w
=== 2013-01-30 10:32:19,449 [l0-6] DEBUG IDACall - remote user: null
=== 2013-01-30 10:32:19,460 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 8ms
=== 2013-01-30 10:32:19,463 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2013-01-30 10:32:19,464 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
    },
    values:{
        value:"1995"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"update"
    },
    componentId:"isc_ListGrid_5",
    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:19,465 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2013-01-30 10:32:19,465 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-01-30 10:32:19,465 [l0-6] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-01-30 10:32:19,465 [l0-6] DEBUG AppBase - [builtinApplication.fillPropertObj_update] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-01-30 10:32:19,466 [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:19,466 [l0-6] INFO  SQLDataSource - [builtinApplication.fillPropertObj_update] Performing update operation with
	criteria: {}	values: {value:"1995"}
=== 2013-01-30 10:32:19,466 [l0-6] INFO  SQLWhereClause - [builtinApplication.fillPropertObj_update] empty condition
=== 2013-01-30 10:32:19,467 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.fillPropertObj_update] Returning borrowed connection '745346830'
=== 2013-01-30 10:32:19,467 [l0-6] DEBUG SQLTransaction - [builtinApplication.fillPropertObj_update] Started new SQLServer transaction "745346830"
=== 2013-01-30 10:32:19,468 [l0-6] DEBUG SQLDriver - [builtinApplication.fillPropertObj_update] About to execute SQL update in 'SQLServer' using connection'745346830'
=== 2013-01-30 10:32:19,468 [l0-6] INFO  SQLDriver - [builtinApplication.fillPropertObj_update] Executing SQL update on 'SQLServer': EXEC sp_FillPropertObjUpdAttrib '1995', 213281, 9 , 33
=== 2013-01-30 10:32:19,473 [l0-6] DEBUG SQLDriver - [builtinApplication.fillPropertObj_update] FAILED to execute SQL update in 'SQLServer' using connection'745346830'
=== 2013-01-30 10:32:19,473 [l0-6] WARN  RequestContext - dsRequest.execute() failed: 
com.microsoft.sqlserver.jdbc.SQLServerException: A result set was generated for update.
	at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDriverError(SQLServerException.java:170)
	at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement.doExecutePreparedStatement(SQLServerPreparedStatement.java:401)
	at com.microsoft.sqlserver.jdbc.SQLServerPreparedStatement$PrepStmtExecCmd.doExecute(SQLServerPreparedStatement.java:338)
	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.SQLServerPreparedStatement.executeUpdate(SQLServerPreparedStatement.java:306)
	at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:102)
	at com.isomorphic.sql.SQLDriver.doUpdate(SQLDriver.java:750)
	at com.isomorphic.sql.SQLDriver.update(SQLDriver.java:700)
	at com.isomorphic.sql.SQLDriver.executeUpdate(SQLDriver.java:824)
	at com.isomorphic.sql.SQLDataSource.executeNativeUpdate(SQLDataSource.java:419)
	at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1464)
	at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:306)
	at com.isomorphic.sql.SQLDataSource.executeUpdate(SQLDataSource.java:254)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1384)
	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-30 10:32:19,474 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-01-30 10:32:19,474 [l0-6] DEBUG SQLTransaction - Rolling back SQLServer transaction "745346830"
=== 2013-01-30 10:32:19,477 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-01-30 10:32:19,477 [l0-6] DEBUG SQLTransaction - Returning transactional connection for SQLServer with hashcode "745346830"
=== 2013-01-30 10:32:19,477 [l0-6] DEBUG SQLTransaction - Ending SQLServer transaction "745346830"
=== 2013-01-30 10:32:19,478 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "745346830"
=== 2013-01-30 10:32:19,478 [l0-6] INFO  Compression - /register/sc/IDACall: 159 -> 142 bytes