Announcement

Collapse
No announcement yet.
X
Collapse
  • Page of 2
  • Filter
  • Time
Clear All
new posts
Previous 1 2 template Next
    #1

    imageFile BLOB retrieval?

    I've followed the example code at http://www.smartclient.com/smartgwte...se/#upload_sql.

    The upload by FileItem works fine, once I add the additional fields described in the JavaDoc Enum FieldType IMAGEFILE
    Code:
    ...
<field name="THEBLOB" type="imageFile"></field>
<field name="THEBLOB_FILENAME" length="256" type="text"></field>
<field name="THEBLOB_FILESIZE" type="number"></field>
<field name="THEBLOB_DATE_CREATED" type="date"></field>
...
    However, I am at a loss as to how to read the BLOB back into an Img(). I can see in the SGWT Dev Console that a DataSource fetch returns all fields, including the BLOB metadata fields, but not the BLOB field itself.

    Hints please! Thanks.

    Clarification: I'm within the Smart GWT Framework (and would like to stay there), using the Built-In-DS sample as the project template.
    Last edited by tinnitus007; 22 Dec 2011, 08:43.
    Tags: None
    #2
    OK, from my continued reading, it appears that ViewFileItem is the way to go.

    Here's how I'm failing thus far. The ViewFileItem only shows the little red x where the graphic should be.

    My client-side Java code:
    Code:
    public class Species8472 implements EntryPoint {

	DataSource employee = DataSource.getDataSource("EMPLOYEE");

	public void onModuleLoad() {
		Canvas canvas = new Canvas();

		DynamicForm viewForm = new DynamicForm();
		viewForm.setDataSource(employee);

		TextItem textItem = new TextItem("NAME", "Signer");

		ViewFileItem target = new ViewFileItem("SIGNATURE");
		target.setShowFileInline(true);

		viewForm.setItems(textItem, target);

		Criteria criteria = new Criteria();
		criteria.addCriteria("AD_ACCOUNT", "wwilso");

		viewForm.fetchData(criteria);

		canvas.addChild(viewForm);

		canvas.draw();
	}

}
    My DataSource:
    Code:
    <DataSource 
        ...
	serverType="sql"	// Oracle
>

  <fields>
    <field name="EMPLOYEE_ID" type="number"  primaryKey="true"></field>
    <field name="IT_GROUP" length="30" type="text"></field>
    <field name="AD_ACCOUNT" length="256" type="text"></field>
    <field name="EMAIL" length="100" type="text"></field>
    <field name="NAME" title="iTeamster" length="100" type="text"></field>
    <field name="IS_APPROVER" type="number"></field>
    <field name="SIGNATURE" type="imageFile"></field>
    <field name="SIGNATURE_FILENAME" length="256" type="text"></field>
    <field name="SIGNATURE_FILESIZE" type="number"></field>
    <field name="SIGNATURE_DATE_CREATED" type="date"></field>
  </fields>
...
    The SmartClient Developer Console reports 2 viewFile operations. Here's one:

    RPCRequest payload:
    Code:
    {
    "actionURL":"http://127.0.0.1:8888/builtinds/sc/IDACall", 
    "showPrompt":true, 
    "transport":"xmlHttpRequest", 
    "promptStyle":"cursor", 
    "bypassCache":true, 
    "data":{
        "criteria":{
            "AD_ACCOUNT":"wwilso"
        }, 
        "operationConfig":{
            "dataSource":"EMPLOYEE", 
            "repo":null, 
            "operationType":"fetch"
        }, 
        "componentId":"isc_DynamicForm_0", 
        "appID":"builtinApplication", 
        "operation":"EMPLOYEE_fetch", 
        "oldValues":{
            "AD_ACCOUNT":"wwilso"
        }, 
        "streamResults":null, 
        "exportToFilesystem":null, 
        "exportToClient":null
    }
}
    DSRequest payload:
    Code:
    {
    "dataSource":"EMPLOYEE", 
    "operationType":"viewFile", 
    "data":{
        "EMPLOYEE_ID":12345, 
        "download_fieldname":"SIGNATURE", 
        "download_filename":null
    }, 
    "callback":null, 
    "showPrompt":false, 
    "oldValues":{
        "EMPLOYEE_ID":12345, 
        "download_fieldname":"SIGNATURE", 
        "download_filename":null
    }, 
    "requestId":"EMPLOYEE$6272"
}
    For both viewFile operations, the response shows "Raw [Transaction in Progress]"

    The Eclipse console reports:
    Code:
    === 2011-12-22 11:22:10,083 [l0-7] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-22 11:22:10,098 [l0-7] DEBUG XML - Parsed XML from (in memory stream): 15ms
=== 2011-12-22 11:22:10,114 [l0-7] DEBUG RPCManager - Processing 1 requests.
=== 2011-12-22 11:22:10,114 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2011-12-22 11:22:10,114 [l0-7] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        AD_ACCOUNT:"wwilso"
    },
    operationConfig:{
        dataSource:"EMPLOYEE",
        operationType:"fetch"
    },
    componentId:"isc_DynamicForm_0",
    appID:"builtinApplication",
    operation:"EMPLOYEE_fetch",
    oldValues:{
        AD_ACCOUNT:"wwilso"
    }
}
=== 2011-12-22 11:22:10,114 [l0-7] INFO  IDACall - Performing 1 operation(s)
=== 2011-12-22 11:22:10,114 [l0-7] DEBUG AppBase - [builtinApplication.EMPLOYEE_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2011-12-22 11:22:10,114 [l0-7] DEBUG AppBase - [builtinApplication.EMPLOYEE_fetch] No public zero-argument method named '_EMPLOYEE_fetch' found, performing generic datasource operation
=== 2011-12-22 11:22:10,114 [l0-7] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_fetch] Performing fetch operation with
	criteria: {AD_ACCOUNT:"wwilso"}	values: {AD_ACCOUNT:"wwilso"}
=== 2011-12-22 11:22:10,114 [l0-7] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2011-12-22 11:22:10,114 [l0-7] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_fetch] Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM mySchema.EMPLOYEE WHERE (EMPLOYEE.AD_ACCOUNT='wwilso')
=== 2011-12-22 11:22:10,114 [l0-7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] DriverManager fetching connection for myOracleDatabase via jdbc url jdbc:oracle:thin:@myOracleServer:1521:myOracleDatabase
=== 2011-12-22 11:22:10,114 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        download_fieldname:"SIGNATURE"
    },
    operationConfig:{
        dataSource:"EMPLOYEE",
        operationType:"viewFile"
    },
    appID:"builtinApplication",
    operation:"EMPLOYEE_viewFile",
    oldValues:{
        download_fieldname:"SIGNATURE"
    }
}
=== 2011-12-22 11:22:10,114 [l0-7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] Passing credentials getConnection separately from JDBC URL
=== 2011-12-22 11:22:10,114 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2011-12-22 11:22:10,114 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile] No userTypes defined, allowing anyone access to all operations for this application
=== 2011-12-22 11:22:10,114 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile] No public zero-argument method named '_EMPLOYEE_viewFile' found, performing generic datasource operation
=== 2011-12-22 11:22:10,114 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2011-12-22 11:22:10,114 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2011-12-22 11:22:10,114 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Performing fetch operation with
	criteria: {download_fieldname:"SIGNATURE"}	values: {download_fieldname:"SIGNATURE"}
=== 2011-12-22 11:22:10,114 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2011-12-22 11:22:10,114 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM mySchema.EMPLOYEE WHERE ('1'='1')
=== 2011-12-22 11:22:10,114 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] DriverManager fetching connection for myOracleDatabase via jdbc url jdbc:oracle:thin:@myOracleServer:1521:myOracleDatabase
=== 2011-12-22 11:22:10,114 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Passing credentials getConnection separately from JDBC URL
=== 2011-12-22 11:22:10,145 [l0-7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] Returning pooled Connection
=== 2011-12-22 11:22:10,145 [l0-7] DEBUG SQLTransaction - [builtinApplication.EMPLOYEE_fetch] Started new myOracleDatabase transaction "9752645"
=== 2011-12-22 11:22:10,145 [l0-7] INFO  SQLDriver - [builtinApplication.EMPLOYEE_fetch] Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM mySchema.EMPLOYEE WHERE (EMPLOYEE.AD_ACCOUNT='wwilso')
=== 2011-12-22 11:22:10,145 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Returning pooled Connection
=== 2011-12-22 11:22:10,161 [l0-6] DEBUG SQLTransaction - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Started new myOracleDatabase transaction "1432764"
=== 2011-12-22 11:22:10,161 [l0-6] INFO  SQLDriver - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM mySchema.EMPLOYEE WHERE ('1'='1')
=== 2011-12-22 11:22:10,161 [l0-7] INFO  DSResponse - [builtinApplication.EMPLOYEE_fetch] DSResponse: List with 1 items
=== 2011-12-22 11:22:10,161 [l0-7] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2011-12-22 11:22:10,161 [l0-7] DEBUG SQLTransaction - Committing myOracleDatabase transaction "9752645"
=== 2011-12-22 11:22:10,161 [l0-7] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2011-12-22 11:22:10,161 [l0-6] INFO  DSResponse - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] DSResponse: List with 27 items
=== 2011-12-22 11:22:10,161 [l0-6] WARN  RequestContext - dsRequest.execute() failed: 
java.lang.Exception: Fetched multiple results when trying single
	at com.isomorphic.datasource.DataSource.forceSingle(DataSource.java:2588)
	at com.isomorphic.sql.SQLDataSource.executeDownload(SQLDataSource.java:318)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1299)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:721)
	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:1948)
	at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:199)
	at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:156)
	at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:121)
	at com.isomorphic.servlet.IDACall.doGet(IDACall.java:81)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
	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.headerComplete(HttpConnection.java:829)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:513)
	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)
=== 2011-12-22 11:22:10,161 [l0-6] WARN  RPCManager - dsResponse.getData().get("SIGNATURE") returned null  when we were expecting an InputStream. Can't continue.
=== 2011-12-22 11:22:10,161 [l0-6] DEBUG SQLTransaction - Ending myOracleDatabase transaction "1432764"
    Just trying to reach the bottom rung of the ladder here, any advice appreciated.

    Comment

      #3
      It appears that your RPCRequest, dsRequest and server log are all from different attempts (please don't do this).

      Looking at the server log, the problem is that the primary key is not being submitted. With a ViewFileItem in a form, you need to make sure whatever Record you're editing in the form contains a primaryKey or the ViewFileItem won't know what image to fetch.

      Comment

        #4
        I apologize, in trying to save space, I cut & pasted mismatched log entries. Here's a do-over, with all log info.

        This Java code has been altered slightly to use the "EMPLOYEE_ID" primary key field for query & display:
        Code:
        public class Species8472 implements EntryPoint {

	DataSource employee = DataSource.getDataSource("EMPLOYEE");

	public void onModuleLoad() {
		Canvas canvas = new Canvas();

		DynamicForm viewForm = new DynamicForm();
		viewForm.setDataSource(employee);

		TextItem textItem = new TextItem("EMPLOYEE_ID", "Employee_ID");

		ViewFileItem target = new ViewFileItem("SIGNATURE");
		target.setShowFileInline(true);

		viewForm.setItems(textItem, target);
		
		Criteria criteria = new Criteria();
		criteria.addCriteria("EMPLOYEE_ID", 12345);
		
		viewForm.fetchData(criteria);

		canvas.addChild(viewForm);

		canvas.draw();
	}
}
        The code generates 3 RPC requests.

        RPC Request # 0, Operation=viewFile, Status=[null]
        Code:
        ////////////// RPCRequest //////////////
{
    "actionURL":"http://127.0.0.1:8888/builtinds/sc/IDACall", 
    "showPrompt":true, 
    "transport":"xmlHttpRequest", 
    "promptStyle":"cursor", 
    "bypassCache":true, 
    "data":{
        "criteria":{
            "EMPLOYEE_ID":12345
        }, 
        "operationConfig":{
            "dataSource":"EMPLOYEE", 
            "repo":null, 
            "operationType":"fetch"
        }, 
        "componentId":"isc_DynamicForm_0", 
        "appID":"builtinApplication", 
        "operation":"EMPLOYEE_fetch", 
        "oldValues":{
            "EMPLOYEE_ID":12345
        }, 
        "streamResults":null, 
        "exportToFilesystem":null, 
        "exportToClient":null
    }
}

////////////// DSRequest //////////////
{
    "dataSource":"EMPLOYEE", 
    "operationType":"viewFile", 
    "data":{
        "download_fieldname":"SIGNATURE", 
        "download_filename":null
    }, 
    "callback":null, 
    "showPrompt":false, 
    "oldValues":{
        "download_fieldname":"SIGNATURE", 
        "download_filename":null
    }, 
    "requestId":"EMPLOYEE$6270"
}

////////////// Response Raw //////////////
[Transaction in Progress]

        RPC Request # 1, Operation=fetch, Status=SUCCESS
        Code:
        ////////////// RPCRequest //////////////
{
    "actionURL":"http://127.0.0.1:8888/builtinds/sc/IDACall", 
    "showPrompt":true, 
    "transport":"xmlHttpRequest", 
    "promptStyle":"cursor", 
    "bypassCache":true, 
    "data":{
        "criteria":{
            "EMPLOYEE_ID":12345
        }, 
        "operationConfig":{
            "dataSource":"EMPLOYEE", 
            "repo":null, 
            "operationType":"fetch"
        }, 
        "componentId":"isc_DynamicForm_0", 
        "appID":"builtinApplication", 
        "operation":"EMPLOYEE_fetch", 
        "oldValues":{
            "EMPLOYEE_ID":12345
        }, 
        "streamResults":null, 
        "exportToFilesystem":null, 
        "exportToClient":null
    }
}

////////////// DSRequest //////////////
{
    "dataSource":"EMPLOYEE", 
    "operationType":"fetch", 
    "componentId":"isc_DynamicForm_0", 
    "data":{
        "EMPLOYEE_ID":12345
    }, 
    "callback":{
        "target":[DynamicForm ID:isc_DynamicForm_0], 
        "methodName":"fetchDataReply"
    }, 
    "showPrompt":true, 
    "oldValues":{
        "EMPLOYEE_ID":12345
    }, 
    "requestId":"EMPLOYEE$6271"
}

////////////// Response Raw //////////////
[
    {
        data:[
            {
                IT_GROUP:"ITEMP", 
                SIGNATURE_FILESIZE:761, 
                SIGNATURE_FILENAME:"TestSignature.gif", 
                AD_ACCOUNT:"wwilso", 
                NAME:"Wilson, Woodrow", 
                SIGNATURE_DATE_CREATED:new Date(1324411200000), 
                IS_APPROVER:67890, 
                EMAIL:"woodrow.wilson@soandso.com", 
                EMPLOYEE_ID:12345
            }
        ], 
        endRow:1, 
        invalidateCache:false, 
        isDSResponse:true, 
        operationType:"fetch", 
        queueStatus:0, 
        startRow:0, 
        status:0, 
        totalRows:1
    }
]
        RPC Request # 2, Operation=viewFile, Status=[null]
        Code:
        ////////////// RPCRequest //////////////
{
    "actionURL":"http://127.0.0.1:8888/builtinds/sc/IDACall", 
    "showPrompt":true, 
    "transport":"xmlHttpRequest", 
    "promptStyle":"cursor", 
    "bypassCache":true, 
    "data":{
        "criteria":{
            "EMPLOYEE_ID":12345
        }, 
        "operationConfig":{
            "dataSource":"EMPLOYEE", 
            "repo":null, 
            "operationType":"fetch"
        }, 
        "componentId":"isc_DynamicForm_0", 
        "appID":"builtinApplication", 
        "operation":"EMPLOYEE_fetch", 
        "oldValues":{
            "EMPLOYEE_ID":12345
        }, 
        "streamResults":null, 
        "exportToFilesystem":null, 
        "exportToClient":null
    }
}

////////////// DSRequest //////////////
{
    "dataSource":"EMPLOYEE", 
    "operationType":"viewFile", 
    "data":{
        "EMPLOYEE_ID":12345, 
        "download_fieldname":"SIGNATURE", 
        "download_filename":null
    }, 
    "callback":null, 
    "showPrompt":false, 
    "oldValues":{
        "EMPLOYEE_ID":12345, 
        "download_fieldname":"SIGNATURE", 
        "download_filename":null
    }, 
    "requestId":"EMPLOYEE$6272"
}

////////////// Response Raw //////////////
[Transaction in Progress]

        Eclipse console log:
        Code:
        === 2011-12-22 14:44:08,544 [l0-6] INFO  Download - Returning 304: Not modified on conditional get of: 

C:\eclipse\workspace\builtinds\war\builtinds\sc\skins\Enterprise\load_skin.js
=== 2011-12-22 14:44:08,623 [0-13] INFO  Compression - /builtinds/sc/modules/ISC_Core.js: 705501 -> 182712 bytes
=== 2011-12-22 14:44:09,310 [0-13] INFO  Download - Returning 304: Not modified on conditional get of: 

C:\eclipse\workspace\builtinds\war\builtinds\sc\skins\Enterprise\skin_styles.css
=== 2011-12-22 14:44:09,326 [0-13] INFO  RequestContext - URL: '/builtinds/sc/DataSourceLoader', User-Agent: 'Mozilla/4.0 

(compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 

3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-22 14:44:09,357 [0-13] INFO  Compression - /builtinds/sc/DataSourceLoader: 1395 -> 458 bytes
=== 2011-12-22 14:44:15,685 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/4.0 (compatible; 

MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 

3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-22 14:44:15,701 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 16ms
=== 2011-12-22 14:44:15,716 [0-13] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/4.0 (compatible; 

MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 

3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-22 14:44:15,732 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2011-12-22 14:44:15,748 [0-13] DEBUG XML - Parsed XML from (in memory stream): 32ms
=== 2011-12-22 14:44:15,748 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        download_fieldname:"SIGNATURE"
    },
    operationConfig:{
        dataSource:"EMPLOYEE",
        operationType:"viewFile"
    },
    appID:"builtinApplication",
    operation:"EMPLOYEE_viewFile",
    oldValues:{
        download_fieldname:"SIGNATURE"
    }
}
=== 2011-12-22 14:44:15,748 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2011-12-22 14:44:15,748 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile] No userTypes defined, allowing 

anyone access to all operations for this application
=== 2011-12-22 14:44:15,748 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile] No public zero-argument method 

named '_EMPLOYEE_viewFile' found, performing generic datasource operation
=== 2011-12-22 14:44:15,748 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] No 

userTypes defined, allowing anyone access to all operations for this application
=== 2011-12-22 14:44:15,748 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] No public 

zero-argument method named '_null' found, performing generic datasource operation
=== 2011-12-22 14:44:15,748 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

Performing fetch operation with
	criteria: {download_fieldname:"SIGNATURE"}	values: {download_fieldname:"SIGNATURE"}
=== 2011-12-22 14:44:15,748 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2011-12-22 14:44:15,748 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, 

EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, 

EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, 

EMPLOYEE.SIGNATURE_filesize FROM MYSCHEMA.EMPLOYEE WHERE ('1'='1')
=== 2011-12-22 14:44:15,748 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, 

builtinApplication.null] DriverManager fetching connection for myOracleDatabase via jdbc url 

jdbc:oracle:thin:@myOracleServer:1521:myOracleDatabase
=== 2011-12-22 14:44:15,748 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, 

builtinApplication.null] Passing credentials getConnection separately from JDBC URL
=== 2011-12-22 14:44:15,748 [0-13] DEBUG RPCManager - Processing 1 requests.
=== 2011-12-22 14:44:15,763 [0-13] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        EMPLOYEE_ID:12345
    },
    operationConfig:{
        dataSource:"EMPLOYEE",
        operationType:"fetch"
    },
    componentId:"isc_DynamicForm_0",
    appID:"builtinApplication",
    operation:"EMPLOYEE_fetch",
    oldValues:{
        EMPLOYEE_ID:12345
    }
}
=== 2011-12-22 14:44:15,763 [0-13] INFO  IDACall - Performing 1 operation(s)
=== 2011-12-22 14:44:15,763 [0-13] DEBUG AppBase - [builtinApplication.EMPLOYEE_fetch] No userTypes defined, allowing anyone 

access to all operations for this application
=== 2011-12-22 14:44:15,763 [0-13] DEBUG AppBase - [builtinApplication.EMPLOYEE_fetch] No public zero-argument method named 

'_EMPLOYEE_fetch' found, performing generic datasource operation
=== 2011-12-22 14:44:15,763 [0-13] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_fetch] Performing fetch operation with
	criteria: {EMPLOYEE_ID:12345}	values: {EMPLOYEE_ID:12345}
=== 2011-12-22 14:44:15,763 [0-13] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_fetch] derived query: SELECT 

$defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2011-12-22 14:44:15,763 [0-13] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_fetch] Executing SQL query on 

'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, 

EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, 

EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM 

MYSCHEMA.EMPLOYEE WHERE (EMPLOYEE.EMPLOYEE_ID='12345')
=== 2011-12-22 14:44:15,763 [0-13] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] DriverManager 

fetching connection for myOracleDatabase via jdbc url jdbc:oracle:thin:@myOracleServer:1521:myOracleDatabase
=== 2011-12-22 14:44:15,763 [0-13] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] Passing 

credentials getConnection separately from JDBC URL
=== 2011-12-22 14:44:15,779 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, 

builtinApplication.null] Returning pooled Connection
=== 2011-12-22 14:44:15,779 [l0-6] DEBUG SQLTransaction - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

Started new myOracleDatabase transaction "6310606"
=== 2011-12-22 14:44:15,779 [l0-6] INFO  SQLDriver - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, 

EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, 

EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, 

EMPLOYEE.SIGNATURE_filesize FROM MYSCHEMA.EMPLOYEE WHERE ('1'='1')
=== 2011-12-22 14:44:15,794 [0-13] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] Returning pooled 

Connection
=== 2011-12-22 14:44:15,794 [l0-6] INFO  DSResponse - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

DSResponse: List with 27 items
=== 2011-12-22 14:44:15,794 [l0-6] WARN  RequestContext - dsRequest.execute() failed: 
java.lang.Exception: Fetched multiple results when trying single
	at com.isomorphic.datasource.DataSource.forceSingle(DataSource.java:2588)
	at com.isomorphic.sql.SQLDataSource.executeDownload(SQLDataSource.java:318)
	at com.isomorphic.datasource.DataSource.execute(DataSource.java:1299)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:721)
	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:1948)
	at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:199)
	at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:156)
	at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:121)
	at com.isomorphic.servlet.IDACall.doGet(IDACall.java:81)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
	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.headerComplete(HttpConnection.java:829)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:513)
	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)
=== 2011-12-22 14:44:15,794 [l0-6] WARN  RPCManager - dsResponse.getData().get("SIGNATURE") returned null  when we were 

expecting an InputStream. Can't continue.
=== 2011-12-22 14:44:15,794 [l0-6] DEBUG SQLTransaction - Ending myOracleDatabase transaction "6310606"
=== 2011-12-22 14:44:15,794 [0-13] DEBUG SQLTransaction - [builtinApplication.EMPLOYEE_fetch] Started new myOracleDatabase 

transaction "6347766"
=== 2011-12-22 14:44:15,794 [0-13] INFO  SQLDriver - [builtinApplication.EMPLOYEE_fetch] Executing SQL query on 

'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, 

EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, 

EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM 

MYSCHEMA.EMPLOYEE WHERE (EMPLOYEE.EMPLOYEE_ID='12345')
=== 2011-12-22 14:44:15,794 [l0-6] INFO  Compression - /builtinds/sc/IDACall: 0 -> 20 bytes
=== 2011-12-22 14:44:15,794 [0-13] INFO  DSResponse - [builtinApplication.EMPLOYEE_fetch] DSResponse: List with 1 items
=== 2011-12-22 14:44:15,794 [0-13] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2011-12-22 14:44:15,794 [0-13] DEBUG SQLTransaction - Committing myOracleDatabase transaction "6347766"
=== 2011-12-22 14:44:15,810 [0-13] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2011-12-22 14:44:15,810 [0-13] DEBUG SQLTransaction - Ending myOracleDatabase transaction "6347766"
=== 2011-12-22 14:44:15,810 [0-13] INFO  Compression - /builtinds/sc/IDACall: 426 -> 328 bytes
=== 2011-12-22 14:44:16,013 [0-13] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/4.0 (compatible; 

MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 

3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-22 14:44:16,013 [0-13] DEBUG XML - Parsed XML from (in memory stream): 0ms
=== 2011-12-22 14:44:16,029 [0-13] DEBUG RPCManager - Processing 1 requests.
=== 2011-12-22 14:44:16,029 [0-13] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        EMPLOYEE_ID:12345,
        download_fieldname:"SIGNATURE"
    },
    operationConfig:{
        dataSource:"EMPLOYEE",
        operationType:"viewFile"
    },
    appID:"builtinApplication",
    operation:"EMPLOYEE_viewFile",
    oldValues:{
        EMPLOYEE_ID:12345,
        download_fieldname:"SIGNATURE"
    }
}
=== 2011-12-22 14:44:16,029 [0-13] INFO  IDACall - Performing 1 operation(s)
=== 2011-12-22 14:44:16,029 [0-13] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile] No userTypes defined, allowing 

anyone access to all operations for this application
=== 2011-12-22 14:44:16,029 [0-13] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile] No public zero-argument method 

named '_EMPLOYEE_viewFile' found, performing generic datasource operation
=== 2011-12-22 14:44:16,029 [0-13] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] No 

userTypes defined, allowing anyone access to all operations for this application
=== 2011-12-22 14:44:16,029 [0-13] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] No public 

zero-argument method named '_null' found, performing generic datasource operation
=== 2011-12-22 14:44:16,029 [0-13] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

Performing fetch operation with
	criteria: {EMPLOYEE_ID:12345,download_fieldname:"SIGNATURE"}	values: 

{EMPLOYEE_ID:12345,download_fieldname:"SIGNATURE"}
=== 2011-12-22 14:44:16,029 [0-13] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2011-12-22 14:44:16,029 [l0-6] INFO  RequestContext - URL: 

'/builtinds/sc/system/reference/skin/images/server_client_exchange.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; 

Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': 

MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-22 14:44:16,029 [0-13] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, 

EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, 

EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, 

EMPLOYEE.SIGNATURE_filesize FROM MYSCHEMA.EMPLOYEE WHERE (EMPLOYEE.EMPLOYEE_ID='12345')
=== 2011-12-22 14:44:16,029 [0-13] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, 

builtinApplication.null] DriverManager fetching connection for myOracleDatabase via jdbc url 

jdbc:oracle:thin:@myOracleServer:1521:myOracleDatabase
=== 2011-12-22 14:44:16,029 [0-13] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, 

builtinApplication.null] Passing credentials getConnection separately from JDBC URL
=== 2011-12-22 14:44:16,060 [l0-6] INFO  Download - File 

H:/MYSCHEMA/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/reference/skin/images/server_client_exchange.pn

g not found, sending 404
=== 2011-12-22 14:44:16,060 [0-13] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, 

builtinApplication.null] Returning pooled Connection
=== 2011-12-22 14:44:16,060 [0-13] DEBUG SQLTransaction - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

Started new myOracleDatabase transaction "2016671"
=== 2011-12-22 14:44:16,060 [0-13] INFO  SQLDriver - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, 

EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, 

EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, 

EMPLOYEE.SIGNATURE_filesize FROM MYSCHEMA.EMPLOYEE WHERE (EMPLOYEE.EMPLOYEE_ID='12345')
=== 2011-12-22 14:44:16,076 [0-13] INFO  DSResponse - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] 

DSResponse: List with 1 items
=== 2011-12-22 14:44:16,076 [0-13] INFO  DSResponse - [builtinApplication.EMPLOYEE_viewFile] DSResponse: Map with 10 keys
=== 2011-12-22 14:44:16,076 [0-13] INFO  ProxyHttpServletResponse - content-disposition set to: inline;  - unwrapping 

response output stream (CompressionFilter)
=== 2011-12-22 14:44:16,091 [0-13] DEBUG SQLTransaction - Committing myOracleDatabase transaction "2016671"
=== 2011-12-22 14:44:16,091 [0-13] DEBUG SQLTransaction - Ending myOracleDatabase transaction "2016671"
=== 2011-12-22 14:44:16,091 [0-13] ERROR IDACall - Error executing operation: EMPLOYEE_viewFile
java.io.IOException: Closed
	at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:627)
	at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:577)
	at com.isomorphic.servlet.ProxyServletOutputStream.write(ProxyServletOutputStream.java:65)
	at java.io.BufferedOutputStream.flushBuffer(Unknown Source)
	at java.io.BufferedOutputStream.flush(Unknown Source)
	at com.isomorphic.util.IOUtil.copyStreams(IOUtil.java:113)
	at com.isomorphic.util.IOUtil.copyStreams(IOUtil.java:52)
	at com.isomorphic.rpc.RPCManager.completeResponse(RPCManager.java:826)
	at com.isomorphic.rpc.RPCManager.send(RPCManager.java:582)
	at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:156)
	at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:121)
	at com.isomorphic.servlet.IDACall.doGet(IDACall.java:81)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
	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.headerComplete(HttpConnection.java:829)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:513)
	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)
=== 2011-12-22 14:44:16,091 [0-13] WARN  RPCManager - dsResponse.getData().get("SIGNATURE") returned null  when we were 

expecting an InputStream. Can't continue.
        Don't know if this is pertinent, but, the GWT Dev Console reports the following, twice:
        Code:
        00:00:00.016 [ERROR] 14:57:18.433:WARN:Canvas:isc_ViewFileItem_1_canvas:ignoring bad or negative height: -4 [enable 'sizing' 

log for stack trace]
com.smartgwt.client.core.JsObject$SGWT_WARN: 14:57:18.433:WARN:Canvas:isc_ViewFileItem_1_canvas:ignoring bad or negative 

height: -4 [enable 'sizing' log for stack trace] 	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native 

Method) 	at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) 	at 

sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) 	at 

java.lang.reflect.Constructor.newInstance(Unknown Source) 	at 

com.google.gwt.dev.shell.MethodAdaptor.invoke(MethodAdaptor.java:105) 	at 

com.google.gwt.dev.shell.MethodDispatch.invoke(MethodDispatch.java:71) 	at 

com.google.gwt.dev.shell.OophmSessionHandler.invoke(OophmSessionHandler.java:172) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.reactToMessagesWhileWaitingForReturn(BrowserChannelServer.java:337) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.invokeJavascript(BrowserChannelServer.java:218) 	at 

com.google.gwt.dev.shell.ModuleSpaceOOPHM.doInvoke(ModuleSpaceOOPHM.java:136) 	at 

com.google.gwt.dev.shell.ModuleSpace.invokeNative(ModuleSpace.java:561) 	at 

com.google.gwt.dev.shell.ModuleSpace.invokeNativeObject(ModuleSpace.java:269) 	at 

com.google.gwt.dev.shell.JavaScriptHost.invokeNativeObject(JavaScriptHost.java:91) 	at 

com.smartgwt.client.widgets.BaseWidget.getInnerHTML(BaseWidget.java) 	at 

sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown 

Source) 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) 	at 

java.lang.reflect.Method.invoke(Unknown Source) 	at 

com.google.gwt.dev.shell.MethodAdaptor.invoke(MethodAdaptor.java:103) 	at 

com.google.gwt.dev.shell.MethodDispatch.invoke(MethodDispatch.java:71) 	at 

com.google.gwt.dev.shell.OophmSessionHandler.invoke(OophmSessionHandler.java:172) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.reactToMessagesWhileWaitingForReturn(BrowserChannelServer.java:337) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.invokeJavascript(BrowserChannelServer.java:218) 	at 

com.google.gwt.dev.shell.ModuleSpaceOOPHM.doInvoke(ModuleSpaceOOPHM.java:136) 	at 

com.google.gwt.dev.shell.ModuleSpace.invokeNative(ModuleSpace.java:561) 	at 

com.google.gwt.dev.shell.ModuleSpace.invokeNativeVoid(ModuleSpace.java:289) 	at 

com.google.gwt.dev.shell.JavaScriptHost.invokeNativeVoid(JavaScriptHost.java:107) 	at 

com.smartgwt.client.widgets.BaseWidget.draw(BaseWidget.java) 	at 

com.smartgwt.sample.client.Species8472.onModuleLoad(Species8472.java:68) 	at 

sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown 

Source) 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) 	at 

java.lang.reflect.Method.invoke(Unknown Source) 	at com.google.gwt.dev.shell.ModuleSpace.onLoad(ModuleSpace.java:396) 	

at com.google.gwt.dev.shell.OophmSessionHandler.loadModule(OophmSessionHandler.java:200) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.processConnection(BrowserChannelServer.java:525) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.run(BrowserChannelServer.java:363) 	at java.lang.Thread.run(Unknown 

Source)
        and also this, also twice:
        Code:
        00:00:01.172 [ERROR] 14:57:19.589:RDQ0:WARN:Canvas:isc_ViewFileItem_1_canvas:ignoring bad or negative height: -4 [enable 

'sizing' log for stack trace]
com.smartgwt.client.core.JsObject$SGWT_WARN: 14:57:19.589:RDQ0:WARN:Canvas:isc_ViewFileItem_1_canvas:ignoring bad or negative 

height: -4 [enable 'sizing' log for stack trace] 	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native 

Method) 	at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) 	at 

sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) 	at 

java.lang.reflect.Constructor.newInstance(Unknown Source) 	at 

com.google.gwt.dev.shell.MethodAdaptor.invoke(MethodAdaptor.java:105) 	at 

com.google.gwt.dev.shell.MethodDispatch.invoke(MethodDispatch.java:71) 	at 

com.google.gwt.dev.shell.OophmSessionHandler.invoke(OophmSessionHandler.java:172) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.reactToMessagesWhileWaitingForReturn(BrowserChannelServer.java:337) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.invokeJavascript(BrowserChannelServer.java:218) 	at 

com.google.gwt.dev.shell.ModuleSpaceOOPHM.doInvoke(ModuleSpaceOOPHM.java:136) 	at 

com.google.gwt.dev.shell.ModuleSpace.invokeNative(ModuleSpace.java:561) 	at 

com.google.gwt.dev.shell.ModuleSpace.invokeNativeObject(ModuleSpace.java:269) 	at 

com.google.gwt.dev.shell.JavaScriptHost.invokeNativeObject(JavaScriptHost.java:91) 	at 

com.smartgwt.client.widgets.BaseWidget.getInnerHTML(BaseWidget.java) 	at 

sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown 

Source) 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) 	at 

java.lang.reflect.Method.invoke(Unknown Source) 	at 

com.google.gwt.dev.shell.MethodAdaptor.invoke(MethodAdaptor.java:103) 	at 

com.google.gwt.dev.shell.MethodDispatch.invoke(MethodDispatch.java:71) 	at 

com.google.gwt.dev.shell.OophmSessionHandler.invoke(OophmSessionHandler.java:172) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.reactToMessages(BrowserChannelServer.java:292) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.processConnection(BrowserChannelServer.java:546) 	at 

com.google.gwt.dev.shell.BrowserChannelServer.run(BrowserChannelServer.java:363) 	at java.lang.Thread.run(Unknown 

Source)
        Your guidance is much appreciated, thanks.

        Comment

          #5
          Something still seems to be odd with these diagnostics. All your client-side request logs show an EMPLOYEE_ID but the server log that fails shows the absence of an ID. Also, your first client-side request log shows an EMPLOYEE_ID value before the form has even fetched the record, which is clearly impossible.

          So once again, if you cause a form with a ViewFileItem to render when it has no values for the record, it doesn't know the primaryKey so it can't show an image. The result you're seeing suggests this is what you're actually doing.

          Comment

            #6
            "Also, your first client-side request log shows an EMPLOYEE_ID value before the form has even fetched the record, which is clearly impossible."

            I think I see what's going on: This appears to be an unrelated refresh issue w/ the SGWT Dev Console RPC tabs. When the RPC screen is freshly loaded, the Request tabs "RPCRequest" and "Raw" are blank for the viewFile operations. Then if I view those tabs for the fetch operation, the tab view is filled with fetch info. When I return focus to the viewFile operations, the tab views are not reset to blank, they continue to show the fetch info.

            Is this a clue? If I remark out the explicit statement "viewForm.fetchData(criteria)" , I still see an RPC request 0, Operation=viewFile, Status=[null]
            Code:
            //////////// DSRequest  ////////////////////
{
    "dataSource":"EMPLOYEE", 
    "operationType":"viewFile", 
    "data":{
        "download_fieldname":"SIGNATURE", 
        "download_filename":null
    }, 
    "callback":null, 
    "showPrompt":false, 
    "oldValues":{
        "download_fieldname":"SIGNATURE", 
        "download_filename":null
    }, 
    "requestId":"EMPLOYEE$6270"
}

///////////// Response Raw ////////////////
[Transaction in Progress]
            Is that what you'd expect?

            Ring any bells?

            Comment

              #7
              Not clear on what you mean about the RPC tab, but yes, that's the bad request (no PK).

              Again it's happening because you're rendering the form with a ViewFileItem but no values, hence no primary key. fetchData() is asynchronous. You can fix this by calling DataSource.fetchData() and giving the resulting record to the form instead of calling DynamicForm.fetchData().

              We'll probably also eliminate this spurious fetch, at least for cases where the form knows for sure it can't possibly succeed (no PK available).

              Comment

                #8
                OK, but I still don't get:

                If I follow your suggestion to query the DataSource directly:
                Code:
                Criteria criteria = new Criteria();
criteria.addCriteria("EMPLOYEE_ID", 12345);

employee.fetchData(criteria);
                ...I'm submitting, and receiving, the PK value
                Code:
                ////////////////// RPCRequest ///////////////////
{
    "actionURL":"http://127.0.0.1:8888/builtinds/sc/IDACall", 
    "showPrompt":true, 
    "transport":"xmlHttpRequest", 
    "promptStyle":"cursor", 
    "bypassCache":true, 
    "data":{
        "criteria":{
            "EMPLOYEE_ID":12345
        }, 
        "operationConfig":{
            "dataSource":"EMPLOYEE", 
            "repo":null, 
            "operationType":"fetch"
        }, 
        "appID":"builtinApplication", 
        "operation":"EMPLOYEE_fetch", 
        "oldValues":{
            "EMPLOYEE_ID":12345
        }, 
        "streamResults":null, 
        "exportToFilesystem":null, 
        "exportToClient":null
    }
}

////////////// DSRequest ///////////////////
{
    "dataSource":"EMPLOYEE", 
    "operationType":"fetch", 
    "data":{
        "EMPLOYEE_ID":12345
    }, 
    "showPrompt":true, 
    "oldValues":{
        "EMPLOYEE_ID":12345
    }, 
    "requestId":"EMPLOYEE$6270"
}

//////////// Response Raw ///////////////////
[
    {
        data:[
            {
                IT_GROUP:"ITEMP", 
                SIGNATURE_FILESIZE:761, 
                SIGNATURE_FILENAME:"TestSignature.gif", 
                AD_ACCOUNT:"wwilso", 
                NAME:"Wilson, Woodrow", 
                SIGNATURE_DATE_CREATED:new Date(1324411200000), 
                IS_APPROVER:67890, 
                EMAIL:"woodrow.wilson@someco.com", 
                EMPLOYEE_ID:12345
            }
        ], 
        endRow:1, 
        invalidateCache:false, 
        isDSResponse:true, 
        operationType:"fetch", 
        queueStatus:0, 
        startRow:0, 
        status:0, 
        totalRows:1
    }
]
                As you can see, the "SIGNATURE" field of type="imageField" is not included in the response. The Eclipse console log has a line about:
                Code:
                WARN  RPCManager - dsResponse.getData().get("SIGNATURE") returned null  when we were expecting an InputStream. Can't continue.
                Am I missing something basic like an extra "imageFile" DSRequest property to the fetch?

                Oracle SQL Developer tells me that the SIGNATURE value for this record is a (BLOB) and our dba tells me it looks valid because he can substring out some bytes, which I guess is his usual test. I.e., I'm not saying the BLOB is valid, but I am saying it's not null.

                SmartClient Version: SC_SNAPSHOT-2011-12-05/EVAL Deployment (expires 2012.02.03_05.26.06) Licensed to: Isomorphic Software (#ISC_EVAL_NIGHTLY)

                Comment

                  #9
                  There's no single-argument signature for fetchData(). Please please don't show pseudocode as if it's real code.

                  Take the Record returned by fetchData and populate the form with it, then draw the form.

                  Comment

                    #10
                    I apologize for the prior code post. Point taken.

                    DataSource field definitions (moved BLOB field canEdit & showFileInline declarations here from Java code):
                    Code:
                    <fields>		
  <field name="EMPLOYEE_ID" type="number"  primaryKey="true" foreignKey="REQUESTS.EMPLOYEE_ID"></field>
  <field name="IT_GROUP" length="30" type="text"></field>
  <field name="AD_ACCOUNT" length="256" type="text"></field>
  <field name="EMAIL" length="100" type="text"></field>
  <field name="NAME" title="iTeamster" length="100" type="text"></field>
  <field name="IS_APPROVER" type="number"></field>
  <field name="SIGNATURE" type="imageFile" canEdit="false" showFileInline="true"></field>
  <field name="SIGNATURE_FILENAME" length="256" type="text" hidden="true"></field>
  <field name="SIGNATURE_FILESIZE" type="number" hidden="true"></field>
  <field name="SIGNATURE_DATE_CREATED" type="date" hidden="true">     </field>
</fields>
                    Java code w/ suggested changes (as best I can figure):
                    Code:
                    package com.smartgwt.sample.client;

import com.google.gwt.core.client.EntryPoint;
import com.smartgwt.client.data.Criteria;
import com.smartgwt.client.data.DSCallback;
import com.smartgwt.client.data.DSRequest;
import com.smartgwt.client.data.DSResponse;
import com.smartgwt.client.data.DataSource;
import com.smartgwt.client.data.Record;
import com.smartgwt.client.widgets.Canvas;
import com.smartgwt.client.widgets.form.DynamicForm;

public class Species8472 implements EntryPoint {

  DataSource employee = DataSource.getDataSource("EMPLOYEE");

  public void onModuleLoad() {
	final Canvas canvas = new Canvas();

	final DynamicForm viewForm = new DynamicForm();
	viewForm.setDataSource(employee);

	Criteria criteria = new Criteria();
	criteria.addCriteria("EMPLOYEE_ID", 12345);

	employee.fetchData(criteria, new DSCallback() {

		@Override
		public void execute(DSResponse response, Object rawData,
				DSRequest request) {

			Record[] record = response.getData();
			String[] attribute = record[0].getAttributes();

			for (int i = 0; i < record[0].getAttributes().length; i++) {
viewForm.setValue(attribute[i],record[0].getAttribute(attribute[i]));
				}

				canvas.addChild(viewForm);
				canvas.draw();

			}

		});

	}
}
                    Still, the (apparent) issue remains, the imageFile value (SIGNATURE) is not returned:
                    Code:
                    "RPCManager - dsResponse.getData().get("SIGNATURE") returned null  when we were expecting an InputStream. Can't continue."
                    The other fields values retrieve & display OK.

                    I went back & verified that the upload code, copied from the Showcase example, is inserting the BLOB without error. I'm wanly confident the BLOB value is OK.

                    Comment

                      #11
                      Client code now looks OK. Can you show a full log for the request that ends in this null field - it doesn't seem to appear in any of the previously posted logs.

                      Comment

                        #12
                        I wonder if you don't have a case-sensitivity problem. I struggled with this a bit myself early on, and it was as simple as that...

                        Comment

                          #13
                          Content of SmartClient Developer Console 'Server Logs' tab, w/ server.properties setting of 'RPCManager.enabledBuiltinMethods: *'

                          Code:
                          === 2011-12-27 07:59:03,012 [main] INFO  ISCInit - Isomorphic SmartClient Framework - Initializing
=== 2011-12-27 07:59:03,028 [main] INFO  ConfigLoader - Attempting to load framework.properties from CLASSPATH
=== 2011-12-27 07:59:03,247 [main] INFO  ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties
=== 2011-12-27 07:59:03,247 [main] INFO  ConfigLoader - Attempting to load project.properties from CLASSPATH
=== 2011-12-27 07:59:03,247 [main] INFO  ConfigLoader - Unable to locate project.properties in CLASSPATH
=== 2011-12-27 07:59:03,262 [main] INFO  ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
=== 2011-12-27 07:59:03,262 [main] INFO  ConfigLoader - Attempting to load server.properties from CLASSPATH
=== 2011-12-27 07:59:03,293 [main] INFO  ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/WEB-INF/classes/server.properties
=== 2011-12-27 07:59:03,293 [main] INFO  Logger - Logging system started.
=== 2011-12-27 07:59:03,293 [main] INFO  ISCInit - Isomorphic SmartClient Framework (SC_SNAPSHOT-2011-12-05/EVAL Deployment 2011-12-05) - Initialization Complete
=== 2011-12-27 07:59:03,309 [main] INFO  ISCInit - Auto-detected webRoot - using: K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war
=== 2011-12-27 08:01:03,933 [l0-0] INFO  RequestContext - URL: '/BuiltInDS.html', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:03,996 [l0-0] INFO  Compression - /BuiltInDS.html: 2096 -> 946 bytes
=== 2011-12-27 08:01:03,996 [l0-0] DEBUG ServletTools - setting cookie 'isc_cState' to: 'ready'
=== 2011-12-27 08:01:04,355 [l0-0] INFO  RequestContext - URL: '/builtinds/builtinds.nocache.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,433 [l0-0] INFO  Compression - /builtinds/builtinds.nocache.js: 7415 -> 2746 bytes
=== 2011-12-27 08:01:04,480 [l0-0] INFO  RequestContext - URL: '/builtinds/clear.cache.gif', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [l0-0] INFO  RequestContext - URL: '/builtinds/sc/initsc.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Grids.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Forms.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_RichTextEditor.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [0-10] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/load_skin.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_DataBinding.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [l0-7] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Calendar.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Core.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [l0-3] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Foundation.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,527 [l0-4] INFO  RequestContext - URL: '/builtinds/sc/modules/ISC_Containers.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:04,636 [l0-0] INFO  Compression - /builtinds/sc/initsc.js: 47 -> 72 bytes
=== 2011-12-27 08:01:04,683 [l0-8] INFO  Compression - /builtinds/sc/modules/ISC_RichTextEditor.js: 40247 -> 11204 bytes
=== 2011-12-27 08:01:04,730 [l0-7] INFO  Compression - /builtinds/sc/modules/ISC_Calendar.js: 104504 -> 25203 bytes
=== 2011-12-27 08:01:04,746 [0-10] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/load_skin.js
=== 2011-12-27 08:01:04,746 [l0-2] INFO  Compression - /builtinds/sc/modules/ISC_Core.js: 705501 -> 182712 bytes
=== 2011-12-27 08:01:04,746 [0-10] INFO  Compression - /builtinds/sc/skins/Enterprise/load_skin.js: 30235 -> 6410 bytes
=== 2011-12-27 08:01:04,761 [l0-4] INFO  Compression - /builtinds/sc/modules/ISC_Containers.js: 130670 -> 30940 bytes
=== 2011-12-27 08:01:04,793 [l0-3] INFO  Compression - /builtinds/sc/modules/ISC_Foundation.js: 236023 -> 55994 bytes
=== 2011-12-27 08:01:04,871 [l0-9] INFO  Compression - /builtinds/sc/modules/ISC_DataBinding.js: 570547 -> 140537 bytes
=== 2011-12-27 08:01:04,902 [l0-6] INFO  Compression - /builtinds/sc/modules/ISC_Forms.js: 631041 -> 153835 bytes
=== 2011-12-27 08:01:04,949 [l0-5] INFO  Compression - /builtinds/sc/modules/ISC_Grids.js: 749180 -> 188507 bytes
=== 2011-12-27 08:01:05,402 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/skin_styles.css', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:05,418 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/DataSourceLoader', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:05,465 [l0-6] INFO  PoolManager - SmartClient pooling disabled for 'EMPLOYEE' objects
=== 2011-12-27 08:01:05,465 [l0-5] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/skin_styles.css
=== 2011-12-27 08:01:05,465 [l0-5] INFO  Compression - /builtinds/sc/skins/Enterprise/skin_styles.css: 65873 -> 8768 bytes
=== 2011-12-27 08:01:05,574 [l0-6] DEBUG XML - Parsed XML from K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war\builtinds\sc\system\schema\builtinTypes.xml: 16ms
=== 2011-12-27 08:01:05,730 [l0-6] DEBUG XML - Parsed XML from K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war\ds\EMPLOYEE.ds.xml: 15ms
=== 2011-12-27 08:01:05,746 [l0-6] DEBUG XML - Parsed XML from K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war\builtinds\sc\system\schema\DataSource.ds.xml: 0ms
=== 2011-12-27 08:01:06,011 [l0-6] DEBUG XML - Parsed XML from K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war\builtinds\sc\system\schema\DataSourceField.ds.xml: 15ms
=== 2011-12-27 08:01:06,090 [l0-6] DEBUG XML - Parsed XML from K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war\builtinds\sc\system\schema\OperationBinding.ds.xml: 0ms
=== 2011-12-27 08:01:06,340 [l0-6] INFO  Compression - /builtinds/sc/DataSourceLoader: 1530 -> 489 bytes
=== 2011-12-27 08:01:06,511 [l0-6] INFO  RequestContext - URL: '/builtinds/hosted.html', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:06,511 [l0-5] INFO  RequestContext - URL: '/builtinds/moduleSpecific.css', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:06,543 [l0-5] INFO  Compression - /builtinds/moduleSpecific.css: 2364 -> 544 bytes
=== 2011-12-27 08:01:06,543 [l0-6] INFO  Compression - /builtinds/hosted.html: 11757 -> 4187 bytes
=== 2011-12-27 08:01:16,339 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/blank.gif', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:16,371 [l0-6] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/blank.gif
=== 2011-12-27 08:01:16,543 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:16,589 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 0ms
=== 2011-12-27 08:01:16,605 [l0-6] DEBUG XML - Parsed XML from K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war\builtinds\sc\system\schema\List.ds.xml: 0ms
=== 2011-12-27 08:01:16,636 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2011-12-27 08:01:16,652 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        EMPLOYEE_ID:12345
    },
    operationConfig:{
        dataSource:"EMPLOYEE",
        operationType:"fetch"
    },
    appID:"builtinApplication",
    operation:"EMPLOYEE_fetch",
    oldValues:{
        EMPLOYEE_ID:12345
    }
}
=== 2011-12-27 08:01:16,668 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2011-12-27 08:01:16,683 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2011-12-27 08:01:16,683 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_fetch] No public zero-argument method named '_EMPLOYEE_fetch' found, performing generic datasource operation
=== 2011-12-27 08:01:16,683 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_fetch] Performing fetch operation with
	criteria: {EMPLOYEE_ID:12345}	values: {EMPLOYEE_ID:12345}
=== 2011-12-27 08:01:16,683 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2011-12-27 08:01:16,996 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_fetch] Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM mySchema.EMPLOYEE WHERE (EMPLOYEE.EMPLOYEE_ID='12345')
=== 2011-12-27 08:01:17,949 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] Initializing SQL config for 'myOracleDatabase' from system config - using DriverManager:  oracle.jdbc.driver.OracleDriver
=== 2011-12-27 08:01:18,011 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] oracle.jdbc.driver.OracleDriver lookup successful
=== 2011-12-27 08:01:18,011 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] DriverManager fetching connection for myOracleDatabase via jdbc url jdbc:oracle:thin:@myOracleServer:1521:myOracleDatabase
=== 2011-12-27 08:01:18,011 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] Passing credentials getConnection separately from JDBC URL
=== 2011-12-27 08:01:18,683 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_fetch] Returning pooled Connection
=== 2011-12-27 08:01:18,808 [l0-6] DEBUG SQLTransaction - [builtinApplication.EMPLOYEE_fetch] Started new myOracleDatabase transaction "28830380"
=== 2011-12-27 08:01:18,808 [l0-6] INFO  SQLDriver - [builtinApplication.EMPLOYEE_fetch] Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM mySchema.EMPLOYEE WHERE (EMPLOYEE.EMPLOYEE_ID='12345')
=== 2011-12-27 08:01:18,855 [l0-6] INFO  DSResponse - [builtinApplication.EMPLOYEE_fetch] DSResponse: List with 1 items
=== 2011-12-27 08:01:18,871 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2011-12-27 08:01:18,871 [l0-6] DEBUG SQLTransaction - Committing myOracleDatabase transaction "28830380"
=== 2011-12-27 08:01:18,871 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2011-12-27 08:01:18,871 [l0-6] DEBUG SQLTransaction - Ending myOracleDatabase transaction "28830380"
=== 2011-12-27 08:01:18,871 [l0-6] INFO  Compression - /builtinds/sc/IDACall: 423 -> 323 bytes
=== 2011-12-27 08:01:19,074 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:01:19,089 [l0-6] DEBUG XML - Parsed XML from (in memory stream): 15ms
=== 2011-12-27 08:01:19,089 [l0-6] DEBUG RPCManager - Processing 1 requests.
=== 2011-12-27 08:01:19,089 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        download_fieldname:"SIGNATURE"
    },
    operationConfig:{
        dataSource:"EMPLOYEE",
        operationType:"viewFile"
    },
    appID:"builtinApplication",
    operation:"EMPLOYEE_viewFile",
    oldValues:{
        download_fieldname:"SIGNATURE"
    }
}
=== 2011-12-27 08:01:19,105 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2011-12-27 08:01:19,105 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile] No userTypes defined, allowing anyone access to all operations for this application
=== 2011-12-27 08:01:19,105 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile] No public zero-argument method named '_EMPLOYEE_viewFile' found, performing generic datasource operation
=== 2011-12-27 08:01:19,105 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2011-12-27 08:01:19,105 [l0-6] DEBUG AppBase - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2011-12-27 08:01:19,105 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Performing fetch operation with
	criteria: {download_fieldname:"SIGNATURE"}	values: {download_fieldname:"SIGNATURE"}
=== 2011-12-27 08:01:19,105 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2011-12-27 08:01:19,105 [l0-6] INFO  SQLDataSource - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM mySchema.EMPLOYEE WHERE ('1'='1')
=== 2011-12-27 08:01:19,105 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] DriverManager fetching connection for myOracleDatabase via jdbc url jdbc:oracle:thin:@myOracleServer:1521:myOracleDatabase
=== 2011-12-27 08:01:19,105 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Passing credentials getConnection separately from JDBC URL
=== 2011-12-27 08:01:19,121 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Returning pooled Connection
=== 2011-12-27 08:01:19,121 [l0-6] DEBUG SQLTransaction - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Started new myOracleDatabase transaction "18604056"
=== 2011-12-27 08:01:19,121 [l0-6] INFO  SQLDriver - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] Executing SQL query on 'myOracleDatabase': SELECT EMPLOYEE.AD_ACCOUNT, EMPLOYEE.EMAIL, EMPLOYEE.EMPLOYEE_ID, EMPLOYEE.IS_APPROVER, EMPLOYEE.IT_GROUP, EMPLOYEE.NAME, EMPLOYEE.SIGNATURE, EMPLOYEE.SIGNATURE_DATE_CREATED, EMPLOYEE.SIGNATURE_FILENAME, EMPLOYEE.SIGNATURE_FILESIZE, EMPLOYEE.SIGNATURE_date_created, EMPLOYEE.SIGNATURE_filename, EMPLOYEE.SIGNATURE_filesize FROM mySchema.EMPLOYEE WHERE ('1'='1')
=== 2011-12-27 08:01:19,152 [l0-6] INFO  DSResponse - [builtinApplication.EMPLOYEE_viewFile, builtinApplication.null] DSResponse: List with 25 items
=== 2011-12-27 08:01:19,152 [l0-6] WARN  RequestContext - dsRequest.execute() failed: 
=== 2011-12-27 08:01:19,168 [l0-6] WARN  RPCManager - dsResponse.getData().get("SIGNATURE") returned null  when we were expecting an InputStream. Can't continue.
=== 2011-12-27 08:01:19,168 [l0-6] DEBUG SQLTransaction - Ending myOracleDatabase transaction "18604056"
=== 2011-12-27 08:01:19,168 [l0-6] INFO  Compression - /builtinds/sc/IDACall: 0 -> 20 bytes
=== 2011-12-27 08:02:29,073 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/helpers/Log.html', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:29,136 [l0-9] INFO  Compression - /builtinds/sc/system/helpers/Log.html: 2745 -> 789 bytes
=== 2011-12-27 08:02:29,183 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_FileLoader.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:29,230 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_FileLoader.js
=== 2011-12-27 08:02:29,230 [l0-9] INFO  Compression - /builtinds/sc/system/development/ISC_FileLoader.js: 28411 -> 8377 bytes
=== 2011-12-27 08:02:29,245 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_Core.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:29,339 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_Core.js
=== 2011-12-27 08:02:29,386 [l0-9] INFO  Compression - /builtinds/sc/system/development/ISC_Core.js: 709059 -> 183460 bytes
=== 2011-12-27 08:02:29,558 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_Foundation.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:29,683 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_Foundation.js
=== 2011-12-27 08:02:29,698 [l0-9] INFO  Compression - /builtinds/sc/system/development/ISC_Foundation.js: 236788 -> 56167 bytes
=== 2011-12-27 08:02:29,745 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_Containers.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:29,823 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_Containers.js
=== 2011-12-27 08:02:29,839 [l0-9] INFO  Compression - /builtinds/sc/system/development/ISC_Containers.js: 130682 -> 30943 bytes
=== 2011-12-27 08:02:29,870 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_Grids.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:30,105 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_Grids.js
=== 2011-12-27 08:02:30,151 [l0-9] INFO  Compression - /builtinds/sc/system/development/ISC_Grids.js: 751970 -> 189124 bytes
=== 2011-12-27 08:02:30,276 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_Forms.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:30,495 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_Forms.js
=== 2011-12-27 08:02:30,526 [l0-9] INFO  Compression - /builtinds/sc/system/development/ISC_Forms.js: 659420 -> 160449 bytes
=== 2011-12-27 08:02:30,651 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_DataBinding.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:30,808 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_DataBinding.js
=== 2011-12-27 08:02:30,855 [l0-9] INFO  Compression - /builtinds/sc/system/development/ISC_DataBinding.js: 570559 -> 140543 bytes
=== 2011-12-27 08:02:30,995 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_Tools.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:31,058 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_Tools.js
=== 2011-12-27 08:02:31,058 [l0-9] INFO  Compression - /builtinds/sc/system/development/ISC_Tools.js: 87402 -> 23022 bytes
=== 2011-12-27 08:02:31,276 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/load_skin.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:31,308 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/load_skin.js
=== 2011-12-27 08:02:31,308 [l0-9] INFO  Compression - /builtinds/sc/skins/Enterprise/load_skin.js: 30235 -> 6410 bytes
=== 2011-12-27 08:02:31,386 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_DeveloperConsole.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:31,448 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_DeveloperConsole.js
=== 2011-12-27 08:02:31,448 [l0-9] INFO  Compression - /builtinds/sc/system/development/ISC_DeveloperConsole.js: 70345 -> 20319 bytes
=== 2011-12-27 08:02:31,683 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_start.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:31,683 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_stretch.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:31,683 [0-10] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_end.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:31,683 [l0-4] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_stretch.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:31,683 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_start.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:31,683 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_end.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:31,730 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_start.png
=== 2011-12-27 08:02:31,745 [l0-5] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_stretch.png
=== 2011-12-27 08:02:31,745 [0-10] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_end.png
=== 2011-12-27 08:02:31,761 [l0-2] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_end.png
=== 2011-12-27 08:02:31,761 [l0-6] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_start.png
=== 2011-12-27 08:02:31,761 [l0-4] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_stretch.png
=== 2011-12-27 08:02:32,089 [l0-4] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/cssButton/button_stretch.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,136 [l0-4] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/cssButton/button_stretch.png
=== 2011-12-27 08:02:32,386 [l0-4] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Disabled_start.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,386 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Disabled_stretch.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,386 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Disabled_end.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,401 [0-10] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,401 [l0-4] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Disabled_start.png
=== 2011-12-27 08:02:32,401 [l0-2] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Disabled_end.png
=== 2011-12-27 08:02:32,401 [l0-6] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Disabled_stretch.png
=== 2011-12-27 08:02:32,401 [0-10] DEBUG XML - Parsed XML from (in memory stream): 0ms
=== 2011-12-27 08:02:32,433 [0-10] DEBUG RPCManager - Processing 1 requests.
=== 2011-12-27 08:02:32,433 [0-10] DEBUG RPCManager - Request #1 (RPCRequest) data: {
    appID:"isc_builtin",
    className:"builtin",
    methodName:"getAvailableScriptEngines",
    arguments:[
    ],
    is_ISC_RPC_DMI:true
}
=== 2011-12-27 08:02:32,433 [0-10] INFO  IDACall - Performing 1 operation(s)
=== 2011-12-27 08:02:32,448 [0-10] DEBUG XML - Parsed XML from jar:file:/K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_builtin.app.xml: 0ms
=== 2011-12-27 08:02:32,464 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Menu/menu_button.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,480 [0-10] DEBUG XML - Parsed XML from K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war\builtinds\sc\system\schema\Application.ds.xml: 16ms
=== 2011-12-27 08:02:32,511 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/button/button_start.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,511 [l0-4] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/button/button_stretch.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,511 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/button/button_end.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,526 [l0-6] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Menu/menu_button.png
=== 2011-12-27 08:02:32,542 [l0-2] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/button/button_start.png
=== 2011-12-27 08:02:32,558 [0-10] DEBUG XML - Parsed XML from K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war\builtinds\sc\system\schema\ServerObject.ds.xml: 16ms
=== 2011-12-27 08:02:32,558 [l0-5] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/button/button_end.png
=== 2011-12-27 08:02:32,558 [l0-4] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/button/button_stretch.png
=== 2011-12-27 08:02:32,589 [0-10] DEBUG XML - Parsed XML from K:\mySchema\!Projects\Dev\eclipse\workspace\builtinds\war\builtinds\sc\system\schema\Method.ds.xml: 0ms
=== 2011-12-27 08:02:32,605 [0-10] DEBUG RPCDMI - appConfig: isc.Application.create({
    rpcBindings:[
        {
            ID:"builtin",
            className:"com.isomorphic.rpc.BuiltinRPC",
            visibleMethods:[
                {
                    name:"downloadWSDL"
                },
                {
                    name:"downloadClientContent"
                },
                {
                    name:"downloadClientExport"
                },
                {
                    name:"xmlToJS"
                },
                {
                    name:"uploadProgressCheck"
                },
                {
                    name:"saveFile"
                },
                {
                    name:"appendToFile"
                },
                {
                    name:"loadFile"
                },
                {
                    name:"deleteFile"
                },
                {
                    name:"loadSharedXML"
                },
                {
                    name:"saveSharedXML"
                },
                {
                    name:"getAvailableScriptEngines"
                },
                {
                    name:"devConsoleEvalServerScript"
                },
                {
                    name:"evalJava"
                },
                {
                    name:"getLogNames"
                },
                {
                    name:"getLogEntries"
                },
                {
                    name:"getLogThresholds"
                },
                {
                    name:"setLogThreshold"
                },
                {
                    name:"getPdfObject"
                }
            ]
        },
        {
            ID:"builtin_tools",
            className:"com.isomorphic.tools.BuiltinRPC",
            visibleMethods:[
                {
                    name:"getDataSourceFromTable"
                },
                {
                    name:"getDataSourceJSONFromTable"
                },
                {
                    name:"getDataSourceFromHibernateMapping"
                },
                {
                    name:"getDataSourceJSONFromHibernateMapping"
                },
                {
                    name:"getTables"
                },
                {
                    name:"getFieldsFromTable"
                },
                {
                    name:"getBeanFields"
                },
                {
                    name:"getHibernateBeans"
                },
                {
                    name:"getDatabaseProductNameAndVersion"
                },
                {
                    name:"getDatabaseTableTypes"
                },
                {
                    name:"setAttributes"
                },
                {
                    name:"clearAttributes"
                },
                {
                    name:"getAttributes"
                },
                {
                    name:"getAttribute"
                },
                {
                    name:"getDataSourceConfigFromJavaClass"
                },
                {
                    args:"cName",
                    language:"groovy",
                    name:"getJavaSource",
                    script:"\n                    if (!com.isomorphic.auth.DevModeAuthFilter.devModeAuthorized(request)) throw new Exception(\"Not Authorized\");                    \n                    //import org.apache.bcel.Repository;\n\n                    try {\n                        return org.apache.bcel.Repository.lookupClass(cName).toString();\n                    } catch (Throwable e) {\n                        return \"Unable to reverse engineer class \"+cName+\": \"+e.getMessage();\n                    }\n                "
                },
                {
                    name:"loadDataSource"
                },
                {
                    name:"dsFromXML"
                },
                {
                    name:"dsConfigFromXML"
                },
                {
                    name:"getDefinedDataSources"
                }
            ]
        },
        {
            ID:"builtin_adminconsole",
            className:"com.isomorphic.tools.AdminConsole",
            visibleMethods:[
                {
                    name:"getDefinedDatabases"
                },
                {
                    name:"testDB"
                },
                {
                    name:"saveDBConfig"
                },
                {
                    name:"setDefaultDB"
                },
                {
                    name:"importDataSources"
                },
                {
                    name:"discoverJNDIDatabases"
                }
            ]
        }
    ]
})

=== 2011-12-27 08:02:32,605 [l0-4] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/SectionHeader/opener_opened.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,605 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/SectionHeader/header_opened_start.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,605 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/SectionHeader/header_opened_stretch.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,620 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/SectionHeader/header_opened_end.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,636 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/SectionHeader/opener_closed.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,636 [l0-3] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/actions/help.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,651 [l0-7] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/SectionHeader/header_closed_start.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,651 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/SectionHeader/header_closed_stretch.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,651 [l0-4] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/SectionHeader/opener_opened.png
=== 2011-12-27 08:02:32,667 [l0-5] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/SectionHeader/header_opened_start.png
=== 2011-12-27 08:02:32,683 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/SectionHeader/opener_closed.png
=== 2011-12-27 08:02:32,683 [l0-7] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/SectionHeader/header_closed_start.png
=== 2011-12-27 08:02:32,683 [l0-8] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/SectionHeader/header_closed_stretch.png
=== 2011-12-27 08:02:32,683 [l0-2] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/SectionHeader/header_opened_stretch.png
=== 2011-12-27 08:02:32,683 [l0-6] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/SectionHeader/header_opened_end.png
=== 2011-12-27 08:02:32,698 [l0-3] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/actions/help.png
=== 2011-12-27 08:02:32,776 [l0-3] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/SectionHeader/header_closed_end.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:32,792 [l0-3] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/SectionHeader/header_closed_end.png
=== 2011-12-27 08:02:32,808 [0-10] DEBUG RPCDMI - rpc returned data
=== 2011-12-27 08:02:32,808 [0-10] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2011-12-27 08:02:32,808 [0-10] INFO  Compression - /builtinds/sc/IDACall: 80 -> 86 bytes
=== 2011-12-27 08:02:35,480 [0-10] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Over_start.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:35,480 [l0-3] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Over_stretch.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:35,480 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Over_end.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:35,495 [0-10] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Over_start.png
=== 2011-12-27 08:02:35,495 [l0-3] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Over_stretch.png
=== 2011-12-27 08:02:35,495 [l0-6] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Over_end.png
=== 2011-12-27 08:02:36,355 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_Over_start.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,355 [l0-3] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_Over_stretch.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,355 [0-10] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_Over_end.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,355 [l0-6] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_Over_start.png
=== 2011-12-27 08:02:36,370 [l0-3] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_Over_stretch.png
=== 2011-12-27 08:02:36,370 [0-10] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Tab/top/tab_Selected_Over_end.png
=== 2011-12-27 08:02:36,433 [0-10] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Window/window_TL.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,433 [l0-3] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Window/window_T.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,433 [l0-6] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Window/window_TR.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,448 [l0-5] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Window/window_BR.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,433 [l0-9] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Window/window_B.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,433 [l0-7] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Window/window_BL.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,433 [l0-8] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Window/window_R.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,433 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/Window/window_L.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,480 [0-10] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Window/window_TL.png
=== 2011-12-27 08:02:36,495 [l0-9] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Window/window_B.png
=== 2011-12-27 08:02:36,495 [l0-7] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Window/window_BL.png
=== 2011-12-27 08:02:36,495 [l0-8] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Window/window_R.png
=== 2011-12-27 08:02:36,511 [l0-3] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Window/window_T.png
=== 2011-12-27 08:02:36,511 [l0-5] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Window/window_BR.png
=== 2011-12-27 08:02:36,511 [l0-6] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Window/window_TR.png
=== 2011-12-27 08:02:36,511 [l0-2] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/Window/window_L.png
=== 2011-12-27 08:02:36,667 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/system/development/ISC_ServerLogViewer.js', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,698 [l0-2] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/system/development/ISC_ServerLogViewer.js
=== 2011-12-27 08:02:36,714 [l0-2] INFO  Compression - /builtinds/sc/system/development/ISC_ServerLogViewer.js: 8897 -> 3070 bytes
=== 2011-12-27 08:02:36,901 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:36,948 [l0-2] INFO  Compression - /builtinds/sc/IDACall: 81 -> 86 bytes
=== 2011-12-27 08:02:36,980 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/skins/Enterprise/images/pickers/comboBoxPicker.png', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS
=== 2011-12-27 08:02:37,011 [l0-2] INFO  Download - done streaming: K:/mySchema/!Projects/Dev/eclipse/workspace/builtinds/war/builtinds/sc/skins/Enterprise/images/pickers/comboBoxPicker.png
=== 2011-12-27 08:02:37,230 [l0-2] INFO  RequestContext - URL: '/builtinds/sc/IDACall', User-Agent: 'Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; .NET CLR 2.0.50727; .NET CLR 3.0.04506.648; .NET CLR 3.5.21022; .NET CLR 3.0.4506.2152; .NET CLR 3.5.30729)': MSIE with Accept-Encoding header, ready for compressed JS

                          Comment

                            #14
                            Originally posted by bbruyn
                            I wonder if you don't have a case-sensitivity problem. I struggled with this a bit myself early on, and it was as simple as that...
                            Your suggestion is much appreciated, but in my case, I'm sticking with 'automatic' SGWT calls. I'm not supplying these variables, but I can see that they are generated in the correct lowercase in the log.

                            Comment

                              #15
                              Right, maybe the thread I pointed you at was a little unclear for your particular situation. If I were you, and if you haven't already, I would just try one or both of the following:

                              1) change your file metadata field names so that they take the lowercase form. e.g., SIGNATURE_date_created

                              2) set autoDeriveSchema=true and remove your file metadata (SIGNATURE_*) fields form your ds xml (this is what I ended up doing).

                              Comment

                              Previous 1 2 template Next
                              Working...
                              X