Uploaded image for project: 'Infinispan'
  1. Infinispan
  2. ISPN-15129

org.infinispan.test.integration.thirdparty.persistence.jdbc.stringbased.PooledConnectionDeployIT.testPutGetRemove fails randomly with mssql2019

This issue belongs to an archived project. You can view it, but you can't modify it. Learn more

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Obsolete
    • Icon: Major Major
    • None
    • 14.0.14.Final
    • Test Suite
    • None

      The test `org.infinispan.test.integration.thirdparty.persistence.jdbc.stringbased.PooledConnectionDeployIT.testPutGetRemove` fails randomly on tomcat with mssql2019 with exception:

      &amp#27;[32m[OK:     1, KO:     0, SKIP:     0] Test succeeded: PooledConnectionDeployIT.testWithoutPreload&amp#27;[0m
      [OK:     1, KO:     0, SKIP:     0] Test starting: PooledConnectionDeployIT.testPutGetRemove
      24-Aug-2023 12:22:26.948 INFO [http-nio-8080-exec-6] org.jboss.arquillian.testenricher.cdi.container.BeanManagerProducer.lookup BeanManager not found.
      24-Aug-2023 12:22:26.959 INFO [http-nio-8080-exec-6] org.infinispan.marshall.core.impl.DelegatingUserMarshaller.start ISPN000556: Starting user marshaller 'org.infinispan.commons.marshall.ImmutableProtoStreamMarshaller'
      24-Aug-2023 12:22:27.168 SEVERE [blocking-thread--p8-t2] com.microsoft.sqlserver.jdbc.TDSReader.throwInvalidTDS ConnectionID:6 ClientConnectionId: edeefd73-3e25-4c45-9c77-629a2d899a45 got unexpected value in TDS response at offset:359
      24-Aug-2023 12:22:27.170 ERROR [non-blocking-thread--p7-t7] org.infinispan.persistence.jdbc.common.sql.BaseTableOperations$FlowableConnection.close ISPN008032: Error during rollback of JDBC transaction
      	com.microsoft.sqlserver.jdbc.SQLServerException: Socket closed
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.terminate(SQLServerConnection.java:3208)
      		at com.microsoft.sqlserver.jdbc.TDSChannel.read(IOBuffer.java:2066)
      		at com.microsoft.sqlserver.jdbc.TDSReader.readPacket(IOBuffer.java:6669)
      		at com.microsoft.sqlserver.jdbc.TDSCommand.startResponse(IOBuffer.java:7805)
      		at com.microsoft.sqlserver.jdbc.TDSCommand.startResponse(IOBuffer.java:7768)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection$1ConnectionCommand.doExecute(SQLServerConnection.java:3316)
      		at com.microsoft.sqlserver.jdbc.TDSCommand.execute(IOBuffer.java:7418)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.executeCommand(SQLServerConnection.java:3274)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.connectionCommand(SQLServerConnection.java:3320)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.rollback(SQLServerConnection.java:3516)
      		at io.agroal.pool.wrapper.ConnectionWrapper.rollback(ConnectionWrapper.java:219)
      		at org.infinispan.persistence.jdbc.common.sql.BaseTableOperations$FlowableConnection.close(BaseTableOperations.java:277)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableUsing$UsingSubscriber.disposeResource(FlowableUsing.java:172)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableUsing$UsingSubscriber.cancel(FlowableUsing.java:165)
      		at io.reactivex.rxjava3.internal.subscriptions.SubscriptionHelper.cancel(SubscriptionHelper.java:181)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableSubscribeOn$SubscribeOnSubscriber.cancel(FlowableSubscribeOn.java:141)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableObserveOn$BaseObserveOnSubscriber.cancel(FlowableObserveOn.java:156)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableDoFinally$DoFinallySubscriber.cancel(FlowableDoFinally.java:102)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableTake$TakeSubscriber.onNext(FlowableTake.java:76)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableDoFinally$DoFinallySubscriber.onNext(FlowableDoFinally.java:85)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableObserveOn$ObserveOnSubscriber.runAsync(FlowableObserveOn.java:404)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableObserveOn$BaseObserveOnSubscriber.run(FlowableObserveOn.java:178)
      		at io.reactivex.rxjava3.internal.schedulers.ExecutorScheduler$ExecutorWorker$BooleanRunnable.run(ExecutorScheduler.java:324)
      		at io.reactivex.rxjava3.internal.schedulers.ExecutorScheduler$ExecutorWorker.runEager(ExecutorScheduler.java:289)
      		at io.reactivex.rxjava3.internal.schedulers.ExecutorScheduler$ExecutorWorker.run(ExecutorScheduler.java:250)
      		at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
      		at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
      		at java.base/java.lang.Thread.run(Thread.java:833)
      	Caused by: java.net.SocketException: Socket closed
      		at java.base/sun.nio.ch.NioSocketImpl.ensureOpenAndConnected(NioSocketImpl.java:165)
      		at java.base/sun.nio.ch.NioSocketImpl.beginRead(NioSocketImpl.java:231)
      		at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:299)
      		at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350)
      		at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803)
      		at java.base/java.net.Socket$SocketInputStream.read(Socket.java:966)
      		at com.microsoft.sqlserver.jdbc.TDSChannel.read(IOBuffer.java:2058)
      		... 26 more
      24-Aug-2023 12:22:27.170 ERROR [blocking-thread--p8-t2] org.infinispan.persistence.jdbc.common.JdbcUtil.unmarshall ISPN008009: I/O error while unmarshalling from stream
      	java.io.IOException: The TDS protocol stream is not valid.
      		at com.microsoft.sqlserver.jdbc.SimpleInputStream.read(SimpleInputStream.java:304)
      		at org.infinispan.protostream.impl.TagReaderImpl$InputStreamDecoder.tryFillBuffer(TagReaderImpl.java:1109)
      		at org.infinispan.protostream.impl.TagReaderImpl$InputStreamDecoder.isAtEnd(TagReaderImpl.java:1069)
      		at org.infinispan.protostream.impl.TagReaderImpl$Decoder.readTag(TagReaderImpl.java:307)
      		at org.infinispan.protostream.impl.TagReaderImpl.readTag(TagReaderImpl.java:99)
      		at org.infinispan.protostream.WrappedMessage.readMessage(WrappedMessage.java:365)
      		at org.infinispan.protostream.WrappedMessage.read(WrappedMessage.java:351)
      		at org.infinispan.protostream.ProtobufUtil.fromWrappedStream(ProtobufUtil.java:137)
      		at org.infinispan.marshall.protostream.impl.AbstractInternalProtoStreamMarshaller.readObject(AbstractInternalProtoStreamMarshaller.java:120)
      		at org.infinispan.persistence.jdbc.common.JdbcUtil.unmarshall(JdbcUtil.java:68)
      		at org.infinispan.persistence.jdbc.impl.table.AbstractTableManager.entryFromResultSet(AbstractTableManager.java:630)
      		at org.infinispan.persistence.jdbc.common.sql.BaseTableOperations$ResultSetEntryIterator.getNext(BaseTableOperations.java:301)
      		at org.infinispan.persistence.jdbc.common.sql.BaseTableOperations$ResultSetEntryIterator.getNext(BaseTableOperations.java:286)
      		at org.infinispan.commons.util.AbstractIterator.hasNext(AbstractIterator.java:26)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableFromIterable$IteratorSubscription.slowPath(FlowableFromIterable.java:253)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableFromIterable$BaseRangeSubscription.request(FlowableFromIterable.java:131)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableDoFinally$DoFinallySubscriber.request(FlowableDoFinally.java:108)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableUsing$UsingSubscriber.request(FlowableUsing.java:153)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableSubscribeOn$SubscribeOnSubscriber.requestUpstream(FlowableSubscribeOn.java:133)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableSubscribeOn$SubscribeOnSubscriber.onSubscribe(FlowableSubscribeOn.java:90)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableUsing$UsingSubscriber.onSubscribe(FlowableUsing.java:98)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableDoFinally$DoFinallySubscriber.onSubscribe(FlowableDoFinally.java:79)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableFromIterable.subscribe(FlowableFromIterable.java:69)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableFromIterable.subscribeActual(FlowableFromIterable.java:47)
      		at io.reactivex.rxjava3.core.Flowable.subscribe(Flowable.java:15917)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableDoFinally.subscribeActual(FlowableDoFinally.java:47)
      		at io.reactivex.rxjava3.core.Flowable.subscribe(Flowable.java:15917)
      		at io.reactivex.rxjava3.core.Flowable.subscribe(Flowable.java:15863)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableUsing.subscribeActual(FlowableUsing.java:73)
      		at io.reactivex.rxjava3.core.Flowable.subscribe(Flowable.java:15917)
      		at io.reactivex.rxjava3.core.Flowable.subscribe(Flowable.java:15863)
      		at io.reactivex.rxjava3.internal.operators.flowable.FlowableSubscribeOn$SubscribeOnSubscriber.run(FlowableSubscribeOn.java:82)
      		at io.reactivex.rxjava3.internal.schedulers.ExecutorScheduler$ExecutorWorker$BooleanRunnable.run(ExecutorScheduler.java:324)
      		at io.reactivex.rxjava3.internal.schedulers.ExecutorScheduler$ExecutorWorker.runEager(ExecutorScheduler.java:289)
      		at io.reactivex.rxjava3.internal.schedulers.ExecutorScheduler$ExecutorWorker.run(ExecutorScheduler.java:250)
      		at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804)
      		at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
      		at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1982)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1486)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1377)
      		at java.base/java.lang.Thread.run(Thread.java:833)
      24-Aug-2023 12:22:27.203 ERROR [blocking-thread--p8-t2] org.infinispan.persistence.jdbc.common.impl.BaseJdbcStore.lambda$clear$6 ISPN008001: Failed clearing cache store
      	com.microsoft.sqlserver.jdbc.SQLServerException: The connection is closed.
      		at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDriverError(SQLServerException.java:234)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.checkClosed(SQLServerConnection.java:1217)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.createStatement(SQLServerConnection.java:3756)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.createStatement(SQLServerConnection.java:3400)
      		at io.agroal.pool.wrapper.ConnectionWrapper.createStatement(ConnectionWrapper.java:321)
      		at org.infinispan.persistence.jdbc.common.sql.BaseTableOperations.deleteAllRows(BaseTableOperations.java:121)
      		at org.infinispan.persistence.jdbc.common.impl.BaseJdbcStore.lambda$clear$6(BaseJdbcStore.java:178)
      		at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804)
      		at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
      		at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1982)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1486)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1377)
      		at java.base/java.lang.Thread.run(Thread.java:833)
      24-Aug-2023 12:22:27.204 ERROR [non-blocking-thread--p7-t4] org.infinispan.interceptors.impl.InvocationContextInterceptor.rethrowException ISPN000136: Error executing command ClearCommand on Cache 'jdbc', writing keys []
      	org.infinispan.persistence.spi.PersistenceException: Failed clearing cache store
      		at org.infinispan.persistence.jdbc.common.impl.BaseJdbcStore.lambda$clear$6(BaseJdbcStore.java:181)
      		at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804)
      		at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
      		at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1982)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1486)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1377)
      		at java.base/java.lang.Thread.run(Thread.java:833)
      	Caused by: com.microsoft.sqlserver.jdbc.SQLServerException: The connection is closed.
      		at com.microsoft.sqlserver.jdbc.SQLServerException.makeFromDriverError(SQLServerException.java:234)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.checkClosed(SQLServerConnection.java:1217)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.createStatement(SQLServerConnection.java:3756)
      		at com.microsoft.sqlserver.jdbc.SQLServerConnection.createStatement(SQLServerConnection.java:3400)
      		at io.agroal.pool.wrapper.ConnectionWrapper.createStatement(ConnectionWrapper.java:321)
      		at org.infinispan.persistence.jdbc.common.sql.BaseTableOperations.deleteAllRows(BaseTableOperations.java:121)
      		at org.infinispan.persistence.jdbc.common.impl.BaseJdbcStore.lambda$clear$6(BaseJdbcStore.java:178)
      		... 6 more 

      The error appeared 2 times in 3 runs.

      The Jenkins URL for the failure is: https://main-jenkins-csb-dgqe.apps.ocp-c1.prod.psi.redhat.com/job/dg8x/job/third-party-server-embedded-db/DATABASE=mssql2019,SERVER=tomcat,jdk=openjdk17,label=rhel-latest/lastCompletedBuild/testReport/org.infinispan.test.integration.thirdparty.persistence.jdbc.stringbased/PooledConnectionDeployIT/testPutGetRemove/

              rh-ee-jbolina Jose Bolina
              amanukya@redhat.com Anna Manukyan
              Archiver:
              rhn-support-adongare Amol Dongare

                Created:
                Updated:
                Resolved:
                Archived: