Selenium 2 - smartgwtpower-4.0 - debug batch process

[masspetracca]

I use Test Selenium 2, smartgwtpower-4.0 suite, and I try to run a batch process called testRunner (server-side).
I use FIREFOX browser.

Please I need your help to solve the problem,

Thanks.

A batch processes a test file named partially - "test.rctest.html" (because it stops) and the same procedure displays a log like this:

<<
......
D=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClicka
ble : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo:
Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[field
Name=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/
row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//Tr
eeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScEleme
ntClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]
/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScEleme
ntClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r
\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/c
ol[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtre
e]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLoca
tor=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]
/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=
//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open\r\ninfo: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open",description:null,capture_filesize:392067,branch:"MAIN",batchStartTime:new Date(1375794759000),capture_filename:"testMassimo1.png",endTime:new Date(137579481
1810),messages:"15:12:59.668:INFO:Log:initialized\r\n15:13:00.075:WARN:Log:New Class ID: &apos;DataView&apos; collides with ID of existing object with value &apos;function DataView() {\r\n [native code]\r\n}&apos;. Existing object will be replaced.\r\nThis conflict would be avoided by disabling ISC Simple Names mode. See documentation for further information.\r\n15:13:09.206:INFO:Log:isc.Page is loaded\r\n",testFile:"testMassimo1.rctest.html"}
=== 2013-08-06 15:13:33,940 [main] INFO SQLValuesClause - [builtinApplication.null] Ignored data for non-existent or included columns: [description]
=== 2013-08-06 15:13:33,942 [main] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {}
=== 2013-08-06 15:13:33,944 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-08-06 15:13:33,945 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '665458008'
=== 2013-08-06 15:13:33,945 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'DB2' using connection'665458008'
=== 2013-08-06 15:13:33,946 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'DB2': INSERT INTO testResult (batchStartTime, branch, capture, capture_filename, capture_filesize, details, endTime, messages, result, startTime, testFile) VALUES ('2013-08-06 15:12:39', 'MAIN', ?, 'testMassimo1.png', 392067, '
info: Starting test /selenium-server/tests/testMassimo16089647789806236937.rctest.html
info: Executing: |open | http://XX.X.XX.XXX:10000/PampWeb/login.jsp?sc_selenium=true | |
info: Executing: |type | id=Username | mpetracca |
info: Executing: |type | id=Password | petracca |
info: Executing: |waitForElementClickable | scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open | |
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open
info: Located in isScElementClickable : scLocator=//TreeGrid[ID=sidenavtree]/body/row[name=iBond||3]/col[fieldName=name||0]/open', '2013-08-06 15:13:31', '15:12:59.668:INFO:Log:initialized
15:13:00.075:WARN:Log:New Class ID: &apos;DataView&apos; collides with ID of existing object with value &apos;function DataView() {
[native code]
}&apos;. Existing object will be replaced.
This conflict would be avoided by disabling ISC Simple Names mode. See documentation for further information.
15:13:09.206:INFO:Log:isc.Page is loaded
', 'failure', '2013-08-06 15:12:42', 'testMassimo1.rctest.html') on db: DB2 threw exception: java.sql.SQLException: [SQL0102] Costante stringa che comincia con ''
info: St' troppo lunga. - assuming stale connection and retrying update.
=== 2013-08-06 15:13:35,091 [main] DEBUG SQLConnectionManager - [builtinApplication.null] About to close PoolableConnection with hashcode "665458008"
=== 2013-08-06 15:13:35,133 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '631294392'
=== 2013-08-06 15:13:35,243 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 631294392
=== 2013-08-06 15:13:35,243 [main] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "631294392"
=== 2013-08-06 15:13:35,245 [main] ERROR TestResultDao - encountered exception trying to add test result for test testMassimo1.rctest.html #1:
java.sql.SQLException: [SQL0102] Costante stringa che comincia con ''
info: St' troppo lunga.
at com.ibm.as400.access.JDError.throwSQLException(JDError.java:650)
at com.ibm.as400.access.JDError.throwSQLException(JDError.java:621)
at com.ibm.as400.access.AS400JDBCStatement.commonPrepare(AS400JDBCStatement.java:1481)
at com.ibm.as400.access.AS400JDBCPreparedStatement.<init>(AS400JDBCPreparedStatement.java:185)
at com.ibm.as400.access.AS400JDBCConnection.prepareStatement(AS400JDBCConnection.java:1883)
at com.ibm.as400.access.AS400JDBCConnection.prepareStatement(AS400JDBCConnection.java:1706)
at org.apache.commons.dbcp.DelegatingConnection.prepareStatement(DelegatingConnection.java:248)
at com.isomorphic.sql.SQLDriver.getPreparedStatement(SQLDriver.java:836)
at com.isomorphic.sql.SQLDriver.doUpdate(SQLDriver.java:797)
at com.isomorphic.sql.SQLDriver.update(SQLDriver.java:776)
at com.isomorphic.sql.SQLDriver.executeUpdate(SQLDriver.java:889)
at com.isomorphic.sql.SQLDataSource.executeNativeUpdate(SQLDataSource.java:533)
at com.isomorphic.sql.SQLDataSource.SQLExecute(SQLDataSource.java:1624)
at com.isomorphic.sql.SQLDataSource.processRequest(SQLDataSource.java:408)
at com.isomorphic.sql.SQLDataSource.executeAdd(SQLDataSource.java:360)
at com.isomorphic.datasource.DataSource.execute(DataSource.java:1447)
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:2419)
at com.isomorphic.autotest.TestResultDao.add(TestResultDao.java:95)
at com.isomorphic.autotest.TestRunner.testAll(TestRunner.java:236)
at com.isomorphic.autotest.TestRunnerLauncher.launch(TestRunnerLauncher.java:342)
at com.isomorphic.autotest.TestRunnerLauncher.launch(TestRunnerLauncher.java:711)
at com.isomorphic.autotest.TestRunner.main(TestRunner.java:128)
=== 2013-08-06 15:13:35,256 [main] DEBUG DSRequest - Caching instance 9 of DS batchRun from DSRequest.getDataSource()
=== 2013-08-06 15:13:35,256 [main] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-08-06 15:13:35,257 [main] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-08-06 15:13:35,258 [main] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-08-06 15:13:35,260 [main] INFO SQLDataSource - [builtinApplication.null] Performing update operation with
criteria: {batchStartTime:new Date(1375794759000)} values: {batchEndTime:new Date(1375794815255)}
=== 2013-08-06 15:13:35,262 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-08-06 15:13:35,263 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '631294392'
=== 2013-08-06 15:13:35,263 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'DB2' using connection'631294392'
=== 2013-08-06 15:13:35,264 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'DB2': UPDATE batchRun SET batchEndTime='2013-08-06 15:13:35' WHERE (batchRun.batchStartTime='2013-08-06 15:12:39')
=== 2013-08-06 15:13:35,521 [main] DEBUG SQLDataSource - [builtinApplication.null] update operation affected 1 rows
=== 2013-08-06 15:13:35,521 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 631294392
=== 2013-08-06 15:13:35,522 [main] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "631294392"
>>

[Isomorphic]

The exception is coming from the SQL layer. According to Google Translate, the message is that the string is too long. It may be that the test result string is too large to fit into the HSQLDB database field.

You may want to reduce the length specified in the file .../selenium/testResult.ds.xml for the details field from 100,000 to a smaller number such as 65,000, before importing the tables into your HSQLDB database. This will cause TestRunner to truncate the reports before committing them to the database.

[Isomorphic]

Some more checking indicates you are using DB2/iSeries rather than the database engine that was shipped in the ZIP (HSQLDB). You may want to dump the table definition for us and add it to this thread so we have more information.

[masspetracca]

Selenium 2 -smartgwtpower-4.0 - debug ... continue

I use smartgwtpower-4.0 and Firefox 23 version.

I need your help to run TestRunner procedure as said below, so include two points:
1) I send you my Data Definition (db2AS400).
<<
CREATE TABLE XXXXXXXX.BATCHRUN (
"ID" BIGINT GENERATED BY DEFAULT AS IDENTITY (
START WITH 1 INCREMENT BY 1
NO MINVALUE NO MAXVALUE
NO CYCLE NO ORDER
CACHE 20 )
,
BRANCH VARCHAR(255) CCSID 1144 DEFAULT NULL ,
BATCHSTARTTIME FOR COLUMN BATCH00001 TIMESTAMP DEFAULT NULL ,
BATCHENDTIME FOR COLUMN BATCH00002 TIMESTAMP DEFAULT NULL ,
"USER" VARCHAR(255) CCSID 1144 DEFAULT NULL ,
"LOG" CLOB(1048576) CCSID 1144 DEFAULT NULL )
;

CREATE TABLE XXXXXXXX.TESTRESULT (
TESTFILE VARCHAR(255) CCSID 1144 NOT NULL DEFAULT '' ,
"RESULT" VARCHAR(255) CCSID 1144 DEFAULT NULL ,
DETAILS CLOB(1048576) CCSID 1144 DEFAULT NULL ,
MESSAGES CLOB(1048576) CCSID 1144 DEFAULT NULL ,
STARTTIME TIMESTAMP NOT NULL ,
ENDTIME TIMESTAMP DEFAULT NULL ,
BATCHSTARTTIME FOR COLUMN BATCH00001 TIMESTAMP DEFAULT NULL ,
BRANCH VARCHAR(255) CCSID 1144 DEFAULT NULL ,
CAPTURE BLOB(1048576) DEFAULT NULL ,
CAPTURE_FILENAME FOR COLUMN CAPTU00001 VARCHAR(255) CCSID 1144 DEFAULT NULL ,
CAPTURE_FILESIZE FOR COLUMN CAPTU00002 BIGINT DEFAULT NULL ,
CONSTRAINT XXXXXXXX.TESTRESULT_UI PRIMARY KEY( TESTFILE , STARTTIME ) ) ;

>> TABLE XXXXXXXX.BATCHRUN contains :
424 MAIN 2013-08-07 16:55:10.000000 2013-08-07 16:56:07.000000 - log-out.txt


>> TABLE XXXXXXXX.TESTRESULT contains :
testMassimo1.rctest.html timeout TestCase results have not appeared after 45 seconds - 2013-08-07 16:55:11.000000 2013-08-07 16:56:06.000000 2013-08-07 16:55:10.000000 MAIN 89504E470D0A1A0A0000000D494844520000078000000438080200000067B15614000080004944415478DAECBD077423D975A0BDE7ACBD5EA5999126F5E4D0D3D3B99BDDCC39E79C23406490004102204080600098732698738E4D363BE7343947699465CB9656962CCBD6DABBB6F73FFEEF7DAF0A89209B3D96BC5EC93CDFC129160A85AA57857AEF7D75DFADFFF25F76FEFBFA371EFAC6430F3FF4F0230F3DF2CD871FF9D623DFDC03DF72E051E09B561EFBD6A384C71E7F1479E2B1C79F78EC89271F7F62DF134F3EF5C4BEA79F7CEAE97D4F3DB3EFE96781A79E019E439E059EDF81E7986576E0695B9E059EB7F00CF0DC0BC0B3CFBF4878E9B9175E7AEEC59781E791FDC84B9457282F002FDB72E0450BFB8157F786CDA70876EB24DF42BE8EFDF617BF142FED8D0758E7CBDB79CE811776E1255B9EB5E5795B5EB4E519E0B917E9617A1A78F605CBE1C3A3CF1EE57DC0D3CFD1D3C681279F7AE6FEEC43E0F4DB99A7E0FC7CDCC213FB80C7FE23819BF42485D9C82728FB6C78D27E2FE8F2CEF7E549E471E45186271C79EC896FFDC7E0518A75DB708371E3712FEC4BC9A17C9C1EEBA79E79622FE7CC7F309E78EA69867DBF636C4F18BBF2B43D379C1C97C7191EB5F2CD3F20BEF56FE33E2BB494DEF653DD5AECE43CFF77B8BC3CBECFEE4B773AD6CE0BEA31E45BFF817864579C7CE4 testMassimo1.png 435768
>>

2) actually batch works:
<<
C:\Users\mpetracc\Downloads\smartgwtpower-4.0p>test_runner.bat -cs -sm -m masspetracca@gmail.com -lg log-out.txt -x

Using user-defined JAVA_HOME: "C:\Program Files\Java\jre7"

A subdirectory or file showcase already exists.
"Installing schema ds.xml files..."
ISC: Configuring log4j from: file:/C:/Users/mpetracc/Downloads/smartgwtpower-4.0p/samples/showcase/war/WEB-INF/classes/log4j.isc.config.xml
=== 2013-08-07 16:55:09,905 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework - Initializing
=== 2013-08-07 16:55:09,913 [main] INFO ConfigLoader - Attempting to load framework.properties from CLASSPATH
=== 2013-08-07 16:55:10,080 [main] INFO ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/C:/Users/mpetracc/Downloads/smartgwtpower-4.0p/lib/isomorphic_core_rpc.jar!/framework.properties
=== 2013-08-07 16:55:10,081 [main] INFO ConfigLoader - Attempting to load project.properties from CLASSPATH
=== 2013-08-07 16:55:10,082 [main] INFO ConfigLoader - Unable to locate project.properties in CLASSPATH
=== 2013-08-07 16:55:10,089 [main] INFO ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/C:/Users/mpetracc/Downloads/smartgwtpower-4.0p/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
=== 2013-08-07 16:55:10,089 [main] INFO ConfigLoader - Attempting to load server.properties from CLASSPATH
=== 2013-08-07 16:55:10,095 [main] INFO ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/C:/Users/mpetracc/Downloads/smartgwtpower-4.0p/selenium/server.properties
=== 2013-08-07 16:55:10,106 [main] INFO Logger - Logging system started.
=== 2013-08-07 16:55:10,108 [main] INFO ISCInit - No ServletContext available yet - using container IO for now
=== 2013-08-07 16:55:10,109 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework (v9.0p_2013-07-25/PowerEdition Deployment 2013-07-25) - Initialization Complete
=== 2013-08-07 16:55:10,154 [main] INFO TestRunnerConfiguration - Setting fileRoot as C:/Users/mpetracc/Downloads/smartgwtpower-4.0p
=== 2013-08-07 16:55:10,177 [main] INFO ISCInit - No ServletContext available yet - using container IO for now
=== 2013-08-07 16:55:10,237 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\showcase\sc\system\schema\builtinTypes.xml: 8ms
=== 2013-08-07 16:55:10,270 [main] INFO PoolManager - SmartClient pooling started for 'simpleTypes' objects
=== 2013-08-07 16:55:10,367 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\selenium\batchRun.ds.xml: 3ms
=== 2013-08-07 16:55:10,375 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\showcase\sc\system\schema\DataSource.ds.xml: 6ms
=== 2013-08-07 16:55:10,460 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\showcase\sc\system\schema\DataSourceField.ds.xml: 7ms
=== 2013-08-07 16:55:10,535 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\showcase\sc\system\schema\OperationBinding.ds.xml: 4ms
=== 2013-08-07 16:55:10,605 [main] DEBUG DSRequest - Caching instance 9 of DS batchRun from DSRequest.getDataSource()
=== 2013-08-07 16:55:10,610 [main] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-08-07 16:55:10,615 [main] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-08-07 16:55:10,616 [main] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-08-07 16:55:10,620 [main] INFO SQLDataSource - [builtinApplication.null] Performing add operation with
criteria: {branch:"MAIN",batchStartTime:new Date(1375887310000),log:"log-out.txt",user:null} values: {branch:"MAIN",batchStartTime:new Date(1375887310000),log:"log-out.txt",user:null}
=== 2013-08-07 16:55:10,624 [main] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {id=__default}
log4j:WARN No appenders could be found for logger (org.apache.Velocity).
log4j:WARN Please initialize the log4j system properly.
=== 2013-08-07 16:55:10,741 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-08-07 16:55:10,821 [main] INFO PoolManager - [builtinApplication.null] SmartClient pooling started for 'DB2' objects
=== 2013-08-07 16:55:10,821 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Initializing SQL config for 'DB2' from system config - using DataSource: com.ibm.as400.access.AS400JDBCConnectionPoolDataSource
=== 2013-08-07 16:55:10,946 [main] INFO DataTools - [builtinApplication.null] setProperties: couldn't set:
{
portNumber:"No such property"
}
=== 2013-08-07 16:55:11,114 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '1977255737'
=== 2013-08-07 16:55:11,119 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '1977255737'
=== 2013-08-07 16:55:11,120 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'DB2' using connection'1977255737'
=== 2013-08-07 16:55:11,121 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'DB2': INSERT INTO batchRun (batchStartTime, branch, log, user) VALUES ('2013-08-07 16:55:10', 'MAIN', 'log-out.txt', NULL)
=== 2013-08-07 16:55:11,418 [main] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-08-07 16:55:11,419 [main] INFO SQLDataSource - [builtinApplication.null] primaryKeys: {}
=== 2013-08-07 16:55:11,421 [main] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-08-07 16:55:11,424 [main] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-08-07 16:55:11,426 [main] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-08-07 16:55:11,429 [main] INFO SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
criteria: {} values: {}
=== 2013-08-07 16:55:11,446 [main] INFO SQLWhereClause - [builtinApplication.null, builtinApplication.null] empty condition
=== 2013-08-07 16:55:11,447 [main] INFO SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2013-08-07 16:55:11,448 [main] INFO SQLDataSource - [builtinApplication.null, builtinApplication.null] 9: Executing SQL query on 'DB2': SELECT batchRun.batchEndTime, batchRun.batchStartTime, batchRun.branch, batchRun.id, batchRun.log, batchRun.user FROM batchRun WHERE ('1'='1')
=== 2013-08-07 16:55:11,449 [main] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'DB2' using connection '1977255737'
=== 2013-08-07 16:55:11,449 [main] INFO SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'DB2': SELECT batchRun.batchEndTime, batchRun.batchStartTime, batchRun.branch, batchRun.id, batchRun.log, batchRun.user FROM batchRun WHERE ('1'='1')
=== 2013-08-07 16:55:11,511 [main] INFO DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 1 items
=== 2013-08-07 16:55:11,512 [main] INFO DSResponse - [builtinApplication.null] DSResponse: List with 1 items
=== 2013-08-07 16:55:11,513 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1977255737
=== 2013-08-07 16:55:11,515 [main] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1977255737"
=== 2013-08-07 16:55:11,828 [main] INFO SeleniumServerHelper - Selenium server started
16:55:11.933 INFO - Preparing Firefox profile...
16:55:17.953 INFO - Launching Firefox...
16:55:20.365 INFO - Checking Resource aliases
16:56:06.005 INFO - Command request: captureScreenshot[C:\Users\mpetracc\AppData\Local\Temp\testMassimo1-6162102653807059844.png, ] on session null
16:56:06.013 INFO - Creating Robot
16:56:06.617 INFO - Got result: OK on session null
16:56:06.621 INFO - Killing Firefox...
=== 2013-08-07 16:56:06,760 [main] INFO TestRunner - results for testMassimo1.rctest.html:
=== 2013-08-07 16:56:06,760 [main] ERROR TestRunner - error:TestCase results have not appeared after 45 seconds
=== 2013-08-07 16:56:07,220 [main] INFO SeleniumServerHelper - Selenium server stopped
=== 2013-08-07 16:56:07,229 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\selenium\testResult.ds.xml: 5ms
=== 2013-08-07 16:56:07,255 [main] DEBUG DSRequest - Caching instance 15 of DS testResult from DSRequest.getDataSource()
=== 2013-08-07 16:56:07,257 [main] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-08-07 16:56:07,258 [main] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-08-07 16:56:07,259 [main] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-08-07 16:56:07,261 [main] INFO SQLDataSource - [builtinApplication.null] Performing add operation with
criteria: {startTime:new Date(1375887311849),result:"timeout",details:"TestCase results have not appeared after 45 seconds",description:null,capture_filesize:435768,branch:"MAIN",batchStartTime:new Date(1375887310000),capture_filename:"testMassimo1.png",endTime:new Date(1375887366759),messages:null,testFile:"testMassimo1.rctest.html"} values: {startTime:new Date(1375887311849),result:"timeout",details:"TestCase results have not appeared after 45 seconds",description:null,capture_f
ilesize:435768,branch:"MAIN",batchStartTime:new Date(1375887310000),capture_filename:"testMassimo1.png",endTime:new Date(1375887366759),messages:null,testFile:"testMassimo1.rctest.html"}
=== 2013-08-07 16:56:07,262 [main] INFO SQLValuesClause - [builtinApplication.null] Ignored data for non-existent or included columns: [description]
=== 2013-08-07 16:56:07,263 [main] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {}
=== 2013-08-07 16:56:07,266 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-08-07 16:56:07,267 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '1977255737'
=== 2013-08-07 16:56:07,269 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'DB2' using connection'1977255737'
=== 2013-08-07 16:56:07,271 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'DB2': INSERT INTO testResult (batchStartTime, branch, capture, capture_filename, capture_filesize, details, endTime, messages, result, startTime, testFile) VALUES ('2013-08-07 16:55:10', 'MAIN', ?, 'testMassimo1.png', 435768, 'TestCase results have not appeared after 45 seconds', '2013-08-07 16:56:06', NULL, 'timeout', '2013-08-07 16:55:11', 'testMassimo1.rctest.html')
=== 2013-08-07 16:56:07,414 [main] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-08-07 16:56:07,415 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1977255737
=== 2013-08-07 16:56:07,416 [main] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1977255737"
=== 2013-08-07 16:56:07,421 [main] DEBUG DSRequest - Caching instance 9 of DS batchRun from DSRequest.getDataSource()
=== 2013-08-07 16:56:07,421 [main] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-08-07 16:56:07,423 [main] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-08-07 16:56:07,424 [main] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-08-07 16:56:07,427 [main] INFO SQLDataSource - [builtinApplication.null] Performing update operation with
criteria: {batchStartTime:new Date(1375887310000)} values: {batchEndTime:new Date(1375887367420)}
=== 2013-08-07 16:56:07,430 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-08-07 16:56:07,430 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '1977255737'
=== 2013-08-07 16:56:07,432 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'DB2' using connection'1977255737'
=== 2013-08-07 16:56:07,434 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'DB2': UPDATE batchRun SET batchEndTime='2013-08-07 16:56:07' WHERE (batchRun.batchStartTime='2013-08-07 16:55:10')
=== 2013-08-07 16:56:07,501 [main] DEBUG SQLDataSource - [builtinApplication.null] update operation affected 1 rows
=== 2013-08-07 16:56:07,501 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1977255737
=== 2013-08-07 16:56:07,503 [main] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1977255737"
C:\Users\mpetracc\Downloads\smartgwtpower-4.0p>
>>

[Isomorphic]

I use smartgwtpower-4.0 and Firefox 23 version.

Today's build of 4.0p is the first one with the new Selenium v2.34 JAR that works with FF22 (and presumably FF23). Prior to that you have to use FF17ESR for Selenium.

I need your help to run TestRunner procedure as said below, so include two points:
1) I send you my Data Definition (db2AS400).
<<
CREATE TABLE XXXXXXXX.BATCHRUN (
"ID" BIGINT GENERATED BY DEFAULT AS IDENTITY (
START WITH 1 INCREMENT BY 1
NO MINVALUE NO MAXVALUE
NO CYCLE NO ORDER
CACHE 20 )
,
BRANCH VARCHAR(255) CCSID 1144 DEFAULT NULL ,
BATCHSTARTTIME FOR COLUMN BATCH00001 TIMESTAMP DEFAULT NULL ,
BATCHENDTIME FOR COLUMN BATCH00002 TIMESTAMP DEFAULT NULL ,
"USER" VARCHAR(255) CCSID 1144 DEFAULT NULL ,
"LOG" CLOB(1048576) CCSID 1144 DEFAULT NULL )
;

CREATE TABLE XXXXXXXX.TESTRESULT (
TESTFILE VARCHAR(255) CCSID 1144 NOT NULL DEFAULT '' ,
"RESULT" VARCHAR(255) CCSID 1144 DEFAULT NULL ,
DETAILS CLOB(1048576) CCSID 1144 DEFAULT NULL ,
MESSAGES CLOB(1048576) CCSID 1144 DEFAULT NULL ,
STARTTIME TIMESTAMP NOT NULL ,
ENDTIME TIMESTAMP DEFAULT NULL ,
BATCHSTARTTIME FOR COLUMN BATCH00001 TIMESTAMP DEFAULT NULL ,
BRANCH VARCHAR(255) CCSID 1144 DEFAULT NULL ,
CAPTURE BLOB(1048576) DEFAULT NULL ,
CAPTURE_FILENAME FOR COLUMN CAPTU00001 VARCHAR(255) CCSID 1144 DEFAULT NULL ,
CAPTURE_FILESIZE FOR COLUMN CAPTU00002 BIGINT DEFAULT NULL ,
CONSTRAINT XXXXXXXX.TESTRESULT_UI PRIMARY KEY( TESTFILE , STARTTIME ) ) ;

>> TABLE XXXXXXXX.BATCHRUN contains :
424 MAIN 2013-08-07 16:55:10.000000 2013-08-07 16:56:07.000000 - log-out.txt

>> TABLE XXXXXXXX.TESTRESULT contains :
testMassimo1.rctest.html timeout TestCase results have not appeared after 45 seconds - 2013-08-07 16:55:11.000000 2013-08-07 16:56:06.000000 2013-08-07 16:55:10.000000 MAIN 89504E470D0A1A0A0000000D494844520000078000000438080200000067B15614000080004944415478DAECBD077423D975A0BDE7ACBD5EA5999126F5E4D0D3D3B99BDDCC39E79C23406490004102204080600098732698738E4D363BE7343947699465CB9656962CCBD6DABBB6F73FFEEF7DAF0A89209B3D96BC5EC93CDFC129160A85AA57857AEF7D75DFADFFF25F76FEFBFA371EFAC6430F3FF4F0230F3DF2CD871FF9D623DFDC03DF72E051E09B561EFBD6A384C71E7F1479E2B1C79F78EC89271F7F62DF134F3EF5C4BEA79F7CEAE97D4F3DB3EFE96781A79E019E439E059EDF81E7986576E0695B9E059EB7F00CF0DC0BC0B3CFBF4878E9B9175E7AEEC59781E791FDC84B9457282F002FDB72E0450BFB8157F786CDA70876EB24DF42BE8EFDF617BF142FED8D0758E7CBDB79CE811776E1255B9EB5E5795B5EB4E519E0B917E9617A1A78F605CBE1C3A3CF1EE57DC0D3CFD1D3C681279F7AE6FEEC43E0F4DB99A7E0FC7CDCC213FB80C7FE23819BF42485D9C82728FB6C78D27E2FE8F2CEF7E549E471E45186271C79EC896FFDC7E0518A75DB708371E3712FEC4BC9A17C9C1EEBA79E79622FE7CC7F309E78EA69867DBF636C4F18BBF2B43D379C1C97C7191EB5F2CD3F20BEF56FE33E2BB494DEF653DD5AECE43CFF77B8BC3CBECFEE4B773AD6CE0BEA31E45BFF817864579C7CE4 testMassimo1.png 435768
>>

The details string looks long enough - by default we clip the length at 100,000 bytes and you have 1mb size. The test result record indicates your test is timing out, meaning either you're hitting the FF22 problem, or you have a problem with the test itself not being designed properly.

2) actually batch works:
<<
C:\Users\mpetracc\Downloads\smartgwtpower-4.0p>test_runner.bat -cs -sm -m masspetracca@gmail.com -lg log-out.txt -x

Using user-defined JAVA_HOME: "C:\Program Files\Java\jre7"

A subdirectory or file showcase already exists.
"Installing schema ds.xml files..."
ISC: Configuring log4j from: file:/C:/Users/mpetracc/Downloads/smartgwtpower-4.0p/samples/showcase/war/WEB-INF/classes/log4j.isc.config.xml
=== 2013-08-07 16:55:09,905 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework - Initializing
=== 2013-08-07 16:55:09,913 [main] INFO ConfigLoader - Attempting to load framework.properties from CLASSPATH
=== 2013-08-07 16:55:10,080 [main] INFO ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/C:/Users/mpetracc/Downloads/smartgwtpower-4.0p/lib/isomorphic_core_rpc.jar!/framework.properties
=== 2013-08-07 16:55:10,081 [main] INFO ConfigLoader - Attempting to load project.properties from CLASSPATH
=== 2013-08-07 16:55:10,082 [main] INFO ConfigLoader - Unable to locate project.properties in CLASSPATH
=== 2013-08-07 16:55:10,089 [main] INFO ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/C:/Users/mpetracc/Downloads/smartgwtpower-4.0p/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
=== 2013-08-07 16:55:10,089 [main] INFO ConfigLoader - Attempting to load server.properties from CLASSPATH
=== 2013-08-07 16:55:10,095 [main] INFO ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/C:/Users/mpetracc/Downloads/smartgwtpower-4.0p/selenium/server.properties
=== 2013-08-07 16:55:10,106 [main] INFO Logger - Logging system started.
=== 2013-08-07 16:55:10,108 [main] INFO ISCInit - No ServletContext available yet - using container IO for now
=== 2013-08-07 16:55:10,109 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework (v9.0p_2013-07-25/PowerEdition Deployment 2013-07-25) - Initialization Complete
=== 2013-08-07 16:55:10,154 [main] INFO TestRunnerConfiguration - Setting fileRoot as C:/Users/mpetracc/Downloads/smartgwtpower-4.0p
=== 2013-08-07 16:55:10,177 [main] INFO ISCInit - No ServletContext available yet - using container IO for now
=== 2013-08-07 16:55:10,237 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\showcase\sc\system\schema\builtinTypes.xml: 8ms
=== 2013-08-07 16:55:10,270 [main] INFO PoolManager - SmartClient pooling started for 'simpleTypes' objects
=== 2013-08-07 16:55:10,367 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\selenium\batchRun.ds.xml: 3ms
=== 2013-08-07 16:55:10,375 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\showcase\sc\system\schema\DataSource.ds.xml: 6ms
=== 2013-08-07 16:55:10,460 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\showcase\sc\system\schema\DataSourceField.ds.xml: 7ms
=== 2013-08-07 16:55:10,535 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\showcase\sc\system\schema\OperationBinding.ds.xml: 4ms
=== 2013-08-07 16:55:10,605 [main] DEBUG DSRequest - Caching instance 9 of DS batchRun from DSRequest.getDataSource()
=== 2013-08-07 16:55:10,610 [main] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-08-07 16:55:10,615 [main] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-08-07 16:55:10,616 [main] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-08-07 16:55:10,620 [main] INFO SQLDataSource - [builtinApplication.null] Performing add operation with
criteria: {branch:"MAIN",batchStartTime:new Date(1375887310000),log:"log-out.txt",user:null} values: {branch:"MAIN",batchStartTime:new Date(1375887310000),log:"log-out.txt",user:null}
=== 2013-08-07 16:55:10,624 [main] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {id=__default}
log4j:WARN No appenders could be found for logger (org.apache.Velocity).
log4j:WARN Please initialize the log4j system properly.
=== 2013-08-07 16:55:10,741 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-08-07 16:55:10,821 [main] INFO PoolManager - [builtinApplication.null] SmartClient pooling started for 'DB2' objects
=== 2013-08-07 16:55:10,821 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] Initializing SQL config for 'DB2' from system config - using DataSource: com.ibm.as400.access.AS400JDBCConnectionPoolDataSource
=== 2013-08-07 16:55:10,946 [main] INFO DataTools - [builtinApplication.null] setProperties: couldn't set:
{
portNumber:"No such property"
}
=== 2013-08-07 16:55:11,114 [main] DEBUG PoolableSQLConnectionFactory - [builtinApplication.null] makeObject() created a pooled Connection '1977255737'
=== 2013-08-07 16:55:11,119 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '1977255737'
=== 2013-08-07 16:55:11,120 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'DB2' using connection'1977255737'
=== 2013-08-07 16:55:11,121 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'DB2': INSERT INTO batchRun (batchStartTime, branch, log, user) VALUES ('2013-08-07 16:55:10', 'MAIN', 'log-out.txt', NULL)
=== 2013-08-07 16:55:11,418 [main] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-08-07 16:55:11,419 [main] INFO SQLDataSource - [builtinApplication.null] primaryKeys: {}
=== 2013-08-07 16:55:11,421 [main] DEBUG DeclarativeSecurity - [builtinApplication.null] Processing security checks for DataSource null, field null
=== 2013-08-07 16:55:11,424 [main] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-08-07 16:55:11,426 [main] DEBUG AppBase - [builtinApplication.null, builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-08-07 16:55:11,429 [main] INFO SQLDataSource - [builtinApplication.null, builtinApplication.null] Performing fetch operation with
criteria: {} values: {}
=== 2013-08-07 16:55:11,446 [main] INFO SQLWhereClause - [builtinApplication.null, builtinApplication.null] empty condition
=== 2013-08-07 16:55:11,447 [main] INFO SQLDataSource - [builtinApplication.null, builtinApplication.null] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2013-08-07 16:55:11,448 [main] INFO SQLDataSource - [builtinApplication.null, builtinApplication.null] 9: Executing SQL query on 'DB2': SELECT batchRun.batchEndTime, batchRun.batchStartTime, batchRun.branch, batchRun.id, batchRun.log, batchRun.user FROM batchRun WHERE ('1'='1')
=== 2013-08-07 16:55:11,449 [main] DEBUG SQLDriver - [builtinApplication.null, builtinApplication.null] About to execute SQL query in 'DB2' using connection '1977255737'
=== 2013-08-07 16:55:11,449 [main] INFO SQLDriver - [builtinApplication.null, builtinApplication.null] Executing SQL query on 'DB2': SELECT batchRun.batchEndTime, batchRun.batchStartTime, batchRun.branch, batchRun.id, batchRun.log, batchRun.user FROM batchRun WHERE ('1'='1')
=== 2013-08-07 16:55:11,511 [main] INFO DSResponse - [builtinApplication.null, builtinApplication.null] DSResponse: List with 1 items
=== 2013-08-07 16:55:11,512 [main] INFO DSResponse - [builtinApplication.null] DSResponse: List with 1 items
=== 2013-08-07 16:55:11,513 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1977255737
=== 2013-08-07 16:55:11,515 [main] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1977255737"
=== 2013-08-07 16:55:11,828 [main] INFO SeleniumServerHelper - Selenium server started
16:55:11.933 INFO - Preparing Firefox profile...
16:55:17.953 INFO - Launching Firefox...
16:55:20.365 INFO - Checking Resource aliases
16:56:06.005 INFO - Command request: captureScreenshot[C:\Users\mpetracc\AppData\Local\Temp\testMassimo1-6162102653807059844.png, ] on session null
16:56:06.013 INFO - Creating Robot
16:56:06.617 INFO - Got result: OK on session null
16:56:06.621 INFO - Killing Firefox...
=== 2013-08-07 16:56:06,760 [main] INFO TestRunner - results for testMassimo1.rctest.html:
=== 2013-08-07 16:56:06,760 [main] ERROR TestRunner - error:TestCase results have not appeared after 45 seconds
=== 2013-08-07 16:56:07,220 [main] INFO SeleniumServerHelper - Selenium server stopped
=== 2013-08-07 16:56:07,229 [main] DEBUG XML - Parsed XML from C:\Users\mpetracc\Downloads\smartgwtpower-4.0p\selenium\testResult.ds.xml: 5ms
=== 2013-08-07 16:56:07,255 [main] DEBUG DSRequest - Caching instance 15 of DS testResult from DSRequest.getDataSource()
=== 2013-08-07 16:56:07,257 [main] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-08-07 16:56:07,258 [main] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-08-07 16:56:07,259 [main] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-08-07 16:56:07,261 [main] INFO SQLDataSource - [builtinApplication.null] Performing add operation with
criteria: {startTime:new Date(1375887311849),result:"timeout",details:"TestCase results have not appeared after 45 seconds",description:null,capture_filesize:435768,branch:"MAIN",batchStartTime:new Date(1375887310000),capture_filename:"testMassimo1.png",endTime:new Date(1375887366759),messages:null,testFile:"testMassimo1.rctest.html"} values: {startTime:new Date(1375887311849),result:"timeout",details:"TestCase results have not appeared after 45 seconds",description:null,capture_f
ilesize:435768,branch:"MAIN",batchStartTime:new Date(1375887310000),capture_filename:"testMassimo1.png",endTime:new Date(1375887366759),messages:null,testFile:"testMassimo1.rctest.html"}
=== 2013-08-07 16:56:07,262 [main] INFO SQLValuesClause - [builtinApplication.null] Ignored data for non-existent or included columns: [description]
=== 2013-08-07 16:56:07,263 [main] DEBUG SQLValuesClause - [builtinApplication.null] Sequences: {}
=== 2013-08-07 16:56:07,266 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-08-07 16:56:07,267 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '1977255737'
=== 2013-08-07 16:56:07,269 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'DB2' using connection'1977255737'
=== 2013-08-07 16:56:07,271 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'DB2': INSERT INTO testResult (batchStartTime, branch, capture, capture_filename, capture_filesize, details, endTime, messages, result, startTime, testFile) VALUES ('2013-08-07 16:55:10', 'MAIN', ?, 'testMassimo1.png', 435768, 'TestCase results have not appeared after 45 seconds', '2013-08-07 16:56:06', NULL, 'timeout', '2013-08-07 16:55:11', 'testMassimo1.rctest.html')
=== 2013-08-07 16:56:07,414 [main] DEBUG SQLDataSource - [builtinApplication.null] add operation affected 1 rows
=== 2013-08-07 16:56:07,415 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1977255737
=== 2013-08-07 16:56:07,416 [main] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1977255737"
=== 2013-08-07 16:56:07,421 [main] DEBUG DSRequest - Caching instance 9 of DS batchRun from DSRequest.getDataSource()
=== 2013-08-07 16:56:07,421 [main] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2013-08-07 16:56:07,423 [main] DEBUG AppBase - [builtinApplication.null] No userTypes defined, allowing anyone access to all operations for this application
=== 2013-08-07 16:56:07,424 [main] DEBUG AppBase - [builtinApplication.null] No public zero-argument method named '_null' found, performing generic datasource operation
=== 2013-08-07 16:56:07,427 [main] INFO SQLDataSource - [builtinApplication.null] Performing update operation with
criteria: {batchStartTime:new Date(1375887310000)} values: {batchEndTime:new Date(1375887367420)}
=== 2013-08-07 16:56:07,430 [main] INFO SQLDriver - [builtinApplication.null] DSRequest has no RPCManager set when testing if we should join a transaction - transaction will not be joined
=== 2013-08-07 16:56:07,430 [main] DEBUG SQLConnectionManager - [builtinApplication.null] Borrowed connection '1977255737'
=== 2013-08-07 16:56:07,432 [main] DEBUG SQLDriver - [builtinApplication.null] About to execute SQL update in 'DB2' using connection'1977255737'
=== 2013-08-07 16:56:07,434 [main] INFO SQLDriver - [builtinApplication.null] Executing SQL update on 'DB2': UPDATE batchRun SET batchEndTime='2013-08-07 16:56:07' WHERE (batchRun.batchStartTime='2013-08-07 16:55:10')
=== 2013-08-07 16:56:07,501 [main] DEBUG SQLDataSource - [builtinApplication.null] update operation affected 1 rows
=== 2013-08-07 16:56:07,501 [main] DEBUG SQLDriver - Freeing SQLDriver dbConnection 1977255737
=== 2013-08-07 16:56:07,503 [main] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1977255737"
C:\Users\mpetracc\Downloads\smartgwtpower-4.0p>
>>

I don't see any exception. Is it working now for you?

You have some mistakes in the TestRunner command. The -m option is for setting your mail host, not an email address, and the -lg option sets the actual log such as -lg 'this is a test' not a log file. To attach file contents as the log, you'll have to pipe it to STDIN:

type log-out.txt  | test_runner.bat -cs -sm -e masspetracca@gmail.com -lp -x