こんにんちは。IBM Automation Technical Salesの岩品です。
Instana Advent Calendar ２日目は、Instanaの基本的な挙動を、実機での環境を踏まえてみていきたいと思います。
Instanaの自動検知と自動構成
Instanaの製品マニュアルの「始めに」に、以下のように記述されています。
ホスト・エージェントをインストールすると、特定のテクノロジーをモニターするように設計された Instana センサーが自動的にインストールされます。 ほとんどのセンサーは、ホスト・エージェントのインストール後に自動的にインストールされ、構成されます。
これがInstanaの特徴である「自動検知」「自動構成」によるシステム環境の可視化です。Kubernetesなど複雑で動的な環境において、大いにその力を発揮します。これは下図のような仕組みで実行されています。
これが実際どのように動いているのか、シンプルな仮想化環境において、実機でログと挙動を追いながら見ていきたいと思います。
検証環境
払い出されたばかりの RHEL8.9環境が導入されています。この環境は外部ネットワークに直接アクセスできる環境です。
[root@itz-3100008gyq-sx2l ~]# cat /etc/redhat-release
Red Hat Enterprise Linux release 8.9 (Ootpa)
後々の検証のために、Java環境とOpenLbertyを導入し、用意しておきます。
以下のコマンドで、Javaを導入します。
[root@itz-3100008gyq-sx2l tmp]# yum install java-1.8.0-openjdk
以下のコマンドで、OpenLibertyをダウンロードして、/optディレクトリ配下に導入し、defaultServerを作成します。
[root@itz-3100008gyq-sx2l tmp]# wget https://public.dhe.ibm.com/ibmdl/export/pub/software/openliberty/runtime/release/23.0.0.11/openliberty-webProfile10-23.0.0.11.zip
[root@itz-3100008gyq-sx2l tmp]# unzip openliberty-webProfile10-23.0.0.11.zip -d /opt/
[root@itz-3100008gyq-sx2l bin]# /opt/wlp/bin/server create
サーバー defaultServer が作成されました。
defaultServer の 構成ファイル server.xmlを開き、Libertyの監視に必要となる monitor-1.0フィーチャーを有効化しておきます。
[root@itz-3100008gyq-sx2l bin]# cd /opt/wlp/usr/servers/defaultServer/
[root@itz-3100008gyq-sx2l defaultServer]# ls
apps dropins server.env server.xml workarea
[root@itz-3100008gyq-sx2l defaultServer]# cat server.xml
<?xml version="1.0" encoding="UTF-8"?>
<server description="new server">
<!-- Enable features -->
<featureManager>
<feature>webProfile-10.0</feature>
<feature>monitor-1.0</feature>
</featureManager>
...
</server>
この時点では、まだ Java および Libertyサーバーは起動されていません。
Intanaエージェントの導入
それでは、Instanaエージェントの挙動を見ていきたいと思います。
まず１行コマンドで、Instnaエージェントを導入します。２分ほどで導入完了します。
[root@itz-3100008gyq-sx2l ~]# curl -o setup_agent.sh https://setup.instana.io/agent && chmod 700 ./setup_agent.sh && sudo ./setup_agent.sh -a xxxxxxxxxxxxxxx -d xxxxxxxxxxxxxxx -t dynamic -e ingress-orange-saas.instana.io:443 -s
% Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
100 24158 100 24158 0 0 17620 0 0:00:01 0:00:01 --:--:-- 17607
Setting up the dynamic Instana agent for Linux
Are you sure? [y/N] y
Setting up Instana RPM repository
Updating YUM metadata
Installing Instana agent
GPG 鍵 0x31933E68 をインポート中:
Userid : "stan@instana.com <stan@instana.com>"
Fingerprint: 386F 852E 8B64 01EB 34FD 86E8 6AA7 718D 3193 3E68
From : https://packages.instana.io/Instana.gpg
Instana agent service is active and agent is reinstalled. Service will be restarted
Intanaエージェントの開始
すでに Instnaエージェントが起動していますので、エージェント・ログを確認してみましょう。エージェント・ログは/opt/instana/agent/data/log/ディレクトリに配置されています。
[agent.log]
Instana Agentが導入され起動開始しています
2023-12-01T00:19:21.774-06:00 | INFO | features-3-thread-1 | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Bootstrapping agent
2023-12-01T00:19:21.778-06:00 | INFO | features-3-thread-1 | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Running on itz-3100008gyq-sx2l.dte.demo.ibmcloud.com, Linux 4.18.0-477.27.1.el8_8.x86_64 amd64
2023-12-01T00:19:21.778-06:00 | INFO | features-3-thread-1 | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Using PATH set to /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin
2023-12-01T00:19:21.778-06:00 | INFO | features-3-thread-1 | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Using Java 1.8.0_392 OpenJDK 64-Bit Server VM 25.392-b08 (Azul Systems, Inc.) from /opt/instana/agent/jvm
2023-12-01T00:19:21.778-06:00 | INFO | features-3-thread-1 | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Using JAVA_OPTS -XX:+UnlockDiagnosticVMOptions -Xmx186071K -XX:MaxDirectMemorySize=20674K -XX:+UseSerialGC -Xmn32m -Xss640k -XX:InitialCodeCacheSize=8m -XX:CodeCacheExpansionSize=64k -XX:ReservedCodeCacheSize=16m -XX:-TieredCompilation -XX:CICompilerCount=1 -XX:CompressedClassSpaceSize=24m -XX:MinMetaspaceExpansion=1M -XX:MaxMetaspaceExpansion=8M -XX:MaxMetaspaceSize=128m -XX:MaxDirectMemorySize=128m -XX:+PerfDisableSharedMem -Dcom.ibm.tools.attach.enable=no -Xverify:none -Xms64m -Dcom.ibm.jsse2.overrideDefaultTLS=true -Dlog4j2.formatMsgNoLookups=true -Djdk.tls.client.protocols=TLSv1,TLSv1.1,TLSv1.2,TLSv1.3 -Djava.endorsed.dirs=/opt/instana/agent/jvm/jre/lib/endorsed:/opt/instana/agent/jvm/lib/endorsed:/opt/instana/agent/lib/endorsed -Djava.ext.dirs=/opt/instana/agent/jvm/jre/lib/ext:/opt/instana/agent/jvm/lib/ext:/opt/instana/agent/lib/ext -Dkaraf.instances=/opt/instana/agent/instances -Dkaraf.home=/opt/instana/agent -Dkaraf.base=/opt/instana/agent -Dkaraf.data=/opt/instana/agent/data -Dkaraf.etc=/opt/instana/agent/etc -Dkaraf.log=/opt/instana/agent/data/log -Dkaraf.restart.jvm.supported=true -Djava.io.tmpdir=/opt/instana/agent/data/tmp -Djava.util.logging.config.file=/opt/instana/agent/etc/java.util.logging.properties -Dkaraf.startLocalConsole=false -Dkaraf.startRemoteShell=false
2023-12-01T00:19:21.779-06:00 | INFO | features-3-thread-1 | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Agent installed to /opt/instana/agent and started by root
2023-12-01T00:19:21.779-06:00 | INFO | features-3-thread-1 | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Dynamic agent running latest version and installed from package dynamic
2023-12-01T00:19:21.798-06:00 | INFO | features-3-thread-1 | pdateManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Automatic updates scheduled every DAY at 04:30
2023-12-01T00:19:21.818-06:00 | INFO | agent-bootstrap | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Installing agent
2023-12-01T00:19:46.789-06:00 | INFO | features-3-thread-1 | ntPidServiceImpl | com.instana.agent - 1.1.706 | Agent PID '54695'
2023-12-01T00:19:46.790-06:00 | INFO | features-3-thread-1 | Agent | com.instana.agent - 1.1.706 | Agent Mode: apm
2023-12-01T00:19:46.811-06:00 | INFO | features-3-thread-1 | SchedulerImpl | com.instana.agent - 1.1.706 | Started sensor scheduler with 4 threads on 1 CPUs.
2023-12-01T00:19:49.119-06:00 | INFO | features-3-thread-1 | AgentImpl | com.instana.agent - 1.1.706 | Starting Instana agent.
2023-12-01T00:19:49.144-06:00 | INFO | features-3-thread-1 | AgentImpl | com.instana.agent - 1.1.706 | Skipping agent auto-restart schedule on Linux
2023-12-01T00:19:49.144-06:00 | INFO | features-3-thread-1 | AgentImpl | com.instana.agent - 1.1.706 | Instana agent started.
[agent.log]
その後、ディスカバリー・プラグインの導入が開始されます。
また、Instana Agent が、Instana SaaSのバックエンドに接続しています。
2023-12-01T00:19:49.164-06:00 | INFO | agent-starter | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Installing discoveries
2023-12-01T00:19:49.195-06:00 | INFO | agent-bootstrap | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-init/1.0.7
2023-12-01T00:19:49.195-06:00 | INFO | agent-bootstrap | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-netty-anti-leak/1.0.5
2023-12-01T00:19:49.195-06:00 | INFO | agent-bootstrap | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-jctools2/2.1.6
2023-12-01T00:19:49.195-06:00 | INFO | agent-bootstrap | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-api/1.2.135
2023-12-01T00:19:49.195-06:00 | INFO | agent-bootstrap | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-unix-socket-util/1.2.7
2023-12-01T00:19:49.195-06:00 | INFO | agent-bootstrap | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-agent-api/1.0.5
2023-12-01T00:19:49.195-06:00 | INFO | agent-bootstrap | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-agent-optional-api/1.0.5
2023-12-01T00:19:49.195-06:00 | INFO | agent-bootstrap | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-agent-internal-api/1.0.7
2023-12-01T00:19:49.195-06:00 | INFO | agent-bootstrap | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-agent/1.1.706
2023-12-01T00:19:49.543-06:00 | INFO | s-orange-saas.instana.io:443-4-1 | irectMemoryGuard | com.instana.agent - 1.1.706 | Initializing DirectMemoryGuard with 48187/134217728 bytes of direct memory and 82547337 permits
2023-12-01T00:19:49.544-06:00 | INFO | s-orange-saas.instana.io:443-4-1 | Backend | com.instana.agent - 1.1.706 | Connected using HTTP/2 to ingress-orange-saas.instana.io:443 with id '06:c4:c8:ff:fe:46:4f:91' and key '*** (redacted)'
2023-12-01T00:19:51.729-06:00 | INFO | instana-scheduler-thread-3-3 | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-process-sensor
2023-12-01T00:19:51.743-06:00 | INFO | 3c8-cc1a-47d0-97d0-2f7c968da910) | Process | com.instana.sensor-process - 1.1.48 | Activated Sensor for PID 54695
[agent.log]
続々と各種 Discovery センサーが導入されています（今回は初期導入ですので、すべて新規にダウンロードされています）。
2023-12-01T00:23:05.282-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-ace-discovery/1.0.64
2023-12-01T00:23:05.282-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-action-discovery/1.0.33
2023-12-01T00:23:05.282-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-activemq-artemis-discovery/1.0.8
2023-12-01T00:23:05.282-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-activemq-discovery/1.1.14
2023-12-01T00:23:05.282-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-alicloud-discovery/1.0.12
・・・
2023-12-01T00:23:05.288-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-vsphere-discovery/1.0.24
2023-12-01T00:23:05.288-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-weblogic-discovery/1.1.19
2023-12-01T00:23:05.288-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-websphere-discovery/1.1.38
2023-12-01T00:23:05.288-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-websphere-liberty-discovery/1.1.4
2023-12-01T00:23:05.288-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-zhmc-discovery/1.0.12
2023-12-01T00:23:05.288-06:00 | INFO | agent-starter | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-zookeeper-discovery/1.0.8
[agent.log]
ディスカバリー・センサーの導入が完了すると、ディスカバリー処理を開始します
2023-12-01T00:23:05.288-06:00 | INFO | agent-starter | AgentBootstrap | com.instana.agent-bootstrap - 1.2.30 | Starting agent now
2023-12-01T00:23:05.288-06:00 | INFO | agent-starter | AgentImpl | com.instana.agent - 1.1.706 | Starting Instana agent Discovery.
2023-12-01T00:23:05.288-06:00 | INFO | agent-starter | AgentImpl | com.instana.agent - 1.1.706 | Instana agent Discovery started.
[agent.log]
プロセスやホストセンサーがアクティベートされ監視を開始します
2023-12-01T00:23:09.209-06:00 | INFO | stana-agent-scheduler-thread-7-2 | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-clr-trace-sensor
2023-12-01T00:23:09.231-06:00 | INFO | 12a-c634-449a-b7bc-4e3ed4e9c6a6) | ClrTrace | com.instana.sensor-clr-trace - 1.1.8 | Activated Sensor
2023-12-01T00:23:09.231-06:00 | INFO | 12a-c634-449a-b7bc-4e3ed4e9c6a6) | ClrTrace | com.instana.sensor-clr-trace - 1.1.8 | CLR-Tracing has been enabled
2023-12-01T00:23:13.010-06:00 | INFO | instana-scheduler-thread-3-2 | Ebpf | com.instana.discovery-ebpf - 1.0.25 | Successfully started eBPF sensor.
2023-12-01T00:23:13.724-06:00 | INFO | stana-agent-scheduler-thread-7-2 | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-netcore-trace-sensor
2023-12-01T00:23:13.755-06:00 | INFO | 4be-8fb3-460c-8c21-4e9b6423a645) | Daemon | com.instana.sensor-netcore-trace - 1.0.5 | Started .NET Core Acceptor daemon with 1 dispatchers
2023-12-01T00:23:13.755-06:00 | INFO | 4be-8fb3-460c-8c21-4e9b6423a645) | NetcoreTrace | com.instana.sensor-netcore-trace - 1.0.5 | .Net Core Tracing daemon activated
2023-12-01T00:23:18.512-06:00 | INFO | stana-agent-scheduler-thread-7-2 | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-action-services-discovery
2023-12-01T00:23:25.523-06:00 | INFO | stana-agent-scheduler-thread-7-2 | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-host-sensor
2023-12-01T00:23:25.524-06:00 | WARN | stana-agent-scheduler-thread-7-2 | yDiscoveryTicker | com.instana.agent - 1.1.706 | Discovery for com.instana.plugin.host took too long (7008 ms)
2023-12-01T00:23:25.577-06:00 | INFO | 54e-5d83-45db-a6be-8720d36b2919) | Host | com.instana.sensor-host - 1.1.165 | Activated Sensor
[/opt/instana/agent/repo/com/instana にダウンロードされたモジュール]
最初の段階では、主に監視対象を検知するdiscoveryプラグインがダウンロードされていることが分かります。すでに監視対象が存在するホストOSのメトリックを取得するホストセンサー（instna-host-sensor)やプロセスのメトリックを取得するプロセスセンサー（instana-process-sensor）もダウンロードされています。
Java および OpenLiberty の起動
次に、以下のコマンドで OpenLibertyを起動します。
[root@itz-3100008gyq-sx2l ~]# /opt/wlp/bin/server start defaultServer
サーバー defaultServer を始動中です。
サーバー defaultServer がプロセス ID 57110 で始動しました。
[agent.log]
このタイミングで、Instanaは初めて JavaおよびLibertyを検知します。
2023-12-01T00:55:00.922-06:00 | INFO | instana-executor-thread-2-12 | lMachineUtilImpl | com.instana.agent - 1.1.706 | adding new VM with PID 57110, VirtualMachineImpl [pid=57110, attachType=HOTSPOT_TOOLS, hasAttachFile=true, commandLine=/opt/wlp/bin/tools/ws-server.jar defaultServer, vmArgs=-javaagent:/opt/wlp/bin/tools/ws-javaagent.jar -Djava.awt.headless=true -Djdk.attach.allowAttachSelf=true, name=OpenJDK 64-Bit Server VM, vendor=Red Hat, Inc., version=1.8.0_392, build=25.392-b08, heapCapacity=1023410176, process=SigarProcessImpl [pid=57110, parentPid=<lazy>, startTime=0, name=java, directory=<lazy>, executable=<lazy>, arguments=[-javaagent:/opt/wlp/bin/tools/ws-javaagent.jar, -Djava.awt.headless=true, -Djdk.attach.allowAttachSelf=true, -jar, /opt/wlp/bin/tools/ws-server.jar, defaultServer], userName=<lazy>, groupName=<lazy>, procCred=null, environmentVariables={PATH=/usr/local/sbin:/sbin:/bin:/usr/sbin:/usr/bin:/root/bin, HISTCONTROL=ignoredups, HISTSIZE=1000, BASH_FUNC_which%%=() { ( alias; eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@}, TERM=xterm-256color, OPENJ9_JAVA_OPTIONS=-Xshareclasses:name=liberty-%u,nonfatal,cacheDir="/opt/wlp/usr/servers/.classCache",cacheDirPerm=1000 -XX:ShareClassesEnableBCI -Xscmx80m , LANG=ja_JP.UTF-8, SUDO_USER=itzuser, keystore_password=0BX********************, SUDO_GID=1001, MAIL=/var/spool/mail/root, LOGNAME=root, which_declare=declare -f, SUDO_UID=1000, PWD=/opt/wlp/usr/servers/defaultServer, _=/bin/java, LESSOPEN=||/usr/bin/lesspipe.sh %s, SHELL=/bin/bash, WLP_OUTPUT_DIR=/opt/wlp/usr/servers, WLP_USER_DIR=/opt/wlp/usr, OLDPWD=/root, USER=root, INVOKED=/opt/wlp/bin/server, SUDO_COMMAND=/bin/bash, X_LOG_FILE=console.log, HOSTNAME=itz-3100008gyq-sx2l.dte.demo.ibmcloud.com, X_CMD=java , X_LOG_DIR=/opt/wlp/usr/servers/defaultServer/logs, IBM_JAVA_OPTIONS=-Xshareclasses:name=liberty-%u,nonfatal,cacheDir="/opt/wlp/usr/servers/.classCache",cacheDirPerm=1000 -XX:ShareClassesEnableBCI -Xscmx80m ...
2023-12-01T00:55:01.084-06:00 | INFO | instana-executor-thread-2-12 | lMachineUtilImpl | com.instana.agent - 1.1.706 | adding new VM with PID 57111, VirtualMachineImpl [pid=57111, attachType=HOTSPOT_TOOLS, hasAttachFile=true, commandLine=/opt/wlp/bin/tools/ws-server.jar defaultServer --pid=57110 --status:start, vmArgs=-javaagent:/opt/wlp/bin/tools/ws-javaagent.jar -Djava.awt.headless=true -Djdk.attach.allowAttachSelf=true, name=OpenJDK 64-Bit Server VM, vendor=Red Hat, Inc., version=1.8.0_392, build=25.392-b08, heapCapacity=1023410176, process=SigarProcessImpl [pid=57111, parentPid=<lazy>, startTime=0, name=java, directory=/opt/wlp/usr/servers/defaultServer, executable=/usr/lib/jvm/java-1.8.0-openjdk-1.8.0.392.b08-4.el8.x86_64/jre/bin/java, arguments=[-javaagent:/opt/wlp/bin/tools/ws-javaagent.jar, -Djava.awt.headless=true, -Djdk.attach.allowAttachSelf=true, -jar, /opt/wlp/bin/tools/ws-server.jar, defaultServer, --pid=57110, --status:start], userName=<lazy>, groupName=<lazy>, procCred=null, environmentVariables={PATH=/usr/local/sbin:/sbin:/bin:/usr/sbin:/usr/bin:/root/bin, HISTCONTROL=ignoredups, HISTSIZE=1000, BASH_FUNC_which%%=() { ( alias; eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@}, TERM=xterm-256color, OPENJ9_JAVA_OPTIONS=-Xquickstart -Dcom.ibm.tools.attach.enable=yes -Xshareclasses:none, LANG=ja_JP.UTF-8, SUDO_USER=itzuser, keystore_password=0BX********************, SUDO_GID=1001, MAIL=/var/spool/mail/root, LOGNAME=root, which_declare=declare -f, SUDO_UID=1000, PWD=/opt/wlp/usr/servers/defaultServer, _=/bin/java, LESSOPEN=||/usr/bin/lesspipe.sh %s, SHELL=/bin/bash, WLP_OUTPUT_DIR=/opt/wlp/usr/servers, WLP_USER_DIR=/opt/wlp/usr, OLDPWD=/root, USER=root, INVOKED=/opt/wlp/bin/server, SUDO_COMMAND=/bin/bash, X_LOG_FILE=console.log, HOSTNAME=itz-3100008gyq-sx2l.dte.demo.ibmcloud.com, X_CMD=java , X_LOG_DIR=/opt/wlp/usr/servers/defaultServer/logs, IBM_JAVA_OPTIONS=-Xquickstart...
[agent.log]
Instanaは初めて Javaセンサー (instana-java-sensor)をダウンロードしてきて導入し、次に Javaトレースセンサー（instana-java-trace-sensor)を導入しています。
2023-12-01T00:55:13.311-06:00 | INFO | stana-agent-scheduler-thread-7-1 | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-java-sensor
2023-12-01T00:55:13.396-06:00 | INFO | 43f-c813-4758-8f50-77e40f141510) | Java | com.instana.sensor-java - 1.2.126 | Activated Sensor for JVM 57110
2023-12-01T00:55:13.433-06:00 | INFO | c53-1c0b-4a54-be5a-776a8fb0d833) | Process | com.instana.sensor-process - 1.1.48 | Activated Sensor for PID 57110
2023-12-01T00:55:13.436-06:00 | INFO | instana-executor-thread-2-12 | Java | com.instana.sensor-java - 1.2.126 | Attaching to JVM with PID 57110
2023-12-01T00:55:16.129-06:00 | INFO | instana-executor-thread-2-12 | aagentLoaderImpl | com.instana.agent - 1.1.706 | Performing initial attach to JVM with PID 57110
2023-12-01T00:55:16.134-06:00 | INFO | instana-executor-thread-2-16 | AttachHelperImpl | com.instana.agent - 1.1.706 | Verifying existence of MavenArtifact{artifactId='attach-tools', extension='jar', version='1.10.8'}
2023-12-01T00:55:16.136-06:00 | INFO | instana-executor-thread-2-13 | AttachHelperImpl | com.instana.agent - 1.1.706 | Verifying existence of MavenArtifact{artifactId='instrumentation-boot', extension='jar', version='1.1.27'}
2023-12-01T00:55:16.140-06:00 | INFO | instana-executor-thread-2-15 | AttachHelperImpl | com.instana.agent - 1.1.706 | Verifying existence of MavenArtifact{artifactId='instrumentation-shared', extension='jar', version='1.0.137'}
2023-12-01T00:55:16.143-06:00 | INFO | instana-executor-thread-2-11 | AttachHelperImpl | com.instana.agent - 1.1.706 | Verifying existence of MavenArtifact{artifactId='javaagent-loader', extension='jar', version='1.3.55'}
2023-12-01T00:55:19.433-06:00 | INFO | stana-agent-scheduler-thread-7-1 | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-java-trace-sensor
2023-12-01T00:55:19.435-06:00 | WARN | stana-agent-scheduler-thread-7-1 | yDiscoveryTicker | com.instana.agent - 1.1.706 | Discovery for com.instana.plugin.java took too long (18462 ms)
2023-12-01T00:55:19.475-06:00 | WARN | stana-agent-scheduler-thread-7-1 | yDiscoveryTicker | com.instana.agent - 1.1.706 | Discovery time (18506 ms)
[agent.log]
JavaセンサーがInstrumentationを開始します
2023-12-01T00:55:19.579-06:00 | INFO | 5f5-88ef-4dbb-81c7-fa6c7ae972ba) | JavaTrace | com.instana.sensor-java-trace - 1.2.453 | Activated Sensor for JVM 57110
2023-12-01T00:55:19.583-06:00 | INFO | instana-executor-thread-2-13 | JavaTrace | com.instana.sensor-java-trace - 1.2.453 | Attaching to JVM with PID 57110
2023-12-01T00:55:27.819-06:00 | INFO | instana-executor-thread-2-21 | unicationChannel | com.instana.agent - 1.1.706 | 57110 Opening Socket to JVM on /127.0.0.1:34447
2023-12-01T00:55:28.555-06:00 | INFO | instana-executor-thread-2-12 | aagentLoaderImpl | com.instana.agent - 1.1.706 | Initial attach to JVM with PID 57110 successful
2023-12-01T00:55:28.706-06:00 | INFO | nt-com.instana.plugin.java-57110 | Java | com.instana.sensor-java - 1.2.126 | Accepting data from JVM with PID 57110 on 127.0.0.1:35247
2023-12-01T00:55:28.724-06:00 | INFO | nt-com.instana.plugin.java-57110 | Java | com.instana.sensor-java - 1.2.126 | Connection with JVM with PID 57110 on 127.0.0.1:35247 established
2023-12-01T00:55:28.811-06:00 | INFO | m.instana.plugin.javatrace-57110 | JavaTrace | com.instana.sensor-java-trace - 1.2.453 | Accepting data from JVM with PID 57110 on 127.0.0.1:37059
2023-12-01T00:55:28.815-06:00 | INFO | instana-executor-thread-2-13 | JavaTrace | com.instana.sensor-java-trace - 1.2.453 | The JVM 57110 is running another agent via the '-javaagent:/opt/wlp/bin/tools/ws-javaagent.jar' startup parameter.
2023-12-01T00:55:28.823-06:00 | INFO | m.instana.plugin.javatrace-57110 | JavaTrace | com.instana.sensor-java-trace - 1.2.453 | Connection with JVM with PID 57110 on 127.0.0.1:37059 established
2023-12-01T00:55:36.549-06:00 | INFO | nstana-socket-client-thread--6-4 | LoggerEndpoint | com.instana.agent - 1.1.706 | JVM (57110) - Spent 7496 ms and 3641 KiB of Metaspace for instrumentation [trace] (Used: 40282 KiB, Max: unlimited) (JVM uptime: 42912 ms)
[agent.log]
WebSphere Libertyセンサーをダウンロードし、導入、開始しています
2023-12-01T00:55:46.272-06:00 | INFO | stana-agent-scheduler-thread-7-2 | turesManagerImpl | com.instana.agent-bootstrap - 1.2.30 | Installed instana-websphere-liberty-sensor
2023-12-01T00:55:46.274-06:00 | WARN | stana-agent-scheduler-thread-7-2 | yDiscoveryTicker | com.instana.agent - 1.1.706 | Discovery for com.instana.plugin.websphereliberty took too long (10872 ms)
2023-12-01T00:55:46.281-06:00 | INFO | 444-7367-4882-a9f3-4e804e601740) | WebSphereLiberty | com.instana.sensor-websphere-liberty - 1.1.4 | Activated Sensor for JVM 57110
2023-12-01T00:55:46.282-06:00 | INFO | instana-executor-thread-2-15 | WebSphereLiberty | com.instana.sensor-websphere-liberty - 1.1.4 | Attaching to JVM with PID 57110
2023-12-01T00:55:47.726-06:00 | INFO | na.plugin.websphereliberty-57110 | WebSphereLiberty | com.instana.sensor-websphere-liberty - 1.1.4 | Accepting data from JVM with PID 57110 on 127.0.0.1:37535
2023-12-01T00:55:47.730-06:00 | INFO | na.plugin.websphereliberty-57110 | WebSphereLiberty | com.instana.sensor-websphere-liberty - 1.1.4 | Connection with JVM with PID 57110 on 127.0.0.1:37535 established
[/opt/instana/agent/repo/com/instana にダウンロードされたモジュール]
Instanaが 監視対象として、Java および Open Liberty を見つけたあと、Java用のセンサーおよび分散トレーシングを担うトレーサー、WebSphereLiberty のサーバーがダウンロードされていることが分かります。
[/opt/instana/agent/repo/com/instana にダウンロードされたモジュール]
タイムスタンプからも、モジュールがダウンロードされているタイミングの差異が分かります
まとめ
ログを順に読んでいくという、斬新な記事でしたが、Instanaの基本のキとなる仕組みが少し理解頂けたのではないでしょうか？
それでは、いい週末をお過ごしください。