Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

agent 등록 후, 데이터 수집이 안됩니다. #10141

Open
JadenTS opened this issue Jul 18, 2023 · 7 comments
Open

agent 등록 후, 데이터 수집이 안됩니다. #10141

JadenTS opened this issue Jul 18, 2023 · 7 comments

Comments

@JadenTS
Copy link

JadenTS commented Jul 18, 2023

안녕하세요, 계속 검색을 해봐도 해결이 되지 않아 부득이하게 문의 드립니다.

서버와 에이전트 설치를 하였는데, 더 이상 확인이 되지 안습니다.
도움을 주시면 감사하겠습니다.

pinpint 버전은 2.5.2 이며 web 접속까지 잘되었습니다.
jvm은 17을 사용중입니다.

image

agent 설치하여 실행 시 위와같이 어플리케이션 목록은 잘 보입니다만
로그 수집이 되지 않고 있습니다.

아래는 agent가 RUNNING 상태로 바뀌는 시점 이후의 로그입니다.

07-18 21:51:07.007 [ main] DEBUG c.n.p.p.DefaultAgent -- Agent status is changed. RUNNING
07-18 21:51:07.007 [ main] INFO c.n.p.p.DefaultAgent -- Starting pinpoint Agent.
07-18 21:51:07.007 [ main] INFO p.p.i.r.DefaultInterceptorRegistryBinder -- bind:DefaultRegistry-0
07-18 21:51:07.007 [ main] INFO c.n.p.p.m.DefaultDeadlockMonitor -- DefaultDeadlockMonitor started
07-18 21:51:07.007 [ main] DEBUG c.n.p.p.AgentInfoSender -- Start scheduler of agentInfoSender
07-18 21:51:07.007 [nfoSender-Timer] INFO c.n.p.p.AgentInfoSender -- Sending AgentInfo AgentInfo{agentInformation=DefaultAgentInformation{agentId='notice', agentName='', applicationName='notice', isContainer=false, startTime=1689684659563, pid=1254411, machineName='apti-dev-notice-gateway', hostIp='172.19.2.90', serverType=STAND_ALONE, jvmVersion='17.0.7', agentVersion='2.5.2'}, serverMetaData=DefaultServerMetaData{serverInfo='', vmArgs=[-javaagent:/pinpoint-agent-2.5.2/pinpoint-bootstrap-2.5.2.jar, -Dpinpoint.agentId=notice, -Dpinpoint.applicationName=notice, -Dpinpoint.config=/pinpoint-agent-2.5.2/pinpoint-root.config, -Dprofiler.transport.grpc.collector.ip=172.19.1.83], connectors={}, serviceInfo=[]}, jvmInformation=com.navercorp.pinpoint.profiler.JvmInformation@2c2a8cec}
07-18 21:51:07.007 [ main] INFO c.n.p.p.m.DefaultAgentStatMonitor -- AgentStat monitor started
07-18 21:51:07.007 [ main] INFO c.n.p.p.DefaultAgent -- registerStopHandler
07-18 21:51:07.007 [ main] INFO c.n.p.p.s.Java9ShutdownHookRegister -- register() started.
07-18 21:51:07.007 [ main] INFO c.n.p.p.s.Java9ShutdownHookRegister -- register(3) completed.
07-18 21:51:07.007 [nfoSender-Timer] DEBUG c.n.p.g.c.UnaryCallDeadlineInterceptor -- interceptCall v1.Agent/RequestAgentInfo
07-18 21:51:07.007 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] OUTBOUND HEADERS: streamId=7 headers=GrpcHttp2OutboundHeaders[:authority: 172.19.1.83:9991, :path: /v1.Agent/RequestAgentInfo, :method: POST, :scheme: http, content-type: application/grpc, te: trailers, user-agent: grpc-java-netty/1.49.2, agentid: notice, applicationname: notice, servicetype: 1000, starttime: 1689684659563, grpc-accept-encoding: gzip, grpc-timeout: 5999492u] streamDependency=0 weight=16 exclusive=false padding=0 endStream=false
07-18 21:51:07.007 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] OUTBOUND DATA: streamId=7 padding=0 endStream=true length=319 bytes=000000013a0a17617074692d6465762d6e6f746963652d67617465776179120b3137322e31392e322e393020e807288bc84c3205322e352e323a0631372e302e...
07-18 21:51:07.007 [ main] DEBUG c.n.p.p.i.l.DefaultLambdaBytecodeHandler -- handleLambdaBytecode class java.util.ResourceBundle null
07-18 21:51:07.007 [ main] DEBUG c.n.p.p.i.l.DefaultLambdaBytecodeHandler -- handleLambdaBytecode class java.util.ResourceBundle$ResourceBundleProviderHelper null
07-18 21:51:07.007 [ main] DEBUG c.n.p.p.i.l.DefaultLambdaBytecodeHandler -- handleLambdaBytecode class java.util.ResourceBundle$ResourceBundleProviderHelper null
07-18 21:51:07.007 [ main] DEBUG c.n.p.p.i.l.DefaultLambdaBytecodeHandler -- handleLambdaBytecode class jdk.internal.module.SystemModuleFinders$SystemModuleReader null
07-18 21:51:07.007 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] INBOUND HEADERS: streamId=7 headers=GrpcHttp2ResponseHeaders[:status: 200, content-type: application/grpc, grpc-encoding: identity, grpc-accept-encoding: gzip] padding=0 endStream=false
07-18 21:51:07.007 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] INBOUND DATA: streamId=7 padding=0 endStream=false length=7 bytes=00000000020801
07-18 21:51:07.007 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] INBOUND HEADERS: streamId=7 headers=GrpcHttp2ResponseHeaders[grpc-status: 0] padding=0 endStream=true
07-18 21:51:07.007 [nfoSender-Timer] INFO c.n.p.p.AgentInfoSender -- AgentInfo sent.
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.DefaultAgent -- stop() started. threadName:DestroyJavaVM
07-18 21:51:07.007 [ DestroyJavaVM] DEBUG c.n.p.p.DefaultAgent -- Agent status is changed. STOPPED
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.DefaultAgent -- Stopping pinpoint Agent.
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.AgentInfoSender -- AgentInfoSender stopped
07-18 21:51:07.007 [eExecutor(11-0)] DEBUG StatStorage@Pinpoint-StatStorageExecutor -- Loop is stop.
07-18 21:51:07.007 [ DestroyJavaVM] INFO StatStorage@Pinpoint-StatStorageExecutor -- Pinpoint-StatStorageExecutor(11-0) stopped.
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.m.DefaultAgentStatMonitor -- AgentStat monitor stopped
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.m.DefaultDeadlockMonitor -- DefaultDeadlockMonitor stopped
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.m.DeadlockMonitorTask -- DeadlockMonitorTask stop started
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.c.m.GrpcModuleLifeCycle -- shutdown()
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.s.g.SpanGrpcDataSender -- Stop SpanGrpcDataSender, channel=ManagedChannelOrphanWrapper{delegate=ManagedChannelImpl{logId=1, target=172.19.1.83:9993}}
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.s.g.s.DefaultStreamTask -- stop start SpanStream-1
07-18 21:51:07.007 [nel-Worker(2-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xa1c2d144, L:/172.19.2.90:35264 - R:172.19.1.83/172.19.1.83:9993] OUTBOUND DATA: streamId=3 padding=0 endStream=true length=0 bytes=
07-18 21:51:07.007 [eadlock-monitor] INFO c.n.p.p.m.DeadlockMonitorTask -- DeadlockMonitorTask stop completed
07-18 21:51:07.007 [nel-Worker(2-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xa1c2d144, L:/172.19.2.90:35264 - R:172.19.1.83/172.19.1.83:9993] INBOUND HEADERS: streamId=3 headers=GrpcHttp2ResponseHeaders[:status: 200, content-type: application/grpc, grpc-encoding: identity, grpc-accept-encoding: gzip] padding=0 endStream=false
07-18 21:51:07.007 [nel-Worker(2-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xa1c2d144, L:/172.19.2.90:35264 - R:172.19.1.83/172.19.1.83:9993] INBOUND DATA: streamId=3 padding=0 endStream=false length=5 bytes=0000000000
07-18 21:51:07.007 [nel-Worker(2-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xa1c2d144, L:/172.19.2.90:35264 - R:172.19.1.83/172.19.1.83:9993] INBOUND HEADERS: streamId=3 headers=GrpcHttp2ResponseHeaders[grpc-status: 0] padding=0 endStream=true
07-18 21:51:07.007 [l-Executor(3-0)] DEBUG c.n.p.p.s.g.ResponseStreamObserver -- DefaultStreamEventListener{SpanStream-1} onNext:
07-18 21:51:07.007 [l-Executor(3-0)] INFO c.n.p.p.s.g.ResponseStreamObserver -- DefaultStreamEventListener{SpanStream-1} onCompleted
07-18 21:51:07.007 [r-Executor(5-0)] DEBUG c.n.p.p.s.g.s.DefaultStreamTask -- dispatch thread interrupted Pinpoint-SpanGrpcDataSender-Executor(5-0)/name='SpanStream-1
07-18 21:51:07.007 [r-Executor(5-0)] INFO c.n.p.p.s.g.s.DefaultStreamTask -- dispatch thread end status:INTERRUPTED name='SpanStream-1
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.s.g.s.DefaultStreamTask -- stop end SpanStream-1
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.s.g.SpanGrpcDataSender -- SpanStream close()
07-18 21:51:07.007 [l-Executor(3-0)] DEBUG c.n.p.p.s.g.ReconnectExecutor -- already shutdown
07-18 21:51:07.007 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown SpanGrpcDataSender
07-18 21:51:07.007 [ DestroyJavaVM] DEBUG c.n.p.g.ManagedChannelUtils -- shutdown SpanGrpcDataSender
07-18 21:51:07.007 [l-Executor(3-0)] INFO c.n.p.p.s.g.SpanGrpcDataSender -- ConnectivityState changed before:READY, change:SHUTDOWN
07-18 21:51:07.007 [nel-Worker(2-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xa1c2d144, L:/172.19.2.90:35264 - R:172.19.1.83/172.19.1.83:9993] OUTBOUND GO_AWAY: lastStreamId=0 errorCode=0 length=0 bytes=
07-18 21:51:07.007 [ DestroyJavaVM] DEBUG c.n.p.g.c.DefaultChannelFactory -- shutdown SpanGrpcDataSender-eventLoopGroup
07-18 21:51:09.009 [nel-Worker(2-0)] DEBUG i.n.b.PoolThreadCache -- Freed 5 thread-local buffer(s) from thread: Pinpoint-SpanGrpcDataSender-Channel-Worker(2-0)
07-18 21:51:09.009 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown SpanGrpcDataSender-eventLoopExecutor
07-18 21:51:09.009 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown SpanGrpcDataSender-executorService
07-18 21:51:09.009 [ DestroyJavaVM] INFO c.n.p.p.s.g.StatGrpcDataSender -- Stop StatGrpcDataSender, channel=ManagedChannelOrphanWrapper{delegate=ManagedChannelImpl{logId=13, target=172.19.1.83:9992}}
07-18 21:51:09.009 [ DestroyJavaVM] INFO c.n.p.p.s.g.s.DefaultStreamTask -- stop start StatStream-3
07-18 21:51:09.009 [el-Worker(15-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0x00720d2e, L:/172.19.2.90:50656 - R:172.19.1.83/172.19.1.83:9992] OUTBOUND DATA: streamId=3 padding=0 endStream=true length=0 bytes=
07-18 21:51:09.009 [el-Worker(15-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0x00720d2e, L:/172.19.2.90:50656 - R:172.19.1.83/172.19.1.83:9992] INBOUND HEADERS: streamId=3 headers=GrpcHttp2ResponseHeaders[:status: 200, content-type: application/grpc, grpc-encoding: identity, grpc-accept-encoding: gzip] padding=0 endStream=false
07-18 21:51:09.009 [el-Worker(15-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0x00720d2e, L:/172.19.2.90:50656 - R:172.19.1.83/172.19.1.83:9992] INBOUND DATA: streamId=3 padding=0 endStream=false length=5 bytes=0000000000
07-18 21:51:09.009 [el-Worker(15-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0x00720d2e, L:/172.19.2.90:50656 - R:172.19.1.83/172.19.1.83:9992] INBOUND HEADERS: streamId=3 headers=GrpcHttp2ResponseHeaders[grpc-status: 0] padding=0 endStream=true
07-18 21:51:09.009 [-Executor(16-0)] DEBUG c.n.p.p.s.g.ResponseStreamObserver -- DefaultStreamEventListener{StatStream-3} onNext:
07-18 21:51:09.009 [-Executor(16-0)] INFO c.n.p.p.s.g.ResponseStreamObserver -- DefaultStreamEventListener{StatStream-3} onCompleted
07-18 21:51:09.009 [-Executor(16-0)] DEBUG c.n.p.p.s.g.ReconnectExecutor -- already shutdown
07-18 21:51:09.009 [-Executor(17-0)] DEBUG c.n.p.p.s.g.s.DefaultStreamTask -- dispatch thread interrupted Pinpoint-StatGrpcDataSender-Executor(17-0)/name='StatStream-3
07-18 21:51:09.009 [-Executor(17-0)] INFO c.n.p.p.s.g.s.DefaultStreamTask -- dispatch thread end status:INTERRUPTED name='StatStream-3
07-18 21:51:09.009 [ DestroyJavaVM] INFO c.n.p.p.s.g.s.DefaultStreamTask -- stop end StatStream-3
07-18 21:51:09.009 [ DestroyJavaVM] INFO c.n.p.p.s.g.StatGrpcDataSender -- StatStream close()
07-18 21:51:09.009 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown StatGrpcDataSender
07-18 21:51:09.009 [ DestroyJavaVM] DEBUG c.n.p.g.ManagedChannelUtils -- shutdown StatGrpcDataSender
07-18 21:51:09.009 [-Executor(16-0)] INFO c.n.p.p.s.g.StatGrpcDataSender -- ConnectivityState changed before:READY, change:SHUTDOWN
07-18 21:51:09.009 [el-Worker(15-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0x00720d2e, L:/172.19.2.90:50656 - R:172.19.1.83/172.19.1.83:9992] OUTBOUND GO_AWAY: lastStreamId=0 errorCode=0 length=0 bytes=
07-18 21:51:09.009 [ DestroyJavaVM] DEBUG c.n.p.g.c.DefaultChannelFactory -- shutdown StatGrpcDataSender-eventLoopGroup
07-18 21:51:11.011 [el-Worker(15-0)] DEBUG i.n.b.PoolThreadCache -- Freed 6 thread-local buffer(s) from thread: Pinpoint-StatGrpcDataSender-Channel-Worker(15-0)
07-18 21:51:11.011 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown StatGrpcDataSender-eventLoopExecutor
07-18 21:51:11.011 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown StatGrpcDataSender-executorService
07-18 21:51:11.011 [ DestroyJavaVM] INFO c.n.p.p.s.g.AgentGrpcDataSender -- Stop AgentGrpcDataSender, channel=ManagedChannelOrphanWrapper{delegate=ManagedChannelImpl{logId=9, target=172.19.1.83:9991}}
07-18 21:51:11.011 [ DestroyJavaVM] INFO c.n.p.p.s.g.PingStreamContext -- PingStream-2 close()
07-18 21:51:11.011 [ DestroyJavaVM] INFO c.n.p.p.r.g.GrpcCommandService -- stop() started
07-18 21:51:11.011 [ DestroyJavaVM] INFO c.n.p.p.r.g.GrpcCommandService -- stop
07-18 21:51:11.011 [ DestroyJavaVM] INFO c.n.p.p.r.g.GrpcCommandDispatcher -- close() started
07-18 21:51:11.011 [ DestroyJavaVM] INFO c.n.p.p.r.g.GrpcActiveThreadCountService -- close
07-18 21:51:11.011 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] OUTBOUND DATA: streamId=5 padding=0 endStream=true length=0 bytes=
07-18 21:51:11.011 [ DestroyJavaVM] INFO c.n.p.p.r.g.GrpcCommandDispatcher -- close() completed
07-18 21:51:11.011 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown AgentGrpcDataSender
07-18 21:51:11.011 [ DestroyJavaVM] DEBUG c.n.p.g.ManagedChannelUtils -- shutdown AgentGrpcDataSender
07-18 21:51:11.011 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] OUTBOUND DATA: streamId=3 padding=0 endStream=true length=0 bytes=
07-18 21:51:11.011 [-Executor(13-0)] INFO c.n.p.p.s.g.AgentGrpcDataSender -- ConnectivityState changed before:READY, change:SHUTDOWN
07-18 21:51:11.011 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] INBOUND HEADERS: streamId=5 headers=GrpcHttp2ResponseHeaders[grpc-status: 0] padding=0 endStream=true
07-18 21:51:11.011 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] INBOUND HEADERS: streamId=3 headers=GrpcHttp2ResponseHeaders[:status: 200, content-type: application/grpc, grpc-status: 0] padding=0 endStream=true
07-18 21:51:11.011 [el-Worker(12-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xe0726a9b, L:/172.19.2.90:51332 - R:172.19.1.83/172.19.1.83:9991] OUTBOUND GO_AWAY: lastStreamId=0 errorCode=0 length=0 bytes=
07-18 21:51:11.011 [ DestroyJavaVM] DEBUG c.n.p.g.c.DefaultChannelFactory -- shutdown AgentGrpcDataSender-eventLoopGroup
07-18 21:51:11.011 [-Executor(13-0)] INFO c.n.p.p.s.g.PingStreamContext -- PingStream-2 completed
07-18 21:51:11.011 [-Executor(13-0)] DEBUG c.n.p.p.s.g.ReconnectExecutor -- already shutdown
07-18 21:51:11.011 [-Executor(13-0)] INFO c.n.p.p.r.g.GrpcCommandService -- onCompleted
07-18 21:51:13.013 [el-Worker(12-0)] DEBUG i.n.b.PoolThreadCache -- Freed 8 thread-local buffer(s) from thread: Pinpoint-AgentGrpcDataSender-Channel-Worker(12-0)
07-18 21:51:13.013 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown AgentGrpcDataSender-eventLoopExecutor
07-18 21:51:13.013 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown AgentGrpcDataSender-executorService
07-18 21:51:13.013 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown MetadataGrpcDataSender
07-18 21:51:13.013 [ DestroyJavaVM] DEBUG c.n.p.g.ManagedChannelUtils -- shutdown MetadataGrpcDataSender
07-18 21:51:13.013 [l-Executor(8-0)] INFO c.n.p.p.s.g.MetadataGrpcDataSender -- ConnectivityState changed before:READY, change:SHUTDOWN
07-18 21:51:13.013 [nel-Worker(7-0)] DEBUG i.g.n.NettyClientHandler -- [id: 0xcc65ef4d, L:/172.19.2.90:51320 - R:172.19.1.83/172.19.1.83:9991] OUTBOUND GO_AWAY: lastStreamId=0 errorCode=0 length=0 bytes=
07-18 21:51:13.013 [ DestroyJavaVM] DEBUG c.n.p.g.c.DefaultChannelFactory -- shutdown MetadataGrpcDataSender-eventLoopGroup
07-18 21:51:15.015 [nel-Worker(7-0)] DEBUG i.n.b.PoolThreadCache -- Freed 7 thread-local buffer(s) from thread: Pinpoint-MetadataGrpcDataSender-Channel-Worker(7-0)
07-18 21:51:15.015 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown MetadataGrpcDataSender-eventLoopExecutor
07-18 21:51:15.015 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown MetadataGrpcDataSender-executorService
07-18 21:51:15.015 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown dnsExecutor
07-18 21:51:15.015 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown reconnectScheduledExecutor
07-18 21:51:15.015 [ DestroyJavaVM] DEBUG c.n.p.g.ExecutorUtils -- shutdown ScheduledReporter
@JadenTS
Copy link
Author

JadenTS commented Jul 19, 2023

image

javaagent 실행옵션 부분이 잘못되어 수정 후, 위와 같은 상태입니다.

@JadenTS
Copy link
Author

JadenTS commented Jul 19, 2023

image

networktest 결과는 정상으로 확인됩니다.

@jaehong-kim
Copy link
Contributor

@JadenTS
처음 공유해 주신 로그를 보면 pinpoint agent 실행 후 바로 종료됩니다.

07-18 21:51:07.007 [ main] DEBUG c.n.p.p.DefaultAgent -- Agent status is changed. RUNNING
07-18 21:51:07.007 [ main] INFO c.n.p.p.DefaultAgent -- Starting pinpoint Agent.

...

07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.DefaultAgent -- stop() started. threadName:DestroyJavaVM
07-18 21:51:07.007 [ DestroyJavaVM] DEBUG c.n.p.p.DefaultAgent -- Agent status is changed. STOPPED
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.DefaultAgent -- Stopping pinpoint Agent.
07-18 21:51:07.007 [ DestroyJavaVM] INFO c.n.p.p.AgentInfoSender -- AgentInfoSender stopped

중간에 어떤 문제가 발생한 것 같은데 관련 내용이 없어서 알 수가 없습니다.
이후 글을 보면 javaagent 실행옵션 부분이 잘못되어 수정하셨다고 했는데, 수정 후의 실행 로그도 공유를 부탁 드립니다.

@JadenTS
Copy link
Author

JadenTS commented Jul 19, 2023 •
edited
Loading

@jaehong-kim
java는 다음과 같이 실행하였고
java -jar -Dspring.profiles.active=dev -javaagent:/pinpoint-agent-2.5.2/pinpoint-bootstrap-2.5.2.jar -Dpinpoint.agentId=test3 -Dpinpoint.applicationName=test3 -Dpinpoint.config=/pinpoint-agent-2.5.2/pinpoint-root.config noticegateway/API_Server_Traffic_Gateway-DEV*.jar 2>&1 &

에이전트 로그 파일 첨부합니다.
pinpoint.log

pinpoint_stat.log

로그는 DEBUG로 설정하였는데
딱히 Error나 Exception은 찾지를 못하겠습니다.

pinpoint버전 관련하여 정정합니다.
hbase는 1.2.7
web/collector는 2.2.2
agent는 2.5.2입니다.

서비스 jar는 springboot 이며 2.7.12 입니다.

추가로, 서버는 모두 GCP의 VM서버입니다.

친절한 답변 감사합니다.

@jaehong-kim
Copy link
Contributor

@JadenTS
The logs were all normal.
Check the status of the collector and web servers.

@JadenTS
Copy link
Author

JadenTS commented Jul 22, 2023 •
edited
Loading

@jaehong-kim
도움 감사합니다.
server와 collector 재 설치 후 잘 동작하는 것이 확인 되었습니다.
agent, collecotr, server 모두 2.5.2입니다.

다만, 추가로 아래와 같이 Active Request가 ERROR와 NOT FOUND로 보이는데 어떤 부분을 살펴봐야 하는지요?
image

collector와 server의 실행은 아래와 같이 했습니다.
nohup java -jar -Dpinpoint.zookeeper.address=localhost pinpoint-collector-boot-2.5.2.jar >/dev/null 2>&1 &
nohup java -jar -Dpinpoint.zookeeper.address=localhost pinpoint-web-boot-2.5.2.jar >/dev/null 2>&1 &

아래는 agent와 collector의 로그입니다.
API-Server-Traffic-Gateway-Dev.log

pinpoint-collector.log

collecotr의 로그를 보니

[rJobWorker(7-0)] WARN c.n.p.c.c.z.ZookeeperJobWorker : handleUpdate failed. caused:Instance must be connected. connectionState:null, jobSize:1
com.navercorp.pinpoint.common.server.cluster.zookeeper.exception.ConnectionException: Instance must be connected. connectionState:null
at com.navercorp.pinpoint.common.server.cluster.zookeeper.CuratorZookeeperClient.checkState(CuratorZookeeperClient.java:253) ~[pinpoint-commons-server-cluster-2.5.2.jar!/:2.5.2]
at com.navercorp.pinpoint.common.server.cluster.zookeeper.CuratorZookeeperClient.createPath(CuratorZookeeperClient.java:81) ~[pinpoint-commons-server-cluster-2.5.2.jar!/:2.5.2]
at com.navercorp.pinpoint.collector.cluster.zookeeper.ZookeeperJobWorker.handleUpdate(ZookeeperJobWorker.java:278) ~[classes!/:2.5.2]
at com.navercorp.pinpoint.collector.cluster.zookeeper.ZookeeperJobWorker.handle(ZookeeperJobWorker.java:259) ~[classes!/:2.5.2]
at com.navercorp.pinpoint.collector.cluster.zookeeper.ZookeeperJobWorker.run(ZookeeperJobWorker.java:208) ~[classes!/:2.5.2]
at java.lang.Thread.run(Thread.java:833) ~[?:?]

이런 부분이 있는데, 방화벽 이슈일까요?
다른 항목들은 모두 수집이 잘 되고 있는데..

@JadenTS
Copy link
Author

JadenTS commented Jul 24, 2023 •
edited
Loading

지금 다시 확인해 보니..

web/collector 기준으로
2.5.2에서는 RealTime에서 아래 부분이
제대로 체크가 안되고 있네요.. ㅠㅠ(실시간으로 확인이 안됨...)

image

2.3.3은
확인이 됩니다;

단지 jar만 바꿔서 실행한 차이입니다.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@jaehong-kim @JadenTS