Please answer the following questions for yourself before submitting an issue. YOU MAY DELETE THE PREREQUISITES SECTION.
Please help provide information about the failure if this is a bug. If it is not a bug, please remove the rest of this template.
Please provide detailed steps for reproducing the issue.
Please provide any relevant information about your setup. This is important in case the issue is not reproducible except for under certain conditions.
Please include any relevant log snippets or files here.
<?php
class PrepareInterceptor extends \Pinpoint\Interceptor
{
var $apiId = -1;
public function __construct()
{
pinpoint_log(PINPOINT_DEBUG, "PDO interceptor prepare");
$this->apiId = pinpoint_add_api("prepare", -1);
error_log(date('Y-m-d H:i:s') . 'pdo pre interceptor got api id' . $this->apiId , 3, '/tmp/pdo.log');
}
public function onBefore($callId, $args)
{
throw new \exception('ssss');
$trace = pinpoint_get_current_trace();
error_log(var_export($trace, 1), 3, '/tmp/pdo.log');
if ($trace)
{
pinpoint_log(PINPOINT_DEBUG, "PDO ready trace");
$event = $trace->traceBlockBegin($callId);
$event->markBeforeTime();
$event->addAnnotation(PINPOINT_ANNOTATION_ARGS, htmlspecialchars(print_r($args,true),ENT_QUOTES));
$event->setApiId($this->apiId);
$event->setServiceType(PINPOINT_PHP_RPC_TYPE);
}
}
public function onEnd($callId, $data)
{
$trace = pinpoint_get_current_trace();
if ($trace)
{
$args = $data["args"];
$retArgs = $data["result"];
$event = $trace->getEvent($callId);
if ($event)
{
$event->addAnnotation(PINPOINT_ANNOTATION_RETURN, htmlspecialchars(print_r($retArgs,true), ENT_QUOTES));
$event->markAfterTime();
$trace->traceBlockEnd($event);
}
}
}
public function onException($callId, $exceptionStr)
{
$trace = pinpoint_get_current_trace();
if ($trace)
{
$event = $trace->getEvent($callId);
if ($event)
{
$event->markAfterTime();
$event->setExceptionInfo($exceptionStr);
}
}
}
}
class PDOPlugin extends \Pinpoint\Plugin
{
public function __construct()
{
// you have to call the construct of parent class here
parent::__construct();
pinpoint_log(PINPOINT_DEBUG, "PDO construct");
$i = new PrepareInterceptor();
pinpoint_log(PINPOINT_DEBUG, "PDO prepare initial finish");
$this->addInterceptor($i, "PDO::prepare", 'pdo_plugin.php');
$this->addSimpleInterceptor("date", -1);
}
}
2019-01-21 10:31:58 [pinpoint] [4516:4516] php_common.cpp:331 [DEBUG] start agent ...
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] common.AgentID=pp_test
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] common.ApplicationName=PA_Sandbox
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] common.LogFileRootPath=/var/log/pinpoint_agent
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] common.PPLogLevel=DEBUG|INFO|ERROR
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] common.CollectorSpanIp=10.130.113.73
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] common.CollectorStatIp=10.130.113.73
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] common.CollectorTcpIp=10.130.113.73
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:193 [INFO] common.CollectorSpanPort=9996
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:193 [INFO] common.CollectorStatPort=9995
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:193 [INFO] common.CollectorTcpPort=9994
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] common.PluginInclude=PhpRequestPlugin;PhpInternalFunctionsPlugin;CurlPlugin;PDOPlugin;
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:208 [TRACE] [common.SkipTraceTime] config not find use default [-1]
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:208 [TRACE] [common.TraceLimit] config not find use default [-1]
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] common.PluginExclude=ExcludePlugin
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:169 [INFO] [test.ApiTableFile] config not find use default [NULL]
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] PHP.PluginEntryFile=plugins_create.php
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:173 [TRACE] PHP.PluginRootDir=/usr/local/pinpoint-c-agent/quickstart/php/web/plugins/
2019-01-21 10:31:58 [pinpoint] [4516:4516] pinpoint_api.cpp:208 [TRACE] [common.ReconTimeOut] config not find use default [5]
2019-01-21 10:31:58 [pinpoint] [4555:4555] php_common.cpp:331 [DEBUG] start agent ...
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] common.AgentID=pp_test
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] common.ApplicationName=PA_Sandbox
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] common.LogFileRootPath=/var/log/pinpoint_agent
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] common.PPLogLevel=DEBUG|INFO|ERROR
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] common.CollectorSpanIp=10.130.113.73
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] common.CollectorStatIp=10.130.113.73
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] common.CollectorTcpIp=10.130.113.73
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:193 [INFO] common.CollectorSpanPort=9996
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:193 [INFO] common.CollectorStatPort=9995
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:193 [INFO] common.CollectorTcpPort=9994
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] common.PluginInclude=PhpRequestPlugin;PhpInternalFunctionsPlugin;CurlPlugin;PDOPlugin;
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:208 [TRACE] [common.SkipTraceTime] config not find use default [-1]
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:208 [TRACE] [common.TraceLimit] config not find use default [-1]
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] common.PluginExclude=ExcludePlugin
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:169 [INFO] [test.ApiTableFile] config not find use default [NULL]
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] PHP.PluginEntryFile=plugins_create.php
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:173 [TRACE] PHP.PluginRootDir=/usr/local/pinpoint-c-agent/quickstart/php/web/plugins/
2019-01-21 10:31:58 [pinpoint] [4555:4555] pinpoint_api.cpp:208 [TRACE] [common.ReconTimeOut] config not find use default [5]
2019-01-21 10:32:02 [pinpoint] [4518:4518] pinpoint_ext.cpp:433 [DEBUG] plugins full path /usr/local/pinpoint-c-agent/quickstart/php/web/plugins/plugins_create.php
2019-01-21 10:32:02 [pinpoint] [4518:4518] pdo_plugin.php:66 [DEBUG] PDO construct
2019-01-21 10:32:02 [pinpoint] [4518:4518] pdo_plugin.php:7 [DEBUG] PDO interceptor prepare
2019-01-21 10:32:02 [pinpoint] [4518:4518] pdo_plugin.php:68 [DEBUG] PDO prepare initial finish
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_ext.cpp:390 [TRACE] c++ plugin count=1
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_ext.cpp:398 [TRACE] php plugin count=2
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_ext.cpp:400 [TRACE] all plugins count = 3
2019-01-21 10:32:02 [pinpoint] [4518:4589] memory_pool.h:118 [DEBUG] SafeShareMemory MainProcessChecker_1548037918897 size 0
2019-01-21 10:32:02 [pinpoint] [4518:4589] memory_pool.h:125 [DEBUG] MainProcessChecker_1548037918897 expand 208 createrPid 4518
2019-01-21 10:32:02 [pinpoint] [4518:4589] memory_pool.h:135 [DEBUG] SafeShareMemory:MainProcessChecker_1548037918897 pid:4518
2019-01-21 10:32:02 [pinpoint] [4518:4589] trace_data_sender.cpp:42 [INFO] TraceDataSender::init() start.
2019-01-21 10:32:02 [pinpoint] [4518:4589] api_data_sender.cpp:240 [INFO] ApiDataSender::init() start.
2019-01-21 10:32:02 [pinpoint] [4518:4589] string_data_sender.cpp:95 [INFO] StringDataSender::init() start.
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_agent.cpp:471 [INFO] PinpointAgent::registerPlugins len(plugins)=3
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_agent.cpp:472 [INFO] PinpointAgent::registerPlugins include=PhpRequestPlugin;PhpInternalFunctionsPlugin;CurlPlugin;PDOPlugin;
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_agent.cpp:473 [INFO] PinpointAgent::registerPlugins exclude=ExcludePlugin
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_agent.cpp:500 [TRACE] plugin=PhpRequestPlugin
2019-01-21 10:32:02 [pinpoint] [4518:4589] request_plugin.cpp:90 [INFO] PhpRequestInterceptor::init() SUCCESS!
2019-01-21 10:32:02 [pinpoint] [4518:4589] interceptor.cpp:77 [DEBUG] add interceptor, name=_request
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_agent.cpp:500 [TRACE] plugin=CurlPlugin
2019-01-21 10:32:02 [pinpoint] [4518:4589] interceptor.cpp:77 [DEBUG] add interceptor, name=curl_exec
2019-01-21 10:32:02 [pinpoint] [4518:4589] interceptor.cpp:77 [DEBUG] add interceptor, name=curl_setopt
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_agent.cpp:500 [TRACE] plugin=PDOPlugin
2019-01-21 10:32:02 [pinpoint] [4518:4589] interceptor.cpp:77 [DEBUG] add interceptor, name=PDO::prepare
2019-01-21 10:32:02 [pinpoint] [4518:4589] interceptor.cpp:77 [DEBUG] add interceptor, name=date
2019-01-21 10:32:02 [pinpoint] [4518:4589] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=agentDataTask, interval=1, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4518:4589] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=agentMonitorBatchTask, interval=5000, callTimes=-1
2019-01-21 10:32:02 [pinpoint] [4518:4589] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x2497860]
2019-01-21 10:32:02 [pinpoint] [4518:4589] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x2497b20]
2019-01-21 10:32:02 [pinpoint] [4518:4589] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x2497fd0]
2019-01-21 10:32:02 [pinpoint] [4518:4589] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x7fd1c4036af0]
2019-01-21 10:32:02 [pinpoint] [4518:4589] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x7fd1c4036e10]
2019-01-21 10:32:02 [pinpoint] [4518:4589] string_data_sender.cpp:113 [TRACE] TStringMetaData: [0x7fd1c4036310]
2019-01-21 10:32:02 [pinpoint] [4518:4589] string_data_sender.cpp:113 [TRACE] TStringMetaData: [0x7fd1c40363d0]
2019-01-21 10:32:02 [pinpoint] [4518:4589] string_data_sender.cpp:113 [TRACE] TStringMetaData: [0x7fd1c4036460]
2019-01-21 10:32:02 [pinpoint] [4518:4589] pinpoint_ext.cpp:411 [INFO] pinpoint agent start !!! code = 0
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:193 [INFO] start_connect: 10.130.113.73:9994
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=0, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918897, apiId=-1, apiInfo=curl_exec, line=<null>, type=<null>)]
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=1, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918897, apiId=-2, apiInfo=curl_setopt, line=<null>, type=<null>)]
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=2, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918897, apiId=-3, apiInfo=prepare, line=<null>, type=<null>)]
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=3, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918897, apiId=-4, apiInfo=PHP Request, line=<null>, type=100)]
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=4, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918897, apiId=-5, apiInfo=date, line=<null>, type=0)]
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=5, tBase=[TStringMetaData(agentId=pp_test, agentStartTime=1548037918897, stringId=-1, stringValue=exception)]
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=6, tBase=[TStringMetaData(agentId=pp_test, agentStartTime=1548037918897, stringId=-2, stringValue=error)]
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=7, tBase=[TStringMetaData(agentId=pp_test, agentStartTime=1548037918897, stringId=-3, stringValue=warning)]
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add agentDataTask
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add agentMonitorBatchTask
2019-01-21 10:32:02 [pinpoint] [4518:4593] agent_data_sender.cpp:123 [INFO] TAgentInfo: [0x7fd1c40377d0]
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=agentDataTask, interval=86400000, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add agentDataTask
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=8, tBase=[TAgentInfo(hostname=test.gadmobe.com, ip=167.99.72.194, ports=80, agentId=pp_test, applicationName=PA_Sandbox, serviceType=1500, pid=4518, agentVersion=1.6.0-SNAPSHOT, vmVersion=, startTimestamp=1548037918897, endTimestamp=<null>, endStatus=<null>, serverMetaData=<null>, jvmInfo=<null>)]
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.h:272 [INFO] state change: 1 => 3
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:251 [INFO] 10.130.113.73:9994 Connect success.
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.h:272 [INFO] state change: 3 => 10
2019-01-21 10:32:02 [pinpoint] [4518:4590] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=handShakeTask, interval=1, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4518:4590] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=pingTask, interval=300, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add handShakeTask
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add pingTask
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=handShakeTask, interval=60, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add handShakeTask
2019-01-21 10:32:02 [pinpoint] [4518:4590] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4518:4590] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4518:4590] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4518:4590] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4518:4590] hand_shake_sender.cpp:205 [INFO] get control message: ControlMessage[type=map; data=(ControlMessage[type=string; data=(code)]=>ControlMessage[type=long; data=(0)], ControlMessage[type=string; data=(subCode)]=>ControlMessage[type=long; data=(2)])]
2019-01-21 10:32:02 [pinpoint] [4518:4590] hand_shake_sender.cpp:234 [INFO] handshake response code = Duplex Connection successfully established.
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.h:272 [INFO] state change: 10 => 12
2019-01-21 10:32:02 [pinpoint] [4518:4590] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4518:4590] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4518:4590] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4518:4590] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4518:4590] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4518:4593] pinpoint_ping.cpp:63 [INFO] send ping: pingId=0, stateVersion=0, stateCode=12
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=pingTask, interval=300000, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4518:4593] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add pingTask
2019-01-21 10:32:02 [pinpoint] [4518:4590] pinpoint_client.cpp:540 [DEBUG] get Pong
2019-01-21 10:32:02 [pinpoint] [4557:4557] pinpoint_ext.cpp:433 [DEBUG] plugins full path /usr/local/pinpoint-c-agent/quickstart/php/web/plugins/plugins_create.php
2019-01-21 10:32:02 [pinpoint] [4557:4557] pdo_plugin.php:66 [DEBUG] PDO construct
2019-01-21 10:32:02 [pinpoint] [4557:4557] pdo_plugin.php:7 [DEBUG] PDO interceptor prepare
2019-01-21 10:32:02 [pinpoint] [4557:4557] pdo_plugin.php:68 [DEBUG] PDO prepare initial finish
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_ext.cpp:390 [TRACE] c++ plugin count=1
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_ext.cpp:398 [TRACE] php plugin count=2
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_ext.cpp:400 [TRACE] all plugins count = 3
2019-01-21 10:32:02 [pinpoint] [4557:4596] memory_pool.h:118 [DEBUG] SafeShareMemory MainProcessChecker_1548037918958 size 0
2019-01-21 10:32:02 [pinpoint] [4557:4596] memory_pool.h:125 [DEBUG] MainProcessChecker_1548037918958 expand 208 createrPid 4557
2019-01-21 10:32:02 [pinpoint] [4557:4596] memory_pool.h:135 [DEBUG] SafeShareMemory:MainProcessChecker_1548037918958 pid:4557
2019-01-21 10:32:02 [pinpoint] [4557:4596] trace_data_sender.cpp:42 [INFO] TraceDataSender::init() start.
2019-01-21 10:32:02 [pinpoint] [4557:4596] api_data_sender.cpp:240 [INFO] ApiDataSender::init() start.
2019-01-21 10:32:02 [pinpoint] [4557:4596] string_data_sender.cpp:95 [INFO] StringDataSender::init() start.
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_agent.cpp:471 [INFO] PinpointAgent::registerPlugins len(plugins)=3
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_agent.cpp:472 [INFO] PinpointAgent::registerPlugins include=PhpRequestPlugin;PhpInternalFunctionsPlugin;CurlPlugin;PDOPlugin;
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_agent.cpp:473 [INFO] PinpointAgent::registerPlugins exclude=ExcludePlugin
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_agent.cpp:500 [TRACE] plugin=PhpRequestPlugin
2019-01-21 10:32:02 [pinpoint] [4557:4596] request_plugin.cpp:90 [INFO] PhpRequestInterceptor::init() SUCCESS!
2019-01-21 10:32:02 [pinpoint] [4557:4596] interceptor.cpp:77 [DEBUG] add interceptor, name=_request
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_agent.cpp:500 [TRACE] plugin=CurlPlugin
2019-01-21 10:32:02 [pinpoint] [4557:4596] interceptor.cpp:77 [DEBUG] add interceptor, name=curl_exec
2019-01-21 10:32:02 [pinpoint] [4557:4596] interceptor.cpp:77 [DEBUG] add interceptor, name=curl_setopt
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_agent.cpp:500 [TRACE] plugin=PDOPlugin
2019-01-21 10:32:02 [pinpoint] [4557:4596] interceptor.cpp:77 [DEBUG] add interceptor, name=PDO::prepare
2019-01-21 10:32:02 [pinpoint] [4557:4596] interceptor.cpp:77 [DEBUG] add interceptor, name=date
2019-01-21 10:32:02 [pinpoint] [4557:4596] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=agentDataTask, interval=1, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4557:4596] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=agentMonitorBatchTask, interval=5000, callTimes=-1
2019-01-21 10:32:02 [pinpoint] [4557:4596] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x2c15860]
2019-01-21 10:32:02 [pinpoint] [4557:4596] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x2c15b20]
2019-01-21 10:32:02 [pinpoint] [4557:4596] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x2c15fd0]
2019-01-21 10:32:02 [pinpoint] [4557:4596] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x7f0cdc036af0]
2019-01-21 10:32:02 [pinpoint] [4557:4596] api_data_sender.cpp:280 [TRACE] TApiMetaData: [0x7f0cdc036e10]
2019-01-21 10:32:02 [pinpoint] [4557:4596] string_data_sender.cpp:113 [TRACE] TStringMetaData: [0x7f0cdc036310]
2019-01-21 10:32:02 [pinpoint] [4557:4596] string_data_sender.cpp:113 [TRACE] TStringMetaData: [0x7f0cdc0363d0]
2019-01-21 10:32:02 [pinpoint] [4557:4596] string_data_sender.cpp:113 [TRACE] TStringMetaData: [0x7f0cdc036460]
2019-01-21 10:32:02 [pinpoint] [4557:4596] pinpoint_ext.cpp:411 [INFO] pinpoint agent start !!! code = 0
2019-01-21 10:32:02 [pinpoint] [4557:4600] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add agentDataTask
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:193 [INFO] start_connect: 10.130.113.73:9994
2019-01-21 10:32:02 [pinpoint] [4557:4600] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add agentMonitorBatchTask
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=0, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918958, apiId=-1, apiInfo=curl_exec, line=<null>, type=<null>)]
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=1, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918958, apiId=-2, apiInfo=curl_setopt, line=<null>, type=<null>)]
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=2, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918958, apiId=-3, apiInfo=prepare, line=<null>, type=<null>)]
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=3, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918958, apiId=-4, apiInfo=PHP Request, line=<null>, type=100)]
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=4, tBase=[TApiMetaData(agentId=pp_test, agentStartTime=1548037918958, apiId=-5, apiInfo=date, line=<null>, type=0)]
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=5, tBase=[TStringMetaData(agentId=pp_test, agentStartTime=1548037918958, stringId=-1, stringValue=exception)]
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=6, tBase=[TStringMetaData(agentId=pp_test, agentStartTime=1548037918958, stringId=-2, stringValue=error)]
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=7, tBase=[TStringMetaData(agentId=pp_test, agentStartTime=1548037918958, stringId=-3, stringValue=warning)]
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.h:272 [INFO] state change: 1 => 3
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:251 [INFO] 10.130.113.73:9994 Connect success.
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.h:272 [INFO] state change: 3 => 10
2019-01-21 10:32:02 [pinpoint] [4557:4597] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=handShakeTask, interval=1, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4557:4597] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=pingTask, interval=300, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4557:4600] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add handShakeTask
2019-01-21 10:32:02 [pinpoint] [4557:4600] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add pingTask
2019-01-21 10:32:02 [pinpoint] [4557:4600] agent_data_sender.cpp:123 [INFO] TAgentInfo: [0x7f0cdc039590]
2019-01-21 10:32:02 [pinpoint] [4557:4600] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=agentDataTask, interval=86400000, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.cpp:336 [DEBUG] doRequest: requestId=8, tBase=[TAgentInfo(hostname=test.gadmobe.com, ip=167.99.72.194, ports=80, agentId=pp_test, applicationName=PA_Sandbox, serviceType=1500, pid=4557, agentVersion=1.6.0-SNAPSHOT, vmVersion=, startTimestamp=1548037918958, endTimestamp=<null>, endStatus=<null>, serverMetaData=<null>, jvmInfo=<null>)]
2019-01-21 10:32:02 [pinpoint] [4557:4600] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add agentDataTask
2019-01-21 10:32:02 [pinpoint] [4557:4600] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=handShakeTask, interval=60, callTimes=1
2019-01-21 10:32:02 [pinpoint] [4557:4600] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add handShakeTask
2019-01-21 10:32:02 [pinpoint] [4557:4597] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4557:4597] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4557:4597] hand_shake_sender.cpp:205 [INFO] get control message: ControlMessage[type=map; data=(ControlMessage[type=string; data=(code)]=>ControlMessage[type=long; data=(0)], ControlMessage[type=string; data=(subCode)]=>ControlMessage[type=long; data=(2)])]
2019-01-21 10:32:02 [pinpoint] [4557:4597] hand_shake_sender.cpp:234 [INFO] handshake response code = Duplex Connection successfully established.
2019-01-21 10:32:02 [pinpoint] [4557:4597] pinpoint_client.h:272 [INFO] state change: 10 => 12
2019-01-21 10:32:02 [pinpoint] [4557:4597] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4557:4597] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4557:4597] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4557:4597] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4557:4597] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4557:4597] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:02 [pinpoint] [4557:4597] serializer.cpp:226 [TRACE] get tbase: type=320, context=TResult(success=1, message=<null>)
2019-01-21 10:32:03 [pinpoint] [4557:4600] pinpoint_ping.cpp:63 [INFO] send ping: pingId=0, stateVersion=0, stateCode=12
2019-01-21 10:32:03 [pinpoint] [4557:4600] executor.cpp:73 [DEBUG] ScheduledExecutor: scheduledExecutor add task: name=pingTask, interval=300000, callTimes=1
2019-01-21 10:32:03 [pinpoint] [4557:4600] executor.cpp:94 [DEBUG] run addTask_ in io.run() thread: add pingTask
2019-01-21 10:32:03 [pinpoint] [4557:4597] pinpoint_client.cpp:540 [DEBUG] get Pong