Announcement

Collapse
No announcement yet.
X
  • Filter
  • Time
Clear All
new posts

    Demo server fails to start

    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"

    #2
    This is addressed by the second question in the FAQ section at the bottom of the TestRunner doc topic: http://www.smartclient.com/docs/9.1/...up..testRunner

    Q: When I run TestRunner, TestRunner fails reporting that DataSource BatchRun or TestResult cannot be found.
    A: These DataSources must be imported into the default HSQLDB before TestRunner can be used. Use the "import" option of tools/adminConsole.jsp under the SDK installation root directory to select and import the BatchRun and TestResult DataSources prior to running TestRunner.

    Comment

    Working...
    X