Uploaded image for project: 'Debezium'
  1. Debezium
  2. DBZ-3901

Intermittent test failures on CI: IncrementalSnapshotIT#updatesWithRestart

    XMLWordPrintable

Details

    Description

      Seeing this one repeatedly, e.g. here:

      2021-07-29T06:40:13.6105503Z 2021-07-29 06:40:10,797 ERROR  IncrementalSnapshotIT||engine  Error while trying to run connector class 'io.debezium.connector.db2.Db2Connector'   [io.debezium.connector.db2.IncrementalSnapshotIT]
      2021-07-29T06:40:13.6107679Z org.apache.kafka.connect.errors.ConnectException: An exception occurred in the change event producer. This connector will be stopped.
      2021-07-29T06:40:13.6110334Z 	at io.debezium.pipeline.ErrorHandler.setProducerThrowable(ErrorHandler.java:42)
      2021-07-29T06:40:13.6112863Z 	at io.debezium.connector.db2.Db2StreamingChangeEventSource.execute(Db2StreamingChangeEventSource.java:257)
      2021-07-29T06:40:13.6115679Z 	at io.debezium.connector.db2.Db2StreamingChangeEventSource.execute(Db2StreamingChangeEventSource.java:57)
      2021-07-29T06:40:13.6118116Z 	at io.debezium.pipeline.ChangeEventSourceCoordinator.streamEvents(ChangeEventSourceCoordinator.java:172)
      2021-07-29T06:40:13.6120458Z 	at io.debezium.pipeline.ChangeEventSourceCoordinator.lambda$start$0(ChangeEventSourceCoordinator.java:134)
      2021-07-29T06:40:13.6122138Z 	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
      2021-07-29T06:40:13.6123222Z 	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
      2021-07-29T06:40:13.6124956Z 	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
      2021-07-29T06:40:13.6126598Z 	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
      2021-07-29T06:40:13.6127658Z 	at java.base/java.lang.Thread.run(Thread.java:829)
      2021-07-29T06:40:13.6128980Z Caused by: org.apache.kafka.connect.errors.ConnectException: Error while processing event at offset {transaction_id=null, event_serial_no=1, commit_lsn=00000000:00003150:0000000000059ea0, change_lsn=00000000:00000000:00000000055ac887}
      2021-07-29T06:40:13.6131134Z 	at io.debezium.pipeline.EventDispatcher.dispatchDataChangeEvent(EventDispatcher.java:254)
      2021-07-29T06:40:13.6133569Z 	at io.debezium.connector.db2.Db2StreamingChangeEventSource.lambda$execute$1(Db2StreamingChangeEventSource.java:236)
      2021-07-29T06:40:13.6135676Z 	at io.debezium.jdbc.JdbcConnection.prepareQuery(JdbcConnection.java:613)
      2021-07-29T06:40:13.6137378Z 	at io.debezium.connector.db2.Db2Connection.getChangesForTables(Db2Connection.java:176)
      2021-07-29T06:40:13.6139936Z 	at io.debezium.connector.db2.Db2StreamingChangeEventSource.execute(Db2StreamingChangeEventSource.java:153)
      2021-07-29T06:40:13.6141374Z 	... 8 more
      2021-07-29T06:40:13.6141894Z Caused by: java.lang.NullPointerException
      2021-07-29T06:40:13.6142649Z 	at java.base/java.lang.String.startsWith(String.java:1428)
      2021-07-29T06:40:13.6143437Z 	at java.base/java.lang.String.startsWith(String.java:1470)
      2021-07-29T06:40:13.6146246Z 	at io.debezium.pipeline.source.snapshot.incremental.AbstractIncrementalSnapshotContext.openWindow(AbstractIncrementalSnapshotContext.java:81)
      2021-07-29T06:40:13.6150262Z 	at io.debezium.pipeline.source.snapshot.incremental.OpenIncrementalSnapshotWindow.arrived(OpenIncrementalSnapshotWindow.java:25)
      2021-07-29T06:40:13.6153291Z 	at io.debezium.pipeline.signal.Signal.process(Signal.java:136)
      2021-07-29T06:40:13.6154454Z 	at io.debezium.pipeline.signal.Signal.process(Signal.java:180)
      2021-07-29T06:40:13.6155685Z 	at io.debezium.pipeline.EventDispatcher$2.changeRecord(EventDispatcher.java:228)
      2021-07-29T06:40:13.6158239Z 	at io.debezium.relational.RelationalChangeRecordEmitter.emitCreateRecord(RelationalChangeRecordEmitter.java:77)
      2021-07-29T06:40:13.6161814Z 	at io.debezium.relational.RelationalChangeRecordEmitter.emitChangeRecords(RelationalChangeRecordEmitter.java:45)
      2021-07-29T06:40:13.6165159Z 	at io.debezium.pipeline.EventDispatcher.dispatchDataChangeEvent(EventDispatcher.java:218)
      2021-07-29T06:40:13.6166983Z 	... 12 more
      2021-07-29T06:41:42.9367785Z 2021-07-29 06:41:42,935 INFO   IncrementalSnapshotIT||test  Connection gracefully closed   [io.debezium.jdbc.JdbcConnection]
      2021-07-29T06:41:42.9382545Z 2021-07-29 06:41:42,936 INFO   IncrementalSnapshotIT||test  Stopping the connector   [io.debezium.connector.db2.IncrementalSnapshotIT]
      2021-07-29T06:41:42.9385099Z 2021-07-29 06:41:42,936 INFO   IncrementalSnapshotIT||test  Interrupting the engine   [io.debezium.connector.db2.IncrementalSnapshotIT]
      2021-07-29T06:41:42.9389178Z 2021-07-29 06:41:42,937 INFO   IncrementalSnapshotIT||test  Test io.debezium.connector.db2.IncrementalSnapshotIT#updatesWithRestart failed   [io.debezium.connector.db2.IncrementalSnapshotIT]
      

      Attachments

        Activity

          People

            jpechane Jiri Pechanec
            gunnar.morling Gunnar Morling
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: