-
Bug
-
Resolution: Obsolete
-
Major
-
None
-
14.0.14.Final
-
None
The test `org.infinispan.test.integration.thirdparty.persistence.jdbc.stringbased.PooledConnectionDeployIT.testPutGetRemove` fails randomly on tomcat with mssql2019 with exception:
[32m[OK: 1, KO: 0, SKIP: 0] Test succeeded: PooledConnectionDeployIT.testWithoutPreload[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/