SmartClient_v91p_2014-04-28_Evaluation
test_runner.sh fails with what appears to be problems connecting to HSQL
I got the same result with SmartClient_v91p_2014-04-25_Evaluation
These lines appear on stderr:
log4j:WARN No appenders could be found for logger (org.apache.Velocity).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
Exception in thread "main" java.sql.SQLSyntaxErrorException: user lacks privilege or object not found: BATCHRUN
at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
at org.hsqldb.jdbc.JDBCPreparedStatement.<init>(Unknown Source)
at org.hsqldb.jdbc.JDBCConnection.prepareStatement(Unknown Source)
at com.isomorphic.sql.SQLDriver.getPreparedStatement(SQLDriver.java:907)
at com.isomorphic.sql.SQLDriver.doUpdate(SQLDriver.java:833)
at com.isomorphic.sql.SQLDriver.update(SQLDriver.java:791)
at com.isomorphic.sql.SQLDriver.executeUpdate(SQLDriver.java:961)
at com.isomorphic.sql.SQLDataSource.executeNativeUpdate(SQLDataSource.java:571)
at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1943)
at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:444)
at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:397)
at com.isomorphic.datasource.DataSource.execute(DataSource.java:1544)
at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
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:2564)
at com.isomorphic.autotest.BatchRunDao.add(BatchRunDao.java:73)
at com.isomorphic.autotest.TestRunner.createBatchRunRecord(TestRunner.java:340)
at com.isomorphic.autotest.TestRunnerLauncher.launch(TestRunnerLauncher.java:355)
at com.isomorphic.autotest.TestRunnerLauncher.launch(TestRunnerLauncher.java:765)
at com.isomorphic.autotest.TestRunner.main(TestRunner.java:146)
Caused by: org.hsqldb.HsqlException: user lacks privilege or object not found: BATCHRUN
at org.hsqldb.error.Error.error(Unknown Source)
at org.hsqldb.error.Error.error(Unknown Source)
at org.hsqldb.SchemaManager.getTable(Unknown Source)
at org.hsqldb.ParserDQL.readTableName(Unknown Source)
at org.hsqldb.ParserDQL.readRangeVariableForDataChange(Unknown Source)
at org.hsqldb.ParserDML.compileInsertStatement(Unknown Source)
at org.hsqldb.ParserCommand.compilePart(Unknown Source)
at org.hsqldb.ParserCommand.compileStatement(Unknown Source)
at org.hsqldb.Session.compileStatement(Unknown Source)
at org.hsqldb.StatementManager.compile(Unknown Source)
at org.hsqldb.Session.execute(Unknown Source)
... 20 more
And these on stdout:
Using auto-detected JAVA_HOME: /usr/java
ISC: Configuring log4j from: file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/classes/log4j.isc.config.xml
=== 2014-04-28 10:04:17,527 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework - Initializing
=== 2014-04-28 10:04:17,533 [main] INFO ConfigLoader - Attempting to load framework.properties from CLASSPATH
=== 2014-04-28 10:04:17,630 [main] INFO ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties
=== 2014-04-28 10:04:17,630 [main] INFO ConfigLoader - Attempting to load project.properties from CLASSPATH
=== 2014-04-28 10:04:17,634 [main] INFO ConfigLoader - Unable to locate project.properties in CLASSPATH
=== 2014-04-28 10:04:17,642 [main] INFO ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
=== 2014-04-28 10:04:17,642 [main] INFO ConfigLoader - Attempting to load server.properties from CLASSPATH
=== 2014-04-28 10:04:17,651 [main] INFO ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/classes/server.properties
=== 2014-04-28 10:04:17,665 [main] INFO Logger - Logging system started.
=== 2014-04-28 10:04:17,666 [main] INFO ISCInit - No ServletContext available yet - using container IO for now
=== 2014-04-28 10:04:17,666 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework (v9.1p_2014-04-28/EVAL Deployment 2014-04-28) - Initialization Complete
=== 2014-04-28 10:04:17,705 [main] INFO TestRunnerConfiguration - Detected webRoot as __USE_CONTAINER__
=== 2014-04-28 10:04:17,705 [main] INFO TestRunnerConfiguration - Setting fileRoot to current working directory /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK
=== 2014-04-28 10:04:17,705 [main] INFO TestRunnerConfiguration - Setting container fallback root directory to /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK
=== 2014-04-28 10:04:17,753 [main] INFO ISCInit - No ServletContext available yet - using container IO for now
=== 2014-04-28 10:04:17,836 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/isomorphic/system/schema/builtinTypes.xml: 14ms
=== 2014-04-28 10:04:17,913 [main] INFO PoolManager - SmartClient pooling started for 'simpleTypes' objects
=== 2014-04-28 10:04:18,054 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/tools/selenium/batchRun.ds.xml: 6ms
=== 2014-04-28 10:04:18,061 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/isomorphic/system/schema/DataSource.ds.xml: 5ms
=== 2014-04-28 10:04:18,172 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/isomorphic/system/schema/DataSourceField.ds.xml: 12ms
=== 2014-04-28 10:04:18,232 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/isomorphic/system/schema/OperationBinding.ds.xml: 5ms
=== 2014-04-28 10:04:18,330 [main] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2014-04-28 10:04:18,341 [main] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2014-04-28 10:04:18,341 [main] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2014-04-28 10:04:18,348 [main] INFO SQLDataSource - [builtinApplication.null] Performing add operation with
criteria: {log:null,user:null,branch:"MAIN",batchStartTime:new Date(1398697457000)} values: {log:null,user:null,branch:"MAIN",batchStartTime:new Date(1398697457000)}
=== 2014-04-28 10:04:18,353 [main] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {id=__default}
=== 2014-04-28 10:04:18,439 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2014-04-28 10:04:18,477 [main] INFO PoolManager - [builtinApplication.null] SmartClient pooling disabled for 'HSQLDB' objects
=== 2014-04-28 10:04:18,477 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Initializing SQL config for 'HSQLDB' from system config - using DriverManager: org.hsqldb.jdbcDriver
=== 2014-04-28 10:04:18,493 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] org.hsqldb.jdbcDriver lookup successful
=== 2014-04-28 10:04:18,494 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/db/hsqldb/isomorphic; shutdown=true
=== 2014-04-28 10:04:18,494 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2014-04-28 10:04:19,277 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created an unpooled Connection '936292831'
=== 2014-04-28 10:04:19,277 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '936292831'
=== 2014-04-28 10:04:19,277 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'HSQLDB' using connection'936292831'
=== 2014-04-28 10:04:19,277 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'HSQLDB': INSERT INTO batchRun (batchStartTime, branch, log, user, id) VALUES ('2014-04-28 10:04:17', 'MAIN', NULL, NULL, null)
=== 2014-04-28 10:04:19,284 [main] INFO SQLDriver - [builtinApplication.null] Execute of update: INSERT INTO batchRun (batchStartTime, branch, log, user, id) VALUES ('2014-04-28 10:04:17', 'MAIN', NULL, NULL, null) on db: HSQLDB threw exception: java.sql.SQLSyntaxErrorException: user lacks privilege or object not found: BATCHRUN - assuming stale connection and retrying update.
=== 2014-04-28 10:04:19,284 [main] DEBUG SQLConnectionManager - [builtinApplication.null] About to close JDBCConnection with hashcode "936292831"
=== 2014-04-28 10:04:19,741 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/db/hsqldb/isomorphic; shutdown=true
=== 2014-04-28 10:04:19,741 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2014-04-28 10:04:20,100 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created an unpooled Connection '1995250556'
=== 2014-04-28 10:04:20,100 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1995250556
=== 2014-04-28 10:04:20,100 [main] DEBUG SQLConnectionManager - About to close JDBCConnection with hashcode "1995250556"
test_runner.sh fails with what appears to be problems connecting to HSQL
I got the same result with SmartClient_v91p_2014-04-25_Evaluation
These lines appear on stderr:
log4j:WARN No appenders could be found for logger (org.apache.Velocity).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
Exception in thread "main" java.sql.SQLSyntaxErrorException: user lacks privilege or object not found: BATCHRUN
at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
at org.hsqldb.jdbc.JDBCPreparedStatement.<init>(Unknown Source)
at org.hsqldb.jdbc.JDBCConnection.prepareStatement(Unknown Source)
at com.isomorphic.sql.SQLDriver.getPreparedStatement(SQLDriver.java:907)
at com.isomorphic.sql.SQLDriver.doUpdate(SQLDriver.java:833)
at com.isomorphic.sql.SQLDriver.update(SQLDriver.java:791)
at com.isomorphic.sql.SQLDriver.executeUpdate(SQLDriver.java:961)
at com.isomorphic.sql.SQLDataSource.executeNativeUpdate(SQLDataSource.java:571)
at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1943)
at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:444)
at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:397)
at com.isomorphic.datasource.DataSource.execute(DataSource.java:1544)
at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:723)
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:2564)
at com.isomorphic.autotest.BatchRunDao.add(BatchRunDao.java:73)
at com.isomorphic.autotest.TestRunner.createBatchRunRecord(TestRunner.java:340)
at com.isomorphic.autotest.TestRunnerLauncher.launch(TestRunnerLauncher.java:355)
at com.isomorphic.autotest.TestRunnerLauncher.launch(TestRunnerLauncher.java:765)
at com.isomorphic.autotest.TestRunner.main(TestRunner.java:146)
Caused by: org.hsqldb.HsqlException: user lacks privilege or object not found: BATCHRUN
at org.hsqldb.error.Error.error(Unknown Source)
at org.hsqldb.error.Error.error(Unknown Source)
at org.hsqldb.SchemaManager.getTable(Unknown Source)
at org.hsqldb.ParserDQL.readTableName(Unknown Source)
at org.hsqldb.ParserDQL.readRangeVariableForDataChange(Unknown Source)
at org.hsqldb.ParserDML.compileInsertStatement(Unknown Source)
at org.hsqldb.ParserCommand.compilePart(Unknown Source)
at org.hsqldb.ParserCommand.compileStatement(Unknown Source)
at org.hsqldb.Session.compileStatement(Unknown Source)
at org.hsqldb.StatementManager.compile(Unknown Source)
at org.hsqldb.Session.execute(Unknown Source)
... 20 more
And these on stdout:
Using auto-detected JAVA_HOME: /usr/java
ISC: Configuring log4j from: file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/classes/log4j.isc.config.xml
=== 2014-04-28 10:04:17,527 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework - Initializing
=== 2014-04-28 10:04:17,533 [main] INFO ConfigLoader - Attempting to load framework.properties from CLASSPATH
=== 2014-04-28 10:04:17,630 [main] INFO ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties
=== 2014-04-28 10:04:17,630 [main] INFO ConfigLoader - Attempting to load project.properties from CLASSPATH
=== 2014-04-28 10:04:17,634 [main] INFO ConfigLoader - Unable to locate project.properties in CLASSPATH
=== 2014-04-28 10:04:17,642 [main] INFO ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
=== 2014-04-28 10:04:17,642 [main] INFO ConfigLoader - Attempting to load server.properties from CLASSPATH
=== 2014-04-28 10:04:17,651 [main] INFO ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/classes/server.properties
=== 2014-04-28 10:04:17,665 [main] INFO Logger - Logging system started.
=== 2014-04-28 10:04:17,666 [main] INFO ISCInit - No ServletContext available yet - using container IO for now
=== 2014-04-28 10:04:17,666 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework (v9.1p_2014-04-28/EVAL Deployment 2014-04-28) - Initialization Complete
=== 2014-04-28 10:04:17,705 [main] INFO TestRunnerConfiguration - Detected webRoot as __USE_CONTAINER__
=== 2014-04-28 10:04:17,705 [main] INFO TestRunnerConfiguration - Setting fileRoot to current working directory /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK
=== 2014-04-28 10:04:17,705 [main] INFO TestRunnerConfiguration - Setting container fallback root directory to /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK
=== 2014-04-28 10:04:17,753 [main] INFO ISCInit - No ServletContext available yet - using container IO for now
=== 2014-04-28 10:04:17,836 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/isomorphic/system/schema/builtinTypes.xml: 14ms
=== 2014-04-28 10:04:17,913 [main] INFO PoolManager - SmartClient pooling started for 'simpleTypes' objects
=== 2014-04-28 10:04:18,054 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/tools/selenium/batchRun.ds.xml: 6ms
=== 2014-04-28 10:04:18,061 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/isomorphic/system/schema/DataSource.ds.xml: 5ms
=== 2014-04-28 10:04:18,172 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/isomorphic/system/schema/DataSourceField.ds.xml: 12ms
=== 2014-04-28 10:04:18,232 [main] DEBUG XML - Parsed XML from /home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/isomorphic/system/schema/OperationBinding.ds.xml: 5ms
=== 2014-04-28 10:04:18,330 [main] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2014-04-28 10:04:18,341 [main] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2014-04-28 10:04:18,341 [main] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2014-04-28 10:04:18,348 [main] INFO SQLDataSource - [builtinApplication.null] Performing add operation with
criteria: {log:null,user:null,branch:"MAIN",batchStartTime:new Date(1398697457000)} values: {log:null,user:null,branch:"MAIN",batchStartTime:new Date(1398697457000)}
=== 2014-04-28 10:04:18,353 [main] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {id=__default}
=== 2014-04-28 10:04:18,439 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2014-04-28 10:04:18,477 [main] INFO PoolManager - [builtinApplication.null] SmartClient pooling disabled for 'HSQLDB' objects
=== 2014-04-28 10:04:18,477 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Initializing SQL config for 'HSQLDB' from system config - using DriverManager: org.hsqldb.jdbcDriver
=== 2014-04-28 10:04:18,493 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] org.hsqldb.jdbcDriver lookup successful
=== 2014-04-28 10:04:18,494 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/db/hsqldb/isomorphic; shutdown=true
=== 2014-04-28 10:04:18,494 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2014-04-28 10:04:19,277 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created an unpooled Connection '936292831'
=== 2014-04-28 10:04:19,277 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '936292831'
=== 2014-04-28 10:04:19,277 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'HSQLDB' using connection'936292831'
=== 2014-04-28 10:04:19,277 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'HSQLDB': INSERT INTO batchRun (batchStartTime, branch, log, user, id) VALUES ('2014-04-28 10:04:17', 'MAIN', NULL, NULL, null)
=== 2014-04-28 10:04:19,284 [main] INFO SQLDriver - [builtinApplication.null] Execute of update: INSERT INTO batchRun (batchStartTime, branch, log, user, id) VALUES ('2014-04-28 10:04:17', 'MAIN', NULL, NULL, null) on db: HSQLDB threw exception: java.sql.SQLSyntaxErrorException: user lacks privilege or object not found: BATCHRUN - assuming stale connection and retrying update.
=== 2014-04-28 10:04:19,284 [main] DEBUG SQLConnectionManager - [builtinApplication.null] About to close JDBCConnection with hashcode "936292831"
=== 2014-04-28 10:04:19,741 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] DriverManager fetching connection for HSQLDB via jdbc url jdbc:hsqldb:file:/home/rpoyner/SmartClient_v91p_2014-04-28_Evaluation/smartclientSDK/WEB-INF/db/hsqldb/isomorphic; shutdown=true
=== 2014-04-28 10:04:19,741 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Passing JDBC URL only to getConnection
=== 2014-04-28 10:04:20,100 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created an unpooled Connection '1995250556'
=== 2014-04-28 10:04:20,100 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1995250556
=== 2014-04-28 10:04:20,100 [main] DEBUG SQLConnectionManager - About to close JDBCConnection with hashcode "1995250556"
Comment