Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

java.lang.Error: SQLWarning chain holds value that is not a SQLWarning when debug enabled in log [BATCH-1029] #2548

Closed
spring-issuemaster opened this issue Jan 26, 2009 · 8 comments

Comments

@spring-issuemaster
Copy link
Collaborator

@spring-issuemaster spring-issuemaster commented Jan 26, 2009

David J. M. Karlsen opened BATCH-1029 and commented

See the forum post for details


Affects: 1.1.3

Reference URL: http://forum.springframework.org/showthread.php?t=65253&highlight=debug

Attachments:

@spring-issuemaster

This comment has been minimized.

Copy link
Collaborator Author

@spring-issuemaster spring-issuemaster commented Jan 26, 2009

Dave Syer commented

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?)

@spring-issuemaster

This comment has been minimized.

Copy link
Collaborator Author

@spring-issuemaster spring-issuemaster commented Jan 28, 2009

David J. M. Karlsen commented

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.

@spring-issuemaster

This comment has been minimized.

Copy link
Collaborator Author

@spring-issuemaster spring-issuemaster commented Jan 29, 2009

Dave Syer commented

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?

@spring-issuemaster

This comment has been minimized.

Copy link
Collaborator Author

@spring-issuemaster spring-issuemaster commented Jan 29, 2009

David J. M. Karlsen commented

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

@spring-issuemaster

This comment has been minimized.

Copy link
Collaborator Author

@spring-issuemaster spring-issuemaster commented Jan 29, 2009

Dave Syer commented

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.

@spring-issuemaster

This comment has been minimized.

Copy link
Collaborator Author

@spring-issuemaster spring-issuemaster commented Feb 10, 2009

David J. M. Karlsen commented

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

@spring-issuemaster

This comment has been minimized.

Copy link
Collaborator Author

@spring-issuemaster spring-issuemaster commented Feb 10, 2009

David J. M. Karlsen commented

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

@spring-issuemaster

This comment has been minimized.

Copy link
Collaborator Author

@spring-issuemaster spring-issuemaster commented Mar 25, 2010

Dave Syer commented

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,

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
1 participant
You can’t perform that action at this time.