=== 2013-02-05 14:55:59,802 [l0-0] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-02-05 14:55:59,802 [l0-0] DEBUG IDACall - session exists: cmwm6ra6cre4
=== 2013-02-05 14:55:59,802 [l0-0] DEBUG IDACall - remote user: null
=== 2013-02-05 14:55:59,811 [l0-0] DEBUG XML - Parsed XML from (in memory stream): 8ms
=== 2013-02-05 14:55:59,813 [l0-0] DEBUG RPCManager - Processing 1 requests.
=== 2013-02-05 14:55:59,815 [l0-0] 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-02-05 14:55:59,815 [l0-0] INFO  IDACall - Performing 1 operation(s)
=== 2013-02-05 14:55:59,816 [l0-0] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-02-05 14:55:59,816 [l0-0] DEBUG DeclarativeSecurity - DataSource biViewObj is not in the pre-checked list, processing...
=== 2013-02-05 14:55:59,839 [l0-0] DEBUG AppBase - [builtinApplication.biViewObj_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-02-05 14:55:59,839 [l0-0] DEBUG AppBase - [builtinApplication.biViewObj_fetch] No public zero-argument method named '_biViewObj_fetch' found, performing generic datasource operation
=== 2013-02-05 14:55:59,840 [l0-0] INFO  SQLDataSource - [builtinApplication.biViewObj_fetch] Performing fetch operation with
	criteria: {typ_id:"1049"}	values: {typ_id:"1049"}
=== 2013-02-05 14:55:59,850 [l0-0] INFO  SQLDataSource - [builtinApplication.biViewObj_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE BI_view_obj.typ_base = $criteria.typ_id
=== 2013-02-05 14:55:59,874 [l0-0] 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-02-05 14:55:59,880 [l0-0] DEBUG SQLConnectionManager - [builtinApplication.biViewObj_fetch] Returning borrowed connection '1253480289'
=== 2013-02-05 14:55:59,881 [l0-0] DEBUG SQLDriver - [builtinApplication.biViewObj_fetch] About to execute SQL query in 'SQLServer' using connection '1253480289'
=== 2013-02-05 14:55:59,881 [l0-0] 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-02-05 14:55:59,900 [l0-0] INFO  DSResponse - [builtinApplication.biViewObj_fetch] DSResponse: List with 23 items
=== 2013-02-05 14:55:59,900 [l0-0] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1253480289"
=== 2013-02-05 14:55:59,900 [l0-0] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-02-05 14:55:59,901 [l0-0] DEBUG RPCManager - non-DMI response, dropExtraFields: true
=== 2013-02-05 14:55:59,923 [l0-0] INFO  Compression - /register/sc/IDACall: 16120 -> 1827 bytes
=== 2013-02-05 14:56:00,385 [l0-0] 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-02-05 14:56:00,390 [l0-0] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/folder_closed.png
=== 2013-02-05 14:56:00,393 [l0-0] 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-02-05 14:56:00,395 [l0-0] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/opener_closed.png
=== 2013-02-05 14:56:01,903 [l0-5] 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-02-05 14:56:01,905 [l0-5] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/file.png
=== 2013-02-05 14:56:01,926 [l0-0] 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-02-05 14:56:01,930 [l0-0] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/folder_open.png
=== 2013-02-05 14:56:01,933 [l0-0] 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-02-05 14:56:01,934 [l0-0] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/TreeGrid/opener_opened.png
=== 2013-02-05 14:56:04,018 [l0-0] 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-02-05 14:56:04,018 [l0-0] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:52940
=== 2013-02-05 14:56:04,018 [l0-0] 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-02-05 14:56:04,018 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-02-05 14:56:04,018 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-02-05 14:56:04,018 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-02-05 14:56:04,028 [l0-0] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:52940/Register.html?gwt.codesvr=127.0.0.1:52936
=== 2013-02-05 14:56:04,028 [l0-0] 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=cmwm6ra6cre4
=== 2013-02-05 14:56:04,031 [l0-0] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-02-05 14:56:04,031 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-02-05 14:56:04,031 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Length:1089
=== 2013-02-05 14:56:04,031 [l0-0] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-02-05 14:56:04,031 [l0-0] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-02-05 14:56:04,031 [l0-0] DEBUG IDACall - session exists: cmwm6ra6cre4
=== 2013-02-05 14:56:04,031 [l0-0] DEBUG IDACall - remote user: null
=== 2013-02-05 14:56:04,035 [l0-0] DEBUG XML - Parsed XML from (in memory stream): 2ms
=== 2013-02-05 14:56:04,037 [l0-0] DEBUG RPCManager - Processing 1 requests.
=== 2013-02-05 14:56:04,038 [l0-0] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        obj_base:"213281"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_ListGrid_1",
    appID:"builtinApplication",
    operation:"FillPropertObj1",
    oldValues:{
        obj_base:"213281"
    }
}
=== 2013-02-05 14:56:04,040 [l0-0] INFO  IDACall - Performing 1 operation(s)
=== 2013-02-05 14:56:04,040 [l0-0] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-02-05 14:56:04,040 [l0-0] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-02-05 14:56:04,040 [l0-0] DEBUG AppBase - [builtinApplication.FillPropertObj1] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-02-05 14:56:04,040 [l0-0] DEBUG AppBase - [builtinApplication.FillPropertObj1] No public zero-argument method named '_FillPropertObj1' found, performing generic datasource operation
=== 2013-02-05 14:56:04,041 [l0-0] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Performing fetch operation with
	criteria: {obj_base:"213281"}	values: {obj_base:"213281"}
=== 2013-02-05 14:56:04,043 [l0-0] INFO  SQLDataSource - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213281'
=== 2013-02-05 14:56:04,044 [l0-0] DEBUG SQLConnectionManager - [builtinApplication.FillPropertObj1] Returning borrowed connection '1253480289'
=== 2013-02-05 14:56:04,044 [l0-0] DEBUG SQLDriver - [builtinApplication.FillPropertObj1] About to execute SQL query in 'SQLServer' using connection '1253480289'
=== 2013-02-05 14:56:04,044 [l0-0] INFO  SQLDriver - [builtinApplication.FillPropertObj1] Executing SQL query on 'SQLServer': EXEC sp_FillPropertObj1 '213281'
=== 2013-02-05 14:56:04,192 [l0-0] INFO  DSResponse - [builtinApplication.FillPropertObj1] DSResponse: List with 9 items
=== 2013-02-05 14:56:04,192 [l0-0] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1253480289"
=== 2013-02-05 14:56:04,193 [l0-0] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-02-05 14:56:04,194 [l0-0] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-02-05 14:56:04,196 [l0-0] INFO  Compression - /register/sc/IDACall: 1338 -> 573 bytes
=== 2013-02-05 14:56:10,694 [l0-0] 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-02-05 14:56:10,694 [l0-0] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:52940
=== 2013-02-05 14:56:10,694 [l0-0] 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-02-05 14:56:10,694 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
=== 2013-02-05 14:56:10,694 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5
=== 2013-02-05 14:56:10,694 [l0-0] DEBUG IDACall - Header Name:Value pair: Accept-Encoding:gzip, deflate
=== 2013-02-05 14:56:10,695 [l0-0] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:52940/Register.html?gwt.codesvr=127.0.0.1:52936
=== 2013-02-05 14:56:10,695 [l0-0] 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=cmwm6ra6cre4
=== 2013-02-05 14:56:10,695 [l0-0] DEBUG IDACall - Header Name:Value pair: Connection:keep-alive
=== 2013-02-05 14:56:10,695 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8
=== 2013-02-05 14:56:10,695 [l0-0] DEBUG IDACall - Header Name:Value pair: Content-Length:1352
=== 2013-02-05 14:56:10,695 [l0-0] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache
=== 2013-02-05 14:56:10,695 [l0-0] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache
=== 2013-02-05 14:56:10,695 [l0-0] DEBUG IDACall - session exists: cmwm6ra6cre4
=== 2013-02-05 14:56:10,695 [l0-0] DEBUG IDACall - remote user: null
=== 2013-02-05 14:56:10,699 [l0-0] DEBUG XML - Parsed XML from (in memory stream): 2ms
=== 2013-02-05 14:56:10,701 [l0-0] DEBUG RPCManager - Processing 1 requests.
=== 2013-02-05 14:56:10,702 [l0-0] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
    },
    values:{
        value:"1992"
    },
    operationConfig:{
        dataSource:"fillPropertObj",
        operationType:"update"
    },
    componentId:"isc_ListGrid_1",
    appID:"builtinApplication",
    operation:"fillPropertObj_update",
    oldValues:{
        typpropert:1,
        gritem_name:"Год образования",
        value:"1994",
        gritem_id:33,
        obj:213281,
        chargr_id:9
    }
}
=== 2013-02-05 14:56:10,703 [l0-0] INFO  IDACall - Performing 1 operation(s)
=== 2013-02-05 14:56:10,703 [l0-0] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-02-05 14:56:10,703 [l0-0] DEBUG DeclarativeSecurity - DataSource fillPropertObj is not in the pre-checked list, processing...
=== 2013-02-05 14:56:10,703 [l0-0] DEBUG AppBase - [builtinApplication.fillPropertObj_update] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-02-05 14:56:10,703 [l0-0] DEBUG AppBase - [builtinApplication.fillPropertObj_update] No public zero-argument method named '_fillPropertObj_update' found, performing generic datasource operation
=== 2013-02-05 14:56:10,703 [l0-0] INFO  SQLDataSource - [builtinApplication.fillPropertObj_update] Performing update operation with
	criteria: {}	values: {value:"1992"}
=== 2013-02-05 14:56:10,705 [l0-0] INFO  SQLWhereClause - [builtinApplication.fillPropertObj_update] empty condition
=== 2013-02-05 14:56:10,707 [l0-0] DEBUG SQLConnectionManager - [builtinApplication.fillPropertObj_update] Returning borrowed connection '1253480289'
=== 2013-02-05 14:56:10,712 [l0-0] DEBUG SQLTransaction - [builtinApplication.fillPropertObj_update] Started new SQLServer transaction "1253480289"
=== 2013-02-05 14:56:10,712 [l0-0] DEBUG SQLDriver - [builtinApplication.fillPropertObj_update] About to execute SQL update in 'SQLServer' using connection'1253480289'
=== 2013-02-05 14:56:10,712 [l0-0] INFO  SQLDriver - [builtinApplication.fillPropertObj_update] Executing SQL update on 'SQLServer': EXEC sp_FillPropertObjUpdAttrib '1992', 213281, 9 , 33
=== 2013-02-05 14:56:10,721 [l0-0] DEBUG SQLDriver - [builtinApplication.fillPropertObj_update] FAILED to execute SQL update in 'SQLServer' using connection'1253480289'
=== 2013-02-05 14:56:10,723 [l0-0] 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:2027)
	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-02-05 14:56:10,724 [l0-0] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2013-02-05 14:56:10,725 [l0-0] DEBUG SQLTransaction - Rolling back SQLServer transaction "1253480289"
=== 2013-02-05 14:56:10,727 [l0-0] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2013-02-05 14:56:10,729 [l0-0] DEBUG SQLTransaction - Returning transactional connection for SQLServer with hashcode "1253480289"
=== 2013-02-05 14:56:10,729 [l0-0] DEBUG SQLTransaction - Ending SQLServer transaction "1253480289"
=== 2013-02-05 14:56:10,731 [l0-0] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1253480289"
=== 2013-02-05 14:56:10,731 [l0-0] INFO  Compression - /register/sc/IDACall: 159 -> 142 bytes
=== 2013-02-05 14:56:11,001 [l0-5] 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-02-05 14:56:11,003 [l0-0] 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-02-05 14:56:11,009 [l0-0] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/Dialog/warn.png
=== 2013-02-05 14:56:11,013 [l0-5] INFO  Download - done streaming: C:/Users/usus/workspace/register/war/register/sc/skins/Enterprise/images/headerIcons/close.png