search

open-telemetry / opentelemetry-java-instrumentation

OpenTelemetry auto-instrumentation and instrumentation libraries for Java
https://opentelemetry.io
Apache License 2.0
1.88k stars 823 forks source link

jdbc trace generated more than expected number of traces #9538

Closed zenvzenv closed 11 months ago

zenvzenv commented 11 months ago

Describe the bug

When I use the kingbase database, more trace data than expected will be generated. I expected that with postgresql data, only one trace data will be generated, but the kingbase database generated more than 20 trace data.As shown below: trace.png

Steps to reproduce

  1. use kingbase jdbc driver
  2. use springboot jdbcTemplate and HikariPool database pool
  3. exec a sql to initialize database driver

Expected behavior

I expect one trace data display on jaeger

Actual behavior

There are more than 20 trace data display on jaeger

Javaagent or library instrumentation version

1.29.0

Environment

JDK: 

java version "1.8.0_161"
Java(TM) SE Runtime Environment (build 1.8.0_161-b12)
Java HotSpot(TM) 64-Bit Server VM (build 1.8.0_161-b12, mixed mode)

OS:

Windows 10 22H2

OTHER

springboot-web:2.2.6.RELEASE
springboot-jdbc:2.2.6.RELEASE
kingbase:V8R6
otelcol-contrib:0.83.0
jaeger:1.48.0

Additional context

No response

mateuszrzeszutek commented 11 months ago

Hey @zenvzenv , Please provide a repro scenario that we can run and see where the problem is.

laurit commented 11 months ago

Or at least provide more context on the unexpected spans. Which instrumentation creates them, what does the iwc in the span name stand for etc. Based on the information provided it is very unlikely that anybody can help you.

zenvzenv commented 11 months ago

Here is a debug log

kingbase8

[otel.javaagent 2023-09-26 15:09:45:441 +0800] [http-nio-8080-Acceptor] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.tomcat.util.net.SocketWrapperBase$OperationState -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:445 +0800] [http-nio-8080-Acceptor] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper$NioOperationState -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:455 +0800] [http-nio-8080-Acceptor] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.tomcat.util.net.NioEndpoint$PollerEvent -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:559 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.Request -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:567 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed javax.servlet.AsyncContext -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:578 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.HelperInjector - Injecting classes onto class loader <bootstrap> -> [io.opentelemetry.javaagent.bootstrap.field.VirtualFieldAccessor$javax$servlet$ServletResponse$java$lang$Integer]
[otel.javaagent 2023-09-26 15:09:45:579 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.HelperInjector - Injecting classes onto class loader <bootstrap> -> [io.opentelemetry.javaagent.bootstrap.field.VirtualFieldImpl$javax$servlet$ServletResponse$java$lang$Integer]
[otel.javaagent 2023-09-26 15:09:45:594 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.Response -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:601 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.CoyoteOutputStream -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:612 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.RequestFacade -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
2023-09-26 15:09:45.618  INFO 3452 --- [nio-8080-exec-1] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring DispatcherServlet 'dispatcherServlet'
2023-09-26 15:09:45.618  INFO 3452 --- [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Initializing Servlet 'dispatcherServlet'
2023-09-26 15:09:45.627  INFO 3452 --- [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Completed initialization in 9 ms
[otel.javaagent 2023-09-26 15:09:45:634 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.ResponseFacade -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:670 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.springframework.web.context.request.async.WebAsyncManager$$Lambda$718 -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
2023-09-26 15:09:45.676  INFO 3452 --- [nio-8080-exec-1] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Starting...
[otel.javaagent 2023-09-26 15:09:45:685 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariPool$PoolEntryCreator -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:698 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.kingbase8.core.BaseConnection -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:706 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.kingbase8.jdbc.KbConnection -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:793 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.kingbase8.core.BaseStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:800 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.kingbase8.jdbc.KbStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:806 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.kingbase8.jdbc.KbStatement$1 -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:836 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : ab9ed56b44458dc60a832e6d3f096a0c 5d39d009bdf4acd9 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=22, thread.name=http-nio-8080-exec-1, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:45:839 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : ab9ed56b44458dc60a832e6d3f096a0c 0f56db3aedc59675 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=22, thread.name=http-nio-8080-exec-1, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:45:843 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : ab9ed56b44458dc60a832e6d3f096a0c 6479518b5bde7c03 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=22, thread.name=http-nio-8080-exec-1, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:45:860 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.kingbase8.jdbc.KbPreparedStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:868 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : ab9ed56b44458dc60a832e6d3f096a0c 8d2622e7f7d2618f CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.user=system, db.statement=, db.system=other_sql, thread.id=22, thread.name=http-nio-8080-exec-1, db.name=iwc}, capacity=128, totalAddedValues=9}
[otel.javaagent 2023-09-26 15:09:45:869 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : ab9ed56b44458dc60a832e6d3f096a0c c44ae1d55c59be47 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.user=system, db.statement=SHOW TRANSACTION_ISOLATION, db.system=other_sql, thread.id=22, thread.name=http-nio-8080-exec-1, db.name=iwc}, capacity=128, totalAddedValues=9}
[otel.javaagent 2023-09-26 15:09:45:873 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariPool$$Lambda$728 -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:881 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariPool$HouseKeeper -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
2023-09-26 15:09:45.881  INFO 3452 --- [nio-8080-exec-1] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Start completed.
[otel.javaagent 2023-09-26 15:09:45:884 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.ProxyLeakTask -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:886 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.ProxyLeakTask$1 -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:893 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.ProxyConnection -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:897 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariProxyConnection -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:907 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.ProxyStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:914 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariProxyStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:45:925 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'SELECT iwc.test' : ab9ed56b44458dc60a832e6d3f096a0c 6cbc0ab1a05d2022 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.user=system, db.statement=select * from test, db.system=other_sql, db.operation=SELECT, thread.id=22, db.sql.table=test, thread.name=http-nio-8080-exec-1, db.name=iwc}, capacity=128, totalAddedValues=11}
[otel.javaagent 2023-09-26 15:09:46:010 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.tomcat.util.net.NioBlockingSelector$BlockPoller$RunnableRemove -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:09:46:012 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'TestController.query' : ab9ed56b44458dc60a832e6d3f096a0c 949b6d9fce4a310e INTERNAL [tracer: io.opentelemetry.spring-webmvc-3.1:1.29.0-alpha] AttributesMap{data={thread.id=22, thread.name=http-nio-8080-exec-1}, capacity=128, totalAddedValues=2}
[otel.javaagent 2023-09-26 15:09:46:015 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 6a49f1b24a4887af5c1293cf41c888c0 203be4ae931d5784 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:017 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'GET /query' : ab9ed56b44458dc60a832e6d3f096a0c 4abec7f2f2a23ec6 SERVER [tracer: io.opentelemetry.tomcat-7.0:1.29.0-alpha] AttributesMap{data={net.sock.host.addr=0:0:0:0:0:0:0:1, http.route=/query, net.protocol.name=http, http.method=GET, net.protocol.version=1.1, http.scheme=http, user_agent.original=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.0.0 Safari/537.36 Edg/114.0.1823.67, net.host.name=localhost, net.host.port=8080, http.target=/query, net.sock.peer.addr=0:0:0:0:0:0:0:1, http.status_code=200, thread.id=22, net.sock.peer.port=59843, thread.name=http-nio-8080-exec-1}, capacity=128, totalAddedValues=15}
[otel.javaagent 2023-09-26 15:09:46:017 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 8e3e61166f8fcf2b11d3153606e75912 8264166360afe664 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:018 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 10e83457d7aa168cb252e16f6744c8bd d2b28be259119d67 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:037 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : a979329d750a768723faaec1f7a3871b e830ef9e69c7713e CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:038 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 8bfe9fc559710bfa222deed9b0677bf1 0a079aa05a1649e7 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:041 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 798f6daaf6ae8abc67c8fe6b309ec8cb 018e0e69dfd6634d CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:059 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 4c60dd7a2eaad713b4dae1095c6e00d3 a6528f5f8b894e2a CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:061 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 7ac45d4472123dfe4627ad2de28453d9 44de10cea8051a26 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:063 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : b1b5123eaf916e467ce8444b52aa9190 02f0331e210718d5 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:082 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 35901cf6fc784a65d6a5ba381287a90a bf98080b8ba21f89 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:084 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 00642061b51aae2b64522aee3ea74337 414ecaa6a43c070e CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:087 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 05804819fcc50e863e19641214e57774 ae29e5f04b4e1d36 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:108 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 5a9fbdf651b113fe60fe09ce4c6602ae 980512df98170bec CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:111 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : cbe80a771714b2f37ebfdb0f56e05368 f0f3519d8cd3a0c5 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:114 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : fd0be1908453e3feb63496cf151cbd60 74c718071be331d2 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:134 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 6d71b358e550e0bf61aa678e18503ae1 cab5e05f9fbaca1d CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:135 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 5fac0c2fe3c8c13cea6bd436e7bda512 f144bfc7b927a171 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:136 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 4742068a62733f1cff4d8f392cf89597 de3807699083bab3 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:151 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : d4c510ec9d9e2e75e794667f5de9cf25 6f0b2a593dd79112 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:152 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : aa481c8195e9f4a09ee9cf76cff18e70 ededd00482782f39 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:153 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 12c7434677bbd19f7f2212a4d0b5ee83 7280cdef3a986790 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:167 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : ece7a34021a3cb67651458e3fe7f968a acf7016d7ba6b4bb CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:168 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 0e001ce580e4f41f520a2e8fc6f50742 e1d7e7aa23f4c768 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:169 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 0ef403bc9de33e1b9e65a311bfa59aa1 86abc762a7b72f11 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:184 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 6d7b7505bfe43122c5f75f1e679e383f 2df3aff55c6dcd5e CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show database_mode, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:185 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : d8cb44b6e1fc381c343a28cc3d406f97 7d04210e09e7bb65 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show ora_input_emptystr_isnull, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:46:187 +0800] [HikariPool-1 connection adder] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'iwc' : 86779e08f380c4ea800d6954781fecb5 e083067a1898a122 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=kingbase8://10.20.82.164:54321, net.peer.name=10.20.82.164, net.peer.port=54321, db.statement=show kdb_flashback.db_recyclebin, db.system=other_sql, thread.id=39, thread.name=HikariPool-1 connection adder, db.name=iwc}, capacity=128, totalAddedValues=8}
[otel.javaagent 2023-09-26 15:09:49:806 +0800] [OkHttp http://10.20.74.57:4317/...] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10005 scheduled after   0 ?s: OkHttp 10.20.74.57
[otel.javaagent 2023-09-26 15:09:49:806 +0800] [OkHttp http://10.20.74.57:4317/...] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 scheduled after   0 ?s: OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:09:49:807 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10005 starting              : OkHttp 10.20.74.57
[otel.javaagent 2023-09-26 15:09:49:807 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 starting              : OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:09:49:808 +0800] [OkHttp ConnectionPool] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 run again after 300 s : OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:09:49:808 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 finished run in   1 ms: OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:09:49:810 +0800] [OkHttp 10.20.74.57] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 scheduled after   0 ?s: OkHttp 10.20.74.57 applyAndAckSettings
[otel.javaagent 2023-09-26 15:09:49:810 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 starting              : OkHttp 10.20.74.57 applyAndAckSettings
[otel.javaagent 2023-09-26 15:09:49:813 +0800] [OkHttp 10.20.74.57 applyAndAckSettings] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10004 scheduled after   0 ?s: OkHttp 10.20.74.57 onSettings
[otel.javaagent 2023-09-26 15:09:49:814 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10004 starting              : OkHttp 10.20.74.57 onSettings
[otel.javaagent 2023-09-26 15:09:49:814 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 finished run in   4 ms: OkHttp 10.20.74.57 applyAndAckSettings
[otel.javaagent 2023-09-26 15:09:49:815 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10004 finished run in 660 ?s: OkHttp 10.20.74.57 onSettings
[otel.javaagent 2023-09-26 15:09:49:819 +0800] [OkHttp 10.20.74.57] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 scheduled after   0 ?s: OkHttp 10.20.74.57 ping
[otel.javaagent 2023-09-26 15:09:49:819 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 starting              : OkHttp 10.20.74.57 ping
[otel.javaagent 2023-09-26 15:09:49:819 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 finished run in 459 ?s: OkHttp 10.20.74.57 ping
[otel.javaagent 2023-09-26 15:09:49:823 +0800] [OkHttp http://10.20.74.57:4317/...] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 scheduled after   0 ?s: OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:09:49:824 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 starting              : OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:09:49:824 +0800] [OkHttp ConnectionPool] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 run again after 300 s : OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:09:49:824 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 finished run in 122 ?s: OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:09:50:515 +0800] [supportability_metrics_reporter] DEBUG io.opentelemetry.javaagent.shaded.instrumentation.api.internal.SupportabilityMetrics - Counter 'SqlStatementSanitizer cache miss' : 6

postgresql

[otel.javaagent 2023-09-26 15:52:38:592 +0800] [http-nio-8080-Acceptor] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.tomcat.util.net.SocketWrapperBase$OperationState -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:594 +0800] [http-nio-8080-Acceptor] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper$NioOperationState -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:601 +0800] [http-nio-8080-Acceptor] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.tomcat.util.net.NioEndpoint$PollerEvent -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:685 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.Request -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:692 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed javax.servlet.AsyncContext -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:702 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.HelperInjector - Injecting classes onto class loader <bootstrap> -> [io.opentelemetry.javaagent.bootstrap.field.VirtualFieldAccessor$javax$servlet$ServletResponse$java$lang$Integer]
[otel.javaagent 2023-09-26 15:52:38:702 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.HelperInjector - Injecting classes onto class loader <bootstrap> -> [io.opentelemetry.javaagent.bootstrap.field.VirtualFieldImpl$javax$servlet$ServletResponse$java$lang$Integer]
[otel.javaagent 2023-09-26 15:52:38:731 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.Response -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:737 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.CoyoteOutputStream -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:746 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.RequestFacade -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
2023-09-26 15:52:38.752  INFO 2588 --- [nio-8080-exec-1] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring DispatcherServlet 'dispatcherServlet'
2023-09-26 15:52:38.753  INFO 2588 --- [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Initializing Servlet 'dispatcherServlet'
2023-09-26 15:52:38.760  INFO 2588 --- [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Completed initialization in 7 ms
[otel.javaagent 2023-09-26 15:52:38:767 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.catalina.connector.ResponseFacade -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:810 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.springframework.web.context.request.async.WebAsyncManager$$Lambda$718 -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
2023-09-26 15:52:38.815  INFO 2588 --- [nio-8080-exec-1] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Starting...
[otel.javaagent 2023-09-26 15:52:38:832 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariPool$PoolEntryCreator -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:856 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.postgresql.core.BaseConnection -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:862 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.postgresql.jdbc.PgConnection -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:967 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.postgresql.core.BaseStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:976 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.postgresql.jdbc.PgStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:983 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.postgresql.jdbc.PgPreparedStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:38:987 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.postgresql.jdbc.PgStatement$1 -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:39:003 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'zfbdp' : 2f3a7194ca95af13b898b56137aa3505 20d43a69ea332ea6 CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=postgresql://10.20.74.60:5433, net.peer.name=10.20.74.60, net.peer.port=5433, db.user=jcs, db.statement=, db.system=postgresql, thread.id=22, thread.name=http-nio-8080-exec-1, db.name=zfbdp}, capacity=128, totalAddedValues=9}
[otel.javaagent 2023-09-26 15:52:39:016 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'zfbdp' : 2f3a7194ca95af13b898b56137aa3505 8780b785169a864c CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=postgresql://10.20.74.60:5433, net.peer.name=10.20.74.60, net.peer.port=5433, db.user=jcs, db.statement=SHOW TRANSACTION ISOLATION LEVEL, db.system=postgresql, thread.id=22, thread.name=http-nio-8080-exec-1, db.name=zfbdp}, capacity=128, totalAddedValues=9}
[otel.javaagent 2023-09-26 15:52:39:019 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariPool$$Lambda$728 -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:39:029 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariPool$HouseKeeper -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
2023-09-26 15:52:39.030  INFO 2588 --- [nio-8080-exec-1] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Start completed.
[otel.javaagent 2023-09-26 15:52:39:032 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.ProxyLeakTask -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:39:035 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.ProxyLeakTask$1 -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:39:040 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.ProxyConnection -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:39:045 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariProxyConnection -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:39:053 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.ProxyStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:39:060 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed com.zaxxer.hikari.pool.HikariProxyStatement -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:39:078 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'SELECT zfbdp.test' : 2f3a7194ca95af13b898b56137aa3505 ecd35e325426a08e CLIENT [tracer: io.opentelemetry.jdbc:1.29.0-alpha] AttributesMap{data={db.connection_string=postgresql://10.20.74.60:5433, net.peer.name=10.20.74.60, net.peer.port=5433, db.user=jcs, db.statement=select * from test, db.system=postgresql, db.operation=SELECT, thread.id=22, db.sql.table=test, thread.name=http-nio-8080-exec-1, db.name=zfbdp}, capacity=128, totalAddedValues=11}
[otel.javaagent 2023-09-26 15:52:39:166 +0800] [http-nio-8080-exec-1] DEBUG io.opentelemetry.javaagent.tooling.AgentInstaller$TransformLoggingListener - Transformed org.apache.tomcat.util.net.NioBlockingSelector$BlockPoller$RunnableRemove -- org.springframework.boot.loader.LaunchedURLClassLoader@5a49af50
[otel.javaagent 2023-09-26 15:52:39:168 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'TestController.query' : 2f3a7194ca95af13b898b56137aa3505 6749a3cd68069306 INTERNAL [tracer: io.opentelemetry.spring-webmvc-3.1:1.29.0-alpha] AttributesMap{data={thread.id=22, thread.name=http-nio-8080-exec-1}, capacity=128, totalAddedValues=2}
[otel.javaagent 2023-09-26 15:52:39:171 +0800] [http-nio-8080-exec-1] INFO io.opentelemetry.exporter.logging.LoggingSpanExporter - 'GET /query' : 2f3a7194ca95af13b898b56137aa3505 f67b3f66c1eadafd SERVER [tracer: io.opentelemetry.tomcat-7.0:1.29.0-alpha] AttributesMap{data={net.sock.host.addr=0:0:0:0:0:0:0:1, http.route=/query, net.protocol.name=http, http.method=GET, net.protocol.version=1.1, http.scheme=http, user_agent.original=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.0.0 Safari/537.36 Edg/114.0.1823.67, net.host.name=localhost, net.host.port=8080, http.target=/query, net.sock.peer.addr=0:0:0:0:0:0:0:1, http.status_code=200, thread.id=22, net.sock.peer.port=58292, thread.name=http-nio-8080-exec-1}, capacity=128, totalAddedValues=15}
[otel.javaagent 2023-09-26 15:52:41:232 +0800] [OkHttp http://10.20.74.57:4317/...] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10005 scheduled after   0 ?s: OkHttp 10.20.74.57
[otel.javaagent 2023-09-26 15:52:41:233 +0800] [OkHttp http://10.20.74.57:4317/...] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 scheduled after   0 ?s: OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:52:41:233 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10005 starting              : OkHttp 10.20.74.57
[otel.javaagent 2023-09-26 15:52:41:233 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 starting              : OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:52:41:235 +0800] [OkHttp ConnectionPool] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 run again after 300 s : OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:52:41:235 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 finished run in   2 ms: OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:52:41:238 +0800] [OkHttp 10.20.74.57] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 scheduled after   0 ?s: OkHttp 10.20.74.57 applyAndAckSettings
[otel.javaagent 2023-09-26 15:52:41:238 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 starting              : OkHttp 10.20.74.57 applyAndAckSettings
[otel.javaagent 2023-09-26 15:52:41:241 +0800] [OkHttp 10.20.74.57 applyAndAckSettings] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10004 scheduled after   0 ?s: OkHttp 10.20.74.57 onSettings
[otel.javaagent 2023-09-26 15:52:41:241 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10004 starting              : OkHttp 10.20.74.57 onSettings
[otel.javaagent 2023-09-26 15:52:41:241 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 finished run in   4 ms: OkHttp 10.20.74.57 applyAndAckSettings
[otel.javaagent 2023-09-26 15:52:41:242 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10004 finished run in 580 ?s: OkHttp 10.20.74.57 onSettings
[otel.javaagent 2023-09-26 15:52:41:245 +0800] [OkHttp 10.20.74.57] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 scheduled after   0 ?s: OkHttp 10.20.74.57 ping
[otel.javaagent 2023-09-26 15:52:41:245 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 starting              : OkHttp 10.20.74.57 ping
[otel.javaagent 2023-09-26 15:52:41:245 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10002 finished run in 529 ?s: OkHttp 10.20.74.57 ping
[otel.javaagent 2023-09-26 15:52:41:251 +0800] [OkHttp http://10.20.74.57:4317/...] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 scheduled after   0 ?s: OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:52:41:252 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 starting              : OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:52:41:252 +0800] [OkHttp ConnectionPool] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 run again after 300 s : OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:52:41:252 +0800] [OkHttp TaskRunner] DEBUG okhttp3.internal.concurrent.TaskRunner - Q10001 finished run in 164 ?s: OkHttp ConnectionPool
[otel.javaagent 2023-09-26 15:52:43:657 +0800] [supportability_metrics_reporter] DEBUG io.opentelemetry.javaagent.shaded.instrumentation.api.internal.SupportabilityMetrics - Counter 'SqlStatementSanitizer cache miss' : 3
laurit commented 11 months ago

Is this something that happens only on application startup? To me it looks more like kingbase jdbc driver executes some statements when a new connection is created.

github-actions[bot] commented 11 months ago

This has been automatically marked as stale because it has been marked as needing author feedback and has not had any activity for 7 days. It will be closed automatically if there is no response from the author within 7 additional days from this comment.