Initializing App Engine server Nov 20, 2012 7:12:42 PM com.google.apphosting.utils.jetty.JettyLogger info INFO: Logging to JettyLogger(null) via com.google.apphosting.utils.jetty.JettyLogger Nov 20, 2012 7:12:42 PM com.google.apphosting.utils.config.AppEngineWebXmlReader readAppEngineWebXml INFO: Successfully processed D:\Development_projects\eclipse_projects\teste1\war\WEB-INF/appengine-web.xml Nov 20, 2012 7:12:42 PM com.google.apphosting.utils.config.AbstractConfigXmlReader readConfigXml INFO: Successfully processed D:\Development_projects\eclipse_projects\teste1\war\WEB-INF/web.xml ISC: Configuring log4j from: file:/D:/Development_projects/eclipse_projects/teste1/war/WEB-INF/classes/log4j.isc.config.xml === 2012-11-20 19:12:45,496 [main] INFO ISCInit - Isomorphic SmartClient Framework - Initializing === 2012-11-20 19:12:45,526 [main] INFO ConfigLoader - Attempting to load framework.properties from CLASSPATH === 2012-11-20 19:12:46,163 [main] INFO ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/D:/Development_projects/eclipse_projects/teste1/war/WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties === 2012-11-20 19:12:46,163 [main] INFO ConfigLoader - Attempting to load project.properties from CLASSPATH === 2012-11-20 19:12:46,164 [main] INFO ConfigLoader - Unable to locate project.properties in CLASSPATH === 2012-11-20 19:12:46,195 [main] INFO ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/D:/Development_projects/eclipse_projects/teste1/war/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties === 2012-11-20 19:12:46,196 [main] INFO ConfigLoader - Attempting to load server.properties from CLASSPATH === 2012-11-20 19:12:46,209 [main] INFO ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/D:/Development_projects/eclipse_projects/teste1/war/WEB-INF/classes/server.properties === 2012-11-20 19:12:46,231 [main] INFO Logger - Logging system started. === 2012-11-20 19:12:46,236 [main] INFO ISCInit - Isomorphic SmartClient Framework (v8.2p_2012-10-24/EVAL Deployment 2012-10-24) - Initialization Complete === 2012-11-20 19:12:46,242 [main] INFO ISCInit - Auto-detected webRoot - using: D:\Development_projects\eclipse_projects\teste1\war Nov 20, 2012 5:12:46 PM com.google.appengine.tools.development.DevAppServerImpl start INFO: The server is running at http://localhost:8888/ Nov 20, 2012 5:12:46 PM com.google.appengine.tools.development.DevAppServerImpl start INFO: The admin console is running at http://localhost:8888/_ah/admin === 2012-11-20 19:12:51,775 [20-4] INFO RequestContext - URL: '/teste1/sc/DataSourceLoader', User-Agent: 'Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; WOW64; Trident/5.0)': MSIE WITHOUT Accept-Encoding header === 2012-11-20 19:12:51,934 [20-4] INFO PoolManager - SmartClient pooling disabled for 'prefeituras' objects === 2012-11-20 19:12:52,068 [20-4] DEBUG XML - Parsed XML from D:\Development_projects\eclipse_projects\teste1\war\teste1\sc\system\schema\builtinTypes.xml: 32ms === 2012-11-20 19:12:52,221 [20-4] DEBUG XML - Parsed XML from D:\Development_projects\eclipse_projects\teste1\war\ds\prefeituras.ds.xml: 2ms === 2012-11-20 19:12:52,226 [20-4] DEBUG XML - Parsed XML from D:\Development_projects\eclipse_projects\teste1\war\teste1\sc\system\schema\DataSource.ds.xml: 4ms === 2012-11-20 19:12:52,354 [20-4] DEBUG XML - Parsed XML from D:\Development_projects\eclipse_projects\teste1\war\teste1\sc\system\schema\DataSourceField.ds.xml: 8ms === 2012-11-20 19:13:14,964 [20-2] INFO RequestContext - URL: '/teste1/sc/IDACall', User-Agent: 'Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; WOW64; Trident/5.0)': MSIE WITHOUT Accept-Encoding header === 2012-11-20 19:13:14,966 [20-5] INFO RequestContext - URL: '/teste1/sc/IDACall', User-Agent: 'Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; WOW64; Trident/5.0)': MSIE WITHOUT Accept-Encoding header === 2012-11-20 19:13:15,010 [20-2] DEBUG XML - Parsed XML from (in memory stream): 8ms === 2012-11-20 19:13:15,010 [20-5] DEBUG XML - Parsed XML from (in memory stream): 8ms === 2012-11-20 19:13:15,020 [20-5] DEBUG XML - Parsed XML from D:\Development_projects\eclipse_projects\teste1\war\teste1\sc\system\schema\List.ds.xml: 5ms === 2012-11-20 19:13:15,023 [20-2] DEBUG XML - Parsed XML from D:\Development_projects\eclipse_projects\teste1\war\teste1\sc\system\schema\List.ds.xml: 6ms === 2012-11-20 19:13:15,034 [20-5] DEBUG RPCManager - Processing 1 requests. === 2012-11-20 19:13:15,037 [20-2] DEBUG RPCManager - Processing 1 requests. === 2012-11-20 19:13:15,054 [20-5] DEBUG RPCManager - Request #1 (DSRequest) payload: { criteria:{ }, operationConfig:{ dataSource:"prefeituras", operationType:"fetch", textMatchStyle:"exact" }, startRow:0, endRow:75, componentId:"isc_ListGrid_0", appID:"builtinApplication", operation:"prefeituras_fetch", oldValues:{ } } === 2012-11-20 19:13:15,055 [20-2] DEBUG RPCManager - Request #1 (DSRequest) payload: { criteria:{ }, operationConfig:{ dataSource:"prefeituras", operationType:"fetch", textMatchStyle:"substring" }, startRow:0, endRow:75, componentId:"isc_ListGrid_0", appID:"builtinApplication", operation:"prefeituras_fetch", oldValues:{ } } === 2012-11-20 19:13:15,059 [20-5] INFO IDACall - Performing 1 operation(s) === 2012-11-20 19:13:15,059 [20-2] INFO IDACall - Performing 1 operation(s) === 2012-11-20 19:13:15,076 [20-2] DEBUG AppBase - [builtinApplication.prefeituras_fetch] No userTypes defined, allowing anyone access to all operations for this application === 2012-11-20 19:13:15,076 [20-5] DEBUG AppBase - [builtinApplication.prefeituras_fetch] No userTypes defined, allowing anyone access to all operations for this application === 2012-11-20 19:13:15,076 [20-2] DEBUG AppBase - [builtinApplication.prefeituras_fetch] No public zero-argument method named '_prefeituras_fetch' found, performing generic datasource operation === 2012-11-20 19:13:15,076 [20-5] DEBUG AppBase - [builtinApplication.prefeituras_fetch] No public zero-argument method named '_prefeituras_fetch' found, performing generic datasource operation === 2012-11-20 19:13:15,077 [20-2] INFO SQLDataSource - [builtinApplication.prefeituras_fetch] Performing fetch operation with criteria: {} values: {} === 2012-11-20 19:13:15,077 [20-5] INFO SQLDataSource - [builtinApplication.prefeituras_fetch] Performing fetch operation with criteria: {} values: {} === 2012-11-20 19:13:15,092 [20-2] INFO SQLWhereClause - [builtinApplication.prefeituras_fetch] empty condition === 2012-11-20 19:13:15,092 [20-5] INFO SQLWhereClause - [builtinApplication.prefeituras_fetch] empty condition === 2012-11-20 19:13:15,095 [20-5] INFO SQLDataSource - [builtinApplication.prefeituras_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause === 2012-11-20 19:13:15,095 [20-2] INFO SQLDataSource - [builtinApplication.prefeituras_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause log4j:WARN No appenders could be found for logger (org.apache.Velocity). log4j:WARN Please initialize the log4j system properly. === 2012-11-20 19:13:15,363 [20-5] DEBUG SQLDataSource - [builtinApplication.prefeituras_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause === 2012-11-20 19:13:15,364 [20-5] DEBUG SQLDataSource - [builtinApplication.prefeituras_fetch] Eval'd row count query: SELECT COUNT(*) FROM prefeituras WHERE ('1'='1') === 2012-11-20 19:13:15,366 [20-2] DEBUG SQLDataSource - [builtinApplication.prefeituras_fetch] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause === 2012-11-20 19:13:15,367 [20-2] DEBUG SQLDataSource - [builtinApplication.prefeituras_fetch] Eval'd row count query: SELECT COUNT(*) FROM prefeituras WHERE ('1'='1') === 2012-11-20 19:13:15,488 [20-5] DEBUG PoolableSQLConnectionFactory - [builtinApplication.prefeituras_fetch] Initializing SQL config for 'Mysql' from system config - using DataSource: com.mysql.jdbc.jdbc2.optional.MysqlDataSource === 2012-11-20 19:13:15,554 [20-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.prefeituras_fetch] Initializing SQL config for 'Mysql' from system config - using DataSource: com.mysql.jdbc.jdbc2.optional.MysqlDataSource === 2012-11-20 19:13:15,571 [20-5] ERROR IDACall - Top-level servlet error: java.lang.ExceptionInInitializerError at com.mysql.jdbc.jdbc2.optional.MysqlDataSource.(MysqlDataSource.java:58) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Class.java:264) at com.isomorphic.base.Reflection.classForName(Reflection.java:137) at com.isomorphic.base.Reflection.newInstance(Reflection.java:207) at com.isomorphic.sql.PoolableSQLConnectionFactory.makeUnpooledObject(PoolableSQLConnectionFactory.java:159) at com.isomorphic.sql.PoolableSQLConnectionFactory.makeObject(PoolableSQLConnectionFactory.java:340) at org.apache.commons.pool.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:974) at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:74) at com.isomorphic.sql.SQLConnectionManager.getConnection(SQLConnectionManager.java:156) at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:310) at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:287) at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:280) at com.isomorphic.sql.SQLDriver.getScalarResult(SQLDriver.java:421) at com.isomorphic.sql.SQLDriver.executeScalar(SQLDriver.java:635) at com.isomorphic.sql.SQLDataSource.executeWindowedSelect(SQLDataSource.java:1628) at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1317) at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:293) at com.isomorphic.sql.SQLDataSource.executeFetch(SQLDataSource.java:237) at com.isomorphic.datasource.DataSource.execute(DataSource.java:1289) at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:725) 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:1954) 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.doPost(IDACall.java:73) 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:511) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1166) at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:246) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.api.socket.dev.DevSocketFilter.doFilter(DevSocketFilter.java:74) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.tools.development.ResponseRewriterFilter.doFilter(ResponseRewriterFilter.java:123) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.tools.development.HeaderVerificationFilter.doFilter(HeaderVerificationFilter.java:34) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.api.blobstore.dev.ServeBlobFilter.doFilter(ServeBlobFilter.java:61) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.apphosting.utils.servlet.TransactionCleanupFilter.doFilter(TransactionCleanupFilter.java:43) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.tools.development.StaticFileFilter.doFilter(StaticFileFilter.java:125) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.tools.development.BackendServersFilter.doFilter(BackendServersFilter.java:97) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388) at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216) at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182) at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765) at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:418) at com.google.appengine.tools.development.DevAppEngineWebAppContext.handle(DevAppEngineWebAppContext.java:94) at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) at com.google.appengine.tools.development.JettyContainerService$ApiProxyHandler.handle(JettyContainerService.java:383) at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) at org.mortbay.jetty.Server.handle(Server.java:326) at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542) at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:938) at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:755) at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212) at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404) at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:409) at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582) Caused by: java.security.AccessControlException: access denied ("java.lang.RuntimePermission" "modifyThreadGroup") at java.security.AccessControlContext.checkPermission(AccessControlContext.java:366) at java.security.AccessController.checkPermission(AccessController.java:560) at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) at com.google.appengine.tools.development.DevAppServerFactory$CustomSecurityManager.checkPermission(DevAppServerFactory.java:289) at com.google.appengine.tools.development.DevAppServerFactory$CustomSecurityManager.checkAccess(DevAppServerFactory.java:314) at java.lang.ThreadGroup.checkAccess(ThreadGroup.java:315) at java.lang.Thread.init(Thread.java:376) at java.lang.Thread.(Thread.java:485) at com.mysql.jdbc.NonRegisteringDriver$1.(NonRegisteringDriver.java:90) at com.mysql.jdbc.NonRegisteringDriver.(NonRegisteringDriver.java:89) ... 66 more === 2012-11-20 19:13:15,571 [20-2] ERROR IDACall - Top-level servlet error: java.lang.NoClassDefFoundError: Could not initialize class com.mysql.jdbc.jdbc2.optional.MysqlDataSource at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Class.java:264) at com.isomorphic.base.Reflection.classForName(Reflection.java:137) at com.isomorphic.base.Reflection.newInstance(Reflection.java:207) at com.isomorphic.sql.PoolableSQLConnectionFactory.makeUnpooledObject(PoolableSQLConnectionFactory.java:159) at com.isomorphic.sql.PoolableSQLConnectionFactory.makeObject(PoolableSQLConnectionFactory.java:340) at org.apache.commons.pool.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:974) at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:74) at com.isomorphic.sql.SQLConnectionManager.getConnection(SQLConnectionManager.java:156) at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:310) at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:287) at com.isomorphic.sql.SQLDriver.getTransformedResults(SQLDriver.java:280) at com.isomorphic.sql.SQLDriver.getScalarResult(SQLDriver.java:421) at com.isomorphic.sql.SQLDriver.executeScalar(SQLDriver.java:635) at com.isomorphic.sql.SQLDataSource.executeWindowedSelect(SQLDataSource.java:1628) at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1317) at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:293) at com.isomorphic.sql.SQLDataSource.executeFetch(SQLDataSource.java:237) at com.isomorphic.datasource.DataSource.execute(DataSource.java:1289) at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:725) 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:1954) 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.doPost(IDACall.java:73) 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:511) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1166) at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:246) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.api.socket.dev.DevSocketFilter.doFilter(DevSocketFilter.java:74) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.tools.development.ResponseRewriterFilter.doFilter(ResponseRewriterFilter.java:123) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.tools.development.HeaderVerificationFilter.doFilter(HeaderVerificationFilter.java:34) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.api.blobstore.dev.ServeBlobFilter.doFilter(ServeBlobFilter.java:61) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.apphosting.utils.servlet.TransactionCleanupFilter.doFilter(TransactionCleanupFilter.java:43) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.tools.development.StaticFileFilter.doFilter(StaticFileFilter.java:125) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at com.google.appengine.tools.development.BackendServersFilter.doFilter(BackendServersFilter.java:97) at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157) at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388) at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216) at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182) at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765) at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:418) at com.google.appengine.tools.development.DevAppEngineWebAppContext.handle(DevAppEngineWebAppContext.java:94) at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) at com.google.appengine.tools.development.JettyContainerService$ApiProxyHandler.handle(JettyContainerService.java:383) at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) at org.mortbay.jetty.Server.handle(Server.java:326) at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542) at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:938) at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:755) at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212) at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404) at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:409) at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)