问题描述
我已经在 Wildfly 15 Linux服务器上部署了 SOAP Web服务,并且我正在使用 Dynatrace 监视此Web服务。{{1} }进行记录。 我正在记录代码中的每个细节,并且每个 private 和 public 方法的执行持续时间。 乍一看,一切都很好,但我有一个奇怪的案例。例如,当我查看日志时,网络方法的一个请求的持续时间为80毫秒,而在 Dynatrace 中的请求的持续时间为780毫秒。
在Dynatrace中,当我查看此请求的详细信息时,有一种情况,出现在{strong> dynatrace 中的某些Log4j2
比在 web方法中花费700ms 和网络方法需要80毫秒(就像在日志中一样)。
此sun.nio.fs.UnixException
的详细信息:
sun.nio.fs.UnixException
还有我的Exception:
sun.nio.fs.UnixException
Message:
No such file or directory
Stacktrace:
sun.nio.fs.UnixNativedispatcher.access0(UnixNativedispatcher.java)
sun.nio.fs.UnixNativedispatcher.access(UnixNativedispatcher.java:449)
sun.nio.fs.UnixFileSystemProvider.checkAccess(UnixFileSystemProvider.java:306)
java.nio.file.Files.exists(Files.java:2385)
org.jboss.modules.PathResourceLoader.lambda$
org.jboss.modules.PathResourceLoader$$Lambda$.run
org.jboss.modules.PathResourceLoader.doPrivilegedIfNeeded(PathResourceLoader.java:248)
org.jboss.modules.PathResourceLoader.getResource(PathResourceLoader.java:155)
org.jboss.modules.ModuleClassLoader.loadResourceLocal(ModuleClassLoader.java:410)
org.jboss.modules.ModuleClassLoader$1.loadResourceLocal(ModuleClassLoader.java:144)
org.jboss.modules.Module.getResource(Module.java:764)
org.jboss.modules.ModuleClassLoader.findResource(ModuleClassLoader.java:616)
org.jboss.modules.ConcurrentClassLoader.getResource(ConcurrentClassLoader.java:255)
java.lang.class.getResource(Class.java:2267)
ge.my.package.ws.security.ConfigManager.getConfigManager(ConfigManager.java:44)
ge.my.package.ws.Ccs.handleUserRequest(Ccs.java:610)
ge.my.package.ws.Ccs.getAuthorizationsByCardSerno(Ccs.java:466)
sun.reflect.GeneratedMethodAccessor.invoke
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
java.lang.reflect.Method.invoke(Method.java:498)
com.sun.xml.ws.util.Trampoline.invoke(MethodUtil.java:82)
sun.reflect.GeneratedMethodAccessor.invoke
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
java.lang.reflect.Method.invoke(Method.java:498)
com.sun.xml.ws.util.MethodUtil.invoke(MethodUtil.java:107)
com.sun.xml.ws.api.server.MethodUtil.invoke(MethodUtil.java:64)
com.sun.xml.ws.api.server.InstanceResolver$1.invoke(InstanceResolver.java:250)
com.sun.xml.ws.server.InvokerTube$2.invoke(InvokerTube.java:149)
com.sun.xml.ws.server.sei.SEIInvokerTube.processRequest(SEIInvokerTube.java:88)
com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1136)
com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:1050)
com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:1019)
com.sun.xml.ws.api.pipe.Fiber.run(Fiber.java:813)
com.sun.xml.ws.api.pipe.Fiber.start(Fiber.java:420)
com.sun.xml.ws.server.WSEndpointImpl.processAsync(WSEndpointImpl.java:368)
com.sun.xml.ws.server.WSEndpointImpl.process(WSEndpointImpl.java:398)
com.sun.xml.ws.transport.http.HttpAdapter.invokeAsync(HttpAdapter.java:734)
com.sun.xml.ws.transport.http.servlet.ServletAdapter.invokeAsync(ServletAdapter.java:212)
com.sun.xml.ws.transport.http.servlet.WSServletDelegate.doGet(WSServletDelegate.java:161)
行代码:
ge.my.package.ws.security.ConfigManager.geConfigManager(ConfigManager.java:44)
看起来,它甚至在请求真正进入 web方法之前都尝试获取配置文件并开始记录日志(请求进入 web方法后,我便立即进行日志记录),我什至没有关于此尝试和错误消息的日志。
进入网络方法并开始记录时,它会读取并读取URL url = CcsConfigManager.class.getResource("/config.properties");
文件,一切正常。
我有Dynatrace只是用来监视生产服务器,正如我所说的,我在日志中没有关于此错误的信息,由于这个原因,我无法对此问题进行深入研究,所以我真的不知道这是否是我的代码,环境或 dynatrace 出现问题,表明有问题。
- 如果确实进入我的服务并失败调用我的方法,为什么我的日志中没有任何内容?如果服务中发生了某些情况,我将记录任何
config.properties
种情况。 - 它会影响响应时间吗?我怎么知道真正是80毫秒还是780毫秒?
- 如果可能的话,我该怎么办才能在日志中记录该错误?
解决方法
-
抛出的
sun.nio.fs.UnixException
被捕获在java.nio.io.Files#exists
中(有关代码,请参见here)。这就是为什么您在日志中看不到异常的原因。 -
由于触发异常的代码是在输入方法之前执行的,因此这当然会增加整个请求的响应时间。 Files.exists方法在JDK 8中的性能明显较差,并且在用于检查实际上不存在的文件时会大大降低应用程序的速度。 诸如Sonar之类的源代码分析工具甚至会对此发出警告(例如,参见here)。性能不佳的根本原因是Dynatrace所显示的异常,或更确切地说,是其昂贵的fillInStackTrace()方法。
-
您无法记录JRE内部类或第三方库捕获的异常。跟踪所有引发的异常的唯一方法是使用Dynatrace之类的监控工具,或者您开发自己的利用Java Instrumentation / Agent API的监控工具。