Initializing App Engine server Mar 19, 2013 10:33:29 AM com.google.apphosting.utils.config.EarHelper logNotAnEar INFO: Directory 'C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war' is not an EAR directory. File C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\appengine-application.xmlappengine-application.xml not detected. Mar 19, 2013 10:33:29 AM com.google.apphosting.utils.config.EarHelper logNotAnEar INFO: Directory 'C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war' is not an EAR directory. File C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\appengine-application.xmlappengine-application.xml not detected. Mar 19, 2013 10:33:29 AM com.google.apphosting.utils.config.EarHelper logNotAnEar INFO: Directory 'C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war' is not an EAR directory. File C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\appengine-application.xmlappengine-application.xml not detected. Mar 19, 2013 10:33:29 AM com.google.apphosting.utils.config.EarHelper logNotAnEar INFO: Directory 'C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war' is not an EAR directory. File C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\appengine-application.xmlappengine-application.xml not detected. Mar 19, 2013 10:33:29 AM com.google.apphosting.utils.config.AppEngineWebXmlReader readAppEngineWebXml INFO: Successfully processed C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\WEB-INF/appengine-web.xml Mar 19, 2013 10:33:29 AM com.google.apphosting.utils.config.AbstractConfigXmlReader readConfigXml INFO: Successfully processed C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\WEB-INF/web.xml Mar 19, 2013 10:33:29 AM com.google.appengine.tools.development.SystemPropertiesManager setSystemProperties INFO: Overwriting system property key 'java.util.logging.config.file', value 'C:\Conejo\desenvolvimento\eclipse\plugins\com.google.appengine.eclipse.sdkbundle_1.7.5\appengine-java-sdk-1.7.5\config\sdk\logging.properties' with value 'WEB-INF/logging.properties' from 'C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\WEB-INF\appengine-web.xml' Mar 19, 2013 10:33:30 AM com.google.apphosting.utils.jetty.JettyLogger info INFO: Logging to JettyLogger(null) via com.google.apphosting.utils.jetty.JettyLogger Mar 19, 2013 1:33:34 PM com.google.apphosting.utils.jetty.JettyLogger info INFO: jetty-6.1.x ISC: Configuring log4j from: file:/C:/Conejo/desenvolvimento/projetos_eclipse/audespSource/war/WEB-INF/classes/log4j.isc.config.xml === 2013-03-19 13:33:35,991 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework initialization called from com.isomorphic.base.Init === 2013-03-19 13:33:35,994 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework - Initializing === 2013-03-19 13:33:36,016 [main] INFO ConfigLoader - Attempting to load framework.properties from CLASSPATH === 2013-03-19 13:33:36,436 [main] INFO ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/C:/Conejo/desenvolvimento/projetos_eclipse/audespSource/war/WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties === 2013-03-19 13:33:36,436 [main] INFO ConfigLoader - Attempting to load project.properties from CLASSPATH === 2013-03-19 13:33:36,437 [main] INFO ConfigLoader - Unable to locate project.properties in CLASSPATH === 2013-03-19 13:33:36,467 [main] INFO ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/C:/Conejo/desenvolvimento/projetos_eclipse/audespSource/war/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties === 2013-03-19 13:33:36,467 [main] INFO ConfigLoader - Attempting to load server.properties from CLASSPATH === 2013-03-19 13:33:36,480 [main] INFO ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/C:/Conejo/desenvolvimento/projetos_eclipse/audespSource/war/WEB-INF/classes/server.properties === 2013-03-19 13:33:36,540 [main] INFO Logger - Logging system started. === 2013-03-19 13:33:36,543 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework (v8.3p_2012-12-27/Pro Deployment 2012-12-27) - Initialization Complete === 2013-03-19 13:33:36,551 [main] INFO ISCInit - Auto-detected webRoot - using: C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war Mar 19, 2013 1:33:36 PM com.google.apphosting.utils.jetty.JettyLogger info INFO: Started SelectChannelConnector@0.0.0.0:8888 Mar 19, 2013 1:33:36 PM com.google.appengine.tools.development.AbstractServer startup INFO: Server default is running at http://localhost:8888/ Mar 19, 2013 1:33:36 PM com.google.appengine.tools.development.AbstractServer startup INFO: The admin console is running at http://localhost:8888/_ah/admin Mar 19, 2013 10:33:36 AM com.google.appengine.tools.development.DevAppServerImpl start INFO: Dev App Server is now running === 2013-03-19 21:57:44,955 [67-6] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework initialization called from com.isomorphic.base.Base === 2013-03-19 21:57:44,956 [67-6] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework is already initialized === 2013-03-19 21:57:45,146 [67-6] INFO PoolManager - SmartClient pooling disabled for 'prefeituras' objects === 2013-03-19 21:57:45,329 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\audespsource\sc\system\schema\builtinTypes.xml: 33ms === 2013-03-19 21:57:45,564 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\prefeituras.ds.xml: 5ms === 2013-03-19 21:57:45,575 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\audespsource\sc\system\schema\DataSource.ds.xml: 8ms === 2013-03-19 21:57:45,820 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\audespsource\sc\system\schema\DataSourceField.ds.xml: 9ms === 2013-03-19 21:57:46,039 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\orgaos.ds.xml: 3ms === 2013-03-19 21:57:46,072 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\classificacaoReceita_DS.ds.xml: 3ms === 2013-03-19 21:57:46,098 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\codigoAplicacao_DS.ds.xml: 3ms === 2013-03-19 21:57:46,128 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\codigoTipoReceita_DS.ds.xml: 4ms === 2013-03-19 21:57:46,155 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\codigoFonteRecurso_DS.ds.xml: 2ms === 2013-03-19 21:57:46,186 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\detalheReceita_DS.ds.xml: 6ms === 2013-03-19 21:57:46,292 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\funcaoGoverno_DS.ds.xml: 5ms === 2013-03-19 21:57:46,317 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\fornecedorCredor_DS.ds.xml: 4ms === 2013-03-19 21:57:46,342 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\subFuncaoGoverno_DS.ds.xml: 4ms === 2013-03-19 21:57:46,366 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\classificacaoDespesa_DS.ds.xml: 3ms === 2013-03-19 21:57:46,392 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\detalheDespesa_DS.ds.xml: 5ms === 2013-03-19 21:57:46,448 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\modalidadeLicitatoria_DS.ds.xml: 4ms === 2013-03-19 21:57:46,474 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\logXMLImport_DS.ds.xml: 4ms === 2013-03-19 21:57:46,513 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\balancoAnual_DS.ds.xml: 4ms === 2013-03-19 21:57:46,536 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\audespsource\sc\system\schema\ValueMap.ds.xml: 3ms === 2013-03-19 21:57:46,611 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\treeDetalheReceita_DS.ds.xml: 5ms === 2013-03-19 21:57:46,644 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\audespsource\sc\system\schema\ServerObject.ds.xml: 3ms === 2013-03-19 21:57:46,679 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\ds\treeDetalheDespesa_DS.ds.xml: 4ms ########## BalanceGraphPlanned(String dS, String name, String s ########## BalanceGraphPlanned(String dS, String name, String s === 2013-03-19 21:57:48,071 [67-2] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888 === 2013-03-19 21:57:48,071 [67-2] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:19.0) Gecko/20100101 Firefox/19.0 === 2013-03-19 21:57:48,071 [67-2] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 === 2013-03-19 21:57:48,071 [67-2] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5 === 2013-03-19 21:57:48,072 [67-2] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:8888/AudespSource.html?gwt.codesvr=127.0.0.1:9997 === 2013-03-19 21:57:48,072 [67-2] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8 === 2013-03-19 21:57:48,072 [67-2] DEBUG IDACall - Header Name:Value pair: Content-Length:784 === 2013-03-19 21:57:48,072 [67-2] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache === 2013-03-19 21:57:48,073 [67-2] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache === 2013-03-19 21:57:48,073 [67-2] DEBUG IDACall - session exists: null === 2013-03-19 21:57:48,073 [67-2] DEBUG IDACall - remote user: null === 2013-03-19 21:57:48,075 [67-6] DEBUG IDACall - Header Name:Value pair: Host:127.0.0.1:8888 === 2013-03-19 21:57:48,079 [67-6] DEBUG IDACall - Header Name:Value pair: User-Agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:19.0) Gecko/20100101 Firefox/19.0 === 2013-03-19 21:57:48,079 [67-6] DEBUG IDACall - Header Name:Value pair: Accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 === 2013-03-19 21:57:48,080 [67-6] DEBUG IDACall - Header Name:Value pair: Accept-Language:en-US,en;q=0.5 === 2013-03-19 21:57:48,080 [67-6] DEBUG IDACall - Header Name:Value pair: Referer:http://127.0.0.1:8888/AudespSource.html?gwt.codesvr=127.0.0.1:9997 === 2013-03-19 21:57:48,080 [67-6] DEBUG IDACall - Header Name:Value pair: Content-Type:application/x-www-form-urlencoded; charset=UTF-8 === 2013-03-19 21:57:48,080 [67-6] DEBUG IDACall - Header Name:Value pair: Content-Length:797 === 2013-03-19 21:57:48,081 [67-6] DEBUG IDACall - Header Name:Value pair: Pragma:no-cache === 2013-03-19 21:57:48,081 [67-6] DEBUG IDACall - Header Name:Value pair: Cache-Control:no-cache === 2013-03-19 21:57:48,081 [67-6] DEBUG IDACall - session exists: null === 2013-03-19 21:57:48,082 [67-6] DEBUG IDACall - remote user: null === 2013-03-19 21:57:48,133 [67-6] INFO RequestContext - URL: '/audespSource/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:19.0) Gecko/20100101 Firefox/19.0': Moz (Gecko) WITHOUT Accept-Encoding header === 2013-03-19 21:57:48,134 [67-2] INFO RequestContext - URL: '/audespSource/sc/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:19.0) Gecko/20100101 Firefox/19.0': Moz (Gecko) WITHOUT Accept-Encoding header === 2013-03-19 21:57:48,216 [67-6] WARN RPCManager - client/server version mismatch. Client is version: v8.3p_2013-03-06, server is version: v8.3p_2012-12-27 - mixing different client/server versions is generally not supported. If you've installed a more recent client version, try clearing the browser cache and reloading the page. === 2013-03-19 21:57:48,216 [67-2] WARN RPCManager - client/server version mismatch. Client is version: v8.3p_2013-03-06, server is version: v8.3p_2012-12-27 - mixing different client/server versions is generally not supported. If you've installed a more recent client version, try clearing the browser cache and reloading the page. === 2013-03-19 21:57:48,226 [67-6] DEBUG XML - Parsed XML from (in memory stream): 8ms === 2013-03-19 21:57:48,226 [67-2] DEBUG XML - Parsed XML from (in memory stream): 8ms === 2013-03-19 21:57:48,236 [67-2] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\audespsource\sc\system\schema\List.ds.xml: 5ms === 2013-03-19 21:57:48,236 [67-6] DEBUG XML - Parsed XML from C:\Conejo\desenvolvimento\projetos_eclipse\audespSource\war\audespsource\sc\system\schema\List.ds.xml: 5ms === 2013-03-19 21:57:48,254 [67-6] DEBUG RPCManager - Processing 1 requests. === 2013-03-19 21:57:48,254 [67-2] DEBUG RPCManager - Processing 1 requests. === 2013-03-19 21:57:48,280 [67-2] DEBUG RPCManager - Request #1 (DSRequest) payload: { criteria:{ }, operationConfig:{ dataSource:"orgaos", operationType:"fetch" }, componentId:"isc_DynamicForm_5", appID:"builtinApplication", operation:"fullFetch", oldValues:null } === 2013-03-19 21:57:48,281 [67-6] DEBUG RPCManager - Request #1 (DSRequest) payload: { criteria:{ }, operationConfig:{ dataSource:"prefeituras", operationType:"fetch" }, componentId:"isc_DynamicForm_4", appID:"builtinApplication", operation:"prefeituras_fetch", oldValues:null } === 2013-03-19 21:57:48,289 [67-6] INFO IDACall - Performing 1 operation(s) === 2013-03-19 21:57:48,289 [67-2] INFO IDACall - Performing 1 operation(s) === 2013-03-19 21:57:48,290 [67-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null === 2013-03-19 21:57:48,290 [67-2] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null === 2013-03-19 21:57:48,291 [67-6] DEBUG DeclarativeSecurity - DataSource prefeituras is not in the pre-checked list, processing... === 2013-03-19 21:57:48,291 [67-2] DEBUG DeclarativeSecurity - DataSource orgaos is not in the pre-checked list, processing... === 2013-03-19 21:57:48,316 [67-2] WARN DataSourceDMI - DataSource orgaos: received a request to execute an operation of type 'fetch' named 'fullFetch', but this operation is not defined to the dataSource. Falling back to default behavior for operationType 'fetch' === 2013-03-19 21:57:48,321 [67-2] DEBUG AppBase - [builtinApplication.fullFetch] No userTypes defined, allowing anyone access to all operations for this application === 2013-03-19 21:57:48,321 [67-6] DEBUG AppBase - [builtinApplication.prefeituras_fetch] No userTypes defined, allowing anyone access to all operations for this application === 2013-03-19 21:57:48,322 [67-2] DEBUG AppBase - [builtinApplication.fullFetch] No public zero-argument method named '_fullFetch' found, performing generic datasource operation === 2013-03-19 21:57:48,322 [67-6] DEBUG AppBase - [builtinApplication.prefeituras_fetch] No public zero-argument method named '_prefeituras_fetch' found, performing generic datasource operation === 2013-03-19 21:57:48,323 [67-6] INFO SQLDataSource - [builtinApplication.prefeituras_fetch] Performing fetch operation with criteria: {} values: {} === 2013-03-19 21:57:48,323 [67-2] INFO SQLDataSource - [builtinApplication.fullFetch] Performing fetch operation with criteria: {} values: {} === 2013-03-19 21:57:48,348 [67-2] INFO SQLWhereClause - [builtinApplication.fullFetch] empty condition === 2013-03-19 21:57:48,348 [67-6] INFO SQLWhereClause - [builtinApplication.prefeituras_fetch] empty condition === 2013-03-19 21:57:48,352 [67-6] INFO SQLDataSource - [builtinApplication.prefeituras_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause === 2013-03-19 21:57:48,352 [67-2] INFO SQLDataSource - [builtinApplication.fullFetch] 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. === 2013-03-19 21:57:48,821 [67-6] INFO SQLDataSource - [builtinApplication.prefeituras_fetch] Executing SQL query on 'Mysql': SELECT prefeituras.codigo, prefeituras.estado, prefeituras.nome, prefeituras.site FROM prefeituras WHERE ('1'='1') === 2013-03-19 21:57:48,822 [67-2] INFO SQLDataSource - [builtinApplication.fullFetch] Executing SQL query on 'Mysql': SELECT orgaos.cod_cidade, orgaos.codigo, orgaos.nome FROM orgaos WHERE ('1'='1') === 2013-03-19 21:57:48,973 [67-6] INFO PoolManager - [builtinApplication.prefeituras_fetch] SmartClient pooling started for 'Mysql' objects === 2013-03-19 21:57:48,974 [67-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.prefeituras_fetch] Initializing SQL config for 'Mysql' from system config - using DriverManager: com.google.appengine.api.rdbms.AppEngineDriver === 2013-03-19 21:57:49,027 [67-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.prefeituras_fetch] com.google.appengine.api.rdbms.AppEngineDriver lookup successful === 2013-03-19 21:57:49,028 [67-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.prefeituras_fetch] DriverManager fetching connection for Mysql via jdbc url jdbc:google:rdbms://conejo-test1:teste1/condo, root, pass2SQL === 2013-03-19 21:57:49,028 [67-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fullFetch] DriverManager fetching connection for Mysql via jdbc url jdbc:google:rdbms://conejo-test1:teste1/condo, root, pass2SQL === 2013-03-19 21:57:49,028 [67-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.prefeituras_fetch] Passing JDBC URL only to getConnection === 2013-03-19 21:57:49,028 [67-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fullFetch] Passing JDBC URL only to getConnection === 2013-03-19 21:57:49,580 [67-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fullFetch] makeObject() created a pooled Connection '579030528' === 2013-03-19 21:57:49,614 [67-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.prefeituras_fetch] makeObject() created a pooled Connection '147670687' === 2013-03-19 21:57:49,640 [67-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.prefeituras_fetch] Executing pingTest 'select 1 from dual' on connection 147670687 === 2013-03-19 21:57:49,640 [67-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.fullFetch] Executing pingTest 'select 1 from dual' on connection 579030528 === 2013-03-19 21:57:49,784 [67-2] DEBUG SQLConnectionManager - [builtinApplication.fullFetch] Returning borrowed connection '579030528' === 2013-03-19 21:57:49,784 [67-6] DEBUG SQLConnectionManager - [builtinApplication.prefeituras_fetch] Returning borrowed connection '147670687' === 2013-03-19 21:57:49,785 [67-2] DEBUG SQLDriver - [builtinApplication.fullFetch] About to execute SQL query in 'Mysql' using connection '579030528' === 2013-03-19 21:57:49,785 [67-6] DEBUG SQLDriver - [builtinApplication.prefeituras_fetch] About to execute SQL query in 'Mysql' using connection '147670687' === 2013-03-19 21:57:49,786 [67-2] INFO SQLDriver - [builtinApplication.fullFetch] Executing SQL query on 'Mysql': SELECT orgaos.cod_cidade, orgaos.codigo, orgaos.nome FROM orgaos WHERE ('1'='1') === 2013-03-19 21:57:49,786 [67-6] INFO SQLDriver - [builtinApplication.prefeituras_fetch] Executing SQL query on 'Mysql': SELECT prefeituras.codigo, prefeituras.estado, prefeituras.nome, prefeituras.site FROM prefeituras WHERE ('1'='1') === 2013-03-19 21:57:50,101 [67-6] INFO DSResponse - [builtinApplication.prefeituras_fetch] DSResponse: List with 645 items === 2013-03-19 21:57:50,102 [67-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "147670687" === 2013-03-19 21:57:50,102 [67-6] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 147670687 === 2013-03-19 21:57:50,115 [67-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8 === 2013-03-19 21:57:50,120 [67-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false === 2013-03-19 21:57:50,356 [67-2] INFO DSResponse - [builtinApplication.fullFetch] DSResponse: List with 2413 items === 2013-03-19 21:57:50,358 [67-2] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "579030528" === 2013-03-19 21:57:50,358 [67-2] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 579030528 === 2013-03-19 21:57:50,361 [67-2] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8 === 2013-03-19 21:57:50,364 [67-2] DEBUG RPCManager - non-DMI response, dropExtraFields: false U