Instanaの基本的な動きを理解する

こんにんちは。IBM Automation Technical Salesの岩品です。
Instana Advent Calendar ２日目は、Instanaの基本的な挙動を、実機での環境を踏まえてみていきたいと思います。

### Instanaの自動検知と自動構成

Instanaの製品マニュアルの[「始めに」](https://www.ibm.com/docs/ja/instana-observability/current?topic=started-set-up-observe-your-own-data#step-1-deploy-an-agent)に、以下のように記述されています。
>ホスト・エージェントをインストールすると、特定のテクノロジーをモニターするように設計された Instana センサーが自動的にインストールされます。 ほとんどのセンサーは、ホスト・エージェントのインストール後に自動的にインストールされ、構成されます。

これがInstanaの特徴である「自動検知」「自動構成」によるシステム環境の可視化です。Kubernetesなど複雑で動的な環境において、大いにその力を発揮します。これは下図のような仕組みで実行されています。
![image.png](https://qiita-image-store.s3.ap-northeast-1.amazonaws.com/0/323873/d8681ffa-ebbb-af67-df53-59572e05a90f.png)

これが実際どのように動いているのか、シンプルな仮想化環境において、実機でログと挙動を追いながら見ていきたいと思います。

### 検証環境
払い出されたばかりの 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/ディレクトリに配置されています。

:::note
[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.
```
:::note
[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
```
:::note
[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
```
:::note
[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.
```
:::note
[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
```


:::note warn
[/opt/instana/agent/repo/com/instana　にダウンロードされたモジュール]
最初の段階では、主に監視対象を検知するdiscoveryプラグインがダウンロードされていることが分かります。すでに監視対象が存在するホストOSのメトリックを取得するホストセンサー（instna-host-sensor)やプロセスのメトリックを取得するプロセスセンサー（instana-process-sensor）もダウンロードされています。
:::
![image.png](https://qiita-image-store.s3.ap-northeast-1.amazonaws.com/0/323873/ff1805e5-2012-1333-5345-c93bef1e6744.png)

### Java および OpenLiberty の起動
次に、以下のコマンドで OpenLibertyを起動します。
```
[root@itz-3100008gyq-sx2l ~]# /opt/wlp/bin/server start defaultServer

サーバー defaultServer を始動中です。
サーバー defaultServer がプロセス ID 57110 で始動しました。
```
:::note
[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...
```
:::note
[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)
```
:::note
[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)
```
:::note
[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
```
:::note warn
[/opt/instana/agent/repo/com/instana　にダウンロードされたモジュール]
Instanaが 監視対象として、Java および Open Liberty を見つけたあと、Java用のセンサーおよび分散トレーシングを担うトレーサー、WebSphereLiberty のサーバーがダウンロードされていることが分かります。
:::
![image.png](https://qiita-image-store.s3.ap-northeast-1.amazonaws.com/0/323873/3e540003-4804-f3c2-c29d-fc02ab0e1501.png)

:::note warn
[/opt/instana/agent/repo/com/instana　にダウンロードされたモジュール]
タイムスタンプからも、モジュールがダウンロードされているタイミングの差異が分かります
:::
![image.png](https://qiita-image-store.s3.ap-northeast-1.amazonaws.com/0/323873/1ba332cd-3d9a-ac80-6703-e34081ab5f01.png)

### まとめ
ログを順に読んでいくという、斬新な記事でしたが、Instanaの基本のキとなる仕組みが少し理解頂けたのではないでしょうか？
それでは、いい週末をお過ごしください。