Spring Batch
  1. Spring Batch
  2. BATCH-1029

java.lang.Error: SQLWarning chain holds value that is not a SQLWarning when debug enabled in log

    Details

    • Type: Improvement Improvement
    • Status: Resolved
    • Priority: Trivial Trivial
    • Resolution: Invalid
    • Affects Version/s: 1.1.3
    • Fix Version/s: None
    • Component/s: Core
    • Labels:
      None
    • Environment:
      DB2 8.2, drivers provided by container
      was 6.1.0.21

      Description

      See the forum post for details

        Activity

        Hide
        Dave Syer added a comment -

        From the forum post it looks like a bug in the DB2 JDBC driver. Do you think there is anything we can do to fix it? (It's only a DEBUg log anyway, so how important is it?)

        Show
        Dave Syer added a comment - From the forum post it looks like a bug in the DB2 JDBC driver. Do you think there is anything we can do to fix it? (It's only a DEBUg log anyway, so how important is it?)
        Hide
        David J. M. Karlsen added a comment -

        It seems to be a weakness in the implementation of LobCreator.setBytes: http://static.springframework.org/spring/docs/2.0.x/api/org/springframework/jdbc/support/lob/LobCreator.html#setBlobAsBytes(java.sql.PreparedStatement,%20int,%20byte[]).

        The docs declare null as allowed value, although this does not seem to be the case in this environment.

        I also smell some code as this only happens when the logging is set to debug or trace.

        Show
        David J. M. Karlsen added a comment - It seems to be a weakness in the implementation of LobCreator.setBytes: http://static.springframework.org/spring/docs/2.0.x/api/org/springframework/jdbc/support/lob/LobCreator.html#setBlobAsBytes(java.sql.PreparedStatement,%20int,%20byte[ ]). The docs declare null as allowed value, although this does not seem to be the case in this environment. I also smell some code as this only happens when the logging is set to debug or trace.
        Hide
        Dave Syer added a comment -

        The fact that it only shows up in DEBUG is not that strange - to me it just means that it isn't a bug (not even informational). Does it actually cause any problems for you?

        Show
        Dave Syer added a comment - The fact that it only shows up in DEBUG is not that strange - to me it just means that it isn't a bug (not even informational). Does it actually cause any problems for you?
        Hide
        David J. M. Karlsen added a comment -

        Yes - it causes the job to not run...

        Show
        David J. M. Karlsen added a comment - Yes - it causes the job to not run...
        Hide
        Dave Syer added a comment -

        Looks like a bug in the driver to me. Can you not just set the ignoreWarnings flag in the jdbcTemplate? (Might involve creating the Dao by hand instead of using a FactoryBean, but that's not a big deal.) Or you could just switch off DEBUG for the jdbcTemplate.

        Show
        Dave Syer added a comment - Looks like a bug in the driver to me. Can you not just set the ignoreWarnings flag in the jdbcTemplate? (Might involve creating the Dao by hand instead of using a FactoryBean, but that's not a big deal.) Or you could just switch off DEBUG for the jdbcTemplate.
        Hide
        David J. M. Karlsen added a comment -

        It's not really easy configurable as none of the properties are exposed from the factory (they are all private - could have been protected - and no getters defined) - which is usually the case in the core spring framework. So I had to copy the whole class and add a line in afterPropertiesSet().

        Also notice the strange name for the variable - as it's declared as a JdbcOperations object - but the variable is named JdbcTemplate (but that's details).

        I've added some logging for you guys:

        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@60a660a6]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.getLastStepExecution]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.getLastStepExecution]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL query
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, CONTINUABLE, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME from BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = ?]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Registering transaction synchronization for JDBC Connection
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Bound value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [1], value class [java.lang.Long], SQL type unknown
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL query
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [SELECT TYPE_CD, KEY_NAME, STRING_VAL, DOUBLE_VAL, LONG_VAL, OBJECT_VAL from BATCH_EXECUTION_CONTEXT where EXECUTION_ID = ? and DISCRIMINATOR = ?]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [1], value class [java.lang.Long], SQL type unknown
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 2, parameter value [J], value class [java.lang.String], SQL type unknown
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL query
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, ITEM_COUNT, CONTINUABLE, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, ROLLBACK_COUNT from BATCH_STEP_EXECUTION where STEP_NAME = ? and JOB_EXECUTION_ID = ?]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [historicalizationStep], value class [java.lang.String], SQL type unknown
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 2, parameter value [1], value class [java.lang.Long], SQL type unknown
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Completing transaction for [org.springframework.batch.core.repository.JobRepository.getLastStepExecution]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCommit synchronization
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCompletion synchronization
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Removed value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] from thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
        10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Initiating transaction commit
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCommit synchronization
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCompletion synchronization
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Clearing transaction synchronization
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@1f461f46]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.saveOrUpdate]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdate]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Registering transaction synchronization for JDBC Connection
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Bound value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL update
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [INSERT into BATCH_STEP_EXECUTION(STEP_EXECUTION_ID, VERSION, STEP_NAME, JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, COMMIT_COUNT, ITEM_COUNT, CONTINUABLE, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, ROLLBACK_COUNT) values(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [1], value class [java.lang.Long], SQL type 4
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 2, parameter value [0], value class [java.lang.Integer], SQL type 4
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 3, parameter value [historicalizationStep], value class [java.lang.String], SQL type 12
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 4, parameter value [1], value class [java.lang.Long], SQL type 4
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 5, parameter value [Tue Feb 10 22:43:03 CET 2009], value class [java.util.Date], SQL type 93
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 6, parameter value [null], value class [null], SQL type 93
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 7, parameter value [STARTED], value class [java.lang.String], SQL type 12
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 8, parameter value [0], value class [java.lang.Integer], SQL type 4
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 9, parameter value [0], value class [java.lang.Integer], SQL type 4
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 10, parameter value [Y], value class [java.lang.String], SQL type 1
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 11, parameter value [CONTINUABLE], value class [java.lang.String], SQL type 12
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 12, parameter value [], value class [java.lang.String], SQL type 12
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 13, parameter value [0], value class [java.lang.Integer], SQL type 4
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 14, parameter value [0], value class [java.lang.Integer], SQL type 4
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 15, parameter value [0], value class [java.lang.Integer], SQL type 4
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - SQL update affected 1 rows
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Completing transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdate]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCommit synchronization
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCompletion synchronization
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Removed value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] from thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Initiating transaction commit
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCommit synchronization
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCompletion synchronization
        10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Clearing transaction synchronization
        10/02/2009 22:43:03,546 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL query
        10/02/2009 22:43:03,546 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [HIDDEN]
        10/02/2009 22:43:03,546 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource
        10/02/2009 22:43:03,546 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [EBA2], value class [java.lang.String], SQL type unknown
        10/02/2009 22:43:03,562 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 2, parameter value [java.util.GregorianCalendar[time=?,areFieldsSet=false,areAllFieldsSet=false,lenient=true,zone=sun.util.calendar.ZoneInfo[id="Europe/Berlin",offset=3600000,dstSavings=3600000,useDaylight=true,transitions=143,lastRule=java.util.SimpleTimeZone[id=Europe/Berlin,offset=3600000,dstSavings=3600000,useDaylight=true,startYear=0,startMode=2,startMonth=2,startDay=-1,startDayOfWeek=1,startTime=3600000,startTimeMode=2,endMode=2,endMonth=9,endDay=-1,endDayOfWeek=1,endTime=3600000,endTimeMode=2]],firstDayOfWeek=1,minimalDaysInFirstWeek=1,ERA=1,YEAR=2009,MONTH=1,WEEK_OF_YEAR=7,WEEK_OF_MONTH=2,DAY_OF_MONTH=9,DAY_OF_YEAR=40,DAY_OF_WEEK=2,DAY_OF_WEEK_IN_MONTH=2,AM_PM=1,HOUR=10,HOUR_OF_DAY=?,MINUTE=43,SECOND=3,MILLISECOND=78,ZONE_OFFSET=3600000,DST_OFFSET=0]], value class [java.util.GregorianCalendar], SQL type unknown
        10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
        10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@49504950]
        10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
        10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization
        10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [UPDATE BATCH_EXECUTION_CONTEXT set TYPE_CD = ?, STRING_VAL = ?, DOUBLE_VAL = ?, LONG_VAL = ?, OBJECT_VAL = ? where EXECUTION_ID = ? and KEY_NAME = ?]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Registering transaction synchronization for JDBC Connection
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Bound value [org.springframework.jdbc.datasource.ConnectionHolder@190a190a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@190a190a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Creating new DefaultLobCreator
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Set BLOB to null
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Closing DefaultLobCreator
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - SQLWarning ignored: SQL state 'null', error code '0', message [Input data type mismatch, see chained exceptions; will retry execution using describe input information. Please change application to use an input data type that matches the database column type as required by JDBC semantics.]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@190a190a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Completing transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext] after exception: java.lang.Error: SQLWarning chain holds value that is not a SQLWarning
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - Applying rules to determine whether transaction should rollback on java.lang.Error: SQLWarning chain holds value that is not a SQLWarning
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - Winning rollback rule is: null
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - No relevant rollback rule found: applying superclass default
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCompletion synchronization
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Removed value [org.springframework.jdbc.datasource.ConnectionHolder@190a190a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] from thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Initiating transaction rollback
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCompletion synchronization
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Clearing transaction synchronization
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] ERROR org.springframework.batch.core.step.AbstractStep - Encountered an error executing the step: class java.lang.Error: SQLWarning chain holds value that is not a SQLWarning
        java.lang.Error: SQLWarning chain holds value that is not a SQLWarning
        at java.sql.SQLWarning.getNextWarning(SQLWarning.java:111)
        at org.springframework.jdbc.core.JdbcTemplate.handleWarnings(JdbcTemplate.java:1112)
        at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:539)
        at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:566)
        at org.springframework.batch.core.repository.dao.JdbcExecutionContextDao.updateExecutionAttribute(JdbcExecutionContextDao.java:187)
        at org.springframework.batch.core.repository.dao.JdbcExecutionContextDao.saveOrUpdateExecutionContext(JdbcExecutionContextDao.java:124)
        at org.springframework.batch.core.repository.dao.JdbcExecutionContextDao.saveOrUpdateExecutionContext(JdbcExecutionContextDao.java:109)
        at org.springframework.batch.core.repository.dao.JdbcStepExecutionDao.saveOrUpdateExecutionContext(JdbcStepExecutionDao.java:140)
        at org.springframework.batch.core.repository.support.SimpleJobRepository.saveOrUpdateExecutionContext(SimpleJobRepository.java:254)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:618)
        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:304)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
        at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at $Proxy1.saveOrUpdateExecutionContext(Unknown Source)
        at org.springframework.batch.core.step.item.ItemOrientedStep.doExecute(ItemOrientedStep.java:231)
        at org.springframework.batch.core.step.AbstractStep.execute(AbstractStep.java:172)
        at org.springframework.batch.core.job.SimpleJob.execute(SimpleJob.java:103)
        at org.springframework.batch.core.configuration.support.ClassPathXmlApplicationContextJobFactory$ContextClosingJob.execute(ClassPathXmlApplicationContextJobFactory.java:107)
        at org.springframework.batch.core.launch.support.SimpleJobLauncher$1.run(SimpleJobLauncher.java:86)
        at org.springframework.scheduling.commonj.DelegatingWork.run(DelegatingWork.java:61)
        at com.ibm.ws.asynchbeans.J2EEContext.run(J2EEContext.java:1114)
        at com.ibm.ws.asynchbeans.WorkWithExecutionContextImpl.go(WorkWithExecutionContextImpl.java:195)
        at com.ibm.ws.asynchbeans.CJWorkItemImpl.run(CJWorkItemImpl.java:187)
        at com.ibm.ws.util.ThreadPool$Worker.run(ThreadPool.java:1473)
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@51245124]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [UPDATE BATCH_EXECUTION_CONTEXT set TYPE_CD = ?, STRING_VAL = ?, DOUBLE_VAL = ?, LONG_VAL = ?, OBJECT_VAL = ? where EXECUTION_ID = ? and KEY_NAME = ?]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Registering transaction synchronization for JDBC Connection
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Bound value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Creating new DefaultLobCreator
        10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Set BLOB to null
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Closing DefaultLobCreator
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - SQLWarning ignored: SQL state '02000', error code '100', message [DB2 SQL error: SQLCODE: 100, SQLSTATE: 02000, SQLERRMC: null]
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [INSERT into BATCH_EXECUTION_CONTEXT(EXECUTION_ID, DISCRIMINATOR, TYPE_CD, KEY_NAME, STRING_VAL, DOUBLE_VAL, LONG_VAL, OBJECT_VAL) values(?,?,?,?,?,?,?,?)]
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Creating new DefaultLobCreator
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Set BLOB to null
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Closing DefaultLobCreator
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - SQLWarning ignored: SQL state 'null', error code '0', message [Input data type mismatch, see chained exceptions; will retry execution using describe input information. Please change application to use an input data type that matches the database column type as required by JDBC semantics.]
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Completing transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext] after exception: java.lang.Error: SQLWarning chain holds value that is not a SQLWarning
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - Applying rules to determine whether transaction should rollback on java.lang.Error: SQLWarning chain holds value that is not a SQLWarning
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - Winning rollback rule is: null
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - No relevant rollback rule found: applying superclass default
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCompletion synchronization
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Removed value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] from thread [WorkManager.Thread : 1]
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource
        10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Initiating transaction rollback
        10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCompletion synchronization
        10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Clearing transaction synchronization
        10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@421a421a]
        10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.saveOrUpdate]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
        10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization
        10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdate]
        10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.batch.core.repository.dao.JdbcStepExecutionDao - Truncating long message before update of StepExecution, original message is: java.lang.Error: SQLWarning chain holds value that is not a SQLWarning

        From the errorcodes - could this be due to returning no rows initially (when no batches has run initially)?
        See http://jira.springframework.org/browse/SPR-2316

        Show
        David J. M. Karlsen added a comment - It's not really easy configurable as none of the properties are exposed from the factory (they are all private - could have been protected - and no getters defined) - which is usually the case in the core spring framework. So I had to copy the whole class and add a line in afterPropertiesSet(). Also notice the strange name for the variable - as it's declared as a JdbcOperations object - but the variable is named JdbcTemplate (but that's details). I've added some logging for you guys: 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@60a660a6] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.getLastStepExecution] : PROPAGATION_REQUIRED,ISOLATION_DEFAULT 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.getLastStepExecution] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL query 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, CONTINUABLE, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME from BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = ?] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Registering transaction synchronization for JDBC Connection 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Bound value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [1] , value class [java.lang.Long] , SQL type unknown 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL query 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [SELECT TYPE_CD, KEY_NAME, STRING_VAL, DOUBLE_VAL, LONG_VAL, OBJECT_VAL from BATCH_EXECUTION_CONTEXT where EXECUTION_ID = ? and DISCRIMINATOR = ?] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [1] , value class [java.lang.Long] , SQL type unknown 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 2, parameter value [J] , value class [java.lang.String] , SQL type unknown 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL query 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, ITEM_COUNT, CONTINUABLE, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, ROLLBACK_COUNT from BATCH_STEP_EXECUTION where STEP_NAME = ? and JOB_EXECUTION_ID = ?] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [historicalizationStep] , value class [java.lang.String] , SQL type unknown 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 2, parameter value [1] , value class [java.lang.Long] , SQL type unknown 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Completing transaction for [org.springframework.batch.core.repository.JobRepository.getLastStepExecution] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCommit synchronization 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCompletion synchronization 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Removed value [org.springframework.jdbc.datasource.ConnectionHolder@339a339a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] from thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource 10/02/2009 22:43:03,062 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Initiating transaction commit 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCommit synchronization 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCompletion synchronization 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Clearing transaction synchronization 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@1f461f46] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.saveOrUpdate] : PROPAGATION_REQUIRED,ISOLATION_DEFAULT 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdate] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Registering transaction synchronization for JDBC Connection 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Bound value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL update 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [INSERT into BATCH_STEP_EXECUTION(STEP_EXECUTION_ID, VERSION, STEP_NAME, JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, COMMIT_COUNT, ITEM_COUNT, CONTINUABLE, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, ROLLBACK_COUNT) values(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [1] , value class [java.lang.Long] , SQL type 4 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 2, parameter value [0] , value class [java.lang.Integer] , SQL type 4 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 3, parameter value [historicalizationStep] , value class [java.lang.String] , SQL type 12 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 4, parameter value [1] , value class [java.lang.Long] , SQL type 4 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 5, parameter value [Tue Feb 10 22:43:03 CET 2009] , value class [java.util.Date] , SQL type 93 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 6, parameter value [null] , value class [null] , SQL type 93 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 7, parameter value [STARTED] , value class [java.lang.String] , SQL type 12 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 8, parameter value [0] , value class [java.lang.Integer] , SQL type 4 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 9, parameter value [0] , value class [java.lang.Integer] , SQL type 4 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 10, parameter value [Y] , value class [java.lang.String] , SQL type 1 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 11, parameter value [CONTINUABLE] , value class [java.lang.String] , SQL type 12 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 12, parameter value [], value class [java.lang.String] , SQL type 12 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 13, parameter value [0] , value class [java.lang.Integer] , SQL type 4 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 14, parameter value [0] , value class [java.lang.Integer] , SQL type 4 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 15, parameter value [0] , value class [java.lang.Integer] , SQL type 4 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - SQL update affected 1 rows 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Completing transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdate] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCommit synchronization 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCompletion synchronization 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Removed value [org.springframework.jdbc.datasource.ConnectionHolder@2cd42cd4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] from thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Initiating transaction commit 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCommit synchronization 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCompletion synchronization 10/02/2009 22:43:03,078 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Clearing transaction synchronization 10/02/2009 22:43:03,546 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL query 10/02/2009 22:43:03,546 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [HIDDEN] 10/02/2009 22:43:03,546 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource 10/02/2009 22:43:03,546 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 1, parameter value [EBA2] , value class [java.lang.String] , SQL type unknown 10/02/2009 22:43:03,562 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.StatementCreatorUtils - Setting SQL statement parameter value: column index 2, parameter value [java.util.GregorianCalendar[time=?,areFieldsSet=false,areAllFieldsSet=false,lenient=true,zone=sun.util.calendar.ZoneInfo[id="Europe/Berlin",offset=3600000,dstSavings=3600000,useDaylight=true,transitions=143,lastRule=java.util.SimpleTimeZone [id=Europe/Berlin,offset=3600000,dstSavings=3600000,useDaylight=true,startYear=0,startMode=2,startMonth=2,startDay=-1,startDayOfWeek=1,startTime=3600000,startTimeMode=2,endMode=2,endMonth=9,endDay=-1,endDayOfWeek=1,endTime=3600000,endTimeMode=2] ],firstDayOfWeek=1,minimalDaysInFirstWeek=1,ERA=1,YEAR=2009,MONTH=1,WEEK_OF_YEAR=7,WEEK_OF_MONTH=2,DAY_OF_MONTH=9,DAY_OF_YEAR=40,DAY_OF_WEEK=2,DAY_OF_WEEK_IN_MONTH=2,AM_PM=1,HOUR=10,HOUR_OF_DAY=?,MINUTE=43,SECOND=3,MILLISECOND=78,ZONE_OFFSET=3600000,DST_OFFSET=0]], value class [java.util.GregorianCalendar] , SQL type unknown 10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource 10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@49504950] 10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext] : PROPAGATION_REQUIRED,ISOLATION_DEFAULT 10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization 10/02/2009 22:43:03,687 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [UPDATE BATCH_EXECUTION_CONTEXT set TYPE_CD = ?, STRING_VAL = ?, DOUBLE_VAL = ?, LONG_VAL = ?, OBJECT_VAL = ? where EXECUTION_ID = ? and KEY_NAME = ?] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Registering transaction synchronization for JDBC Connection 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Bound value [org.springframework.jdbc.datasource.ConnectionHolder@190a190a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@190a190a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Creating new DefaultLobCreator 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Set BLOB to null 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Closing DefaultLobCreator 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - SQLWarning ignored: SQL state 'null', error code '0', message [Input data type mismatch, see chained exceptions; will retry execution using describe input information. Please change application to use an input data type that matches the database column type as required by JDBC semantics.] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@190a190a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Completing transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext] after exception: java.lang.Error: SQLWarning chain holds value that is not a SQLWarning 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - Applying rules to determine whether transaction should rollback on java.lang.Error: SQLWarning chain holds value that is not a SQLWarning 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - Winning rollback rule is: null 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - No relevant rollback rule found: applying superclass default 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCompletion synchronization 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Removed value [org.springframework.jdbc.datasource.ConnectionHolder@190a190a] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] from thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Initiating transaction rollback 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCompletion synchronization 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Clearing transaction synchronization 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] ERROR org.springframework.batch.core.step.AbstractStep - Encountered an error executing the step: class java.lang.Error: SQLWarning chain holds value that is not a SQLWarning java.lang.Error: SQLWarning chain holds value that is not a SQLWarning at java.sql.SQLWarning.getNextWarning(SQLWarning.java:111) at org.springframework.jdbc.core.JdbcTemplate.handleWarnings(JdbcTemplate.java:1112) at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:539) at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:566) at org.springframework.batch.core.repository.dao.JdbcExecutionContextDao.updateExecutionAttribute(JdbcExecutionContextDao.java:187) at org.springframework.batch.core.repository.dao.JdbcExecutionContextDao.saveOrUpdateExecutionContext(JdbcExecutionContextDao.java:124) at org.springframework.batch.core.repository.dao.JdbcExecutionContextDao.saveOrUpdateExecutionContext(JdbcExecutionContextDao.java:109) at org.springframework.batch.core.repository.dao.JdbcStepExecutionDao.saveOrUpdateExecutionContext(JdbcStepExecutionDao.java:140) at org.springframework.batch.core.repository.support.SimpleJobRepository.saveOrUpdateExecutionContext(SimpleJobRepository.java:254) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:618) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:304) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at $Proxy1.saveOrUpdateExecutionContext(Unknown Source) at org.springframework.batch.core.step.item.ItemOrientedStep.doExecute(ItemOrientedStep.java:231) at org.springframework.batch.core.step.AbstractStep.execute(AbstractStep.java:172) at org.springframework.batch.core.job.SimpleJob.execute(SimpleJob.java:103) at org.springframework.batch.core.configuration.support.ClassPathXmlApplicationContextJobFactory$ContextClosingJob.execute(ClassPathXmlApplicationContextJobFactory.java:107) at org.springframework.batch.core.launch.support.SimpleJobLauncher$1.run(SimpleJobLauncher.java:86) at org.springframework.scheduling.commonj.DelegatingWork.run(DelegatingWork.java:61) at com.ibm.ws.asynchbeans.J2EEContext.run(J2EEContext.java:1114) at com.ibm.ws.asynchbeans.WorkWithExecutionContextImpl.go(WorkWithExecutionContextImpl.java:195) at com.ibm.ws.asynchbeans.CJWorkItemImpl.run(CJWorkItemImpl.java:187) at com.ibm.ws.util.ThreadPool$Worker.run(ThreadPool.java:1473) 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@51245124] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext] : PROPAGATION_REQUIRED,ISOLATION_DEFAULT 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [UPDATE BATCH_EXECUTION_CONTEXT set TYPE_CD = ?, STRING_VAL = ?, DOUBLE_VAL = ?, LONG_VAL = ?, OBJECT_VAL = ? where EXECUTION_ID = ? and KEY_NAME = ?] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Fetching JDBC Connection from DataSource 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Registering transaction synchronization for JDBC Connection 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Bound value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Creating new DefaultLobCreator 10/02/2009 22:43:03,703 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Set BLOB to null 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Closing DefaultLobCreator 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - SQLWarning ignored: SQL state '02000', error code '100', message [DB2 SQL error: SQLCODE: 100, SQLSTATE: 02000, SQLERRMC: null] 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - Executing prepared SQL statement [INSERT into BATCH_EXECUTION_CONTEXT(EXECUTION_ID, DISCRIMINATOR, TYPE_CD, KEY_NAME, STRING_VAL, DOUBLE_VAL, LONG_VAL, OBJECT_VAL) values(?,?,?,?,?,?,?,?)] 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Creating new DefaultLobCreator 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Set BLOB to null 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.support.lob.DefaultLobHandler - Closing DefaultLobCreator 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.core.JdbcTemplate - SQLWarning ignored: SQL state 'null', error code '0', message [Input data type mismatch, see chained exceptions; will retry execution using describe input information. Please change application to use an input data type that matches the database column type as required by JDBC semantics.] 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] bound to thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Completing transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdateExecutionContext] after exception: java.lang.Error: SQLWarning chain holds value that is not a SQLWarning 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - Applying rules to determine whether transaction should rollback on java.lang.Error: SQLWarning chain holds value that is not a SQLWarning 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - Winning rollback rule is: null 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.RuleBasedTransactionAttribute - No relevant rollback rule found: applying superclass default 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering beforeCompletion synchronization 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Removed value [org.springframework.jdbc.datasource.ConnectionHolder@20d420d4] for key [com.ibm.ws.rsadapter.jdbc.WSJdbcDataSource@f16d59a7] from thread [WorkManager.Thread : 1] 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.jdbc.datasource.DataSourceUtils - Returning JDBC Connection to DataSource 10/02/2009 22:43:03,718 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Initiating transaction rollback 10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Triggering afterCompletion synchronization 10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Clearing transaction synchronization 10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@421a421a] 10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.jta.JtaTransactionManager - Creating new transaction with name [org.springframework.batch.core.repository.JobRepository.saveOrUpdate] : PROPAGATION_REQUIRED,ISOLATION_DEFAULT 10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Initializing transaction synchronization 10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.transaction.interceptor.TransactionInterceptor - Getting transaction for [org.springframework.batch.core.repository.JobRepository.saveOrUpdate] 10/02/2009 22:43:03,796 [WorkManager.Thread : 1] DEBUG org.springframework.batch.core.repository.dao.JdbcStepExecutionDao - Truncating long message before update of StepExecution, original message is: java.lang.Error: SQLWarning chain holds value that is not a SQLWarning From the errorcodes - could this be due to returning no rows initially (when no batches has run initially)? See http://jira.springframework.org/browse/SPR-2316
        Hide
        David J. M. Karlsen added a comment -

        1.1.4 can also be added for the "affects version" attribute

        Show
        David J. M. Karlsen added a comment - 1.1.4 can also be added for the "affects version" attribute
        Hide
        Dave Syer added a comment -

        It seems clear that a) there is a trivial workaround (set ignoreWarnings=true in the template when the DAO is created, and switch off DEBUG logging in JdbcTemplate); and b) this is not a Batch issue anyway. So I'm going to mark as resolved invalid. Please open another one in SPR-* if you dissagree,

        Show
        Dave Syer added a comment - It seems clear that a) there is a trivial workaround (set ignoreWarnings=true in the template when the DAO is created, and switch off DEBUG logging in JdbcTemplate); and b) this is not a Batch issue anyway. So I'm going to mark as resolved invalid. Please open another one in SPR-* if you dissagree,

          People

          • Assignee:
            Thomas Risberg
            Reporter:
            David J. M. Karlsen
          • Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

            Dates

            • Created:
              Updated:
              Resolved: