Performance-analyzer-agent-cli spamming syslog in OD 1.3.0

lmit · December 20, 2019, 9:25pm

Has anyone been seeing the following messages every 5 second in your syslog after installing Open Distro for Elasticsearch version 1.3.0?

I have installed OD 1.3.0 on 3 x Ubuntu 16.04.6 LTS hosts to create a 3 node cluster.
Everyone one of my nodes has the performance-analyzer-agent-cli task filling up /var/log/syslog with the SAME identical message generated every 5 seconds.

Any insights how to resolve?

Dec 20 21:07:37 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:37 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:37 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:07:37 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:37 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:37 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:07:42 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:42 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:42 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:07:42 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:42 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:42 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:07:47 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:47 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:47 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:07:47 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:47 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:47 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:07:52 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:52 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:52 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:07:52 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:52 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:52 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:07:57 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:57 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:57 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:07:57 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:07:57 PM org.jooq.tools.JooqLogger info
Dec 20 21:07:57 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:02 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:02 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:02 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:02 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:02 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:02 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:07 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:07 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:07 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:07 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:07 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:07 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:12 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:12 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:12 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:12 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:12 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:12 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:17 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:17 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:17 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:17 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:17 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:17 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:22 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:22 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:22 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:22 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:22 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:22 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:27 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:27 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:27 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:27 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:27 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:27 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:32 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:32 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:32 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:32 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:32 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:32 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:37 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:37 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:37 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:37 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:37 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:37 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:42 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:42 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:42 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:42 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:42 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:42 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:47 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:47 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:47 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:47 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:47 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:47 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:52 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:52 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:52 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:52 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:52 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:52 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:57 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:57 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:57 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:08:57 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:08:57 PM org.jooq.tools.JooqLogger info
Dec 20 21:08:57 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:09:02 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:09:02 PM org.jooq.tools.JooqLogger info
Dec 20 21:09:02 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:09:02 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:09:02 PM org.jooq.tools.JooqLogger info
Dec 20 21:09:02 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:09:07 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:09:07 PM org.jooq.tools.JooqLogger info
Dec 20 21:09:07 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:09:07 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:09:07 PM org.jooq.tools.JooqLogger info
Dec 20 21:09:07 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:09:12 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:09:12 PM org.jooq.tools.JooqLogger info
Dec 20 21:09:12 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:09:12 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:09:12 PM org.jooq.tools.JooqLogger info
Dec 20 21:09:12 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:09:17 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:09:17 PM org.jooq.tools.JooqLogger info
Dec 20 21:09:17 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Dec 20 21:09:17 my performance-analyzer-agent-cli[13112]: Dec 20, 2019 9:09:17 PM org.jooq.tools.JooqLogger info
Dec 20 21:09:17 my performance-analyzer-agent-cli[13112]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse

Pretty sure I am not the only one experiencing this

Thanks in advance

simonc · January 2, 2020, 9:52pm

I think the service logs this to stdout by default; I have added a fix to the systemd unit file for now

eg in /usr/lib/systemd/system/opendistro-performance-analyzer.service

under [Service] add StandardOutput=null

lmit · January 3, 2020, 12:31am

Ahhhh, Thank you.

What does this error mean? Does it signify an error in my cluster configuration?

I don’t want to just hide the error if it represents a deeper issue

lmit · January 3, 2020, 1:07am

Hey Simonc,

The suggested configuration in /usr/lib/systemd/system/opendistro-performance-analyzer.service does not seem to make a difference

[Unit]
Description=Opendistro for Elasticsearch Performance Analyzer
PartOf=elasticsearch.service

[Service]
ExecStart=/usr/share/elasticsearch/bin/performance-analyzer-agent-cli
Restart=on-failure
User=elasticsearch
Group=elasticsearch
Environment=ES_HOME=/usr/share/elasticsearch
WorkingDirectory=/usr/share/elasticsearch
StandardOutput=/dev/null

[Install]
WantedBy=elasticsearch.service

I tried StandardOutput=/dev/null and StandardOutput=null with the same result

My Syslog file /var/log/syslog is still spammed with the following after restart

Jan  3 00:45:37 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:45:42 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:45:42 AM org.jooq.tools.JooqLogger info
Jan  3 00:45:42 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:45:42 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:45:42 AM org.jooq.tools.JooqLogger info
Jan  3 00:45:42 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:45:47 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:45:47 AM org.jooq.tools.JooqLogger info
Jan  3 00:45:47 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:45:47 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:45:47 AM org.jooq.tools.JooqLogger info
Jan  3 00:45:47 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:45:52 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:45:52 AM org.jooq.tools.JooqLogger info
Jan  3 00:45:52 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:45:52 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:45:52 AM org.jooq.tools.JooqLogger info
Jan  3 00:45:52 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:45:57 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:45:57 AM org.jooq.tools.JooqLogger info
Jan  3 00:45:57 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:45:57 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:45:57 AM org.jooq.tools.JooqLogger info
Jan  3 00:45:57 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:46:02 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:46:02 AM org.jooq.tools.JooqLogger info
Jan  3 00:46:02 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse
Jan  3 00:46:02 mistral-rustam performance-analyzer-agent-cli[30234]: Jan 03, 2020 12:46:02 AM org.jooq.tools.JooqLogger info
Jan  3 00:46:02 mistral-rustam performance-analyzer-agent-cli[30234]: INFO: Single batch             : No bind variables have been provided with a single statement batch execution. This may be due to accidental API misuse

simonc · January 3, 2020, 9:33am

Sorry should have also added you would need to reload systemd via ‘/bin/systemctl daemon-reload’ for it to take affect

lmit · January 3, 2020, 9:56am

Thank you! That worked!

lmit · January 3, 2020, 10:04am

So This error is of no concern?

adithyac · January 6, 2020, 7:23pm

Hi,

Yes, these logs should not impact elasticsearch in any way. The log is being emitted by the PerformanceAnalyzer side car process that reads events generated by Elasticsearch and creates fine-grained resource usage metrics. The error message is being generated when bulk-loading event data into sqlite, as the bulk load is empty.

Have you run perf-top on your installation and checked if PerformanceAnalyzer metrics are available? That will give us a better idea of whether there is some issue in generating Performance Analyzer metrics.

lmit · January 6, 2020, 8:03pm

Thank you for educating me on the meaning of the error message.
I too wanted to know if the Agent was working, so I installed perf-top on a peripheral host

These are the dashboard screenshots

ClusterOverview

image1275×841 90 KB
ClusterNetworkMemoryAnalysis

image1275×841 102 KB
ClusterThreadAnalysis

image1275×841 121 KB
NodeAnalysis

image1275×841 117 KB

Thanks again for all your support

adithyac · January 6, 2020, 9:01pm

Great! I created a new issue here - Investigate error messages from sqlite batch upload · Issue #39 · opendistro-for-elasticsearch/performance-analyzer-rca · GitHub. We will investigate and try to root cause what is causing empty uploads.

Topic		Replies	Views
Performance Analyzer Log Spam Performance Analyzer	1	1353	February 7, 2023
Issues with 1.11 performance analyzer no data no bind variables have been provided Performance Analyzer	1	1283	February 7, 2023
Performance-Analyzer-Logs not rotated Performance Analyzer	6	3446	February 1, 2021
Elasticsearch crash when start, caused by performance analyzer plugin Performance Analyzer	1	1387	February 7, 2023
Error writing entry 'NOT_INITIALIZED'. Cause /dev/shm/performanceanalyzer/xxxxx.tmp Performance Analyzer	2	1380	November 7, 2023

Performance-analyzer-agent-cli spamming syslog in OD 1.3.0

Related topics