=== 2012-03-23 10:59:57,062 [sor3] DEBUG RPCManager - Processing 1 requests. === 2012-03-23 10:59:57,062 [sor3] DEBUG RPCManager - Request #1 (RPCRequest) data: { appID:"isc_builtin", className:"builtin", methodName:"getAvailableScriptEngines", arguments:[ ], is_ISC_RPC_DMI:true } === 2012-03-23 10:59:57,093 [sor2] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/Menu/menu_button.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:57,093 [sor3] INFO IDACall - Performing 1 operation(s) === 2012-03-23 10:59:57,187 [sor4] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/cssButton/button_stretch.png === 2012-03-23 10:59:57,328 [sor8] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/button/button_stretch.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:57,343 [sor1] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/button/button_end.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:57,359 [sor7] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/button/button_start.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:57,421 [sor4] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/SectionHeader/opener_closed.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:57,453 [sor3] DEBUG XML - Parsed XML from jar:file:/D:/My%20Websites/PP_Client/WEB-INF/embeddedTomcat/../embeddedTomcat/webapps/../../../WEB-INF/lib/isomorphic_core_rpc.jar!/isc_builtin.app.xml: 125ms === 2012-03-23 10:59:57,828 [sor2] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/Menu/menu_button.png === 2012-03-23 10:59:57,859 [sor3] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\isomorphic\system\schema\ServerObject.ds.xml: 31ms === 2012-03-23 10:59:57,875 [sor8] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/button/button_stretch.png === 2012-03-23 10:59:57,890 [sor7] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/button/button_start.png === 2012-03-23 10:59:57,890 [sor2] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/actions/help.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:57,953 [sor1] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/button/button_end.png === 2012-03-23 10:59:57,953 [sor4] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/SectionHeader/opener_closed.png === 2012-03-23 10:59:58,000 [sor3] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\isomorphic\system\schema\Method.ds.xml: 16ms === 2012-03-23 10:59:58,000 [sor8] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/SectionHeader/header_closed_start.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:58,015 [sor3] 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" } ] } ] }) === 2012-03-23 10:59:58,062 [sor7] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/SectionHeader/header_closed_stretch.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:58,093 [sor1] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/SectionHeader/header_closed_end.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:58,109 [sor8] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/SectionHeader/header_closed_start.png === 2012-03-23 10:59:58,140 [sor1] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/SectionHeader/header_closed_end.png === 2012-03-23 10:59:58,156 [sor2] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/actions/help.png === 2012-03-23 10:59:58,156 [sor7] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/SectionHeader/header_closed_stretch.png === 2012-03-23 10:59:58,312 [sor3] DEBUG RPCDMI - rpc returned data === 2012-03-23 10:59:58,312 [sor3] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8 === 2012-03-23 10:59:59,875 [sor4] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/Tab/top/tab_Over_start.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:59,890 [sor8] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/Tab/top/tab_Over_stretch.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:59,937 [sor1] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/Tab/top/tab_Over_end.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 10:59:59,968 [sor2] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/Tab/top/tab_Selected_Over_start.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 11:00:00,062 [sor7] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/Tab/top/tab_Selected_Over_stretch.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 11:00:00,109 [sor3] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/Tab/top/tab_Selected_Over_end.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 11:00:00,234 [sor6] INFO RequestContext - URL: '/isomorphic/system/development/ISC_ServerLogViewer.js', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 11:00:00,265 [sor6] INFO Download - Using gzipped form of request file: D:/My Websites/PP_Client/isomorphic/system/development/ISC_ServerLogViewer.js === 2012-03-23 11:00:00,296 [sor4] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/Tab/top/tab_Over_start.png === 2012-03-23 11:00:00,312 [sor7] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/Tab/top/tab_Selected_Over_stretch.png === 2012-03-23 11:00:00,312 [sor2] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/Tab/top/tab_Selected_Over_start.png === 2012-03-23 11:00:00,312 [sor6] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/system/development/ISC_ServerLogViewer.js === 2012-03-23 11:00:00,343 [sor1] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/Tab/top/tab_Over_end.png === 2012-03-23 11:00:00,343 [sor3] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/Tab/top/tab_Selected_Over_end.png === 2012-03-23 11:00:00,343 [sor8] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/Tab/top/tab_Over_stretch.png === 2012-03-23 11:00:00,484 [sor7] INFO RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 11:00:00,687 [sor2] INFO RequestContext - URL: '/isomorphic/skins/Enterprise/images/pickers/comboBoxPicker.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 11:00:00,937 [sor3] INFO RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 11:00:01,062 [sor2] INFO Download - done streaming: D:/My Websites/PP_Client/isomorphic/skins/Enterprise/images/pickers/comboBoxPicker.png === 2012-03-23 11:03:10,078 [sor5] INFO RequestContext - URL: '/tools/visualBuilder/workspace/AutoMakerApp2.jsp', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header === 2012-03-23 11:03:10,343 [sor5] DEBUG LoadISCTag - isomorphicURI not specified in tag, defaulting to: ../../../isomorphic/ === 2012-03-23 11:03:10,359 [sor5] DEBUG XML - Parsed XML from (in memory stream): 0ms === 2012-03-23 11:03:10,375 [sor5] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\shared\ds\AutoMaker.ds.xml: 0ms === 2012-03-23 11:03:10,437 [sor5] INFO SQLDataSource - Deriving dataSource AutoMaker from table: AutoMaker === 2012-03-23 11:03:10,562 [sor5] DEBUG PoolableSQLConnectionFactory - Initializing SQL config for 'PlanetProjectDB' from system config - using DriverManager: org.hsqldb.jdbcDriver === 2012-03-23 11:03:10,562 [sor5] DEBUG PoolableSQLConnectionFactory - org.hsqldb.jdbcDriver lookup successful === 2012-03-23 11:03:10,562 [sor5] DEBUG PoolableSQLConnectionFactory - DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:hsqldb:file:D:\My Websites\PP_client/WEB-INF/db/hsqldb/PlanetProjectDB === 2012-03-23 11:03:10,562 [sor5] DEBUG PoolableSQLConnectionFactory - Passing JDBC URL only to getConnection === 2012-03-23 11:03:11,093 [sor5] DEBUG PoolableSQLConnectionFactory - Returning pooled Connection === 2012-03-23 11:03:11,109 [sor5] INFO SQLDSGenerator - Fetching column metadata for table: AutoMaker === 2012-03-23 11:03:11,109 [sor5] INFO SQLDSGenerator - =============Using catalog: null === 2012-03-23 11:03:11,421 [sor5] INFO SQLDSGenerator - Fetching column metadata for AutoMaker complete === 2012-03-23 11:03:11,421 [sor5] INFO SQLDSGenerator - Failed to get columns for AutoMaker, trying AUTOMAKER === 2012-03-23 11:03:11,437 [sor5] INFO SQLDSGenerator - Fetching column metadata for AUTOMAKER complete === 2012-03-23 11:03:11,453 [sor5] WARN SQLDataSource - ds:isc.DataSource.create({ schema:"PUBLIC", dbName:"PlanetProjectDB", ID:"AutoMaker_inheritsFrom", dataSourceVersion:"1", serverType:"sql", generatedBy:"SC_SNAPSHOT-2012-03-05_v8.2p/Pro Deployment 2012-03-05", fields:[ { name:"AUTOMAKERID", type:"integer" }, { name:"AUTOMAKERNAME", length:64, type:"text" } ] }) === 2012-03-23 11:03:11,687 [sor5] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\isomorphic\system\schema\ListGridField.ds.xml: 172ms === 2012-03-23 11:03:11,734 [sor5] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\isomorphic\system\schema\Action.ds.xml: 0ms === 2012-03-23 11:03:11,750 [sor5] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\shared\ds\Vehicle.ds.xml: 0ms === 2012-03-23 11:03:11,750 [sor5] INFO SQLDataSource - Deriving dataSource Vehicle from table: Vehicle === 2012-03-23 11:03:11,750 [sor5] DEBUG PoolableSQLConnectionFactory - DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:hsqldb:file:D:\My Websites\PP_client/WEB-INF/db/hsqldb/PlanetProjectDB === 2012-03-23 11:03:11,750 [sor5] DEBUG PoolableSQLConnectionFactory - Passing JDBC URL only to getConnection === 2012-03-23 11:03:11,765 [sor5] DEBUG PoolableSQLConnectionFactory - Returning pooled Connection === 2012-03-23 11:03:11,765 [sor5] INFO SQLDSGenerator - Fetching column metadata for table: Vehicle === 2012-03-23 11:03:11,765 [sor5] INFO SQLDSGenerator - =============Using catalog: null === 2012-03-23 11:03:11,796 [sor5] INFO SQLDSGenerator - Fetching column metadata for Vehicle complete === 2012-03-23 11:03:11,796 [sor5] INFO SQLDSGenerator - Failed to get columns for Vehicle, trying VEHICLE === 2012-03-23 11:03:11,796 [sor5] INFO SQLDSGenerator - Fetching column metadata for VEHICLE complete === 2012-03-23 11:03:11,812 [sor5] WARN SQLDataSource - ds:isc.DataSource.create({ schema:"PUBLIC", dbName:"PlanetProjectDB", ID:"Vehicle_inheritsFrom", dataSourceVersion:"1", serverType:"sql", generatedBy:"SC_SNAPSHOT-2012-03-05_v8.2p/Pro Deployment 2012-03-05", fields:[ { name:"VEHICLEID", type:"integer" }, { name:"VEHICLENAME", length:64, type:"text" }, { name:"AUTOMAKERID", type:"integer" }, { name:"COLORID", type:"integer" } ] }) === 2012-03-23 11:03:11,843 [sor5] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\isomorphic\system\schema\SectionStack.ds.xml: 0ms === 2012-03-23 11:03:11,859 [sor5] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\isomorphic\system\schema\VLayout.ds.xml: 0ms === 2012-03-23 11:03:11,906 [sor5] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\isomorphic\system\schema\Layout.ds.xml: 0ms === 2012-03-23 11:03:12,000 [sor5] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\isomorphic\system\schema\SectionStackSection.ds.xml: 0ms === 2012-03-23 11:03:12,031 [sor5] DEBUG XML - Parsed XML from D:\My Websites\PP_Client\isomorphic\system\schema\DataView.ds.xml: 0ms === 2012-03-23 11:03:12,062 [sor5] INFO JSSyntaxScannerFilter - /tools/visualBuilder/workspace/AutoMakerApp2.jsp: scanning