=== 2010-04-21 11:41:35,053 [l0-0] DEBUG XML - Parsed XML from jar:file:/Users/macinp/Documents/GT/Clients/sigh/Dev/tracker-dev/workspace/Tracker/war/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_builtin.app.xml: 2ms
=== 2010-04-21 11:41:35,058 [l0-0] DEBUG XML - Parsed XML from /Users/macinp/Documents/GT/Clients/sigh/Dev/tracker-dev/workspace/Tracker/war/tracker/sc/system/schema/ServerObject.ds.xml: 4ms
=== 2010-04-21 11:41:35,064 [l0-0] DEBUG XML - Parsed XML from /Users/macinp/Documents/GT/Clients/sigh/Dev/tracker-dev/workspace/Tracker/war/tracker/sc/system/schema/Method.ds.xml: 2ms
=== 2010-04-21 11:41:35,067 [l0-0] DEBUG RPCDMI - appConfig: isc.Application.create({
    rpcBindings:[
        {
            ID:"builtin",
            className:"com.isomorphic.rpc.BuiltinRPC",
            visibleMethods:[
                {
                    name:"downloadWSDL"
                },
                {
                    name:"downloadClientContent"
                },
                {
                    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"
                }
            ]
        },
        {
            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"
                }
            ]
        }
    ]
})

=== 2010-04-21 11:41:35,070 [l0-0] WARN  RequestContext - rpcRequest.execute() failed: 
java.lang.Exception: Attempt to execute RPC DMI Builtin 'getAvailableScriptEngines' DENIED by server.properties configuration.  To enable this method, add it to the list of methods under RPCManager.enabledBuiltinMethods in server.properties
	at com.isomorphic.rpc.RPCDMI.execute(RPCDMI.java:180)
	at com.isomorphic.rpc.RPCDMI.execute(RPCDMI.java:66)
	at com.isomorphic.rpc.RPCRequest.execute(RPCRequest.java:229)
	at com.isomorphic.servlet.IDACall.handleRPCRequest(IDACall.java:201)
	at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:102)
	at com.isomorphic.servlet.IDACall.doPost(IDACall.java:54)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:637)
	at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
	at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:487)
	at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1097)
	at com.google.inject.servlet.DefaultFilterPipeline.dispatch(DefaultFilterPipeline.java:43)
	at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:110)
	at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1088)
	at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
	at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
	at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
	at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:729)
	at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.handler.RequestLogHandler.handle(RequestLogHandler.java:49)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.Server.handle(Server.java:324)
	at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:505)
	at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:843)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:647)
	at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
	at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:380)
	at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:395)
	at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:488)
=== 2010-04-21 11:41:35,071 [l0-0] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2010-04-21 11:42:07,274 [l0-4] INFO  RequestContext - URL: '/tracker/sc/IDACall', User-Agent: 'Mozilla/5.0 (Macintosh; U; Intel Mac OS X 10_6_3; en-us) AppleWebKit/531.22.7 (KHTML, like Gecko) Version/4.0.5 Safari/531.22.7': Safari with Accept-Encoding header
=== 2010-04-21 11:42:07,281 [l0-4] DEBUG XML - Parsed XML from (in memory stream): 6ms
=== 2010-04-21 11:42:07,282 [l0-4] DEBUG RPCManager - Processing 1 requests.
=== 2010-04-21 11:42:07,283 [l0-4] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    values:{
        Name:"Test10",
        Description:"test item",
        ElementTypeID:1,
        BoolStartState:"1"
    },
    operationConfig:{
        dataSource:"Element",
        operationType:"add"
    },
    componentId:"isc_OID_50",
    appID:"builtinApplication",
    operation:"Element_add",
    oldValues:{
        Name:null,
        Description:null
    },
    criteria:{
    }
}
=== 2010-04-21 11:42:07,285 [l0-4] DEBUG XML - Parsed XML from (in memory stream): 2ms
=== 2010-04-21 11:42:07,287 [l0-4] DEBUG AppBase - [builtinApplication.Element_add] No userTypes defined, allowing anyone access to all operations for this application
=== 2010-04-21 11:42:07,287 [l0-4] DEBUG AppBase - [builtinApplication.Element_add] No public zero-argument method named '_Element_add' found, performing generic datasource operation
=== 2010-04-21 11:42:07,288 [l0-4] INFO  SQLDataSource - [builtinApplication.Element_add] Performing add operation with
	criteria: {}	values: {Name:"Test10",Description:"test item",ElementTypeID:1,BoolStartState:"1"}
=== 2010-04-21 11:42:07,290 [l0-4] DEBUG SQLValuesClause - [builtinApplication.Element_add] Sequences: {ElementID=__default}
=== 2010-04-21 11:42:07,292 [l0-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Element_add] DriverManager fetching connection for SQLServer via jdbc url jdbc:jtds:sqlserver://127.0.0.1:1433;DatabaseName=sigh_tracker;User=sa;Password=%%DB_PASSWORD%%
=== 2010-04-21 11:42:07,292 [l0-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Element_add] Passing JDBC URL only to getConnection
=== 2010-04-21 11:42:07,300 [l0-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Element_add] Returning pooled Connection
=== 2010-04-21 11:42:07,301 [l0-4] INFO  SQLDriver - [builtinApplication.Element_add] Executing SQL update on 'SQLServer': INSERT INTO Element (Name, Description, ElementTypeID, BoolStartState) VALUES ('Test10', 'test item', 1, '1')
=== 2010-04-21 11:42:07,316 [l0-4] DEBUG SQLDataSource - [builtinApplication.Element_add] add operation affected 1 rows
=== 2010-04-21 11:42:07,318 [l0-4] INFO  SQLDriver - [builtinApplication.Element_add] Executing SQL query on 'SQLServer': SELECT @@IDENTITY
=== 2010-04-21 11:42:07,319 [l0-4] INFO  SQLDataSource - [builtinApplication.Element_add] primaryKeys: {ElementID=62}
=== 2010-04-21 11:42:07,319 [l0-4] INFO  SQLDriver - [builtinApplication.Element_add] Executing SQL query on 'SQLServer': SELECT Element.Name, Element.SelectOptions, Element.Description, Element.MaxLength, Element.ElementID, Element.ElementTypeID, Element.MinIntValue, Element.MaxIntValue, Element.MaxFloatValue, Element.BoolStartState, Element.MinFloatValue FROM Element WHERE (Element.ElementID=62)
=== 2010-04-21 11:42:07,321 [l0-4] WARN  SQLDataSource - [builtinApplication.Element_add] SQLServer getLastRow(): empty result set fetching last row
=== 2010-04-21 11:42:07,321 [l0-4] INFO  DSResponse - [builtinApplication.Element_add] DSResponse: List with 0 items
=== 2010-04-21 11:42:07,323 [l0-4] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2010-04-21 11:42:07,324 [l0-4] DEBUG RPCManager - non-DMI response, dropExtraFields: false