Uploaded image for project: 'Debezium'
  1. Debezium
  2. DBZ-2268 Intermittent test failures
  3. DBZ-2474

Intermittent test failure on CI - AbstractSqlServerDatatypesTest#stringTypes()

XMLWordPrintable

      See https://travis-ci.org/github/debezium/debezium/jobs/723384407

      [ERROR] Failures:
      [ERROR] DatatypesFromSnapshotIT>AbstractSqlServerDatatypesTest.stringTypes:244 expecting actual value not to be null

      2020-09-02 10:02:06,158 INFO   CaseSensitivenessIT||test  Starting test io.debezium.connector.sqlserver.DatatypesFromSnapshotIT#stringTypes   [io.debezium.connector.sqlserver.DatatypesFromSnapshotIT]
      2020-09-02 10:02:06,160 INFO   DatatypesFromSnapshotIT||test  Worker configuration property 'internal.key.converter' is deprecated and may be removed in an upcoming release. The specified value 'org.apache.kafka.connect.json.JsonConverter' matches the default, so this property can be safely removed from the worker configuration.   [org.apache.kafka.connect.runtime.WorkerConfig]
      2020-09-02 10:02:06,160 INFO   DatatypesFromSnapshotIT||test  Worker configuration property 'internal.value.converter' is deprecated and may be removed in an upcoming release. The specified value 'org.apache.kafka.connect.json.JsonConverter' matches the default, so this property can be safely removed from the worker configuration.   [org.apache.kafka.connect.runtime.WorkerConfig]
      2020-09-02 10:02:06,164 WARN   DatatypesFromSnapshotIT||test  Variables cannot be used in the 'plugin.path' property, since the property is used by plugin scanning before the config providers that replace the variables are initialized. The raw value 'null' was used for plugin scanning, as opposed to the transformed value 'null', and this may cause unexpected results.   [org.apache.kafka.connect.runtime.WorkerConfig]
      2020-09-02 10:02:06,165 INFO   DatatypesFromSnapshotIT||engine  Starting FileOffsetBackingStore with file /home/travis/build/debezium/debezium/debezium-connector-sqlserver/target/data/file-connector-offsets.txt   [org.apache.kafka.connect.storage.FileOffsetBackingStore]
      2020-09-02 10:02:06,166 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "column.whitelist" is deprecated and will be removed in future versions. Please use "column.include.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,166 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "column.blacklist" is deprecated and will be removed in future versions. Please use "column.exclude.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,166 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "column.whitelist" is deprecated and will be removed in future versions. Please use "column.include.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,166 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "column.blacklist" is deprecated and will be removed in future versions. Please use "column.exclude.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,166 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "table.whitelist" is deprecated and will be removed in future versions. Please use "table.include.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,166 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "table.blacklist" is deprecated and will be removed in future versions. Please use "table.exclude.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,166 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "table.whitelist" is deprecated and will be removed in future versions. Please use "table.include.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,166 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "table.blacklist" is deprecated and will be removed in future versions. Please use "table.exclude.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine  Starting SqlServerConnectorTask with configuration:   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     connector.class = io.debezium.connector.sqlserver.SqlServerConnector   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     database.history.file.filename = /home/travis/build/debezium/debezium/debezium-connector-sqlserver/target/data/file-db-history-connect.txt   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     database.user = sa   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     database.dbname = testdb   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     time.precision.mode = adaptive   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     database.server.name = server1   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     include.schema.changes = false   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     database.port = 1433   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     offset.flush.interval.ms = 0   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     offset.storage.file.filename = /home/travis/build/debezium/debezium/debezium-connector-sqlserver/target/data/file-connector-offsets.txt   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     database.hostname = localhost   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     database.password = ********   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     name = testing-connector   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     snapshot.mode = initial   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,166 INFO   DatatypesFromSnapshotIT||engine     database.history = io.debezium.relational.history.FileDatabaseHistory   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:06,167 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "schema.whitelist" is deprecated and will be removed in future versions. Please use "schema.include.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,167 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "schema.blacklist" is deprecated and will be removed in future versions. Please use "schema.exclude.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,167 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "table.whitelist" is deprecated and will be removed in future versions. Please use "table.include.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,167 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "table.blacklist" is deprecated and will be removed in future versions. Please use "table.exclude.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,167 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "column.blacklist" is deprecated and will be removed in future versions. Please use "column.exclude.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,249 WARN   DatatypesFromSnapshotIT||engine  Unable to register the MBean 'debezium.sql_server:type=connector-metrics,context=schema-history,server=server1': debezium.sql_server:type=connector-metrics,context=schema-history,server=server1   [io.debezium.relational.history.DatabaseHistoryMetrics]
      2020-09-02 10:02:06,253 INFO   DatatypesFromSnapshotIT||engine  Requested thread factory for connector SqlServerConnector, id = server1 named = change-event-source-coordinator   [io.debezium.util.Threads]
      2020-09-02 10:02:06,255 INFO   DatatypesFromSnapshotIT||engine  Creating thread debezium-sqlserverconnector-server1-change-event-source-coordinator   [io.debezium.util.Threads]
      2020-09-02 10:02:06,263 INFO   DatatypesFromSnapshotIT||engine  Metrics registered   [io.debezium.pipeline.ChangeEventSourceCoordinator]
      2020-09-02 10:02:06,263 INFO   DatatypesFromSnapshotIT||engine  Context created   [io.debezium.pipeline.ChangeEventSourceCoordinator]
      2020-09-02 10:02:06,264 INFO   DatatypesFromSnapshotIT||engine  No previous offset has been found   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:06,264 INFO   DatatypesFromSnapshotIT||engine  According to the connector configuration both schema and data will be snapshotted   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:06,264 INFO   DatatypesFromSnapshotIT||engine  Snapshot step 1 - Preparing   [io.debezium.relational.RelationalSnapshotChangeEventSource]
      2020-09-02 10:02:06,265 INFO   DatatypesFromSnapshotIT||engine  Snapshot step 2 - Determining captured tables   [io.debezium.relational.RelationalSnapshotChangeEventSource]
      2020-09-02 10:02:06,373 WARN   DatatypesFromSnapshotIT||engine  Using configuration property "table.whitelist" is deprecated and will be removed in future versions. Please use "table.include.list" instead.   [io.debezium.config.Configuration]
      2020-09-02 10:02:06,373 INFO   DatatypesFromSnapshotIT||engine  Snapshot step 3 - Locking captured tables   [io.debezium.relational.RelationalSnapshotChangeEventSource]
      2020-09-02 10:02:06,373 INFO   DatatypesFromSnapshotIT||engine  Setting locking timeout to 10 s   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:06,375 INFO   DatatypesFromSnapshotIT||engine  Executing schema locking   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:06,375 INFO   DatatypesFromSnapshotIT||engine  Locking table testDB.dbo.type_fp   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:06,376 INFO   DatatypesFromSnapshotIT||engine  Locking table testDB.dbo.type_int   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:06,380 INFO   DatatypesFromSnapshotIT||engine  Locking table testDB.dbo.type_string   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:06,381 INFO   DatatypesFromSnapshotIT||engine  Locking table testDB.dbo.type_time   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:06,382 INFO   DatatypesFromSnapshotIT||engine  Locking table testDB.dbo.type_xml   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:06,383 INFO   DatatypesFromSnapshotIT||engine  Snapshot step 4 - Determining snapshot offset   [io.debezium.relational.RelationalSnapshotChangeEventSource]
      Inserted
      2020-09-02 10:02:08,572 INFO   DatatypesFromSnapshotIT||engine  Snapshot - Final stage   [io.debezium.pipeline.source.AbstractSnapshotChangeEventSource]
      2020-09-02 10:02:08,573 INFO   DatatypesFromSnapshotIT||engine  Removing locking timeout   [io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource]
      2020-09-02 10:02:08,574 ERROR  DatatypesFromSnapshotIT||engine  Producer failure   [io.debezium.pipeline.ErrorHandler]
      java.lang.RuntimeException: com.microsoft.sqlserver.jdbc.SQLServerException: Transaction (Process ID 51) was deadlocked on lock resources with another process and has been chosen as the deadlock victim. Rerun the transaction.
      	at io.debezium.pipeline.source.AbstractSnapshotChangeEventSource.execute(AbstractSnapshotChangeEventSource.java:76)
      	at io.debezium.pipeline.ChangeEventSourceCoordinator.lambda$start$0(ChangeEventSourceCoordinator.java:96)
      	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
      	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
      	at java.lang.Thread.run(Thread.java:748)
      Caused by: com.microsoft.sqlserver.jdbc.SQLServerException: Transaction (Process ID 51) was deadlocked on lock resources with another process and has been chosen as the deadlock victim. Rerun the transaction.
      	at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDatabaseError(SQLServerException.java:262)
      	at com.microsoft.sqlserver.jdbc.SQLServerResultSet$FetchBuffer.nextRow(SQLServerResultSet.java:5427)
      	at com.microsoft.sqlserver.jdbc.SQLServerResultSet.fetchBufferNext(SQLServerResultSet.java:1758)
      	at com.microsoft.sqlserver.jdbc.SQLServerResultSet.next(SQLServerResultSet.java:1016)
      	at io.debezium.jdbc.JdbcConnection.lambda$singleResultMapper$6(JdbcConnection.java:1237)
      	at io.debezium.jdbc.JdbcConnection.queryAndMap(JdbcConnection.java:575)
      	at io.debezium.jdbc.JdbcConnection.queryAndMap(JdbcConnection.java:443)
      	at io.debezium.connector.sqlserver.SqlServerConnection.getMaxLsn(SqlServerConnection.java:129)
      	at io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource.determineSnapshotOffset(SqlServerSnapshotChangeEventSource.java:158)
      	at io.debezium.relational.RelationalSnapshotChangeEventSource.doExecute(RelationalSnapshotChangeEventSource.java:120)
      	at io.debezium.pipeline.source.AbstractSnapshotChangeEventSource.execute(AbstractSnapshotChangeEventSource.java:63)
      	... 6 more
      2020-09-02 10:02:08,574 INFO   DatatypesFromSnapshotIT||engine  Connected metrics set to 'false'   [io.debezium.pipeline.metrics.StreamingChangeEventSourceMetrics]
      2020-09-02 10:02:08,753 INFO   DatatypesFromSnapshotIT||engine  Stopping down connector   [io.debezium.connector.common.BaseSourceTask]
      2020-09-02 10:02:08,754 INFO   DatatypesFromSnapshotIT||engine  Stopped FileOffsetBackingStore   [org.apache.kafka.connect.storage.FileOffsetBackingStore]
      2020-09-02 10:02:08,754 ERROR  DatatypesFromSnapshotIT||engine  Error while trying to run connector class 'io.debezium.connector.sqlserver.SqlServerConnector'   [io.debezium.connector.sqlserver.DatatypesFromSnapshotIT]
      org.apache.kafka.connect.errors.ConnectException: An exception occurred in the change event producer. This connector will be stopped.
      	at io.debezium.pipeline.ErrorHandler.setProducerThrowable(ErrorHandler.java:42)
      	at io.debezium.pipeline.ChangeEventSourceCoordinator.lambda$start$0(ChangeEventSourceCoordinator.java:117)
      	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
      	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
      	at java.lang.Thread.run(Thread.java:748)
      Caused by: java.lang.RuntimeException: com.microsoft.sqlserver.jdbc.SQLServerException: Transaction (Process ID 51) was deadlocked on lock resources with another process and has been chosen as the deadlock victim. Rerun the transaction.
      	at io.debezium.pipeline.source.AbstractSnapshotChangeEventSource.execute(AbstractSnapshotChangeEventSource.java:76)
      	at io.debezium.pipeline.ChangeEventSourceCoordinator.lambda$start$0(ChangeEventSourceCoordinator.java:96)
      	... 5 more
      Caused by: com.microsoft.sqlserver.jdbc.SQLServerException: Transaction (Process ID 51) was deadlocked on lock resources with another process and has been chosen as the deadlock victim. Rerun the transaction.
      	at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDatabaseError(SQLServerException.java:262)
      	at com.microsoft.sqlserver.jdbc.SQLServerResultSet$FetchBuffer.nextRow(SQLServerResultSet.java:5427)
      	at com.microsoft.sqlserver.jdbc.SQLServerResultSet.fetchBufferNext(SQLServerResultSet.java:1758)
      	at com.microsoft.sqlserver.jdbc.SQLServerResultSet.next(SQLServerResultSet.java:1016)
      	at io.debezium.jdbc.JdbcConnection.lambda$singleResultMapper$6(JdbcConnection.java:1237)
      	at io.debezium.jdbc.JdbcConnection.queryAndMap(JdbcConnection.java:575)
      	at io.debezium.jdbc.JdbcConnection.queryAndMap(JdbcConnection.java:443)
      	at io.debezium.connector.sqlserver.SqlServerConnection.getMaxLsn(SqlServerConnection.java:129)
      	at io.debezium.connector.sqlserver.SqlServerSnapshotChangeEventSource.determineSnapshotOffset(SqlServerSnapshotChangeEventSource.java:158)
      	at io.debezium.relational.RelationalSnapshotChangeEventSource.doExecute(RelationalSnapshotChangeEventSource.java:120)
      	at io.debezium.pipeline.source.AbstractSnapshotChangeEventSource.execute(AbstractSnapshotChangeEventSource.java:63)
      	... 6 more
      Stopped connector
      2020-09-02 10:02:22,264 INFO   DatatypesFromSnapshotIT||test  Stopping the connector   [io.debezium.connector.sqlserver.DatatypesFromSnapshotIT]
      2020-09-02 10:02:22,264 INFO   DatatypesFromSnapshotIT||test  Interrupting the engine   [io.debezium.connector.sqlserver.DatatypesFromSnapshotIT]
      2020-09-02 10:02:22,265 INFO   DatatypesFromSnapshotIT||test  Test io.debezium.connector.sqlserver.DatatypesFromSnapshotIT#stringTypes failed   [io.debezium.connector.sqlserver.DatatypesFromSnapshotIT]
      

              ccranfor@redhat.com Chris Cranford
              gunnar.morling Gunnar Morling
              Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

                Created:
                Updated:
                Resolved: