Statement.setQueryTimeout 在 Oracle 18c jdbc 驱动程序上不起作用

kot*_*dir 6 java oracle jdbc oracle18c

我们在 payara 5.2020 服务器上有一个 J2EE 应用程序,它执行长时间运行的查询(PL/SQL 执行几个小时)。为了避免超时异常,我们在StatementLevel使用这句话:

statement.setQueryTimeout(0);
Run Code Online (Sandbox Code Playgroud)

这可以使用 Oracle jdbc 驱动程序版本 12c,但当我们迁移到 Oracle 18c,并将驱动程序更改为版本 18c 时,查询执行会在 15 分钟后停止,并出现此异常。该代码适用于 Oracle 12 和 Oracle 18,驱动程序 jar 中的更改导致了问题。该问题已在 Linux 和 Windows 机器上重现:

2021-06-14T07:50:01.762+0200|SEVERE: java.sql.SQLRecoverableException: Error de E/S: Socket read interrupted
    at oracle.jdbc.driver.T4CCallableStatement.executeForRows(T4CCallableStatement.java:946)
    at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:1136)
    at oracle.jdbc.driver.OraclePreparedStatement.executeInternal(OraclePreparedStatement.java:3640)
    at oracle.jdbc.driver.T4CCallableStatement.executeInternal(T4CCallableStatement.java:1318)
    at oracle.jdbc.driver.OraclePreparedStatement.execute(OraclePreparedStatement.java:3752)
    at oracle.jdbc.driver.OracleCallableStatement.execute(OracleCallableStatement.java:4242)
    at oracle.jdbc.driver.OraclePreparedStatementWrapper.execute(OraclePreparedStatementWrapper.java:1079)
    at com.sun.gjc.spi.base.PreparedStatementWrapper.execute(PreparedStatementWrapper.java:532)
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
    at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.base/java.lang.reflect.Method.invoke(Method.java:566)
    at com.sun.gjc.spi.jdbc40.ProfiledConnectionWrapper40$1.invoke(ProfiledConnectionWrapper40.java:437)
    at com.sun.proxy.$Proxy324.execute(Unknown Source)
    at org.apache.jsp.index_jsp.callPL(index_jsp.java:49)
    at org.apache.jsp.index_jsp._jspService(index_jsp.java:108)
    at org.apache.jasper.runtime.HttpJspBase.service(HttpJspBase.java:111)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:750)
    at org.apache.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:411)
    at org.apache.jasper.servlet.JspServlet.serviceJspFile(JspServlet.java:473)
    at org.apache.jasper.servlet.JspServlet.service(JspServlet.java:377)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:750)
    at org.apache.catalina.core.StandardWrapper.service(StandardWrapper.java:1636)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:259)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
    at org.apache.catalina.core.StandardPipeline.doInvoke(StandardPipeline.java:757)
    at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:577)
    at com.sun.enterprise.web.WebPipeline.invoke(WebPipeline.java:99)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:158)
    at org.apache.catalina.connector.CoyoteAdapter.doService(CoyoteAdapter.java:371)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:238)
    at com.sun.enterprise.v3.services.impl.ContainerMapper$HttpHandlerCallable.call(ContainerMapper.java:520)
    at com.sun.enterprise.v3.services.impl.ContainerMapper.service(ContainerMapper.java:217)
    at org.glassfish.grizzly.http.server.HttpHandler.runService(HttpHandler.java:182)
    at org.glassfish.grizzly.http.server.HttpHandler.doHandle(HttpHandler.java:156)
    at org.glassfish.grizzly.http.server.HttpServerFilter.handleRead(HttpServerFilter.java:218)
    at org.glassfish.grizzly.filterchain.ExecutorResolver$9.execute(ExecutorResolver.java:95)
    at org.glassfish.grizzly.filterchain.DefaultFilterChain.executeFilter(DefaultFilterChain.java:260)
    at org.glassfish.grizzly.filterchain.DefaultFilterChain.executeChainPart(DefaultFilterChain.java:177)
    at org.glassfish.grizzly.filterchain.DefaultFilterChain.execute(DefaultFilterChain.java:109)
    at org.glassfish.grizzly.filterchain.DefaultFilterChain.process(DefaultFilterChain.java:88)
    at org.glassfish.grizzly.ProcessorExecutor.execute(ProcessorExecutor.java:53)
    at org.glassfish.grizzly.nio.transport.TCPNIOTransport.fireIOEvent(TCPNIOTransport.java:524)
    at org.glassfish.grizzly.strategies.AbstractIOStrategy.fireIOEvent(AbstractIOStrategy.java:89)
    at org.glassfish.grizzly.strategies.WorkerThreadIOStrategy.run0(WorkerThreadIOStrategy.java:94)
    at org.glassfish.grizzly.strategies.WorkerThreadIOStrategy.access$100(WorkerThreadIOStrategy.java:33)
    at org.glassfish.grizzly.strategies.WorkerThreadIOStrategy$WorkerThreadRunnable.run(WorkerThreadIOStrategy.java:114)
    at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.doWork(AbstractThreadPool.java:569)
    at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.run(AbstractThreadPool.java:549)
    at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: java.io.InterruptedIOException: Socket read interrupted
    at oracle.net.nt.TimeoutSocketChannel.handleInterrupt(TimeoutSocketChannel.java:262)
    at oracle.net.nt.TimeoutSocketChannel.read(TimeoutSocketChannel.java:184)
    at oracle.net.ns.NSProtocolNIO.doSocketRead(NSProtocolNIO.java:544)
    at oracle.net.ns.NIOPacket.readHeader(NIOPacket.java:234)
    at oracle.net.ns.NIOPacket.readPacketFromSocketChannel(NIOPacket.java:174)
    at oracle.net.ns.NIOPacket.readFromSocketChannel(NIOPacket.java:122)
    at oracle.net.ns.NIOPacket.readFromSocketChannel(NIOPacket.java:100)
    at oracle.net.ns.NIONSDataChannel.readDataFromSocketChannel(NIONSDataChannel.java:86)
    at oracle.jdbc.driver.T4CMAREngineNIO.prepareForUnmarshall(T4CMAREngineNIO.java:762)
    at oracle.jdbc.driver.T4CMAREngineNIO.unmarshalUB1(T4CMAREngineNIO.java:427)
    at oracle.jdbc.driver.T4CTTIfun.receive(T4CTTIfun.java:394)
    at oracle.jdbc.driver.T4CTTIfun.doRPC(T4CTTIfun.java:255)
    at oracle.jdbc.driver.T4C8Oall.doOALL(T4C8Oall.java:610)
    at oracle.jdbc.driver.T4CCallableStatement.doOall8(T4CCallableStatement.java:249)
    at oracle.jdbc.driver.T4CCallableStatement.doOall8(T4CCallableStatement.java:82)
    at oracle.jdbc.driver.T4CCallableStatement.executeForRows(T4CCallableStatement.java:924)
Run Code Online (Sandbox Code Playgroud)

看来传输层已经迁移到java.nio并且方法setQueryTimeout不再是

我们尝试过的事情:

  1. 在 payara 控制台的 JDBC 连接池高级属性屏幕中将默认语句超时设置为 -1。
  2. 尝试直接在连接中设置时间 conn.setNetworkTimeout(Executors.newFixedThreadPool(1), 0) 没有任何效果。
  3. 在不同的来源中,我们发现以下这些属性应该会影响网络超时评估。我们将它们设置为 payara 启动的 JVM 属性 (-Doracle.net.CONNECT_TIMEOUT=xxx) 和 JDBC 连接池属性,这两种情况的值都为 0 和 -1。无论如何都不起作用。
  • oracle.net.CONNECT_TIMEOUT
  • oracle.net.READ_TIMEOUT
  • oracle.jdbc.ReadTimeout

资料来源:

在此输入图像描述

4.- 由于我们通过 payara 数据源池访问数据源,因此我们无法将 com.sun.gjc.spi.jdbc40.DataSource40(由 payara 提供的类)转换为 OracleDataSource,但我们以编程方式创建了 DataSorce 来设置连接属性如此处所示并设置上图中所示的属性,但它不起作用:

public static Properties oracleProperties() {
    // Already tried -1 and 0
    Properties properties = new Properties();
    properties.put("Oracle.net.CONNECT_TIMEOUT", 0);
    properties.put("Oracle.net.READ_TIMEOUT", 0);
    properties.put("Oracle.jdbc.ReadTimeout", 0);
    return properties;
}

public static OracleDataSource createDataSource() throws Exception {
    OracleDataSource ods = new OracleDataSource();
    ods.setURL("jdbc:oracle:thin:@itauc4602x:1521/BDExp");
    ods.setUser("enevac");
    ods.setPassword("enevac");
    ods.setDataSourceName("OracleXADataSource");
    ods.setLoginTimeout(0);
    // default connection properties to avoid timeoutException
    ods.setConnectionProperties(oracleProperties());
    return ods;
}
Run Code Online (Sandbox Code Playgroud)
  1. 有没有人遇到过这个问题,有关于如何避免超时限制的想法吗?
  2. 为什么是15分钟?根据参考资料,oracle.net.ReadTimeout的默认值是10分钟。

更新:为了更详细地解释为什么我认为问题出在驱动程序中以及为什么我放弃异常的其他可能来源,我假设可以从三个来源引发超时:

  • 网络超时:我放弃它,因为我正在本地计算机上针对开发数据库测试 payara 服务器,中间没有防火墙。
  • 数据库服务器:DBA 已检查 Oracle 网络服务配置,并且没有设置限制来解释 15 分钟的缩短。此外,在这些情况下,将会出现 SQLException 并带有某种 ORA-xxx 错误代码。
  • JDBC:可以在连接级别、语句级别和事务级别设置。正如我在开始时所说,该代码适用于针对 Oracle 12 和 Oracle 18 服务器的 Oracle 12c 驱动程序,正是驱动程序 jar 的更改导致代码停止工作。

kot*_*dir 6

最后,问题得到解决,在 payara 池中配置 OracleDataSource 的“connectionProperties”自定义属性。正如 @ibre5041 指出的,设置属性 oracle.jdbc.javaNetNio=false 会更改驱动程序使用的传输层,并且它开始像 oracle 12c 以前的版本一样工作。

\n

根据Oracle 参考资料,OracleDataSource 实现者可以将连接属性作为 java.util.Properties 对象接收。

\n

表 8-2 Oracle 扩展数据源属性

\n
    \n
  • 名称:连接属性
  • \n
  • 类型:java.util.Properties
  • \n
  • 描述:指定连接属性。
  • \n
\n

要在 Payara 管理控制台中为 jdbc 池设置多值属性,您必须将属性设置为 (prop1=value1,prop2=value2),(再次感谢 Ondro Mih\xc3\xa1lyi)。所以在我们的例子中我们设置:

\n

连接属性 = (oracle.jdbc.ReadTimeout=0, oracle.jdbc.javaNetNio=false)

\n

作为使用 Oracle 18c jdbc 驱动程序的有效和无效的总结(每个步骤都单独测试):

\n
    \n
  • 在语句级别设置超时不起作用:

    \n

    语句.setQueryTimeout(0)

    \n
  • \n
  • 在连接级别设置超时(-1 或 0)不起作用:

    \n

    conn.setNetworkTimeout(Executors.newFixedThreadPool(1),超时以毫秒为单位)

    \n
  • \n
  • 使用 OracleDataSource 在 java.util.connection 中以编程方式设置超时属性使其按问题所示工作。

    \n
  • \n
  • 如果限制低于 15 分钟,则将超时属性设置为 JVM 属性可以使其正常工作,但如果设置的值 > 15 分钟,则会引发异常,因此设置为 0 或 -1 无效:

    \n
  • \n
\n

因此,这使得查询在 10 秒后停止:\n-Doracle.net.CONNECT_TIMEOUT=10000 -Doracle.net.READ_TIMEOUT=10000 -Doracle.jdbc.ReadTimeout=10000

\n

但 15 分钟后停止:\n-Doracle.net.CONNECT_TIMEOUT=-1 -Doracle.net.READ_TIMEOUT=-1 -Doracle.jdbc.ReadTimeout=-1

\n
    \n
  • 按照 @Nirmala 建议将 oracle.net.keepAlive=true 设置为 JVM 属性不起作用。
  • \n
  • 将 oracle.jdbc.javaNetNio=false 设置为 JVM 属性 @ibre5041使其工作。所以这表明 java.nio 传输层存在一些问题。
  • \n
\n

无论如何,我们向 Oracle 提出了一个支持问题,因为 jdbc api 语句。setQueryTimeout(0) 应该可以工作,而无需配置数据源,我将在案例关闭时给出响应。

\n


Nir*_*ala 0

由于默认 tcp 连接超时,查询执行可能会停止。您可以将 keepalive 属性“oracle.net.keepAlive”设置为 \xe2\x80\x9ctrue\xe2\x80\x9d 并验证吗?

\n